[Apr 27 16:06:02] DEBUG[5577] pbx.c: Launching 'Set'
[Apr 27 16:06:02] VERBOSE[5577] pbx.c:     -- Executing [s@conf-in:1] Set("SIP/SERV1-00000032", "CONFID=10000") in new stack
[Apr 27 16:06:02] DEBUG[5577] pbx.c: Function result is 'dcMpsi'
[Apr 27 16:06:02] DEBUG[5577] pbx.c: Launching 'Set'
[Apr 27 16:06:02] VERBOSE[5577] pbx.c:     -- Executing [s@conf-in:2] Set("SIP/SERV1-00000032", "CONF_FLAGS=dcMpsi") in new stack
[Apr 27 16:06:02] DEBUG[5577] pbx.c: Result of 'CONFID' is '10000'
[Apr 27 16:06:02] DEBUG[5577] pbx.c: Result of 'CONF_FLAGS' is 'dcMpsi'
[Apr 27 16:06:02] DEBUG[5577] pbx.c: Launching 'MeetMe'
[Apr 27 16:06:02] VERBOSE[5577] pbx.c:     -- Executing [s@conf-in:3] MeetMe("SIP/SERV1-00000032", "10000,dcMpsi") in new stack
[Apr 27 16:06:02] DEBUG[5577] chan_sip.c: SIP answering channel: SIP/SERV1-00000032
[Apr 27 16:06:02] DEBUG[4402] devicestate.c: No provider found, checking channel drivers for SIP - SERV1
[Apr 27 16:06:02] DEBUG[5577] res_rtp_asterisk.c: Setting the marker bit due to a source update
[Apr 27 16:06:02] DEBUG[4402] chan_sip.c: Checking device state for peer SERV1
[Apr 27 16:06:02] DEBUG[4402] devicestate.c: Changing state for SIP/SERV1 - state 1 (Not in use)
[Apr 27 16:06:02] DEBUG[4402] devicestate.c: device 'SIP/SERV1' state '1'
[Apr 27 16:06:02] DEBUG[5577] chan_sip.c: Setting framing from config on incoming call
[Apr 27 16:06:02] DEBUG[5577] chan_sip.c: ** Our capability: 0xc (ulaw|alaw) Video flag: True Text flag: True
[Apr 27 16:06:02] DEBUG[4435] app_queue.c: Device 'SIP/SERV1' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Apr 27 16:06:02] DEBUG[5577] chan_sip.c: ** Our prefcodec: 0x0 (nothing)
[Apr 27 16:06:02] DEBUG[5577] chan_sip.c: -- Done with adding codecs to SDP
[Apr 27 16:06:02] DEBUG[5577] chan_sip.c: Done building SDP. Settling with this capability: 0xc (ulaw|alaw)
[Apr 27 16:06:02] DEBUG[5577] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for IP.50:5060
[Apr 27 16:06:02] DEBUG[4425] chan_sip.c: = Looking for  Call ID: 2a8bdfd46b7e4dbd72705b4b64cac278@IP.50:5060 (Checking From) --From tag as03d43487 --To-tag as5b443ebf
[Apr 27 16:06:02] DEBUG[4425] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[Apr 27 16:06:02] DEBUG[4425] chan_sip.c: Stopping retransmission on '2a8bdfd46b7e4dbd72705b4b64cac278@IP.50:5060' of Response 102: Match Found
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: Sending dtmf: 52 (4), at IP.50:11752
[Apr 27 16:06:03] DEBUG[5577] app_meetme.c: The requested confno is '10000'?
[Apr 27 16:06:03] DEBUG[5577] app_meetme.c: Building dynamic conference '10000'
[Apr 27 16:06:03] DEBUG[5577] chan_dahdi.c: Using channel -2
[Apr 27 16:06:03] DEBUG[4402] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel DAHDI/pseudo-1621456318 to read format slin
[Apr 27 16:06:03] DEBUG[4402] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel DAHDI/pseudo-1621456318 to write format slin
[Apr 27 16:06:03] DEBUG[4402] devicestate.c: device 'DAHDI/pseudo' state '2'
[Apr 27 16:06:03] VERBOSE[5577] app_meetme.c:     -- Created MeetMe conference 1023 for conference '10000'
[Apr 27 16:06:03] DEBUG[4435] app_queue.c: Device 'DAHDI/pseudo' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Apr 27 16:06:03] VERBOSE[5577] app.c:     -- Recording
[Apr 27 16:06:03] DEBUG[5577] app.c: play_and_record: vm-rec-name, /var/spool/asterisk/meetme/meetme-username-10000-1, 'sln'
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel SIP/SERV1-00000032 to write format gsm
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160
[Apr 27 16:06:03] DEBUG[5577] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Apr 27 16:06:03] VERBOSE[5577] file.c:     -- <SIP/SERV1-00000032> Playing 'vm-rec-name.gsm' (language 'en')
[Apr 27 16:06:03] DEBUG[5577] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Apr 27 16:06:03] DEBUG[5577] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel SIP/SERV1-00000032 to write format ulaw
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel SIP/SERV1-00000032 to write format gsm
[Apr 27 16:06:03] DEBUG[5577] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Apr 27 16:06:03] VERBOSE[5577] file.c:     -- <SIP/SERV1-00000032> Playing 'beep.gsm' (language 'en')
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: - RTP 2833 Event: 00000004 (len = 4)
[Apr 27 16:06:03] DEBUG[5577] chan_sip.c: Oooh, format changed to alaw
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel SIP/SERV1-00000032 to read format ulaw
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel SIP/SERV1-00000032 to write format gsm
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: Ooh, format changed from ulaw to alaw
[Apr 27 16:06:03] DEBUG[5577] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Apr 27 16:06:03] DEBUG[4425] chan_sip.c: Auto destroying SIP dialog '5ef5f28c22c0f0ef11340908061f1bd2@IP.52'
[Apr 27 16:06:03] DEBUG[4425] chan_sip.c: Destroying SIP dialog 5ef5f28c22c0f0ef11340908061f1bd2@IP.52
[Apr 27 16:06:03] DEBUG[5577] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Apr 27 16:06:03] DEBUG[5577] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Apr 27 16:06:03] DEBUG[5577] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Apr 27 16:06:03] DEBUG[5577] channel.c: Set channel SIP/SERV1-00000032 to write format ulaw
[Apr 27 16:06:03] DEBUG[5577] app.c: Recording Formats: sfmts=sln
[Apr 27 16:06:03] VERBOSE[5577] app.c:     -- x=0, open writing:  /var/spool/asterisk/meetme/meetme-username-10000-1 format: sln, 0xb78d13c0