Jan 30 16:20:11.621 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1
Jan 30 16:20:11.630 [DEBUG0] UXM: Request backlight for module -1 with value 15
Jan 30 16:20:11.632 [INFO ] GUI: GUI: Allocating channel 7, line 3
Jan 30 16:20:11.636 [NOTICE] CSTA: csta_cid = 7
phone_cid7
Jan 30 16:20:11.637 [DEBUG1] SIP: RTP: set_port - ports: 49152 65534, con 6
Jan 30 16:20:11.637 [DEBUG0] MEDIA: MediaIpc::rtpSetup: rtp: RP6 rtcp: RC6 start: 49152 end: 65534
Jan 30 16:20:11.639 [INFO ] SIP: Dialog 7/6/1 going to trying
Jan 30 16:20:11.639 [INFO ] GUI: GUI: Line-Seize subscription was not sent
Jan 30 16:20:11.639 [DEBUG0] MEDIA: MediaIpc::setSpeakerDefault: 4 10
Jan 30 16:20:11.641 [DEBUG1] PHN: Goto Best State from 'pIdle' (26), force 1 called-by start_dialing-902
Jan 30 16:20:11.641 [DEBUG1] PHN: Goto State 'Edit_number' (5) from' Idle' (26), MB 0, blocked 0 caller:goto_best_state-452
Jan 30 16:20:11.667 [DEBUG0] UXM: Request backlight for module -1 with value 15
Jan 30 16:20:11.676 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=0
Jan 30 16:20:11.676 [DEBUG0] PHN: MbParser:: enter
Jan 30 16:20:11.676 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/
Jan 30 16:20:11.681 [INFO ] PHN: Xpath applies: /IPPhoneBatch/
Jan 30 16:20:11.681 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:11.681 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/
Jan 30 16:20:11.681 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:11.681 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch
Jan 30 16:20:11.681 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch
Jan 30 16:20:11.681 [DEBUG0] PHN: MbParserMainTag:: searching in subs
Jan 30 16:20:11.681 [DEBUG0] PHN: MbParserMainTag:: found tree = IPPhoneMenu
Jan 30 16:20:11.682 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu
Jan 30 16:20:11.686 [WARN ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Jan 30 16:20:11.692 [INFO ] PHN: Xpath applies: /*
Jan 30 16:20:11.692 [DEBUG0] PHN: Xpath succesfully fetched: /*
Jan 30 16:20:11.692 [DEBUG0] PHN: Xpath succesfully fetched:
Jan 30 16:20:11.694 [WARN ] PHN: AddDataIdAndCheckSelection: no id set for line: 0
Jan 30 16:20:11.793 [DEBUG0] UXM: Request backlight for module -1 with value 15
Jan 30 16:20:11.794 [DEBUG0] MEDIA: playSynthesizer cmd[11]: PLAY 0 0 0
Jan 30 16:20:11.795 [INFO ] GUI: synth_silent: lines: 1 state: Edit_number inIdle: 0 inTerminated: 0
Jan 30 16:20:11.795 [INFO ] GUI: synth_silent: has lines with call action: 1
Jan 30 16:20:11.799 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=1
Jan 30 16:20:11.800 [DEBUG0] MEDIA: Socket 0x267fc0/noname: state connected(5)
Jan 30 16:20:11.800 [DEBUG0] MEDIA: Socket 0x2682b8/noname: state connected(5)
Jan 30 16:20:11.801 [INFO ] MEDIA: setSynthProgram
Jan 30 16:20:11.801 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2
Jan 30 16:20:11.804 [DEBUG1] MEDIA: 1321591 onPlayDtmf (0) time 1321522
Jan 30 16:20:11.842 [DEBUG1] MEDIA: 1321591 onPlayDtmf: timer pending 0 list size: 0
Jan 30 16:20:11.843 [DEBUG1] MEDIA: 1321591 onPlayDtmf: start PlayDtmf_cb now
Jan 30 16:20:11.843 [DEBUG1] MEDIA: 1321591 PlayDtmf_cb: list size: 1
Jan 30 16:20:11.843 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: time since last event: 1321592
Jan 30 16:20:11.843 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: Current and last events are release events - ignore, no wait time needed
Jan 30 16:20:11.843 [DEBUG1] MEDIA: 1321592 PlayDtmf_cb: Found 1321522 type 0
Jan 30 16:20:11.844 [DEBUG1] MEDIA: 1321592 PlayDtmf_cb: got duplicate event type 0
Jan 30 16:20:11.844 [DEBUG1] MEDIA: 1321592 PlayDtmf_cb: restart cb in 0 ms
Jan 30 16:20:11.844 [DEBUG1] MEDIA: 1321592 PlayDtmf_cb: list size: 0
Jan 30 16:20:11.935 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1
Jan 30 16:20:11.935 [DEBUG0] UXM: Request backlight for module -1 with value 15
Jan 30 16:20:12.001 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=1
Jan 30 16:20:12.001 [DEBUG0] PHN: MbParser:: enter
Jan 30 16:20:12.001 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/
Jan 30 16:20:12.006 [INFO ] PHN: Xpath applies: /IPPhoneBatch/
Jan 30 16:20:12.006 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:12.007 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/
Jan 30 16:20:12.007 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:12.007 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch
Jan 30 16:20:12.007 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch
Jan 30 16:20:12.007 [DEBUG0] PHN: MbParserMainTag:: searching in subs
Jan 30 16:20:12.007 [DEBUG0] PHN: MbParserMainTag:: found tree = IPPhoneMenu
Jan 30 16:20:12.007 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu
Jan 30 16:20:12.011 [WARN ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Jan 30 16:20:12.018 [INFO ] PHN: Xpath applies: /*
Jan 30 16:20:12.018 [DEBUG0] PHN: Xpath succesfully fetched: /*
Jan 30 16:20:12.018 [DEBUG0] PHN: Xpath succesfully fetched:
Jan 30 16:20:12.019 [WARN ] PHN: AddDataIdAndCheckSelection: no id set for line: 0
Jan 30 16:20:12.139 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2
Jan 30 16:20:12.163 [DEBUG1] MEDIA: 1321929 onPlayDtmf (0) time 1321831
Jan 30 16:20:12.164 [DEBUG1] MEDIA: 1321929 onPlayDtmf: timer pending 0 list size: 0
Jan 30 16:20:12.164 [DEBUG1] MEDIA: 1321929 onPlayDtmf: start PlayDtmf_cb now
Jan 30 16:20:12.164 [DEBUG1] MEDIA: 1321929 PlayDtmf_cb: list size: 1
Jan 30 16:20:12.164 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: time since last event: 1321929
Jan 30 16:20:12.164 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: Current and last events are release events - ignore, no wait time needed
Jan 30 16:20:12.164 [DEBUG1] MEDIA: 1321930 PlayDtmf_cb: Found 1321831 type 0
Jan 30 16:20:12.165 [DEBUG1] MEDIA: 1321930 PlayDtmf_cb: got duplicate event type 0
Jan 30 16:20:12.165 [DEBUG1] MEDIA: 1321930 PlayDtmf_cb: restart cb in 0 ms
Jan 30 16:20:12.165 [DEBUG1] MEDIA: 1321930 PlayDtmf_cb: list size: 0
Jan 30 16:20:12.315 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1
Jan 30 16:20:12.316 [DEBUG0] UXM: Request backlight for module -1 with value 15
Jan 30 16:20:12.417 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2
Jan 30 16:20:12.420 [DEBUG1] MEDIA: 1322207 onPlayDtmf (0) time 1322198
Jan 30 16:20:12.421 [DEBUG1] MEDIA: 1322207 onPlayDtmf: timer pending 0 list size: 0
Jan 30 16:20:12.421 [DEBUG1] MEDIA: 1322207 onPlayDtmf: start PlayDtmf_cb now
Jan 30 16:20:12.421 [DEBUG1] MEDIA: 1322207 PlayDtmf_cb: list size: 1
Jan 30 16:20:12.422 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: time since last event: 1322207
Jan 30 16:20:12.422 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: Current and last events are release events - ignore, no wait time needed
Jan 30 16:20:12.422 [DEBUG1] MEDIA: 1322207 PlayDtmf_cb: Found 1322198 type 0
Jan 30 16:20:12.423 [DEBUG1] MEDIA: 1322208 PlayDtmf_cb: got duplicate event type 0
Jan 30 16:20:12.423 [DEBUG1] MEDIA: 1322208 PlayDtmf_cb: restart cb in 0 ms
Jan 30 16:20:12.423 [DEBUG1] MEDIA: 1322208 PlayDtmf_cb: list size: 0
Jan 30 16:20:12.873 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=1
Jan 30 16:20:12.873 [DEBUG0] PHN: MbParser:: enter
Jan 30 16:20:12.873 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/
Jan 30 16:20:12.877 [INFO ] PHN: Xpath applies: /IPPhoneBatch/
Jan 30 16:20:12.877 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:12.877 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/
Jan 30 16:20:12.877 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:12.878 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch
Jan 30 16:20:12.878 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch
Jan 30 16:20:12.878 [DEBUG0] PHN: MbParserMainTag:: searching in subs
Jan 30 16:20:12.878 [DEBUG0] PHN: MbParserMainTag:: found tree = IPPhoneMenu
Jan 30 16:20:12.878 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu
Jan 30 16:20:12.883 [WARN ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Jan 30 16:20:12.894 [INFO ] PHN: Xpath applies: /*
Jan 30 16:20:12.894 [DEBUG0] PHN: Xpath succesfully fetched: /*
Jan 30 16:20:12.894 [DEBUG0] PHN: Xpath succesfully fetched:
Jan 30 16:20:12.896 [WARN ] PHN: AddDataIdAndCheckSelection: no id set for line: 0
Jan 30 16:20:12.912 [WARN ] PHN: AddDataIdAndCheckSelection: no id set for line: 1
Jan 30 16:20:12.914 [WARN ] PHN: AddDataIdAndCheckSelection: no id set for line: 2
Jan 30 16:20:13.042 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1
Jan 30 16:20:13.043 [DEBUG0] UXM: Request backlight for module -1 with value 15
Jan 30 16:20:13.045 [DEBUG1] PHN: Sending Keepalive to Watchdog
Jan 30 16:20:13.061 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2
Jan 30 16:20:13.064 [INFO ] PHN: HandleWatchdog: status: 1 -> 1, timeout: 60, dog_fd: 5, setting set: 1 value: on
Jan 30 16:20:13.065 [DEBUG1] MEDIA: 1322851 onPlayDtmf (0) time 1322834
Jan 30 16:20:13.065 [DEBUG1] MEDIA: 1322851 onPlayDtmf: timer pending 0 list size: 0
Jan 30 16:20:13.065 [DEBUG1] MEDIA: 1322851 onPlayDtmf: start PlayDtmf_cb now
Jan 30 16:20:13.065 [DEBUG1] MEDIA: 1322851 PlayDtmf_cb: list size: 1
Jan 30 16:20:13.066 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: time since last event: 1322851
Jan 30 16:20:13.066 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: Current and last events are release events - ignore, no wait time needed
Jan 30 16:20:13.066 [DEBUG1] MEDIA: 1322852 PlayDtmf_cb: Found 1322834 type 0
Jan 30 16:20:13.066 [DEBUG1] MEDIA: 1322852 PlayDtmf_cb: got duplicate event type 0
Jan 30 16:20:13.066 [DEBUG1] MEDIA: 1322852 PlayDtmf_cb: restart cb in 0 ms
Jan 30 16:20:13.067 [DEBUG1] MEDIA: 1322852 PlayDtmf_cb: list size: 0
Jan 30 16:20:13.162 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=1
Jan 30 16:20:13.225 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1
Jan 30 16:20:13.226 [DEBUG0] UXM: Request backlight for module -1 with value 15
Jan 30 16:20:13.277 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=1, force_redraw=1
Jan 30 16:20:13.277 [DEBUG0] PHN: MbParser:: enter
Jan 30 16:20:13.277 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/
Jan 30 16:20:13.281 [INFO ] PHN: Xpath applies: /IPPhoneBatch/
Jan 30 16:20:13.282 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:13.282 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/
Jan 30 16:20:13.282 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:13.282 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch
Jan 30 16:20:13.282 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch
Jan 30 16:20:13.282 [DEBUG0] PHN: MbParserMainTag:: searching in subs
Jan 30 16:20:13.283 [DEBUG0] PHN: MbParserMainTag:: found tree = IPPhoneMenu
Jan 30 16:20:13.283 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu
Jan 30 16:20:13.287 [WARN ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Jan 30 16:20:13.295 [INFO ] PHN: Xpath applies: /*
Jan 30 16:20:13.295 [DEBUG0] PHN: Xpath succesfully fetched: /*
Jan 30 16:20:13.295 [DEBUG0] PHN: Xpath succesfully fetched:
Jan 30 16:20:13.297 [WARN ] PHN: AddDataIdAndCheckSelection: no id set for line: 0
Jan 30 16:20:13.409 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2
Jan 30 16:20:13.413 [DEBUG1] MEDIA: 1323199 onPlayDtmf (0) time 1323099
Jan 30 16:20:13.413 [DEBUG1] MEDIA: 1323199 onPlayDtmf: timer pending 0 list size: 0
Jan 30 16:20:13.414 [DEBUG1] MEDIA: 1323199 onPlayDtmf: start PlayDtmf_cb now
Jan 30 16:20:13.414 [DEBUG1] MEDIA: 1323199 PlayDtmf_cb: list size: 1
Jan 30 16:20:13.414 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: time since last event: 1323199
Jan 30 16:20:13.414 [DEBUG1] MEDIA: GetMsUntilNextDtmfPlay: Current and last events are release events - ignore, no wait time needed
Jan 30 16:20:13.414 [DEBUG1] MEDIA: 1323199 PlayDtmf_cb: Found 1323099 type 0
Jan 30 16:20:13.415 [DEBUG1] MEDIA: 1323200 PlayDtmf_cb: got duplicate event type 0
Jan 30 16:20:13.415 [DEBUG1] MEDIA: 1323200 PlayDtmf_cb: restart cb in 0 ms
Jan 30 16:20:13.415 [DEBUG1] MEDIA: 1323200 PlayDtmf_cb: list size: 0
Jan 30 16:20:13.792 [INFO ] PHN: entering gui_object::display_on_minibrowser() state=2, force_redraw=1
Jan 30 16:20:13.792 [DEBUG0] PHN: MbParser:: enter
Jan 30 16:20:13.793 [DEBUG0] PHN: MbParser:: selected_tree = /IPPhoneBatch/
Jan 30 16:20:13.797 [INFO ] PHN: Xpath applies: /IPPhoneBatch/
Jan 30 16:20:13.797 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:13.797 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/
Jan 30 16:20:13.797 [INFO ] PHN: Xpath contains unrecognized steps, aborting
Jan 30 16:20:13.797 [DEBUG0] PHN: MbParser:: found tree = IPPhoneBatch
Jan 30 16:20:13.797 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneBatch
Jan 30 16:20:13.797 [DEBUG0] PHN: MbParserMainTag:: searching in subs
Jan 30 16:20:13.798 [DEBUG0] PHN: MbParserMainTag:: found tree = IPPhoneMenu
Jan 30 16:20:13.798 [DEBUG0] PHN: MbParserMainTag:: tree = IPPhoneMenu
Jan 30 16:20:13.803 [WARN ] GUI: Minibrowser callback $/enter_number_fkey_list expecting vector result, but no vector callback defined
Jan 30 16:20:13.814 [INFO ] PHN: Xpath applies: /*