<--- SIP read from UDP:192.168.10.52:5060 ---> INVITE sip:XXXXXXXXX@192.168.10.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.52:5060;branch=z9hG4bK7bc9c68a12cd3fd5 From: "YYY YYY" ;tag=9373850803e70dd3 To: Contact: Supported: replaces, timer, path P-Early-Media: Supported Call-ID: c2581ba3e487e71a@192.168.10.52 CSeq: 2738 INVITE User-Agent: Grandstream GXP2000 1.2.2.26 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Content-Type: application/sdp Content-Length: 213 v=0 o=2102 8000 8000 IN IP4 192.168.10.52 s=SIP Call c=IN IP4 192.168.10.52 t=0 0 m=audio 5036 RTP/AVP 8 101 a=sendrecv a=rtpmap:8 PCMA/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 <-------------> --- (14 headers 11 lines) --- [Jan 14 16:12:56] DEBUG[11201]: acl.c:499 ast_ouraddrfor: Found IP address for this socket [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3677 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.10.1:5060 == Using SIP RTP CoS mark 5 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:5000 do_setnat: Setting NAT on RTP to Off [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:7268 sip_alloc: Allocating new SIP dialog for c2581ba3e487e71a@192.168.10.52 - INVITE (With RTP) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3397 parse_sip_options: Begin: parsing SIP "Supported: replaces, timer, path" [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3405 parse_sip_options: Found SIP option: -replaces- [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3411 parse_sip_options: Matched SIP option: replaces [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3405 parse_sip_options: Found SIP option: -timer- [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3411 parse_sip_options: Matched SIP option: timer [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3405 parse_sip_options: Found SIP option: -path- [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3411 parse_sip_options: Matched SIP option: path Sending to 192.168.10.52 : 5060 (no NAT) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:19892 handle_request_invite: Initializing initreq for method INVITE - callid c2581ba3e487e71a@192.168.10.52 Using INVITE request as basis request - c2581ba3e487e71a@192.168.10.52 Found peer '2102' for '2102' from 192.168.10.52:5060 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:5000 do_setnat: Setting NAT on RTP to Off <--- Reliably Transmitting (no NAT) to 192.168.10.52:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.10.52:5060;branch=z9hG4bK7bc9c68a12cd3fd5;received=192.168.10.52 From: "YYY YYY" ;tag=9373850803e70dd3 To: ;tag=as6691fb0e Call-ID: c2581ba3e487e71a@192.168.10.52 CSeq: 2738 INVITE Server: Commsmundi PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="0d5b4272" Content-Length: 0 <------------> [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3556 __sip_xmit: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.10.52:5060 Scheduling destruction of SIP dialog 'c2581ba3e487e71a@192.168.10.52' in 6400 ms (Method: INVITE) <--- SIP read from UDP:192.168.10.52:5060 ---> ACK sip:XXXXXXXXX@192.168.10.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.52:5060;branch=z9hG4bK7bc9c68a12cd3fd5 From: "YYY YYY" ;tag=9373850803e70dd3 To: ;tag=as6691fb0e Contact: Supported: path Call-ID: c2581ba3e487e71a@192.168.10.52 CSeq: 2738 ACK User-Agent: Grandstream GXP2000 1.2.2.26 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Content-Length: 0 <-------------> --- (12 headers 0 lines) --- [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:4092 __sip_ack: Stopping retransmission on 'c2581ba3e487e71a@192.168.10.52' of Response 2738: Match Found <--- SIP read from UDP:192.168.10.52:5060 ---> INVITE sip:XXXXXXXXX@192.168.10.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.10.52:5060;branch=z9hG4bKe2a233778c4d5b73 From: "YYY YYY" ;tag=9373850803e70dd3 To: Contact: Supported: replaces, timer, path P-Early-Media: Supported Authorization: Digest username="2102", realm="asterisk", algorithm=MD5, uri="sip:XXXXXXXXX@192.168.10.1", nonce="0d5b4272", response="3cf1acd450d8feaec168d4048ad036de" Call-ID: c2581ba3e487e71a@192.168.10.52 CSeq: 2739 INVITE User-Agent: Grandstream GXP2000 1.2.2.26 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE Content-Type: application/sdp Content-Length: 213 v=0 o=2102 8000 8001 IN IP4 192.168.10.52 s=SIP Call c=IN IP4 192.168.10.52 t=0 0 m=audio 5036 RTP/AVP 8 101 a=sendrecv a=rtpmap:8 PCMA/8000 a=ptime:20 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 <-------------> --- (15 headers 11 lines) --- Sending to 192.168.10.52 : 5060 (no NAT) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:19892 handle_request_invite: Initializing initreq for method INVITE - callid c2581ba3e487e71a@192.168.10.52 Using INVITE request as basis request - c2581ba3e487e71a@192.168.10.52 Found peer '2102' for '2102' from 192.168.10.52:5060 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:5000 do_setnat: Setting NAT on RTP to Off [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8173 process_sdp: Processing session-level SDP v=0... UNSUPPORTED. [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8173 process_sdp: Processing session-level SDP o=2102 8000 8001 IN IP4 192.168.10.52... UNSUPPORTED. [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8173 process_sdp: Processing session-level SDP s=SIP Call... UNSUPPORTED. [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8173 process_sdp: Processing session-level SDP c=IN IP4 192.168.10.52... OK. [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8173 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED. Found RTP audio format 8 Found RTP audio format 101 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8337 process_sdp: Processing media-level (audio) SDP a=sendrecv... OK. Found audio description format PCMA for ID 8 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8337 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8337 process_sdp: Processing media-level (audio) SDP a=ptime:20... OK. Found audio description format telephone-event for ID 101 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8337 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8337 process_sdp: Processing media-level (audio) SDP a=fmtp:101 0-11... UNSUPPORTED. Capabilities: us - 0x8 (alaw), peer - audio=0x8 (alaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 192.168.10.52:5036 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:8516 process_sdp: We're settling with these formats: 0x8 (alaw) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:19984 handle_request_invite: Checking SIP call limits for device 2102 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:5630 update_call_counter: Updating call counter for incoming call Looking for XXXXXXXXX in outgoing (domain 192.168.10.1) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:6635 sip_new: *** Our native formats are 0x8 (alaw) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:6636 sip_new: *** Joint capabilities are 0x8 (alaw) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:6637 sip_new: *** Our capabilities are 0x8 (alaw) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:6638 sip_new: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:6668 sip_new: This channel will not be able to handle video. [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:12489 build_route: build_route: Contact hop: list_route: hop: [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:20057 handle_request_invite: Incoming INVITE with 'timer' option enabled [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:22497 start_session_timer: Session timer started: 2606 - c2581ba3e487e71a@192.168.10.52 [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:20234 handle_request_invite: SIP/2102-0000001c: New call is still down.... Trying... <--- Transmitting (no NAT) to 192.168.10.52:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.10.52:5060;branch=z9hG4bKe2a233778c4d5b73;received=192.168.10.52 From: "YYY YYY" ;tag=9373850803e70dd3 To: Call-ID: c2581ba3e487e71a@192.168.10.52 CSeq: 2739 INVITE Server: Commsmundi PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Require: timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <------------> [Jan 14 16:12:56] DEBUG[11201]: chan_sip.c:3556 __sip_xmit: Trying to put 'SIP/2.0 100' onto UDP socket destined for 192.168.10.52:5060 [Jan 14 16:12:56] DEBUG[11190]: chan_sip.c:22935 sip_devicestate: Checking device state for peer 2102 [Jan 14 16:12:56] DEBUG[11190]: devicestate.c:460 do_state_change: Changing state for SIP/2102 - state 1 (Not in use) [Jan 14 16:12:56] DEBUG[11190]: devicestate.c:440 devstate_event: device 'SIP/2102' state '1' [Jan 14 16:12:56] DEBUG[11200]: app_queue.c:1084 handle_statechange: Device 'SIP/2102' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 14 16:12:56] DEBUG[10580]: pbx.c:3687 pbx_extension_helper: Launching 'Set' -- Executing [XXXXXXXXX@outgoing:1] Set("SIP/2102-0000001c", "TIMEOUT(absolute)=7200") in new stack Channel will hangup at 2010-01-14 18:12:56.758 CET. [Jan 14 16:12:56] DEBUG[10580]: pbx.c:3687 pbx_extension_helper: Launching 'AGI' -- Executing [XXXXXXXXX@outgoing:2] AGI("SIP/2102-0000001c", "action.php,14") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/action.php [Jan 14 16:12:56] DEBUG[11266]: manager.c:2993 process_message: Manager received command 'command' action.php,14: SOURCE: Channel=SIP/2102-0000001c CID='YYY YYY' <2102> action.php,14: DESTINATION: [ 1 ] Extension=XXXXXXXXX Rule='forced analog2' -- AGI Script Executing Application: (SET) Options: (CDR(accountcode)=XXXXXXXXX) -- AGI Script Executing Application: (MixMonitor) Options: (/home/system/asterisk/monitor_record/general/CMr_general_20100114_161256_2102_XXXXXXXXX.wav|v(0)V(0))) == Begin MixMonitor Recording SIP/2102-0000001c action.php,14: Set Music on Hold: class = -- AGI Script Executing Application: (SET) Options: (CHANNEL(musicclass)=default) -- AGI Script Executing Application: (SET) Options: (CDR(userfield)=14) action.php,14: -- AGI Script Executing Application: (DIAL) Options: (DAHDI/4/XXXXXXXXX|60) [Jan 14 16:12:56] DEBUG[10580]: chan_dahdi.c:10942 dahdi_request: Using channel 4 [Jan 14 16:12:56] DEBUG[10580]: dsp.c:472 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Jan 14 16:12:56] DEBUG[10580]: dsp.c:472 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Jan 14 16:12:56] DEBUG[10580]: devicestate.c:440 devstate_event: device 'DAHDI/4-1' state '2' [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable DIALEDTIME. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable ANSWEREDTIME. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable DIALEDPEERNAME. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable DIALEDPEERNUMBER. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable DIALSTATUS. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable DYNAMIC_FEATURES. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4293 ast_channel_inherit_variables: Copying soft-transferable variable CALL_LIMIT_COUNT. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4293 ast_channel_inherit_variables: Copying soft-transferable variable RULE_RECORDING. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable MIXMONITOR_FILENAME. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4293 ast_channel_inherit_variables: Copying soft-transferable variable DEST_EXT_MONITOR. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4293 ast_channel_inherit_variables: Copying soft-transferable variable SRC_EXT_MONITOR. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Jan 14 16:12:56] DEBUG[10580]: channel.c:4304 ast_channel_inherit_variables: Not copying variable SIPURI. [Jan 14 16:12:56] DEBUG[10580]: chan_dahdi.c:3242 dahdi_call: Ignore possible polarity reversal on line seizure [Jan 14 16:12:56] DEBUG[10580]: chan_dahdi.c:3287 dahdi_call: Dialing 'XXXXXXXXX' [Jan 14 16:12:56] DEBUG[10580]: chan_dahdi.c:3379 dahdi_call: Deferring dialing... -- Called 4/XXXXXXXXX [Jan 14 16:12:56] DEBUG[11190]: devicestate.c:460 do_state_change: Changing state for DAHDI/4 - state 2 (In use) [Jan 14 16:12:56] DEBUG[11190]: devicestate.c:440 devstate_event: device 'DAHDI/4' state '2' [Jan 14 16:12:56] DEBUG[11200]: app_queue.c:1084 handle_statechange: Device 'DAHDI/4-1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 14 16:12:56] DEBUG[11200]: app_queue.c:1084 handle_statechange: Device 'DAHDI/4' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 14 16:12:57] DEBUG[10580]: chan_dahdi.c:6695 __dahdi_exception: Exception on 13, channel 4 [Jan 14 16:12:57] DEBUG[10580]: chan_dahdi.c:5730 dahdi_handle_event: Got event Hook Transition Complete(12) on channel 4 (index 0) [Jan 14 16:12:57] DEBUG[10580]: chan_dahdi.c:6496 dahdi_handle_event: Sent deferred digit string: TXXXXXXXXXw [Jan 14 16:12:59] DEBUG[11266]: manager.c:2993 process_message: Manager received command 'command' [Jan 14 16:13:00] DEBUG[10580]: chan_dahdi.c:6695 __dahdi_exception: Exception on 13, channel 4 [Jan 14 16:13:00] DEBUG[10580]: chan_dahdi.c:5730 dahdi_handle_event: Got event Dial Complete(9) on channel 4 (index 0) [Jan 14 16:13:00] DEBUG[10580]: chan_dahdi.c:2685 dahdi_enable_ec: Enabled echo cancellation on channel 4 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:00] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:01] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [Jan 14 16:13:02] DEBUG[10593]: audiohook.c:248 audiohook_read_frame_both: Failed to get 160 samples from read factory 0xa71d70 [...]