Good Day Everyone,
I am running into a tough one here. I have just completed an Asterisk Installation and have tested phone connectivity with no problems. I then installed SIP Server and used the White Paper from Philippe Rais on the Genesys Tech Support Site to configure the Asterisk Trunk and a DN to be monitored in Genesys. I have followed these instructions to the letter and actually, for once, understand what it all means. However, I must be missing something because I cannot get my DN registered. Is there something special I need to do in the SIP server to tell it that there is an Asterisk box in the mix? Here is a copy of my log. Any help or pointers would be most appreciated. Thanks! Perry
Application name: TServer_Asterisk
Application type: TServer (1)
Command line: sip_server.exe -service TSrvSIP -l 7260@owgenah25 -app TServer_Asterisk -host owgenah25 -port 2020 -sstart
Host name: owgenah25
DST: TZ = 1, timeb = 0
Time zone: 18000, Eastern Standard Time, Eastern Daylight Time
UTC time: 2007-12-28T12:14:50.830
Local time: 2007-12-28T07:14:50.830
Start time (UTC): 2007-12-28T12:14:50
Running time: 0:00:00:00
Host info: Windows 5.2.3790, 2, Service Pack 1, 1.0, 0110, 3
File: (1) e:\gcti\logs\TServer_Asterisk\TServer_Asterisk.20071228_071450_831.log
07:14:50.830 Trc 04112 The Log Output of type 'e:\gcti\logs\TServer_Asterisk\TServer_Asterisk' has been created and opened
07:14:50.830 Trc 04112 The Log Output of type 'stdout' has been created and opened
07:14:50.830 Std 04106 Log Messages file 'sip_server.lms' successfully loaded
07:14:50.830 Trc 04500 Connecting to Message server '' at host 'owgenah25', port 5150
07:14:50.830 Std 04503 Connected to ConfigServer at owgenah25:2020 (appName=TServer_Asterisk)
@07:14:50.8300 [TCONF] Debug: Redundancy Type : ColdStandby
07:14:50.830 Std 04503 Connected to Message server '' at host 'owgenah25', port 5150
@07:14:50.8300 [TCONF] Debug: Main Tenant received: DBID [101], name [Resources]
@07:14:50.8300 [TCONF] Debug: Main Switch received: DBID [105], type [SIPSwitch], name [Asterisk_Test]
@07:14:50.8300 [TCONF] Debug: Requesting DNs for the main Switch [Asterisk_Test] ...
@07:14:50.8300 [TCONF] Debug: Main Switch's DNs are read. Number: 3
@07:14:50.8300 [TCONF] Debug: Client application info received: DBID [114], type [RouterServer], name [URS]
@07:14:50.8450 [ISCC] Debug: ISCC initialization started
@07:14:50.8610 [ISCC] Debug: Location localLocation/0/0/0 is created
@07:14:50.8610 [ISCC] Debug: {server localServer {role primary} {state 0} {device 0} {created}}
@07:14:50.8610 [ISCC] Debug: [connection 00f16c68] Connection [0]0 is created
@07:14:50.8610 [ISCC] Debug: {server localServer {role backup} {state 0} {device 0} {role changed}}
@07:14:50.8610 [ISCC] Debug: Client location ISCC:00000001@ added
@07:14:50.8610 [ISCC] Debug: ISCC initialization finished
SIP Server, Version: 7.5.000.37 Compiled: Sep 18 2007 13:09:42
Genesys Telecommunications Laboratories, Inc., Copyright 1991 - 2007
Build with Genesys SIP Library 7.5.002.18
Build w/ UK Framework (TSPP) 7.5.003.06
Build with TServerCommonPart 7.5.010.02
High Availability feature: ON
ISCC feature support: ON
Genesys Common Library SE: 7.5.000.33 C2
TServer Library (TLib): 7.5.005.01 HA
gmessage library: 7.5.000.01
gservice library: 7.5.000.04
gthread library: 7.5.000.01
Config Server support: CfgLib 7.5.000.16
LCA support: LCALib 7.5.000.06
License support: GLMLib 7.5.000.00 MT (FLEXLm 8.3c)
Message Server support: LogLib 7.5.000.05
Nonstop Operation: NSOLib 7.5.000.00 __try/except
SNMP support: MngmLib 7.5.000.00
07:14:50.861 Std 05060 Application started
@07:14:50.8610 [TCONF] Debug: Registering CfgSwitch tenant=101
@07:14:50.8610 [TCONF] Debug: Registering CfgDN tenant=101
@07:14:50.8610 [TCONF] Debug: Registering CfgApplication tenant=101
@07:14:50.8610 [TCONF] Debug: Registering CfgHost tenant=0
@07:14:50.8610 [TCONF] Debug: Registering CfgTenant tenant=0
@07:14:50.8610 [TCONF] Debug: Registering CfgAgentLogin tenant=101
TServer list
list <+>
compatibility-port = 0
management-port = 0
license list <+>
num-of-licenses = 0 <+>
num-sdn-licenses = max
tenant-profile list <+>
check-tenant-profile = false
@tenant-name = 'Resources' <+>
@tenant-password = NULL
server-id = 216 <D+>
customer-id = NULL
background-processing = false
background-timeout = '60 msec'
extrouter list <+>
reconnect-tout = '5 sec'
request-tout = '20 sec'
timeout = '1 min' <+>
report-connid-changes = false
match-call-once = true
network-request-timeout = '20 sec'
use-data-from = active
tcs-use = never
tcs-queue = NULL
cast-type = 'route direct-callid reroute direct-uui direct-ani direct-notoken dnis-pool direct-digits pullback route-uui direct-network-callid'
register-tout = '2 sec'
register-attempts = 5
default-dn = NULL
route-dn = NULL
dn-for-unexpected-calls = NULL
use-implicit-access-numbers = false
direct-digits-key = 'CDT_Track_Num'
cdt-udata-key -- alias for direct-digits-key
resource-load-maximum = 0
resource-allocation-mode = circular
cof-feature = false
cof-ci-req-tout = '500 msec'
cof-rci-tout = '10 sec'
cof-ci-wait-all = false
cof-ci-defer-delete = '0'
cof-ci-defer-create = '0'
event-propagation = list
inbound-translator-%d array
backup-sync list
network-provided-address = false
report-network-supplied-backup-server-address -- alias for network-provided-address
sync-reconnect-tout = '20 sec'
protocol = 'default'
addp-timeout = 0
addp-remote-timeout = 0
addp-trace = 'off'
@server-name = 'TServer_Asterisk' <+>
@location = 'Asterisk_Test' <+>
consult-user-data = separate
user-data-limit = 16000
merged-user-data = main-only
merge-consult-data -- alias for merged-user-data
ani-distribution = inbound-calls-only
call-cleanup list
notify-idle-tout = '0'
cleanup-idle-tout = '0'
periodic-check-tout = '10 min'
agent-reservation list <+>
request-collection-time = '100 msec'
reservation-time = '10 sec' <+>
reject-subsequent-request = true
expire-call-tout = 60
expire-call-tmout -- alias for expire-call-tout
unknown-xfer-merge-udata = false
agent-group = NULL
agent-strict-id = false
legal-guard-time = 0
untimed-wrap-up-value = 1000
inbound-bsns-calls = false
outbound-bsns-calls = false
internal-bsns-calls = false
unknown-bsns-calls = false
timed-acw-in-idle = true
timed-cwk-in-idle -- alias for timed-acw-in-idle
acw-in-idle-force-ready = true
cwk-in-idle-force-ready -- alias for acw-in-idle-force-ready
inherit-bsns-type = false
backwds-compat-acw-behavior = false
override-switch-acw = false
extn-no-answer-timeout = 15
posn-no-answer-timeout = 15
agent-no-answer-timeout = 15
extn-no-answer-overflow = NULL
posn-no-answer-overflow = NULL
agent-no-answer-overflow = NULL
agent-no-answer-action = none
nas-private = false
recall-no-answer-timeout = 15
prd-dist-call-ans-time = 0
max-pred-req-delay = 3
accept-dn-type = 0x11F = +extension +position +acdqueue +routedn +trunk +routequeue
default-dn-type = none
dn-del-mode = 'never'
link-control list
reg-delay = 10
reg-silent = true
emulate-login = on-RP
emulated-login-state = ready
wrap-up-time = '0'
sync-emu-agent = off
rq-expire-tmout = 32000
call-rq-gap = 0
sip-port = 5060
predictive-call-router-timeout = 20
cpd-info-timeout = 3
sip-enable-moh = false
sip-hold-rfc3264 = false
default-dn = NULL
router-timeout = 10
after-routing-timeout = 10
external-registrar = NULL
internal-registrar-enabled = true
internal-registrar-domains = NULL
enforce-external-domains = NULL
make-call-alert-info = ''
sip-enable-100rel = true
ringing-on-route-point = true
divert-on-ringing = true
am-detected = drop
fax-detected = drop
session-refresh-interval = 1800
sip-enable-sdp-encryption-removal = false
sip-enforce-sdp-origin-rules = false
sip-enable-sdp-application-filter = false
sip-enable-sdp-codec-filter = false
audio-codecs = 'telephone-event,PCMU,PCMA,G723,G729,GSM'
dtmf-payload = 101
max-legs-per-sm = 0
default-music = 'music/on_hold'
ring-tone = 'music/ring_back'
busy-tone = 'music/busy_5sec'
fast-busy-tone = 'music/atb_5sec'
silence-tone = 'music/silence'
collect-tone = 'music/collect'
default-video-file = ''
music-in-queue-file = ''
override-to-on-divert = false
internal-registrar-persistent = false
mwi-host = NULL
mwi-port = 5060
mwi-extension-enable = false
mwi-agent-enable = false
mwi-group-enable = false
mwi-domain = NULL
sip-treatments-continuous = false
find-trunk-by-location = false
INVITE list
extensions-%d array
userdata-%d array
UPDATE list
extensions-%d array
userdata-%d array
INFO list
extensions-%d array
userdata-%d array
cancel-monitor-on-disconnect = true
logout-on-disconnect = true
intrusion-enabled = true
monitor-internal-calls = true
default-monitor-scope = call
default-monitor-mode = mute
sip-address = NULL
sip-sync-local-contact = NULL
sip-sync-peer-contact = NULL
sip-disable-3pcc-on-sync-calls = false
subscription-timeout = 180
registrar-default-timeout = 1800
sip-invite-timeout = 0
sip-invite-treatment-timeout = 0
emergency-recording-filename = NULL
recording-filename = NULL
sip-refer-to-sst-enabled = true
event-ringing-on-100trying = false
preview-expired = 20
forced-notready = true
emergency-recording-cleanup-enabled = false
sip-call-id-in-tevent = false
sip-call-retain-timeout = 1
log-trace-flags = 0x6C00000 = +iscc +cfg$dn -cfgserv +passwd +udata -devlink -sw -req -callops -conn -client
backup-server-%d array
remote-server-%d array
AgentLogin array
DN array
77900 [379] dn = '77900' type = Extension xtype = DN cfgtype = 1 contact = 'sip:10.41.142.49:5060' refer-enabled = false sip-hold-rfc3264 = true make-call-rfc3725-flow = 1 reuse-sdp-on-reinvite = true dual-dialog-enabled = false subscribe-presence = 'asterisk' reg-mode = 0x21 = +force
77940_TestRoute [380] dn = '77940' type = RtQueue xtype = RouteDN cfgtype = 4 reg-mode = 0x1 = +force
Asterisk Monitor Trunk [381] dn = 'asterisk' type = TRUNK xtype = Trunk cfgtype = 14 contact = 'sip:10.41.142.49:5060' subscribe-presence-from = '<sip:gsip@owgenah25:5060>' subscribe-presence-expire = 120 reg-mode = 0x1 = +force
DN/EXR array
[-1] cdn = 'direct' type = N/A reg-mode = 0x0 =
access-list array
linked-resources array
Connecting to LCA at port 4999...
Open LCALayer on port=4999
LCA Library version 7.5.000.06
GLM_CO(tserver_sdn:switch_105,0,1)=0 count=400
07:14:50.923 Std 07101 Feature 'tserver_sdn': 400 licenses checked out
LICENSE: seat=400
GLM_CO(tserver_iscc:switch_105,1,1)=-97
LICENSE: tserver_iscc feature not available (GLMR_LICENSE_NO_LICENSE)
07:14:50.923 {listener-set.00a4f460 {initialize}}
07:14:50.923 {listener-set.00a4f460 {on-port-created {port-id default}}}
07:14:50.923 {listener.01667910 {created} {port-id default}}
07:14:50.923 {listener.01667910 {open}}
@07:14:50.9230 [TSCP] Debug: Port parameters: ("name"="TServer_Asterisk","server-type"="1","backlog"="5","port"="3020","id"="default","name"="TLib:7.5.005.01 HA
SIP Server, Version: 7.5.000.37 Compiled: Sep 18 2007 13:09:42
Genesys Telecommunications Laboratories, Inc., Copyright 1991 - 2007
Build with Genesys SIP Library 7.5.002.18
")
07:14:50.923 Std 04525 Port 3020 opened for listening
07:14:50.923 {listener.01667910 {open complete} {socket 364}}
07:14:50.923 {listener-set.00a4f460 {on-port-created complete}}
07:14:50.923 {listener-set.00a4f460 {initialize complete}}
07:14:50.923 FrameworkUK version: 7.5.003.06
07:14:50.923 +++ device_configure +++
+++ Creating devices +++
== geo-location: After Exclude 016c49a4
@07:14:50.9230 [0] 7.5.000.37 distribute_event: message EventDNBackInService
AttributeEventSequenceNumber 0000000000000001
AttributeTimeinuSecs 923000
AttributeTimeinSecs 1198844090 (07:14:50)
AttributeThisDN 'direct'
--- Creating devices ---
07:14:50.923 --- device_configure ---
ISCC feature configuration error
07:14:50.923 +++ device_open +++
-- CIFace::StartHandler()
CIFace::Signal:Stop->Init
CIFace::LinkInit
SIPTS_IFace::Start()
sipcs: SIP Listener TCP ...
CGCL2Listener[376]: Port 5060 opened for listening, protocol TCP
sipcs: CGSIPListener[376]:VIA("SIP/2.0/TCP 10.41.100.29:5060")
sipcs: CGSIPListener[376]:CONTACT("<sip:10.41.100.29:5060;transport=tcp>")
sipcs: SIP Listener UDP ...
CGCL2Listener[388]: Port 5060 opened for listening, protocol UDP
sipcs: CGSIPListener[388]:VIA("SIP/2.0/UDP 10.41.100.29:5060")
sipcs: CGSIPListener[388]:CONTACT("<sip:10.41.100.29:5060>")
sipcs: SIP Transaction Manager ...
sipcs: SIP Message Factory ...
sipcs: ParseOptionCodecs: new codecs configured: 'telephone-event,PCMU,PCMA,G723,G729,GSM'
sipcs: ParseOptionCodecs: codec 'telephone-event' unknown - ignored.
[DDP]:configure: SIP Stack initialized CIFace::Signal:Init->Starting
07:14:50.939 Trc 35100 CTI link unnamed starting
TModel::LinkStarting: link 0
CIFace::ReRegister
CIFace::Signal:Starting->Up
CIFace::ServiceUp
+++ CIFace::Event +++
+++ Pre-event +++
Type EventLinkConnected
Devices: <-/-> <-/-> <-/->
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
--- Pre-event ---
TModel::LinkUp: link 0
@07:14:50.9390 [0] 7.5.000.37 send_to_all: message EventLinkConnected
AttributeEventSequenceNumber 0000000000000002
AttributeServerStartTime 4774e8ba000e53f8 (07:14:50.939000)
AttributeTimeinuSecs 939000
AttributeTimeinSecs 1198844090 (07:14:50)
07:14:50.939 Std 20001 CTI Link connected
LCA_set_AppLiveStatus(APP_STATUS_RUNNING)
@07:14:50.9390 [0] 7.5.000.37 distribute_event: message EventDNBackInService
AttributeEventSequenceNumber 0000000000000003
AttributeTimeinuSecs 939000
AttributeTimeinSecs 1198844090 (07:14:50)
AttributeThisDN 'direct'
@07:14:50.9390 [0] 7.5.000.37 distribute_event: message EventDNBackInService
AttributeEventSequenceNumber 0000000000000004
AttributeTimeinuSecs 939000
AttributeTimeinSecs 1198844090 (07:14:50)
AttributeThisDN 'asterisk'
@07:14:50.9390 [0] 7.5.000.37 distribute_event: message EventDNBackInService
AttributeEventSequenceNumber 0000000000000005
AttributeTimeinuSecs 939000
AttributeTimeinSecs 1198844090 (07:14:50)
AttributeThisDN '77940'
@07:14:50.9390 [0] 7.5.000.37 distribute_event: message EventDNBackInService
AttributeEventSequenceNumber 0000000000000006
AttributeTimeinuSecs 939000
AttributeTimeinSecs 1198844090 (07:14:50)
AttributeThisDN '77900'
+++ Post-event +++
Type EventLinkConnected
Devices: <-/-> <-/-> <-/->
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
--- Post-event ---
--- CIFace::Event ---
sipcs: MWIMNGR:STARTED:Checking pending MWI ...
sipcs: PRESMNGR:START:Initiating Presence Subscriptions ...
sipcs: PRESMGR:START:WARNING: DN="77900" - Unable to start subscription dialog
@07:14:50.9390 [ISCC] Trace: SD message handler is set
07:14:50.939 --- device_open ---
07:14:50.939 Std 05061 Initialization completed
LCA_set_AppLiveStatus(APP_STATUS_RUNNING)
@07:14:50.9390 [TCONF] Debug: Application type [MessageServer] is skipped for the Remote Server
07:14:50.939 Trc 04541 Registered received from 196 (CfgServer)
************************************
LCALayer: REventRegistered on LCA...
************************************
07:14:50.939 Trc 04541 Registered received from 196 (CfgServer)
07:14:50.939 Trc 04541 Registered received from 196 (CfgServer)
07:14:50.939 Trc 04541 Registered received from 196 (CfgServer)
07:14:50.939 Trc 04541 Registered received from 196 (CfgServer)
07:14:50.939 Trc 04541 Registered received from 196 (CfgServer)
AgentLogin array <+>
[118] login-id = '77900' <+> wrap-up-time = 0
@07:14:50.9390 [TCONF] Debug: Main Switch's Agent Logins are read. Number: 1
@07:14:50.9390 [TCONF] Debug: Configuration is fully initialized
07:14:57.360 Trc 04521 New client 372 connected from '10.41.100.29'
07:14:57.360 {connection {accepted} {socket 372} {remote-host 10.41.100.29} {remote-port 2495} {listener {port-id default} {socket 364}}}
07:14:57.360 Trc 04541 RequestRegisterClient received from [372]
message RequestRegisterClient
AttributeProtocolVersion 'tserver protocol 4.2'
AttributeApplicationName 'URS'
AttributeApplicationPassword 'Resources/'
AttributeSessionID 0
07:14:57.360 Trc 04522 Client [372] authorized, name 'URS', id=00000002
07:14:57.360 -- created: CRequest@016D4958 RequestRegisterClient-URS[372]/-1
SIPS:LOGBLOCK:BEGIN:REQUEST:[
07:14:57.360 +++ CIFace::Request +++
-- new invoke
Parsed: RequestRegisterClient
From: URS[372]/-1
Numbers: -<none> -<none>
Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
-----
@07:14:57.3600 [0] 7.5.000.37 send_to_client: message EventLinkConnected
AttributeApplicationName 'TServer_Asterisk'
AttributeSessionID 14155777
AttributeUserData [2] 00 00..
AttributeRegistrationCode 0
AttributeEventSequenceNumber 0000000000000007
AttributeServerStartTime 4774e8ba000e53f8 (07:14:50.939000)
AttributeTimeinuSecs 360000
AttributeTimeinSecs 1198844097 (07:14:57)
07:14:57.360 Trc 04542 EventLinkConnected sent to [372] (00000002 URS 10.41.100.29:2495)
-- processed by client handler
07:14:57.360 --- CIFace::Request ---
SIPS:LOGBLOCK:END:REQUEST:]
07:14:57.360 -- deleted: CRequest@016D4958 RequestRegisterClient-URS[372]/-1
07:14:57.360 Trc 04541 RequestQueryServer received from [372] (00000002 URS 10.41.100.29:2495)
message RequestQueryServer
AttributeReferenceID 27466
@07:14:57.3600 [0] 7.5.000.37 send_to_client: message EventServerInfo
AttributeEventSequenceNumber 0000000000000008
AttributeTimeinuSecs 360000
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeReferenceID 27466
AttributeExtensions [304] 00 02 00 00..
'T-Server' 'SIP Server, Version: 7.5.000.37 Compiled: Sep 18 2007 13:09:42
Genesys Telecommunications Laboratories, Inc., Copyright 1991 - 2007
Build with Genesys SIP Library 7.5.002.18
'
'Features' '
Build with TServerCommonPart 7.5.010.02
High Availability feature: ON
ISCC feature support: ON
'
AttributeServerXCapabilities '(2=(56=396273),8=(16=7),10=(16=15),11=(16=25),14=(73=1),36=(60=131),75=(16=1),76=(16=25),108=(74=2),113=(83=132604),160=(146=1),161=(146=1),162=(146=1))'
AttributeServerCapabilityMask [64] FF FF FE E6..
AttributeHomeLocation 'Asterisk_Test'
AttributeApplicationName 'TServer_Asterisk'
AttributeServerRole 0
AttributeServerVersion '7.5.000.37'
07:14:57.360 Trc 04542 EventServerInfo sent to [372] (00000002 URS 10.41.100.29:2495)
07:14:57.360 Trc 04541 RequestRegisterAddress received from [372] (00000002 URS 10.41.100.29:2495)
message RequestRegisterAddress
AttributeThisDN 'exr'
AttributeRegisterMode 0
AttributeControlMode 2
AttributeAddressType 0 (Unknown)
AttributeExtensions [51] 00 02 00 00..
'SWITCH' 'Asterisk_Test'
'CUSTOMER_ID' 'Resources'
AttributeReferenceID 27467
07:14:57.360 -- created: CRequest@016D4958 RequestRegisterAddress-URS[372]/27467
SIPS:LOGBLOCK:BEGIN:REQUEST:[
07:14:57.360 +++ CIFace::Request +++
-- new invoke
07:14:57.360 Std 36211 Client 2(URS): this device (exr) invalid in request
Parsed: RequestRegisterAddress
From: URS[372]/27467
Numbers: -<exr> -<none>
Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
-----
-- taken by TRegManager
07:14:57.360 --- CIFace::Request ---
SIPS:LOGBLOCK:END:REQUEST:]
07:14:57.360 Trc 04541 RequestRegisterAddress received from [372] (00000002 URS 10.41.100.29:2495)
message RequestRegisterAddress
AttributeThisDN '77940'
AttributeRegisterMode 0
AttributeControlMode 0
AttributeAddressType 4 (RouteDN)
AttributeExtensions [51] 00 02 00 00..
'SWITCH' 'Asterisk_Test'
'CUSTOMER_ID' 'Resources'
AttributeReferenceID 27468
(tservice_proc) ControlMode set to 1 for 77940
07:14:57.360 -- created: CRequest@016DD998 RequestRegisterAddress-URS[372]/27468
SIPS:LOGBLOCK:BEGIN:REQUEST:[
07:14:57.360 +++ CIFace::Request +++
-- new invoke
Parsed: RequestRegisterAddress
From: URS[372]/27468
Numbers: +<77940> -<none>
Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
-----
@07:14:57.3600 [0] 7.5.000.37 send_to_client: message EventRegistered
AttributeEventSequenceNumber 0000000000000009
AttributeTimeinuSecs 360000
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeReferenceID 27468
AttributeThisDN '77940'
AttributeExtensions [17] 00 01 01 00..
'status' 0
AttributeAddressInfoStatus 4
AttributeAddressInfoType 8 (AddressInfoAddressType)
AttributeAddressType 4 (RouteDN)
07:14:57.360 Trc 04542 EventRegistered sent to [372] (00000002 URS 10.41.100.29:2495)
TRegManager::ClientRegistered 2 77940
-- processed by client handler
07:14:57.360 --- CIFace::Request ---
SIPS:LOGBLOCK:END:REQUEST:]
07:14:57.360 -- deleted: CRequest@016DD998 RequestRegisterAddress-URS[372]/27468
07:14:57.360 Trc 04541 RequestDistributeEvent received from [372] (00000002 URS 10.41.100.29:2495)
message RequestDistributeEvent
AttributeUserEvent EventUserEvent
AttributeCustomerID 'Resources'
AttributeThisDN 'exr'
AttributeUserData [80] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS'
'VERSION' 'Version: 7.5.001.05'
'CLUSTER' 'URS'
AttributeExtensions [80] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS'
'VERSION' 'Version: 7.5.001.05'
'CLUSTER' 'URS'
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeTimeinuSecs 360
07:14:57.360 Int 04543 Interaction message "RequestDistributeEvent" received from 372 ("URS")
@07:14:57.3600 [0] 7.5.000.37 send_to_client: message EventACK
AttributeEventSequenceNumber 000000000000000a
AttributeTimeinuSecs 360000
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeThisDN 'exr'
AttributeUserEvent RequestDistributeEvent
07:14:57.360 Trc 04542 EventACK sent to [372] (00000002 URS 10.41.100.29:2495)
@07:14:57.3600 [0] 7.5.000.37 distribute_user_event: message EventUserEvent
AttributeEventSequenceNumber 000000000000000b
AttributeTimeinuSecs 360
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeExtensions [80] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS'
'VERSION' 'Version: 7.5.001.05'
'CLUSTER' 'URS'
AttributeUserData [80] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS'
'VERSION' 'Version: 7.5.001.05'
'CLUSTER' 'URS'
AttributeThisDN 'exr'
AttributeCustomerID 'Resources'
AttributeUserEvent EventUserEvent
07:14:57.360 Trc 04541 RequestQueryLocation received from [372] (00000002 URS 10.41.100.29:2495)
message RequestQueryLocation
AttributeLocationInfoType 4
AttributeExtensions [51] 00 02 00 00..
'SWITCH' 'Asterisk_Test'
'CUSTOMER_ID' 'Resources'
AttributeReferenceID 27469
@07:14:57.3600 [ISCC] Debug: Client location URS:00000002@ added
@07:14:57.3600 [ISCC] Debug: Message EventError [Event (To Client):00000000] is queued
07:14:57.376 -- device reg timer up: exr
SIPS:LOGBLOCK:BEGIN:REQUEST:[
07:14:57.376 +++ CIFace::Request +++
-- re-invoke 016D4958
Parsed: RequestRegisterAddress
From: URS[372]/27467
Numbers: +<exr> -<none>
Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
-----
@07:14:57.3760 [0] 7.5.000.37 send_to_client: message EventRegistered
AttributeEventSequenceNumber 000000000000000c
AttributeTimeinuSecs 376000
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeReferenceID 27467
AttributeThisDN 'exr'
AttributeExtensions [17] 00 01 01 00..
'status' 128
AttributeAddressInfoStatus 0
AttributeAddressInfoType 8 (AddressInfoAddressType)
AttributeAddressType 0 (Unknown)
07:14:57.376 Trc 04542 EventRegistered sent to [372] (00000002 URS 10.41.100.29:2495)
TRegManager::ClientRegistered 2 exr
-- processed by client handler
07:14:57.376 --- CIFace::Request ---
SIPS:LOGBLOCK:END:REQUEST:]
07:14:57.376 -- deleted: CRequest@016D4958 RequestRegisterAddress-URS[372]/27467
@07:14:57.3760 [0] 7.5.000.37 distribute_event: message EventAddressInfo
AttributeEventSequenceNumber 000000000000000d
AttributeTimeinuSecs 376000
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeAddressInfoStatus 0
AttributeAddressInfoType 8 (AddressInfoAddressType)
AttributeAddressType 0 (Unknown)
AttributeThisDN 'exr'
07:14:57.376 Trc 04542 EventAddressInfo sent to [372] (00000002 URS 10.41.100.29:2495)
@07:14:57.3760 [0] 7.5.000.37 distribute_event: message EventDNBackInService
AttributeEventSequenceNumber 000000000000000e
AttributeTimeinuSecs 376000
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeThisDN 'exr'
07:14:57.376 Trc 04542 EventDNBackInService sent to [372] (00000002 URS 10.41.100.29:2495)
@07:14:57.3760 [ISCC] Debug: Message EventError [Event (To Client):00000000] is dequeued
@07:14:57.3760 [0] 7.5.000.37 send_to_client: message EventError
(Extrouter feature is not initiated)
AttributeEventSequenceNumber 000000000000000f
AttributeTimeinuSecs 376000
AttributeTimeinSecs 1198844097 (07:14:57)
AttributeReferenceID 27469
AttributeErrorCode 1005
07:14:57.376 Int 04545 Interaction message "EventError" sent to 372 ("URS")
07:14:57.376 Trc 04542 EventError sent to [372] (00000002 URS 10.41.100.29:2495)
@07:14:57.3760 [ISCC] Debug: Message [Event (To Client):00000000] is deleted
07:19:25.124 Trc 04541 Message ObjectInfoChanged received from 196 (CfgServer '')
@07:19:25.1240 [TCONF] Debug: Main Switch's [105] DN changed: DBID [379], number [77900]
tserv_dn_changed(77900)
sipcs: PRESMGR:STOP:WARNING: DN="77900" - MONITORING NOT STARTED
sipcs: PRESMGR:START:WARNING: DN="77900" - Unable to start subscription dialog
@07:19:25.1240 [TCONF] Debug: Changed in DN array
77900 [379] dn = '77900' type = Extension xtype = DN cfgtype = 1 contact = '10.41.142.49:5060' <+> refer-enabled = false sip-hold-rfc3264 = true make-call-rfc3725-flow = 1 reuse-sdp-on-reinvite = true dual-dialog-enabled = false subscribe-presence = 'asterisk' reg-mode = 0x21 = +force
07:39:46.507 Trc 04541 Message ObjectInfoChanged received from 196 (CfgServer '')
@07:39:46.5070 [TCONF] Debug: Main Switch changed: DBID [105], name [Asterisk_Test]
tserv_dn_changed(77900-77999)
07:39:46.507 DN 77900-77999 is range
sipcs: PRESMGR:STOP:WARNING: DN="77900-77999" - MONITORING NOT STARTED
tserv_reconfig(DNSetChanged)
08:14:50.800 Trc 04120 Check point 2007-12-28T08:14:50
09:14:50.816 Trc 04120 Check point 2007-12-28T09:14:50
09:53:06.193 Trc 04541 Message ObjectInfoChanged received from 196 (CfgServer '')
@09:53:06.1930 [TCONF] Debug: Main Switch's [105] DN changed: DBID [379], number [77900]
tserv_dn_changed(77900)
sipcs: PRESMGR:STOP:WARNING: DN="77900" - MONITORING NOT STARTED
sipcs: PRESMGR:START:WARNING: DN="77900" - Unable to start subscription dialog
@09:53:06.1930 [TCONF] Debug: Changed in DN array <+>
77900 [379] dn = '77900' type = Extension xtype = DN cfgtype = 1 contact = 'sip:10.41.142.49:5060' <+> refer-enabled = false sip-hold-rfc3264 = true make-call-rfc3725-flow = 1 reuse-sdp-on-reinvite = true dual-dialog-enabled = false subscribe-presence = 'asterisk' reg-mode = 0x21 = +force
10:10:10.940 Trc 04541 Message ObjectInfoChanged received from 196 (CfgServer '')
@10:10:10.9400 [TCONF] Debug: Main Switch changed: DBID [105], name [Asterisk_Test]
tserv_dn_deleted(77900-77999)
sipcs: MWIMNGR:STOP:WARNING: DN="77900-77999", Type=1 - NOT MONITORED
sipcs: PRESMGR:STOP:WARNING: DN="77900-77999" - MONITORING NOT STARTED
sipcs: DNDeletedCME[0x00000000]:DN=77900-77999;DELETING DEVICE
tserv_reconfig(DNSetChanged)
10:14:50.820 Trc 04120 Check point 2007-12-28T10:14:50
