[cisco-voip] SCCP 6945 show "Phone not registered"
Jason Aarons (AM)
jason.aarons at dimensiondata.com
Tue Apr 16 20:32:17 EDT 2013
I've been able to narrow it down.
Either some value is too long, or maybe the & is cause a parser problem on the phone? Once I changed this the label/des/name to Americas, the phone LCD display was fine. I smell a bug.
ephone-dn 218 octo-line
number 8621
label 6621 - Conf Rm, Americas A & B
description Conf Rm, Americas A & B
name Conf Rm, Americas A & B
mobility
snr calling-number local
ephone-dn-template 1
!
!
From: Jason Aarons (AM)
Sent: Tuesday, April 16, 2013 7:57 PM
To: Jason Aarons (AM)
Subject: SCCP 6945 not registering
I'm having a high failure rate of new out of box 6945s. They show on the LCD "Phone not registered "They show I can unplug the phone and put another one in its spot and have no problems with the new 6945. Problem is I already have 5 RMAs out of 200 phones. I've done Factory resets etc. There is not CTL or ITL or Trust List, etc. and the phones are new out of the box.
load 6945 SCCP6945.9-1-1-0.loads
c3900e-universalk9-mz.SPA.153-2.T.bin
The CME debug tftp events looks normal;
Here is what the 6945 console log shows;
[Command Description]
DN display name
TID: 0x16 task name: tMVX_AMMU tag name: label value : 6621 - Conf Rm, Americas A & B
is error format.
[Command Description]
DN text label
TID: 0x16 task name: tMVX_AMMU tag name: name value : Conf Rm, Americas A & B is erro
format.
[Command Description]
DN display name
TID: 0x16 task name: tMVX_AMMU tag name: label value : 7621 - Conf Rm, Americas A & B
is error format.
[Command Description]
DN text label
path = /nvdata is exist
FFS Commit to /nvdata/CFG1.
DNS(dynamic/net/dns1) is NULL
--> dot1x_activate(470) :
path = /nvdata is exist
FFS Commit to /nvdata/CFG2.
DNS(dynamic/net/dns1) is NULL
--> dot1x_activate(470) :
path = /nvdata is exist
FFS Commit to /nvdata/CFG1.
DNS(dynamic/net/dns1) is NULL
DNS(dynamic/net/dns1) is NULL
DNS(dynamic/net/dns1) is NULL
DNS(dynamic/net/dns1) is NULL
DNS(dynamic/net/dns1) is NULL
DNS(dynamic/net/dns1) is NULL
DNS(dynamic/net/dns1) is NULL
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
XML_Document error
Unable to load buffer (error #4: Internal Expat parser error)
Expat error #4 (line 14, column 23): not well-formed (invalid token)
retrieve_local_xml_page 186: the page does not contain any data
windmlui_interval_check_new 472: partp is null.
Debug tftp events
021771: Apr 16 19:33:43.720: TFTP: Looking for CTLSEP8478ACC70909.tlv
021772: Apr 16 19:33:43.736: TFTP: Looking for SEP8478ACC70909.cnf.xml
021773: Apr 16 19:33:43.736: TFTP: Opened system:/its/vrf1/XMLDefault6945.cnf.xml, fd 3, size 1677 for process 59
021774: Apr 16 19:33:43.744: TFTP: Finished system:/its/vrf1/XMLDefault6945.cnf.xml, time 00:00:00 for process 59
021775: Apr 16 19:33:46.747: TFTP: Looking for SCCP6945.9-1-1-0.loads
021776: Apr 16 19:33:46.747: TFTP: Opened flash0:/loads/SCCP6945.9-1-1-0.loads, fd 3, size 512 for process 310
021777: Apr 16 19:33:46.753: TFTP: Finished flash0:/loads/SCCP6945.9-1-1-0.loads, time 00:00:00 for process 310
021778: Apr 16 19:33:46.871: TFTP: Looking for English_United_States/rtl-sccp.jar
021779: Apr 16 19:33:46.887: TFTP: Looking for United_States/g3-tones.xml
021780: Apr 16 19:33:48.648: %IPPHONE-6-REGISTER: ephone-218:SEP8478ACC70909 IP:10.83.112.56 Socket:80 DeviceType:Phone has registered.
uswty-v-3925#show ephone 8478.ACC7.0909
ephone-218[217] Mac:8478.ACC7.0909 TCP socket:[143] activeLine:0 whisperLine:0 REGISTERED in SCCP ver 20/17 max_streams=1
mediaActive:0 whisper_mediaActive:0 startMedia:0 offhook:0 ringing:0 reset:0 reset_sent:0 paging 0 debug:0 caps:9 privacy:0
IP:10.83.112.56 * 35274 6945 keepalive 1 max_line 4 available_line 4
button 1: cw:1 ccw:(0 0 0 0 0 0 0 0)
dn 218 number 6621 CH1 IDLE CH2 IDLE CH3 IDLE CH4 IDLE CH5 IDLE CH6 IDLE CH7 IDLE CH8 IDLE
button 2: cw:1 ccw:(0 0 0 0 0 0 0 0)
dn 219 number 7621 CH1 IDLE CH2 IDLE CH3 IDLE CH4 IDLE CH5 IDLE CH6 IDLE CH7 IDLE CH8 IDLE
Preferred Codec: g711ulaw
Lpcor Type: none
uswty-v-3925#
021793: Apr 16 19:43:37.454: SkinnyTransfereeHairpinRinging: dn 866 chan 1 tonetype 37
021794: Apr 16 19:43:40.311: SkinnyStopDnRecallTimer: dn 866 chan 1
021795: Apr 16 19:43:40.311: ephone_get_callid_from_dn_chan: dn[866] chan[1] callID[2834]
021796: Apr 16 19:43:40.323: skinnySetGccbCallInfoNull: callId=2834 dn=866 ch=1
021797: Apr 16 19:43:40.323: skinnyIsSplitAndFlowThruAudio: not split dataplane
021798: Apr 16 19:43:40.479: ephone-218[217/143][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
021799: Apr 16 19:43:40.479: ephone-218[217/143][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
021800: Apr 16 19:43:54.207: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
021801: Apr 16 19:43:54.207: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
021802: Apr 16 19:43:54.207: sccp_transmit_msg: sending on socket 0
021803: Apr 16 19:43:54.207: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
021804: Apr 16 19:43:54.207: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
021805: Apr 16 19:43:54.207: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
021806: Apr 16 19:43:54.207: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
021807: Apr 16 19:43:54.207: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
021808: Apr 16 19:43:54.207: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
021809: Apr 16 19:43:54.207: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
021810: Apr 16 19:43:54.207: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
021811: Apr 16 19:43:54.207: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
021812: Apr 16 19:43:54.207: sccp_parse_control_msg: glob_ccm->version 9
021813: Apr 16 19:43:55.273: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
021814: Apr 16 19:43:55.273: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
021815: Apr 16 19:43:55.273: sccp_transmit_msg: sending on socket 1
021816: Apr 16 19:43:55.273: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
021817: Apr 16 19:43:55.273: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
021818: Apr 16 19:43:55.273: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
021819: Apr 16 19:43:55.273: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
021820: Apr 16 19:43:55.273: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
021821: Apr 16 19:43:55.273: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
021822: Apr 16 19:43:55.273: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
021823: Apr 16 19:43:55.273: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
021824: Apr 16 19:43:55.273: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
021825: Apr 16 19:43:55.273: sccp_parse_control_msg: glob_ccm->version 9
021826: Apr 16 19:44:10.486: ephone-218[217/143][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
021827: Apr 16 19:44:10.488: ephone-218[217/143][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
021828: Apr 16 19:44:24.230: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
021829: Apr 16 19:44:24.230: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
021830: Apr 16 19:44:24.230: sccp_transmit_msg: sending on socket 0
021831: Apr 16 19:44:24.230: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
021832: Apr 16 19:44:24.232: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
021833: Apr 16 19:44:24.232: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
021834: Apr 16 19:44:24.232: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
021835: Apr 16 19:44:24.232: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
021836: Apr 16 19:44:24.232: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
021837: Apr 16 19:44:24.232: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
021838: Apr 16 19:44:24.232: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
021839: Apr 16 19:44:24.232: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
021840: Apr 16 19:44:24.232: sccp_parse_control_msg: glob_ccm->version 9
021841: Apr 16 19:44:25.282: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
021842: Apr 16 19:44:25.282: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
021843: Apr 16 19:44:25.282: sccp_transmit_msg: sending on socket 1
021844: Apr 16 19:44:25.282: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
021845: Apr 16 19:44:25.282: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
021846: Apr 16 19:44:25.282: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
021847: Apr 16 19:44:25.282: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
021848: Apr 16 19:44:25.282: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
021849: Apr 16 19:44:25.282: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
021850: Apr 16 19:44:25.282: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
021851: Apr 16 19:44:25.282: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
021852: Apr 16 19:44:25.282: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
021853: Apr 16 19:44:25.282: sccp_parse_control_msg: glob_ccm->version 9
021854: Apr 16 19:44:33.859: TFTP: Looking for CTLSEP8478ACC70909.tlv
021855: Apr 16 19:44:33.875: TFTP: Looking for SEP8478ACC70909.cnf.xml
021856: Apr 16 19:44:33.875: TFTP: Opened system:/its/vrf1/XMLDefault6945.cnf.xml, fd 3, size 1677 for process 310
021857: Apr 16 19:44:33.883: TFTP: Finished system:/its/vrf1/XMLDefault6945.cnf.xml, time 00:00:00 for process 310
021858: Apr 16 19:44:36.749: TFTP: Looking for SCCP6945.9-1-1-0.loads
021859: Apr 16 19:44:36.749: TFTP: Opened flash0:/loads/SCCP6945.9-1-1-0.loads, fd 3, size 512 for process 310
021860: Apr 16 19:44:36.753: TFTP: Finished flash0:/loads/SCCP6945.9-1-1-0.loads, time 00:00:00 for process 310
021861: Apr 16 19:44:36.857: TFTP: Looking for English_United_States/rtl-sccp.jar
021862: Apr 16 19:44:36.873: TFTP: Looking for United_States/g3-tones.xml
021863: Apr 16 19:44:37.241: New Skinny socket accepted [1] from 0, sub 1 (196 active)
021864: Apr 16 19:44:37.241: sin_family 2, sin_port 35426, in_addr 10.83.112.56
021865: Apr 16 19:44:37.241: skinny_add_socket 1 10.83.112.56 35426
021866: Apr 16 19:44:37.303: UNSUPPORTED message 0x15A on socket 80
021867: Apr 16 19:44:37.504: ephone-(218)[80] StationRegisterMessage (194/202/1030) from 10.83.112.56
021868: Apr 16 19:44:37.504: ephone-(218)[80] Register StationIdentifier DeviceName SEP8478ACC70909
021869: Apr 16 19:44:37.504: ephone-(218)[80] StationIdentifier Instance 1 deviceType 564
021870: Apr 16 19:44:37.504: fSkinnyStationRegister deviceType 564 protocolVer = 0x85702014
021871: Apr 16 19:44:37.504: StationJoinAndDirectTransferFeatureSupportMask set enable
021872: Apr 16 19:44:37.504: StationDisableJoinOnTheSameLineFeatureMask set enable
021873: Apr 16 19:44:37.504: StationDisableJoinAcrossLineFeatureMask set enable
021874: Apr 16 19:44:37.504: StationDisableDirectTransferOnTheSameLineFeatureMask set enable
021875: Apr 16 19:44:37.504: StationDisableDirectTransferAcrossLineFeatureMask set enable
021876: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:stationIpAddr 10.83.112.56
021877: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:stationIpv6Addr ::
021878: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:maxStreams 40
021879: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:From Phone raw protocol Ver 0x85702014
021880: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:protocol Ver 0x85702014
021881: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:phone-size 36080 dn-size 1008
021882: Apr 16 19:44:37.504: ephone-(218) Allow any Skinny Server IP address 10.83.112.11
021883: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:Found entry 217 for 8478ACC70909
021884: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:socket change 143 to 80
021885: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]:DisAssociate: Closed socket 143 while REGISTERED
021886: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 1
021887: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 1 IDLE
021888: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021889: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 2
021890: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 2 IDLE
021891: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021892: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 3
021893: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 3 IDLE
021894: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021895: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 4
021896: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 4 IDLE
021897: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021898: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 5
021899: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 5 IDLE
021900: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021901: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 6
021902: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 6 IDLE
021903: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021904: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 7
021905: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 7 IDLE
021906: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021907: Apr 16 19:44:37.504: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 218 chan 8
021908: Apr 16 19:44:37.504: SkinnyGetCallState for DN 218 chan 8 IDLE
021909: Apr 16 19:44:37.504: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021910: Apr 16 19:44:37.504: Bring down DN 218 chan 1 (6621) with the following traceback
021911: Apr 16 19:44:37.504: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021912: Apr 16 19:44:37.504: Skinny DN 218 chan 1 state change to DOWN
021913: Apr 16 19:44:37.504: Bring down DN 218 chan 2 (6621) with the following traceback
021914: Apr 16 19:44:37.504: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021915: Apr 16 19:44:37.506: Skinny DN 218 chan 2 state change to DOWN
021916: Apr 16 19:44:37.506: Bring down DN 218 chan 3 (6621) with the following traceback
021918: Apr 16 19:44:37.506: Skinny DN 218 chan 3 state change to DOWN
021919: Apr 16 19:44:37.506: Bring down DN 218 chan 4 (6621) with the following traceback
021920: Apr 16 19:44:37.506: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021921: Apr 16 19:44:37.506: Skinny DN 218 chan 4 state change to DOWN
021922: Apr 16 19:44:37.506: Bring down DN 218 chan 5 (6621) with the following traceback
021923: Apr 16 19:44:37.506: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021924: Apr 16 19:44:37.506: Skinny DN 218 chan 5 state change to DOWN
021925: Apr 16 19:44:37.506: Bring down DN 218 chan 6 (6621) with the following traceback
021926: Apr 16 19:44:37.506: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021927: Apr 16 19:44:37.506: Skinny DN 218 chan 6 state change to DOWN
021928: Apr 16 19:44:37.506: Bring down DN 218 chan 7 (6621) with the following traceback
021929: Apr 16 19:44:37.506: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021930: Apr 16 19:44:37.506: Skinny DN 218 chan 7 state change to DOWN
021931: Apr 16 19:44:37.506: Bring down DN 218 chan 8 (6621) with the following traceback
021932: Apr 16 19:44:37.506: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021933: Apr 16 19:44:37.506: Skinny DN 218 chan 8 state change to DOWN
021934: Apr 16 19:44:37.506: SkinnySetCTILineStatus: phone 217 dn 218 line status 0 line_removed 0
021935: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 1
021936: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 1 IDLE
021937: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021938: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 2
021939: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 2 IDLE
021940: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021941: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 3
021942: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 3 IDLE
021943: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021944: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 4
021945: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 4 IDLE
021946: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021947: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 5
021948: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 5 IDLE
021949: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021950: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 6
021951: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 6 IDLE
021952: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021953: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 7
021954: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 7 IDLE
021955: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021956: Apr 16 19:44:37.506: ephone-218[217/143][SEP8478ACC70909]: DN out-of-service for DN 219 chan 8
021957: Apr 16 19:44:37.506: SkinnyGetCallState for DN 219 chan 8 IDLE
021958: Apr 16 19:44:37.506: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
021959: Apr 16 19:44:37.506: Bring down DN 219 chan 1 (7621) with the following traceback
021960: Apr 16 19:44:37.506: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021961: Apr 16 19:44:37.508: Skinny DN 219 chan 1 state change to DOWN
021962: Apr 16 19:44:37.508: Bring down DN 219 chan 2 (7621) with the following traceback
021963: Apr 16 19:44:37.508: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021964: Apr 16 19:44:37.508: Skinny DN 219 chan 2 state change to DOWN
021965: Apr 16 19:44:37.508: Bring down DN 219 chan 3 (7621) with the following traceback
021966: Apr 16 19:44:37.508: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021967: Apr 16 19:44:37.508: Skinny DN 219 chan 3 state change to DOWN
021968: Apr 16 19:44:37.508: Bring down DN 219 chan 4 (7621) with the following traceback
021969: Apr 16 19:44:37.508: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021970: Apr 16 19:44:37.508: Skinny DN 219 chan 4 state change to DOWN
021971: Apr 16 19:44:37.508: Bring down DN 219 chan 5 (7621) with the following traceback
021972: Apr 16 19:44:37.508: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021973: Apr 16 19:44:37.508: Skinny DN 219 chan 5 state change to DOWN
021974: Apr 16 19:44:37.508: Bring down DN 219 chan 6 (7621) with the following traceback
021975: Apr 16 19:44:37.508: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021976: Apr 16 19:44:37.508: Skinny DN 219 chan 6 state change to DOWN
021977: Apr 16 19:44:37.508: Bring down DN 219 chan 7 (7621) with the following traceback
021978: Apr 16 19:44:37.508: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021979: Apr 16 19:44:37.508: Skinny DN 219 chan 7 state change to DOWN
021980: Apr 16 19:44:37.508: Bring down DN 219 chan 8 (7621) with the following traceback
021981: Apr 16 19:44:37.508: -Traceback= 19B686Cz 46C03AAz 46C8C78z 46C878Ez 4749500z 4764AF4z 47624A0z 4725089z 476A663z
021982: Apr 16 19:44:37.508: Skinny DN 219 chan 8 state change to DOWN
021983: Apr 16 19:44:37.508: SkinnySetCTILineStatus: phone 217 dn 219 line status 0 line_removed 0
021984: Apr 16 19:44:37.508: ephone-218[217/143][SEP8478ACC70909]:skinny_delete_socket [143]
021985: Apr 16 19:44:37.508: %IPPHONE-6-UNREGISTER_ABNORMAL: ephone-218:SEP8478ACC70909 IP:10.83.112.56 Socket:143 DeviceType:Phone has unregistered abnormally.
021986: Apr 16 19:44:37.508: ephone-218[217/-1][SEP8478ACC70909]:CLOSED old socket -1
021987: Apr 16 19:44:37.508: ephone-218[217/-1][SEP8478ACC70909]:
021988: Apr 16 19:44:37.508: bulk_speeddial_init_ephone: 217
021989: Apr 16 19:44:37.508: ephone-218[217/-1]:SkinnyVideoParmsUpdate: phone: 217, action: 2, dn_index: -1
021990: Apr 16 19:44:37.510: ephone-218[217/80][SEP8478ACC70909]:phone SEP8478ACC70909 re-associate OK on socket [80]
021991: Apr 16 19:44:37.510: %IPPHONE-6-REGISTER: ephone-218:SEP8478ACC70909 IP:10.83.112.56 Socket:80 DeviceType:Phone has registered.
021992: Apr 16 19:44:37.510: Phone 217 socket 80
021993: Apr 16 19:44:37.510: Skinny Local IP address = 10.83.112.11 on port 2000
021994: Apr 16 19:44:37.510: Skinny Phone IP address = 10.83.112.56 35426
021995: Apr 16 19:44:37.510: ephone-218[217/80]:SkinnyVideoParmsUpdate: phone: 217, action: 2, dn_index: -1
021996: Apr 16 19:44:37.510: ephone-218[217/80][SEP8478ACC70909]:Signal protocol ver 17 to phone with ver 20
021997: Apr 16 19:44:37.510: ephone-218[217/80][SEP8478ACC70909]:Date Format M/D/Y
021998: Apr 16 19:44:37.510: ephone-218[217/80]:RegisterAck sent to sockettype ephone socket 80: keepalive period 30 use sccp-version 17
021999: Apr 16 19:44:37.510: SkinnyRegisterAck: phone = 217 deviceType 564 highestProtocolVer = 0x13CE011
022000: Apr 16 19:44:37.510: StationJoinAndDirectTransferFeatureSupportMask set enable
022001: Apr 16 19:44:37.510: StationDisableJoinOnTheSameLineFeatureMask set disable
022002: Apr 16 19:44:37.510: StationDisableJoinAcrossLineFeatureMask set disable
022003: Apr 16 19:44:37.510: StationDisableDirectTransferOnTheSameLineFeatureMask set disable
022004: Apr 16 19:44:37.510: StationDisableDirectTransferAcrossLineFeatureMask set disable
022005: Apr 16 19:44:37.510: ephone-218[217/80]:CapabilitiesReq sent
022006: Apr 16 19:44:37.518: ephone-218[217/80]:HeadsetStatusMessage OFF
022007: Apr 16 19:44:37.518: ephone-218[217/80]:HeadsetStatusMessage
022008: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:MediaPathEventMessage Headset OFF
022009: Apr 16 19:44:37.718: ephone-218[217/80]:MediaPathEventMessage
022010: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:MediaPathEventMessage Handset OFF
022011: Apr 16 19:44:37.718: ephone-218[217/80]:MediaPathEventMessage
022012: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:MediaPathCapabilitiesMessage Speaker Enable
022013: Apr 16 19:44:37.718: ephone-218[217/80]:MediaPathCapabilitiesMessage
022014: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:StationServerReqMessage from ephone
022015: Apr 16 19:44:37.718: TcpListenPort 2000 on address 10.83.112.11
022016: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:StationServerResMessage sent to ephone
022017: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022018: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022019: Apr 16 19:44:37.718: ephone-218[217/80]:ButtonTemplateReqMessage
022020: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:ButtonTemplateReqMessage get maxNumberOfLines 12
022021: Apr 16 19:44:37.718: ephone-218[217/80]:ButtonTemplateReqMessage waiting for Caps
022022: Apr 16 19:44:37.718: ephone-218[217/80]:StationConfigStatReqMessage
022023: Apr 16 19:44:37.718: ephone-218[217/80]:waiting for Caps
022024: Apr 16 19:44:37.718: ephone-218[217/80]:CapabilitiesRes received
022025: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:Caps list 9
WideBand_256K 20 ms, is_mtp 0
G711Ulaw64k 80 ms, is_mtp 0
G711Alaw64k 80 ms, is_mtp 0
ILBC 80 ms, is_mtp 0
G729AnnexB 80 ms, is_mtp 0
G729AnnexAwAnnexB 80 ms, is_mtp 0
G729 80 ms, is_mtp 0
G729AnnexA 80 ms, is_mtp 0
RFC2833 Dynamic Payload 1 ms, is_mtp 0
022026: Apr 16 19:44:37.718: ephone-218[217/80]:Process pending button template
022027: Apr 16 19:44:37.718: ephone-218[217/80]:ButtonTemplateReqMessage
022028: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:StationButtonTemplateReqMessage set max presentation to 2
022029: Apr 16 19:44:37.718: ephone-218[217/80]:CheckAutoReg
022030: Apr 16 19:44:37.718: ephone-218[217/80]:AutoReg is disabled
022031: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:Setting 4 lines 0 speed-dials on phone (max_line 4)
022032: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:First Speed Dial Button location is 0 (0)
022033: Apr 16 19:44:37.718: ephone-218[217/80]:ButtonTemplate lines=4 speed=0 buttons=4 offset=0
022034: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:ButtonTemplate buttonCount=4 totalButtonCount=4 buttonOffset=0
022035: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:Configured 0 speed dial buttons
022036: Apr 16 19:44:37.718: ephone-218[217/80]:Process pending ConfigStat
022037: Apr 16 19:44:37.718: ephone-218[217/80]:StationConfigStatReqMessage
022038: Apr 16 19:44:37.718: ephone-218[217/80][SEP8478ACC70909]:StationConfigStatMessage sent for device SEP8478ACC70909 (40/280)
022039: Apr 16 19:44:37.772: ephone-218[217/80]:StationSoftKeyTemplateReqMessage
022040: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 1 Redial
022041: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 2 NewCall
022042: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 3 Hold
022043: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 4 Trnsfer
022044: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 5 CFwdAll
022045: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 6 CW Off
022046: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 7 CFwdNoAnswer
022047: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 8 <<
022048: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 9 EndCall
022049: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 10 Resume
022050: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 11 Answer
022051: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 12 Info
022052: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 13 Confrn
022053: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 14 Park
022054: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 15 Join
022055: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 16 MeetMe
022056: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 17 PickUp
022057: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 18 GPickUp
022058: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 19 RmLstC
022059: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 20 CallBack
022060: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 21 Barge
022061: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 22 DnD
022062: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 23 Acct
022063: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 24 Flash
022064: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 25 Login
022065: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 26 HLog
022066: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 27 ConfList
022067: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 28 Select
022068: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 29 TrnsfVM
022069: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 30 CBarge
022070: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 31 LiveRcd
022071: Apr 16 19:44:37.772: ephone-218[217/80][SEP8478ACC70909]:SoftKeys: 32 Mobility
022072: Apr 16 19:44:37.772: ephone-218[217/80]:StationSoftKeyTemplateResMessage
022073: Apr 16 19:44:37.790: ephone-218[217/80]:StationSoftKeySetReqMessage
022074: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 0 key 1
022075: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 1 key 2
022076: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 2 key 5
022077: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 3 key 17
022078: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 4 key 32
022079: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 5 key 0
022080: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 6 key 0
022081: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 7 key 0
022082: Apr 16 19:44:37.790: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 8 key 0
022083: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 9 key 0
022084: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 10 key 0
022085: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 11 key 0
022086: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 12 key 0
022087: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 13 key 0
022088: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 14 key 0
022089: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetIdle: template 1 index 15 key 0
022090: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 0 key 3
022091: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 1 key 9
022092: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 2 key 4
022093: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 3 key 14
022094: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 4 key 13
022095: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 5 key 27
022096: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 6 key 28
022097: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 7 key 15
022098: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 8 key 32
022099: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 9 key 0
022100: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 10 key 0
022101: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 11 key 0
022102: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 12 key 0
022103: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 13 key 0
022104: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 14 key 0
022105: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetConnected: template 1 index 15 key 0
022106: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 0 key 10
022107: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 1 key 2
022108: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 2 key 28
022109: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 3 key 15
022110: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 4 key 0
022111: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 5 key 0
022112: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 6 key 0
022113: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 7 key 0
022114: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 8 key 0
022115: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 9 key 0
022116: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 10 key 0
022117: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 11 key 0
022118: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 12 key 0
022119: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 13 key 0
022120: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 14 key 0
022121: Apr 16 19:44:37.792: ephone-218[217/80][SEP8478ACC70909]:GetSoftkeysetHold: template 1 index 15 key 0
022122: Apr 16 19:44:37.792: ephone-218[217/80]:StationSoftKeySetResMessage
022123: Apr 16 19:44:37.800: ephone-218[217/80][SEP8478ACC70909]:StationLineStatReqMessage from ephone line 1
022124: Apr 16 19:44:37.800: ephone-218[217/80]:StationLineStatReqMessage ephone line 1 DN 218 = 6621 desc = Conf Rm, Americas A & B label = 6621 - Conf Rm, Americas A & B
022125: Apr 16 19:44:37.800: ephone-218[217/80][SEP8478ACC70909]:StationLineStatResMessage sent to ephone (1 of 4)
022126: Apr 16 19:44:37.810: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022127: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatReqMessage from ephone line 2
022128: Apr 16 19:44:37.820: ephone-218[217/80]:StationLineStatReqMessage ephone line 2 DN 219 = 7621 desc = Conf Rm, Americas A & B label = 7621 - Conf Rm, Americas A & B
022129: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatResMessage sent to ephone (2 of 4)
022130: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatReqMessage from ephone line 3
022131: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatReqMessage from ephone line 3 Invalid DN -1
022132: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatResMessage sent to ephone (3 of 4)
022133: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatReqMessage from ephone line 4
022134: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatReqMessage from ephone line 4 Invalid DN -1
022135: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:StationLineStatResMessage sent to ephone (4 of 4)
022136: Apr 16 19:44:37.820: ephone-218[217/80]:SkinnyCompleteRegistration
022137: Apr 16 19:44:37.820: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 0 ref 0
022138: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022139: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022140: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 1
022141: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 1 IDLE
022142: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022143: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 2
022144: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 2 IDLE
022145: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022146: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 3
022147: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 3 IDLE
022148: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022149: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 4
022150: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 4 IDLE
022151: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022152: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 5
022153: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 5 IDLE
022154: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022155: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 6
022156: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 6 IDLE
022157: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022158: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 7
022159: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 7 IDLE
022160: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022161: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 218 chan 8
022162: Apr 16 19:44:37.820: SkinnyGetCallState for DN 218 chan 8 IDLE
022163: Apr 16 19:44:37.820: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022164: Apr 16 19:44:37.820: Skinny DN 218 chan 1 state change to UP
022165: Apr 16 19:44:37.820: Skinny DN 218 chan 2 state change to UP
022166: Apr 16 19:44:37.820: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022167: Apr 16 19:44:37.822: Skinny DN 218 chan 3 state change to UP
022168: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022169: Apr 16 19:44:37.822: Skinny DN 218 chan 4 state change to UP
022170: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022171: Apr 16 19:44:37.822: Skinny DN 218 chan 5 state change to UP
022172: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022173: Apr 16 19:44:37.822: Skinny DN 218 chan 6 state change to UP
022174: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022175: Apr 16 19:44:37.822: Skinny DN 218 chan 7 state change to UP
022176: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022177: Apr 16 19:44:37.822: Skinny DN 218 chan 8 state change to UP
022178: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022179: Apr 16 19:44:37.822: SkinnySetCTILineStatus: phone 217 dn 218 line status 1 line_removed 0
022180: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 1
022181: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 1 IDLE
022182: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022183: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 2
022184: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 2 IDLE
022185: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022186: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 3
022187: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 3 IDLE
022188: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022189: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 4
022190: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 4 IDLE
022191: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022192: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 5
022193: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 5 IDLE
022194: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022195: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 6
022196: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 6 IDLE
022197: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022198: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 7
022199: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 7 IDLE
022200: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022201: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]: DN in-service for DN 219 chan 8
022202: Apr 16 19:44:37.822: SkinnyGetCallState for DN 219 chan 8 IDLE
022203: Apr 16 19:44:37.822: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022204: Apr 16 19:44:37.822: Skinny DN 219 chan 1 state change to UP
022205: Apr 16 19:44:37.822: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022206: Apr 16 19:44:37.824: Skinny DN 219 chan 2 state change to UP
022207: Apr 16 19:44:37.824: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022208: Apr 16 19:44:37.824: Skinny DN 219 chan 3 state change to UP
022209: Apr 16 19:44:37.824: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022210: Apr 16 19:44:37.824: Skinny DN 219 chan 4 state change to UP
022211: Apr 16 19:44:37.824: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022212: Apr 16 19:44:37.824: Skinny DN 219 chan 5 state change to UP
022213: Apr 16 19:44:37.824: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022214: Apr 16 19:44:37.824: Skinny DN 219 chan 6 state change to UP
022215: Apr 16 19:44:37.824: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022216: Apr 16 19:44:37.824: Skinny DN 219 chan 7 state change to UP
022217: Apr 16 19:44:37.824: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022218: Apr 16 19:44:37.824: Skinny DN 219 chan 8 state change to UP
022219: Apr 16 19:44:37.824: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022220: Apr 16 19:44:37.824: SkinnySetCTILineStatus: phone 217 dn 219 line status 1 line_removed 0
022221: Apr 16 19:44:37.830: ephone-218[217/80][SEP8478ACC70909]:StationForwardStatReqMessage line 1 from ephone
022222: Apr 16 19:44:37.830: Skinny StationForwardStatMessage line 1 sent on ephone socket [80] for ephone-218
022223: Apr 16 19:44:37.830: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
022224: Apr 16 19:44:37.872: ephone-218[217/80][SEP8478ACC70909]:StationForwardStatReqMessage line 2 from ephone
022225: Apr 16 19:44:37.872: Skinny StationForwardStatMessage line 2 sent on ephone socket [80] for ephone-218
022226: Apr 16 19:44:37.872: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
022227: Apr 16 19:44:37.882: ephone-218[217/80][SEP8478ACC70909]:StationForwardStatReqMessage line 3 from ephone
022228: Apr 16 19:44:37.882: Skinny StationForwardStatMessage line 3 sent on ephone socket [80] for ephone-218
022229: Apr 16 19:44:37.882: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
022230: Apr 16 19:44:37.894: ephone-218[217/80][SEP8478ACC70909]:StationForwardStatReqMessage line 4 from ephone
022231: Apr 16 19:44:37.894: Skinny StationForwardStatMessage line 4 sent on ephone socket [80] for ephone-218
022232: Apr 16 19:44:37.894: activeForward 0 AllActive 0 BusyActive 0 NoAnswerActive 0
022233: Apr 16 19:44:37.900: ephone-218[217/80][SEP8478ACC70909]:Check Hot-Sync for line from 1 to 4 out of 2 lines
022234: Apr 16 19:44:37.900: ephone-218[217/80][SEP8478ACC70909]:SkinnySyncDnCFA DN 218 line 1 to []
022235: Apr 16 19:44:37.900: SkinnySyncDnCFA for DN 218 for 1 phones
022236: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022237: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022238: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022239: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022240: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022241: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022242: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022243: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022244: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022245: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022246: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022247: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022248: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022249: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022250: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022251: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022252: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 8 IDLE
022253: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022254: Apr 16 19:44:37.900: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 1 ref 0 TsOnHook
022255: Apr 16 19:44:37.900: ephone_get_callid_from_dn_chan: dn[218] chan[1] callID[-1]
022256: Apr 16 19:44:37.900: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
022257: Apr 16 19:44:37.900: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
022258: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022259: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022260: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022261: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022262: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022263: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022264: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022265: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022266: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022267: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022268: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022269: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022270: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022271: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022272: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022273: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022274: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 8 IDLE
022275: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022276: Apr 16 19:44:37.900: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 2 ref 0 TsOnHook
022277: Apr 16 19:44:37.900: ephone_get_callid_from_dn_chan: dn[218] chan[2] callID[-1]
022278: Apr 16 19:44:37.900: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
,022279: Apr 16 19:44:37.900: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
022280: Apr 16 19:44:37.900: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022281: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022282: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022283: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022284: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022285: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022286: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022287: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022288: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022289: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022290: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022291: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022292: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022293: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022294: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022295: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022296: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022297: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 8 IDLE
022298: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022299: Apr 16 19:44:37.900: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 3 ref 0 TsOnHook
022300: Apr 16 19:44:37.900: ephone_get_callid_from_dn_chan: dn[218] chan[3] callID[-1]
022301: Apr 16 19:44:37.900: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
022302: Apr 16 19:44:37.900: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
022303: Apr 16 19:44:37.900: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022304: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022305: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022306: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022307: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022308: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022309: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022310: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022311: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022312: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022313: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022314: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022315: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022316: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022317: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022318: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022319: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022320: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 8 IDLE
022321: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022322: Apr 16 19:44:37.900: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 4 ref 0 TsOnHook
022323: Apr 16 19:44:37.900: ephone_get_callid_from_dn_chan: dn[218] chan[4] callID[-1]
022324: Apr 16 19:44:37.900: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
022325: Apr 16 19:44:37.900: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
022326: Apr 16 19:44:37.900: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022327: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022328: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022329: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022330: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022331: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022332: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022333: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022334: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022335: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022336: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022337: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022338: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022339: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022340: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022341: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022342: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022343: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 8 IDLE
022344: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022345: Apr 16 19:44:37.900: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 5 ref 0 TsOnHook
022346: Apr 16 19:44:37.900: ephone_get_callid_from_dn_chan: dn[218] chan[5] callID[-1]
022347: Apr 16 19:44:37.900: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
022348: Apr 16 19:44:37.900: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
,022349: Apr 16 19:44:37.900: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022350: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022351: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022352: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022353: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022354: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022355: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022356: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022357: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022358: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022359: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022360: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022361: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022362: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022363: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022364: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022365: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022366: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 8 IDLE
022367: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022368: Apr 16 19:44:37.900: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 6 ref 0 TsOnHook
022369: Apr 16 19:44:37.900: ephone_get_callid_from_dn_chan: dn[218] chan[6] callID[-1]
022370: Apr 16 19:44:37.900: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
022371: Apr 16 19:44:37.900: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
022372: Apr 16 19:44:37.900: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022373: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022374: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022375: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 1 IDLE
022376: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022377: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 2 IDLE
022378: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022379: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 3 IDLE
022380: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022381: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 4 IDLE
022382: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022383: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 5 IDLE
022384: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022385: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 6 IDLE
022386: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022387: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 7 IDLE
022388: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022389: Apr 16 19:44:37.900: SkinnyGetCallState for DN 218 chan 8 IDLE
022390: Apr 16 19:44:37.900: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022391: Apr 16 19:44:37.900: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 7 ref 0 TsOnHook
,022392: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[218] chan[7] callID[-1]
022393: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
022394: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
022395: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022396: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 8 IDLE
022397: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022398: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 1 IDLE
022399: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022400: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 2 IDLE
022401: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022402: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 3 IDLE
022403: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022404: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 4 IDLE
022405: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022406: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 5 IDLE
022407: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022408: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 6 IDLE
022409: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022410: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 7 IDLE
022411: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022412: Apr 16 19:44:37.902: SkinnyGetCallState for DN 218 chan 8 IDLE
022413: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022414: Apr 16 19:44:37.902: ephone-218[80]:SetCallState line 1 DN 218(-1) chan 8 ref 0 TsOnHook
022415: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[218] chan[8] callID[-1]
022416: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 1 ref 0 state 2 (slot 0)
022417: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 1 ref 0
022418: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
,022419: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnySyncDnCFA DN 219 line 2 to []
022420: Apr 16 19:44:37.902: SkinnySyncDnCFA for DN 219 for 1 phones
022421: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022422: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022423: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022424: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022425: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022426: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022427: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022428: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022429: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022430: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022431: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022432: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022433: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 6 IDLE
022434: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022435: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 7 IDLE
022436: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022437: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 8 IDLE
022438: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022439: Apr 16 19:44:37.902: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 1 ref 0 TsOnHook
022440: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[219] chan[1] callID[-1]
022441: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
022442: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
022443: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022444: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022445: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022446: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022447: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022448: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022449: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022450: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022451: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022452: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022453: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022454: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022455: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022456: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 6 IDLE
022457: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022458: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 7 IDLE
022459: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022460: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 8 IDLE
022461: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022462: Apr 16 19:44:37.902: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 2 ref 0 TsOnHook
,022463: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[219] chan[2] callID[-1]
022464: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
022465: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
022466: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022467: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022468: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022469: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022470: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022471: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022472: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022473: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022474: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022475: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022476: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022477: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022478: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022479: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 6 IDLE
022480: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022481: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 7 IDLE
022482: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022483: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 8 IDLE
022484: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022485: Apr 16 19:44:37.902: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 3 ref 0 TsOnHook
022486: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[219] chan[3] callID[-1]
022487: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
022488: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
022489: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
,022490: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022491: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022492: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022493: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022494: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022495: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022496: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022497: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022498: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022499: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022500: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022501: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022502: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 6 IDLE
022503: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022504: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 7 IDLE
022505: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022506: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 8 IDLE
022507: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022508: Apr 16 19:44:37.902: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 4 ref 0 TsOnHook
022509: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[219] chan[4] callID[-1]
022510: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
022511: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
022512: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022513: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022514: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022515: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022516: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022517: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022518: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022519: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022520: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022521: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022522: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022523: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022524: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022525: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 6 IDLE
022526: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022527: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 7 IDLE
022528: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022529: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 8 IDLE
022530: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022531: Apr 16 19:44:37.902: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 5 ref 0 TsOnHook
022532: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[219] chan[5] callID[-1]
022533: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
,022534: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
022535: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022536: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 6 IDLE
022537: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022538: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022539: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022540: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022541: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022542: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022543: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022544: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022545: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022546: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022547: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022548: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 6 IDLE
022549: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022550: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 7 IDLE
022551: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022552: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 8 IDLE
022553: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022554: Apr 16 19:44:37.902: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 6 ref 0 TsOnHook
022555: Apr 16 19:44:37.902: ephone_get_callid_from_dn_chan: dn[219] chan[6] callID[-1]
022556: Apr 16 19:44:37.902: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
022557: Apr 16 19:44:37.902: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
022558: Apr 16 19:44:37.902: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022559: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 7 IDLE
022560: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022561: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 1 IDLE
022562: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022563: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 2 IDLE
022564: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022565: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 3 IDLE
022566: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022567: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 4 IDLE
022568: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022569: Apr 16 19:44:37.902: SkinnyGetCallState for DN 219 chan 5 IDLE
022570: Apr 16 19:44:37.902: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022571: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 6 IDLE
022572: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022573: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 7 IDLE
022574: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022575: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 8 IDLE
022576: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022577: Apr 16 19:44:37.904: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 7 ref 0 TsOnHook
022578: Apr 16 19:44:37.904: ephone_get_callid_from_dn_chan: dn[219] chan[7] callID[-1]
022579: Apr 16 19:44:37.904: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
022580: Apr 16 19:44:37.904: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
022581: Apr 16 19:44:37.904: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022582: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 8 IDLE
022583: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022584: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 1 IDLE
022585: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022586: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 2 IDLE
022587: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022588: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 3 IDLE
022589: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
,022590: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 4 IDLE
022591: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022592: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 5 IDLE
022593: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022594: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 6 IDLE
022595: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022596: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 7 IDLE
022597: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022598: Apr 16 19:44:37.904: SkinnyGetCallState for DN 219 chan 8 IDLE
022599: Apr 16 19:44:37.904: called DN -1 chan 1, calling DN -1 chan 1 phone -1 s2s:0
022600: Apr 16 19:44:37.904: ephone-218[80]:SetCallState line 2 DN 219(-1) chan 8 ref 0 TsOnHook
022601: Apr 16 19:44:37.904: ephone_get_callid_from_dn_chan: dn[219] chan[8] callID[-1]
022602: Apr 16 19:44:37.904: ephone-218[217/80]:SkinnyTrackActiveCall for line 2 ref 0 state 2 (slot 0)
022603: Apr 16 19:44:37.904: ephone-218[217/80]:SelectPhoneSoftKeys set 0 mask FFFE for line 2 ref 0
,022604: Apr 16 19:44:37.904: ephone-218[217/80][SEP8478ACC70909]:SkinnyCleanupOverlapInfo: Obtained phone index(217) from DN
022605: Apr 16 19:44:37.904: ephone-218[80]:Set MWI line 1 to OFF
022606: Apr 16 19:44:37.904: SkinnyNotifyCTIMWI: phone 217 dn 218 mwi OFF mwi count 0
022607: Apr 16 19:44:37.904: ephone-218[80]:Set MWI line 0 to OFF
022608: Apr 16 19:44:37.904: ephone-218[80]:Set MWI line 2 to OFF
022609: Apr 16 19:44:37.904: SkinnyNotifyCTIMWI: phone 217 dn 219 mwi OFF mwi count 0
022610: Apr 16 19:44:37.904: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022611: Apr 16 19:44:37.904: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022612: Apr 16 19:44:37.904: ephone-218[217/80][SEP8478ACC70909]:Skinny Available Lines 4 set for socket [80]
022613: Apr 16 19:44:37.904: ephone-218[217/80]:Already done SkinnyCompleteRegistration
022614: Apr 16 19:44:37.908: ephone-218[217/80]:TimeDateReqMessage
022615: Apr 16 19:44:37.908: year=2013 month=4 day=16
022616: Apr 16 19:44:37.908: hour=19 minute=44 second=37
022617: Apr 16 19:44:37.908: day=2 dayofyear=106 tzoffset=-14400
022618: Apr 16 19:44:37.908: ephone-218[217/80]:DefineTimeDate sent
,022619: Apr 16 19:44:40.496: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022620: Apr 16 19:44:40.496: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022621: Apr 16 19:44:54.238: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022622: Apr 16 19:44:54.238: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022623: Apr 16 19:44:54.238: sccp_transmit_msg: sending on socket 0
022624: Apr 16 19:44:54.238: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
022625: Apr 16 19:44:54.238: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022626: Apr 16 19:44:54.238: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
022627: Apr 16 19:44:54.238: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022628: Apr 16 19:44:54.238: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
022629: Apr 16 19:44:54.238: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
022630: Apr 16 19:44:54.238: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
022631: Apr 16 19:44:54.238: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
,022632: Apr 16 19:44:54.238: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022633: Apr 16 19:44:54.238: sccp_parse_control_msg: glob_ccm->version 9
022634: Apr 16 19:44:55.291: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022635: Apr 16 19:44:55.291: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022636: Apr 16 19:44:55.291: sccp_transmit_msg: sending on socket 1
022637: Apr 16 19:44:55.291: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
022638: Apr 16 19:44:55.291: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022639: Apr 16 19:44:55.291: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
022640: Apr 16 19:44:55.291: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022641: Apr 16 19:44:55.291: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
022642: Apr 16 19:44:55.291: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
022643: Apr 16 19:44:55.291: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
022644: Apr 16 19:44:55.291: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
,022645: Apr 16 19:44:55.291: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022646: Apr 16 19:44:55.291: sccp_parse_control_msg: glob_ccm->version 9
022647: Apr 16 19:45:10.503: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022648: Apr 16 19:45:10.505: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022649: Apr 16 19:45:24.261: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022650: Apr 16 19:45:24.261: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022651: Apr 16 19:45:24.261: sccp_transmit_msg: sending on socket 0
022652: Apr 16 19:45:24.261: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
022653: Apr 16 19:45:24.263: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022654: Apr 16 19:45:24.263: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
022655: Apr 16 19:45:24.263: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022656: Apr 16 19:45:24.263: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
022657: Apr 16 19:45:24.263: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
,022658: Apr 16 19:45:24.263: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
022659: Apr 16 19:45:24.263: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
022660: Apr 16 19:45:24.263: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022661: Apr 16 19:45:24.263: sccp_parse_control_msg: glob_ccm->version 9
022662: Apr 16 19:45:25.300: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022663: Apr 16 19:45:25.300: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022664: Apr 16 19:45:25.300: sccp_transmit_msg: sending on socket 1
022665: Apr 16 19:45:25.300: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
022666: Apr 16 19:45:25.300: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022667: Apr 16 19:45:25.300: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
022668: Apr 16 19:45:25.300: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022669: Apr 16 19:45:25.300: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
022670: Apr 16 19:45:25.300: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
,022671: Apr 16 19:45:25.300: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
022672: Apr 16 19:45:25.300: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
022673: Apr 16 19:45:25.300: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022674: Apr 16 19:45:25.300: sccp_parse_control_msg: glob_ccm->version 9
022675: Apr 16 19:45:38.788: ephone_get_callid_from_dn_chan: dn[790] chan[1] callID[-1]
022676: Apr 16 19:45:38.790: SkinnyTransfereeHairpinRinging: dn 790 chan 1 tonetype 33
022677: Apr 16 19:45:40.512: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022678: Apr 16 19:45:40.512: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022679: Apr 16 19:45:42.314: SkinnyStopDnRecallTimer: dn 790 chan 1
022680: Apr 16 19:45:42.314: ephone_get_callid_from_dn_chan: dn[790] chan[1] callID[2835]
022681: Apr 16 19:45:42.326: DN: 790, CHAN: 1, voice_hcmask: 0x0
022682: Apr 16 19:45:42.326: dn_support_srtp DN 790 chan 1 with dn_index 790 found phone -1
022683: Apr 16 19:45:42.326: dn_support_srtp DN 790 chan 1 can not find phone support srtp
,022684: Apr 16 19:45:42.326: skinnyDeleteDpSessionStatsPending: callId=2835 dn=790 ch=1
022685: Apr 16 19:45:42.326: skinnyIsSplitAndFlowThruAudio: not split dataplane
022686: Apr 16 19:45:42.326: skinnySetGccbCallInfoNull: callId=2835 dn=790 ch=1
022687: Apr 16 19:45:42.326: skinnyIsSplitAndFlowThruAudio: not split dataplane
022688: Apr 16 19:45:54.270: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022689: Apr 16 19:45:54.270: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022690: Apr 16 19:45:54.270: sccp_transmit_msg: sending on socket 0
022691: Apr 16 19:45:54.270: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
022692: Apr 16 19:45:54.270: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022693: Apr 16 19:45:54.270: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
022694: Apr 16 19:45:54.270: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022695: Apr 16 19:45:54.270: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
022696: Apr 16 19:45:54.270: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
022697: Apr 16 19:45:54.270: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
,022698: Apr 16 19:45:54.270: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
022699: Apr 16 19:45:54.270: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022700: Apr 16 19:45:54.270: sccp_parse_control_msg: glob_ccm->version 9
022701: Apr 16 19:45:55.308: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022702: Apr 16 19:45:55.308: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022703: Apr 16 19:45:55.308: sccp_transmit_msg: sending on socket 1
022704: Apr 16 19:45:55.308: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
022705: Apr 16 19:45:55.308: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022706: Apr 16 19:45:55.308: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
022707: Apr 16 19:45:55.308: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022708: Apr 16 19:45:55.308: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
022709: Apr 16 19:45:55.308: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
022710: Apr 16 19:45:55.308: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
,022711: Apr 16 19:45:55.308: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
022712: Apr 16 19:45:55.308: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022713: Apr 16 19:45:55.308: sccp_parse_control_msg: glob_ccm->version 9
022714: Apr 16 19:46:10.521: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022715: Apr 16 19:46:10.521: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022716: Apr 16 19:46:24.293: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022717: Apr 16 19:46:24.293: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022718: Apr 16 19:46:24.293: sccp_transmit_msg: sending on socket 0
022719: Apr 16 19:46:24.293: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
022720: Apr 16 19:46:24.293: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022721: Apr 16 19:46:24.293: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
022722: Apr 16 19:46:24.295: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022723: Apr 16 19:46:24.295: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
,022724: Apr 16 19:46:24.295: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
022725: Apr 16 19:46:24.295: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
022726: Apr 16 19:46:24.295: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
022727: Apr 16 19:46:24.295: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022728: Apr 16 19:46:24.295: sccp_parse_control_msg: glob_ccm->version 9
022729: Apr 16 19:46:25.317: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022730: Apr 16 19:46:25.317: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022731: Apr 16 19:46:25.317: sccp_transmit_msg: sending on socket 1
022732: Apr 16 19:46:25.317: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
022733: Apr 16 19:46:25.317: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022734: Apr 16 19:46:25.317: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
022735: Apr 16 19:46:25.317: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022736: Apr 16 19:46:25.317: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
,022737: Apr 16 19:46:25.317: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
022738: Apr 16 19:46:25.317: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
022739: Apr 16 19:46:25.317: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
022740: Apr 16 19:46:25.317: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022741: Apr 16 19:46:25.317: sccp_parse_control_msg: glob_ccm->version 9
022742: Apr 16 19:46:40.530: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022743: Apr 16 19:46:40.530: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022744: Apr 16 19:46:54.302: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022745: Apr 16 19:46:54.302: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022746: Apr 16 19:46:54.302: sccp_transmit_msg: sending on socket 0
022747: Apr 16 19:46:54.302: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
022748: Apr 16 19:46:54.302: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022749: Apr 16 19:46:54.302: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
,022750: Apr 16 19:46:54.302: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022751: Apr 16 19:46:54.302: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
022752: Apr 16 19:46:54.302: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
022753: Apr 16 19:46:54.302: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
022754: Apr 16 19:46:54.302: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
022755: Apr 16 19:46:54.302: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022756: Apr 16 19:46:54.302: sccp_parse_control_msg: glob_ccm->version 9
022757: Apr 16 19:46:55.326: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022758: Apr 16 19:46:55.326: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022759: Apr 16 19:46:55.326: sccp_transmit_msg: sending on socket 1
022760: Apr 16 19:46:55.326: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
022761: Apr 16 19:46:55.326: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022762: Apr 16 19:46:55.326: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
,022763: Apr 16 19:46:55.326: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022764: Apr 16 19:46:55.326: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
022765: Apr 16 19:46:55.326: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
022766: Apr 16 19:46:55.326: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
022767: Apr 16 19:46:55.326: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
022768: Apr 16 19:46:55.326: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022769: Apr 16 19:46:55.326: sccp_parse_control_msg: glob_ccm->version 9
022770: Apr 16 19:47:10.540: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022771: Apr 16 19:47:10.540: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022772: Apr 16 19:47:24.324: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022773: Apr 16 19:47:24.324: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022774: Apr 16 19:47:24.324: sccp_transmit_msg: sending on socket 0
022775: Apr 16 19:47:24.324: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
,022776: Apr 16 19:47:24.326: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022777: Apr 16 19:47:24.326: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
022778: Apr 16 19:47:24.326: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022779: Apr 16 19:47:24.326: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
022780: Apr 16 19:47:24.326: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
022781: Apr 16 19:47:24.326: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
022782: Apr 16 19:47:24.326: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
022783: Apr 16 19:47:24.326: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022784: Apr 16 19:47:24.326: sccp_parse_control_msg: glob_ccm->version 9
022785: Apr 16 19:47:25.335: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022786: Apr 16 19:47:25.335: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022787: Apr 16 19:47:25.335: sccp_transmit_msg: sending on socket 1
022788: Apr 16 19:47:25.335: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
,022789: Apr 16 19:47:25.335: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022790: Apr 16 19:47:25.335: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
022791: Apr 16 19:47:25.335: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022792: Apr 16 19:47:25.335: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
022793: Apr 16 19:47:25.335: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
022794: Apr 16 19:47:25.335: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
022795: Apr 16 19:47:25.335: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
022796: Apr 16 19:47:25.335: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022797: Apr 16 19:47:25.335: sccp_parse_control_msg: glob_ccm->version 9
022798: Apr 16 19:47:40.547: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022799: Apr 16 19:47:40.547: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
022800: Apr 16 19:47:54.333: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022801: Apr 16 19:47:54.333: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
,022802: Apr 16 19:47:54.333: sccp_transmit_msg: sending on socket 0
022803: Apr 16 19:47:54.333: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
022804: Apr 16 19:47:54.333: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022805: Apr 16 19:47:54.333: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
022806: Apr 16 19:47:54.333: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022807: Apr 16 19:47:54.333: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
022808: Apr 16 19:47:54.333: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
022809: Apr 16 19:47:54.333: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
022810: Apr 16 19:47:54.333: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
022811: Apr 16 19:47:54.333: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022812: Apr 16 19:47:54.333: sccp_parse_control_msg: glob_ccm->version 9
022813: Apr 16 19:47:55.343: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022814: Apr 16 19:47:55.343: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
,022815: Apr 16 19:47:55.343: sccp_transmit_msg: sending on socket 1
022816: Apr 16 19:47:55.343: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
022817: Apr 16 19:47:55.343: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022818: Apr 16 19:47:55.343: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
022819: Apr 16 19:47:55.343: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022820: Apr 16 19:47:55.343: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
022821: Apr 16 19:47:55.343: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
022822: Apr 16 19:47:55.343: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
022823: Apr 16 19:47:55.343: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
022824: Apr 16 19:47:55.343: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022825: Apr 16 19:47:55.343: sccp_parse_control_msg: glob_ccm->version 9
022826: Apr 16 19:48:10.556: ephone-218[217/80][SEP8478ACC70909]:ClearCallPrompt line 0 ref 0
022827: Apr 16 19:48:10.556: ephone-218[217/80][SEP8478ACC70909]:SkinnyCheckPendingCallBackPhone scan 4 lines
,022828: Apr 16 19:48:24.356: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022829: Apr 16 19:48:24.356: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022830: Apr 16 19:48:24.356: sccp_transmit_msg: sending on socket 0
022831: Apr 16 19:48:24.356: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 0, profile_id 2
022832: Apr 16 19:48:24.356: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 0, pak size 12
022833: Apr 16 19:48:24.356: sccp_send_msg_nonblocking: Sccp message sent socket 0, profile_id 2, sent bytes 12, total pak_size 12, bytes_to_send 12
022834: Apr 16 19:48:24.358: sccpapp_process_socket_events: appl_type 2, soc_fd 0, soc 0, swb_soc -1
022835: Apr 16 19:48:24.358: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 2, eve 4, state 6
022836: Apr 16 19:48:24.358: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 0
022837: Apr 16 19:48:24.358: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 0
022838: Apr 16 19:48:24.358: sccp_get_control_message: sccp cntl msg rcvd, prof_id 2, appl_type 2, msg_len 4
022839: Apr 16 19:48:24.358: sccp_parse_control_msg: msg_ptr 37D82758, msg_len 4, msg_id 256
022840: Apr 16 19:48:24.358: sccp_parse_control_msg: glob_ccm->version 9
,022841: Apr 16 19:48:25.352: sccp_generate_msg: msg_id 0 msg_len 4 pak_size 12
022842: Apr 16 19:48:25.352: sccp_send_keep_alive_msg_v1: send keepalive id 0, len 4
022843: Apr 16 19:48:25.352: sccp_transmit_msg: sending on socket 1
022844: Apr 16 19:48:25.352: sccp_queue_msg_to_xmit:Enqueued sccp msg,size 12, socket 1, profile_id 1
022845: Apr 16 19:48:25.352: sccpapp_process_send_sccpmsg_q: Send sccp msg from sccp_msg_send_q, soc 1, pak size 12
022846: Apr 16 19:48:25.352: sccp_send_msg_nonblocking: Sccp message sent socket 1, profile_id 1, sent bytes 12, total pak_size 12, bytes_to_send 12
022847: Apr 16 19:48:25.352: sccpapp_process_socket_events: appl_type 3, soc_fd 1, soc 1, swb_soc -1
022848: Apr 16 19:48:25.352: sccpapp_process_socket_events: TCP_SOCKET_READ: appl_type 3, eve 4, state 6
022849: Apr 16 19:48:25.352: sccp_get_control_message: bytes_to_read 8, bytes_read 8, appl_total_bytes 8, appl_bytes_read 8, soc_read_mode 0, soc 1
022850: Apr 16 19:48:25.352: sccp_get_control_message: bytes_to_read 4, bytes_read 4, appl_total_bytes 4, appl_bytes_read 4, soc_read_mode 1, soc 1
022851: Apr 16 19:48:25.352: sccp_get_control_message: sccp cntl msg rcvd, prof_id 1, appl_type 3, msg_len 4
022852: Apr 16 19:48:25.352: sccp_parse_control_msg: msg_ptr 37D84AC0, msg_len 4, msg_id 256
022853: Apr 16 19:48:25.352: sccp_parse_control_msg: glob_ccm->version 9
uswty-v-3925#
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20130416/462da752/attachment.html>
More information about the cisco-voip
mailing list