Oct 16 13:40:41.307 [INFO ] PHN: new Tag: >kks0k3qxd4pjkj0b< Filename: log.htm Oct 16 13:40:44.320 [NOTICE] PHN: TPL: Socket 36 idle/connect timeout Oct 16 13:40:44.321 [DEBUG2] PHN: WEB: HTTP 36/80 disconnected Oct 16 13:40:44.321 [DEBUG2] PHN: WEB: HTTP 36/80 deleted Oct 16 13:40:44.614 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1 Oct 16 13:40:44.614 [DEBUG2] PHN: gui_keys: on key event (F2, 1), from_hardware 1, is_longpressed 0, State Idle (26), time 102652162 Oct 16 13:40:44.617 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:44.708 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2 Oct 16 13:40:44.708 [DEBUG2] PHN: gui_keys: on key event (F2, 0), from_hardware 1, is_longpressed 0, State Idle (26), time 102652256 Oct 16 13:40:44.735 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=0 Oct 16 13:40:44.735 [DEBUG0] PHN: MbParser:: enter Oct 16 13:40:44.736 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/ Oct 16 13:40:44.757 [INFO ] PHN: Xpath applies: /IPPhoneBatch/ Oct 16 13:40:44.757 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:40:44.757 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/ Oct 16 13:40:44.757 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:40:44.757 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch Oct 16 13:40:44.757 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch Oct 16 13:40:44.757 [DEBUG0] PHN: MbParserMainTag:: searching in subs Oct 16 13:40:44.758 [DEBUG0] PHN: MbParserMainTag:: faoun tree = IPPhoneMenu Oct 16 13:40:44.759 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu Oct 16 13:40:44.762 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:44.765 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:44.769 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:44.812 [INFO ] PHN: Xpath applies: /* Oct 16 13:40:44.812 [DEBUG2] PHN: Xpath matched element node IPPhoneMenu Oct 16 13:40:44.812 [DEBUG0] PHN: Xpath succesfully fetched: /* Oct 16 13:40:44.812 [DEBUG0] PHN: Xpath succesfully fetched: Oct 16 13:40:44.939 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:44.941 [NOTICE] TOOLS: Call List Pool RequestToUpdateCache Oct 16 13:40:44.941 [NOTICE] PHN: Call active CallListManager Request function Oct 16 13:40:44.943 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=1 Oct 16 13:40:45.108 [DEBUG2] PHN: LabelDisplay::OnPanelRepainted: idx=0, active_page_=0, display_device_=0x14126d8 Oct 16 13:40:45.140 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=1 Oct 16 13:40:45.226 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=1 Oct 16 13:40:45.226 [DEBUG0] PHN: MbParser:: enter Oct 16 13:40:45.226 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/ Oct 16 13:40:45.249 [INFO ] PHN: Xpath applies: /IPPhoneBatch/ Oct 16 13:40:45.249 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:40:45.249 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/ Oct 16 13:40:45.249 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:40:45.249 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch Oct 16 13:40:45.249 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch Oct 16 13:40:45.249 [DEBUG0] PHN: MbParserMainTag:: searching in subs Oct 16 13:40:45.250 [DEBUG0] PHN: MbParserMainTag:: faoun tree = IPPhoneMenu Oct 16 13:40:45.251 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu Oct 16 13:40:45.254 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:45.258 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:45.261 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:45.305 [INFO ] PHN: Xpath applies: /* Oct 16 13:40:45.305 [DEBUG2] PHN: Xpath matched element node IPPhoneMenu Oct 16 13:40:45.305 [DEBUG0] PHN: Xpath succesfully fetched: /* Oct 16 13:40:45.305 [DEBUG0] PHN: Xpath succesfully fetched: Oct 16 13:40:45.510 [DEBUG0] PHN: LoopMon: LCS/1770135 took 284 (63) msecs, started Oct 16 15:40:45, 1 tasks Oct 16 13:40:45.720 [DEBUG1] SIP: remove receive packet: 1435, all 2 Oct 16 13:40:45.730 [DEBUG1] SIP: remove receive packet: 1436, all 1 Oct 16 13:40:46.051 [DEBUG2] PHN: LLDP: testNetworkPolicyIsSet, timeout=90, now=102653, has_policy=0, timestamp=0, elapsed=102653 Oct 16 13:40:46.663 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1 Oct 16 13:40:46.664 [DEBUG2] PHN: gui_keys: on key event (ENTER, 1), from_hardware 1, is_longpressed 0, State Idle (26), time 102654212 Oct 16 13:40:46.664 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:46.668 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:46.669 [NOTICE] PHN: Minibrowser started a call but was in state others_except_mb. Please check if state is correct. Oct 16 13:40:46.670 [DEBUG1] PHN: Goto Best State from 'pIdle' (26), force 1 called-by MbStop-269 Oct 16 13:40:46.671 [DEBUG1] PHN: Goto State 'Idle' (26) from' Idle' (26), MB 0, blocked 0 caller:goto_best_state-607 Oct 16 13:40:46.672 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:46.673 [INFO ] GUI: find_function_key_for_uri: Looking to match 33@192.168.1.200 Oct 16 13:40:46.673 [INFO ] GUI: GUI: Allocating channel 4, line 1 Oct 16 13:40:46.677 [DEBUG2] GUI: Line 4: state change 'closed' (5) -> 'offhook' (6) Oct 16 13:40:46.680 [NOTICE] CSTA: csta_cid = 4 phone_cid4 Oct 16 13:40:46.680 [DEBUG1] SIP: RTP: set_port - ports: 49152 65534, con 4 Oct 16 13:40:46.681 [DEBUG0] MEDIA: MediaIpc::rtpSetup: rtp: RP4 rtcp: RC4 start: 49152 end: 65534 Oct 16 13:40:46.682 [DEBUG2] SIP: RTP: received_port still not set Oct 16 13:40:46.683 [DEBUG2] SIP: RTP: received_port still not set Oct 16 13:40:46.683 [INFO ] SIP: Dialog 4/4/1 going to trying Oct 16 13:40:46.683 [INFO ] GUI: GUI: Line-Seize subscription was not sent Oct 16 13:40:46.683 [DEBUG0] MEDIA: MediaIpc::setSpeakerDefault: 6 0 Oct 16 13:40:46.684 [DEBUG2] GUI: Line 4: state change 'offhook' (6) -> 'calling' (7) Oct 16 13:40:46.692 [DEBUG1] PHN: Goto State 'Calling' (6) from' Idle' (26), MB 0, blocked 0 caller:MakeCall-767 Oct 16 13:40:46.723 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:46.724 [DEBUG0] MEDIA: Socket 0x2890c8/noname: state connected(5) Oct 16 13:40:46.724 [DEBUG0] MEDIA: Socket 0x2893b8/noname: state connected(5) Oct 16 13:40:46.725 [DEBUG2] SIP: rtp allocated for connection 4, id 4 Oct 16 13:40:46.725 [INFO ] SIP: Send Invite(c = 0x14a4700, moh 0, reuse_sdp 0) caller:rtp_allocated-2318 Oct 16 13:40:46.725 [NOTICE] SIP: getInternalSdpFmts: Skipped Codec g723 is not supported for ptime of 20 Oct 16 13:40:46.732 [DEBUG1] SIP: SDP: Dialog 4, sdp state idle -> offering Oct 16 13:40:46.734 [DEBUG1] SIP: No special routing, routing to sip:@192.168.1.200;user=phone Oct 16 13:40:46.735 [DEBUG1] SIP: RoutePendingPacket 1001494: entry=url ? sip:@192.168.1.200;user=phone all 6 Oct 16 13:40:46.735 [DEBUG1] SIP: RoutePendingPacket 1001494: entry=Udp:192.168.1.200:5060 all 6 Oct 16 13:40:46.735 [DEBUG2] SIP: Increment Transport Udp:192.168.1.200:5060: 0 Oct 16 13:40:46.735 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001494 Oct 16 13:40:46.735 [DEBUG0] SIP: send INVITE (1: def5f068d0a6-80kgd0mesw1r) -> Udp:192.168.1.200:5060 Oct 16 13:40:46.738 [INFO ] SIP: Dialog 4/4/0 going to trying Oct 16 13:40:46.738 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2 Oct 16 13:40:46.739 [DEBUG2] PHN: gui_keys: on key event (ENTER, 0), from_hardware 1, is_longpressed 0, State Calling (6), time 102654258 Oct 16 13:40:46.748 [DEBUG0] PHN: Request lookup for number sip:@192.168.1.200;user=phone Oct 16 13:40:46.749 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:46.800 [DEBUG1] SIP: recv 401 INVITE (1: def5f068d0a6-80kgd0mesw1r) <- Udp:192.168.1.200:5060 Oct 16 13:40:46.800 [DEBUG2] SIP: clear message repetition 1001494 def5f068d0a6-80kgd0mesw1r Oct 16 13:40:46.801 [DEBUG1] SIP: Routing to explicit plan Udp:192.168.1.200:5060 Oct 16 13:40:46.802 [DEBUG1] SIP: RoutePendingPacket 1001495: entry=Udp:192.168.1.200:5060 all 7 Oct 16 13:40:46.802 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001495 Oct 16 13:40:46.802 [DEBUG0] SIP: send ACK (1: def5f068d0a6-80kgd0mesw1r) -> Udp:192.168.1.200:5060 Oct 16 13:40:46.804 [DEBUG2] SIP: sip::ProcessChallenge Identity=0, sip:@192.168.1.200;user=phone Oct 16 13:40:46.804 [WARN ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce Oct 16 13:40:46.804 [NOTICE] SIP: process auth: Match challenge for user=33, realm=asterisk Oct 16 13:40:46.806 [DEBUG1] SIP: Routing to explicit plan Udp:192.168.1.200:5060 Oct 16 13:40:46.806 [DEBUG1] SIP: RoutePendingPacket 1001496: entry=Udp:192.168.1.200:5060 all 8 Oct 16 13:40:46.806 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001496 Oct 16 13:40:46.807 [DEBUG0] SIP: send INVITE (2: def5f068d0a6-80kgd0mesw1r) -> Udp:192.168.1.200:5060 Oct 16 13:40:46.813 [DEBUG1] SIP: recv 488 INVITE (2: def5f068d0a6-80kgd0mesw1r) <- Udp:192.168.1.200:5060 Oct 16 13:40:46.813 [DEBUG2] SIP: clear message repetition 1001496 def5f068d0a6-80kgd0mesw1r Oct 16 13:40:46.814 [DEBUG1] SIP: Routing to explicit plan Udp:192.168.1.200:5060 Oct 16 13:40:46.814 [DEBUG1] SIP: RoutePendingPacket 1001497: entry=Udp:192.168.1.200:5060 all 9 Oct 16 13:40:46.815 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001497 Oct 16 13:40:46.815 [DEBUG0] SIP: send ACK (2: def5f068d0a6-80kgd0mesw1r) -> Udp:192.168.1.200:5060 Oct 16 13:40:46.818 [INFO ] SIP: Dialog 4/4/1 going to terminated Oct 16 13:40:46.818 [DEBUG1] SIP: mark for deletion 4/def5f068d0a6-80kgd0mesw1r, cbi 0, 0 Oct 16 13:40:46.818 [DEBUG2] SIP: Connection 4, media hold: sendrecv -> inactive Oct 16 13:40:46.818 [INFO ] SIP: packet 1001494 stopped retransmission Oct 16 13:40:46.818 [INFO ] SIP: packet 1001496 stopped retransmission Oct 16 13:40:46.818 [DEBUG2] SIP: SIP: delete connection 4/4 in 120 secs Oct 16 13:40:46.818 [DEBUG2] GUI: if_state(4, SIP/2.0 488 Not acceptable here) Oct 16 13:40:46.818 [DEBUG1] PHN: Goto Best State from 'pCalling' (6), force 0 called-by if_state-613 Oct 16 13:40:46.818 [DEBUG1] PHN: Goto State 'Calling' (6) from' Calling' (6), MB 0, blocked 0 caller:goto_best_state-471 Oct 16 13:40:46.818 [DEBUG2] PHN: SipRedirectTargets::GetNextTarget Oct 16 13:40:46.818 [DEBUG2] PHN: SipRedirectTargets::PopExpired Oct 16 13:40:46.819 [INFO ] SIP: gui_unreach(4, Not Acceptable Here, ) Oct 16 13:40:46.819 [DEBUG0] GUI: if_unreach(4, Not Acceptable Here, ): state (calling) Oct 16 13:40:46.819 [DEBUG0] GUI: Line 4: EndCall (8) Oct 16 13:40:46.819 [DEBUG1] SIP: set_rtp_state: hold state: inactive holding: 0 moh: 0 Oct 16 13:40:46.819 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP4 changed=0, update_req=1, adr= Oct 16 13:40:46.819 [DEBUG0] MEDIA: MediaIpc::rtpSymmetric: RP4 0 Oct 16 13:40:46.820 [DEBUG1] PHN: Goto Best State from 'pCalling' (6), force 0 called-by if_continued-1827 Oct 16 13:40:46.820 [DEBUG1] PHN: Goto State 'Calling' (6) from' Calling' (6), MB 0, blocked 0 caller:goto_best_state-471 Oct 16 13:40:46.820 [DEBUG2] GUI: Line 4: state change 'calling' (7) -> 'closed' (5) Oct 16 13:40:46.822 [INFO ] SIP: onhook(4) Oct 16 13:40:46.822 [DEBUG1] PHN: Goto Best State from 'pCalling' (6), force 0 called-by if_unreach-1617 Oct 16 13:40:46.822 [DEBUG1] PHN: Goto State 'Terminated' (12) from' Calling' (6), MB 0, blocked 0 caller:goto_best_state-529 Oct 16 13:40:46.836 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:46.836 [DEBUG0] GUI: SetSynthMode: synth 0->9, audio 0, force 0, caller:enter_on_terminated-322 Oct 16 13:40:46.836 [INFO ] GUI: SetAudioPlaystate: 2 Oct 16 13:40:46.837 [DEBUG0] MEDIA: MediaIpc::SetupAudioDevice: audio_mode_handsfree 8 microfone_device_front 5 0 audio_playstate_synth Oct 16 13:40:46.838 [DEBUG0] MEDIA: Synthesizer Command: PLAY 200 15 425 PLAY 200 0 0 LOOP 2 0 0 Oct 16 13:40:46.840 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:46.841 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:40:46.845 [DEBUG1] PHN: Goto State 'Minibrowser_Message' (25) from' Terminated' (12), MB 0, blocked 0 caller:ShowMessage-219 Oct 16 13:40:46.860 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:46.865 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=0 Oct 16 13:40:46.865 [DEBUG0] PHN: MbParser:: enter Oct 16 13:40:46.865 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/ Oct 16 13:40:46.868 [INFO ] PHN: Xpath applies: /IPPhoneBatch/ Oct 16 13:40:46.868 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:40:46.869 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/ Oct 16 13:40:46.869 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:40:46.869 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch Oct 16 13:40:46.869 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch Oct 16 13:40:46.869 [DEBUG0] PHN: MbParserMainTag:: searching in subs Oct 16 13:40:46.869 [DEBUG0] PHN: MbParserMainTag:: faoun tree = IPPhoneMenu Oct 16 13:40:46.869 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu Oct 16 13:40:46.872 [INFO ] PHN: Xpath applies: /* Oct 16 13:40:46.872 [DEBUG2] PHN: Xpath matched element node IPPhoneMenu Oct 16 13:40:46.873 [DEBUG0] PHN: Xpath succesfully fetched: /* Oct 16 13:40:46.873 [DEBUG0] PHN: Xpath succesfully fetched: Oct 16 13:40:46.931 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:46.931 [DEBUG1] SIP: mark for deletion 4/def5f068d0a6-80kgd0mesw1r, cbi 1, 1 Oct 16 13:40:46.931 [INFO ] SIP: packet 1001494 stopped retransmission Oct 16 13:40:46.931 [INFO ] SIP: packet 1001496 stopped retransmission Oct 16 13:40:46.931 [DEBUG2] SIP: SIP: delete connection 4/4 in 0 secs Oct 16 13:40:46.932 [DEBUG1] SIP: mark for deletion 4/def5f068d0a6-80kgd0mesw1r, cbi 0, 1 Oct 16 13:40:46.932 [INFO ] SIP: packet 1001494 stopped retransmission Oct 16 13:40:46.932 [INFO ] SIP: packet 1001496 stopped retransmission Oct 16 13:40:46.932 [DEBUG2] SIP: SIP: delete connection 4/4 in 0 secs Oct 16 13:40:46.932 [DEBUG0] SIP: connection 4 terminated Oct 16 13:40:46.932 [DEBUG2] SIP: Decrement Transport Udp:192.168.1.200:5060: 1 Oct 16 13:40:46.932 [DEBUG2] SIP: Updated Transport 0x1590c70 TTL 3660000 Oct 16 13:40:46.932 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP4 changed=0, update_req=1, adr= Oct 16 13:40:46.932 [DEBUG0] MEDIA: MediaIpc::closeStream: 81118f2a Oct 16 13:40:46.933 [INFO ] SIP: rtp_port::release_turn_resources(), rtp relay port=0, rtcp relay port=0 Oct 16 13:40:46.933 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP4 changed=0, update_req=1, adr= Oct 16 13:40:46.933 [NOTICE] MEDIA: MediaIpc::rtpClose: RP4 Oct 16 13:40:46.933 [NOTICE] MEDIA: MediaIpc::rtpClose: RC4 Oct 16 13:40:46.933 [INFO ] MEDIA: 102654387 onSetupAudioDevice: 6 8 - 5 0 - 2 Oct 16 13:40:46.934 [INFO ] MEDIA: CAudioSubsystem::setAudioMode 0->6 Oct 16 13:40:46.951 [INFO ] MEDIA: playstate 0->2 Oct 16 13:40:46.951 [DEBUG2] GUI: SlotPlayWavSynthUpdate: new: 1 current: 0 Oct 16 13:40:46.981 [INFO ] MEDIA: setSynthProgram Oct 16 13:40:46.981 [DEBUG0] MEDIA: StreamClose: 2165411626 Oct 16 13:40:46.982 [NOTICE] MEDIA: onRtpClose: RP4 Oct 16 13:40:46.982 [DEBUG0] MEDIA: onRtpClose: name: RP4 ssrc: 0 Oct 16 13:40:46.982 [INFO ] MEDIA: NumberOfSocketsWithSsrc: ssrc: 0, sockets: 0 Oct 16 13:40:46.982 [DEBUG0] MEDIA: Socket 0x2890c8/noname: state disconnected(7) Oct 16 13:40:46.982 [DEBUG0] MEDIA: Socket 0x2890c8/noname: state deleted(8) Oct 16 13:40:46.982 [NOTICE] MEDIA: onRtpClose: RC4 Oct 16 13:40:46.983 [DEBUG0] MEDIA: onRtpClose: name: RC4 ssrc: 0 Oct 16 13:40:46.983 [INFO ] MEDIA: NumberOfSocketsWithSsrc: ssrc: 0, sockets: 0 Oct 16 13:40:46.983 [DEBUG0] MEDIA: Socket 0x2893b8/noname: state disconnected(7) Oct 16 13:40:46.983 [DEBUG0] MEDIA: Socket 0x2893b8/noname: state deleted(8) Oct 16 13:40:47.141 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=0 Oct 16 13:40:47.188 [DEBUG2] PHN: LabelDisplay::OnPanelRepainted: idx=0, active_page_=0, display_device_=0x14126d8 Oct 16 13:40:48.190 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1 Oct 16 13:40:48.191 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 1), from_hardware 1, is_longpressed 0, State Minibrowser_Message (25), time 102655737 Oct 16 13:40:48.260 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2 Oct 16 13:40:48.260 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 0), from_hardware 1, is_longpressed 0, State Minibrowser_Message (25), time 102655807 Oct 16 13:40:48.261 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:48.263 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:48.264 [DEBUG1] PHN: Goto State 'Terminated' (12) from' Minibrowser_Message' (25), MB 0, blocked 0 caller:key_on_minibrowser_msg-60 Oct 16 13:40:48.265 [INFO ] PHN: Deleting channel 4, identity 1, state closed, total connection count 1 Oct 16 13:40:48.284 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:48.284 [DEBUG0] GUI: SetSynthMode: synth 9->0, audio 0, force 0, caller:InitiateEndOfStateTerminated-97 Oct 16 13:40:48.285 [DEBUG0] MEDIA: Synthesizer Command: PLAY 0 0 0 Oct 16 13:40:48.286 [INFO ] GUI: synth_silent: connected lines: 0 state: Terminated inIdle: 0 inTerminated: 1 Oct 16 13:40:48.287 [INFO ] GUI: synth_silent: lines with call action: 0 Oct 16 13:40:48.287 [INFO ] GUI: synth_silent: set playstate idle and audio mode to none Oct 16 13:40:48.287 [INFO ] GUI: SetAudioPlaystate: 0 Oct 16 13:40:48.287 [DEBUG0] MEDIA: MediaIpc::SetupAudioDevice: audio_mode_none 0 microfone_device_front 5 0 audio_playstate_idle Oct 16 13:40:48.290 [DEBUG1] PHN: Goto Best State from 'pTerminated' (12), force 1 called-by InitiateEndOfStateTerminated-99 Oct 16 13:40:48.290 [DEBUG1] PHN: Goto State 'Idle' (26) from' Terminated' (12), MB 0, blocked 0 caller:goto_best_state-607 Oct 16 13:40:48.304 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:40:48.304 [DEBUG0] PHN: conference off TodosWhenPhoneTurnsIdle Oct 16 13:40:48.304 [DEBUG0] MEDIA: MediaIpc::setSpeakerDefault: 6 0 Oct 16 13:40:48.397 [INFO ] MEDIA: setSynthProgram Oct 16 13:40:48.397 [INFO ] MEDIA: 102655837 onSetupAudioDevice: 0 0 - 5 0 - 0 Oct 16 13:40:48.397 [INFO ] MEDIA: CAudioSubsystem::setAudioMode 6->0 Oct 16 13:40:48.408 [INFO ] MEDIA: Can't determine device for current audio_mode_=0 Oct 16 13:40:48.408 [INFO ] MEDIA: Can't determine device for current audio_mode_=0 Oct 16 13:40:48.409 [INFO ] MEDIA: playstate 2->0 Oct 16 13:40:48.409 [DEBUG2] GUI: SlotPlayWavSynthUpdate: new: 0 current: 0 Oct 16 13:40:50.821 [DEBUG2] PHN: WEB: HTTP accepting from Tcp:192.168.1.54:54902 Oct 16 13:40:50.822 [DEBUG2] PHN: WEB: HTTP 37/80 accepted from Tcp:192.168.1.54:54902 Oct 16 13:40:50.822 [DEBUG2] PHN: WEB: HTTP 37/80 connected Oct 16 13:40:50.822 [INFO ] PHN: WEB: Request 37/825/0: POST /log.htm HTTP/1.1 Oct 16 13:40:50.824 [INFO ] PHN: WEB: 37 Digest authentication Oct 16 13:40:50.830 [INFO ] PHN: new Tag: >1zoety1drj18k96p< Filename: log.htm