[asterisk-bugs] [JIRA] (ASTERISK-28705) chan_sip: Phones loose abiltiy to work, core restarting asterisk fixes issue

Sean Bright (JIRA) noreply at issues.asterisk.org
Mon Jan 20 14:10:34 CST 2020


    [ https://issues.asterisk.org/jira/browse/ASTERISK-28705?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=249482#comment-249482 ] 

Sean Bright edited comment on ASTERISK-28705 at 1/20/20 2:10 PM:
-----------------------------------------------------------------

{noformat}
2020-01-20T10:48:45.186290-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx.c:2945 in pbx_extension_helper: Executing [s at GreatDane-Menu-Root:4] NoOp("Local/39028 at stations-0000034d;2", "GreatDane is OPEN") in new stack
2020-01-20T10:48:45.186299-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx.c:2945 in pbx_extension_helper: Executing [s at GreatDane-Menu-Root:5] Goto("Local/39028 at stations-0000034d;2", "GreatDane-Menu-Trunk-Business-Hours,s,1") in new stack
2020-01-20T10:48:45.186307-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx_builtins.c:867 in pbx_builtin_goto: Goto (GreatDane-Menu-Trunk-Business-Hours,s,1)
2020-01-20T10:48:45.186314-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx.c:2945 in pbx_extension_helper: Executing [s at GreatDane-Menu-Trunk-Business-Hours:1] BackGround("Local/39028 at stations-0000034d;2", "greatdane/business-hour-greeting") in new stack
2020-01-20T10:48:45.186819-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Root:2] #033[1;36mGotoIfTime#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35m08:00-19:00,mon-fri,*,*?open#033[0m") in new stack
2020-01-20T10:48:45.187088-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mGoto (GreatDane-Menu-Root,s,4)
2020-01-20T10:48:45.187336-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Root:4] #033[1;36mNoOp#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35mGreatDane is OPEN#033[0m") in new stack
2020-01-20T10:48:45.188263-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: file.c:1295 in ast_streamfile: <Local/39028 at stations-0000034d;2> Playing 'greatdane/business-hour-greeting.ulaw' (language 'en')
2020-01-20T10:48:45.187535-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Root:5] #033[1;36mGoto#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35mGreatDane-Menu-Trunk-Business-Hours,s,1#033[0m") in new stack
2020-01-20T10:48:45.187730-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mGoto (GreatDane-Menu-Trunk-Business-Hours,s,1)
2020-01-20T10:48:45.187926-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Trunk-Business-Hours:1] #033[1;36mBackGround#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35mgreatdane/business-hour-greeting#033[0m") in new stack
2020-01-20T10:48:45.188707-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0m<Local/39028 at stations-0000034d;2> Playing 'greatdane/business-hour-greeting.ulaw' (language 'en')
2020-01-20T10:48:45.899886-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.899] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3987#033[0m #033[1;37m__ast_read#033[0m: DTMF begin '#' received on SIP/opmgw23-00002e42
2020-01-20T10:48:45.900185-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.899] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3991#033[0m #033[1;37m__ast_read#033[0m: DTMF begin ignored '#' on SIP/opmgw23-00002e42
2020-01-20T10:48:45.937279-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.936] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3873#033[0m #033[1;37m__ast_read#033[0m: DTMF end '#' received on SIP/opmgw23-00002e42, duration 50 ms
2020-01-20T10:48:45.937521-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.936] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3962#033[0m #033[1;37m__ast_read#033[0m: DTMF end passthrough '#' on SIP/opmgw23-00002e42
2020-01-20T10:48:45.938716-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77755][C-00001a58]: res_agi.c:2580 in handle_streamfile: <SIP/opmgw23-00002e42> Playing 'en/authsystem/please-enter-card.gsm' (escape_digits=#*0123456789) (sample_offset 0) (language 'en')
2020-01-20T10:48:45.939198-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0m<SIP/opmgw23-00002e42> Playing 'en/authsystem/please-enter-card.gsm' (escape_digits=#*0123456789) (sample_offset 0) (language 'en')
2020-01-20T10:48:46.337811-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:46.337] #033[1;32mDTMF#033[0m[77486][C-00001a41]: #033[1;37mchannel.c#033[0m:#033[1;37m3987#033[0m #033[1;37m__ast_read#033[0m: DTMF begin '#' received on SIP/Sarah-Martin-00002e1d
2020-01-20T10:48:46.338103-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:46.337] #033[1;32mDTMF#033[0m[77486][C-00001a41]: #033[1;37mchannel.c#033[0m:#033[1;37m3998#033[0m #033[1;37m__ast_read#033[0m: DTMF begin passthrough '#' on SIP/Sarah-Martin-00002e1d
2020-01-20T10:48:46.397846-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:46.397] #033[1;32mDTMF#033[0m[77486][C-00001a41]: #033[1;37mchannel...skipping...
2020-01-20T10:52:53.711731-06:00 vmctel22.multiservice.com sendmail[78724]: 00KGqr1U078724: from=asterisk, size=72250, class=0, nrcpts=1, msgid=<Asterisk-1-1620423055-32871-111592 at vmctel22.multiservice.com>, relay=asterisk at localhost
2020-01-20T10:52:53.775537-06:00 vmctel22.multiservice.com sendmail[78725]: 00KGqrL0078725: from=<asterisk at vmctel22.multiservice.com>, size=72391, class=0, nrcpts=1, msgid=<Asterisk-1-1620423055-32871-111592 at vmctel22.multiservice.com>, proto=ESMTP, daemon=MTA, relay=localhost [127.0.0.1]
2020-01-20T10:52:53.783655-06:00 vmctel22.multiservice.com sendmail[78724]: 00KGqr1U078724: to="Jodi Hall" <jhall at multiservice.com>, ctladdr=asterisk (209/206), delay=00:00:00, xdelay=00:00:00, mailer=relay, pri=102250, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (00KGqrL0078725 Message accepted for delivery)
2020-01-20T10:52:54.365865-06:00 vmctel22.multiservice.com sendmail[78727]: 00KGqrL0078725: to=<jhall at multiservice.com>, ctladdr=<asterisk at vmctel22.multiservice.com> (209/206), delay=00:00:01, xdelay=00:00:01, mailer=esmtp, pri=192391, relay=mail-dlp.multiservice.com. [172.24.112.131], dsn=2.0.0, stat=Sent (00KGqrkG011784 Message accepted for delivery)
2020-01-20T10:52:54.493331-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.2:5060' - Wrong password
2020-01-20T10:52:54.493784-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:54.493] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.2:5060' - Wrong password
2020-01-20T10:52:54.545986-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:Bob-Featherston at multiservice.com>' failed for '10.119.221.44:5060' - Wrong password
2020-01-20T10:52:54.546354-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:54.545] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:Bob-Featherston at multiservice.com>' failed for '10.119.221.44:5060' - Wrong password
2020-01-20T10:52:54.833750-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.29:5060' - Wrong password
2020-01-20T10:52:54.834190-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:54.833] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.29:5060' - Wrong password
2020-01-20T10:52:56.216590-06:00 vmctel22.multiservice.com asterisk[7330]: NOTICE[10394]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:lwatkins at 172.25.220.18;transport=TCP>' failed for '10.119.100.51:60833' - Wrong password
2020-01-20T10:52:56.703673-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:Jessica-Coogle at multiservice.com>' failed for '10.119.221.13:5060' - Wrong password
2020-01-20T10:52:56.704388-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:56.703] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:Jessica-Coogle at multiservice.com>' failed for '10.119.221.13:5060' - Wrong password
2020-01-20T10:52:58.315150-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[111641][C-00001a8c]: netsock2.c:639 in ast_set_qos: Using SIP RTP TOS bits 184
2020-01-20T10:52:58.315180-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[111641][C-00001a8c]: netsock2.c:661 in ast_set_qos: Using SIP RTP CoS mark 5
2020-01-20T10:52:58.315617-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m  == #033[0mUsing SIP RTP TOS bits 184
2020-01-20T10:52:58.315891-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m  == #033[0mUsing SIP RTP CoS mark 5
2020-01-20T10:52:58.316707-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[111641][C-00001a8c]: res_rtp_asterisk.c:8052 in ast_rtp_remote_address_set: 0x3cf5970 -- Strict RTP learning after remote address set to: 10.123.240.178:36740
2020-01-20T10:52:58.317843-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: pbx.c:2945 in pbx_extension_helper: Executing [9133483901 at gateways-inbound:1] DumpChan("SIP/ec2-tel36-00002ea1", "3") in new stack
2020-01-20T10:52:58.317868-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: app_dumpchan.c:191 in dumpchan_exec: #012Dumping Info For Channel: SIP/ec2-tel36-00002ea1:#012================================================================================#012Info:#012Name=               SIP/ec2-tel36-00002ea1#012Type=               SIP#012UniqueID=           1579539178.127703#012LinkedID=           1579539178.127703#012CallerIDNum=        6302300983#012CallerIDName=       A S TRANS#012ConnectedLineIDNum= (N/A)#012ConnectedLineIDName=(N/A)#012DNIDDigits=         9133483901#012RDNIS=              (N/A)#012Parkinglot=         default#012Language=           en#012State=              Ring (4)#012Rings=              0#012NativeFormat=       (g729)#012WriteFormat=        g729#012ReadFormat=         g729#012RawWriteFormat=     g729#012RawReadFormat=      g729#012WriteTranscode=     No #012ReadTranscode=      No #0121stFileDescriptor=  99#012Framesin=           0 #012Framesout=          0 #012TimetoHangup=       0#012ElapsedTime=        0h0m0s#012BridgeID=           (Not bridged)#012Context=            gateways-inbound#012Extension=          9133483901#012Priority=           1#012CallGroup=          #012PickupGroup=        #012Application=        DumpChan#012Data=               3#012Blocking_in=        (Not Blocking)#012#012Variables:#012SIPCALLID=57040245411a098e0108ea380f48f6cb at 10.123.240.178:5060#012SIPDOMAIN=siptel02.multiservice.com#012SIPURI=sip:6302300983 at 10.123.240.178:5060#012================================================================================
2020-01-20T10:52:58.317949-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: pbx.c:2945 in pbx_extension_helper: Executing [9133483901 at gateways-inbound:2] GotoIf("SIP/ec2-tel36-00002ea1", "0?blacklisted") in new stack
2020-01-20T10:52:58.317962-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: pbx.c:2945 in pbx_extension_helper: Executing [9133483901...skipping...
2020-01-20T11:00:04.113150-06:00 vmctel22.multiservice.com asterisk[111592]: ERROR[111707]:  
 (This is me shutting it down)   manager.c:6552 in process_message: Unable to process manager action 'SIPShowPeer'. Asterisk is shutting down.
{noformat}


was (Author: dwsiemens):
2020-01-20T10:48:45.186290-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx.c:2945 in pbx_extension_helper: Executing [s at GreatDane-Menu-Root:4] NoOp("Local/39028 at stations-0000034d;2", "GreatDane is OPEN") in new stack
2020-01-20T10:48:45.186299-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx.c:2945 in pbx_extension_helper: Executing [s at GreatDane-Menu-Root:5] Goto("Local/39028 at stations-0000034d;2", "GreatDane-Menu-Trunk-Business-Hours,s,1") in new stack
2020-01-20T10:48:45.186307-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx_builtins.c:867 in pbx_builtin_goto: Goto (GreatDane-Menu-Trunk-Business-Hours,s,1)
2020-01-20T10:48:45.186314-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: pbx.c:2945 in pbx_extension_helper: Executing [s at GreatDane-Menu-Trunk-Business-Hours:1] BackGround("Local/39028 at stations-0000034d;2", "greatdane/business-hour-greeting") in new stack
2020-01-20T10:48:45.186819-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Root:2] #033[1;36mGotoIfTime#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35m08:00-19:00,mon-fri,*,*?open#033[0m") in new stack
2020-01-20T10:48:45.187088-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mGoto (GreatDane-Menu-Root,s,4)
2020-01-20T10:48:45.187336-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Root:4] #033[1;36mNoOp#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35mGreatDane is OPEN#033[0m") in new stack
2020-01-20T10:48:45.188263-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77846][C-00001a43]: file.c:1295 in ast_streamfile: <Local/39028 at stations-0000034d;2> Playing 'greatdane/business-hour-greeting.ulaw' (language 'en')
2020-01-20T10:48:45.187535-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Root:5] #033[1;36mGoto#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35mGreatDane-Menu-Trunk-Business-Hours,s,1#033[0m") in new stack
2020-01-20T10:48:45.187730-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mGoto (GreatDane-Menu-Trunk-Business-Hours,s,1)
2020-01-20T10:48:45.187926-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0mExecuting [s at GreatDane-Menu-Trunk-Business-Hours:1] #033[1;36mBackGround#033[0m("#033[1;35mLocal/39028 at stations-0000034d;2#033[0m", "#033[1;35mgreatdane/business-hour-greeting#033[0m") in new stack
2020-01-20T10:48:45.188707-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0m<Local/39028 at stations-0000034d;2> Playing 'greatdane/business-hour-greeting.ulaw' (language 'en')
2020-01-20T10:48:45.899886-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.899] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3987#033[0m #033[1;37m__ast_read#033[0m: DTMF begin '#' received on SIP/opmgw23-00002e42
2020-01-20T10:48:45.900185-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.899] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3991#033[0m #033[1;37m__ast_read#033[0m: DTMF begin ignored '#' on SIP/opmgw23-00002e42
2020-01-20T10:48:45.937279-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.936] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3873#033[0m #033[1;37m__ast_read#033[0m: DTMF end '#' received on SIP/opmgw23-00002e42, duration 50 ms
2020-01-20T10:48:45.937521-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:45.936] #033[1;32mDTMF#033[0m[77755][C-00001a58]: #033[1;37mchannel.c#033[0m:#033[1;37m3962#033[0m #033[1;37m__ast_read#033[0m: DTMF end passthrough '#' on SIP/opmgw23-00002e42
2020-01-20T10:48:45.938716-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[77755][C-00001a58]: res_agi.c:2580 in handle_streamfile: <SIP/opmgw23-00002e42> Playing 'en/authsystem/please-enter-card.gsm' (escape_digits=#*0123456789) (sample_offset 0) (language 'en')
2020-01-20T10:48:45.939198-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m    -- #033[0m<SIP/opmgw23-00002e42> Playing 'en/authsystem/please-enter-card.gsm' (escape_digits=#*0123456789) (sample_offset 0) (language 'en')
2020-01-20T10:48:46.337811-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:46.337] #033[1;32mDTMF#033[0m[77486][C-00001a41]: #033[1;37mchannel.c#033[0m:#033[1;37m3987#033[0m #033[1;37m__ast_read#033[0m: DTMF begin '#' received on SIP/Sarah-Martin-00002e1d
2020-01-20T10:48:46.338103-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:46.337] #033[1;32mDTMF#033[0m[77486][C-00001a41]: #033[1;37mchannel.c#033[0m:#033[1;37m3998#033[0m #033[1;37m__ast_read#033[0m: DTMF begin passthrough '#' on SIP/Sarah-Martin-00002e1d
2020-01-20T10:48:46.397846-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:48:46.397] #033[1;32mDTMF#033[0m[77486][C-00001a41]: #033[1;37mchannel...skipping...
2020-01-20T10:52:53.711731-06:00 vmctel22.multiservice.com sendmail[78724]: 00KGqr1U078724: from=asterisk, size=72250, class=0, nrcpts=1, msgid=<Asterisk-1-1620423055-32871-111592 at vmctel22.multiservice.com>, relay=asterisk at localhost
2020-01-20T10:52:53.775537-06:00 vmctel22.multiservice.com sendmail[78725]: 00KGqrL0078725: from=<asterisk at vmctel22.multiservice.com>, size=72391, class=0, nrcpts=1, msgid=<Asterisk-1-1620423055-32871-111592 at vmctel22.multiservice.com>, proto=ESMTP, daemon=MTA, relay=localhost [127.0.0.1]
2020-01-20T10:52:53.783655-06:00 vmctel22.multiservice.com sendmail[78724]: 00KGqr1U078724: to="Jodi Hall" <jhall at multiservice.com>, ctladdr=asterisk (209/206), delay=00:00:00, xdelay=00:00:00, mailer=relay, pri=102250, relay=[127.0.0.1] [127.0.0.1], dsn=2.0.0, stat=Sent (00KGqrL0078725 Message accepted for delivery)
2020-01-20T10:52:54.365865-06:00 vmctel22.multiservice.com sendmail[78727]: 00KGqrL0078725: to=<jhall at multiservice.com>, ctladdr=<asterisk at vmctel22.multiservice.com> (209/206), delay=00:00:01, xdelay=00:00:01, mailer=esmtp, pri=192391, relay=mail-dlp.multiservice.com. [172.24.112.131], dsn=2.0.0, stat=Sent (00KGqrkG011784 Message accepted for delivery)
2020-01-20T10:52:54.493331-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.2:5060' - Wrong password
2020-01-20T10:52:54.493784-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:54.493] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.2:5060' - Wrong password
2020-01-20T10:52:54.545986-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:Bob-Featherston at multiservice.com>' failed for '10.119.221.44:5060' - Wrong password
2020-01-20T10:52:54.546354-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:54.545] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:Bob-Featherston at multiservice.com>' failed for '10.119.221.44:5060' - Wrong password
2020-01-20T10:52:54.833750-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.29:5060' - Wrong password
2020-01-20T10:52:54.834190-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:54.833] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:450-Test at siptel02.multiservice.com>' failed for '10.119.221.29:5060' - Wrong password
2020-01-20T10:52:56.216590-06:00 vmctel22.multiservice.com asterisk[7330]: NOTICE[10394]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:lwatkins at 172.25.220.18;transport=TCP>' failed for '10.119.100.51:60833' - Wrong password
2020-01-20T10:52:56.703673-06:00 vmctel22.multiservice.com asterisk[111592]: NOTICE[111641]: chan_sip.c:28942 in handle_request_register: Registration from '<sip:Jessica-Coogle at multiservice.com>' failed for '10.119.221.13:5060' - Wrong password
2020-01-20T10:52:56.704388-06:00 vmctel22.multiservice.com asterisk[111592]: [2020-01-20 10:52:56.703] #033[1;33mNOTICE#033[0m[111641]: #033[1;37mchan_sip.c#033[0m:#033[1;37m28942#033[0m #033[1;37mhandle_request_register#033[0m: Registration from '<sip:Jessica-Coogle at multiservice.com>' failed for '10.119.221.13:5060' - Wrong password
2020-01-20T10:52:58.315150-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[111641][C-00001a8c]: netsock2.c:639 in ast_set_qos: Using SIP RTP TOS bits 184
2020-01-20T10:52:58.315180-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[111641][C-00001a8c]: netsock2.c:661 in ast_set_qos: Using SIP RTP CoS mark 5
2020-01-20T10:52:58.315617-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m  == #033[0mUsing SIP RTP TOS bits 184
2020-01-20T10:52:58.315891-06:00 vmctel22.multiservice.com asterisk[111592]: #033[1;30m  == #033[0mUsing SIP RTP CoS mark 5
2020-01-20T10:52:58.316707-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[111641][C-00001a8c]: res_rtp_asterisk.c:8052 in ast_rtp_remote_address_set: 0x3cf5970 -- Strict RTP learning after remote address set to: 10.123.240.178:36740
2020-01-20T10:52:58.317843-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: pbx.c:2945 in pbx_extension_helper: Executing [9133483901 at gateways-inbound:1] DumpChan("SIP/ec2-tel36-00002ea1", "3") in new stack
2020-01-20T10:52:58.317868-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: app_dumpchan.c:191 in dumpchan_exec: #012Dumping Info For Channel: SIP/ec2-tel36-00002ea1:#012================================================================================#012Info:#012Name=               SIP/ec2-tel36-00002ea1#012Type=               SIP#012UniqueID=           1579539178.127703#012LinkedID=           1579539178.127703#012CallerIDNum=        6302300983#012CallerIDName=       A S TRANS#012ConnectedLineIDNum= (N/A)#012ConnectedLineIDName=(N/A)#012DNIDDigits=         9133483901#012RDNIS=              (N/A)#012Parkinglot=         default#012Language=           en#012State=              Ring (4)#012Rings=              0#012NativeFormat=       (g729)#012WriteFormat=        g729#012ReadFormat=         g729#012RawWriteFormat=     g729#012RawReadFormat=      g729#012WriteTranscode=     No #012ReadTranscode=      No #0121stFileDescriptor=  99#012Framesin=           0 #012Framesout=          0 #012TimetoHangup=       0#012ElapsedTime=        0h0m0s#012BridgeID=           (Not bridged)#012Context=            gateways-inbound#012Extension=          9133483901#012Priority=           1#012CallGroup=          #012PickupGroup=        #012Application=        DumpChan#012Data=               3#012Blocking_in=        (Not Blocking)#012#012Variables:#012SIPCALLID=57040245411a098e0108ea380f48f6cb at 10.123.240.178:5060#012SIPDOMAIN=siptel02.multiservice.com#012SIPURI=sip:6302300983 at 10.123.240.178:5060#012================================================================================
2020-01-20T10:52:58.317949-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: pbx.c:2945 in pbx_extension_helper: Executing [9133483901 at gateways-inbound:2] GotoIf("SIP/ec2-tel36-00002ea1", "0?blacklisted") in new stack
2020-01-20T10:52:58.317962-06:00 vmctel22.multiservice.com asterisk[111592]: VERBOSE[78729][C-00001a8c]: pbx.c:2945 in pbx_extension_helper: Executing [9133483901...skipping...
2020-01-20T11:00:04.113150-06:00 vmctel22.multiservice.com asterisk[111592]: ERROR[111707]:  
 (This is me shutting it down)   manager.c:6552 in process_message: Unable to process manager action 'SIPShowPeer'. Asterisk is shutting down.


> chan_sip: Phones loose abiltiy to work,  core restarting asterisk fixes issue
> -----------------------------------------------------------------------------
>
>                 Key: ASTERISK-28705
>                 URL: https://issues.asterisk.org/jira/browse/ASTERISK-28705
>             Project: Asterisk
>          Issue Type: Bug
>      Security Level: None
>          Components: Channels/chan_sip/General
>    Affects Versions: 16.7.0
>         Environment: I have a Redhat HA cluster running with 2 Rhel 7 nodes with asterisk 16.7.0
>            Reporter: Dan 
>            Assignee: Unassigned
>
> Incoming calls on Chan sip still come into the sytem.    Calls in flight stay running.  
> Endpoints get a "x" on them being ploycom phone model vvx310, 311, soundpoint 430, 450's and  zoipe soft phones. 
> THere are no messages about phones loosing registration. 
> A core restart now on asterisk fixes the issue. 
> I think the problem started around asterisk 16.4.0     



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



More information about the asterisk-bugs mailing list