-- Accepting call from '5555551234' to '4350' on channel 0/1, span 3 [Oct 16 09:27:28] DEBUG[21737]: chan_dahdi.c:1784 dahdi_enable_ec: Enabled echo cancellation on channel 49 [Oct 16 09:27:28] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Ping' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Answer' -- Executing [4350@zap-incoming:1] Answer("Zap/49-1", "") in new stack [Oct 16 09:27:28] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:28] DEBUG[11247]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Zap/49 [Oct 16 09:27:28] DEBUG[21730]: channel.c:1133 channel_find_locked: Avoiding initial deadlock for channel '0x9750fa8' [Oct 16 09:27:28] DEBUG[21730]: channel.c:1133 channel_find_locked: Avoiding initial deadlock for channel '0x9750fa8' [Oct 16 09:27:28] DEBUG[11247]: chan_dahdi.c:5474 dahdi_indicate: Requested indication -1 on channel Zap/49-1 [Oct 16 09:27:28] DEBUG[21730]: channel.c:1133 channel_find_locked: Avoiding initial deadlock for channel '0x9750fa8' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:2] Set("Zap/49-1", "CHANNEL(musicclass)=default") in new stack [Oct 16 09:27:28] DEBUG[21730]: devicestate.c:287 do_state_change: Changing state for Zap/49 - state 2 (In use) [Oct 16 09:27:28] DEBUG[21779]: app_queue.c:682 handle_statechange: Device 'Zap/49' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1691 pbx_substitute_variables_helper_full: Function result is '5555551234' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1691 pbx_substitute_variables_helper_full: Function result is 'Dave Brillert' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'NoOp' -- Executing [4350@zap-incoming:3] NoOp("Zap/49-1", ""INCOMING CALL FROM CALLER ID: 5555551234 (Dave Brillert)"") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:4] Set("Zap/49-1", "__INCOMING_DNIS=4350") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:5] Set("Zap/49-1", "CALLERID(dnid)=4350") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:6] Set("Zap/49-1", "CDR(userfield)=4350") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Goto' -- Executing [4350@zap-incoming:7] Goto("Zap/49-1", "10") in new stack -- Goto (zap-incoming,4350,10) [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'AGI' -- Executing [4350@zap-incoming:10] AGI("Zap/49-1", "/var/www/scopserv/telephony/scripts/agi/fixcidname.php") in new stack -- Launched AGI Script /var/www/scopserv/telephony/scripts/agi/fixcidname.php AGI Tx >> agi_request: /var/www/scopserv/telephony/scripts/agi/fixcidname.php AGI Tx >> agi_channel: Zap/49-1 AGI Tx >> agi_language: en AGI Tx >> agi_type: Zap AGI Tx >> agi_uniqueid: 1255699648.551 AGI Tx >> agi_callerid: 5555551234 AGI Tx >> agi_calleridname: Dave Brillert AGI Tx >> agi_callingpres: 1 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 33 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: 4350 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: zap-incoming AGI Tx >> agi_extension: 4350 AGI Tx >> agi_priority: 10 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: _NONE_ AGI Tx >> LI> AGI Rx << SET CALLERID "Dave Brillert"<5555551234> AGI Tx >> 200 result=1 -- AGI Script /var/www/scopserv/telephony/scripts/agi/fixcidname.php completed, returning 0 [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:11] Set("Zap/49-1", "FAXNUMBER=4350") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:12] Set("Zap/49-1", "__INCOMINGLINE=1") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1691 pbx_substitute_variables_helper_full: Function result is '5555551234' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1759 pbx_substitute_variables_helper_full: Expression result is '0' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'GotoIf' -- Executing [4350@zap-incoming:13] GotoIf("Zap/49-1", "0?14:15") in new stack -- Goto (zap-incoming,4350,15) [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1691 pbx_substitute_variables_helper_full: Function result is '5555551234' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1759 pbx_substitute_variables_helper_full: Expression result is '0' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'GotoIf' -- Executing [4350@zap-incoming:15] GotoIf("Zap/49-1", "0?16:17") in new stack -- Goto (zap-incoming,4350,17) [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1691 pbx_substitute_variables_helper_full: Function result is 'Dave Brillert' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1759 pbx_substitute_variables_helper_full: Expression result is '0' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'GotoIf' -- Executing [4350@zap-incoming:17] GotoIf("Zap/49-1", "0?18:19") in new stack -- Goto (zap-incoming,4350,19) [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1691 pbx_substitute_variables_helper_full: Function result is 'Dave Brillert' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1759 pbx_substitute_variables_helper_full: Expression result is '0' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'GotoIf' -- Executing [4350@zap-incoming:19] GotoIf("Zap/49-1", "0?20:21") in new stack -- Goto (zap-incoming,4350,21) [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1691 pbx_substitute_variables_helper_full: Function result is '5555551234' [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:21] Set("Zap/49-1", "CALLERID(number)=95555551234") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [4350@zap-incoming:22] Set("Zap/49-1", "CHANNEL(language)=en") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Gosub' -- Executing [4350@zap-incoming:23] Gosub("Zap/49-1", "customer-aa-mainaa|s|1") in new stack [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Answer' -- Executing [s@customer-aa-mainaa:1] Answer("Zap/49-1", "") in new stack [Oct 16 09:27:28] DEBUG[11247]: chan_dahdi.c:5474 dahdi_indicate: Requested indication -1 on channel Zap/49-1 [Oct 16 09:27:28] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'AGI' -- Executing [s@customer-aa-mainaa:2] AGI("Zap/49-1", "/var/www/scopserv/telephony/scripts/agi/ivr.php|2944") in new stack -- Launched AGI Script /var/www/scopserv/telephony/scripts/agi/ivr.php AGI Tx >> agi_request: /var/www/scopserv/telephony/scripts/agi/ivr.php AGI Tx >> agi_channel: Zap/49-1 [Oct 16 09:27:28] NOTICE[11247]: utils.c:938 ast_carefulwrite: Timed out trying to write AGI Tx >> agi_language: en [Oct 16 09:27:29] NOTICE[11247]: utils.c:938 ast_carefulwrite: Timed out trying to write AGI Tx >> agi_type: Zap AGI Tx >> agi_uniqueid: 1255699648.551 AGI Tx >> agi_callerid: 95555551234 AGI Tx >> agi_calleridname: Dave Brillert AGI Tx >> agi_callingpres: 1 AGI Tx >> agi_callingani2: 0 AGI Tx >> agi_callington: 33 AGI Tx >> agi_callingtns: 0 AGI Tx >> agi_dnid: 4350 AGI Tx >> agi_rdnis: unknown AGI Tx >> agi_context: customer-aa-mainaa AGI Tx >> agi_extension: s AGI Tx >> agi_priority: 2 AGI Tx >> agi_enhanced: 0.0 AGI Tx >> agi_accountcode: _NONE_ AGI Tx >> -- AGI Script /var/www/scopserv/telephony/scripts/agi/ivr.php completed, returning 0 [Oct 16 09:27:29] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Wait' -- Executing [s@customer-aa-mainaa:3] Wait("Zap/49-1", "1") in new stack [Oct 16 09:27:29] DEBUG[5941]: rtp.c:924 ast_rtcp_read: Got RTCP report of 84 bytes [Oct 16 09:27:29] DEBUG[8005]: rtp.c:924 ast_rtcp_read: Got RTCP report of 84 bytes [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 102 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on '38d3fb527ca372c3681d5de77d2be376@172.24.0.2' of Request 102: Match Found [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:5792 process_sdp: T38 state changed to 0 on channel SIP/3328-096d6ba8 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:5872 process_sdp: We're settling with these formats: 0x4 (ulaw) [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:3381 update_call_counter: Updating call counter for outgoing call [Oct 16 09:27:30] DEBUG[21757]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3328 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:8804 build_route: build_route: Contact hop: [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 [Oct 16 09:27:30] DEBUG[21730]: devicestate.c:287 do_state_change: Changing state for SIP/3328 - state 2 (In use) [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 [Oct 16 09:27:30] DEBUG[11051]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5029 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5003 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5000 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5001 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5002 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5017 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 3358 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 3334 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5004 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 Extension Changed 3328[customer-local] new state InUse for Notify User 5030 [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 [Oct 16 09:27:30] DEBUG[21730]: devicestate.c:287 do_state_change: Changing state for SIP/3328 - state 2 (In use) [Oct 16 09:27:30] DEBUG[21730]: chan_sip.c:17152 sip_devicestate: Checking device state for peer 3328 [Oct 16 09:27:30] DEBUG[21779]: app_queue.c:682 handle_statechange: Device 'SIP/3328' changed to state '2' (In use) but we don't care because they're not a member of any queue. -- SIP/3328-096d6ba8 answered Local/3328@customer-local-bda6,2 [Oct 16 09:27:30] DEBUG[21779]: app_queue.c:682 handle_statechange: Device 'SIP/3328' changed to state '2' (In use) but we don't care because they're not a member of any queue. -- Local/3328@customer-local-bda6,1 stopped sounds [Oct 16 09:27:30] DEBUG[11051]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/3328@customer-local [Oct 16 09:27:30] DEBUG[11049]: chan_dahdi.c:5474 dahdi_indicate: Requested indication -1 on channel Zap/54-1 [Oct 16 09:27:30] DEBUG[11049]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/3328@customer-local [Oct 16 09:27:30] DEBUG[21730]: chan_local.c:145 local_devicestate: Checking if extension 3328@customer-local exists (devicestate) [Oct 16 09:27:30] DEBUG[21730]: devicestate.c:287 do_state_change: Changing state for Local/3328@customer-local - state 2 (In use) [Oct 16 09:27:30] DEBUG[21730]: chan_local.c:145 local_devicestate: Checking if extension 3328@customer-local exists (devicestate) [Oct 16 09:27:30] DEBUG[21730]: devicestate.c:287 do_state_change: Changing state for Local/3328@customer-local - state 2 (In use) [Oct 16 09:27:30] DEBUG[21779]: app_queue.c:682 handle_statechange: Device 'Local/3328@customer-local' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Oct 16 09:27:30] DEBUG[21779]: app_queue.c:682 handle_statechange: Device 'Local/3328@customer-local' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Oct 16 09:27:30] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Ping' [Oct 16 09:27:30] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on 'c3a4c2b9-d0d55b62-839a5c63@172.24.0.184' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on '128ac23d-25139a84-825feb1f@172.24.0.103' of Request 130: Match Found -- Local/3328@customer-local-bda6,1 answered Zap/54-1 [Oct 16 09:27:30] DEBUG[11049]: rtp.c:1560 ast_rtp_early_bridge: Channel 'Zap/54-1' has no RTP, not doing anything [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on 'da6298fc-4b21f437-1e45803a@172.24.0.105' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[11049]: chan_dahdi.c:5474 dahdi_indicate: Requested indication -1 on channel Zap/54-1 [Oct 16 09:27:30] DEBUG[11049]: chan_dahdi.c:5474 dahdi_indicate: Requested indication 20 on channel Zap/54-1 [Oct 16 09:27:30] DEBUG[11049]: channel.c:4326 ast_generic_bridge: Got a FRAME_CONTROL (-1) frame on channel Local/3328@customer-local-bda6,1 [Oct 16 09:27:30] DEBUG[11049]: chan_dahdi.c:5474 dahdi_indicate: Requested indication 20 on channel Zap/54-1 [Oct 16 09:27:30] DEBUG[11049]: channel.c:4670 ast_channel_bridge: Bridge stops bridging channels Zap/54-1 and Local/3328@customer-local-bda6,1 [Oct 16 09:27:30] DEBUG[11049]: chan_dahdi.c:5474 dahdi_indicate: Requested indication -1 on channel Zap/54-1 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on 'a2840daf-32107a36-24425c59@172.24.0.140' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on '8862539d-1ec38818-9a0118c3@172.24.0.111' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on '5d5519a7-beb2e45e-85520741@172.24.0.109' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on '53561d28-a6eead1-3443926@172.24.0.113' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on '6f14e703-214fe002-589b7461@172.24.0.100' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[7880]: rtp.c:924 ast_rtcp_read: Got RTCP report of 84 bytes [Oct 16 09:27:30] DEBUG[11049]: chan_dahdi.c:5474 dahdi_indicate: Requested indication 20 on channel Zap/54-1 [Oct 16 09:27:30] DEBUG[11049]: chan_dahdi.c:5474 dahdi_indicate: Requested indication 20 on channel Zap/54-1 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[11051]: rtp.c:2894 ast_rtp_write: Ooh, format changed from unknown to ulaw [Oct 16 09:27:30] DEBUG[11051]: rtp.c:2911 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on '1297ceb-43deb78e-af960bf5@172.24.0.110' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2230 __sip_ack: Acked pending invite 130 [Oct 16 09:27:30] DEBUG[21757]: chan_sip.c:2262 __sip_ack: Stopping retransmission on 'a78a28f4-73d3bbd-b5ae0c2a@172.24.0.102' of Request 130: Match Found [Oct 16 09:27:30] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:30] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:30] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [s@customer-aa-mainaa:4] Set("Zap/49-1", "MAXCOUNT_INVALID=3") in new stack [Oct 16 09:27:30] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [s@customer-aa-mainaa:5] Set("Zap/49-1", "COUNT_INVALID=0") in new stack [Oct 16 09:27:30] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [s@customer-aa-mainaa:6] Set("Zap/49-1", "__IVRNAME=customer-aa-mainaa") in new stack [Oct 16 09:27:30] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [s@customer-aa-mainaa:7] Set("Zap/49-1", "TIMEOUT(digit)=3") in new stack -- Digit timeout set to 3 [Oct 16 09:27:30] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [s@customer-aa-mainaa:8] Set("Zap/49-1", "TIMEOUT(response)=5") in new stack -- Response timeout set to 5 [Oct 16 09:27:30] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'Set' -- Executing [s@customer-aa-mainaa:9] Set("Zap/49-1", "CHANNEL(language)=en") in new stack [Oct 16 09:27:30] DEBUG[11247]: pbx.c:1843 pbx_extension_helper: Launching 'BackGround' -- Executing [s@customer-aa-mainaa:10] BackGround("Zap/49-1", "custom/0002") in new stack [Oct 16 09:27:30] DEBUG[11247]: channel.c:3148 set_format: Set channel Zap/49-1 to write format slin -- Playing 'custom/0002' (language 'en') [Oct 16 09:27:30] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:30] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:30] DEBUG[27764]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:30] DEBUG[21744]: manager.c:2233 process_message: Manager received command 'Ping' [Oct 16 09:27:30] DEBUG[5296]: rtp.c:924 ast_rtcp_read: Got RTCP report of 84 bytes [Oct 16 09:27:30] DEBUG[9711]: rtp.c:924 ast_rtcp_read: Got RTCP report of 84 bytes [Oct 16 09:27:30] DEBUG[21881]: manager.c:2233 process_message: Manager received command 'Ping' [Oct 16 09:27:30] DEBUG[21881]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:30] DEBUG[21881]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:31] DEBUG[21881]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:31] DEBUG[21881]: manager.c:2233 process_message: Manager received command 'Command' [Oct 16 09:27:31] DEBUG[21881]: manager.c:2233 process_message: Manager received command 'Command'