Delays when dialling out? More gremlins after restore?


(D E) #1

I’ve had to restore from a backup and since then my system hasn’t been ‘right’.

It’s hard to put my finger on it, but my local extensions are occasionally going unreachable - which wasn’t happening before - and calls out and in are subject to quite long delays.

"Exceptionally long voice queue length queuing" appears in my logs as does

WARNING[9787][C-0000000b] res_musiconhold.c: Music on Hold class 'transfer' not found in memory. Verify your configuration.

Here’s a call trace

Anyone have any ideas?!?

Thank you!!


(D E) #2

Another example. This time a 15 second wait on dialling in - can’t see why?

There’s also a bit of this in my logs…

[2021-01-19 19:56:58] WARNING[20762] loader.c: Some non-required modules failed to load.	
9348	[2021-01-19 19:56:58] ERROR[20762] loader.c: Error loading module 'chan_local.so': /usr/lib/asterisk/modules/chan_local.so: cannot open shared object file: No such file or directory	
9349	[2021-01-19 19:56:58] ERROR[20762] loader.c: Error loading module 'res_pjsip_phoneprov_provider.so', missing dependency: res_phoneprov	
9350	[2021-01-19 19:56:58] ERROR[20762] loader.c: res_config_pgsql declined to load.	
9351	[2021-01-19 19:56:58] ERROR[20762] loader.c: res_xmpp declined to load.	
9352	[2021-01-19 19:56:58] ERROR[20762] loader.c: Declined modules which depend on res_xmpp: chan_motif	
9353	[2021-01-19 19:56:58] ERROR[20762] loader.c: res_stun_monitor declined to load.	
9354	[2021-01-19 19:56:58] ERROR[20762] loader.c: chan_ooh323 declined to load.	
9355	[2021-01-19 19:56:58] ERROR[20762] loader.c: chan_skinny declined to load.	
9356	[2021-01-19 19:56:58] ERROR[20762] loader.c: chan_console declined to load.	
9357	[2021-01-19 19:56:58] ERROR[20762] loader.c: chan_mobile declined to load.	
9358	[2021-01-19 19:56:58] ERROR[20762] loader.c: res_hep declined to load.	
9359	[2021-01-19 19:56:58] ERROR[20762] loader.c: Declined modules which depend on res_hep: res_hep_rtcp, res_hep_pjsip	
9360	[2021-01-19 19:56:58] ERROR[20762] loader.c: res_pjsip_transport_websocket declined to load.	
9361	[2021-01-19 19:56:58] ERROR[20762] loader.c: app_agent_pool declined to load.	
9362	[2021-01-19 19:56:58] ERROR[20762] loader.c: res_calendar declined to load.	
9363	[2021-01-19 19:56:58] ERROR[20762] loader.c: Declined modules which depend on res_calendar: res_calendar_ews, res_calendar_icalendar, res_calendar_exchange, res_calendar_caldav	
9364	[2021-01-19 19:56:58] ERROR[20762] loader.c: res_parking declined to load.	
9365	[2021-01-19 19:56:58] ERROR[20762] loader.c: cel_tds declined to load.	
9366	[2021-01-19 19:56:58] ERROR[20762] loader.c: cdr_sqlite3_custom declined to load.	
9367	[2021-01-19 19:56:58] ERROR[20762] loader.c: cdr_syslog declined to load.	
9368	[2021-01-19 19:56:58] ERROR[20762] loader.c: cel_sqlite3_custom declined to load.	
9369	[2021-01-19 19:56:58] ERROR[20762] loader.c: cdr_csv declined to load.	
9370	[2021-01-19 19:56:58] ERROR[20762] loader.c: cel_pgsql declined to load.	
9371	[2021-01-19 19:56:58] ERROR[20762] loader.c: cdr_odbc declined to load.	
9372	[2021-01-19 19:56:58] ERROR[20762] loader.c: cdr_tds declined to load.	
9373	[2021-01-19 19:56:58] ERROR[20762] loader.c: cdr_pgsql declined to load.	
9374	[2021-01-19 19:56:58] ERROR[20762] loader.c: cdr_manager declined to load.	
9375	[2021-01-19 19:56:58] ERROR[20762] loader.c: app_followme declined to load.	
9376	[2021-01-19 19:56:58] ERROR[20762] loader.c: pbx_dundi declined to load.	
9377	[2021-01-19 19:56:58] ERROR[20762] loader.c: app_alarmreceiver declined to load.	
9378	[2021-01-19 19:56:58] ERROR[20762] loader.c: app_festival declined to load.	
9379	[2021-01-19 19:56:58] ERROR[20762] loader.c: chan_unistim declined to load.	
9380	[2021-01-19 19:56:58] ERROR[20762] loader.c: app_osplookup declined to load.	
9381	[2021-01-19 19:56:58] ERROR[20762] loader.c: pbx_lua declined to load.	
9382	[2021-01-19 19:56:58] ERROR[20762] loader.c: pbx_ael declined to load.	
9383	[2021-01-19 19:56:58] ERROR[20762] loader.c: func_odbc declined to load.

(D E) #3

Looking at another test call where I’m trying to call out over an IAX trunk, the delay seems to be where an AGI script is called. However I know that in my former system, this same script didn’t result in a ten second delay…

10364[2021-01-19 22:26:21] VERBOSE[13495][C-00000002] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:2] AGI("PJSIP/4002-00000001", "lgaetz-cmadd.php,24") in new stack
10365[2021-01-19 22:26:21] VERBOSE[13495][C-00000002] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/lgaetz-cmadd.php
10366[2021-01-19 22:26:31] VERBOSE[13495][C-00000002] res_agi.c: lgaetz-cmadd.php,24: 24 already exists in Contact Mgr group whitelist, exiting
10367[2021-01-19 22:26:31] VERBOSE[13495][C-00000002] res_agi.c: <PJSIP/4002-00000001>AGI Script lgaetz-cmadd.php completed, returning 0
10368[2021-01-19 22:26:31] VERBOSE[13495][C-00000002] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:3] MacroExit("PJSIP/4002-00000001", "") in new stack

(Itzik) #4

What do all your custom scripts do?