FreePBX | Register | Issues | Wiki | Portal | Support

Zulu 3 Extension not getting calls consistently - Logfiles included

zulu
Tags: #<Tag:0x00007f749d035ae8>

(Mvogel4949) #1

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":"jmobley@travelbank.com","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":"jmobley@travelbank.com","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":"jmobley@travelbank.com","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) #2

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:bm6564pk@127.0.0.1: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:bm6564pk@127.0.0.1:55374;transport=ws has been deleted
[2019-05-12 16:18:36] VERBOSE[28455] res_pjsip_registrar.c: Added contact 'sip:bm6564pk@127.0.0.1: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:bm6564pk@127.0.0.1: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:bm6564pk@127.0.0.1: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:bm6564pk@127.0.0.1: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:bm6564pk@127.0.0.1:55374;transport=ws is now Unreachable. RTT: 0.000 msec

(Andrew Nagy) #3

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) #4

Done. Thank you


(system) closed #5

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