[asterisk-bugs] [JIRA] (ASTERISK-28166) asterisk crash after changing voicemail password

Thu Nov 15 21:04:47 CST 2018

     [ https://issues.asterisk.org/jira/browse/ASTERISK-28166?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Michael updated ASTERISK-28166:
-------------------------------

    Description: 
i have 2 fresh installs of the latest v16 LTS on debian 9 using pjsip config setup in mysql through odbc driver mysql-connector-odbc-8.0.11.

When I change voicemail password, at the time the new password would get writen into sql table, asterisk stops responding and must be killed -9 and restarted in order to resume normal opteration.

This happens 100% of the time on 2 identical fresh installs.

[Nov 15 21:46:14] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 53 (5), at #.#.#.#:16408
[Nov 15 21:46:14] DEBUG[20748] threadpool.c: Increasing threadpool stasis-core's size by 1
[Nov 15 21:46:14] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 53 (5), at #.#.#.#:16408
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:14] DEBUG[16091][C-00000004] media_cache.c: Failed to obtain media at 'vm-newpassword'
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Nov 15 21:46:14] VERBOSE[16091][C-00000004] file.c: <PJSIP/XXXXX-00000003> Playing 'vm-newpassword.g722' (language 'en')
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (82 requested / 82 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:20] DEBUG[16091][C-00000004] media_cache.c: Failed to obtain media at 'vm-reenterpassword'
[Nov 15 21:46:20] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Difference is 22248, ms is 2801
[Nov 15 21:46:20] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Nov 15 21:46:20] VERBOSE[16091][C-00000004] file.c: <PJSIP/XXXXX-00000003> Playing 'vm-reenterpassword.g722' (language 'en')
[Nov 15 21:46:21] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:21] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_odbc.c: Reusing ODBC handle 0x7f8c50003388 from class 'sqlP'

  was:
i have 2 fresh installs of the latest v16 LTS on debian 9 using pjsip config setup in mysql through odbc driver mysql-connector-odbc-8.0.11.

When I change voicemail password, at the time the new password would get writen into sql table, asterisk stops responding and must be killed -9 and restarted in order to resume normal opteration.

This happens 100% of the time on 2 identical fresh installs.

[Nov 15 21:46:14] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 53 (5), at #.#.#.#:16408
[Nov 15 21:46:14] DEBUG[20748] threadpool.c: Increasing threadpool stasis-core's size by 1
[Nov 15 21:46:14] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 53 (5), at #.#.#.#:16408
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/52502-viapbx-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:14] DEBUG[16091][C-00000004] media_cache.c: Failed to obtain media at 'vm-newpassword'
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/52502-viapbx-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Nov 15 21:46:14] VERBOSE[16091][C-00000004] file.c: <PJSIP/52502-viapbx-00000003> Playing 'vm-newpassword.g722' (language 'en')
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (82 requested / 82 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/52502-viapbx-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:20] DEBUG[16091][C-00000004] media_cache.c: Failed to obtain media at 'vm-reenterpassword'
[Nov 15 21:46:20] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/52502-viapbx-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Difference is 22248, ms is 2801
[Nov 15 21:46:20] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Nov 15 21:46:20] VERBOSE[16091][C-00000004] file.c: <PJSIP/52502-viapbx-00000003> Playing 'vm-reenterpassword.g722' (language 'en')
[Nov 15 21:46:21] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:21] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/52502-viapbx-00000003 setting write format path: g722 -> g722
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 35 (#), at #.#.#.#:16408
[Nov 15 21:46:22] DEBUG[16091][C-00000004] res_odbc.c: Reusing ODBC handle 0x7f8c50003388 from class 'sqlP'

> asterisk crash after changing voicemail password
> ------------------------------------------------
>
>                 Key: ASTERISK-28166
>                 URL: https://issues.asterisk.org/jira/browse/ASTERISK-28166
>             Project: Asterisk
>          Issue Type: Bug
>      Security Level: None
>          Components: Applications/app_voicemail
>    Affects Versions: 16.0.0
>         Environment: debian 9 amd64
> asterisk v16 LTS
> using pjsip
> mysql ODBC
>            Reporter: Michael
>              Labels: pjsip
>
> i have 2 fresh installs of the latest v16 LTS on debian 9 using pjsip config setup in mysql through odbc driver mysql-connector-odbc-8.0.11.
> When I change voicemail password, at the time the new password would get writen into sql table, asterisk stops responding and must be killed -9 and restarted in order to resume normal opteration.
> This happens 100% of the time on 2 identical fresh installs.
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 53 (5), at #.#.#.#:16408
> [Nov 15 21:46:14] DEBUG[20748] threadpool.c: Increasing threadpool stasis-core's size by 1
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 53 (5), at #.#.#.#:16408
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] media_cache.c: Failed to obtain media at 'vm-newpassword'
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
> [Nov 15 21:46:14] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
> [Nov 15 21:46:14] VERBOSE[16091][C-00000004] file.c: <PJSIP/XXXXX-00000003> Playing 'vm-newpassword.g722' (language 'en')
> [Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (82 requested / 82 actual) timer ticks per second
> [Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Nov 15 21:46:18] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
> [Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:19] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 35 (#), at #.#.#.#:16408
> [Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 35 (#), at #.#.#.#:16408
> [Nov 15 21:46:20] DEBUG[16091][C-00000004] media_cache.c: Failed to obtain media at 'vm-reenterpassword'
> [Nov 15 21:46:20] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
> [Nov 15 21:46:20] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Difference is 22248, ms is 2801
> [Nov 15 21:46:20] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
> [Nov 15 21:46:20] VERBOSE[16091][C-00000004] file.c: <PJSIP/XXXXX-00000003> Playing 'vm-reenterpassword.g722' (language 'en')
> [Nov 15 21:46:21] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:21] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Nov 15 21:46:21] DEBUG[16091][C-00000004] channel.c: Channel PJSIP/XXXXX-00000003 setting write format path: g722 -> g722
> [Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 52 (4), at #.#.#.#:16408
> [Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating BEGIN DTMF Frame: 35 (#), at #.#.#.#:16408
> [Nov 15 21:46:22] DEBUG[16091][C-00000004] res_rtp_asterisk.c: Creating END DTMF Frame: 35 (#), at #.#.#.#:16408
> [Nov 15 21:46:22] DEBUG[16091][C-00000004] res_odbc.c: Reusing ODBC handle 0x7f8c50003388 from class 'sqlP'

--
This message was sent by Atlassian JIRA
(v6.2#6252)