7962 Freezes if call comes in while checking voicemail

jracca · ‎05-04-2018

Any ideas?

On my system, I have 4 Cisco 7962 phones with sidecars that are in a group with a queue. If any one of the 4 phones mentioned checks voicemail and a call comes in, the phone freezes and you are unable to press any buttons. The only option is to hang up and try to check messages again. All phones are running SCCP42.9-4-2SR3-1S.

Chris Deren · ‎05-04-2018

I ran into similar issue and TAC identified it as:

CSCvg54953

SCCP Phone freezes after call transfer

it is resolved in ES31 which you'll need to obtain from TAC.

Gregory Brunn · ‎05-04-2018

Do you see anything in the phone logs....?

jracca · ‎05-04-2018

The log files are not showing anything obvious that would indicate errors. There are a bunch of NOT and WRN and a few ERR. Not familiar with the log troubleshooting.

Thanks

Gregory Brunn · ‎05-07-2018

Want to post console logs and status messages from the time it is happening…

Really the only time I seen something similar is when I created a loop and of course call signaling was not making it to CUCM to update what the phone should be doing.

jracca · ‎05-07-2018

Console log from when this happen.

1305: NOT 13:19:20.911810 EWCLIENT: [.RefAgent.c:255]old_seq 275950, seq 275976, RANGE 50
1306: NOT 13:19:20.913706 EWCLIENT: [.SvcApi.c:395]broadcast 458 bytes to 255.255.255.255:43440
1307: NOT 13:19:21.263395 DSP: STREAM- DestoryEgressChan- ChanType 1, stream (46782594, 46782594) --> Chan 0
1308: NOT 13:19:21.266757 DSP: Added back for CODEC[0] G.711 direction:0 cost:13 old budget:74
1309: ERR 13:19:21.267589 DSP: released the RTCP resource in the cache.
1310: NOT 13:19:21.267776 DSP: ETHSTAT- (unicast, broadcast, multicast) rx = 3340421, 988787, 341890; tx = 9124959, 29670
1311: NOT 13:19:21.268002 DSP: MIB2- ipInDelivers= 4065039, udpInDG= 3119874, udpOutDG= 8948444, udpNoPort= 741470, udpInErr= 0, icmpInDestUnreach = 282, icmpOutDestUnreach = 172
1312: NOT 13:19:21.273861 DSP: STREAM- DestroyIngressChan- ChanType 1, stream (46782594, 46782594) --> Chan 0
1313: NOT 13:19:21.276177 DSP: Added back for CODEC[0] G.711 direction:1 cost:13 old budget:87
1314: WRN 13:19:21.421574 JVM: Startup Module Loader|cip.mmgr.dt:? - [MediaMgrSM]: Unhandled Event, State = StateOnHook Event = EventSetSpeakerModeOff
1315: WRN 13:19:21.422687 JVM: Startup Module Loader|cip.mmgr.dt:? - [MediaMgrSM]: Unhandled Event, State = StateOnHook Event = EventEndcall
1316: NOT 13:19:21.434373 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
1317: NOT 13:19:21.435367 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
1318: WRN 13:19:30.780815 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1319: WRN 13:19:30.876650 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1320: ERR 13:19:30.879770 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1321: ERR 13:19:30.883837 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1322: ERR 13:19:30.928514 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1323: WRN 13:19:31.041649 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1324: WRN 13:19:43.019416 CDP-D: lldpInetdStatsRsp: port: 0
1325: WRN 13:19:46.659340 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1326: WRN 13:19:46.758692 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1327: ERR 13:19:46.765025 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1328: ERR 13:19:46.765658 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1329: ERR 13:19:46.808446 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1330: WRN 13:19:47.011235 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1331: WRN 13:19:49.506628 CDP-D: lldpInetdStatsRsp: port: 1
1332: NOT 13:19:50.910906 EWCLIENT: [.RefAgent.c:255]old_seq 275950, seq 275977, RANGE 50
1333: NOT 13:19:50.912814 EWCLIENT: [.SvcApi.c:395]broadcast 458 bytes to 255.255.255.255:43440
1334: WRN 13:20:02.609079 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1335: WRN 13:20:02.705244 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1336: ERR 13:20:02.707733 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1337: ERR 13:20:02.708379 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1338: ERR 13:20:02.749398 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1339: WRN 13:20:02.853907 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1340: ERR 13:20:14.020167 JVM: Calling enable listening for incoming http connections
1341: WRN 13:20:18.456313 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1342: WRN 13:20:18.554183 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1343: ERR 13:20:18.556562 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1344: ERR 13:20:18.557192 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1345: ERR 13:20:18.598036 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1346: WRN 13:20:18.714247 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1347: NOT 13:20:20.910941 EWCLIENT: [.RefAgent.c:255]old_seq 275950, seq 275978, RANGE 50
1348: NOT 13:20:20.912903 EWCLIENT: [.SvcApi.c:395]broadcast 458 bytes to 255.255.255.255:43440
1349: WRN 13:20:34.274211 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1350: WRN 13:20:34.376564 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1351: ERR 13:20:34.378983 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1352: ERR 13:20:34.379667 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1353: ERR 13:20:34.418962 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1354: WRN 13:20:34.534418 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1355: WRN 13:20:50.139434 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1356: WRN 13:20:50.238951 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1357: ERR 13:20:50.245367 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1358: ERR 13:20:50.246064 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1359: ERR 13:20:50.288624 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1360: WRN 13:20:50.394883 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1361: NOT 13:20:50.910900 EWCLIENT: [.RefAgent.c:255]old_seq 275950, seq 275979, RANGE 50
1362: NOT 13:20:50.912817 EWCLIENT: [.SvcApi.c:395]broadcast 458 bytes to 255.255.255.255:43440
1363: WRN 13:21:05.949986 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1364: WRN 13:21:06.045161 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1365: ERR 13:21:06.047551 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1366: ERR 13:21:06.048195 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1367: ERR 13:21:06.089114 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1368: WRN 13:21:06.196292 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1369: DBG 13:21:18.956675 cffs_gc(): runing gc 2
1370: DBG 13:21:19.068875 cffs_journal_commit() change journal base 0xc00000
1371: DBG 13:21:19.071184 cffs_journal_create() sec 102
1372: DBG 13:21:19.072904 cffs_journal_commit() new journal base 0xcc0000/hdr:4/data:5
1373: DBG 13:21:19.814598 cffs_gc(): runing gc 2
1374: DBG 13:21:20.369115 cffs_gc(): runing gc 1
1375: NOT 13:21:20.911492 EWCLIENT: [.RefAgent.c:255]old_seq 275950, seq 275980, RANGE 50
1376: NOT 13:21:20.913437 EWCLIENT: [.SvcApi.c:395]broadcast 458 bytes to 255.255.255.255:43440
1377: WRN 13:21:21.869487 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1378: WRN 13:21:21.966965 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1379: ERR 13:21:21.969945 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1380: ERR 13:21:21.973832 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1381: ERR 13:21:22.018329 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1382: WRN 13:21:22.133926 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1383: WRN 13:21:37.729649 JVM: Startup Module Loader|HttpClientTask:? - Current State = 2 this=cip.http.ae@38468c
1384: WRN 13:21:37.823951 JVM: Startup Module Loader|HttpClientTask:? - Current State = 5 this=cip.http.ae@38468c
1385: ERR 13:21:37.826384 JVM: WcAnalyzeUrl: Port = 8082, scheme = eWcSchemeHttp, fHostName =
1386: ERR 13:21:37.827064 JVM: Not TLS <0> or Not DefaultTls Port <8082>
1387: ERR 13:21:37.858451 JVM: Startup Module Loader|cip.http.ae:? - listener.httpSucceed
1388: WRN 13:21:37.973110 JVM: Startup Module Loader|cip.xml.aq:parse - Encoding Updated to UTF-8
1389: WRN 13:21:48.816859 JVM: Startup Module Loader|cip.mmgr.dt:? - [MediaMgrSM]: Unhandled Event, State = StateHeadsetOffHook Event = EventSetSpeakerMode
1390: NOT 13:21:49.044637 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
1391: NOT 13:21:49.095558 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
1392: NOT 13:21:49.096973 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
1393: NOT 13:21:49.098407 JVM: Startup Module Loader|cip.cfg.ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount