Oct 16 13:47:03.733 [INFO ] PHN: new Tag: >uplhhuiwsj0hp862< Filename: log.htm Oct 16 13:47:06.431 [DEBUG2] PHN: LLDP: testNetworkPolicyIsSet, timeout=90, now=103033, has_policy=0, timestamp=0, elapsed=103033 Oct 16 13:47:06.750 [NOTICE] PHN: TPL: Socket 45 idle/connect timeout Oct 16 13:47:06.751 [DEBUG2] PHN: WEB: HTTP 45/80 disconnected Oct 16 13:47:06.751 [DEBUG2] PHN: WEB: HTTP 45/80 deleted Oct 16 13:47:07.184 [DEBUG1] SIP: recv OPTIONS (102: 0c6fcd2c25f61ab260988c4d6208f8eb@192.168.1.200) <- Udp:192.168.1.200:5060 Oct 16 13:47:07.186 [DEBUG1] SIP: RoutePendingPacket 1001506: entry=Udp:192.168.1.200:5060 all 1 Oct 16 13:47:07.186 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001506 Oct 16 13:47:07.186 [DEBUG0] SIP: send 200 OPTIONS (102: 0c6fcd2c25f61ab260988c4d6208f8eb@192.168.1.200) -> Udp:192.168.1.200:5060 Oct 16 13:47:07.188 [DEBUG2] SIP: Updated Transport 0x1590c70 TTL 3660000 Oct 16 13:47:10.527 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1 Oct 16 13:47:10.527 [DEBUG2] PHN: gui_keys: on key event (F2, 1), from_hardware 1, is_longpressed 0, State Idle (26), time 103038074 Oct 16 13:47:10.529 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:10.711 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2 Oct 16 13:47:10.712 [DEBUG2] PHN: gui_keys: on key event (F2, 0), from_hardware 1, is_longpressed 0, State Idle (26), time 103038252 Oct 16 13:47:10.739 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=0 Oct 16 13:47:10.739 [DEBUG0] PHN: MbParser:: enter Oct 16 13:47:10.740 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/ Oct 16 13:47:10.761 [INFO ] PHN: Xpath applies: /IPPhoneBatch/ Oct 16 13:47:10.761 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:47:10.761 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/ Oct 16 13:47:10.761 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:47:10.761 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch Oct 16 13:47:10.761 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch Oct 16 13:47:10.761 [DEBUG0] PHN: MbParserMainTag:: searching in subs Oct 16 13:47:10.762 [DEBUG0] PHN: MbParserMainTag:: faoun tree = IPPhoneMenu Oct 16 13:47:10.763 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu Oct 16 13:47:10.767 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:10.769 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:10.773 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:10.814 [INFO ] PHN: Xpath applies: /* Oct 16 13:47:10.814 [DEBUG2] PHN: Xpath matched element node IPPhoneMenu Oct 16 13:47:10.814 [DEBUG0] PHN: Xpath succesfully fetched: /* Oct 16 13:47:10.814 [DEBUG0] PHN: Xpath succesfully fetched: Oct 16 13:47:10.942 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:10.943 [NOTICE] TOOLS: Call List Pool RequestToUpdateCache Oct 16 13:47:10.943 [NOTICE] PHN: Call active CallListManager Request function Oct 16 13:47:10.944 [DEBUG0] PHN: LoopMon: LCS/1774200 took 243 (0) msecs, started Oct 16 15:47:10, 6 tasks Oct 16 13:47:10.946 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=1 Oct 16 13:47:11.141 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=1 Oct 16 13:47:11.226 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=1 Oct 16 13:47:11.226 [DEBUG0] PHN: MbParser:: enter Oct 16 13:47:11.227 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/ Oct 16 13:47:11.249 [INFO ] PHN: Xpath applies: /IPPhoneBatch/ Oct 16 13:47:11.249 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:47:11.249 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/ Oct 16 13:47:11.250 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:47:11.250 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch Oct 16 13:47:11.250 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch Oct 16 13:47:11.250 [DEBUG0] PHN: MbParserMainTag:: searching in subs Oct 16 13:47:11.251 [DEBUG0] PHN: MbParserMainTag:: faoun tree = IPPhoneMenu Oct 16 13:47:11.251 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu Oct 16 13:47:11.255 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:11.258 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:11.262 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:11.307 [INFO ] PHN: Xpath applies: /* Oct 16 13:47:11.307 [DEBUG2] PHN: Xpath matched element node IPPhoneMenu Oct 16 13:47:11.307 [DEBUG0] PHN: Xpath succesfully fetched: /* Oct 16 13:47:11.307 [DEBUG0] PHN: Xpath succesfully fetched: Oct 16 13:47:11.469 [DEBUG2] PHN: LabelDisplay::OnPanelRepainted: idx=0, active_page_=0, display_device_=0x14126d8 Oct 16 13:47:11.480 [DEBUG0] PHN: LoopMon: LCS/1774209 took 253 (0) msecs, started Oct 16 15:47:11, 8 tasks Oct 16 13:47:12.243 [INFO ] PHN: LLDP: No Voice Application Type TLV packet found. Oct 16 13:47:12.811 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1 Oct 16 13:47:12.812 [DEBUG2] PHN: gui_keys: on key event (ENTER, 1), from_hardware 1, is_longpressed 0, State Idle (26), time 103040360 Oct 16 13:47:12.812 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:12.816 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:12.817 [NOTICE] PHN: Minibrowser started a call but was in state others_except_mb. Please check if state is correct. Oct 16 13:47:12.818 [DEBUG1] PHN: Goto Best State from 'pIdle' (26), force 1 called-by MbStop-269 Oct 16 13:47:12.818 [DEBUG1] PHN: Goto State 'Idle' (26) from' Idle' (26), MB 0, blocked 0 caller:goto_best_state-607 Oct 16 13:47:12.820 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:12.821 [INFO ] GUI: find_function_key_for_uri: Looking to match 33@192.168.1.200 Oct 16 13:47:12.821 [INFO ] GUI: GUI: Allocating channel 5, line 1 Oct 16 13:47:12.824 [DEBUG2] GUI: Line 5: state change 'closed' (5) -> 'offhook' (6) Oct 16 13:47:12.828 [NOTICE] CSTA: csta_cid = 5 phone_cid5 Oct 16 13:47:12.828 [DEBUG1] SIP: RTP: set_port - ports: 49152 65534, con 5 Oct 16 13:47:12.828 [DEBUG0] MEDIA: MediaIpc::rtpSetup: rtp: RP5 rtcp: RC5 start: 49152 end: 65534 Oct 16 13:47:12.830 [DEBUG2] SIP: RTP: received_port still not set Oct 16 13:47:12.830 [DEBUG2] SIP: RTP: received_port still not set Oct 16 13:47:12.831 [INFO ] SIP: Dialog 5/5/1 going to trying Oct 16 13:47:12.831 [INFO ] GUI: GUI: Line-Seize subscription was not sent Oct 16 13:47:12.831 [DEBUG0] MEDIA: MediaIpc::setSpeakerDefault: 6 0 Oct 16 13:47:12.832 [DEBUG2] GUI: Line 5: state change 'offhook' (6) -> 'calling' (7) Oct 16 13:47:12.840 [DEBUG1] PHN: Goto State 'Calling' (6) from' Idle' (26), MB 0, blocked 0 caller:MakeCall-767 Oct 16 13:47:12.871 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:12.872 [DEBUG0] MEDIA: Socket 0x289240/noname: state connected(5) Oct 16 13:47:12.872 [DEBUG0] MEDIA: Socket 0x289530/noname: state connected(5) Oct 16 13:47:12.872 [DEBUG2] SIP: rtp allocated for connection 5, id 5 Oct 16 13:47:12.872 [INFO ] SIP: Send Invite(c = 0x14a4700, moh 0, reuse_sdp 0) caller:rtp_allocated-2318 Oct 16 13:47:12.873 [NOTICE] SIP: getInternalSdpFmts: Skipped Codec g723 is not supported for ptime of 20 Oct 16 13:47:12.880 [DEBUG1] SIP: SDP: Dialog 5, sdp state idle -> offering Oct 16 13:47:12.882 [DEBUG1] SIP: No special routing, routing to sip:@192.168.1.200;user=phone Oct 16 13:47:12.882 [DEBUG1] SIP: RoutePendingPacket 1001507: entry=url ? sip:@192.168.1.200;user=phone all 2 Oct 16 13:47:12.882 [DEBUG1] SIP: RoutePendingPacket 1001507: entry=Udp:192.168.1.200:5060 all 2 Oct 16 13:47:12.883 [DEBUG2] SIP: Increment Transport Udp:192.168.1.200:5060: 0 Oct 16 13:47:12.883 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001507 Oct 16 13:47:12.883 [DEBUG0] SIP: send INVITE (1: 60f7f068ecca-r5760nw4jxuk) -> Udp:192.168.1.200:5060 Oct 16 13:47:12.885 [INFO ] SIP: Dialog 5/5/0 going to trying Oct 16 13:47:12.886 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2 Oct 16 13:47:12.886 [DEBUG2] PHN: gui_keys: on key event (ENTER, 0), from_hardware 1, is_longpressed 0, State Calling (6), time 103040403 Oct 16 13:47:12.895 [DEBUG0] PHN: Request lookup for number sip:@192.168.1.200;user=phone Oct 16 13:47:12.897 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:12.947 [DEBUG1] SIP: recv 401 INVITE (1: 60f7f068ecca-r5760nw4jxuk) <- Udp:192.168.1.200:5060 Oct 16 13:47:12.948 [DEBUG2] SIP: clear message repetition 1001507 60f7f068ecca-r5760nw4jxuk Oct 16 13:47:12.949 [DEBUG1] SIP: Routing to explicit plan Udp:192.168.1.200:5060 Oct 16 13:47:12.949 [DEBUG1] SIP: RoutePendingPacket 1001508: entry=Udp:192.168.1.200:5060 all 3 Oct 16 13:47:12.949 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001508 Oct 16 13:47:12.949 [DEBUG0] SIP: send ACK (1: 60f7f068ecca-r5760nw4jxuk) -> Udp:192.168.1.200:5060 Oct 16 13:47:12.952 [DEBUG2] SIP: sip::ProcessChallenge Identity=0, sip:@192.168.1.200;user=phone Oct 16 13:47:12.952 [WARN ] SIP: sip::ProcessAuthDigest: 401 needs 128 bit nonce Oct 16 13:47:12.952 [NOTICE] SIP: process auth: Match challenge for user=33, realm=asterisk Oct 16 13:47:12.953 [DEBUG1] SIP: Routing to explicit plan Udp:192.168.1.200:5060 Oct 16 13:47:12.953 [DEBUG1] SIP: RoutePendingPacket 1001509: entry=Udp:192.168.1.200:5060 all 4 Oct 16 13:47:12.953 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001509 Oct 16 13:47:12.954 [DEBUG0] SIP: send INVITE (2: 60f7f068ecca-r5760nw4jxuk) -> Udp:192.168.1.200:5060 Oct 16 13:47:12.961 [DEBUG1] SIP: recv 404 INVITE (2: 60f7f068ecca-r5760nw4jxuk) <- Udp:192.168.1.200:5060 Oct 16 13:47:12.962 [DEBUG2] SIP: clear message repetition 1001509 60f7f068ecca-r5760nw4jxuk Oct 16 13:47:12.963 [DEBUG1] SIP: Routing to explicit plan Udp:192.168.1.200:5060 Oct 16 13:47:12.963 [DEBUG1] SIP: RoutePendingPacket 1001510: entry=Udp:192.168.1.200:5060 all 5 Oct 16 13:47:12.963 [DEBUG0] SIP: Use Connection Udp:192.168.1.200:5060 for packet 1001510 Oct 16 13:47:12.963 [DEBUG0] SIP: send ACK (2: 60f7f068ecca-r5760nw4jxuk) -> Udp:192.168.1.200:5060 Oct 16 13:47:12.966 [INFO ] SIP: Dialog 5/5/1 going to terminated Oct 16 13:47:12.966 [DEBUG1] SIP: mark for deletion 5/60f7f068ecca-r5760nw4jxuk, cbi 0, 0 Oct 16 13:47:12.966 [DEBUG2] SIP: Connection 5, media hold: sendrecv -> inactive Oct 16 13:47:12.966 [INFO ] SIP: packet 1001507 stopped retransmission Oct 16 13:47:12.966 [INFO ] SIP: packet 1001509 stopped retransmission Oct 16 13:47:12.966 [DEBUG2] SIP: SIP: delete connection 5/5 in 120 secs Oct 16 13:47:12.967 [DEBUG2] GUI: if_state(5, SIP/2.0 404 Not Found) Oct 16 13:47:12.967 [DEBUG1] PHN: Goto Best State from 'pCalling' (6), force 0 called-by if_state-613 Oct 16 13:47:12.967 [DEBUG1] PHN: Goto State 'Calling' (6) from' Calling' (6), MB 0, blocked 0 caller:goto_best_state-471 Oct 16 13:47:12.967 [DEBUG2] PHN: SipRedirectTargets::GetNextTarget Oct 16 13:47:12.967 [DEBUG2] PHN: SipRedirectTargets::PopExpired Oct 16 13:47:12.967 [INFO ] SIP: gui_unreach(5, Not Found, ) Oct 16 13:47:12.967 [DEBUG0] GUI: if_unreach(5, Not Found, ): state (calling) Oct 16 13:47:12.967 [DEBUG0] GUI: Line 5: EndCall (8) Oct 16 13:47:12.967 [DEBUG1] SIP: set_rtp_state: hold state: inactive holding: 0 moh: 0 Oct 16 13:47:12.967 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP5 changed=0, update_req=1, adr= Oct 16 13:47:12.968 [DEBUG0] MEDIA: MediaIpc::rtpSymmetric: RP5 0 Oct 16 13:47:12.968 [DEBUG1] PHN: Goto Best State from 'pCalling' (6), force 0 called-by if_continued-1827 Oct 16 13:47:12.968 [DEBUG1] PHN: Goto State 'Calling' (6) from' Calling' (6), MB 0, blocked 0 caller:goto_best_state-471 Oct 16 13:47:12.968 [DEBUG2] GUI: Line 5: state change 'calling' (7) -> 'closed' (5) Oct 16 13:47:12.970 [INFO ] SIP: onhook(5) Oct 16 13:47:12.970 [DEBUG1] PHN: Goto Best State from 'pCalling' (6), force 0 called-by if_unreach-1617 Oct 16 13:47:12.971 [DEBUG1] PHN: Goto State 'Terminated' (12) from' Calling' (6), MB 0, blocked 0 caller:goto_best_state-529 Oct 16 13:47:12.984 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:12.984 [DEBUG0] GUI: SetSynthMode: synth 0->9, audio 0, force 0, caller:enter_on_terminated-322 Oct 16 13:47:12.984 [INFO ] GUI: SetAudioPlaystate: 2 Oct 16 13:47:12.984 [DEBUG0] MEDIA: MediaIpc::SetupAudioDevice: audio_mode_handsfree 8 microfone_device_front 5 0 audio_playstate_synth Oct 16 13:47:12.987 [DEBUG0] MEDIA: Synthesizer Command: PLAY 200 15 425 PLAY 200 0 0 LOOP 2 0 0 Oct 16 13:47:12.988 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:12.989 [DEBUG0] PHN: ContactUtils::GetVcard: identity=1 Oct 16 13:47:12.995 [DEBUG1] PHN: Goto State 'Minibrowser_Message' (25) from' Terminated' (12), MB 0, blocked 0 caller:ShowMessage-219 Oct 16 13:47:13.009 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:13.015 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=0 Oct 16 13:47:13.015 [DEBUG0] PHN: MbParser:: enter Oct 16 13:47:13.015 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/ Oct 16 13:47:13.018 [INFO ] PHN: Xpath applies: /IPPhoneBatch/ Oct 16 13:47:13.018 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:47:13.018 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/ Oct 16 13:47:13.018 [INFO ] PHN: Xpath contains unrecognized steps, aborting Oct 16 13:47:13.018 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch Oct 16 13:47:13.018 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch Oct 16 13:47:13.019 [DEBUG0] PHN: MbParserMainTag:: searching in subs Oct 16 13:47:13.019 [DEBUG0] PHN: MbParserMainTag:: faoun tree = IPPhoneMenu Oct 16 13:47:13.019 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu Oct 16 13:47:13.022 [INFO ] PHN: Xpath applies: /* Oct 16 13:47:13.022 [DEBUG2] PHN: Xpath matched element node IPPhoneMenu Oct 16 13:47:13.022 [DEBUG0] PHN: Xpath succesfully fetched: /* Oct 16 13:47:13.022 [DEBUG0] PHN: Xpath succesfully fetched: Oct 16 13:47:13.079 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:13.080 [DEBUG1] SIP: mark for deletion 5/60f7f068ecca-r5760nw4jxuk, cbi 1, 1 Oct 16 13:47:13.081 [INFO ] SIP: packet 1001507 stopped retransmission Oct 16 13:47:13.081 [INFO ] SIP: packet 1001509 stopped retransmission Oct 16 13:47:13.081 [DEBUG2] SIP: SIP: delete connection 5/5 in 0 secs Oct 16 13:47:13.081 [DEBUG1] SIP: mark for deletion 5/60f7f068ecca-r5760nw4jxuk, cbi 0, 1 Oct 16 13:47:13.081 [INFO ] SIP: packet 1001507 stopped retransmission Oct 16 13:47:13.081 [INFO ] SIP: packet 1001509 stopped retransmission Oct 16 13:47:13.081 [DEBUG2] SIP: SIP: delete connection 5/5 in 0 secs Oct 16 13:47:13.089 [DEBUG2] PHN: LabelDisplay::OnPanelRepainted: idx=0, active_page_=0, display_device_=0x14126d8 Oct 16 13:47:13.104 [DEBUG0] SIP: connection 5 terminated Oct 16 13:47:13.104 [DEBUG2] SIP: Decrement Transport Udp:192.168.1.200:5060: 1 Oct 16 13:47:13.104 [DEBUG2] SIP: Updated Transport 0x1590c70 TTL 3660000 Oct 16 13:47:13.104 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP5 changed=0, update_req=1, adr= Oct 16 13:47:13.104 [DEBUG0] MEDIA: MediaIpc::closeStream: 7c9ada87 Oct 16 13:47:13.105 [INFO ] SIP: rtp_port::release_turn_resources(), rtp relay port=0, rtcp relay port=0 Oct 16 13:47:13.105 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP5 changed=0, update_req=1, adr= Oct 16 13:47:13.105 [NOTICE] MEDIA: MediaIpc::rtpClose: RP5 Oct 16 13:47:13.107 [NOTICE] MEDIA: MediaIpc::rtpClose: RC5 Oct 16 13:47:13.110 [INFO ] MEDIA: 103040535 onSetupAudioDevice: 6 8 - 5 0 - 2 Oct 16 13:47:13.110 [INFO ] MEDIA: CAudioSubsystem::setAudioMode 0->6 Oct 16 13:47:13.111 [INFO ] MEDIA: playstate 0->2 Oct 16 13:47:13.111 [DEBUG2] GUI: SlotPlayWavSynthUpdate: new: 1 current: 0 Oct 16 13:47:13.111 [INFO ] MEDIA: setSynthProgram Oct 16 13:47:13.111 [DEBUG0] MEDIA: StreamClose: 2090523271 Oct 16 13:47:13.112 [NOTICE] MEDIA: onRtpClose: RP5 Oct 16 13:47:13.112 [DEBUG0] MEDIA: onRtpClose: name: RP5 ssrc: 0 Oct 16 13:47:13.112 [INFO ] MEDIA: NumberOfSocketsWithSsrc: ssrc: 0, sockets: 0 Oct 16 13:47:13.112 [DEBUG0] MEDIA: Socket 0x289240/noname: state disconnected(7) Oct 16 13:47:13.112 [DEBUG0] MEDIA: Socket 0x289240/noname: state deleted(8) Oct 16 13:47:13.170 [NOTICE] MEDIA: onRtpClose: RC5 Oct 16 13:47:13.170 [DEBUG0] MEDIA: onRtpClose: name: RC5 ssrc: 0 Oct 16 13:47:13.170 [INFO ] MEDIA: NumberOfSocketsWithSsrc: ssrc: 0, sockets: 0 Oct 16 13:47:13.171 [DEBUG0] MEDIA: Socket 0x289530/noname: state disconnected(7) Oct 16 13:47:13.171 [DEBUG0] MEDIA: Socket 0x289530/noname: state deleted(8) Oct 16 13:47:13.311 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=0 Oct 16 13:47:14.487 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1 Oct 16 13:47:14.488 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 1), from_hardware 1, is_longpressed 0, State Minibrowser_Message (25), time 103042035 Oct 16 13:47:14.550 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2 Oct 16 13:47:14.550 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 0), from_hardware 1, is_longpressed 0, State Minibrowser_Message (25), time 103042097 Oct 16 13:47:14.551 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:14.553 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:14.554 [DEBUG1] PHN: Goto State 'Terminated' (12) from' Minibrowser_Message' (25), MB 0, blocked 0 caller:key_on_minibrowser_msg-60 Oct 16 13:47:14.555 [INFO ] PHN: Deleting channel 5, identity 1, state closed, total connection count 1 Oct 16 13:47:14.574 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:14.574 [DEBUG0] GUI: SetSynthMode: synth 9->0, audio 0, force 0, caller:InitiateEndOfStateTerminated-97 Oct 16 13:47:14.574 [DEBUG0] MEDIA: Synthesizer Command: PLAY 0 0 0 Oct 16 13:47:14.576 [INFO ] GUI: synth_silent: connected lines: 0 state: Terminated inIdle: 0 inTerminated: 1 Oct 16 13:47:14.576 [INFO ] GUI: synth_silent: lines with call action: 0 Oct 16 13:47:14.576 [INFO ] GUI: synth_silent: set playstate idle and audio mode to none Oct 16 13:47:14.577 [INFO ] GUI: SetAudioPlaystate: 0 Oct 16 13:47:14.577 [DEBUG0] MEDIA: MediaIpc::SetupAudioDevice: audio_mode_none 0 microfone_device_front 5 0 audio_playstate_idle Oct 16 13:47:14.579 [DEBUG1] PHN: Goto Best State from 'pTerminated' (12), force 1 called-by InitiateEndOfStateTerminated-99 Oct 16 13:47:14.579 [DEBUG1] PHN: Goto State 'Idle' (26) from' Terminated' (12), MB 0, blocked 0 caller:goto_best_state-607 Oct 16 13:47:14.594 [DEBUG0] UXM: Request backlight for module -1 with value 15 Oct 16 13:47:14.594 [DEBUG0] PHN: conference off TodosWhenPhoneTurnsIdle Oct 16 13:47:14.594 [DEBUG0] MEDIA: MediaIpc::setSpeakerDefault: 6 0 Oct 16 13:47:14.687 [INFO ] MEDIA: setSynthProgram Oct 16 13:47:14.687 [INFO ] MEDIA: 103042127 onSetupAudioDevice: 0 0 - 5 0 - 0 Oct 16 13:47:14.687 [INFO ] MEDIA: CAudioSubsystem::setAudioMode 6->0 Oct 16 13:47:14.701 [INFO ] MEDIA: Can't determine device for current audio_mode_=0 Oct 16 13:47:14.701 [INFO ] MEDIA: Can't determine device for current audio_mode_=0 Oct 16 13:47:14.701 [INFO ] MEDIA: playstate 2->0 Oct 16 13:47:14.702 [DEBUG2] GUI: SlotPlayWavSynthUpdate: new: 0 current: 0 Oct 16 13:47:16.441 [DEBUG2] PHN: LLDP: testNetworkPolicyIsSet, timeout=90, now=103043, has_policy=0, timestamp=0, elapsed=103043 Oct 16 13:47:16.592 [DEBUG2] PHN: WEB: HTTP accepting from Tcp:192.168.1.54:39854 Oct 16 13:47:16.592 [DEBUG2] PHN: WEB: HTTP 46/80 accepted from Tcp:192.168.1.54:39854 Oct 16 13:47:16.592 [DEBUG2] PHN: WEB: HTTP 46/80 connected Oct 16 13:47:16.593 [INFO ] PHN: WEB: Request 46/825/0: POST /log.htm HTTP/1.1 Oct 16 13:47:16.594 [INFO ] PHN: WEB: 46 Digest authentication Oct 16 13:47:16.600 [INFO ] PHN: new Tag: >t6hswyir6exzipct< Filename: log.htm