Zulu 3 Extension not getting calls consistently - Logfiles included

mvogel4949 · May 14, 2019, 7:09pm

I have an customer with a remote extension 7058 that periodically is not receiving calls from zulu. This happened this past Sunday with only a single of the calls actually ringing the zulu app. The zulu server does appear to be sending push notifications

[2019-5-12 16:26:08.439] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:26 -05:00: e[32m[2019-5-12 16:26:30.397] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:26 -05:00: e[32m[2019-5-12 16:26:52.968] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:27 -05:00: e[32m[2019-5-12 16:27:16.305] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:27 -05:00: e[32m[2019-5-12 16:27:39.238] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:28 -05:00: e[32m[2019-5-12 16:28:02.387] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:28 -05:00: e[32m[2019-5-12 16:28:24.458] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:28 -05:00: e[32m[2019-5-12 16:28:46.477] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:29 -05:00: e[32m[2019-5-12 16:29:09.543] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:29 -05:00: e[32m[2019-5-12 16:29:27.754] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:29 -05:00: e[32m[2019-5-12 16:29:51.431] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:30 -05:00: e[32m[2019-5-12 16:30:13.950] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:30 -05:00: e[32m[2019-5-12 16:30:36.257] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:30 -05:00: e[32m[2019-5-12 16:30:59.298] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:31 -05:00: e[32m[2019-5-12 16:31:21.451] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:31 -05:00: e[32m[2019-5-12 16:31:43.761] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:32 -05:00: e[32m[2019-5-12 16:32:05.766] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:32 -05:00: e[32m[2019-5-12 16:32:28.851] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:32 -05:00: e[32m[2019-5-12 16:32:50.921] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:33 -05:00: e[32m[2019-5-12 16:33:13.589] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:33 -05:00: e[32m[2019-5-12 16:33:35.873] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:33 -05:00: e[32m[2019-5-12 16:33:57.968] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 16:37 -05:00: e[32m[2019-5-12 16:37:58.885] [INFO] console - e[39mKfqLwnD9EWjZak7eACkyTA== has successfully authenticated as user 7058
2019-05-12 16:37 -05:00: e[32m[2019-5-12 16:37:58.886] [INFO] console - e[39mChat connection open {"id":"12","auth":"1","authid":null,"username":"7058","description":"Autogenerated user on new device creation","password":"$2a$08$sRNPL7irtMGdfC9dVY8mQOSEUi8ON8bOoIn3/wjf.eYtB4pE/fDBa","default_extension":"7058","primary_group":null,"permissions":null,"fname":"","lname":"","displayname":"Jackie Mobley","title":"","company":"","department":"","language":null,"timezone":null,"dateformat":null,"timeformat":null,"datetimeformat":null,"email":"[email protected]","cell":"","work":"","home":"","fax":"","xmpp":"7058@localhost"}
2019-05-12 18:07 -05:00: e[32m[2019-5-12 18:07:18.412] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 18:07 -05:00: e[32m[2019-5-12 18:07:18.577] [INFO] console - e[39mCall Event of 'inbound' from Travel BankTOLL FREE CALL &lt;8666828785&gt; to 7058
2019-05-12 18:07 -05:00: e[32m[2019-5-12 18:07:18.718] [INFO] console - e[39mSending Call event message 'inbound' to 7058 [KfqLwnD9EWjZak7eACkyTA==]
2019-05-12 18:07 -05:00: e[32m[2019-5-12 18:07:25.028] [INFO] console - e[39mCall Event of 'answered' from Travel BankTOLL FREE CALL &lt;8666828785&gt; to 7058
2019-05-12 18:07 -05:00: e[32m[2019-5-12 18:07:25.029] [INFO] console - e[39mSending Call event message 'answered' to 7058 [KfqLwnD9EWjZak7eACkyTA==]
2019-05-12 18:11 -05:00: e[32m[2019-5-12 18:11:16.013] [INFO] console - e[39mCall Event of 'hangup' from Travel BankTOLL FREE CALL &lt;8666828785&gt; to 7058
2019-05-12 18:11 -05:00: e[32m[2019-5-12 18:11:16.014] [INFO] console - e[39mSending Call event message 'hangup' to 7058 [KfqLwnD9EWjZak7eACkyTA==]
2019-05-12 19:04 -05:00: e[32m[2019-5-12 19:04:25.386] [INFO] console - e[39mRemoved session from collection {"id":"12","auth":"1","authid":null,"username":"7058","description":"Autogenerated user on new device creation","password":"$2a$08$sRNPL7irtMGdfC9dVY8mQOSEUi8ON8bOoIn3/wjf.eYtB4pE/fDBa","default_extension":"7058","primary_group":null,"permissions":null,"fname":"","lname":"","displayname":"Jackie Mobley","title":"","company":"","department":"","language":null,"timezone":null,"dateformat":null,"timeformat":null,"datetimeformat":null,"email":"[email protected]","cell":"","work":"","home":"","fax":"","xmpp":"7058@localhost"}
2019-05-12 20:47 -05:00: e[32m[2019-5-12 20:47:36.340] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:48 -05:00: e[32m[2019-5-12 20:48:00.920] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:48 -05:00: e[32m[2019-5-12 20:48:22.681] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:48 -05:00: e[32m[2019-5-12 20:48:45.342] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:49 -05:00: e[32m[2019-5-12 20:49:08.484] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:49 -05:00: e[32m[2019-5-12 20:49:31.178] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:49 -05:00: e[32m[2019-5-12 20:49:54.141] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:50 -05:00: e[32m[2019-5-12 20:50:17.463] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:50 -05:00: e[32m[2019-5-12 20:50:40.590] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:51 -05:00: e[32m[2019-5-12 20:51:03.570] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:51 -05:00: e[32m[2019-5-12 20:51:26.304] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:51 -05:00: e[32m[2019-5-12 20:51:48.929] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:52 -05:00: e[32m[2019-5-12 20:52:10.760] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:52 -05:00: e[32m[2019-5-12 20:52:34.166] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:52 -05:00: e[32m[2019-5-12 20:52:56.907] [INFO] console - e[39mSending call push notification to 7058
e[32m[2019-5-12 20:53:19.516] [INFO] console - e[39mSending call push notification to 7058
e[32m[2019-5-12 20:53:43.082] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:54 -05:00: e[32m[2019-5-12 20:54:05.945] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:54 -05:00: e[32m[2019-5-12 20:54:28.554] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:54 -05:00: e[32m[2019-5-12 20:54:51.313] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:55 -05:00: e[32m[2019-5-12 20:55:15.485] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:55 -05:00: e[32m[2019-5-12 20:55:37.171] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:55 -05:00: e[32m[2019-5-12 20:55:59.797] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 20:56 -05:00: e[32m[2019-5-12 20:56:22.717] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:12 -05:00: e[32m[2019-5-12 23:12:03.704] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:12 -05:00: e[32m[2019-5-12 23:12:26.396] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:12 -05:00: e[32m[2019-5-12 23:12:49.410] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:13 -05:00: e[32m[2019-5-12 23:13:14.550] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:13 -05:00: e[32m[2019-5-12 23:13:37.518] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:14 -05:00: e[32m[2019-5-12 23:14:01.362] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:14 -05:00: e[32m[2019-5-12 23:14:25.123] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:14 -05:00: e[32m[2019-5-12 23:14:49.773] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:15 -05:00: e[32m[2019-5-12 23:15:12.626] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:15 -05:00: e[32m[2019-5-12 23:15:37.301] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:16 -05:00: e[32m[2019-5-12 23:16:00.600] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:16 -05:00: e[32m[2019-5-12 23:16:24.119] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:16 -05:00: e[32m[2019-5-12 23:16:48.590] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:17 -05:00: e[32m[2019-5-12 23:17:11.991] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:17 -05:00: e[32m[2019-5-12 23:17:34.821] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:17 -05:00: e[32m[2019-5-12 23:17:58.892] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:18 -05:00: e[32m[2019-5-12 23:18:22.574] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:18 -05:00: e[32m[2019-5-12 23:18:47.019] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:19 -05:00: e[32m[2019-5-12 23:19:10.791] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:20 -05:00: e[32m[2019-5-12 23:20:35.867] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:20 -05:00: e[32m[2019-5-12 23:20:59.456] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:21 -05:00: e[32m[2019-5-12 23:21:22.827] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:21 -05:00: e[32m[2019-5-12 23:21:46.762] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:22 -05:00: e[32m[2019-5-12 23:22:10.281] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:22 -05:00: e[32m[2019-5-12 23:22:33.411] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:22 -05:00: e[32m[2019-5-12 23:22:56.953] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:23 -05:00: e[32m[2019-5-12 23:23:20.721] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:24 -05:00: e[32m[2019-5-12 23:24:40.000] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:25 -05:00: e[32m[2019-5-12 23:25:04.221] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:30 -05:00: e[32m[2019-5-12 23:30:19.966] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:30 -05:00: e[32m[2019-5-12 23:30:43.183] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:31 -05:00: e[32m[2019-5-12 23:31:06.720] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:31 -05:00: e[32m[2019-5-12 23:31:29.667] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:31 -05:00: e[32m[2019-5-12 23:31:53.720] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:32 -05:00: e[32m[2019-5-12 23:32:17.740] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:32 -05:00: e[32m[2019-5-12 23:32:40.423] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:33 -05:00: e[32m[2019-5-12 23:33:04.172] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:33 -05:00: e[32m[2019-5-12 23:33:27.102] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:33 -05:00: e[32m[2019-5-12 23:33:50.748] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:34 -05:00: e[32m[2019-5-12 23:34:13.646] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:34 -05:00: e[32m[2019-5-12 23:34:37.520] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:35 -05:00: e[32m[2019-5-12 23:35:00.732] [INFO] console - e[39mSending call push notification to 7058
2019-05-12 23:35 -05:00: e[32m[2019-5-12 23:35:23.943] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 02:39 -05:00: e[32m[2019-5-13 02:39:29.170] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 04:47 -05:00: e[32m[2019-5-13 04:47:26.574] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 04:47 -05:00: e[32m[2019-5-13 04:47:50.382] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 11:12 -05:00: e[32m[2019-5-13 11:12:41.858] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 11:26 -05:00: e[32m[2019-5-13 11:26:02.148] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 11:27 -05:00: e[32m[2019-5-13 11:27:25.507] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 11:38 -05:00: e[32m[2019-5-13 11:38:05.410] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 11:59 -05:00: e[32m[2019-5-13 11:59:40.187] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 12:39 -05:00: e[32m[2019-5-13 12:39:43.018] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 12:53 -05:00: e[32m[2019-5-13 12:53:48.922] [INFO] console - e[39mMzKOhPm1i+1nvW1RbdOYCg== has successfully authenticated as user 7058
2019-05-13 12:53 -05:00: e[32m[2019-5-13 12:53:48.923] [INFO] console - e[39mChat connection open {"id":"12","auth":"1","authid":null,"username":"7058","description":"Autogenerated user on new device creation","password":"$2a$08$sRNPL7irtMGdfC9dVY8mQOSEUi8ON8bOoIn3/wjf.eYtB4pE/fDBa","default_extension":"7058","primary_group":null,"permissions":null,"fname":"","lname":"","displayname":"Jackie Mobley","title":"","company":"","department":"","language":null,"timezone":null,"dateformat":null,"timeformat":null,"datetimeformat":null,"email":"[email protected]","cell":"","work":"","home":"","fax":"","xmpp":"7058@localhost"}
2019-05-13 14:15 -05:00: e[32m[2019-5-13 14:15:00.645] [INFO] console - e[39mSending call push notification to 7058
2019-05-13 14:15 -05:00: e[32m[2019-5-13 14:15:01.008] [INFO] console - e[39mCall Event of 'inbound' from Travel BankMLADINEO &lt;4403426403&gt; to 7058
2019-05-13 14:15 -05:00: e[32m[2019-5-13 14:15:01.312] [INFO] console - e[39mSending Call event message 'inbound' to 7058 [MzKOhPm1i+1nvW1RbdOYCg==]
e[32m[2019-5-13 14:15:12.272] [INFO] console - e[39mCall Event of 'missed' from Travel BankMLADINEO &lt;4403426403&gt; to 7058
e[32m[2019-5-13 14:15:12.273] [INFO] console - e[39mSending Call event message 'missed' to 7058 [MzKOhPm1i+1nvW1RbdOYCg==]

mvogel4949 · May 14, 2019, 7:45pm

So in the logfiles right before the call came to this extension I found the following:

[2019-05-12 16:04:30] VERBOSE[6999] res_pjsip/pjsip_configuration.c: Contact 907058/sip:[email protected]:55374;transport=ws is now Unreachable. RTT: 0.000 msec
[2019-05-12 16:04:30] VERBOSE[6999] res_pjsip/pjsip_configuration.c: Endpoint 907058 is now Unreachable
[2019-05-12 16:12:23] VERBOSE[6999] res_pjsip/pjsip_configuration.c: Contact 907058/sip:[email protected]:55374;transport=ws has been deleted
[2019-05-12 16:18:36] VERBOSE[28455] res_pjsip_registrar.c: Added contact 'sip:[email protected]:55374;transport=ws' to AOR '907058' with expiration of 600 seconds
[2019-05-12 16:18:36] VERBOSE[17919] res_pjsip/pjsip_configuration.c: Contact 907058/sip:[email protected]:55374;transport=ws has been created
[2019-05-12 16:18:36] VERBOSE[17919] res_pjsip/pjsip_configuration.c: Endpoint 907058 is now Reachable
[2019-05-12 16:18:37] VERBOSE[7291] res_pjsip_registrar.c: Removed contact 'sip:[email protected]:55374;transport=ws' from AOR '907058' due to transport shutdown
[2019-05-12 16:18:37] VERBOSE[17919] res_pjsip/pjsip_configuration.c: Contact 907058/sip:[email protected]:55374;transport=ws has been deleted
[2019-05-12 16:18:37] VERBOSE[17919] res_pjsip/pjsip_configuration.c: Endpoint 907058 is now Unreachable
[2019-05-12 16:18:40] VERBOSE[17919] res_pjsip/pjsip_configuration.c: Contact 907058/sip:[email protected]:55374;transport=ws is now Unreachable. RTT: 0.000 msec

tm1000 · May 14, 2019, 10:15pm

Zulu comes with support since it’s a commercial module. You should go through the support channel. There isn’t anything the support team can do on these forums that wouldn’t be better suited for the real support system (where they can look at your system)

mvogel4949 · May 15, 2019, 2:06am

Done. Thank you

system · May 22, 2019, 2:08am

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.