[2021-12-06 19:04:42]     -- Executing [100@adsi-spill:1] Set("Local/redacted@redacted-lines-pre-00000108;2", "CDR_PROP(disable)=1") in new stack
[2021-12-06 19:04:42]     -- Executing [100@adsi-spill:2] Answer("Local/redacted@redacted-lines-pre-00000108;2", "") in new stack
[2021-12-06 19:04:42]     -- Executing [100@adsi-spill:3] Wait("Local/redacted@redacted-lines-pre-00000108;2", "1") in new stack
[2021-12-06 19:04:43]     -- Executing [100@adsi-spill:4] MixMonitor("Local/redacted@redacted-lines-pre-00000108;2", "adsi-1638817483.wav") in new stack
[2021-12-06 19:04:43]     -- Executing [100@adsi-spill:5] PlayTones("Local/redacted@redacted-lines-pre-00000108;2", "2130+2750/0.11") in new stack
[2021-12-06 19:04:43]     -- Executing [100@adsi-spill:6] Wait("Local/redacted@redacted-lines-pre-00000108;2", "0.11") in new stack
[2021-12-06 19:04:43]   == Begin MixMonitor Recording Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:43]     -- Executing [100@adsi-spill:7] StopPlayTones("Local/redacted@redacted-lines-pre-00000108;2", "") in new stack
[2021-12-06 19:04:43]     -- Executing [100@adsi-spill:8] Wait("Local/redacted@redacted-lines-pre-00000108;2", "3") in new stack
[2021-12-06 19:04:43] DTMF[23680][C-0000008c]: channel.c:4002 __ast_read: DTMF begin 'A' received on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:43] DTMF[23680][C-0000008c]: channel.c:4013 __ast_read: DTMF begin passthrough 'A' on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:43] DTMF[11648]: channel.c:4002 __ast_read: DTMF begin 'A' received on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1
[2021-12-06 19:04:43] DTMF[11648]: channel.c:4006 __ast_read: DTMF begin ignored 'A' on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1
[2021-12-06 19:04:43] DTMF[23689][C-0000008c]: channel.c:4002 __ast_read: DTMF begin 'A' received on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:43] DTMF[23689][C-0000008c]: channel.c:4013 __ast_read: DTMF begin passthrough 'A' on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:43] DTMF[23690][C-0000008c]: channel.c:4002 __ast_read: DTMF begin 'A' received on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:43] DTMF[23690][C-0000008c]: channel.c:4013 __ast_read: DTMF begin passthrough 'A' on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:44] DTMF[23680][C-0000008c]: channel.c:3888 __ast_read: DTMF end 'A' received on PJSIP/ATAxGrandstream1-00000040, duration 180 ms
[2021-12-06 19:04:44] DTMF[23680][C-0000008c]: channel.c:3939 __ast_read: DTMF end accepted with begin 'A' on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:44] DTMF[23680][C-0000008c]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:44] DTMF[11648]: channel.c:3888 __ast_read: DTMF end 'A' received on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1, duration 180 ms
[2021-12-06 19:04:44] DTMF[11648]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1
[2021-12-06 19:04:44] DTMF[23689][C-0000008c]: channel.c:3888 __ast_read: DTMF end 'A' received on Local/2575@redacted-originate-local-00000107;2, duration 180 ms
[2021-12-06 19:04:44] DTMF[23689][C-0000008c]: channel.c:3939 __ast_read: DTMF end accepted with begin 'A' on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:44] DTMF[23689][C-0000008c]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:44] DTMF[23690][C-0000008c]: channel.c:3888 __ast_read: DTMF end 'A' received on Local/redacted@redacted-lines-pre-00000108;2, duration 180 ms
[2021-12-06 19:04:44] DTMF[23690][C-0000008c]: channel.c:3939 __ast_read: DTMF end accepted with begin 'A' on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:44] DTMF[23690][C-0000008c]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:46]     -- Executing [100@adsi-spill:9] PlayTones("Local/redacted@redacted-lines-pre-00000108;2", "2130+2750/0.11") in new stack
[2021-12-06 19:04:46]     -- Executing [100@adsi-spill:10] Wait("Local/redacted@redacted-lines-pre-00000108;2", "0.08") in new stack
[2021-12-06 19:04:46]     -- Executing [100@adsi-spill:11] StopPlayTones("Local/redacted@redacted-lines-pre-00000108;2", "") in new stack
[2021-12-06 19:04:46]     -- Executing [100@adsi-spill:12] System("Local/redacted@redacted-lines-pre-00000108;2", "/sbin/asterisk -rx "core set debug 1"") in new stack
[2021-12-06 19:04:46]     -- Remote UNIX connection
[2021-12-06 19:04:46]     -- Remote UNIX connection disconnected
[2021-12-06 19:04:46] DEBUG[23690][C-0000008c]: pbx.c:2938 pbx_extension_helper: Launching 'TryExec'
[2021-12-06 19:04:46]     -- Executing [100@adsi-spill:13] TryExec("Local/redacted@redacted-lines-pre-00000108;2", "GetCPEID") in new stack
[2021-12-06 19:04:46] DEBUG[23690][C-0000008c]: app_getcpeid.c:83 cpeid_exec: Starting load session
[2021-12-06 19:04:46] DEBUG[23690][C-0000008c]: res_adsi.c:398 adsi_transmit_message_full: Switch to data is sent!
[2021-12-06 19:04:47] DEBUG[23680][C-0000008c]: res_rtp_asterisk.c:5513 create_dtmf_frame: (0x7ff9b8bc7bc0) RTP creating BEGIN DTMF Frame: 65 (A), at redacted:5004
[2021-12-06 19:04:47] DTMF[23680][C-0000008c]: channel.c:4002 __ast_read: DTMF begin 'A' received on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:47] DTMF[23680][C-0000008c]: channel.c:4013 __ast_read: DTMF begin passthrough 'A' on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:47] DTMF[11648]: channel.c:4002 __ast_read: DTMF begin 'A' received on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1
[2021-12-06 19:04:47] DTMF[11648]: channel.c:4006 __ast_read: DTMF begin ignored 'A' on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1
[2021-12-06 19:04:47] DTMF[23689][C-0000008c]: channel.c:4002 __ast_read: DTMF begin 'A' received on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:47] DTMF[23689][C-0000008c]: channel.c:4013 __ast_read: DTMF begin passthrough 'A' on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:47] DTMF[23690][C-0000008c]: channel.c:4002 __ast_read: DTMF begin 'A' received on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:47] DTMF[23690][C-0000008c]: channel.c:4013 __ast_read: DTMF begin passthrough 'A' on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:47] DEBUG[23680][C-0000008c]: res_rtp_asterisk.c:5513 create_dtmf_frame: (0x7ff9b8bc7bc0) RTP creating END DTMF Frame: 65 (A), at redacted:5004
[2021-12-06 19:04:47] DTMF[23680][C-0000008c]: channel.c:3888 __ast_read: DTMF end 'A' received on PJSIP/ATAxGrandstream1-00000040, duration 180 ms
[2021-12-06 19:04:47] DTMF[23680][C-0000008c]: channel.c:3939 __ast_read: DTMF end accepted with begin 'A' on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:47] DTMF[23680][C-0000008c]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on PJSIP/ATAxGrandstream1-00000040
[2021-12-06 19:04:47] DTMF[11648]: channel.c:3888 __ast_read: DTMF end 'A' received on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1, duration 180 ms
[2021-12-06 19:04:47] DTMF[11648]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on CBAnn/linePJSIP/ATAxGrandstream1-00000106;1
[2021-12-06 19:04:47] DTMF[23689][C-0000008c]: channel.c:3888 __ast_read: DTMF end 'A' received on Local/2575@redacted-originate-local-00000107;2, duration 180 ms
[2021-12-06 19:04:47] DTMF[23689][C-0000008c]: channel.c:3939 __ast_read: DTMF end accepted with begin 'A' on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:47] DTMF[23689][C-0000008c]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on Local/2575@redacted-originate-local-00000107;2
[2021-12-06 19:04:47] DTMF[23690][C-0000008c]: channel.c:3888 __ast_read: DTMF end 'A' received on Local/redacted@redacted-lines-pre-00000108;2, duration 180 ms
[2021-12-06 19:04:47] DTMF[23690][C-0000008c]: channel.c:3939 __ast_read: DTMF end accepted with begin 'A' on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:47] DTMF[23690][C-0000008c]: channel.c:3977 __ast_read: DTMF end passthrough 'A' on Local/redacted@redacted-lines-pre-00000108;2
[2021-12-06 19:04:47]        > ADSI Compatible CPE Detected
[2021-12-06 19:04:47] DEBUG[23690][C-0000008c]: res_adsi.c:310 __adsi_transmit_messages: Message 1, of 4 input bytes, 1734 output bytes
[2021-12-06 19:04:47] DEBUG[23690][C-0000008c]: audiohook.c:189 ast_audiohook_write_frame: Flushing audiohook 0x7ff9c408d6a0 so it remains in sync
[2021-12-06 19:04:47] DEBUG[23690][C-0000008c]: audiohook.c:189 ast_audiohook_write_frame: Flushing audiohook 0x7ff9c408d6a0 so it remains in sync
[2021-12-06 19:04:47] DEBUG[23690][C-0000008c]: res_adsi.c:325 __adsi_transmit_messages: Sent total spill of 1734 bytes
[2021-12-06 19:04:47] DEBUG[23690][C-0000008c]: channel.c:5826 set_format: Channel Local/redacted@redacted-lines-pre-00000108;2 setting write format path: slin -> slin
[2021-12-06 19:04:47] DEBUG[23690][C-0000008c]: channel.c:3215 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[2021-12-06 19:04:47] DEBUG[23690][C-0000008c]: channel.c:8275 ast_channel_start_silence_generator: Started silence generator on 'Local/redacted@redacted-lines-pre-00000108;2'

[2021-12-06 19:04:48] DEBUG[23690][C-0000008c]: channel.c:3215 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2021-12-06 19:04:48] DEBUG[23690][C-0000008c]: channel.c:8310 ast_channel_stop_silence_generator: Stopped silence generator on 'Local/redacted@redacted-lines-pre-00000108;2'
[2021-12-06 19:04:48] DEBUG[23690][C-0000008c]: channel.c:5826 set_format: Channel Local/redacted@redacted-lines-pre-00000108;2 setting write format path: ulaw -> slin
[2021-12-06 19:04:48] WARNING[23690][C-0000008c]: res_adsi.c:342 __adsi_transmit_messages: Unexpected response to ack:  (retry 1)
[2021-12-06 19:04:49]        > No ADSI CPE detected (0)
[2021-12-06 19:04:49] DEBUG[23690][C-0000008c]: channel.c:5826 set_format: Channel Local/redacted@redacted-lines-pre-00000108;2 setting write format path: slin -> slin
[2021-12-06 19:04:49] DEBUG[23690][C-0000008c]: channel.c:5826 set_format: Channel Local/redacted@redacted-lines-pre-00000108;2 setting read format path: slin -> slin
[2021-12-06 19:04:49] DEBUG[23690][C-0000008c]: app_getcpeid.c:85 cpeid_exec: Ending load session
[2021-12-06 19:04:49] DEBUG[23690][C-0000008c]: pbx.c:2938 pbx_extension_helper: Launching 'System'
[2021-12-06 19:04:49]     -- Executing [100@adsi-spill:14] System("Local/redacted@redacted-lines-pre-00000108;2", "/sbin/asterisk -rx "core set debug 0"") in new stack
[2021-12-06 19:04:49] DEBUG[23695][C-0000008c]: audiohook.c:336 audiohook_read_frame_both: Failed to get 160 samples from write factory 0x7ff9c408e168
[2021-12-06 19:04:49] DEBUG[23695][C-0000008c]: audiohook.c:275 audiohook_read_frame_both: Read factory 0x7ff9c408d728 and write factory 0x7ff9c408e168 both fail to provide 160 samples
[2021-12-06 19:04:49]     -- Remote UNIX connection
[2021-12-06 19:04:49]     -- Remote UNIX connection disconnected
[2021-12-06 19:04:49]     -- Executing [100@adsi-spill:15] NoOp("Local/redacted@redacted-lines-pre-00000108;2", "FAILED") in new stack