8316 - Cisco Systems - Model=88xx Version=sip88xx.14-1-1-0001-125[TZ=EST+5:00EDT+4:00,69,307] Sun May 19 00:45:01 2024 =============================================================[V11][HW ver: V31][8316] 9543 INF May 19 00:45:01.119624 syslogd forced rotation 9544 NOT May 19 00:45:01.151532 (14922-14922) sarch: -Archiving syslogs to /cisco/logsave/main max=999 9545 NOT May 19 00:45:01.346736 (14959-14959) sarch: -dir size /cisco/logsave/main is 0 9546 NOT May 19 00:45:01.371643 (14967-14967) sarch: -trying to remove oldest file 20240517_110002.tar.gz 9547 NOT May 19 00:45:01.419774 (14982-14982) sarch: -dir size /cisco/logsave/main is 1024000 9548 NOT May 19 00:45:01.427876 (14983-14983) sarch: -archiving /tmp/20240519_044501.tar.gz 9549 NOT May 19 00:45:14.892601 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9550 NOT May 19 00:45:14.892690 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9551 ERR May 19 00:45:14.892718 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9552 ERR May 19 00:45:14.892733 (447-492) CDP-lldpRcvPkt(): parse error: 9 9553 DEB May 19 00:45:26.467579 (616-616) meetingService-getStringProperty: read value for device.feature.bgb_native: 0 9554 NOT May 19 00:45:26.467645 (616-616) meetingService-BGB feature toggle is disabled 9555 DEB May 19 00:45:30.301314 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_register_send_msg: cmd=86=SIP_REG_CANCEL ndx=319 9556 DEB May 19 00:45:30.301511 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=319 ack_timer_index=118 9557 DEB May 19 00:45:30.301540 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_start_ack_timer: ccb->index=319 ack_timer_index=118 9558 DEB May 19 00:45:30.301628 (630-797) JAVA-SIPCC-MSG_SEND_REQ: sipSPIBuildRegisterHeaders: Sending REGISTER... 9559 DEB May 19 00:45:30.302145 (630-797) JAVA-edge_send_get: Length of data read from edge_gateway 64 9560 DEB May 19 00:45:30.302220 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: tracking ID: 8841_9dbc6595-0010-5000-a000-2c01b5e58465 9561 DEB May 19 00:45:30.302242 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: local_uuid: 9dbc659500105000a0002c01b5e58465 remote_uuid: 9562 NOT May 19 00:45:30.302295 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP2C01B5E58465 9563 NOT May 19 00:45:30.302328 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: ccb->call_mode = 0, display_name = 9564 DEB May 19 00:45:30.302408 (630-797) JAVA-VCM : vcmIsICESupported : mediaTraversalMode(1)=Standard. 9565 DEB May 19 00:45:30.302541 (630-797) JAVA-SIP : sipTransportCreateSendMessage : sippmh_write() with message size=[1061] 9566 DEB May 19 00:45:30.302709 (630-797) JAVA-SIPCC-SIP_TRANS: sipTransportSendMessage: Sip msg sent handle=<86>,length=<1061>, message= 9567 DEB May 19 00:45:30.303102 (630-797) JAVA-sipio-sent---> REGISTER sip:ru-psc-ucm-s06.rucm.rutgers.edu SIP/2.0^M Via: SIP/2.0/TCP 128.6.194.71:50034;branch=z9hG4bK1033cceb^M From: ;tag=2c01b5e584655d1e222fdd7f-520c091b^M To: ^M Call-ID: 2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71^M Max-Forwards: 70^M Session-ID: 9dbc659500105000a0002c01b5e58465;remote=00000000000000000000000000000000^M Date: Sun, 19 May 2024 04:45:30 GMT^M CSeq: 58771 REGISTER^M User-Agent: Cisco-CP8841/14.1.1^M Contact: ;+sip.instance="";+u.sip!devicename.ccm.cisco.com="SEP2C01B5E58465";+u.sip!model.ccm.cisco.com="683";expires=0;cisco-keep-alive^M Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1^M Content-Length: 0^M Expires: 0^M ^M 9568 DEB May 19 00:45:30.303124 (630-797) JAVA- 9569 DEB May 19 00:45:30.303152 (630-797) JAVA-::End-Of-Sip-Message:: 9570 DEB May 19 00:45:30.303171 (630-797) JAVA-SIPCC-ENTRY: LINE 319/1: sipTransportSendMessage : Stopping reTx timer 9571 DEB May 19 00:45:30.303198 (630-797) JAVA-[[MESSAGE_1.0]]: [SIPCC] --> REGISTER sip:ru-psc-ucm-s06.rucm.rutgers.edu SIP/2.0() --> [10.240.0.23] : 9572 DEB May 19 00:45:30.303412 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:19 9573 DEB May 19 00:45:30.303438 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: Sent:REGISTER sip:***@128.6.194.71 9574 DEB May 19 00:45:30.303481 (630-797) JAVA-SIPCC-SIP_STATE: 319/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_UNREGISTERING ---> SIP_REG_STATE_UNREGISTERING 9575 DEB May 19 00:45:30.306115 (630-797) JAVA-SIPCC-HTTPISH: httpish_msg_process_network_msg: Content Length 0, Bytes Remaining 392. 9576 DEB May 19 00:45:30.306171 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9577 DEB May 19 00:45:30.306312 (630-797) JAVA-sipio-recv<--- SIP/2.0 100 Trying^M Via: SIP/2.0/TCP 128.6.194.71:50034;branch=z9hG4bK1033cceb^M From: ;tag=2c01b5e584655d1e222fdd7f-520c091b^M To: ^M Date: Sun, 19 May 2024 04:45:31 GMT^M Call-ID: 2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71^M CSeq: 58771 REGISTER^M Content-Length: 0^M ^M 9578 DEB May 19 00:45:30.306344 (630-797) JAVA-::End-Of-Sip-Message:: 9579 DEB May 19 00:45:30.306363 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.23] --> SIP/2.0 100 Trying() --> [SIPCC] : 9580 DEB May 19 00:45:30.306557 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:19 9581 DEB May 19 00:45:30.306581 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 100 Trying Cseq:58771 REGISTER CallId:2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71 9582 DEB May 19 00:45:30.306709 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9583 DEB May 19 00:45:30.306740 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9584 DEB May 19 00:45:30.306951 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71, cseq=58771, cseq_method=REGISTER 9585 DEB May 19 00:45:30.307003 (630-797) JAVA-SIPCC-FUNC_ENTRY: LINE 319/1: ccsip_handle_ev_1xx : SIP_REG_STATE_UNREGISTERING <- SIP(100) 9586 DEB May 19 00:45:30.307081 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9587 DEB May 19 00:45:30.307229 (630-797) JAVA-sipio-recv<--- SIP/2.0 200 OK^M Via: SIP/2.0/TCP 128.6.194.71:50034;branch=z9hG4bK1033cceb^M From: ;tag=2c01b5e584655d1e222fdd7f-520c091b^M To: ;tag=39087664^M Date: Sun, 19 May 2024 04:45:31 GMT^M Call-ID: 2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71^M CSeq: 58771 REGISTER^M Expires: 0^M Content-Length: 0^M ^M 9588 DEB May 19 00:45:30.307261 (630-797) JAVA-::End-Of-Sip-Message:: 9589 DEB May 19 00:45:30.307279 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.23] --> SIP/2.0 200 OK() --> [SIPCC] : 9590 DEB May 19 00:45:30.307443 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:19 9591 DEB May 19 00:45:30.307468 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 200 OK Cseq:58771 REGISTER CallId:2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71 9592 DEB May 19 00:45:30.307598 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9593 DEB May 19 00:45:30.307627 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9594 DEB May 19 00:45:30.307814 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71, cseq=58771, cseq_method=REGISTER 9595 DEB May 19 00:45:30.307967 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=319 ack_timer_index=118 9596 DEB May 19 00:45:30.308065 (630-797) JAVA-SIPCC-SIP_TIMER: 319/1, ccsip_handle_ev_unreg_2xx: Keep alive timer (120 sec) 9597 DEB May 19 00:45:30.308127 (630-797) JAVA-SIPCC-SIP_TIMER: sip_platform_standby_keepalive_timer_start: Timer started for 120000 msecs 9598 DEB May 19 00:45:33.015162 (623-623) sparkd-getStringProperty: read value for device.settings.fullyregistered: 1 9599 DEB May 19 00:45:33.015240 (623-623) sparkd-[getDeployMode] deploy-mode:1 9600 WRN May 19 00:45:33.015264 (623-623) sparkd-Phone is not in spark call mode - no further toggle checks are required 9601 NOT May 19 00:45:33.015277 (623-623) sparkd-Not ready to init sparkd - retrying in 60 seconds 9602 NOT May 19 00:45:37.832659 (447-492) CDP-cdpRcvPkt(): deviceId is as02-tec-alex 9603 DEB May 19 00:45:40.996620 (0-605) syslog-dbus_manager::Sleeping 60 seconds 9604 ERR May 19 00:45:44.645937 (483-488) DHCP6: -@@@@@ recv netlink msg in ipv4 only mode 9605 NOT May 19 00:45:44.713123 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9606 NOT May 19 00:45:44.713211 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9607 ERR May 19 00:45:44.713238 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9608 ERR May 19 00:45:44.713253 (447-492) CDP-lldpRcvPkt(): parse error: 9 9609 DEB May 19 00:45:49.764794 (156-14992) restart_mgr-monitor timer fires, check process status. 9610 NOT May 19 00:45:49.779272 cat: can't open '/proc/312/status': No such file or directory 9611 NOT May 19 00:45:49.779976 (156-14992) restart_mgr-start the process monitor timer 9612 DEB May 19 00:46:01.561403 (630-797) JAVA-SIPCC-ENTRY: LINE 201/1: sip_sm_call_cleanup : Cleaning up the call 9613 DEB May 19 00:46:01.561578 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_REGISTERED ---> SIP_REG_STATE_IDLE 9614 DEB May 19 00:46:01.561688 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_register_send_msg: cmd=85=SIP_REG_REQ ndx=201 9615 DEB May 19 00:46:01.561818 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_IDLE ---> SIP_REG_STATE_IDLE 9616 DEB May 19 00:46:01.561894 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=201 ack_timer_index=0 9617 DEB May 19 00:46:01.561915 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_start_ack_timer: ccb->index=201 ack_timer_index=0 9618 DEB May 19 00:46:01.562016 (630-797) JAVA-[getDeployMode] deploy-mode:1 9619 DEB May 19 00:46:01.562062 (630-797) JAVA-SIPCC-SIP_REG: ccsip_is_reg_refresh: Registration Refresh is TRUE 9620 DEB May 19 00:46:01.562089 (630-797) JAVA-SIPCC-MSG_SEND_REQ: sipSPIBuildRegisterHeaders: Sending REGISTER... 9621 DEB May 19 00:46:01.562659 (630-797) JAVA-edge_send_get: Length of data read from edge_gateway 64 9622 DEB May 19 00:46:01.562721 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: tracking ID: 8841_9dbc6595-0010-5000-a000-2c01b5e58465 9623 DEB May 19 00:46:01.562742 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: local_uuid: 9dbc659500105000a0002c01b5e58465 remote_uuid: 9624 NOT May 19 00:46:01.562795 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP2C01B5E58465 9625 NOT May 19 00:46:01.562828 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: ccb->call_mode = 0, display_name = 9626 DEB May 19 00:46:01.562910 (630-797) JAVA-VCM : vcmIsICESupported : mediaTraversalMode(1)=Standard. 9627 DEB May 19 00:46:01.563026 (630-797) JAVA-SIP : sipTransportCreateSendMessage : sippmh_write() with message size=[1037] 9628 DEB May 19 00:46:01.563189 (630-797) JAVA-SIPCC-SIP_TRANS: sipTransportSendMessage: Sip msg sent handle=<81>,length=<1037>, message= 9629 DEB May 19 00:46:01.563569 (630-797) JAVA-sipio-sent---> REGISTER sip:ru-psc-ucm-s05.rucm.rutgers.edu SIP/2.0^M Via: SIP/2.0/TCP 128.6.194.71:50082;branch=z9hG4bK54329a1d^M From: ;tag=2c01b5e58465f06262172be8-6c7edb8a^M To: ^M Call-ID: 2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71^M Max-Forwards: 70^M Session-ID: 9dbc659500105000a0002c01b5e58465;remote=00000000000000000000000000000000^M Date: Sun, 19 May 2024 04:46:01 GMT^M CSeq: 61331 REGISTER^M User-Agent: Cisco-CP8841/14.1.1^M Contact: ;+sip.instance="";+u.sip!devicename.ccm.cisco.com="SEP2C01B5E58465";+u.sip!model.ccm.cisco.com="683"^M Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1^M Content-Length: 0^M Expires: 3600^M ^M 9630 DEB May 19 00:46:01.563605 (630-797) JAVA-::End-Of-Sip-Message:: 9631 DEB May 19 00:46:01.563624 (630-797) JAVA-SIPCC-ENTRY: LINE 201/1: sipTransportSendMessage : Stopping reTx timer 9632 DEB May 19 00:46:01.563651 (630-797) JAVA-[[MESSAGE_1.0]]: [SIPCC] --> REGISTER sip:ru-psc-ucm-s05.rucm.rutgers.edu SIP/2.0() --> [10.240.0.22] : 9633 DEB May 19 00:46:01.563840 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:16 9634 DEB May 19 00:46:01.563866 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: Sent:REGISTER sip:***@128.6.194.71 9635 DEB May 19 00:46:01.563909 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_IDLE ---> SIP_REG_STATE_REGISTERING 9636 DEB May 19 00:46:01.566664 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9637 DEB May 19 00:46:01.566831 (630-797) JAVA-sipio-recv<--- SIP/2.0 100 Trying^M Via: SIP/2.0/TCP 128.6.194.71:50082;branch=z9hG4bK54329a1d^M From: ;tag=2c01b5e58465f06262172be8-6c7edb8a^M To: ^M Date: Sun, 19 May 2024 04:46:02 GMT^M Call-ID: 2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71^M CSeq: 61331 REGISTER^M Content-Length: 0^M ^M 9638 DEB May 19 00:46:01.566865 (630-797) JAVA-::End-Of-Sip-Message:: 9639 DEB May 19 00:46:01.566884 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.22] --> SIP/2.0 100 Trying() --> [SIPCC] : 9640 DEB May 19 00:46:01.567056 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:17 9641 DEB May 19 00:46:01.567080 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 100 Trying Cseq:61331 REGISTER CallId:2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71 9642 DEB May 19 00:46:01.567195 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9643 DEB May 19 00:46:01.567225 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9644 DEB May 19 00:46:01.567437 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71, cseq=61331, cseq_method=REGISTER 9645 DEB May 19 00:46:01.567491 (630-797) JAVA-SIPCC-FUNC_ENTRY: LINE 201/1: ccsip_handle_ev_1xx : SIP_REG_STATE_REGISTERING <- SIP(100) 9646 DEB May 19 00:46:01.567715 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9647 DEB May 19 00:46:01.567979 (630-797) JAVA-sipio-recv<--- SIP/2.0 200 OK^M Via: SIP/2.0/TCP 128.6.194.71:50082;branch=z9hG4bK54329a1d^M From: ;tag=2c01b5e58465f06262172be8-6c7edb8a^M To: ;tag=1928555991^M Date: Sun, 19 May 2024 04:46:02 GMT^M Call-ID: 2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71^M Server: Cisco-CUCM14.0^M CSeq: 61331 REGISTER^M Expires: 120^M Contact: ;+sip.instance="";+u.sip!devicename.ccm.cisco.com="SEP2C01B5E58465";+u.sip!model.ccm.cisco.com="683"^M Supported: X-cisco-srtp-fallback,X-cisco-sis-10.0.0^M Content-Length: 0^M ^M 9648 DEB May 19 00:46:01.568012 (630-797) JAVA-::End-Of-Sip-Message:: 9649 DEB May 19 00:46:01.568030 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.22] --> SIP/2.0 200 OK() --> [SIPCC] : 9650 DEB May 19 00:46:01.568191 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:17 9651 DEB May 19 00:46:01.568213 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 200 OK Cseq:61331 REGISTER CallId:2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71 9652 DEB May 19 00:46:01.568397 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9653 DEB May 19 00:46:01.568434 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9654 DEB May 19 00:46:01.568647 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71, cseq=61331, cseq_method=REGISTER 9655 DEB May 19 00:46:01.568686 (630-797) JAVA-SIPCC-SIP_REG_BULK: 201/0, ccsip_handle_ev_2xx: bulk_reg_status: BULK_REG_SUCCESS, bulk_reg_resp_code: 200 9656 DEB May 19 00:46:01.569042 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=201 ack_timer_index=0 9657 DEB May 19 00:46:01.569193 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_REGISTERING ---> SIP_REG_STATE_REGISTERED 9658 DEB May 19 00:46:01.569323 (630-797) JAVA-SIPCC-SIP_REG_STATE: ccsip_handle_ev_2xx: Bulk_reg=false. Setting Reg state to TRUE for line=1 9659 DEB May 19 00:46:01.569356 (630-797) JAVA-SIPCC-SIP_TIMER: 201/1, ccsip_handle_ev_2xx: Starting expires timer (115 sec) 9660 DEB May 19 00:46:01.569448 (630-797) JAVA-SIPCC-SIP_FALLBACK: sip_regmgr_trigger_fallback_monitor: Looking to trigger fallback if any available 9661 DEB May 19 00:46:01.569472 (630-797) JAVA-SIPCC-SIP_REG: ccsip_set_reg_refresh: Registration Refresh is set to TRUE 9662 NOT May 19 00:46:01.569492 (630-797) JAVA-SIPCC-UI_API: ui_is_server_side_adr: server side application dial rules set = 0 9663 DEB May 19 00:46:01.569648 (630-797) JAVA-[getDeployMode] deploy-mode:1 9664 NOT May 19 00:46:14.440442 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9665 NOT May 19 00:46:14.440529 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9666 ERR May 19 00:46:14.440556 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9667 ERR May 19 00:46:14.440573 (447-492) CDP-lldpRcvPkt(): parse error: 9 9668 DEB May 19 00:46:26.467793 (616-616) meetingService-getStringProperty: read value for device.feature.bgb_native: 0 9669 NOT May 19 00:46:26.467859 (616-616) meetingService-BGB feature toggle is disabled 9670 NOT May 19 00:46:30.631387 (447-492) CDP-cdpRcvPkt(): deviceId is as02-tec-alex 9671 DEB May 19 00:46:33.015418 (623-623) sparkd-getStringProperty: read value for device.settings.fullyregistered: 1 9672 DEB May 19 00:46:33.015499 (623-623) sparkd-[getDeployMode] deploy-mode:1 9673 WRN May 19 00:46:33.015524 (623-623) sparkd-Phone is not in spark call mode - no further toggle checks are required 9674 NOT May 19 00:46:33.015537 (623-623) sparkd-Not ready to init sparkd - retrying in 60 seconds 9675 DEB May 19 00:46:41.005708 (0-605) syslog-dbus_manager::Sleeping 60 seconds 9676 NOT May 19 00:46:44.323385 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9677 NOT May 19 00:46:44.323473 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9678 ERR May 19 00:46:44.323501 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9679 ERR May 19 00:46:44.323517 (447-492) CDP-lldpRcvPkt(): parse error: 9 9680 ERR May 19 00:46:45.398431 (483-488) DHCP6: -@@@@@ recv netlink msg in ipv4 only mode 9681 DEB May 19 00:46:49.780140 (156-15003) restart_mgr-monitor timer fires, check process status. 9682 NOT May 19 00:46:49.794859 cat: can't open '/proc/312/status': No such file or directory 9683 NOT May 19 00:46:49.795563 (156-15003) restart_mgr-start the process monitor timer 9684 NOT May 19 00:47:14.201357 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9685 NOT May 19 00:47:14.201446 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9686 ERR May 19 00:47:14.201474 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9687 ERR May 19 00:47:14.201489 (447-492) CDP-lldpRcvPkt(): parse error: 9 9688 NOT May 19 00:47:19.461882 (447-492) CDP-cdpRcvPkt(): deviceId is as02-tec-alex 9689 DEB May 19 00:47:26.468003 (616-616) meetingService-getStringProperty: read value for device.feature.bgb_native: 0 9690 NOT May 19 00:47:26.468069 (616-616) meetingService-BGB feature toggle is disabled 9691 DEB May 19 00:47:30.410771 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_register_send_msg: cmd=86=SIP_REG_CANCEL ndx=319 9692 DEB May 19 00:47:30.410966 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=319 ack_timer_index=118 9693 DEB May 19 00:47:30.410995 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_start_ack_timer: ccb->index=319 ack_timer_index=118 9694 DEB May 19 00:47:30.411083 (630-797) JAVA-SIPCC-MSG_SEND_REQ: sipSPIBuildRegisterHeaders: Sending REGISTER... 9695 DEB May 19 00:47:30.411598 (630-797) JAVA-edge_send_get: Length of data read from edge_gateway 64 9696 DEB May 19 00:47:30.411677 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: tracking ID: 8841_9dbc6595-0010-5000-a000-2c01b5e58465 9697 DEB May 19 00:47:30.411699 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: local_uuid: 9dbc659500105000a0002c01b5e58465 remote_uuid: 9698 NOT May 19 00:47:30.411751 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP2C01B5E58465 9699 NOT May 19 00:47:30.411783 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: ccb->call_mode = 0, display_name = 9700 DEB May 19 00:47:30.411864 (630-797) JAVA-VCM : vcmIsICESupported : mediaTraversalMode(1)=Standard. 9701 DEB May 19 00:47:30.412001 (630-797) JAVA-SIP : sipTransportCreateSendMessage : sippmh_write() with message size=[1061] 9702 DEB May 19 00:47:30.412169 (630-797) JAVA-SIPCC-SIP_TRANS: sipTransportSendMessage: Sip msg sent handle=<86>,length=<1061>, message= 9703 DEB May 19 00:47:30.412561 (630-797) JAVA-sipio-sent---> REGISTER sip:ru-psc-ucm-s06.rucm.rutgers.edu SIP/2.0^M Via: SIP/2.0/TCP 128.6.194.71:50034;branch=z9hG4bK7068c8cc^M From: ;tag=2c01b5e584655d1e222fdd7f-520c091b^M To: ^M Call-ID: 2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71^M Max-Forwards: 70^M Session-ID: 9dbc659500105000a0002c01b5e58465;remote=00000000000000000000000000000000^M Date: Sun, 19 May 2024 04:47:30 GMT^M CSeq: 58772 REGISTER^M User-Agent: Cisco-CP8841/14.1.1^M Contact: ;+sip.instance="";+u.sip!devicename.ccm.cisco.com="SEP2C01B5E58465";+u.sip!model.ccm.cisco.com="683";expires=0;cisco-keep-alive^M Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1^M Content-Length: 0^M Expires: 0^M ^M 9704 DEB May 19 00:47:30.412581 (630-797) JAVA- 9705 DEB May 19 00:47:30.412608 (630-797) JAVA-::End-Of-Sip-Message:: 9706 DEB May 19 00:47:30.412628 (630-797) JAVA-SIPCC-ENTRY: LINE 319/1: sipTransportSendMessage : Stopping reTx timer 9707 DEB May 19 00:47:30.412655 (630-797) JAVA-[[MESSAGE_1.0]]: [SIPCC] --> REGISTER sip:ru-psc-ucm-s06.rucm.rutgers.edu SIP/2.0() --> [10.240.0.23] : 9708 DEB May 19 00:47:30.412874 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:19 9709 DEB May 19 00:47:30.412900 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: Sent:REGISTER sip:***@128.6.194.71 9710 DEB May 19 00:47:30.412942 (630-797) JAVA-SIPCC-SIP_STATE: 319/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_UNREGISTERING ---> SIP_REG_STATE_UNREGISTERING 9711 DEB May 19 00:47:30.415992 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9712 DEB May 19 00:47:30.416160 (630-797) JAVA-sipio-recv<--- SIP/2.0 100 Trying^M Via: SIP/2.0/TCP 128.6.194.71:50034;branch=z9hG4bK7068c8cc^M From: ;tag=2c01b5e584655d1e222fdd7f-520c091b^M To: ^M Date: Sun, 19 May 2024 04:47:31 GMT^M Call-ID: 2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71^M CSeq: 58772 REGISTER^M Content-Length: 0^M ^M 9713 DEB May 19 00:47:30.416288 (630-797) JAVA-::End-Of-Sip-Message:: 9714 DEB May 19 00:47:30.416317 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.23] --> SIP/2.0 100 Trying() --> [SIPCC] : 9715 DEB May 19 00:47:30.416521 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:19 9716 DEB May 19 00:47:30.416547 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 100 Trying Cseq:58772 REGISTER CallId:2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71 9717 DEB May 19 00:47:30.416674 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9718 DEB May 19 00:47:30.416706 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9719 DEB May 19 00:47:30.416917 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71, cseq=58772, cseq_method=REGISTER 9720 DEB May 19 00:47:30.416971 (630-797) JAVA-SIPCC-FUNC_ENTRY: LINE 319/1: ccsip_handle_ev_1xx : SIP_REG_STATE_UNREGISTERING <- SIP(100) 9721 DEB May 19 00:47:30.417089 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9722 DEB May 19 00:47:30.417239 (630-797) JAVA-sipio-recv<--- SIP/2.0 200 OK^M Via: SIP/2.0/TCP 128.6.194.71:50034;branch=z9hG4bK7068c8cc^M From: ;tag=2c01b5e584655d1e222fdd7f-520c091b^M To: ;tag=962214293^M Date: Sun, 19 May 2024 04:47:31 GMT^M Call-ID: 2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71^M CSeq: 58772 REGISTER^M Expires: 0^M Content-Length: 0^M ^M 9723 DEB May 19 00:47:30.417273 (630-797) JAVA-::End-Of-Sip-Message:: 9724 DEB May 19 00:47:30.417291 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.23] --> SIP/2.0 200 OK() --> [SIPCC] : 9725 DEB May 19 00:47:30.417458 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:19 9726 DEB May 19 00:47:30.417482 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 200 OK Cseq:58772 REGISTER CallId:2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71 9727 DEB May 19 00:47:30.417613 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9728 DEB May 19 00:47:30.417644 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9729 DEB May 19 00:47:30.417841 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503de-64b9425a-352604c8@128.6.194.71, cseq=58772, cseq_method=REGISTER 9730 DEB May 19 00:47:30.417999 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=319 ack_timer_index=118 9731 DEB May 19 00:47:30.418096 (630-797) JAVA-SIPCC-SIP_TIMER: 319/1, ccsip_handle_ev_unreg_2xx: Keep alive timer (120 sec) 9732 DEB May 19 00:47:30.418159 (630-797) JAVA-SIPCC-SIP_TIMER: sip_platform_standby_keepalive_timer_start: Timer started for 120000 msecs 9733 NOT May 19 00:47:31.020252 (630-826) JAVA-HTTP JNI| processRedirect: Redirecting to, /CGI/Java/Serviceability?adapter=device.statistics.device 9734 INF May 19 00:47:31.027466 (630-769) JAVA-MicroServletTask|cip.http.HttpMicroServletRequest:? - IN: getSession() 9735 INF May 19 00:47:31.027676 (630-769) JAVA-MicroServletTask|cip.http.HttpSession:? - IN: HttpSession() 9736 INF May 19 00:47:31.027793 (630-769) JAVA-MicroServletTask|cip.http.HttpSession:? - OUT: HttpSession() 9737 INF May 19 00:47:31.027838 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - IN: insertSession() 9738 INF May 19 00:47:31.027889 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - before session insertion 9739 INF May 19 00:47:31.027925 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - OUT: insertSession() 9740 NOT May 19 00:47:31.142321 (630-754) JAVA-HTTP JNI| Java_cip_http_NativeHttpTask_updateServletRequest: conId=0, state=3, error=0, mimeType=0 9741 NOT May 19 00:47:31.142504 (630-825) JAVA-HTTP JNI| httpServerHandler: conId=0, pushStatus=3 9742 NOT May 19 00:47:31.142542 (630-825) JAVA-HTTP JNI| processFileResponse: Sending, fileName=/tmp/cache/PUSH_RESP_0_215.x to client with additional headers: Set-Cookie: C0=ID=MRyk0pi1fkt74VuoNG7unw==; path=/CGI/Java; HttpOnly; secure;^M 9743 INF May 19 00:47:31.188621 (630-769) JAVA-MicroServletTask|cip.http.HttpMicroServletRequest:? - IN: getSession() 9744 INF May 19 00:47:31.188949 (630-769) JAVA-MicroServletTask|cip.http.HttpSession:? - IN: HttpSession() 9745 INF May 19 00:47:31.189130 (630-769) JAVA-MicroServletTask|cip.http.HttpSession:? - OUT: HttpSession() 9746 INF May 19 00:47:31.189234 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - IN: insertSession() 9747 INF May 19 00:47:31.189348 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - before session insertion 9748 INF May 19 00:47:31.189436 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - OUT: insertSession() 9749 NOT May 19 00:47:31.195978 (630-754) JAVA-HTTP JNI| Java_cip_http_NativeHttpTask_updateServletRequest: conId=0, state=3, error=0, mimeType=0 9750 NOT May 19 00:47:31.196159 (630-827) JAVA-HTTP JNI| httpServerHandler: conId=0, pushStatus=3 9751 NOT May 19 00:47:31.196198 (630-827) JAVA-HTTP JNI| processFileResponse: Sending, fileName=/tmp/cache/PUSH_RESP_0_216.x to client with additional headers: Set-Cookie: C0=ID=qgELIVs6bP2ErhNcwnxiag==; path=/CGI/Java; HttpOnly; secure;^M 9752 INF May 19 00:47:31.212592 (630-769) JAVA-MicroServletTask|cip.http.HttpMicroServletRequest:? - IN: getSession() 9753 INF May 19 00:47:31.212930 (630-769) JAVA-MicroServletTask|cip.http.HttpSession:? - IN: HttpSession() 9754 INF May 19 00:47:31.213086 (630-769) JAVA-MicroServletTask|cip.http.HttpSession:? - OUT: HttpSession() 9755 INF May 19 00:47:31.213180 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - IN: insertSession() 9756 INF May 19 00:47:31.213275 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - before session insertion 9757 INF May 19 00:47:31.213356 (630-769) JAVA-MicroServletTask|MicroServletContainer:? - OUT: insertSession() 9758 NOT May 19 00:47:31.213838 (630-769) JAVA-cprGetEthMib(): <<<<<<<<<, 9759 NOT May 19 00:47:31.213966 (630-769) JAVA-cprGetEthMib(): complet first Get..... 9760 NOT May 19 00:47:31.214108 (630-769) JAVA-cprGetEthMib(): <<<<<<<<<, 9761 NOT May 19 00:47:31.214159 (630-769) JAVA-cprGetEthMib(): complet first Get..... 9762 NOT May 19 00:47:31.214283 (630-769) JAVA-cprGetEthMib(): <<<<<<<<<, 9763 NOT May 19 00:47:31.214325 (630-769) JAVA-cprGetEthMib(): complet first Get..... 9764 NOT May 19 00:47:31.216374 (630-754) JAVA-HTTP JNI| Java_cip_http_NativeHttpTask_updateServletRequest: conId=0, state=3, error=0, mimeType=0 9765 NOT May 19 00:47:31.216551 (630-828) JAVA-HTTP JNI| httpServerHandler: conId=0, pushStatus=3 9766 NOT May 19 00:47:31.216591 (630-828) JAVA-HTTP JNI| processFileResponse: Sending, fileName=/tmp/cache/PUSH_RESP_0_217.x to client with additional headers: Set-Cookie: C0=ID=JsOikivnCa9XTZ81Jzcxrw==; path=/CGI/Java; HttpOnly; secure;^M 9767 DEB May 19 00:47:33.015678 (623-623) sparkd-getStringProperty: read value for device.settings.fullyregistered: 1 9768 DEB May 19 00:47:33.015760 (623-623) sparkd-[getDeployMode] deploy-mode:1 9769 WRN May 19 00:47:33.015784 (623-623) sparkd-Phone is not in spark call mode - no further toggle checks are required 9770 NOT May 19 00:47:33.015797 (623-623) sparkd-Not ready to init sparkd - retrying in 60 seconds 9771 DEB May 19 00:47:41.014742 (0-605) syslog-dbus_manager::Sleeping 60 seconds 9772 NOT May 19 00:47:44.199865 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9773 NOT May 19 00:47:44.199950 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9774 ERR May 19 00:47:44.199979 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9775 ERR May 19 00:47:44.199995 (447-492) CDP-lldpRcvPkt(): parse error: 9 9776 DEB May 19 00:47:49.795727 (156-15016) restart_mgr-monitor timer fires, check process status. 9777 NOT May 19 00:47:49.810261 cat: can't open '/proc/312/status': No such file or directory 9778 NOT May 19 00:47:49.810953 (156-15016) restart_mgr-start the process monitor timer 9779 DEB May 19 00:47:56.662763 (630-797) JAVA-SIPCC-ENTRY: LINE 201/1: sip_sm_call_cleanup : Cleaning up the call 9780 DEB May 19 00:47:56.662941 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_REGISTERED ---> SIP_REG_STATE_IDLE 9781 DEB May 19 00:47:56.663051 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_register_send_msg: cmd=85=SIP_REG_REQ ndx=201 9782 DEB May 19 00:47:56.663177 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_IDLE ---> SIP_REG_STATE_IDLE 9783 DEB May 19 00:47:56.663250 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=201 ack_timer_index=0 9784 DEB May 19 00:47:56.663272 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_start_ack_timer: ccb->index=201 ack_timer_index=0 9785 DEB May 19 00:47:56.663373 (630-797) JAVA-[getDeployMode] deploy-mode:1 9786 DEB May 19 00:47:56.663419 (630-797) JAVA-SIPCC-SIP_REG: ccsip_is_reg_refresh: Registration Refresh is TRUE 9787 DEB May 19 00:47:56.663445 (630-797) JAVA-SIPCC-MSG_SEND_REQ: sipSPIBuildRegisterHeaders: Sending REGISTER... 9788 DEB May 19 00:47:56.664008 (630-797) JAVA-edge_send_get: Length of data read from edge_gateway 64 9789 DEB May 19 00:47:56.664078 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: tracking ID: 8841_9dbc6595-0010-5000-a000-2c01b5e58465 9790 DEB May 19 00:47:56.664099 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipAddSessionIdHeader: local_uuid: 9dbc659500105000a0002c01b5e58465 remote_uuid: 9791 NOT May 19 00:47:56.664152 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: CFGID_DEVICE_NAME = SEP2C01B5E58465 9792 NOT May 19 00:47:56.664185 (630-797) JAVA-ccsip_messaging: sipSPIAddContactHeader: ccb->call_mode = 0, display_name = 9793 DEB May 19 00:47:56.664265 (630-797) JAVA-VCM : vcmIsICESupported : mediaTraversalMode(1)=Standard. 9794 DEB May 19 00:47:56.664381 (630-797) JAVA-SIP : sipTransportCreateSendMessage : sippmh_write() with message size=[1037] 9795 DEB May 19 00:47:56.664550 (630-797) JAVA-SIPCC-SIP_TRANS: sipTransportSendMessage: Sip msg sent handle=<81>,length=<1037>, message= 9796 DEB May 19 00:47:56.664928 (630-797) JAVA-sipio-sent---> REGISTER sip:ru-psc-ucm-s05.rucm.rutgers.edu SIP/2.0^M Via: SIP/2.0/TCP 128.6.194.71:50082;branch=z9hG4bK12d84f0d^M From: ;tag=2c01b5e58465f06300b269c4-63ec5611^M To: ^M Call-ID: 2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71^M Max-Forwards: 70^M Session-ID: 9dbc659500105000a0002c01b5e58465;remote=00000000000000000000000000000000^M Date: Sun, 19 May 2024 04:47:56 GMT^M CSeq: 61332 REGISTER^M User-Agent: Cisco-CP8841/14.1.1^M Contact: ;+sip.instance="";+u.sip!devicename.ccm.cisco.com="SEP2C01B5E58465";+u.sip!model.ccm.cisco.com="683"^M Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1^M Content-Length: 0^M Expires: 3600^M ^M 9797 DEB May 19 00:47:56.664967 (630-797) JAVA-::End-Of-Sip-Message:: 9798 DEB May 19 00:47:56.664985 (630-797) JAVA-SIPCC-ENTRY: LINE 201/1: sipTransportSendMessage : Stopping reTx timer 9799 DEB May 19 00:47:56.665012 (630-797) JAVA-[[MESSAGE_1.0]]: [SIPCC] --> REGISTER sip:ru-psc-ucm-s05.rucm.rutgers.edu SIP/2.0() --> [10.240.0.22] : 9800 DEB May 19 00:47:56.665215 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:16 9801 DEB May 19 00:47:56.665241 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_send_msg_for_alarm: Sent:REGISTER sip:***@128.6.194.71 9802 DEB May 19 00:47:56.665284 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_IDLE ---> SIP_REG_STATE_REGISTERING 9803 DEB May 19 00:47:56.667849 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9804 DEB May 19 00:47:56.668015 (630-797) JAVA-sipio-recv<--- SIP/2.0 100 Trying^M Via: SIP/2.0/TCP 128.6.194.71:50082;branch=z9hG4bK12d84f0d^M From: ;tag=2c01b5e58465f06300b269c4-63ec5611^M To: ^M Date: Sun, 19 May 2024 04:47:58 GMT^M Call-ID: 2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71^M CSeq: 61332 REGISTER^M Content-Length: 0^M ^M 9805 DEB May 19 00:47:56.668050 (630-797) JAVA-::End-Of-Sip-Message:: 9806 DEB May 19 00:47:56.668070 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.22] --> SIP/2.0 100 Trying() --> [SIPCC] : 9807 DEB May 19 00:47:56.668243 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:17 9808 DEB May 19 00:47:56.668268 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 100 Trying Cseq:61332 REGISTER CallId:2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71 9809 DEB May 19 00:47:56.668493 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9810 DEB May 19 00:47:56.668533 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9811 DEB May 19 00:47:56.668745 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71, cseq=61332, cseq_method=REGISTER 9812 DEB May 19 00:47:56.668799 (630-797) JAVA-SIPCC-FUNC_ENTRY: LINE 201/1: ccsip_handle_ev_1xx : SIP_REG_STATE_REGISTERING <- SIP(100) 9813 DEB May 19 00:47:56.669027 (630-797) JAVA-SIP : sip_tcp_newmsg_to_spi : Sip message rcv: message= 9814 DEB May 19 00:47:56.669287 (630-797) JAVA-sipio-recv<--- SIP/2.0 200 OK^M Via: SIP/2.0/TCP 128.6.194.71:50082;branch=z9hG4bK12d84f0d^M From: ;tag=2c01b5e58465f06300b269c4-63ec5611^M To: ;tag=646369979^M Date: Sun, 19 May 2024 04:47:58 GMT^M Call-ID: 2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71^M Server: Cisco-CUCM14.0^M CSeq: 61332 REGISTER^M Expires: 120^M Contact: ;+sip.instance="";+u.sip!devicename.ccm.cisco.com="SEP2C01B5E58465";+u.sip!model.ccm.cisco.com="683"^M Supported: X-cisco-srtp-fallback,X-cisco-sis-10.0.0^M Content-Length: 0^M ^M 9815 DEB May 19 00:47:56.669321 (630-797) JAVA-::End-Of-Sip-Message:: 9816 DEB May 19 00:47:56.669339 (630-797) JAVA-[[MESSAGE_1.0]]: [10.240.0.22] --> SIP/2.0 200 OK() --> [SIPCC] : 9817 DEB May 19 00:47:56.669490 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: local_uuid: , remote_uuid: , type:1, state:17 9818 DEB May 19 00:47:56.669515 (630-797) JAVA-SIPCC-SIP_MSG_SEND: ccsip_store_rcvd_msg_for_alarm: Rcvd:SIP/2.0 200 OK Cseq:61332 REGISTER CallId:2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71 9819 DEB May 19 00:47:56.669656 (630-797) JAVA-SIPCC-SIP_BRANCH: sip_sm_ccb_match_branch_cseq: Matched branch_id & CSeq 9820 DEB May 19 00:47:56.669689 (630-797) JAVA-SIPCC-SIP_SESSION_ID: sipGetSessionId: Remote Session ID: (null) 9821 DEB May 19 00:47:56.669893 (630-797) JAVA-SIPCC-SIP_RESP: sipSPICheckResponse: Response match: callid=2c01b5e5-846503df-0b9590a0-175379c9@128.6.194.71, cseq=61332, cseq_method=REGISTER 9822 DEB May 19 00:47:56.669931 (630-797) JAVA-SIPCC-SIP_REG_BULK: 201/0, ccsip_handle_ev_2xx: bulk_reg_status: BULK_REG_SUCCESS, bulk_reg_resp_code: 200 9823 DEB May 19 00:47:56.670296 (630-797) JAVA-SIPCC-SIP_STATE: 1/0, sip_stop_ack_timer: ccb->index=201 ack_timer_index=0 9824 DEB May 19 00:47:56.670451 (630-797) JAVA-SIPCC-SIP_STATE: 201/1, sip_reg_sm_change_state: Registration state change: SIP_REG_STATE_REGISTERING ---> SIP_REG_STATE_REGISTERED 9825 DEB May 19 00:47:56.670581 (630-797) JAVA-SIPCC-SIP_REG_STATE: ccsip_handle_ev_2xx: Bulk_reg=false. Setting Reg state to TRUE for line=1 9826 DEB May 19 00:47:56.670615 (630-797) JAVA-SIPCC-SIP_TIMER: 201/1, ccsip_handle_ev_2xx: Starting expires timer (115 sec) 9827 DEB May 19 00:47:56.670708 (630-797) JAVA-SIPCC-SIP_FALLBACK: sip_regmgr_trigger_fallback_monitor: Looking to trigger fallback if any available 9828 DEB May 19 00:47:56.670732 (630-797) JAVA-SIPCC-SIP_REG: ccsip_set_reg_refresh: Registration Refresh is set to TRUE 9829 NOT May 19 00:47:56.670752 (630-797) JAVA-SIPCC-UI_API: ui_is_server_side_adr: server side application dial rules set = 0 9830 DEB May 19 00:47:56.670828 (630-797) JAVA-[getDeployMode] deploy-mode:1 9831 NOT May 19 00:48:11.521498 (447-492) CDP-cdpRcvPkt(): deviceId is as02-tec-alex 9832 NOT May 19 00:48:13.930765 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9833 NOT May 19 00:48:13.930854 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9834 ERR May 19 00:48:13.930881 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9835 ERR May 19 00:48:13.930898 (447-492) CDP-lldpRcvPkt(): parse error: 9 9836 DEB May 19 00:48:26.468215 (616-616) meetingService-getStringProperty: read value for device.feature.bgb_native: 0 9837 NOT May 19 00:48:26.468281 (616-616) meetingService-BGB feature toggle is disabled 9838 DEB May 19 00:48:33.015938 (623-623) sparkd-getStringProperty: read value for device.settings.fullyregistered: 1 9839 DEB May 19 00:48:33.016018 (623-623) sparkd-[getDeployMode] deploy-mode:1 9840 WRN May 19 00:48:33.016044 (623-623) sparkd-Phone is not in spark call mode - no further toggle checks are required 9841 NOT May 19 00:48:33.016059 (623-623) sparkd-Not ready to init sparkd - retrying in 60 seconds 9842 DEB May 19 00:48:41.023658 (0-605) syslog-dbus_manager::Sleeping 60 seconds 9843 NOT May 19 00:48:43.840030 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9844 NOT May 19 00:48:43.840121 (447-492) CDP-cprCdpGetPort address: A8:9D:21:CE:31:8A Phyport=0 appPort=0 9845 ERR May 19 00:48:43.840149 (447-492) CDP-lldpRcvParse(): bit count err: length: 424 start+len: 2bbff506 pTLVNextHdr: 2bbff5b8 next-start: 602 9846 ERR May 19 00:48:43.840165 (447-492) CDP-lldpRcvPkt(): parse error: 9 9847 DEB May 19 00:48:49.811115 (156-15027) restart_mgr-monitor timer fires, check process status. 9848 NOT May 19 00:48:49.825644 cat: can't open '/proc/312/status': No such file or directory 9849 NOT May 19 00:48:49.826349 (156-15027) restart_mgr-start the process monitor timer