Attendant transfer call recording stopped

faqterson · May 26, 2025, 3:08pm

Why did the following 2 calls Mixmonitor stop:

[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [0987654321@extensions:1] Gosub(“PJSIP/101-00063d60”, “trunk,s,1(0987654321)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:1] Gosub(“PJSIP/101-00063d60”, “check-record,s,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:1] Set(“PJSIP/101-00063d60”, “RECORD=YES”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:2] GotoIf(“PJSIP/101-00063d60”, “1?3:7”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx_builtins.c: Goto (check-record,s,3)
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:3] Set(“PJSIP/101-00063d60”, “__CALLRECID=1748254699.4143947”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:4] Set(“PJSIP/101-00063d60”, “EXISTS=0”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:5] GotoIf(“PJSIP/101-00063d60”, “0?7”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:6] MixMonitor(“PJSIP/101-00063d60”, “1748254699.4143947.wav,ab,/usr/local/scripts/convert 1748254699.4143947”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:7] NoOp(“PJSIP/101-00063d60”, “RECORD: YES”) in new stack
[May 26 12:18:19] VERBOSE[50171][C-00030416] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/101-00063d60
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-record:8] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:2] Set(“PJSIP/101-00063d60”, “__SIPCALLID=0_278054131@192.168.18.206”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:3] Set(“PJSIP/101-00063d60”, “__APIURL=”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:4] Set(“PJSIP/101-00063d60”, “AGENT=PJSIP/101”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:5] Set(“PJSIP/101-00063d60”, “__NUM=0987654321”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:6] Set(“PJSIP/101-00063d60”, “CHANNEL(hangup_handler_push)=hdlr3,h,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:7] ExecIf(“PJSIP/101-00063d60”, “?Set(temp=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:8] ExecIf(“PJSIP/101-00063d60”, “?Set(temp=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:9] ExecIf(“PJSIP/101-00063d60”, “0?Set(EXT=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:10] ExecIf(“PJSIP/101-00063d60”, “0?Set(CFEXT=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:11] Gosub(“PJSIP/101-00063d60”, “check-pin,s,1(0987654321)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-pin:1] GotoIf(“PJSIP/101-00063d60”, “0?s-pin,2”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-pin:2] Gosub(“PJSIP/101-00063d60”, “check-barring,s,1(0987654321)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:1] ExecIf(“PJSIP/101-00063d60”, “1?Set(EXT=101)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:2] GotoIf(“PJSIP/101-00063d60”, “0?s,20”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:3] GotoIf(“PJSIP/101-00063d60”, “0?s-Internal,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:4] GotoIf(“PJSIP/101-00063d60”, “0?s-Internal,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:5] GotoIf(“PJSIP/101-00063d60”, “0?s-National,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:6] GotoIf(“PJSIP/101-00063d60”, “0?s-National,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:7] GotoIf(“PJSIP/101-00063d60”, “0?s-National,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:8] GotoIf(“PJSIP/101-00063d60”, “0?s-National,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:9] GotoIf(“PJSIP/101-00063d60”, “0?s-National,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-barring:10] GotoIf(“PJSIP/101-00063d60”, “1?s-Cell,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx_builtins.c: Goto (check-barring,s-Cell,1)
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-Cell@check-barring:1] NoOp(“PJSIP/101-00063d60”, “The number is a cell number”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-Cell@check-barring:2] GotoIf(“PJSIP/101-00063d60”, “0?s-Cell,6”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-Cell@check-barring:3] GotoIf(“PJSIP/101-00063d60”, “1?s-Cell,6”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx_builtins.c: Goto (check-barring,s-Cell,6)
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-Cell@check-barring:6] NoOp(“PJSIP/101-00063d60”, “User is Allowed to call Cellphones”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-Cell@check-barring:7] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-pin:3] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:12] Gosub(“PJSIP/101-00063d60”, “check-queueout,s,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-queueout:1] Set(“PJSIP/101-00063d60”, “DIALER=101”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-queueout:2] Set(“PJSIP/101-00063d60”, “queue_out=reception_outbound”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@check-queueout:3] GotoIf(“PJSIP/101-00063d60”, “0?s-NO,1:s-OK,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx_builtins.c: Goto (check-queueout,s-OK,1)
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-OK@check-queueout:1] Set(“PJSIP/101-00063d60”, “__QUEUE_OUT=reception_outbound”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-OK@check-queueout:2] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:13] Gosub(“PJSIP/101-00063d60”, “set-trunk,s,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:1] ExecIf(“PJSIP/101-00063d60”, “0?Set(CALLERID(num)=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:2] ExecIf(“PJSIP/101-00063d60”, “1?Set(EXT=101)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:3] Set(“PJSIP/101-00063d60”, “department=HO”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:4] ExecIf(“PJSIP/101-00063d60”, “0?Set(ARRAY(forwarder,department)=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:5] ExecIf(“PJSIP/101-00063d60”, “0?Set(ARRAY(CALLERID,department)=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:6] Set(“PJSIP/101-00063d60”, “CHANNEL(musicclass)=HO”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:7] GotoIf(“PJSIP/101-00063d60”, “0?s-global,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:8] Set(“PJSIP/101-00063d60”, “TRUNK=Use Global”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:9] Set(“PJSIP/101-00063d60”, “FAILTRUNK=Use Global”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-trunk:10] GotoIf(“PJSIP/101-00063d60”, “1?s-global,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx_builtins.c: Goto (set-trunk,s-global,1)
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-global@set-trunk:1] Set(“PJSIP/101-00063d60”, “TRUNK=PJSIP/dst@27123456789”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-global@set-trunk:2] Set(“PJSIP/101-00063d60”, “FAILTRUNK=PJSIP/dst@27123456789”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s-global@set-trunk:3] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:14] Gosub(“PJSIP/101-00063d60”, “set-outcallerid,s,1”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-outcallerid:1] ExecIf(“PJSIP/101-00063d60”, “1?Set(EXT=101)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-outcallerid:2] Set(“PJSIP/101-00063d60”, “callerid=27123456789”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-outcallerid:3] ExecIf(“PJSIP/101-00063d60”, “0?SET(callerid=101)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-outcallerid:4] Set(“PJSIP/101-00063d60”, “CALLERID(num)=27123456789”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-outcallerid:5] ExecIf(“PJSIP/101-00063d60”, “0?Set(CALLERID(num)=)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@set-outcallerid:6] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:15] System(“PJSIP/101-00063d60”, “echo “1748254699|1748254699.4143947|reception_outbound|PJSIP/101|CALLOUTBOUND||0987654321” >> /var/log/asterisk/queue_log”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] pbx.c: Executing [s@trunk:16] Dial(“PJSIP/101-00063d60”, “PJSIP/0987654321@27123456789,300,TrU(connected,PJSIP/101,1748254699,1748254699.4143947)”) in new stack
[May 26 12:18:19] VERBOSE[50170][C-00030416] app_dial.c: Called PJSIP/0987654321@27123456789
[May 26 12:18:23] VERBOSE[50170][C-00030416] app_dial.c: PJSIP/27123456789-00063d61 is ringing
[May 26 12:18:31] VERBOSE[50170][C-00030416] app_dial.c: PJSIP/27123456789-00063d61 answered PJSIP/101-00063d60
[May 26 12:18:31] VERBOSE[50170][C-00030416] app_stack.c: PJSIP/27123456789-00063d61 Internal Gosub(connected,s,1(PJSIP/101,1748254699,1748254699.4143947)) start
[May 26 12:18:31] VERBOSE[50170][C-00030416] pbx.c: Executing [s@connected:1] Set(“PJSIP/27123456789-00063d61”, “AGENT=PJSIP/101”) in new stack
[May 26 12:18:31] VERBOSE[50170][C-00030416] pbx.c: Executing [s@connected:2] Set(“PJSIP/27123456789-00063d61”, “HOLDTIME=12”) in new stack
[May 26 12:18:31] VERBOSE[50170][C-00030416] pbx.c: Executing [s@connected:3] System(“PJSIP/27123456789-00063d61”, “echo “1748254711|1748254699.4143947|reception_outbound|PJSIP/101|CONNECT|12|1748254699.4143947|12” >> /var/log/asterisk/queue_log”) in new stack
[May 26 12:18:31] VERBOSE[50170][C-00030416] pbx.c: Executing [s@connected:4] Return(“PJSIP/27123456789-00063d61”, “”) in new stack
[May 26 12:18:31] VERBOSE[50170][C-00030416] app_stack.c: Spawn extension (external, , 1) exited non-zero on ‘PJSIP/27123456789-00063d61’
[May 26 12:18:31] VERBOSE[50170][C-00030416] app_stack.c: PJSIP/27123456789-00063d61 Internal Gosub(connected,s,1(PJSIP/101,1748254699,1748254699.4143947)) complete GOSUB_RETVAL=
[May 26 12:18:31] VERBOSE[50200][C-00030416] bridge_channel.c: Channel PJSIP/27123456789-00063d61 joined ‘simple_bridge’ bc-bridge <04a7d452-89b8-4630-9bb5-b306dbbcff6b>
[May 26 12:18:31] VERBOSE[50170][C-00030416] bridge_channel.c: Channel PJSIP/101-00063d60 joined ‘simple_bridge’ bc-bridge <04a7d452-89b8-4630-9bb5-b306dbbcff6b>
[May 26 12:18:39] WARNING[50200][C-00030416] res_musiconhold.c: Music onHOld class ‘HO’ not found in memory/database. Verify your configuration.
[May 26 12:18:39] WARNING[50200][C-00030416] res_musiconhold.c: Music onHOld class ‘HO’ not found in memory/database. Verify your configuration.
[May 26 12:18:39] VERBOSE[50200][C-00030416] res_musiconhold.c: Started music onHOld, class ‘default’, on channel ‘PJSIP/27123456789-00063d61’
[May 26 12:19:11] VERBOSE[50200][C-00030416] res_musiconhold.c: Stopped music onHOld on PJSIP/27123456789-00063d61
[May 26 12:19:20] WARNING[50200][C-00030416] res_musiconhold.c: Music onHOld class ‘HO’ not found in memory/database. Verify your configuration.
[May 26 12:19:20] WARNING[50200][C-00030416] res_musiconhold.c: Music onHOld class ‘HO’ not found in memory/database. Verify your configuration.
[May 26 12:19:20] VERBOSE[50200][C-00030416] res_musiconhold.c: Started music onHOld, class ‘default’, on channel ‘PJSIP/27123456789-00063d61’
[May 26 12:19:21] VERBOSE[50200][C-00030416] res_musiconhold.c: Stopped music onHOld on PJSIP/27123456789-00063d61
[May 26 12:19:21] VERBOSE[50170][C-00030416] bridge_channel.c: Channel PJSIP/101-00063d60 left ‘simple_bridge’ bc-bridge <04a7d452-89b8-4630-9bb5-b306dbbcff6b>
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Spawn extension (trunk, s, 16) exited non-zero on ‘PJSIP/101-00063d60’
[May 26 12:19:21] VERBOSE[50170][C-00030416] app_stack.c: PJSIP/101-00063d60 Internal Gosub(hdlr3,h,1) start
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h@hdlr3:1] Gosub(“PJSIP/101-00063d60”, “rtploss,s,1”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:1] Set(“PJSIP/101-00063d60”, “RTPLOSS=minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=1587.000000;reported_avglost=793.500000;reported_stdevlost=28.160258;”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:2] System(“PJSIP/101-00063d60”, “echo “uniqueid=1748254699.4143947,minrxlost=0.000000,maxrxlost=0.000000,avgrxlost=0.000000,stdevrxlost=0.000000,reported_minlost=0.000000,reported_maxlost=1587.000000,reported_avglost=793.500000,reported_stdevlost=28.160258,” >> /var/log/asterisk/RTPLoss.log”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:3] Set(“PJSIP/101-00063d60”, “RTPINFO=minrxlost=0.000000,maxrxlost=0.000000,avgrxlost=0.000000,stdevrxlost=0.000000,reported_minlost=0.000000,reported_maxlost=1587.000000,reported_avglost=793.500000,reported_stdevlost=28.160258,”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:4] Set(“PJSIP/101-00063d60”, “minrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:5] Set(“PJSIP/101-00063d60”, “maxrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:6] Set(“PJSIP/101-00063d60”, “avgrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:7] Set(“PJSIP/101-00063d60”, “stdevrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:8] Set(“PJSIP/101-00063d60”, “reported_minlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:9] Set(“PJSIP/101-00063d60”, “reported_maxlost=1587.000000”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:10] Set(“PJSIP/101-00063d60”, “reported_avglost=793.500000”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:11] Set(“PJSIP/101-00063d60”, “reported_stdevlost=28.160258”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:12] Set(“PJSIP/101-00063d60”, “ODBC_rtploss(1748254699.4143947)=0.000000,0.000000,0.000000,0.000000,0.000000,1587.000000,793.500000,28.160258”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [s@rtploss:13] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h@hdlr3:2] AGI(“PJSIP/101-00063d60”, “api.agi,0_278054131@192.168.18.206,1748254699.4143947,”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/api.agi
[May 26 12:19:21] VERBOSE[50170][C-00030416] res_agi.c: api.agi,0_278054131@192.168.18.206,1748254699.4143947,:
[May 26 12:19:21] VERBOSE[50170][C-00030416] res_agi.c: <PJSIP/101-00063d60>AGI Script api.agi completed, returning 0
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h@hdlr3:3] GotoIf(“PJSIP/101-00063d60”, “reception_outbound?h-QUEUE,1”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx_builtins.c: Goto (hdlr3,h-QUEUE,1)
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h-QUEUE@hdlr3:1] GotoIf(“PJSIP/101-00063d60”, “1?h-QUEUE-ANSWER,1:h-QUEUE-ABANDON,1”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx_builtins.c: Goto (hdlr3,h-QUEUE-ANSWER,1)
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h-QUEUE-ANSWER@hdlr3:1] Set(“PJSIP/101-00063d60”, “CALLTIME=50”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h-QUEUE-ANSWER@hdlr3:2] Set(“PJSIP/101-00063d60”, “HOLDTIME=12”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h-QUEUE-ANSWER@hdlr3:3] System(“PJSIP/101-00063d60”, “echo “1748254761|1748254699.4143947|reception_outbound|PJSIP/101|COMPLETECALLER|12|50” >> /var/log/asterisk/queue_log”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] pbx.c: Executing [h-QUEUE-ANSWER@hdlr3:4] Return(“PJSIP/101-00063d60”, “”) in new stack
[May 26 12:19:21] VERBOSE[50170][C-00030416] app_stack.c: Spawn extension (trunk, s, 16) exited non-zero on ‘PJSIP/101-00063d60’
[May 26 12:19:21] VERBOSE[50170][C-00030416] app_stack.c: PJSIP/101-00063d60 Internal Gosub(hdlr3,h,1) complete GOSUB_RETVAL=
[May 26 12:19:21] VERBOSE[50171][C-00030416] app_mixmonitor.c: MixMonitor close filestream (mixed)
[May 26 12:19:21] VERBOSE[50171][C-00030416] app_mixmonitor.c: Executing [/usr/local/scripts/convert 1748254699.4143947]
[May 26 12:19:22] VERBOSE[50171][C-00030416] app_mixmonitor.c: End MixMonitor Recording PJSIP/101-00063d60
[May 26 12:20:19] WARNING[50200][C-00030416] res_musiconhold.c: Music onHOld class ‘HO’ not found in memory/database. Verify your configuration.
[May 26 12:20:19] WARNING[50200][C-00030416] res_musiconhold.c: Music onHOld class ‘EB’ not found in memory/database. Verify your configuration.
[May 26 12:20:19] VERBOSE[50200][C-00030416] res_musiconhold.c: Started music onHOld, class ‘default’, on channel ‘PJSIP/27123456789-00063d61’
[May 26 12:20:44] VERBOSE[50200][C-00030416] res_musiconhold.c: Stopped music onHOld on PJSIP/27123456789-00063d61
[May 26 12:23:16] VERBOSE[50200][C-00030416] bridge_channel.c: Channel PJSIP/27123456789-00063d61 left ‘simple_bridge’ bc-bridge <691c6964-fadd-4968-a7a2-2ca563f80b00>

Surely after an Attendant transfer the call should have continued on the second leg:

[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx_realtime.c: Executing [706@extensions:1] Gosub(“PJSIP/101-00063d62”, “exten,s,1(706)”)
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:1] Gosub(“PJSIP/101-00063d62”, “check-record,s,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:1] Set(“PJSIP/101-00063d62”, “RECORD=YES”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:2] GotoIf(“PJSIP/101-00063d62”, “1?3:7”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx_builtins.c: Goto (check-record,s,3)
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:3] Set(“PJSIP/101-00063d62”, “__CALLRECID=1748254732.4143967”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:4] Set(“PJSIP/101-00063d62”, “EXISTS=0”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:5] GotoIf(“PJSIP/101-00063d62”, “0?7”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:6] MixMonitor(“PJSIP/101-00063d62”, “1748254732.4143967.wav,ab,/usr/local/scripts/convert 1748254732.4143967”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:7] NoOp(“PJSIP/101-00063d62”, “RECORD: YES”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@check-record:8] Return(“PJSIP/101-00063d62”, “”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:2] NoOp(“PJSIP/101-00063d62”, “1748254732.4143967”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:3] PlayTones(“PJSIP/101-00063d62”, “40033/400,0/200,40033/400,0/2000”) in new stack
[May 26 12:18:52] VERBOSE[50208][C-00030417] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/101-00063d62
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:4] Set(“PJSIP/101-00063d62”, “__SIPCALLID=0_1895679494@192.168.18.206”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:5] Set(“PJSIP/101-00063d62”, “__APIURL=”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:6] Set(“PJSIP/101-00063d62”, “CHANNEL(hangup_handler_push)=hdlrexten,h,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:7] NoOp(“PJSIP/101-00063d62”, "AVAILORIGCHAN = ") in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:8] Set(“PJSIP/101-00063d62”, “EXT=706”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:9] Set(“PJSIP/101-00063d62”, “ARG1=”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:10] Set(“PJSIP/101-00063d62”, “OPTIONS=”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:11] Set(“PJSIP/101-00063d62”, “ARG2=”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:12] Set(“PJSIP/101-00063d62”, “DEV=706”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:13] Set(“PJSIP/101-00063d62”, “DND=no”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:14] ChanIsAvail(“PJSIP/101-00063d62”, “PJSIP/706,s”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:15] NoOp(“PJSIP/101-00063d62”, “1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:16] Set(“PJSIP/101-00063d62”, “TIME=20”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:17] Set(“PJSIP/101-00063d62”, “CW=1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:18] Set(“PJSIP/101-00063d62”, “temp=”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:19] Set(“PJSIP/101-00063d62”, “RETURN=”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:20] Set(“PJSIP/101-00063d62”, “CF=0”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:21] GotoIf(“PJSIP/101-00063d62”, “0?s-CF,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:22] Set(“PJSIP/101-00063d62”, “CFBU=0”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:23] GotoIf(“PJSIP/101-00063d62”, “0?s-CFBU,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:24] GotoIf(“PJSIP/101-00063d62”, “0?s-VM,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:25] GotoIf(“PJSIP/101-00063d62”, “0?s-DIAL,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:26] GotoIf(“PJSIP/101-00063d62”, “?s-RETURN,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:27] GotoIf(“PJSIP/101-00063d62”, “0?s-DND,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:28] GotoIf(“PJSIP/101-00063d62”, “0?s-BUSY,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@exten:29] Goto(“PJSIP/101-00063d62”, “s-DIAL,1”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx_builtins.c: Goto (exten,s-DIAL,1)
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s-DIAL@exten:1] Dial(“PJSIP/101-00063d62”, “PJSIP/706,300,tTrb(set-headers-queue^s^1)”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] app_stack.c: PJSIP/706-00063d64 Internal Gosub(set-headers-queue,s,1) start
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@set-headers-queue:1] Set(“PJSIP/706-00063d64”, “PJSIP_HEADER(add,CallRecUniqueid)=1748254732.4143967”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] pbx.c: Executing [s@set-headers-queue:2] Return(“PJSIP/706-00063d64”, “”) in new stack
[May 26 12:18:52] VERBOSE[50207][C-00030417] app_stack.c: Spawn extension (extensions, s-DIAL, 1) exited non-zero on ‘PJSIP/706-00063d64’
[May 26 12:18:52] VERBOSE[50207][C-00030417] app_stack.c: PJSIP/706-00063d64 Internal Gosub(set-headers-queue,s,1) complete GOSUB_RETVAL=
[May 26 12:18:52] VERBOSE[50207][C-00030417] app_dial.c: Called PJSIP/706
[May 26 12:18:52] VERBOSE[50207][C-00030417] app_dial.c: PJSIP/706-00063d64 is ringing
[May 26 12:18:57] VERBOSE[50207][C-00030417] app_dial.c: PJSIP/706-00063d64 answered PJSIP/101-00063d62
[May 26 12:18:57] VERBOSE[50213][C-00030417] bridge_channel.c: Channel PJSIP/706-00063d64 joined ‘simple_bridge’ bc-bridge <691c6964-fadd-4968-a7a2-2ca563f80b00>
[May 26 12:18:57] VERBOSE[50207][C-00030417] bridge_channel.c: Channel PJSIP/101-00063d62 joined ‘simple_bridge’ bc-bridge <691c6964-fadd-4968-a7a2-2ca563f80b00>
[May 26 12:19:11] WARNING[50213][C-00030417] res_musiconhold.c: Music onHOld class ‘HO’ not found in memory/database. Verify your configuration.
[May 26 12:19:11] VERBOSE[50213][C-00030417] res_musiconhold.c: Started music onHOld, class ‘default’, on channel ‘PJSIP/706-00063d64’
[May 26 12:19:21] VERBOSE[50213][C-00030417] res_musiconhold.c: Stopped music onHOld on PJSIP/706-00063d64
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Spawn extension (exten, s-DIAL, 1) exited non-zero on ‘PJSIP/101-00063d62’
[May 26 12:19:21] VERBOSE[50207][C-00030417] app_stack.c: PJSIP/101-00063d62 Internal Gosub(hdlrexten,h,1) start
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [h@hdlrexten:1] Gosub(“PJSIP/101-00063d62”, “rtploss,s,1”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:1] Set(“PJSIP/101-00063d62”, “RTPLOSS=minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000;”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:2] System(“PJSIP/101-00063d62”, “echo “uniqueid=1748254732.4143967,minrxlost=0.000000,maxrxlost=0.000000,avgrxlost=0.000000,stdevrxlost=0.000000,reported_minlost=0.000000,reported_maxlost=0.000000,reported_avglost=0.000000,reported_stdevlost=0.000000,” >> /var/log/asterisk/RTPLoss.log”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:3] Set(“PJSIP/101-00063d62”, “RTPINFO=minrxlost=0.000000,maxrxlost=0.000000,avgrxlost=0.000000,stdevrxlost=0.000000,reported_minlost=0.000000,reported_maxlost=0.000000,reported_avglost=0.000000,reported_stdevlost=0.000000,”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:4] Set(“PJSIP/101-00063d62”, “minrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:5] Set(“PJSIP/101-00063d62”, “maxrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:6] Set(“PJSIP/101-00063d62”, “avgrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:7] Set(“PJSIP/101-00063d62”, “stdevrxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:8] Set(“PJSIP/101-00063d62”, “reported_minlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:9] Set(“PJSIP/101-00063d62”, “reported_maxlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:10] Set(“PJSIP/101-00063d62”, “reported_avglost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:11] Set(“PJSIP/101-00063d62”, “reported_stdevlost=0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:12] Set(“PJSIP/101-00063d62”, “ODBC_rtploss(1748254732.4143967)=0.000000,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000,0.000000”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [s@rtploss:13] Return(“PJSIP/101-00063d62”, “”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] pbx.c: Executing [h@hdlrexten:2] AGI(“PJSIP/101-00063d62”, “api.agi,0_1895679494@192.168.18.206,1748254732.4143967,”) in new stack
[May 26 12:19:21] VERBOSE[50207][C-00030417] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/api.agi
[May 26 12:19:21] VERBOSE[50207][C-00030417] res_agi.c: api.agi,0_1895679494@192.168.18.206,1748254732.4143967,:
[May 26 12:19:21] VERBOSE[50207][C-00030417] res_agi.c: <PJSIP/101-00063d62>AGI Script api.agi completed, returning 0
[May 26 12:19:21] NOTICE[50207][C-00030417] app_stack.c: PJSIP/101-00063d62 Abnormal ‘Gosub(hdlrexten,h,1)’ exit. Popping routine return locations.
[May 26 12:19:21] VERBOSE[50208][C-00030417] app_mixmonitor.c: MixMonitor close filestream (mixed)
[May 26 12:19:21] VERBOSE[50208][C-00030417] app_mixmonitor.c: Executing [/usr/local/scripts/convert 1748254732.4143967]
[May 26 12:19:21] VERBOSE[50208][C-00030417] app_mixmonitor.c: End MixMonitor Recording PJSIP/101-00063d62
[May 26 12:23:16] VERBOSE[50213][C-00030417] bridge_channel.c: Channel PJSIP/706-00063d64 left ‘simple_bridge’ bc-bridge <691c6964-fadd-4968-a7a2-2ca563f80b00>

The CEL database shows an Attendant transfer:

| 17105407 | ATTENDEDTRANSFER | 2025-05-26 12:19:21 | Reception HO | 27123456789 | 101 | | 0987654321 | s | trunk | PJSIP/101-00063d60 | Dial | PJSIP/0987654321@27123456789,300,TrU(connected,PJSIP/101,1748254699,1748254699.4 | 3 | | 1748254699.4143947 | 1748254699.4143947 | | | {“bridge1_id”:“04a7d452-89b8-4630-9bb5-b306dbbcff6b”,“channel2_name”:“PJSIP/101-00063d62”,“channel2_uniqueid”:“1748254732.4143967”,“bridge2_id”:“691c6964-fadd-4968-a7a2-2ca563f80b00”,“transferee_channel_name”:“PJSIP/27123456789-00063d61”,“transferee_channel_uniqueid”:“1748254699.4143951”,“transfer_target_channel_name”:“PJSIP/706-00063d64”,“transfer_target_channel_uniqueid”:“1748254732.4143975”} |

jcolp · May 26, 2025, 9:00pm

MixMonitor records the channel it is invoked on. In the given output, that channel is hung up so the recording stops.

faqterson · May 27, 2025, 5:43am

Was this as a result of the device that did the transfer?

I haven’t seen this problem before, normally Mixmonitor continues on the second leg.

faqterson · May 27, 2025, 7:06am

I did a test again using a Yealink W56H and experience the same problem:

    -- Executing [0123456789@extensions:1] Gosub("PJSIP/214-00000088", "trunk,s,1(0123456789)") in new stack
    -- Executing [s@trunk:1] Gosub("PJSIP/214-00000088", "check-record,s,1") in new stack
    -- Executing [s@check-record:1] Set("PJSIP/214-00000088", "RECORD=YES") in new stack
    -- Executing [s@check-record:2] GotoIf("PJSIP/214-00000088", "1?3:6") in new stack
    -- Goto (check-record,s,3)
    -- Executing [s@check-record:3] Set("PJSIP/214-00000088", "EXISTS=0") in new stack
    -- Executing [s@check-record:4] GotoIf("PJSIP/214-00000088", "0?6") in new stack
    -- Executing [s@check-record:5] MixMonitor("PJSIP/214-00000088", "1748327078.276.wav,ab,/usr/local/scripts/convert 1748327078.276") in new stack
    -- Executing [s@check-record:6] NoOp("PJSIP/214-00000088", "RECORD: YES") in new stack
    -- Executing [s@check-record:7] Return("PJSIP/214-00000088", "") in new stack
    -- Executing [s@trunk:2] Set("PJSIP/214-00000088", "__SIPCALLID=0_4084030132@192.168.88.250") in new stack
    -- Executing [s@trunk:3] Set("PJSIP/214-00000088", "AGENT=PJSIP/214") in new stack
    -- Executing [s@trunk:4] Set("PJSIP/214-00000088", "__NUM=0123456789") in new stack
    -- Executing [s@trunk:5] Set("PJSIP/214-00000088", "CHANNEL(hangup_handler_push)=hdlr3,h,1") in new stack
    -- Executing [s@trunk:6] ExecIf("PJSIP/214-00000088", "?Set(temp=)") in new stack
    -- Executing [s@trunk:7] ExecIf("PJSIP/214-00000088", "?Set(temp=)") in new stack
    -- Executing [s@trunk:8] ExecIf("PJSIP/214-00000088", "0?Set(EXT=)") in new stack
    -- Executing [s@trunk:9] ExecIf("PJSIP/214-00000088", "0?Set(CFEXT=)") in new stack
    -- Executing [s@trunk:10] Gosub("PJSIP/214-00000088", "check-pin,s,1(0123456789)") in new stack
    -- Executing [s@check-pin:1] GotoIf("PJSIP/214-00000088", "0?s-pin,2") in new stack
    -- Executing [s@check-pin:2] Gosub("PJSIP/214-00000088", "check-barring,s,1(0123456789)") in new stack
    -- Executing [s@check-barring:1] ExecIf("PJSIP/214-00000088", "1?Set(EXT=214)") in new stack
    -- Executing [s@check-barring:2] GotoIf("PJSIP/214-00000088", "0?s,20") in new stack
    -- Executing [s@check-barring:3] GotoIf("PJSIP/214-00000088", "0?s-Internal,1") in new stack
    -- Executing [s@check-barring:4] GotoIf("PJSIP/214-00000088", "0?s-Internal,1") in new stack
    -- Executing [s@check-barring:5] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:6] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:7] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:8] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:9] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:10] GotoIf("PJSIP/214-00000088", "0?s-Cell,1") in new stack
    -- Executing [s@check-barring:11] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:12] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:13] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:14] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:15] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:16] GotoIf("PJSIP/214-00000088", "0?s-National,1") in new stack
    -- Executing [s@check-barring:17] GotoIf("PJSIP/214-00000088", "1?s-Cell,1") in new stack
    -- Goto (check-barring,s-Cell,1)
    -- Executing [s-Cell@check-barring:1] NoOp("PJSIP/214-00000088", "The number is a cell number") in new stack
    -- Executing [s-Cell@check-barring:2] GotoIf("PJSIP/214-00000088", "0?s-Cell,6") in new stack
    -- Executing [s-Cell@check-barring:3] GotoIf("PJSIP/214-00000088", "1?s-Cell,6") in new stack
  == Begin MixMonitor Recording PJSIP/214-00000088
    -- Goto (check-barring,s-Cell,6)
    -- Executing [s-Cell@check-barring:6] NoOp("PJSIP/214-00000088", "User is Allowed to call Cellphones") in new stack
    -- Executing [s-Cell@check-barring:7] Return("PJSIP/214-00000088", "") in new stack
    -- Executing [s@check-pin:3] Return("PJSIP/214-00000088", "") in new stack
    -- Executing [s@trunk:11] Gosub("PJSIP/214-00000088", "check-queueout,s,1") in new stack
    -- Executing [s@check-queueout:1] Set("PJSIP/214-00000088", "DIALER=214") in new stack
    -- Executing [s@check-queueout:2] Set("PJSIP/214-00000088", "queue_out=") in new stack
    -- Executing [s@check-queueout:3] GotoIf("PJSIP/214-00000088", "1?s-NO,1:s-OK,1") in new stack
    -- Goto (check-queueout,s-NO,1)
    -- Executing [s-NO@check-queueout:1] Set("PJSIP/214-00000088", "__QUEUE_OUT=outbound") in new stack
    -- Executing [s-NO@check-queueout:2] Return("PJSIP/214-00000088", "") in new stack
    -- Executing [s@trunk:12] Gosub("PJSIP/214-00000088", "set-trunk,s,1") in new stack
    -- Executing [s@set-trunk:1] ExecIf("PJSIP/214-00000088", "0?Set(CALLERID(num)=)") in new stack
    -- Executing [s@set-trunk:2] ExecIf("PJSIP/214-00000088", "1?Set(EXT=214)") in new stack
    -- Executing [s@set-trunk:3] Set("PJSIP/214-00000088", "department=DNS") in new stack
    -- Executing [s@set-trunk:4] ExecIf("PJSIP/214-00000088", "0?Set(ARRAY(forwarder,department)=)") in new stack
    -- Executing [s@set-trunk:5] ExecIf("PJSIP/214-00000088", "0?Set(ARRAY(CALLERID,department)=)") in new stack
    -- Executing [s@set-trunk:6] Set("PJSIP/214-00000088", "CHANNEL(musicclass)=DNS") in new stack
    -- Executing [s@set-trunk:7] GotoIf("PJSIP/214-00000088", "0?s-global,1") in new stack
    -- Executing [s@set-trunk:8] Set("PJSIP/214-00000088", "TRUNK=PJSIP/dst@27123456789") in new stack
    -- Executing [s@set-trunk:9] GotoIf("PJSIP/214-00000088", "0?s-global,1") in new stack
    -- Executing [s@set-trunk:10] Return("PJSIP/214-00000088", "") in new stack
    -- Executing [s@trunk:13] Gosub("PJSIP/214-00000088", "set-outcallerid,s,1") in new stack
    -- Executing [s@set-outcallerid:1] ExecIf("PJSIP/214-00000088", "1?Set(EXT=214)") in new stack
    -- Executing [s@set-outcallerid:2] Set("PJSIP/214-00000088", "callerid=27123456789") in new stack
    -- Executing [s@set-outcallerid:3] ExecIf("PJSIP/214-00000088", "0?SET(callerid=214)") in new stack
    -- Executing [s@set-outcallerid:4] Set("PJSIP/214-00000088", "CALLERID(num)=27123456789") in new stack
    -- Executing [s@set-outcallerid:5] ExecIf("PJSIP/214-00000088", "0?Set(CALLERID(num)=)") in new stack
    -- Executing [s@set-outcallerid:6] Return("PJSIP/214-00000088", "") in new stack
    -- Executing [s@trunk:14] System("PJSIP/214-00000088", "echo "1748327078|1748327078.276|outbound|PJSIP/214|CALLOUTBOUND||0123456789" >> /var/log/asterisk/queue_log") in new stack
    -- Executing [s@trunk:15] Dial("PJSIP/214-00000088", "PJSIP/0123456789@27123456789,300,TrU(connected,PJSIP/214,1748327078,1748327078.276)") in new stack
    -- Called PJSIP/0123456789@27123456789
  == Using SIP RTP Audio TOS bits 184
  == Using SIP RTP Audio TOS bits 184 in TCLASS field.
    -- PJSIP/27123456789-00000089 is ringing
    -- PJSIP/27123456789-00000089 is ringing
    -- PJSIP/27123456789-00000089 answered PJSIP/214-00000088
    -- PJSIP/27123456789-00000089 Internal Gosub(connected,s,1(PJSIP/214,1748327078,1748327078.276)) start
    -- Executing [s@connected:1] Set("PJSIP/27123456789-00000089", "AGENT=PJSIP/214") in new stack
    -- Executing [s@connected:2] Set("PJSIP/27123456789-00000089", "HOLDTIME=7") in new stack
    -- Executing [s@connected:3] System("PJSIP/27123456789-00000089", "echo "1748327085|1748327078.276|outbound|PJSIP/214|CONNECT|7|1748327078.276|7" >> /var/log/asterisk/queue_log") in new stack
    -- Executing [s@connected:4] Return("PJSIP/27123456789-00000089", "") in new stack
  == Spawn extension (external, , 1) exited non-zero on 'PJSIP/27123456789-00000089'
    -- PJSIP/27123456789-00000089 Internal Gosub(connected,s,1(PJSIP/214,1748327078,1748327078.276)) complete GOSUB_RETVAL=
    -- Channel PJSIP/27123456789-00000089 joined 'simple_bridge' basic-bridge <9a1048b0-b13c-4d73-96e6-c15ed4d9ac19>
    -- Channel PJSIP/214-00000088 joined 'simple_bridge' basic-bridge <9a1048b0-b13c-4d73-96e6-c15ed4d9ac19>
    -- Started music on hold, class 'default', on channel 'PJSIP/27123456789-00000089'
  == Using SIP RTP Audio TOS bits 184
  

  
    -- Executing [200@extensions:1] Gosub("PJSIP/214-0000008a", "exten,s,1(200)")
    -- Executing [s@exten:1] Gosub("PJSIP/214-0000008a", "check-record,s,1") in new stack
    -- Executing [s@check-record:1] Set("PJSIP/214-0000008a", "RECORD=YES") in new stack
    -- Executing [s@check-record:2] GotoIf("PJSIP/214-0000008a", "1?3:6") in new stack
    -- Goto (check-record,s,3)
    -- Executing [s@check-record:3] Set("PJSIP/214-0000008a", "EXISTS=0") in new stack
    -- Executing [s@check-record:4] GotoIf("PJSIP/214-0000008a", "0?6") in new stack
    -- Executing [s@check-record:5] MixMonitor("PJSIP/214-0000008a", "1748327092.278.wav,ab,/usr/local/scripts/convert 1748327092.278") in new stack
    -- Executing [s@check-record:6] NoOp("PJSIP/214-0000008a", "RECORD: YES") in new stack
    -- Executing [s@check-record:7] Return("PJSIP/214-0000008a", "") in new stack
    -- Executing [s@exten:2] PlayTones("PJSIP/214-0000008a", "400*33/400,0/200,400*33/400,0/2000") in new stack
    -- Executing [s@exten:3] Set("PJSIP/214-0000008a", "CHANNEL(hangup_handler_push)=hdlrexten,h,1") in new stack
    -- Executing [s@exten:4] NoOp("PJSIP/214-0000008a", "AVAILORIGCHAN = ") in new stack
    -- Executing [s@exten:5] Set("PJSIP/214-0000008a", "EXT=200") in new stack
    -- Executing [s@exten:6] Set("PJSIP/214-0000008a", "ARG1=") in new stack
    -- Executing [s@exten:7] Set("PJSIP/214-0000008a", "OPTIONS=") in new stack
    -- Executing [s@exten:8] Set("PJSIP/214-0000008a", "ARG2=") in new stack
    -- Executing [s@exten:9] Set("PJSIP/214-0000008a", "DEV=200") in new stack
    -- Executing [s@exten:10] Set("PJSIP/214-0000008a", "DND=no") in new stack
    -- Executing [s@exten:11] Set("PJSIP/214-0000008a", "department=DBS") in new stack
    -- Executing [s@exten:12] ChanIsAvail("PJSIP/214-0000008a", "PJSIP/200,s") in new stack
    -- Executing [s@exten:13] NoOp("PJSIP/214-0000008a", "1") in new stack
    -- Executing [s@exten:14] Set("PJSIP/214-0000008a", "TIME=20") in new stack
    -- Executing [s@exten:15] Set("PJSIP/214-0000008a", "CW=0") in new stack
    -- Executing [s@exten:16] Set("PJSIP/214-0000008a", "temp=") in new stack
    -- Executing [s@exten:17] Set("PJSIP/214-0000008a", "RETURN=") in new stack
  == Begin MixMonitor Recording PJSIP/214-0000008a
    -- Executing [s@exten:18] Set("PJSIP/214-0000008a", "CF=0") in new stack
    -- Executing [s@exten:19] GotoIf("PJSIP/214-0000008a", "0?s-CF,1") in new stack
    -- Executing [s@exten:20] Set("PJSIP/214-0000008a", "CFBU=0") in new stack
    -- Executing [s@exten:21] GotoIf("PJSIP/214-0000008a", "0?s-CFBU,1") in new stack
    -- Executing [s@exten:22] GotoIf("PJSIP/214-0000008a", "0?s-VM,1") in new stack
    -- Executing [s@exten:23] GotoIf("PJSIP/214-0000008a", "0?s-DIAL,1") in new stack
    -- Executing [s@exten:24] GotoIf("PJSIP/214-0000008a", "?s-RETURN,1") in new stack
    -- Executing [s@exten:25] GotoIf("PJSIP/214-0000008a", "0?s-DND,1") in new stack
    -- Executing [s@exten:26] GotoIf("PJSIP/214-0000008a", "0?s-BUSY,1") in new stack
    -- Executing [s@exten:27] GotoIf("PJSIP/214-0000008a", "0?s-BUSY,1") in new stack
    -- Executing [s@exten:28] Goto("PJSIP/214-0000008a", "s-DIAL,1") in new stack
    -- Goto (exten,s-DIAL,1)
    -- Executing [s-DIAL@exten:1] GotoIf("PJSIP/214-0000008a", "0?s-BUSY,1") in new stack
    -- Executing [s-DIAL@exten:2] GotoIf("PJSIP/214-0000008a", "0?s-BUSY,1") in new stack
    -- Executing [s-DIAL@exten:3] NoOp("PJSIP/214-0000008a", "PJSIP/200/sip:200@169.255.228.74:1039;x-ast-orig-host=192.168.100.7:5060") in new stack
    -- Executing [s-DIAL@exten:4] Dial("PJSIP/214-0000008a", "PJSIP/200/sip:200@169.255.228.74:1039;x-ast-orig-host=192.168.100.7:5060,300,tr") in new stack
  == Using SIP RTP Audio TOS bits 184
    -- Called PJSIP/200/sip:200@169.255.228.74:1039;x-ast-orig-host=192.168.100.7:5060
    -- PJSIP/200-0000008c is ringing
    -- PJSIP/200-0000008c answered PJSIP/214-0000008a
    -- Channel PJSIP/200-0000008c joined 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>
    -- Channel PJSIP/214-0000008a joined 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>
    -- Stopped music on hold on PJSIP/27123456789-00000089
    -- Channel PJSIP/27123456789-00000089 left 'simple_bridge' basic-bridge <9a1048b0-b13c-4d73-96e6-c15ed4d9ac19>
    -- Channel PJSIP/214-0000008a left 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>
    -- Channel PJSIP/27123456789-00000089 swapped with PJSIP/214-0000008a into 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>
    -- Channel PJSIP/214-00000088 left 'simple_bridge' basic-bridge <9a1048b0-b13c-4d73-96e6-c15ed4d9ac19>
  == Spawn extension (trunk, s, 15) exited non-zero on 'PJSIP/214-00000088'

  == Spawn extension (exten, s-DIAL, 4) exited non-zero on 'PJSIP/214-0000008a'
    -- PJSIP/214-0000008a Internal Gosub(hdlrexten,h,1) start
    -- Executing [h@hdlrexten:1] Gosub("PJSIP/214-0000008a", "rtploss,s,1") in new stack
    -- Executing [s@rtploss:1] System("PJSIP/214-0000008a", "echo "CHANNEL SCORE=1748327092.278,88.087887,88.087887,,g729}" >> /var/log/asterisk/MES.log") in new stack
    -- Executing [s@rtploss:2] Set("PJSIP/214-0000008a", "QUEUE_MEMBER(,penalty,)=2") in new stack
    -- Executing [s@rtploss:3] Return("PJSIP/214-0000008a", "") in new stack
    -- Executing [h@hdlrexten:2] Set("PJSIP/214-0000008a", "QUEUE_MEMBER(,penalty,PJSIP/200)=2") in new stack
  == MixMonitor close filestream (mixed)
  == Executing [/usr/local/scripts/convert 1748327092.278]
  == End MixMonitor Recording PJSIP/214-0000008a

    -- PJSIP/214-00000088 Internal Gosub(hdlr3,h,1) start
    -- Executing [h@hdlr3:1] Gosub("PJSIP/214-00000088", "rtploss,s,1") in new stack
    -- Executing [s@rtploss:1] System("PJSIP/214-00000088", "echo "CHANNEL SCORE=1748327078.276,87.966892,87.966891,,alaw}" >> /var/log/asterisk/MES.log") in new stack
    -- Executing [s@rtploss:2] Set("PJSIP/214-00000088", "QUEUE_MEMBER(,penalty,PJSIP/214)=2") in new stack
    -- Executing [s@rtploss:3] Return("PJSIP/214-00000088", "") in new stack
    -- Executing [h@hdlr3:2] Set("PJSIP/214-00000088", "QUEUE_MEMBER(,penalty,PJSIP/214)=2") in new stack
    -- Executing [h@hdlr3:3] GosubIf("PJSIP/214-00000088", "0?hdlr3-api,h,1") in new stack
    -- Executing [h@hdlr3:4] GotoIf("PJSIP/214-00000088", "outbound?h-QUEUE,1") in new stack
    -- Goto (hdlr3,h-QUEUE,1)
    -- Executing [h-QUEUE@hdlr3:1] GotoIf("PJSIP/214-00000088", "1?h-QUEUE-ANSWER,1:h-QUEUE-ABANDON,1") in new stack
    -- Goto (hdlr3,h-QUEUE-ANSWER,1)
    -- Executing [h-QUEUE-ANSWER@hdlr3:1] Set("PJSIP/214-00000088", "CALLTIME=19") in new stack
    -- Executing [h-QUEUE-ANSWER@hdlr3:2] Set("PJSIP/214-00000088", "HOLDTIME=7") in new stack
    -- Executing [h-QUEUE-ANSWER@hdlr3:3] System("PJSIP/214-00000088", "echo "1748327105|1748327078.276|outbound|PJSIP/214|COMPLETECALLER|7|19" >> /var/log/asterisk/queue_log") in new stack
    -- Executing [h-QUEUE-ANSWER@hdlr3:4] Return("PJSIP/214-00000088", "") in new stack
  == Spawn extension (trunk, s, 15) exited non-zero on 'PJSIP/214-00000088'
    -- PJSIP/214-00000088 Internal Gosub(hdlr3,h,1) complete GOSUB_RETVAL=
  == MixMonitor close filestream (mixed)
  == Executing [/usr/local/scripts/convert 1748327078.276]
  == End MixMonitor Recording PJSIP/214-00000088

    -- Channel PJSIP/200-0000008c left 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>
    -- Channel PJSIP/27123456789-00000089 left 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>

This is the scenario:
Endpoint 214 - Makes outgoing call to external number - Speaks to External Caller (1748327078.276)
Endpoint 214 - Makes second call to Endpoint 200 - Speaks to Endpoint 200 (1748327092.278)
Endpoint 214 - Attended Transfer Endpoint 200 to External caller (Audio Missing)

I would expect the call recording to exist and append to the original recording, which it doesn’t.

-- Executing [s@check-record:5] MixMonitor("PJSIP/214-00000088", "1748327078.276.wav,ab,/usr/local/scripts/convert 1748327078.276") in new stack

CEL Data:

| 168413 | ATTENDEDTRANSFER | 2025-05-27 08:25:04 | Jaco     | 27123456789 | 214     |           | 0123456789 | s          | trunk        | PJSIP/214-00000088         | Dial       | PJSIP/0123456789@27123456789,300,TrU(connected,PJSIP/214,1748327078,1748327078.276) |        3 |             | 1748327078.276 | 1748327078.276 |                            |             | {"bridge1_id":"9a1048b0-b13c-4d73-96e6-c15ed4d9ac19","channel2_name":"PJSIP/214-0000008a","channel2_uniqueid":"1748327092.278","bridge2_id":"07861a1b-fa96-4166-965b-7fea4fa8ac85","transferee_channel_name":"PJSIP/27123456789-00000089","transferee_channel_uniqueid":"1748327078.277","transfer_target_channel_name":"PJSIP/200-0000008c","transfer_target_channel_uniqueid":"1748327092.280"} |

If when looking at the Hung up Handler both the external call (h@hdlr3) and endpoint call handler (h@hdlrexten) was trigger on the Attended transfer. Which seems to have cause Mixmonitor to End recording.

The channels that were in the bridge only left at 08:25:19.

[May 27 08:25:19] VERBOSE[512993][C-00000041] bridge_channel.c: Channel PJSIP/27123456789-00000089 left 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>
[May 27 08:25:19] VERBOSE[512997][C-00000042] bridge_channel.c: Channel PJSIP/200-0000008c left 'simple_bridge' basic-bridge <07861a1b-fa96-4166-965b-7fea4fa8ac85>

How can I get these 15 seconds to record?

faqterson · May 27, 2025, 7:37am

I did a SIP debug and I full understand why this is happening.
Endpoint 214 sends a bye when the attended transfer take place, which makes sense because both channel on endpoint 214 will end.

I truly have no idea on how to resolve this.

jcolp · May 27, 2025, 8:14am

If you invoke MixMonitor on the channel not doing the transfer, then it would continue.

faqterson · May 27, 2025, 8:40am

That is exactly the problem. Mixmonitor is being invoked on the endpoint that make the call and that attended transfer the call. The endpoint device then sends SIP bye on both channels on call transfer which causes MixMonitor end.
This is as a result of the Yealink Transfer function.

I have provided the customer with Asterisk Attended Transfer feature code. The feature code create a Local channel for the second leg which then allow Mixmonitor to continue on the second leg.

Ideally I would like to make use of device transfers but I can’t think of a way to make this work other than rewriting internal endpoint dial plan and making use of Local channel some how.

Topic		Replies	Views
Stop recording? or stop playing? Asterisk Support	0	178	September 9, 2009
MixMonitor doesn't continue recording after transfer Asterisk Support	8	3908	December 27, 2016
Call recordings - Outbound calls, attended transfers not recording Asterisk Support	11	5851	October 26, 2023
Call record after transfer don't work Asterisk Support	9	1296	November 9, 2019
GoSub & Call Forwarding problem Asterisk Support	5	478	August 7, 2009

Attendant transfer call recording stopped

Related topics