Dismiss Notice
We would like to remind you that we’re updating our login process for all 3CX forums whereby you will be able to login with the same credentials you use for the Partner or Customer Portal. Click here to read more.

Trouble with dialing out

Discussion in '3CX Phone System - General' started by terry@advancedcomm, Feb 5, 2015.

Thread Status:
Not open for further replies.
  1. terry@advancedcomm

    Joined:
    Feb 4, 2015
    Messages:
    4
    Likes Received:
    0
    I have a customer running 3CX on a Patton SNBX server with POTS lines delivered by Comcast through three Patton Sn4114 gateways. They say they have problems making outside calls where they will dial a number and then just hear dead air. When I test while I'm onsite, I never seem to have a problem. Today they called me again and while the 'problem' was occurring I remoted into the system and took a snapshot of the server logs which are pasted below.
    I would really appreciate if anyone could take a look and see if they can give me an idea of what to do to correct this issue. When I'm looking thru the logs I really don't have much of an idea of what I'm looking at and can't seem to find any literature to educate myself.
    Thanks in advance for any insight!!

    05-Feb-2015 13:56:17.887 [CM503027]: Call(C:8717): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10001>>17183663444@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:56:17.887 [CM503004]: Call(C:8717): Route 3: from L:8717.1[Extn] to T:Line:10001>>17183663444@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:56:17.887 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 0; limit is 1
    05-Feb-2015 13:56:17.887 Line limit check: Current # of calls for line Lc:10003(@Colonial1[<sip:10003@10.1.5.48:5066>]) is 0; limit is 1
    05-Feb-2015 13:56:17.887 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 0; limit is 1
    05-Feb-2015 13:56:17.887 Line limit check: Current # of calls for line Lc:10000(@Colonial1[<sip:10000@10.1.5.48:5060>]) is 1; limit is 1
    05-Feb-2015 13:56:17.886 [CM503027]: Call(C:8717): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10006>>17183663444@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:56:17.886 [CM503004]: Call(C:8717): Route 2: from L:8717.1[Extn] to T:Line:10006>>17183663444@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10004(@Colonial2[<sip:10004@10.1.5.49:5060>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10007(@Colonial2[<sip:10007@10.1.5.49:5066>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10005(@Colonial2[<sip:10005@10.1.5.49:5062>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10006(@Colonial2[<sip:10006@10.1.5.49:5064>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 [CM503027]: Call(C:8717): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10011>>17183663444@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:56:17.886 [CM503004]: Call(C:8717): Route 1: from L:8717.1[Extn] to T:Line:10011>>17183663444@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10010(@Colonial3[<sip:10010@10.1.5.50:5064>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10009(@Colonial3[<sip:10009@10.1.5.50:5062>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10008(@Colonial3[<sip:10008@10.1.5.50:5060>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 Line limit check: Current # of calls for line Lc:10011(@Colonial3[<sip:10011@10.1.5.50:5066>]) is 0; limit is 1
    05-Feb-2015 13:56:17.886 Call(C:8717): Call from Extn:3269 to 917183663444 matches outbound rule 'Rule for Colonial1'
    05-Feb-2015 13:56:17.881 [CM503001]: Call(C:8717): Incoming call from Extn:3269 to <sip:917183663444@10.1.5.28:5060>
    05-Feb-2015 13:55:59.570 Currently active calls - 1: [8697]
    05-Feb-2015 13:55:27.568 Currently active calls - 1: [8697]
    05-Feb-2015 13:54:55.566 Currently active calls - 1: [8697]
    05-Feb-2015 13:54:23.564 Currently active calls - 1: [8697]
    05-Feb-2015 13:54:12.200 Leg L:8713.1[Line:10001<<3012231051] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:54:12.200 [CM503008]: Call(C:8713): Call is terminated
    05-Feb-2015 13:54:12.194 Leg L:8713.3[Extn] is terminated: Cause: BYE from 10.1.5.16:5062
    05-Feb-2015 13:53:51.562 Currently active calls - 2: [8697,8713]
    05-Feb-2015 13:53:47.156 [CM503008]: Call(C:8716): Call is terminated
    05-Feb-2015 13:53:47.154 Leg L:8716.1[Extn] is terminated: Cause: CANCEL from 10.1.5.34:5062
    05-Feb-2015 13:53:46.951 Leg L:8716.2[Line:10011>>17183663444] is terminated: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:53:46.951 [CM503025]: Call(C:8716): Calling T:Line:10011>>17183663444@[Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8716.1[Extn]
    05-Feb-2015 13:53:46.936 Call to T:Line:10011>>17183663444@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] from L:8716.1[Extn] failed, cause: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:53:46.935 [CM503003]: Call(C:8716): Call to <sip:17183663444@10.1.5.50:5066> has failed; Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:53:46.775 [CM503025]: Call(C:8716): Calling T:Line:10011>>17183663444@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8716.1[Extn]
    05-Feb-2015 13:53:46.718 [CM503027]: Call(C:8716): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10003>>17183663444@[Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:53:46.718 [CM503004]: Call(C:8716): Route 3: from L:8716.1[Extn] to T:Line:10003>>17183663444@[Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 0; limit is 1
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10003(@Colonial1[<sip:10003@10.1.5.48:5066>]) is 0; limit is 1
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 1; limit is 1
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10000(@Colonial1[<sip:10000@10.1.5.48:5060>]) is 1; limit is 1
    05-Feb-2015 13:53:46.718 [CM503027]: Call(C:8716): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10006>>17183663444@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:53:46.718 [CM503004]: Call(C:8716): Route 2: from L:8716.1[Extn] to T:Line:10006>>17183663444@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10004(@Colonial2[<sip:10004@10.1.5.49:5060>]) is 0; limit is 1
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10007(@Colonial2[<sip:10007@10.1.5.49:5066>]) is 0; limit is 1
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10005(@Colonial2[<sip:10005@10.1.5.49:5062>]) is 0; limit is 1
    05-Feb-2015 13:53:46.718 Line limit check: Current # of calls for line Lc:10006(@Colonial2[<sip:10006@10.1.5.49:5064>]) is 0; limit is 1
    05-Feb-2015 13:53:46.718 [CM503027]: Call(C:8716): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10011>>17183663444@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:53:46.718 [CM503004]: Call(C:8716): Route 1: from L:8716.1[Extn] to T:Line:10011>>17183663444@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:53:46.717 Line limit check: Current # of calls for line Lc:10010(@Colonial3[<sip:10010@10.1.5.50:5064>]) is 0; limit is 1
    05-Feb-2015 13:53:46.717 Line limit check: Current # of calls for line Lc:10009(@Colonial3[<sip:10009@10.1.5.50:5062>]) is 0; limit is 1
    05-Feb-2015 13:53:46.717 Line limit check: Current # of calls for line Lc:10008(@Colonial3[<sip:10008@10.1.5.50:5060>]) is 0; limit is 1
    05-Feb-2015 13:53:46.717 Line limit check: Current # of calls for line Lc:10011(@Colonial3[<sip:10011@10.1.5.50:5066>]) is 0; limit is 1
    05-Feb-2015 13:53:46.717 Call(C:8716): Call from Extn:3269 to 917183663444 matches outbound rule 'Rule for Colonial1'
    05-Feb-2015 13:53:46.712 [CM503001]: Call(C:8716): Incoming call from Extn:3269 to <sip:917183663444@10.1.5.28:5060>
    05-Feb-2015 13:53:19.561 Currently active calls - 2: [8697,8713]
    05-Feb-2015 13:53:15.629 Leg L:8713.2[Extn] is terminated: Cause: BYE from 10.1.6.5:5062
    05-Feb-2015 13:53:15.494 [CM503007]: Call(C:8713): Extn:3244 has joined, contact <sip:3244@10.1.5.16:5062>
    05-Feb-2015 13:53:15.490 L:8713.3[Extn] has joined to L:8713.1[Line:10001<<3012231051]
    05-Feb-2015 13:53:10.576 [MS105000] C:8715.2: No RTP packets were received:remoteAddr=127.0.0.1:40702,extAddr=0.0.0.0:0,localAddr=127.0.0.1:7282
    05-Feb-2015 13:53:09.654 Leg L:8715.2[VMail] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:53:09.565 [CM503008]: Call(C:8715): Call is terminated
    05-Feb-2015 13:53:09.563 Leg L:8715.1[Extn] is terminated: Cause: CANCEL from 10.1.6.5:5062
    05-Feb-2015 13:53:09.513 [CM503025]: Call(C:8715): Calling T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7] for L:8715.1[Extn]
    05-Feb-2015 13:53:09.458 [CM503005]: Call(C:8715): Forwarding: T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7]
    05-Feb-2015 13:53:09.458 L:8715.1[Extn] forwards call from Extn:3244 to VMail:9999 based on rule Fwd[Available/Busy]
    05-Feb-2015 13:53:09.457 L:8715.1[Extn] failed to reach Extn:3244, reason Busy
    05-Feb-2015 13:53:09.457 [CM503015]: Call(C:8715): Destination [<sip:3244@10.1.5.28>] is busy
    05-Feb-2015 13:53:09.451 [CM503001]: Call(C:8715): Incoming call from Extn:3200 to <sip:3244@10.1.5.28:5060>
    05-Feb-2015 13:53:08.713 [CM503025]: Call(C:8713): Calling T:Extn:3244@[Dev:sip:3244@10.1.5.16:5062] for L:8713.1[Line:10001<<3012231051]
    05-Feb-2015 13:53:08.661 [CM503027]: Call(C:8713): From: Line:10001<<3012231051 ("D.M. BOWMAN IN " <sip:3012231051@10.1.5.28:5060>) to T:Extn:3244@[Dev:sip:3244@10.1.5.16:5062]
    05-Feb-2015 13:53:08.661 [CM503004]: Call(C:8713): Route 1: from L:8713.1[Line:10001<<3012231051] to T:Extn:3244@[Dev:sip:3244@10.1.5.16:5062]
    05-Feb-2015 13:53:05.484 Leg L:8714.3[Line:10011>>9403284] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:53:05.483 [CM503008]: Call(C:8714): Call is terminated
    05-Feb-2015 13:53:05.477 Leg L:8714.1[Extn] is terminated: Cause: BYE from 10.1.6.20:5062
    05-Feb-2015 13:52:49.559 Currently active calls - 3: [8697,8713,8714]
    05-Feb-2015 13:52:44.922 [CM503007]: Call(C:8714): Line:10011>>9403284 has joined, contact <sip:10008@10.1.5.50:5060>
    05-Feb-2015 13:52:44.920 [CM503007]: Call(C:8714): Extn:3441 has joined, contact <sip:3441@10.1.6.20:5062>
    05-Feb-2015 13:52:44.915 L:8714.3[Line:10011>>9403284] has joined to L:8714.1[Extn]
    05-Feb-2015 13:52:44.914 [CM505002]: Gateway:[Colonial3] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994DE R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10008@10.1.5.28:5060]
    05-Feb-2015 13:52:44.565 [CM503007]: Call(C:8713): Extn:3200 has joined, contact <sip:3200@10.1.6.5:5062>
    05-Feb-2015 13:52:44.563 [CM503007]: Call(C:8713): Line:10001<<3012231051 has joined, contact <sip:10001@10.1.5.48:5062>
    05-Feb-2015 13:52:44.560 L:8713.2[Extn] has joined to L:8713.1[Line:10001<<3012231051]
    05-Feb-2015 13:52:41.248 Leg L:8714.2[Line:10011>>9403284] is terminated: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:52:41.247 [CM503025]: Call(C:8714): Calling T:Line:10011>>9403284@[Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8714.1[Extn]
    05-Feb-2015 13:52:41.230 Call to T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] from L:8714.1[Extn] failed, cause: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:52:41.229 [CM503003]: Call(C:8714): Call to <sip:9403284@10.1.5.50:5066> has failed; Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:52:41.058 [CM503025]: Call(C:8714): Calling T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8714.1[Extn]
    05-Feb-2015 13:52:41.008 [CM503027]: Call(C:8714): From: Extn:3441 ("Jeanette Fitzerald" <sip:3441@10.1.5.28:5060>) to T:Line:10003>>9403284@[Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:52:41.008 [CM503004]: Call(C:8714): Route 3: from L:8714.1[Extn] to T:Line:10003>>9403284@[Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:52:41.008 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 0; limit is 1
    05-Feb-2015 13:52:41.008 Line limit check: Current # of calls for line Lc:10003(@Colonial1[<sip:10003@10.1.5.48:5066>]) is 0; limit is 1
    05-Feb-2015 13:52:41.008 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 1; limit is 1
    05-Feb-2015 13:52:41.008 Line limit check: Current # of calls for line Lc:10000(@Colonial1[<sip:10000@10.1.5.48:5060>]) is 1; limit is 1
    05-Feb-2015 13:52:41.008 [CM503027]: Call(C:8714): From: Extn:3441 ("Jeanette Fitzerald" <sip:3441@10.1.5.28:5060>) to T:Line:10006>>9403284@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:52:41.008 [CM503004]: Call(C:8714): Route 2: from L:8714.1[Extn] to T:Line:10006>>9403284@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10004(@Colonial2[<sip:10004@10.1.5.49:5060>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10007(@Colonial2[<sip:10007@10.1.5.49:5066>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10005(@Colonial2[<sip:10005@10.1.5.49:5062>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10006(@Colonial2[<sip:10006@10.1.5.49:5064>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 [CM503027]: Call(C:8714): From: Extn:3441 ("Jeanette Fitzerald" <sip:3441@10.1.5.28:5060>) to T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:52:41.007 [CM503004]: Call(C:8714): Route 1: from L:8714.1[Extn] to T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10010(@Colonial3[<sip:10010@10.1.5.50:5064>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10009(@Colonial3[<sip:10009@10.1.5.50:5062>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10008(@Colonial3[<sip:10008@10.1.5.50:5060>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 Line limit check: Current # of calls for line Lc:10011(@Colonial3[<sip:10011@10.1.5.50:5066>]) is 0; limit is 1
    05-Feb-2015 13:52:41.007 Call(C:8714): Call from Extn:3441 to 99403284 matches outbound rule 'Rule for Colonial1'
    05-Feb-2015 13:52:41.002 [CM503001]: Call(C:8714): Incoming call from Extn:3441 to <sip:99403284@10.1.5.28:5060>
    05-Feb-2015 13:52:37.597 Leg L:8711.2[Extn] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:52:37.596 [CM503008]: Call(C:8711): Call is terminated
    05-Feb-2015 13:52:37.591 Leg L:8711.1[Extn] is terminated: Cause: BYE from 10.1.5.16:5062
    05-Feb-2015 13:52:33.792 [CM503025]: Call(C:8713): Calling T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062] for L:8713.1[Line:10001<<3012231051]
    05-Feb-2015 13:52:33.745 [CM503027]: Call(C:8713): From: Line:10001<<3012231051 ("D.M. BOWMAN IN " <sip:3012231051@10.1.5.28:5060>) to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:52:33.745 [CM503004]: Call(C:8713): Route 1: from L:8713.1[Line:10001<<3012231051] to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:52:33.744 [CM505002]: Gateway:[Colonial1] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994F0 R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10001@10.1.5.28:5060]
    05-Feb-2015 13:52:33.740 [CM503001]: Call(C:8713): Incoming call from Line:10001<<3012231051 to <sip:3200@10.1.5.28:5060>
    05-Feb-2015 13:52:33.737 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 1; limit is 1
    05-Feb-2015 13:52:33.725 [CM503012]: Inbound specific hours w/o holidays rule (unnamed) for 10001 forwards to DN:3200
    05-Feb-2015 13:52:31.400 Leg L:8712.3[Line:10011>>9403284] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:52:31.399 [CM503008]: Call(C:8712): Call is terminated
    05-Feb-2015 13:52:31.395 Leg L:8712.1[Extn] is terminated: Cause: BYE from 10.1.6.20:5062
    05-Feb-2015 13:52:17.557 Currently active calls - 3: [8697,8711,8712]
    05-Feb-2015 13:52:16.646 [CM503007]: Call(C:8712): Line:10011>>9403284 has joined, contact <sip:10008@10.1.5.50:5060>
    05-Feb-2015 13:52:16.643 [CM503007]: Call(C:8712): Extn:3441 has joined, contact <sip:3441@10.1.6.20:5062>
    05-Feb-2015 13:52:16.639 L:8712.3[Line:10011>>9403284] has joined to L:8712.1[Extn]
    05-Feb-2015 13:52:16.638 [CM505002]: Gateway:[Colonial3] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994DE R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10008@10.1.5.28:5060]
    05-Feb-2015 13:52:13.063 Leg L:8712.2[Line:10011>>9403284] is terminated: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:52:13.062 [CM503025]: Call(C:8712): Calling T:Line:10011>>9403284@[Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8712.1[Extn]
    05-Feb-2015 13:52:13.048 Call to T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] from L:8712.1[Extn] failed, cause: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:52:13.047 [CM503003]: Call(C:8712): Call to <sip:9403284@10.1.5.50:5066> has failed; Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:52:12.836 [CM503025]: Call(C:8712): Calling T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8712.1[Extn]
    05-Feb-2015 13:52:12.780 [CM503027]: Call(C:8712): From: Extn:3441 ("Jeanette Fitzerald" <sip:3441@10.1.5.28:5060>) to T:Line:10001>>9403284@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:52:12.780 [CM503004]: Call(C:8712): Route 3: from L:8712.1[Extn] to T:Line:10001>>9403284@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10003(@Colonial1[<sip:10003@10.1.5.48:5066>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10000(@Colonial1[<sip:10000@10.1.5.48:5060>]) is 1; limit is 1
    05-Feb-2015 13:52:12.779 [CM503027]: Call(C:8712): From: Extn:3441 ("Jeanette Fitzerald" <sip:3441@10.1.5.28:5060>) to T:Line:10006>>9403284@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:52:12.779 [CM503004]: Call(C:8712): Route 2: from L:8712.1[Extn] to T:Line:10006>>9403284@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10004(@Colonial2[<sip:10004@10.1.5.49:5060>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10007(@Colonial2[<sip:10007@10.1.5.49:5066>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10005(@Colonial2[<sip:10005@10.1.5.49:5062>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10006(@Colonial2[<sip:10006@10.1.5.49:5064>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 [CM503027]: Call(C:8712): From: Extn:3441 ("Jeanette Fitzerald" <sip:3441@10.1.5.28:5060>) to T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:52:12.779 [CM503004]: Call(C:8712): Route 1: from L:8712.1[Extn] to T:Line:10011>>9403284@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10010(@Colonial3[<sip:10010@10.1.5.50:5064>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10009(@Colonial3[<sip:10009@10.1.5.50:5062>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10008(@Colonial3[<sip:10008@10.1.5.50:5060>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Line limit check: Current # of calls for line Lc:10011(@Colonial3[<sip:10011@10.1.5.50:5066>]) is 0; limit is 1
    05-Feb-2015 13:52:12.779 Call(C:8712): Call from Extn:3441 to 99403284 matches outbound rule 'Rule for Colonial1'
    05-Feb-2015 13:52:12.774 [CM503001]: Call(C:8712): Incoming call from Extn:3441 to <sip:99403284@10.1.5.28:5060>
    05-Feb-2015 13:51:45.555 Currently active calls - 2: [8697,8711]
    05-Feb-2015 13:51:15.553 Currently active calls - 2: [8697,8711]
    05-Feb-2015 13:51:01.478 Leg L:8710.3[Line:10011>>17046143771] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:51:01.477 [CM503008]: Call(C:8710): Call is terminated
    05-Feb-2015 13:51:01.471 Leg L:8710.1[Extn] is terminated: Cause: BYE from 10.1.5.34:5062
    05-Feb-2015 13:50:43.552 Currently active calls - 3: [8697,8710,8711]
    05-Feb-2015 13:50:11.550 Currently active calls - 3: [8697,8710,8711]
    05-Feb-2015 13:49:51.342 [CM503007]: Call(C:8711): Extn:3438 has joined, contact <sip:3438@10.1.5.89:5062>
    05-Feb-2015 13:49:51.339 [CM503007]: Call(C:8711): Extn:3244 has joined, contact <sip:3244@10.1.5.16:5062>
    05-Feb-2015 13:49:51.334 L:8711.2[Extn] has joined to L:8711.1[Extn]
    05-Feb-2015 13:49:44.391 [CM503007]: Call(C:8710): Line:10011>>17046143771 has joined, contact <sip:10008@10.1.5.50:5060>
    05-Feb-2015 13:49:44.389 [CM503007]: Call(C:8710): Extn:3269 has joined, contact <sip:3269@10.1.5.34:5062>
    05-Feb-2015 13:49:44.384 L:8710.3[Line:10011>>17046143771] has joined to L:8710.1[Extn]
    05-Feb-2015 13:49:44.383 [CM505002]: Gateway:[Colonial3] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994DE R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10008@10.1.5.28:5060]
    05-Feb-2015 13:49:42.616 [CM503025]: Call(C:8711): Calling T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062] for L:8711.1[Extn]
    05-Feb-2015 13:49:42.554 [CM503027]: Call(C:8711): From: Extn:3244 ("Shipping" <sip:3244@10.1.5.28:5060>) to T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062]
    05-Feb-2015 13:49:42.554 [CM503004]: Call(C:8711): Route 1: from L:8711.1[Extn] to T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062]
    05-Feb-2015 13:49:42.548 [CM503001]: Call(C:8711): Incoming call from Extn:3244 to <sip:3438@10.1.5.28:5060>
    05-Feb-2015 13:49:41.548 Currently active calls - 2: [8697,8710]
    05-Feb-2015 13:49:39.853 Leg L:8710.2[Line:10011>>17046143771] is terminated: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:49:39.853 [CM503025]: Call(C:8710): Calling T:Line:10011>>17046143771@[Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8710.1[Extn]
    05-Feb-2015 13:49:39.842 Call to T:Line:10011>>17046143771@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] from L:8710.1[Extn] failed, cause: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:49:39.841 [CM503003]: Call(C:8710): Call to <sip:17046143771@10.1.5.50:5066> has failed; Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:49:39.168 [CM503025]: Call(C:8710): Calling T:Line:10011>>17046143771@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8710.1[Extn]
    05-Feb-2015 13:49:39.108 [CM503027]: Call(C:8710): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10001>>17046143771@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:49:39.108 [CM503004]: Call(C:8710): Route 3: from L:8710.1[Extn] to T:Line:10001>>17046143771@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10003(@Colonial1[<sip:10003@10.1.5.48:5066>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10000(@Colonial1[<sip:10000@10.1.5.48:5060>]) is 1; limit is 1
    05-Feb-2015 13:49:39.107 [CM503027]: Call(C:8710): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10006>>17046143771@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:49:39.107 [CM503004]: Call(C:8710): Route 2: from L:8710.1[Extn] to T:Line:10006>>17046143771@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10004(@Colonial2[<sip:10004@10.1.5.49:5060>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10007(@Colonial2[<sip:10007@10.1.5.49:5066>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10005(@Colonial2[<sip:10005@10.1.5.49:5062>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10006(@Colonial2[<sip:10006@10.1.5.49:5064>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 [CM503027]: Call(C:8710): From: Extn:3269 ("Kathy Markley" <sip:3269@10.1.5.28:5060>) to T:Line:10011>>17046143771@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:49:39.107 [CM503004]: Call(C:8710): Route 1: from L:8710.1[Extn] to T:Line:10011>>17046143771@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10010(@Colonial3[<sip:10010@10.1.5.50:5064>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10009(@Colonial3[<sip:10009@10.1.5.50:5062>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10008(@Colonial3[<sip:10008@10.1.5.50:5060>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Line limit check: Current # of calls for line Lc:10011(@Colonial3[<sip:10011@10.1.5.50:5066>]) is 0; limit is 1
    05-Feb-2015 13:49:39.107 Call(C:8710): Call from Extn:3269 to 917046143771 matches outbound rule 'Rule for Colonial1'
    05-Feb-2015 13:49:39.102 [CM503001]: Call(C:8710): Incoming call from Extn:3269 to <sip:917046143771@10.1.5.28:5060>
    05-Feb-2015 13:49:27.151 [CM506004]: STUN request to STUN server 192.95.0.175:3478 has timed out; used Transport: 10.1.5.28:5060
    05-Feb-2015 13:49:24.047 [CM506004]: STUN request to STUN server 192.95.0.175:3478 has timed out; used Transport: 10.1.5.28:5060
    05-Feb-2015 13:49:20.942 [CM506004]: STUN request to STUN server 192.95.0.175:3478 has timed out; used Transport: 10.1.5.28:5060
    05-Feb-2015 13:49:17.935 [CM506004]: STUN request to STUN server 192.95.0.175:3478 has timed out; used Transport: 10.1.5.28:5060
    05-Feb-2015 13:49:14.675 [CM506001]: STUN request to resolve SIP external IP:port mapping is sent to STUN server 198.50.247.220:3478 over Transport 10.1.5.28:5060
    05-Feb-2015 13:49:09.546 Currently active calls - 1: [8697]
    05-Feb-2015 13:48:37.544 Currently active calls - 1: [8697]
    05-Feb-2015 13:48:21.371 Leg L:8709.1[Extn] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:48:21.371 [CM503008]: Call(C:8709): Call is terminated
    05-Feb-2015 13:48:21.365 Leg L:8709.2[Extn] is terminated: Cause: BYE from 10.1.5.45:5062
    05-Feb-2015 13:48:11.907 [CM503007]: Call(C:8709): Extn:3235 has joined, contact <sip:3235@10.1.5.45:5062>
    05-Feb-2015 13:48:11.906 [CM503007]: Call(C:8709): Extn:3206 has joined, contact <sip:3206@10.1.5.62:5062>
    05-Feb-2015 13:48:11.902 L:8709.2[Extn] has joined to L:8709.1[Extn]
    05-Feb-2015 13:48:05.543 Currently active calls - 2: [8697,8709]
    05-Feb-2015 13:48:04.060 [CM503025]: Call(C:8709): Calling T:Extn:3235@[Dev:sip:3235@10.1.5.45:5062] for L:8709.1[Extn]
    05-Feb-2015 13:48:04.038 [CM503027]: Call(C:8709): From: Extn:3206 ("Mike Mann" <sip:3206@10.1.5.28:5060>) to T:Extn:3235@[Dev:sip:3235@10.1.5.45:5062]
    05-Feb-2015 13:48:04.038 [CM503004]: Call(C:8709): Route 1: from L:8709.1[Extn] to T:Extn:3235@[Dev:sip:3235@10.1.5.45:5062]
    05-Feb-2015 13:48:04.033 [CM503001]: Call(C:8709): Incoming call from Extn:3206 to <sip:3235@10.1.5.28:5060>
    05-Feb-2015 13:47:33.541 Currently active calls - 1: [8697]
    05-Feb-2015 13:47:01.539 Currently active calls - 1: [8697]
    05-Feb-2015 13:47:00.590 Leg L:8671.2[Extn] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:47:00.589 [CM503008]: Call(C:8671): Call is terminated
    05-Feb-2015 13:47:00.584 Leg L:8671.1[Extn] is terminated: Cause: BYE from 10.1.5.89:5062
    05-Feb-2015 13:46:57.741 Leg L:8706.4[VMail] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:46:57.740 [CM503008]: Call(C:8706): Call is terminated
    05-Feb-2015 13:46:57.736 Leg L:8706.1[Line:10002<<2037442450] is terminated: Cause: BYE from 10.1.5.48:5064
    05-Feb-2015 13:46:36.070 Leg L:8708.3[VMail] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:46:36.069 [CM503008]: Call(C:8708): Call is terminated
    05-Feb-2015 13:46:36.065 Leg L:8708.1[Extn] is terminated: Cause: BYE from 10.1.5.16:5062
    05-Feb-2015 13:46:34.091 [CM503007]: Call(C:8708): VMail:9999 has joined, contact <sip:9999@127.0.0.1:40600>
    05-Feb-2015 13:46:34.088 [CM503007]: Call(C:8708): Extn:3244 has joined, contact <sip:3244@10.1.5.16:5062>
    05-Feb-2015 13:46:34.080 L:8708.3[VMail] has joined to L:8708.1[Extn]
    05-Feb-2015 13:46:34.009 Leg L:8708.2[Extn] is terminated: Cause: 487 Request Terminated/INVITE from 10.1.5.89:5062
    05-Feb-2015 13:46:34.005 [CM503003]: Call(C:8708): Call to <sip:3438@10.1.5.28:5060> has failed; Cause: 487 Request Terminated/INVITE from 10.1.5.89:5062
    05-Feb-2015 13:46:33.995 [CM503025]: Call(C:8708): Calling T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7] for L:8708.1[Extn]
    05-Feb-2015 13:46:33.984 [CM503005]: Call(C:8708): Forwarding: T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7]
    05-Feb-2015 13:46:33.984 L:8708.1[Extn] forwards call from Extn:3438 to VMail:9999 based on rule Fwd[Available/NoAnsw]
    05-Feb-2015 13:46:33.983 L:8708.1[Extn] failed to reach Extn:3438, reason No Answer
    05-Feb-2015 13:46:31.537 Currently active calls - 4: [8671,8697,8706,8708]
    05-Feb-2015 13:46:29.290 [MS105000] C:8706.2: No RTP packets were received:remoteAddr=10.1.6.5:11782,extAddr=0.0.0.0:0,localAddr=10.1.5.28:7232
    05-Feb-2015 13:46:28.837 Leg L:8706.2[Extn] is terminated: Cause: BYE from 10.1.6.5:5062
    05-Feb-2015 13:46:28.707 [CM503007]: Call(C:8706): VMail:9999 has joined, contact <sip:9999@127.0.0.1:40600>
    05-Feb-2015 13:46:28.694 L:8706.4[VMail] has joined to L:8706.1[Line:10002<<2037442450]
    05-Feb-2015 13:46:28.605 Leg L:8706.3[Extn] is terminated: Cause: 487 Request Terminated/INVITE from 10.1.5.34:5062
    05-Feb-2015 13:46:28.602 [CM503003]: Call(C:8706): Call to <sip:3269@10.1.5.28:5060> has failed; Cause: 487 Request Terminated/INVITE from 10.1.5.34:5062
    05-Feb-2015 13:46:28.488 [CM503025]: Call(C:8706): Calling T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7] for L:8706.1[Line:10002<<2037442450]
    05-Feb-2015 13:46:28.480 [CM503005]: Call(C:8706): Forwarding: T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7]
    05-Feb-2015 13:46:28.479 L:8706.1[Line:10002<<2037442450] forwards call from Extn:3269 to VMail:9999 based on rule Fwd[Available/NoAnsw]
    05-Feb-2015 13:46:28.479 L:8706.1[Line:10002<<2037442450] failed to reach Extn:3269, reason No Answer
    05-Feb-2015 13:46:13.958 [CM503025]: Call(C:8708): Calling T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062] for L:8708.1[Extn]
    05-Feb-2015 13:46:13.905 [CM503027]: Call(C:8708): From: Extn:3244 ("Shipping" <sip:3244@10.1.5.28:5060>) to T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062]
    05-Feb-2015 13:46:13.905 [CM503004]: Call(C:8708): Route 1: from L:8708.1[Extn] to T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062]
    05-Feb-2015 13:46:13.901 [CM503001]: Call(C:8708): Incoming call from Extn:3244 to <sip:3438@10.1.5.28:5060>
    05-Feb-2015 13:46:09.944 Leg L:8707.2[Extn] is terminated: Cause: 487 Request Terminated/INVITE from 10.1.5.34:5062
    05-Feb-2015 13:46:09.944 [CM503003]: Call(C:8707): Call to <sip:3269@10.1.5.28:5060> has failed; Cause: 487 Request Terminated/INVITE from 10.1.5.34:5062
    05-Feb-2015 13:46:09.824 [CM503008]: Call(C:8707): Call is terminated
    05-Feb-2015 13:46:09.819 Leg L:8707.1[Extn] is terminated: Cause: CANCEL from 10.1.6.5:5062
    05-Feb-2015 13:46:09.147 [CM503025]: Call(C:8707): Calling T:Extn:3269@[Dev:sip:3269@10.1.5.34:5062] for L:8707.1[Extn]
    05-Feb-2015 13:46:09.095 [CM503027]: Call(C:8707): From: Extn:3200 ("Operator" <sip:3200@10.1.5.28:5060>) to T:Extn:3269@[Dev:sip:3269@10.1.5.34:5062]
    05-Feb-2015 13:46:09.095 [CM503004]: Call(C:8707): Route 1: from L:8707.1[Extn] to T:Extn:3269@[Dev:sip:3269@10.1.5.34:5062]
    05-Feb-2015 13:46:09.091 [CM503001]: Call(C:8707): Incoming call from Extn:3200 to <sip:3269@10.1.5.28:5060>
    05-Feb-2015 13:46:08.449 [CM503025]: Call(C:8706): Calling T:Extn:3269@[Dev:sip:3269@10.1.5.34:5062] for L:8706.1[Line:10002<<2037442450]
    05-Feb-2015 13:46:08.391 [CM503027]: Call(C:8706): From: Line:10002<<2037442450 ("Danbury CT" <sip:2037442450@10.1.5.28:5060>) to T:Extn:3269@[Dev:sip:3269@10.1.5.34:5062]
    05-Feb-2015 13:46:08.391 [CM503004]: Call(C:8706): Route 1: from L:8706.1[Line:10002<<2037442450] to T:Extn:3269@[Dev:sip:3269@10.1.5.34:5062]
    05-Feb-2015 13:46:03.084 Leg L:8704.1[Line:10001<<anonymous] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:46:03.083 [CM503008]: Call(C:8704): Call is terminated
    05-Feb-2015 13:46:03.079 Leg L:8704.3[Extn] is terminated: Cause: BYE from 10.1.6.20:5062
    05-Feb-2015 13:45:59.535 Currently active calls - 4: [8671,8697,8704,8706]
    05-Feb-2015 13:45:58.722 [CM503007]: Call(C:8706): Extn:3200 has joined, contact <sip:3200@10.1.6.5:5062>
    05-Feb-2015 13:45:58.720 [CM503007]: Call(C:8706): Line:10002<<2037442450 has joined, contact <sip:10002@10.1.5.48:5064>
    05-Feb-2015 13:45:58.717 L:8706.2[Extn] has joined to L:8706.1[Line:10002<<2037442450]
    05-Feb-2015 13:45:55.960 [CM503025]: Call(C:8706): Calling T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062] for L:8706.1[Line:10002<<2037442450]
    05-Feb-2015 13:45:55.924 [CM503027]: Call(C:8706): From: Line:10002<<2037442450 ("Danbury CT" <sip:2037442450@10.1.5.28:5060>) to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:45:55.924 [CM503004]: Call(C:8706): Route 1: from L:8706.1[Line:10002<<2037442450] to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:45:55.923 [CM505002]: Gateway:[Colonial1] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994F0 R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10002@10.1.5.28:5060]
    05-Feb-2015 13:45:55.918 [CM503001]: Call(C:8706): Incoming call from Line:10002<<2037442450 to <sip:3200@10.1.5.28:5060>
    05-Feb-2015 13:45:55.915 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 1; limit is 1
    05-Feb-2015 13:45:55.903 [CM503012]: Inbound specific hours w/o holidays rule (unnamed) for 10002 forwards to DN:3200
    05-Feb-2015 13:45:55.514 Leg L:8705.3[Line:10011>>18776601336] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:45:55.513 [CM503008]: Call(C:8705): Call is terminated
    05-Feb-2015 13:45:55.509 Leg L:8705.1[Extn] is terminated: Cause: BYE from 10.1.5.63:5062
    05-Feb-2015 13:45:27.534 Currently active calls - 4: [8671,8697,8704,8705]
    05-Feb-2015 13:45:24.612 Leg L:8704.2[Extn] is terminated: Cause: BYE from 10.1.6.5:5062
    05-Feb-2015 13:45:24.439 [CM503007]: Call(C:8704): Extn:3441 has joined, contact <sip:3441@10.1.6.20:5062>
    05-Feb-2015 13:45:24.435 L:8704.3[Extn] has joined to L:8704.1[Line:10001<<anonymous]
    05-Feb-2015 13:45:15.159 [CM503025]: Call(C:8704): Calling T:Extn:3441@[Dev:sip:3441@10.1.6.20:5062] for L:8704.1[Line:10001<<anonymous]
    05-Feb-2015 13:45:15.101 [CM503027]: Call(C:8704): From: Line:10001<<anonymous (<sip:anonymous@10.1.5.28:5060>) to T:Extn:3441@[Dev:sip:3441@10.1.6.20:5062]
    05-Feb-2015 13:45:15.101 [CM503004]: Call(C:8704): Route 1: from L:8704.1[Line:10001<<anonymous] to T:Extn:3441@[Dev:sip:3441@10.1.6.20:5062]
    05-Feb-2015 13:44:57.532 Currently active calls - 4: [8671,8697,8704,8705]
    05-Feb-2015 13:44:57.260 [CM503007]: Call(C:8705): Line:10011>>18776601336 has joined, contact <sip:10008@10.1.5.50:5060>
    05-Feb-2015 13:44:57.258 [CM503007]: Call(C:8705): Extn:3221 has joined, contact <sip:3221@10.1.5.63:5062>
    05-Feb-2015 13:44:57.252 L:8705.3[Line:10011>>18776601336] has joined to L:8705.1[Extn]
    05-Feb-2015 13:44:57.251 [CM505002]: Gateway:[Colonial3] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994DE R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10008@10.1.5.28:5060]
    05-Feb-2015 13:44:55.781 [CM503007]: Call(C:8704): Extn:3200 has joined, contact <sip:3200@10.1.6.5:5062>
    05-Feb-2015 13:44:55.779 [CM503007]: Call(C:8704): Line:10001<<anonymous has joined, contact <sip:10001@10.1.5.48:5062>
    05-Feb-2015 13:44:55.776 L:8704.2[Extn] has joined to L:8704.1[Line:10001<<anonymous]
    05-Feb-2015 13:44:52.676 Leg L:8705.2[Line:10011>>18776601336] is terminated: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:44:52.676 [CM503025]: Call(C:8705): Calling T:Line:10011>>18776601336@[Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8705.1[Extn]
    05-Feb-2015 13:44:52.657 Call to T:Line:10011>>18776601336@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] from L:8705.1[Extn] failed, cause: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:44:52.656 [CM503003]: Call(C:8705): Call to <sip:18776601336@10.1.5.50:5066> has failed; Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:44:52.479 [CM503025]: Call(C:8705): Calling T:Line:10011>>18776601336@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8705.1[Extn]
    05-Feb-2015 13:44:52.445 [CM503027]: Call(C:8705): From: Extn:3221 ("Allan Sabol" <sip:3221@10.1.5.28:5060>) to T:Line:10003>>18776601336@[Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:44:52.445 [CM503004]: Call(C:8705): Route 3: from L:8705.1[Extn] to T:Line:10003>>18776601336@[Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 0; limit is 1
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10003(@Colonial1[<sip:10003@10.1.5.48:5066>]) is 0; limit is 1
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 1; limit is 1
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10000(@Colonial1[<sip:10000@10.1.5.48:5060>]) is 1; limit is 1
    05-Feb-2015 13:44:52.444 [CM503027]: Call(C:8705): From: Extn:3221 ("Allan Sabol" <sip:3221@10.1.5.28:5060>) to T:Line:10006>>18776601336@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:44:52.444 [CM503004]: Call(C:8705): Route 2: from L:8705.1[Extn] to T:Line:10006>>18776601336@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10004(@Colonial2[<sip:10004@10.1.5.49:5060>]) is 0; limit is 1
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10007(@Colonial2[<sip:10007@10.1.5.49:5066>]) is 0; limit is 1
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10005(@Colonial2[<sip:10005@10.1.5.49:5062>]) is 0; limit is 1
    05-Feb-2015 13:44:52.444 Line limit check: Current # of calls for line Lc:10006(@Colonial2[<sip:10006@10.1.5.49:5064>]) is 0; limit is 1
    05-Feb-2015 13:44:52.444 [CM503027]: Call(C:8705): From: Extn:3221 ("Allan Sabol" <sip:3221@10.1.5.28:5060>) to T:Line:10011>>18776601336@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:44:52.444 [CM503004]: Call(C:8705): Route 1: from L:8705.1[Extn] to T:Line:10011>>18776601336@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:44:52.443 Line limit check: Current # of calls for line Lc:10010(@Colonial3[<sip:10010@10.1.5.50:5064>]) is 0; limit is 1
    05-Feb-2015 13:44:52.443 Line limit check: Current # of calls for line Lc:10009(@Colonial3[<sip:10009@10.1.5.50:5062>]) is 0; limit is 1
    05-Feb-2015 13:44:52.443 Line limit check: Current # of calls for line Lc:10008(@Colonial3[<sip:10008@10.1.5.50:5060>]) is 0; limit is 1
    05-Feb-2015 13:44:52.443 Line limit check: Current # of calls for line Lc:10011(@Colonial3[<sip:10011@10.1.5.50:5066>]) is 0; limit is 1
    05-Feb-2015 13:44:52.443 Call(C:8705): Call from Extn:3221 to 918776601336 matches outbound rule 'Rule for Colonial1'
    05-Feb-2015 13:44:52.437 [CM503001]: Call(C:8705): Incoming call from Extn:3221 to <sip:918776601336@10.1.5.28:5060>
    05-Feb-2015 13:44:52.289 [CM503025]: Call(C:8704): Calling T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062] for L:8704.1[Line:10001<<anonymous]
    05-Feb-2015 13:44:52.254 [CM503027]: Call(C:8704): From: Line:10001<<anonymous (<sip:anonymous@10.1.5.28:5060>) to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:44:52.254 [CM503004]: Call(C:8704): Route 1: from L:8704.1[Line:10001<<anonymous] to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:44:52.253 [CM505002]: Gateway:[Colonial1] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994F0 R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10001@10.1.5.28:5060]
    05-Feb-2015 13:44:52.249 [CM503001]: Call(C:8704): Incoming call from Line:10001<<anonymous to <sip:3200@10.1.5.28:5060>
    05-Feb-2015 13:44:52.245 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 1; limit is 1
    05-Feb-2015 13:44:52.232 [CM503012]: Inbound specific hours w/o holidays rule (unnamed) for 10001 forwards to DN:3200
    05-Feb-2015 13:44:40.531 [CM503008]: Call(C:8703): Call is terminated
    05-Feb-2015 13:44:40.530 Leg L:8703.1[Extn] is terminated: Cause: CANCEL from 10.1.5.63:5062
    05-Feb-2015 13:44:39.300 Leg L:8703.2[Line:10011>>1877660] is terminated: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:44:39.300 [CM503025]: Call(C:8703): Calling T:Line:10011>>1877660@[Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8703.1[Extn]
    05-Feb-2015 13:44:39.286 Call to T:Line:10011>>1877660@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] from L:8703.1[Extn] failed, cause: Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:44:39.285 [CM503003]: Call(C:8703): Call to <sip:1877660@10.1.5.50:5066> has failed; Cause: 502 Bad Gateway/INVITE from 10.1.5.50:5066
    05-Feb-2015 13:44:39.114 [CM503025]: Call(C:8703): Calling T:Line:10011>>1877660@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064] for L:8703.1[Extn]
    05-Feb-2015 13:44:39.060 [CM503027]: Call(C:8703): From: Extn:3221 ("Allan Sabol" <sip:3221@10.1.5.28:5060>) to T:Line:10001>>1877660@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:44:39.060 [CM503004]: Call(C:8703): Route 3: from L:8703.1[Extn] to T:Line:10001>>1877660@[Dev:sip:10001@10.1.5.48:5062,Dev:sip:10003@10.1.5.48:5066,Dev:sip:10002@10.1.5.48:5064]
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10002(@Colonial1[<sip:10002@10.1.5.48:5064>]) is 0; limit is 1
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10003(@Colonial1[<sip:10003@10.1.5.48:5066>]) is 0; limit is 1
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 0; limit is 1
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10000(@Colonial1[<sip:10000@10.1.5.48:5060>]) is 1; limit is 1
    05-Feb-2015 13:44:39.060 [CM503027]: Call(C:8703): From: Extn:3221 ("Allan Sabol" <sip:3221@10.1.5.28:5060>) to T:Line:10006>>1877660@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:44:39.060 [CM503004]: Call(C:8703): Route 2: from L:8703.1[Extn] to T:Line:10006>>1877660@[Dev:sip:10006@10.1.5.49:5064,Dev:sip:10005@10.1.5.49:5062,Dev:sip:10007@10.1.5.49:5066,Dev:sip:10004@10.1.5.49:5060]
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10004(@Colonial2[<sip:10004@10.1.5.49:5060>]) is 0; limit is 1
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10007(@Colonial2[<sip:10007@10.1.5.49:5066>]) is 0; limit is 1
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10005(@Colonial2[<sip:10005@10.1.5.49:5062>]) is 0; limit is 1
    05-Feb-2015 13:44:39.060 Line limit check: Current # of calls for line Lc:10006(@Colonial2[<sip:10006@10.1.5.49:5064>]) is 0; limit is 1
    05-Feb-2015 13:44:39.060 [CM503027]: Call(C:8703): From: Extn:3221 ("Allan Sabol" <sip:3221@10.1.5.28:5060>) to T:Line:10011>>1877660@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:44:39.060 [CM503004]: Call(C:8703): Route 1: from L:8703.1[Extn] to T:Line:10011>>1877660@[Dev:sip:10011@10.1.5.50:5066,Dev:sip:10008@10.1.5.50:5060,Dev:sip:10009@10.1.5.50:5062,Dev:sip:10010@10.1.5.50:5064]
    05-Feb-2015 13:44:39.059 Line limit check: Current # of calls for line Lc:10010(@Colonial3[<sip:10010@10.1.5.50:5064>]) is 0; limit is 1
    05-Feb-2015 13:44:39.059 Line limit check: Current # of calls for line Lc:10009(@Colonial3[<sip:10009@10.1.5.50:5062>]) is 0; limit is 1
    05-Feb-2015 13:44:39.059 Line limit check: Current # of calls for line Lc:10008(@Colonial3[<sip:10008@10.1.5.50:5060>]) is 0; limit is 1
    05-Feb-2015 13:44:39.059 Line limit check: Current # of calls for line Lc:10011(@Colonial3[<sip:10011@10.1.5.50:5066>]) is 0; limit is 1
    05-Feb-2015 13:44:39.059 Call(C:8703): Call from Extn:3221 to 91877660 matches outbound rule 'Rule for Colonial1'
    05-Feb-2015 13:44:39.055 [CM503001]: Call(C:8703): Incoming call from Extn:3221 to <sip:91877660@10.1.5.28:5060>
    05-Feb-2015 13:44:35.732 [CM504001]: Endpoint Extn:3442: new contact is registered. Contact(s): [sip:3442@10.1.5.50:5060 / 3442]
    05-Feb-2015 13:44:25.530 Currently active calls - 2: [8671,8697]
    05-Feb-2015 13:43:53.528 Currently active calls - 2: [8671,8697]
    05-Feb-2015 13:43:23.526 Currently active calls - 2: [8671,8697]
    05-Feb-2015 13:42:51.525 Currently active calls - 2: [8671,8697]
    05-Feb-2015 13:42:36.467 Leg L:8701.1[Line:10001<<8659811794] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:42:36.466 [CM503008]: Call(C:8701): Call is terminated
    05-Feb-2015 13:42:36.462 Leg L:8701.3[Extn] is terminated: Cause: BYE from 10.1.5.63:5062
    05-Feb-2015 13:42:19.523 Currently active calls - 3: [8671,8697,8701]
    05-Feb-2015 13:42:09.086 [CM504002]: Endpoint Extn:3442: a contact is unregistered. Contact(s): []
    05-Feb-2015 13:42:00.461 Leg L:8701.2[Extn] is terminated: Cause: BYE from 10.1.6.5:5062
    05-Feb-2015 13:42:00.330 [CM503007]: Call(C:8701): Extn:3221 has joined, contact <sip:3221@10.1.5.63:5062>
    05-Feb-2015 13:42:00.326 L:8701.3[Extn] has joined to L:8701.1[Line:10001<<8659811794]
    05-Feb-2015 13:41:55.273 [MS105000] C:8702.1: No RTP packets were received:remoteAddr=10.1.6.5:11800,extAddr=0.0.0.0:0,localAddr=10.1.5.28:7206
    05-Feb-2015 13:41:54.826 Leg L:8702.2[VMail] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:41:54.825 [CM503008]: Call(C:8702): Call is terminated
    05-Feb-2015 13:41:54.821 Leg L:8702.1[Extn] is terminated: Cause: BYE from 10.1.6.5:5062
    05-Feb-2015 13:41:54.094 [CM503007]: Call(C:8702): VMail:9999 has joined, contact <sip:9999@127.0.0.1:40600>
    05-Feb-2015 13:41:54.090 [CM503007]: Call(C:8702): Extn:3200 has joined, contact <sip:3200@10.1.6.5:5062>
    05-Feb-2015 13:41:54.083 L:8702.2[VMail] has joined to L:8702.1[Extn]
    05-Feb-2015 13:41:53.932 [CM503025]: Call(C:8702): Calling T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7] for L:8702.1[Extn]
    05-Feb-2015 13:41:53.890 [CM503005]: Call(C:8702): Forwarding: T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7]
    05-Feb-2015 13:41:53.890 L:8702.1[Extn] forwards call from Extn:3221 to VMail:9999 based on rule Fwd[Custom 1/Busy]
    05-Feb-2015 13:41:53.890 L:8702.1[Extn] failed to reach Extn:3221, reason Busy
    05-Feb-2015 13:41:53.889 [CM503015]: Call(C:8702): Destination [<sip:3221@10.1.5.28>] is busy
    05-Feb-2015 13:41:53.884 [CM503001]: Call(C:8702): Incoming call from Extn:3200 to <sip:3221@10.1.5.28:5060>
    05-Feb-2015 13:41:53.145 [CM503025]: Call(C:8701): Calling T:Extn:3221@[Dev:sip:3221@10.1.5.63:5062] for L:8701.1[Line:10001<<8659811794]
    05-Feb-2015 13:41:53.120 [CM503027]: Call(C:8701): From: Line:10001<<8659811794 ("Rittenhouse G " <sip:8659811794@10.1.5.28:5060>) to T:Extn:3221@[Dev:sip:3221@10.1.5.63:5062]
    05-Feb-2015 13:41:53.120 [CM503004]: Call(C:8701): Route 1: from L:8701.1[Line:10001<<8659811794] to T:Extn:3221@[Dev:sip:3221@10.1.5.63:5062]
    05-Feb-2015 13:41:47.521 Currently active calls - 3: [8671,8697,8701]
    05-Feb-2015 13:41:42.578 [CM503007]: Call(C:8701): Extn:3200 has joined, contact <sip:3200@10.1.6.5:5062>
    05-Feb-2015 13:41:42.576 [CM503007]: Call(C:8701): Line:10001<<8659811794 has joined, contact <sip:10001@10.1.5.48:5062>
    05-Feb-2015 13:41:42.573 L:8701.2[Extn] has joined to L:8701.1[Line:10001<<8659811794]
    05-Feb-2015 13:41:40.112 [CM503025]: Call(C:8701): Calling T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062] for L:8701.1[Line:10001<<8659811794]
    05-Feb-2015 13:41:40.075 [CM503027]: Call(C:8701): From: Line:10001<<8659811794 ("Rittenhouse G " <sip:8659811794@10.1.5.28:5060>) to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:41:40.075 [CM503004]: Call(C:8701): Route 1: from L:8701.1[Line:10001<<8659811794] to T:Extn:3200@[Dev:sip:3200@10.1.6.5:5062]
    05-Feb-2015 13:41:40.075 [CM505002]: Gateway:[Colonial1] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994F0 R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10001@10.1.5.28:5060]
    05-Feb-2015 13:41:40.070 [CM503001]: Call(C:8701): Incoming call from Line:10001<<8659811794 to <sip:3200@10.1.5.28:5060>
    05-Feb-2015 13:41:40.067 Line limit check: Current # of calls for line Lc:10001(@Colonial1[<sip:10001@10.1.5.48:5062>]) is 1; limit is 1
    05-Feb-2015 13:41:40.054 [CM503012]: Inbound specific hours w/o holidays rule (unnamed) for 10001 forwards to DN:3200
    05-Feb-2015 13:41:35.181 Leg L:8700.3[VMail] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:41:35.180 [CM503008]: Call(C:8700): Call is terminated
    05-Feb-2015 13:41:35.176 Leg L:8700.1[Extn] is terminated: Cause: BYE from 10.1.5.16:5062
    05-Feb-2015 13:41:32.269 [CM503007]: Call(C:8700): VMail:9999 has joined, contact <sip:9999@127.0.0.1:40600>
    05-Feb-2015 13:41:32.265 [CM503007]: Call(C:8700): Extn:3244 has joined, contact <sip:3244@10.1.5.16:5062>
    05-Feb-2015 13:41:32.260 L:8700.3[VMail] has joined to L:8700.1[Extn]
    05-Feb-2015 13:41:32.163 Leg L:8700.2[Extn] is terminated: Cause: 487 Request Terminated/INVITE from 10.1.5.89:5062
    05-Feb-2015 13:41:32.161 [CM503003]: Call(C:8700): Call to <sip:3438@10.1.5.28:5060> has failed; Cause: 487 Request Terminated/INVITE from 10.1.5.89:5062
    05-Feb-2015 13:41:32.091 [CM503025]: Call(C:8700): Calling T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7] for L:8700.1[Extn]
    05-Feb-2015 13:41:32.028 [CM503005]: Call(C:8700): Forwarding: T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=f0bb70c04595fed7]
    05-Feb-2015 13:41:32.027 L:8700.1[Extn] forwards call from Extn:3438 to VMail:9999 based on rule Fwd[Available/NoAnsw]
    05-Feb-2015 13:41:32.027 L:8700.1[Extn] failed to reach Extn:3438, reason No Answer
    05-Feb-2015 13:41:15.519 Currently active calls - 3: [8671,8697,8700]
    05-Feb-2015 13:41:12.011 [CM503025]: Call(C:8700): Calling T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062] for L:8700.1[Extn]
    05-Feb-2015 13:41:11.964 [CM503027]: Call(C:8700): From: Extn:3244 ("Shipping" <sip:3244@10.1.5.28:5060>) to T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062]
    05-Feb-2015 13:41:11.964 [CM503004]: Call(C:8700): Route 1: from L:8700.1[Extn] to T:Extn:3438@[Dev:sip:3438@10.1.5.89:5062]
    05-Feb-2015 13:41:11.959 [CM503001]: Call(C:8700): Incoming call from Extn:3244 to <sip:3438@10.1.5.28:5060>
    05-Feb-2015 13:40:48.222 Leg L:8699.3[Line:10011>>18659811794] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:40:48.221 [CM503008]: Call(C:8699): Call is terminated
    05-Feb-2015 13:40:48.217 Leg L:8699.1[Extn] is terminated: Cause: BYE from 10.1.5.63:5062
    05-Feb-2015 13:40:43.517 Currently active calls - 3: [8671,8697,8699]
    05-Feb-2015 13:40:13.516 Currently active calls - 3: [8671,8697,8699]
    05-Feb-2015 13:40:09.739 Leg L:8698.1[Line:10001<<7174959277] is terminated: Cause: BYE from PBX
    05-Feb-2015 13:40:09.738 [CM503008]: Call(C:8698): Call is terminated
    05-Feb-2015 13:40:09.733 Leg L:8698.2[Extn] is terminated: Cause: BYE from 10.1.6.5:5062
    05-Feb-2015 13:40:00.119 [CM503007]: Call(C:8699): Line:10011>>18659811794 has joined, contact <sip:10008@10.1.5.50:5060>
    05-Feb-2015 13:40:00.116 [CM503007]: Call(C:8699): Extn:3221 has joined, contact <sip:3221@10.1.5.63:5062>
    05-Feb-2015 13:40:00.110 L:8699.3[Line:10011>>18659811794] has joined to L:8699.1[Extn]
    05-Feb-2015 13:40:00.110 [CM505002]: Gateway:[Colonial3] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Patton SN4114 JO EUI 00A0BA0994DE R6.3 2013-09-05 H323 SIP FXS FXO M5T SIP Stack/4.1.12.18] PBX contact: [sip:10008@10.1.5.28:5060]
     
  2. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    11,073
    Likes Received:
    323
    I'm assuming that this was one of those calls? As it's happening with more than one gateway it is unlikely, but not totally out of the question, that it is a bad line(s). Have you confirmed that there is dialtone on each line (trunk), using a phone? There could also be an issue with DTMF timing, or levels as set in the gateway. It could be a Comcast issue. What sort of equipment do they have on site? Is it an ATA "box" of sorts? If so, is it one, or more than one device?

    There may also be logs, available from the Patton, that may provide additional information as to what the 502 Bad gateway/INVITE message is all about. Set the 3CX server logs to Verbose and see if there is any additional (helpful) information during one of the failed calls.
     
  3. terry@advancedcomm

    Joined:
    Feb 4, 2015
    Messages:
    4
    Likes Received:
    0
    Comcast has four ATA or channel bank type devices to break out the lines. If I place an inbound call to each number, they all come thru fine. And if I clip my buttset on the lines in front of the gateway, outbound calls go thru okay. On most 'old' phone systems there's a way to 'grab' a particular line by dialing an access number and make an outside call. I wish there was a way to do this on the 3CX system!!!
    This is such an intermittent problem - it'll work fine for weeks (months) and then for a couple days, they have great difficulty making outbound calls. I'll set the server logs to verbose and see what I get.
    Thanks!!
     
  4. craigreilly

    craigreilly Well-Known Member

    Joined:
    Feb 1, 2012
    Messages:
    3,530
    Likes Received:
    298
    For testing you could create outbound rules that if it starts with 55 then use 10000, if start with 66 then use 10001, etc.
    maybe not ideal, but workable. I do it all the time for testing... and add my extension as the source so it does not affect anyone else.
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  5. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    11,073
    Likes Received:
    323
    You might be able to narrow it down a bit if you sent certain prefixes to certain gateways (trunk groups), in a manner similar to what Craig suggested.
    I'm assuming that each gateway is a different trunk group.

    You could "split" calls based on the areacode, or prefixes (or a combination), so that approximately one third of numbers went to each gateway. You could have a second trunk group as an alternate route, just for overflow if necessary. Have callers keep track of what number they are dialling, when the call fails. See if this provides any sort of pattern that implicates a particular piece of hardware.

    Intermittent problems are the worst things to try to troubleshoot.
     
  6. terry@advancedcomm

    Joined:
    Feb 4, 2015
    Messages:
    4
    Likes Received:
    0
    Thanks for the input!! I never thought about using different prefixes for different lines to test each one. I'll use that method to test each line and see if perhaps I have either a bad port on a gateway or a bad CO line.
     
Thread Status:
Not open for further replies.