Connected to Asterisk SVN-branch-1.4-r47581M currently running on ilha1 (pid = 1629)
Verbosity is at least 3
[Nov 13 20:57:06] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=127
[Nov 13 20:57:06] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=127
    -- Executing [5000@default:1] Dial("SIP/xlite-083f3238", "SIP/sjphone") in new stack
    -- Called sjphone
    -- SIP/sjphone-083f7d10 is ringing
[Nov 13 20:57:06] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:06] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
    -- SIP/sjphone-083f7d10 answered SIP/xlite-083f3238
    -- Packet2Packet bridging SIP/xlite-083f3238 and SIP/sjphone-083f7d10
[Nov 13 20:57:10] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:10] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:10] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=127
[Nov 13 20:57:10] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=127
[Nov 13 20:57:16] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:16] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
  == Spawn extension (default, 5000, 1) exited non-zero on 'SIP/xlite-083f3238'
[Nov 13 20:57:16] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
[Nov 13 20:57:16] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
[Nov 13 20:57:21] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:21] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
    -- Executing [4000@default:1] Dial("SIP/snom-083f3238", "SIP/xlite") in new stack
    -- Called xlite
    -- SIP/xlite-083f7d10 is ringing
[Nov 13 20:57:21] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
[Nov 13 20:57:21] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
    -- SIP/xlite-083f7d10 answered SIP/snom-083f3238
    -- Packet2Packet bridging SIP/snom-083f3238 and SIP/xlite-083f7d10
[Nov 13 20:57:26] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
[Nov 13 20:57:26] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
[Nov 13 20:57:26] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:26] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
  == Spawn extension (default, 4000, 1) exited non-zero on 'SIP/snom-083f3238'
[Nov 13 20:57:29] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
[Nov 13 20:57:29] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=102
[Nov 13 20:57:29] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:29] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:33] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:33] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
    -- Executing [6000@default:1] Dial("SIP/sjphone-083fcd88", "SIP/snom") in new stack
    -- Called snom
    -- SIP/snom-083f3238 is ringing
[Nov 13 20:57:33] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:33] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
    -- SIP/snom-083f3238 is ringing
    -- SIP/snom-083f3238 is ringing
    -- SIP/snom-083f3238 answered SIP/sjphone-083fcd88
    -- Packet2Packet bridging SIP/sjphone-083fcd88 and SIP/snom-083f3238
[Nov 13 20:57:36] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:36] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:36] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:36] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
  == Spawn extension (default, 6000, 1) exited non-zero on 'SIP/sjphone-083fcd88'
[Nov 13 20:57:47] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:47] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:47] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:47] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:57] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:57:57] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
    -- Executing [5000@default:1] Dial("SIP/snom-083fcd88", "SIP/sjphone") in new stack
    -- Called sjphone
    -- SIP/sjphone-083f3238 is ringing
[Nov 13 20:57:58] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:57:58] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
    -- SIP/sjphone-083f3238 answered SIP/snom-083fcd88
    -- Packet2Packet bridging SIP/snom-083fcd88 and SIP/sjphone-083f3238
[Nov 13 20:58:00] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:58:00] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:58:00] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:58:00] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:58:05] NOTICE[1637]: chan_sip.c:8032 sip_peer_hold: onHold now: 1;  hold arg was: 1
    -- Started music on hold, class 'default', on SIP/sjphone-083f3238
[Nov 13 20:58:05] NOTICE[1635]: chan_sip.c:14945 sip_devicestate: sip_devicestate(snom) returning On Hold call-lim=0, inuse=0, onHold=1,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:58:05] NOTICE[1635]: chan_sip.c:14945 sip_devicestate: sip_devicestate(snom) returning On Hold call-lim=0, inuse=0, onHold=1,  inRinging=0, maxms=2000, lastms=44
[Nov 13 20:58:11] NOTICE[1637]: chan_sip.c:8032 sip_peer_hold: onHold now: 0;  hold arg was: 0
    -- Stopped music on hold on SIP/sjphone-083f3238
[Nov 13 20:58:11] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:11] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
  == Spawn extension (default, 5000, 1) exited non-zero on 'SIP/snom-083fcd88'
[Nov 13 20:58:15] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:58:15] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:58:15] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:15] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:23] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:23] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
    -- Executing [6000@default:1] Dial("SIP/xlite-083fcd88", "SIP/snom") in new stack
    -- Called snom
[Nov 13 20:58:23] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:23] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
    -- SIP/snom-083f3238 is ringing
    -- SIP/snom-083f3238 is ringing
    -- SIP/snom-083f3238 is ringing
    -- SIP/snom-083f3238 answered SIP/xlite-083fcd88
    -- Packet2Packet bridging SIP/xlite-083fcd88 and SIP/snom-083f3238
[Nov 13 20:58:25] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:25] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:25] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:25] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:28] NOTICE[1637]: chan_sip.c:8032 sip_peer_hold: onHold now: 1;  hold arg was: 1
    -- Started music on hold, class 'default', on SIP/snom-083f3238
[Nov 13 20:58:28] NOTICE[1635]: chan_sip.c:14945 sip_devicestate: sip_devicestate(xlite) returning On Hold call-lim=0, inuse=0, onHold=1,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:28] NOTICE[1635]: chan_sip.c:14945 sip_devicestate: sip_devicestate(xlite) returning On Hold call-lim=0, inuse=0, onHold=1,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:31] NOTICE[2019]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 0.0.0.0:0
[Nov 13 20:58:37] NOTICE[2019]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 0.0.0.0:0
[Nov 13 20:58:38] NOTICE[2019]: rtp.c:1213 ast_rtp_read: Unknown RTP codec 126 received from '0.0.0.0'
[Nov 13 20:58:43] NOTICE[2019]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 0.0.0.0:0
[Nov 13 20:58:48] NOTICE[2019]: rtp.c:1213 ast_rtp_read: Unknown RTP codec 126 received from '0.0.0.0'
[Nov 13 20:58:49] NOTICE[2019]: rtp.c:902 ast_rtcp_read: Unknown RTCP packet (pt=207) received from 0.0.0.0:0
[Nov 13 20:58:53] NOTICE[1637]: chan_sip.c:8032 sip_peer_hold: onHold now: 0;  hold arg was: 0
    -- Stopped music on hold on SIP/snom-083f3238
[Nov 13 20:58:53] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:53] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:54] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
[Nov 13 20:58:54] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(sjphone) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=1
  == Spawn extension (default, 6000, 1) exited non-zero on 'SIP/xlite-083fcd88'
[Nov 13 20:58:58] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:58] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(snom) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=15
[Nov 13 20:58:58] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
[Nov 13 20:58:58] NOTICE[1635]: chan_sip.c:14961 sip_devicestate: sip_devicestate(xlite) returning Not in use call-lim=0, inuse=0, onHold=0,  inRinging=0, maxms=2000, lastms=101
ilha1*CLI>