# How interpret the verbose message?

**URL:** <https://community.asterisk.org/t/how-interpret-the-verbose-message/47991>\
**Category:** Asterisk General\
**Created:** [January 25, 2015, 6:34pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991 "2015-01-25T18:34:14Z")\
**Posts on this page:** 9\
**Page:** 1

<div class="post-metadata">

**Author:** ![oscaroxy](https://avatars.discourse-cdn.com/v4/letter/o/90ced4/32.png) [@oscaroxy](https://community.asterisk.org/u/oscaroxy)\
**Post date:** [January 25, 2015, 6:34pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/1 "2015-01-25T18:34:14Z")

</div>

I install asterisk on CentOS 6.5, It run and work fine.  
I configure sip.conf add:

[200]  
type=friend  
username=200  
secret=200ab  
qualify=yes  
nat=no  
host=dynamic  
canreinvite=no  
context=oscar

[201]  
type=friend  
username=201  
secret=201ab  
qualify=yes  
nat=no  
host=dynamic  
canreinvite=no  
context=oscar

and in extensions.conf add:  
[oscar]  
exten =\> 200,1,Dial(SIP/200)  
exten =\> 201,1,Dial(SIP/201)

when I call with sip application then asterisk ring, but when I open the call then asterisk close itself

localhost_CLI\>  
[Jan 25 19:16:08] NOTICE[3305]: chan\_sip.c:27554 handle\_request\_subscribe: Received SIP subscribe for peer without mailbox: 201  
== Using SIP RTP CoS mark 5  
– Executing [200@oscar:1] Dial(“SIP/201-00000000”, “SIP/200”) in new stack  
== Using SIP RTP CoS mark 5  
– Called SIP/200  
– SIP/200-00000001 is ringing  
localhost_CLI\>  
Disconnected from Asterisk server  
Asterisk cleanly ending (0).  
Executing last minute cleanups

why? Where can I to see for understand?  
thanks

---

<div class="post-metadata">

**Author:** ![david55](https://avatars.discourse-cdn.com/v4/letter/d/e19adc/32.png) [@david55](https://community.asterisk.org/u/david55)\
**Post date:** [January 25, 2015, 7:47pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/2 "2015-01-25T19:47:38Z")

</div>

There is nothing that is necessarily wrong in that output. More information is needed.

---

<div class="post-metadata">

**Author:** ![oscaroxy](https://avatars.discourse-cdn.com/v4/letter/o/90ced4/32.png) [@oscaroxy](https://community.asterisk.org/u/oscaroxy)\
**Post date:** [January 25, 2015, 7:58pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/3 "2015-01-25T19:58:25Z")

</div>

and now?

Connected to Asterisk 13.1.0 currently running on localhost (pid = 2074)  
– Registered SIP ‘200’ at 192.168.0.2:26310  
[Jan 25 20:55:35] NOTICE[2118]: chan\_sip.c:23798 handle\_response\_peerpoke: Peer ‘200’ is now Reachable. (1ms / 2000ms)  
[Jan 25 20:55:35] NOTICE[2118]: chan\_sip.c:27554 handle\_request\_subscribe: Received SIP subscribe for peer without mailbox: 200  
– Registered SIP ‘201’ at 192.168.0.4:5060  
[Jan 25 20:55:42] NOTICE[2118]: chan\_sip.c:23798 handle\_response\_peerpoke: Peer ‘201’ is now Reachable. (6ms / 2000ms)  
[Jan 25 20:55:42] NOTICE[2118]: chan\_sip.c:27554 handle\_request\_subscribe: Received SIP subscribe for peer without mailbox: 201  
== Using SIP RTP CoS mark 5  
– Executing [200@oscar:1] Dial(“SIP/201-00000000”, “SIP/200”) in new stack  
== Using SIP RTP CoS mark 5  
– Called SIP/200  
– SIP/200-00000001 is ringing  
– SIP/200-00000001 answered SIP/201-00000000  
– Channel SIP/201-00000000 joined ‘simple\_bridge’ basic-bridge \<29e8645e-30be-426c-b98e-09389fc78af8\>  
localhost\*CLI\>  
Disconnected from Asterisk server  
Asterisk cleanly ending (0).  
Executing last minute cleanups

---

<div class="post-metadata">

**Author:** ![david55](https://avatars.discourse-cdn.com/v4/letter/d/e19adc/32.png) [@david55](https://community.asterisk.org/u/david55)\
**Post date:** [January 25, 2015, 8:23pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/4 "2015-01-25T20:23:24Z")

</div>

Nothing wrong there, either. The only odd thing is that a stop now command seems to have been issued, but there is no evidence that one hasn’t.

---

<div class="post-metadata">

**Author:** ![oscaroxy](https://avatars.discourse-cdn.com/v4/letter/o/90ced4/32.png) [@oscaroxy](https://community.asterisk.org/u/oscaroxy)\
**Post date:** [January 26, 2015, 1:48pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/5 "2015-01-26T13:48:19Z")

</div>

I start from begin, I followed the instruction from (installed only last release):

[blogs.digium.com/2012/11/05/how- … -centos-6/](http://blogs.digium.com/2012/11/05/how-to-install-asterisk-11-on-centos-6/)

I installed asterisk on centos 6.5 into virtualbox with 3 net (NAT, only host, bridge) and anything work fine, service dahdi start, service asterisk start… asterisk -rvvv work…

I done for the configuration “make samples” and “make config”.  
Any thing seems ok, a sip phone calls other sip phone, it rings, but when I reply on one phone this is asterisk\>CLI:

(notice: \*\*\* glibc detected \*\*\* /usr/sbin/asterisk: free(): invalid pointer:)

# [code][root@localhost asterisk-13.1.0]# asterisk -rvvv Asterisk 13.1.0, Copyright © 1999 - 2014, Digium, Inc. and others. Created by Mark Spencer [markster@digium.com](mailto:markster@digium.com) Asterisk comes with ABSOLUTELY NO WARRANTY; type ‘core show warranty’ for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type ‘core show license’ for details.

Connected to Asterisk 13.1.0 currently running on localhost (pid = 9944)  
– Registered SIP ‘200’ at 192.168.56.1:37296  
– Registered SIP ‘201’ at 192.168.1.98:5060  
== Using SIP RTP CoS mark 5  
– Executing [200@oscar:1] Dial(“SIP/201-00000000”, “SIP/200,30,Ttm”) in new stack  
== Using SIP RTP CoS mark 5  
– Called SIP/200  
– Started music on hold, class ‘default’, on channel ‘SIP/201-00000000’  
– SIP/200-00000001 is ringing  
– SIP/200-00000001 answered SIP/201-00000000  
– Stopped music on hold on SIP/201-00000000  
– Channel SIP/201-00000000 joined ‘simple\_bridge’ basic-bridge   
– Channel SIP/200-00000001 joined ‘simple\_bridge’ basic-bridge   
localhost\*CLI\> \*\*\* glibc detected \*\*\* /usr/sbin/asterisk: free(): invalid pointer: 0x00007f2894003e98 \*\*\*  
======= Backtrace: =========  
/lib64/libc.so.6[0x33f0c76166]  
/usr/lib64/libjansson.so.4[0x3ddf005efc]  
/usr/lib64/libjansson.so.4(json\_dumps+0x85)[0x3ddf002825]  
/usr/sbin/asterisk(ast\_json\_dump\_string\_format+0x3a)[0x51210a]  
/usr/lib64/asterisk/modules/res\_hep\_rtcp.so(+0xf67)[0x7f28785d7f67]  
/usr/sbin/asterisk[0x593a8a]  
/usr/sbin/asterisk[0x593ee0]  
/usr/sbin/asterisk(ast\_taskprocessor\_execute+0x13e)[0x5a75de]  
/usr/sbin/asterisk[0x5a7f38]  
/usr/sbin/asterisk[0x5ba1bb]  
/lib64/libpthread.so.0[0x33f10079d1]  
/lib64/libc.so.6(clone+0x6d)[0x33f0ce8b7d]  
======= Memory map: ========  
00400000-00650000 r-xp 00000000 fd:00 60568 /usr/sbin/asterisk  
0084f000-00862000 rw-p 0024f000 fd:00 60568 /usr/sbin/asterisk  
00862000-00888000 rw-p 00000000 00:00 0  
00a61000-00a74000 rw-p 00261000 fd:00 60568 /usr/sbin/asterisk  
01a14000-02e4e000 rw-p 00000000 00:00 0 [heap]  
33f0400000-33f0420000 r-xp 00000000 fd:00 50019 /lib64/ld-2.12.so  
33f061f000-33f0620000 r–p 0001f000 fd:00 50019 /lib64/ld-2.12.so  
33f0620000-33f0621000 rw-p 00020000 fd:00 50019 /lib64/ld-2.12.so  
33f0621000-33f0622000 rw-p 00000000 00:00 0  
33f0800000-33f0815000 r-xp 00000000 fd:00 50065 /lib64/libz.so.1.2.3  
33f0815000-33f0a14000 —p 00015000 fd:00 50065 /lib64/libz.so.1.2.3  
33f0a14000-33f0a15000 r–p 00014000 fd:00 50065 /lib64/libz.so.1.2.3  
33f0a15000-33f0a16000 rw-p 00015000 fd:00 50065 /lib64/libz.so.1.2.3  
33f0c00000-33f0d8b000 r-xp 00000000 fd:00 50020 /lib64/libc-2.12.so  
33f0d8b000-33f0f8a000 —p 0018b000 fd:00 50020 /lib64/libc-2.12.so  
33f0f8a000-33f0f8e000 r–p 0018a000 fd:00 50020 /lib64/libc-2.12.so  
33f0f8e000-33f0f8f000 rw-p 0018e000 fd:00 50020 /lib64/libc-2.12.so  
33f0f8f000-33f0f94000 rw-p 00000000 00:00 0  
33f1000000-33f1017000 r-xp 00000000 fd:00 50023 /lib64/libpthread-2.12.so  
33f1017000-33f1217000 —p 00017000 fd:00 50023 /lib64/libpthread-2.12.so  
33f1217000-33f1218000 r–p 00017000 fd:00 50023 /lib64/libpthread-2.12.so  
33f1218000-33f1219000 rw-p 00018000 fd:00 50023 /lib64/libpthread-2.12.so  
33f1219000-33f121d000 rw-p 00000000 00:00 0  
33f1400000-33f1402000 r-xp 00000000 fd:00 50027 /lib64/libdl-2.12.so  
33f1402000-33f1602000 —p 00002000 fd:00 50027 /lib64/libdl-2.12.so  
33f1602000-33f1603000 r–p 00002000 fd:00 50027 /lib64/libdl-2.12.so  
33f1603000-33f1604000 rw-p 00003000 fd:00 50027 /lib64/libdl-2.12.so  
33f1800000-33f1807000 r-xp 00000000 fd:00 50024 /lib64/librt-2.12.so  
33f1807000-33f1a06000 —p 00007000 fd:00 50024 /lib64/librt-2.12.so  
33f1a06000-33f1a07000 r–p 00006000 fd:00 50024 /lib64/librt-2.12.so  
33f1a07000-33f1a08000 rw-p 00007000 fd:00 50024 /lib64/librt-2.12.so  
33f1c00000-33f1c83000 r-xp 00000000 fd:00 50066 /lib64/libm-2.12.so  
33f1c83000-33f1e82000 —p 00083000 fd:00 50066 /lib64/libm-2.12.so  
33f1e82000-33f1e83000 r–p 00082000 fd:00 50066 /lib64/libm-2.12.so  
33f1e83000-33f1e84000 rw-p 00083000 fd:00 50066 /lib64/libm-2.12.so  
33f2000000-33f20e8000 r-xp 00000000 fd:00 6315 /usr/lib64/libstdc++.so.6.0.13  
33f20e8000-33f22e8000 —p 000e8000 fd:00 6315 /usr/lib64/libstdc++.so.6.0.13  
33f22e8000-33f22ef000 r–p 000e8000 fd:00 6315 /usr/lib64/libstdc++.so.6.0.13  
33f22ef000-33f22f1000 rw-p 000ef000 fd:00 6315 /usr/lib64/libstdc++.so.6.0.13  
33f22f1000-33f2306000 rw-p 00000000 00:00 0  
33f2400000-33f241d000 r-xp 00000000 fd:00 7977 /lib64/libtinfo.so.5.7  
33f241d000-33f261d000 —p 0001d000 fd:00 7977 /lib64/libtinfo.so.5.7  
33f261d000-33f2621000 rw-p 0001d000 fd:00 7977 /lib64/libtinfo.so.5.7  
33f2800000-33f2816000 r-xp 00000000 fd:00 3868 /lib64/libgcc\_s-4.4.7-20120601.so.1  
33f2816000-33f2a15000 —p 00016000 fd:00 3868 /lib64/libgcc\_s-4.4.7-20120601.so.1  
33f2a15000-33f2a16000 rw-p 00015000 fd:00 3868 /lib64/libgcc\_s-4.4.7-20120601.so.1  
33f2c00000-33f2c03000 r-xp 00000000 fd:00 50104 /lib64/libcom\_err.so.2.1  
33f2c03000-33f2e02000 —p 00003000 fd:00 50104 /lib64/libcom\_err.so.2.1  
33f2e02000-33f2e03000 r–p 00002000 fd:00 50104 /lib64/libcom\_err.so.2.1  
33f2e03000-33f2e04000 rw-p 00003000 fd:00 50104 /lib64/libcom\_err.so.2.1  
33f3000000-33f3071000 r-xp 00000000 fd:00 50060 /lib64/libfreebl3.so  
33f3071000-33f3270000 —p 00071000 fd:00 50060 /lib64/libfreebl3.so  
33f3270000-33f3272000 r–p 00070000 fd:00 50060 /lib64/libfreebl3.so  
33f3272000-33f3273000 rw-p 00072000 fd:00 50060 /lib64/libfreebl3.so  
33f3273000-33f3277000 rw-p 00000000 00:00 0  
33f3400000-33f3407000 r-xp 00000000 fd:00 50061 /lib64/libcrypt-2.12.so  
33f3407000-33f3607000 —p 00007000 fd:00 50061 /lib64/libcrypt-2.12.so  
33f3607000-33f3608000 r–p 00007000 fd:00 50061 /lib64/libcrypt-2.12.so  
33f3608000-33f3609000 rw-p 00008000 fd:00 50061 /lib64/libcrypt-2.12.so  
33f3609000-33f3637000 rw-p 00000000 00:00 0  
33f3800000-33f3803000 r-xp 00000000 fd:00 7168 /lib64/libgpg-error.so.0.5.0  
33f3803000-33f3a02000 —p 00003000 fd:00 7168 /lib64/libgpg-error.so.0.5.0  
33f3a02000-33f3a03000 r–p 00002000 fd:00 7168 /lib64/libgpg-error.so.0.5.0  
33f3a03000-33f3a04000 rw-p 00003000 fd:00 7168 /lib64/libgpg-error.so.0.5.0  
33f3c00000-33f3c22000 r-xp 00000000 fd:00 12770 /lib64/libncurses.so.5.7  
Disconnected from Asterisk server  
Asterisk cleanly ending (0).  
Executing last minute cleanups  
[root@localhost asterisk-13.1.0]#[/code]

sip.conf:

[code][200]  
type=friend  
secret=200  
qualify=yes  
nat=no  
host=dynamic  
canreinvite=no  
context=oscar  
mailbox=200@miprimerbuzon

[201]  
type=friend  
secret=201  
qualify=yes  
nat=no  
host=dynamic  
canreinvite=no  
context=oscar  
mailbox=201@myfirstvoicemail[/code]

[code][oscar]  
exten =\> 200,1,Dial(SIP/200,30,Ttm)  
exten =\> 200,2,Hangup  
exten =\> 200,102,Voicemail(200)  
exten =\> 200,103,Hangup

exten =\> 201,1,Dial(SIP/201,30,Ttm)  
exten =\> 201,2,Hangup  
exten =\> 201,102,Voicemail(201)  
exten =\> 201,103,Hangup

exten =\> 30000,1,VoicemailMain[/code]

the service asterisk is in execute with another pid, so it restart.  
Why? What do I mistake? Though I set the base configuration and installation…  
thanks thanks thanks

---

<div class="post-metadata">

**Author:** ![oscaroxy](https://avatars.discourse-cdn.com/v4/letter/o/90ced4/32.png) [@oscaroxy](https://community.asterisk.org/u/oscaroxy)\
**Post date:** [January 26, 2015, 2:06pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/6 "2015-01-26T14:06:07Z")

</div>

I added memory to virtualbox from 512Mb to 4Gb the result is:

# [code][root@localhost webuser]# asterisk -rvvv Asterisk 13.1.0, Copyright © 1999 - 2014, Digium, Inc. and others. Created by Mark Spencer [markster@digium.com](mailto:markster@digium.com) Asterisk comes with ABSOLUTELY NO WARRANTY; type ‘core show warranty’ for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type ‘core show license’ for details.

Connected to Asterisk 13.1.0 currently running on localhost (pid = 9265)  
– Registered SIP ‘200’ at 192.168.56.1:7708  
[Jan 26 15:03:06] NOTICE[9346]: chan\_sip.c:23798 handle\_response\_peerpoke: Peer ‘200’ is now Reachable. (1ms / 2000ms)  
– Registered SIP ‘201’ at 192.168.1.98:5060  
[Jan 26 15:03:12] NOTICE[9346]: chan\_sip.c:23798 handle\_response\_peerpoke: Peer ‘201’ is now Reachable. (7ms / 2000ms)  
== Using SIP RTP CoS mark 5  
– Executing [200@oscar:1] Dial(“SIP/201-00000000”, “SIP/200,30,Ttm”) in new stack  
== Using SIP RTP CoS mark 5  
– Called SIP/200  
– Started music on hold, class ‘default’, on channel ‘SIP/201-00000000’  
– SIP/200-00000001 is ringing  
– SIP/200-00000001 answered SIP/201-00000000  
– Stopped music on hold on SIP/201-00000000  
– Channel SIP/201-00000000 joined ‘simple\_bridge’ basic-bridge \<32f7bde5-08ec-4386-aaa7-075a28d11535\>  
– Channel SIP/200-00000001 joined ‘simple\_bridge’ basic-bridge \<32f7bde5-08ec-4386-aaa7-075a28d11535\>  
localhost\*CLI\>  
Disconnected from Asterisk server  
Asterisk cleanly ending (0).  
Executing last minute cleanups[/code]

Where Can I see for the logs?  
thanks

---

<div class="post-metadata">

**Author:** ![david55](https://avatars.discourse-cdn.com/v4/letter/d/e19adc/32.png) [@david55](https://community.asterisk.org/u/david55)\
**Post date:** [January 26, 2015, 3:10pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/7 "2015-01-26T15:10:15Z")

</div>

/var/log/asterisk

---

<div class="post-metadata">

**Author:** ![oscaroxy](https://avatars.discourse-cdn.com/v4/letter/o/90ced4/32.png) [@oscaroxy](https://community.asterisk.org/u/oscaroxy)\
**Post date:** [January 26, 2015, 3:32pm UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/8 "2015-01-26T15:32:40Z")

</div>

into /var/log/asterisk/message I read:

`
[Jan 26 16:27:17] NOTICE[10065] chan_sip.c: Peer '201' is now Reachable. (9ms / 2000ms)
[Jan 26 16:28:24] NOTICE[10065] chan_sip.c: Peer '200' is now Reachable. (1ms / 2000ms)
[Jan 26 16:30:24] Asterisk 13.1.0 built by root @ localhost.localdomain on a x86_64 running Linux on 2015-01-26 08:06:41 UTC
[Jan 26 16:30:25] NOTICE[10140] cdr.c: CDR simple logging enabled.
[Jan 26 16:30:25] NOTICE[10140] loader.c: 254 modules will be loaded.
[Jan 26 16:30:25] NOTICE[10140] res_odbc.c: res_odbc loaded.
[Jan 26 16:30:25] WARNING[10140] res_phoneprov.c: Unable to find a valid server address or name.
[Jan 26 16:30:25] ERROR[10140] ari/config.c: No configured users for ARI
[Jan 26 16:30:25] NOTICE[10140] pbx_lua.c: Lua PBX Switch loaded.
[Jan 26 16:30:25] WARNING[10140] loader.c: Error loading module 'res_ari_mailboxes.so': /usr/lib64/asterisk/modules/res_ari_mailboxes.so: undefined symbol: stasis_app_mailbox_update
[Jan 26 16:30:25] WARNING[10140] loader.c: Module 'res_ari_mailboxes.so' could not be loaded.
[Jan 26 16:30:25] NOTICE[10140] res_config_ldap.c: No directory user found, anonymous binding as default.
[Jan 26 16:30:25] ERROR[10140] res_config_ldap.c: No directory URL or host found.
[Jan 26 16:30:25] ERROR[10140] res_config_ldap.c: Cannot load LDAP RealTime driver.
[Jan 26 16:30:25] ERROR[10140] res_config_pgsql.c: PostgreSQL RealTime: Failed to connect database asterisk on 127.0.0.1:
[Jan 26 16:30:25] WARNING[10140] res_config_pgsql.c: PostgreSQL RealTime: Couldn't establish connection. Check debug.
[Jan 26 16:30:25] NOTICE[10140] chan_skinny.c: Configuring skinny from skinny.conf
[Jan 26 16:30:25] WARNING[10140] chan_dahdi.c: Ignoring any changes to 'userbase' (on reload) at line 23.
[Jan 26 16:30:25] WARNING[10140] chan_dahdi.c: Ignoring any changes to 'vmsecret' (on reload) at line 31.
[Jan 26 16:30:25] WARNING[10140] chan_dahdi.c: Ignoring any changes to 'hassip' (on reload) at line 35.
[Jan 26 16:30:25] WARNING[10140] chan_dahdi.c: Ignoring any changes to 'hasiax' (on reload) at line 39.
[Jan 26 16:30:25] WARNING[10140] chan_dahdi.c: Ignoring any changes to 'hasmanager' (on reload) at line 47.
[Jan 26 16:30:25] NOTICE[10184] chan_sip.c: Peer '201' is now Reachable. (8ms / 2000ms)
[Jan 26 16:30:25] NOTICE[10184] chan_sip.c: Peer '200' is now Reachable. (1ms / 2000ms)
[Jan 26 16:30:25] NOTICE[10140] confbridge/conf_config_parser.c: Adding default_menu menu to app_confbridge
[Jan 26 16:30:25] NOTICE[10140] cdr_pgsql.c: cdr_pgsql configuration contains no global section, skipping module load.
[Jan 26 16:30:25] NOTICE[10140] cel_custom.c: No mappings found in cel_custom.conf. Not logging CEL to custom CSVs.
[Jan 26 16:30:25] WARNING[10140] cel_pgsql.c: CEL pgsql config file missing global section.`

I don’t understand …  
thanks

---

<div class="post-metadata">

**Author:** ![oscaroxy](https://avatars.discourse-cdn.com/v4/letter/o/90ced4/32.png) [@oscaroxy](https://community.asterisk.org/u/oscaroxy)\
**Post date:** [January 30, 2015, 7:51am UTC](https://community.asterisk.org/t/how-interpret-the-verbose-message/47991/9 "2015-01-30T07:51:30Z")

</div>

nothing… I start with ubuntu in virtualbox, follow same steps than centos and then all it’s ok, asterisk work fine.  
bye
