freeswitch@webrtc> originate loopback/101 &conference(301-webrtc.door.local)
+OK decf228a-7984-11e6-8daf-2f1f9414ccab

2016-09-13 07:37:06.205837 [DEBUG] switch_ivr_originate.c:2136 Parsing global variables
2016-09-13 07:37:06.205837 [NOTICE] switch_channel.c:1104 New Channel loopback/101-a [decf228a-7984-11e6-8daf-2f1f9414ccab]
2016-09-13 07:37:06.205837 [DEBUG] mod_loopback.c:158 loopback/101-a setup codec L16/8000/20
2016-09-13 07:37:06.205837 [NOTICE] switch_channel.c:1102 Rename Channel loopback/101-a->loopback/101-a [decf228a-7984-11e6-8daf-2f1f9414ccab]
2016-09-13 07:37:06.205837 [DEBUG] mod_loopback.c:1174 (loopback/101-a) State Change CS_NEW -> CS_INIT
2016-09-13 07:37:06.205837 [DEBUG] mod_loopback.c:601 loopback/101-a CHANNEL KILL
2016-09-13 07:37:06.205837 [DEBUG] switch_core_state_machine.c:543 (loopback/101-a) Running State Change CS_INIT
2016-09-13 07:37:06.205837 [DEBUG] switch_core_state_machine.c:586 (loopback/101-a) State INIT
2016-09-13 07:37:06.205837 [NOTICE] switch_channel.c:1104 New Channel loopback/101-b [decf6038-7984-11e6-8db1-2f1f9414ccab]
2016-09-13 07:37:06.205837 [DEBUG] mod_loopback.c:158 loopback/101-b setup codec L16/8000/20
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:276 (loopback/101-b) State Change CS_NEW -> CS_INIT
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:601 loopback/101-b CHANNEL KILL
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:362 (loopback/101-a) State Change CS_INIT -> CS_ROUTING
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:601 loopback/101-a CHANNEL KILL
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:543 (loopback/101-b) Running State Change CS_INIT
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:586 (loopback/101-a) State INIT going to sleep
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:586 (loopback/101-b) State INIT
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:362 (loopback/101-b) State Change CS_INIT -> CS_ROUTING
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:601 loopback/101-b CHANNEL KILL
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:586 (loopback/101-b) State INIT going to sleep
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:543 (loopback/101-a) Running State Change CS_ROUTING
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:543 (loopback/101-b) Running State Change CS_ROUTING
2016-09-13 07:37:06.215850 [DEBUG] switch_channel.c:2249 (loopback/101-b) Callstate Change DOWN -> RINGING
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:602 (loopback/101-a) State ROUTING
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:394 loopback/101-a CHANNEL ROUTING
2016-09-13 07:37:06.215850 [DEBUG] switch_ivr_originate.c:67 (loopback/101-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:601 loopback/101-a CHANNEL KILL
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:602 (loopback/101-a) State ROUTING going to sleep
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:543 (loopback/101-a) Running State Change CS_CONSUME_MEDIA
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:621 (loopback/101-a) State CONSUME_MEDIA
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:661 CHANNEL CONSUME_MEDIA
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:621 (loopback/101-a) State CONSUME_MEDIA going to sleep
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:602 (loopback/101-b) State ROUTING
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:394 loopback/101-b CHANNEL ROUTING
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:236 loopback/101-b Standard ROUTING
2016-09-13 07:37:06.215850 [INFO] mod_dialplan_xml.c:637 Processing  <0000000000>->101 in context default
Dialplan: loopback/101-b parsing [default->sample] continue=true
Dialplan: loopback/101-b Absolute Condition [sample]
Dialplan: loopback/101-b Action log(YEAH - SAMPLE! - INFO Dialed number ${destination_number}) INLINE
EXECUTE loopback/101-b log(YEAH - SAMPLE! - INFO Dialed number 101)
2016-09-13 07:37:06.215850 [DEBUG] mod_dptools.c:1713 - SAMPLE! - INFO Dialed number 101
Dialplan: loopback/101-b parsing [default->door] continue=true
Dialplan: loopback/101-b Regex (FAIL) [door] destination_number(101) =~ /^100$/ break=on-false
Dialplan: loopback/101-b parsing [default->user] continue=true
Dialplan: loopback/101-b Regex (FAIL) [user] destination_number(101) =~ /^200$/ break=on-false
Dialplan: loopback/101-b parsing [default->user] continue=true
Dialplan: loopback/101-b Regex (FAIL) [user] destination_number(101) =~ /^201$/ break=on-false
Dialplan: loopback/101-b parsing [default->video1] continue=true
Dialplan: loopback/101-b Regex (PASS) [video1] destination_number(101) =~ /^101$/ break=on-false
Dialplan: loopback/101-b Action answer() 
Dialplan: loopback/101-b Action playback(vlc://rtsp://user:pass@192.168.23.101:554/axis-media/media.amp?videocodec=h264&resolution=640x480) 
Dialplan: loopback/101-b parsing [default->video2] continue=true
Dialplan: loopback/101-b Regex (FAIL) [video2] destination_number(101) =~ /^102$/ break=on-false
Dialplan: loopback/101-b parsing [default->conference] continue=true
Dialplan: loopback/101-b Regex (FAIL) [conference] destination_number(101) =~ /^301$/ break=on-false
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:286 (loopback/101-b) State Change CS_ROUTING -> CS_EXECUTE
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:601 loopback/101-b CHANNEL KILL
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:602 (loopback/101-b) State ROUTING going to sleep
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:543 (loopback/101-b) Running State Change CS_EXECUTE
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:609 (loopback/101-b) State EXECUTE
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:436 loopback/101-b CHANNEL EXECUTE
2016-09-13 07:37:06.215850 [DEBUG] switch_core_state_machine.c:328 loopback/101-b Standard EXECUTE
EXECUTE loopback/101-b answer()
2016-09-13 07:37:06.215850 [NOTICE] mod_loopback.c:956 Channel [loopback/101-a] has been answered
2016-09-13 07:37:06.215850 [DEBUG] switch_channel.c:3770 (loopback/101-a) Callstate Change DOWN -> ACTIVE
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:601 loopback/101-b CHANNEL KILL
2016-09-13 07:37:06.215850 [NOTICE] mod_dptools.c:1309 Channel [loopback/101-b] has been answered
2016-09-13 07:37:06.215850 [DEBUG] switch_channel.c:3770 (loopback/101-b) Callstate Change RINGING -> ACTIVE
2016-09-13 07:37:06.215850 [DEBUG] mod_loopback.c:601 loopback/101-b CHANNEL KILL
EXECUTE loopback/101-b playback(vlc://rtsp://user:pass@192.168.23.101:554/axis-media/media.amp?videocodec=h264&resolution=640x480)
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_originate.c:3616 Originate Resulted in Success: [loopback/101-a]
2016-09-13 07:37:06.235852 [DEBUG] mod_loopback.c:601 loopback/101-a CHANNEL KILL
2016-09-13 07:37:06.235852 [INFO] switch_channel.c:3127 loopback/101-a Flipping CID from "" <0000000000> to "Outbound Call" <101>
2016-09-13 07:37:06.235852 [DEBUG] mod_commands.c:4785 (loopback/101-a) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
2016-09-13 07:37:06.235852 [DEBUG] mod_loopback.c:601 loopback/101-a CHANNEL KILL
2016-09-13 07:37:06.235852 [DEBUG] switch_core_state_machine.c:543 (loopback/101-a) Running State Change CS_EXECUTE
2016-09-13 07:37:06.235852 [DEBUG] switch_core_state_machine.c:609 (loopback/101-a) State EXECUTE
2016-09-13 07:37:06.235852 [DEBUG] mod_loopback.c:436 loopback/101-a CHANNEL EXECUTE
2016-09-13 07:37:06.235852 [DEBUG] switch_core_state_machine.c:328 loopback/101-a Standard EXECUTE
EXECUTE loopback/101-a conference(301-webrtc.door.local)
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:1654 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:1701 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2016-09-13 07:37:06.235852 [DEBUG] switch_core_codec.c:221 loopback/101-a Push codec L16:100
2016-09-13 07:37:06.235852 [ERR] switch_core_file.c:260 Invalid file format [tone_stream] for [%(200,0,500,600,700)]!
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '0' to 'vid-floor-force'
2016-09-13 07:37:06.235852 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x748b4a28 data: 0x74d714c8
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '*' to 'deaf mute'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x748b4a28 data: 0x74d714e8
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '9' to 'energy up'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x748b4a28 data: 0x74d71508
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '8' to 'energy equ'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x748b4a28 data: 0x74d71528
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '7' to 'energy dn'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x748b4a28 data: 0x74d71548
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '3' to 'vol talk up'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x748b4a28 data: 0x74d71568
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '2' to 'vol talk zero'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x748b4a28 data: 0x74d71588
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '1' to 'vol talk dn'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x748b4a28 data: 0x74d715a8
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '6' to 'vol listen up'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x748b4a28 data: 0x74d715c8
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '5' to 'vol listen zero'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x748b4a28 data: 0x74d715e8
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '4' to 'vol listen dn'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x748b4a28 data: 0x74d71608
2016-09-13 07:37:06.235852 [DEBUG] conference_member.c:128 loopback/101-a binding '#' to 'hangup'
2016-09-13 07:37:06.235852 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x748b4a28 data: 0x74d71628
2016-09-13 07:37:06.235852 [DEBUG] mod_loopback.c:601 loopback/101-a CHANNEL KILL
2016-09-13 07:37:06.235852 [DEBUG] conference_loop.c:1102 Setup timer soft success interval: 20  samples: 160
2016-09-13 07:37:06.235852 [DEBUG] mod_loopback.c:601 loopback/101-a CHANNEL KILL
2016-09-13 07:37:06.815858 [DEBUG] mod_vlc.c:832 VLC attempt to open rtsp://user:pass@192.168.23.101:554/axis-media/media.amp?videocodec=h264&resolution=640x480 read  
2016-09-13 07:37:06.815858 [DEBUG] mod_vlc.c:857 VLC open rtsp://user:pass@192.168.23.101:554/axis-media/media.amp?videocodec=h264&resolution=640x480 for reading
2016-09-13 07:37:06.815858 [NOTICE] mod_vlc.c:875 VLC Path is unknown type rtsp://user:pass@192.168.23.101:554/axis-media/media.amp?videocodec=h264&resolution=640x480
VLC: PulseAudio server connection failure: Connection refused
2016-09-13 07:37:06.925849 [DEBUG] mod_loopback.c:601 loopback/101-b CHANNEL KILL
2016-09-13 07:37:06.925849 [DEBUG] mod_vlc.c:242 Got a libvlc_MediaStateChanged callback. New state: 1
2016-09-13 07:37:06.925849 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
2016-09-13 07:37:07.625857 [DEBUG] mod_vlc.c:242 Got a libvlc_MediaStateChanged callback. New state: 3
VLC: window not available
VLC: window not available
VLC: window not available
VLC: window not available
VLC: Cannot create primary surface
VLC: standard input is not a TTY
freeswitch@webrtc>