Monster UI - create user and send credentials

[Marcin Muzylo]

Hi
Kazoo and Monster-UI from 3.22-34 , installed on 7 nodes, rabbitmq clustered . I do not get email notification when creating new user under SmartPBX and checked "Send credentials to this user" , but i can get VM emails. I have SMTP config setup under db doc - system_config -> smtp_client.


some log:

Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_util:477 (<0.2459.0>) request has a json payload: {"data":{"caller_id":{"internal":{"name":"m22 m22","number":"1004"}},"presence_id":"1004","email":"m...@ipvoice.pl","priv_level":"user","first_name":"m22","last_name":"m22","username":"m...@ipvoice.pl","password":"sdfjn34jnseklrf","send_email_on_creation":true,"ui_metadata":{"version":"3.22-34","ui":"monster-ui","origin":"voip"}}}

Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|cb_users_v2:621 (<0.2459.0>) password set on doc, updating hashes for m...@ipvoice.pl

Dec 19 02:52:40 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_channel:154 (<0.368.0>) published to notifications(amqp://guest:gu...@11.11.11.11:5672) exchange (routing key notifications.user.new) via <0.1047.0>
Dec 19 02:52:40 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_worker:532 (<0.368.0>) published request with msg id ea702d109e046d2b for <0.2459.0>
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_amqp_channel:154 (<0.367.0>) published to configuration(amqp://guest:gu...@11.11.11.11:5672) exchange (routing key doc_created.account%2F01%2Fd9%2F132322c8b6fba5f3a2ab9713c077.user.5f8c842a3b85d50be1d97a01f17aac90) via <0.1043.0>
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_amqp_worker:610 (<0.367.0>) published message 4e9ddcf9d145f034 for <0.2471.0>

Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|whapps_config:244 (<0.2472.0>) missing category crossbar.devices(default) [<<"provisioning_type">>]: undefined
Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|provisioner_util:755 (<0.2472.0>) using <<"provisioner">> for provisioner_type
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:938 (<0.735.0>) storing {couch_util,<<"system_config">>,<<"provisioner">>} for 3600s
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:940 (<0.735.0>) inserted {couch_util,<<"system_config">>,<<"provisioner">>}
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:942 (<0.735.0>) inserted origin pointers for {couch_util,<<"system_config">>,<<"provisioner">>}
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:944 (<0.735.0>) exec store callbacks
Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|whapps_config:244 (<0.2472.0>) missing category provisioner(default) [<<"provisioning_type">>]: undefined
Dec 19 02:52:41 kz1 2600hz[20439]: |ac7f37a7573746bd|wh_amqp_channel:154 (<0.511.0>) published to nodes(amqp://guest:gu...@11.11.11.11:5672) exchange (routing key ) via <0.1021.0>
Dec 19 02:52:41 kz1 2600hz[20439]: |ac7f37a7573746bd|wh_amqp_worker:610 (<0.511.0>) published message ac7f37a7573746bd for <0.598.0>
Dec 19 02:52:42 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_worker:834 (<0.368.0>) request timeout exceeded for msg id: ea702d109e046d2b and client: <0.2459.0>
Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|cb_users_v2:418 (<0.2459.0>) failed to publish new user notification: timeout

Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_util:1111 (<0.2459.0>) push response content: {"data":{"caller_id":{"internal":{"name":"m22 m22","number":"1004"}},"presence_id":"1004","email":"m...@ipvoice.pl","priv_level":"user","first_name":"m22","last_name":"m22","username":"m...@ipvoice.pl","send_email_on_creation":true,"ui_metadata":{"version":"3.22-34","ui":"monster-ui","origin":"voip"},"call_restriction":{},"contact_list":{},"dial_plan":{},"enabled":true,"hotdesk":{"enabled":false,"keep_logged_in_elsewhere":false,"require_pin":false},"media":{"audio":{"codecs":["PCMU"]},"encryption":{"enforce_security":false,"methods":[]},"video":{"codecs":[]}},"music_on_hold":{},"profile":{},"require_password_update":false,"ringtones":{},"verified":false,"vm_to_email_enabled":true,"id":"5f8c842a3b85d50be1d97a01f17aac90"},"revision":"1-118f06c005efecda832f56af18eb49c2","request_id":"b68d894e315180a698a3001191dcac96","status":"success","auth_token":"c559b6b021062657df120000000"}
Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_resource:155 (<0.2459.0>) PUT request fulfilled in 2117 ms

 


maybe something related with :

|cb_users_v2:418 (<0.2459.0>) failed to publish new user notification: timeout

how i can debug more ?

Thanks
Marcin

[Marcin Muzylo]

notify app and teletype are running and i have queue at rabbitmq:
- teletype_shared_listener with Routing Key : notifications.user.new
- notify_listener without notifications.user.new

so teletype should take care this, how i can debug more? or should i enable teletype notificatons somehow , there is any flag for it ?

W dniu poniedziałek, 19 grudnia 2016 11:17:39 UTC+1 użytkownik Marcin Muzylo napisał:

Hi
Kazoo and Monster-UI from 3.22-34 , installed on 7 nodes, rabbitmq clustered . I do not get email notification when creating new user under SmartPBX and checked "Send credentials to this user" , but i can get VM emails. I have SMTP config setup under db doc - system_config -> smtp_client.


some log:

Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_util:477 (<0.2459.0>) request has a json payload: {"data":{"caller_id":{"internal":{"name":"m22 m22","number":"1004"}},"presence_id":"1004","email":"m2...@ipvoice.pl","priv_level":"user","first_name":"m22","last_name":"m22","username":"m2...@ipvoice.pl","password":"sdfjn34jnseklrf","send_email_on_creation":true,"ui_metadata":{"version":"3.22-34","ui":"monster-ui","origin":"voip"}}}

Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|cb_users_v2:621 (<0.2459.0>) password set on doc, updating hashes for m...@ipvoice.pl

Dec 19 02:52:40 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_channel:154 (<0.368.0>) published to notifications(amqp://guest:gu...@11.11.11.11:5672) exchange (routing key notifications.user.new) via <0.1047.0>
Dec 19 02:52:40 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_worker:532 (<0.368.0>) published request with msg id ea702d109e046d2b for <0.2459.0>
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_amqp_channel:154 (<0.367.0>) published to configuration(amqp://guest:gu...@11.11.11.11:5672) exchange (routing key doc_created.account%2F01%2Fd9%2F132322c8b6fba5f3a2ab9713c077.user.5f8c842a3b85d50be1d97a01f17aac90) via <0.1043.0>
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_amqp_worker:610 (<0.367.0>) published message 4e9ddcf9d145f034 for <0.2471.0>

Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|whapps_config:244 (<0.2472.0>) missing category crossbar.devices(default) [<<"provisioning_type">>]: undefined
Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|provisioner_util:755 (<0.2472.0>) using <<"provisioner">> for provisioner_type
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:938 (<0.735.0>) storing {couch_util,<<"system_config">>,<<"provisioner">>} for 3600s
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:940 (<0.735.0>) inserted {couch_util,<<"system_config">>,<<"provisioner">>}
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:942 (<0.735.0>) inserted origin pointers for {couch_util,<<"system_config">>,<<"provisioner">>}
Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:944 (<0.735.0>) exec store callbacks
Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac

96|whapps_config:244 (<0.2472.0>) missing category provisioner(default) [<<"provisioning_type">>]: undefined
Dec 19 02:52:41 kz1 2600hz[20439]: |ac7f37a7573746bd|wh_amqp_channel:154 (<0.511.0>) published to nodes(amqp://guest:guest@11.11.11.11:5672) exchange (routing key ) via <0.1021.0>
Dec 19 02:52:41 kz1 2600hz[20439]: |ac7f37a7573746bd|wh_amqp_worker:610 (<0.511.0>) published message ac7f37a7573746bd for <0.598.0>
Dec 19 02:52:42 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_worker:834 (<0.368.0>) request timeout exceeded for msg id: ea702d109e046d2b and client: <0.2459.0>
Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|cb_users_v2:418 (<0.2459.0>) failed to publish new user notification: timeout

Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_util:1111 (<0.2459.0>) push response content: {"data":{"caller_id":{"internal":{"name":"m22 m22","number":"1004"}},"presence_id":"1004","email":"m2...@ipvoice.pl","priv_level":"user","first_name":"m22","last_name":"m22","username":"m2...@ipvoice.pl","send_email_on_creation":true,"ui_metadata":{"version":"3.22-34","ui":"monster-ui","origin":"voip"},"call_restriction":{},"contact_list":{},"dial_plan":{},"enabled":true,"hotdesk":{"enabled":false,"keep_logged_in_elsewhere":false,"require_pin":false},"media":{"audio":{"codecs":["PCMU"]},"encryption":{"enforce_security":false,"methods":[]},"video":{"codecs":[]}},"music_on_hold":{},"profile":{},"require_password_update":false,"ringtones":{},"verified":false,"vm_to_email_enabled":true,"id":"5f8c842a3b85d50be1d97a01f17aac90"},"revision":"1-118f06c005efecda832f56af18eb49c2","request_id":"b68d894e315180a698a3001191dcac96","status":"success","auth_token":"c559b6b021062657df120000000"}
Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_resource:155 (<0.2459.0>) PUT request fulfilled in 2117 ms

[James Aimonetti]

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA256

Hi Marcin,

Yes, you will need to track down the teletype logs related to this
request. I believe it should use the request_id from the Crossbar
request in the logging of the template rendering too.

So, make sure teletype is running, and on each node that has it, check
the logs for the request_id to see what it tried.

You can also try reading the SMTP logs for the account's notifications:

https://github.com/2600hz/kazoo/blob/master/applications/crossbar/doc/notifications.md#operations-considerations

This could provide some insight if the failure is with sending the template.

> <https://redmine.ipvoice.pl/issues/935/time_entries/new>

>
>
> maybe something related with :
>
> |cb_users_v2:418 (<0.2459.0>) failed to publish new user notification: timeout
>
>
> how i can debug more ?
>
> Thanks
> Marcin

- --
James Aimonetti

Lead Systems Architect
"If Dialyzer don't care, I don't care"
2600HzPDX | http://2600hz.com
sip:ja...@2600hz.com
tel:415.886.7905
irc:mc_ @ freenode
-----BEGIN PGP SIGNATURE-----

iQEcBAEBCAAGBQJYWBgOAAoJENTKa+JPXCVgJWcIAJfSxapp1V/kRs2iIVAheV+y
KZRMRmbNd1ctyRoDX3VaqSkTjWx+c5+p48EXRixkIEpwur6vIMrITLWzSAjrErDv
+rGClr9ALq/mVX4y87wyYPLunxJyZ8uoE5U6B7AAmSvh5UvfiAz+Ge85au4x/omu
jtyXhSxV9oN04hpfYvAcRhU4rjhCkDob+SiwwBboiBzWR3QuIcqNSCIIAcWQK0g/
YEJ6NSq1v77BYW960u8eax4y+5tAzzxkvXO8cznHMyEEu0QOAW7afI+GBsvlnQt5
vSW55qCqf3L1jX0IKau5CjPMdAv4Qc7Lfu3x4HwBO49QfqZS33SdGFEUvtCvgHw=
=jRIj
-----END PGP SIGNATURE-----

[Marcin Muzylo]

Hi James,

resolved, what i had to change :

- setup



"pvt_notification_preference": "teletype",

on already created doc account

- add same line to system_config ->  accounts so new created account should have this flag by default

{
   "_id": "accounts",

   "default": {
       "allow_subaccounts_for_direct": true,
       "master_account_id": "xxxxxxxxxcfde72559c37dd6a89c2",
       "pvt_notification_preference": "teletype",
       "default_timezone": "America/Los_Angeles"
   },

- also i had problem with smtp_client, there is more info what type of params teletype needs :

https://github.com/2600hz/kazoo/blob/3.22/applications/teletype/src/teletype_util.erl
-spec smtp_options()

- so i added special params for teletype:

 "tls": "never",
"auth": "always",


   "_id": "smtp_client",
   "default": {
       "relay": "some.server.net",
       "username": "no_r...@somedomain.net",
       "password": "nicepass",
       "auth": "always",
       "port": 25,
       "retries": 1,
       "no_mx_lookups": true,
       "tls": "never",
       "use_ssl": false
   },

Thank you for help James , everything is in erlang code and logs :)

W dniu poniedziałek, 19 grudnia 2016 18:25:38 UTC+1 użytkownik James Aimonetti napisał:

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA256

Hi Marcin,

Yes, you will need to track down the teletype logs related to this
request. I believe it should use the request_id from the Crossbar
request in the logging of the template rendering too.

So, make sure teletype is running, and on each node that has it, check
the logs for the request_id to see what it tried.

You can also try reading the SMTP logs for the account's notifications:

https://github.com/2600hz/kazoo/blob/master/applications/crossbar/doc/notifications.md#operations-considerations

This could provide some insight if the failure is with sending the template.

Marcin Muzylo writes:

> Hi
> Kazoo and Monster-UI from 3.22-34 , installed on 7 nodes, rabbitmq
> clustered . I do not get email notification when creating new user under
> SmartPBX and checked "Send credentials to this user" , but i can get VM
> emails. I have SMTP config setup under db doc - system_config ->
> smtp_client.
>
>
> some log:
>
>

> Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_util:477 (<0.2459.0>) request has a json payload: {"data":{"caller_id":{"internal":{"name":"m22 m22","number":"1004"}},"presence_id":"1004","email":"m2...@ipvoice.pl","priv_level":"user","first_name":"m22","last_name":"m22","username":"m2...@ipvoice.pl","password":"sdfjn34jnseklrf","send_email_on_creation":true,"ui_metadata":{"version":"3.22-34","ui":"monster-ui","origin":"voip"}}}

>
> Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|cb_users_v2:621 (<0.2459.0>) password set on doc, updating hashes for m...@ipvoice.pl
>
> Dec 19 02:52:40 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_channel:154 (<0.368.0>) published to notifications(amqp://guest:gu...@11.11.11.11:5672) exchange (routing key notifications.user.new) via <0.1047.0>
> Dec 19 02:52:40 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_worker:532 (<0.368.0>) published request with msg id ea702d109e046d2b for <0.2459.0>
> Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_amqp_channel:154 (<0.367.0>) published to configuration(amqp://guest:gu...@11.11.11.11:5672) exchange (routing key doc_created.account%2F01%2Fd9%2F132322c8b6fba5f3a2ab9713c077.user.5f8c842a3b85d50be1d97a01f17aac90) via <0.1043.0>
> Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_amqp_worker:610 (<0.367.0>) published message 4e9ddcf9d145f034 for <0.2471.0>
>
> Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|whapps_config:244 (<0.2472.0>) missing category crossbar.devices(default) [<<"provisioning_type">>]: undefined
> Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|provisioner_util:755 (<0.2472.0>) using <<"provisioner">> for provisioner_type
> Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:938 (<0.735.0>) storing {couch_util,<<"system_config">>,<<"provisioner">>} for 3600s
> Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:940 (<0.735.0>) inserted {couch_util,<<"system_config">>,<<"provisioner">>}
> Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:942 (<0.735.0>) inserted origin pointers for {couch_util,<<"system_config">>,<<"provisioner">>}
> Dec 19 02:52:40 kz1 2600hz[20371]: |4e9ddcf9d145f034|wh_cache:944 (<0.735.0>) exec store callbacks
> Dec 19 02:52:40 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|whapps_config:244 (<0.2472.0>) missing category provisioner(default) [<<"provisioning_type">>]: undefined

> Dec 19 02:52:41 kz1 2600hz[20439]: |ac7f37a7573746bd|wh_amqp_channel:154 (<0.511.0>) published to nodes(amqp://guest:guest@11.11.11.11:5672) exchange (routing key ) via <0.1021.0>

> Dec 19 02:52:41 kz1 2600hz[20439]: |ac7f37a7573746bd|wh_amqp_worker:610 (<0.511.0>) published message ac7f37a7573746bd for <0.598.0>
> Dec 19 02:52:42 kz1 2600hz[20371]: |ea702d109e046d2b|wh_amqp_worker:834 (<0.368.0>) request timeout exceeded for msg id: ea702d109e046d2b and client: <0.2459.0>
> Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|cb_users_v2:418 (<0.2459.0>) failed to publish new user notification: timeout
>

> Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_util:1111 (<0.2459.0>) push response content: {"data":{"caller_id":{"internal":{"name":"m22 m22","number":"1004"}},"presence_id":"1004","email":"m2...@ipvoice.pl","priv_level":"user","first_name":"m22","last_name":"m22","username":"m2...@ipvoice.pl","send_email_on_creation":true,"ui_metadata":{"version":"3.22-34","ui":"monster-ui","origin":"voip"},"call_restriction":{},"contact_list":{},"dial_plan":{},"enabled":true,"hotdesk":{"enabled":false,"keep_logged_in_elsewhere":false,"require_pin":false},"media":{"audio":{"codecs":["PCMU"]},"encryption":{"enforce_security":false,"methods":[]},"video":{"codecs":[]}},"music_on_hold":{},"profile":{},"require_password_update":false,"ringtones":{},"verified":false,"vm_to_email_enabled":true,"id":"5f8c842a3b85d50be1d97a01f17aac90"},"revision":"1-118f06c005efecda832f56af18eb49c2","request_id":"b68d894e315180a698a3001191dcac96","status":"success","auth_token":"c559b6b021062657df120000000"}

> Dec 19 02:52:42 kz1 2600hz[20371]: |b68d894e315180a698a3001191dcac96|api_resource:155 (<0.2459.0>) PUT request fulfilled in 2117 ms
>
>   <https://redmine.ipvoice.pl/issues/935/time_entries/new>
>
>
> maybe something related with :
>
> |cb_users_v2:418 (<0.2459.0>) failed to publish new user notification: timeout
>
>
> how i can debug more ?
>
> Thanks
> Marcin


- --
James Aimonetti

Lead Systems Architect
"If Dialyzer don't care, I don't care"
2600HzPDX | http://2600hz.com

sip:...@2600hz.com