Vocemail läst sich nicht abfragen - Update Officemaster 6

[cheesy33]

Hallo Formum,

Wir haben von Officemaster 5.21 auf OfficeMaster 6.01 upgedatet.

Bisher Funktionierte die Dienstauswahl für die Mailboxabfrage mit folgendem Eintrag:

Info Element: Called Party Number
Filter: Pilotnummer
Dienst: Voice
Voice-Server: voice0
Voice-Connector: msx2kgate0
Projekt: Voicebox über Pilot-ID
Sprache: deutsch(de)


Seit dem Update wird der Anruf entweder abgewiesen oder landet in absoluter Stille.

Hier ist mal ein Ausschnitt aus dem Voice-Log:

(784) 16/06/21 13:26:39 - INFO: [0] VoicePath is 'audio\de\'
(784) 16/06/21 13:26:39 - INFO: [0] --> EVENT_PRE_OPEN STATE_BASE
(784) 16/06/21 13:26:39 - INFO: [0] SetUserInfoReady ok=1 OffhookonAnswer=0
(784) 16/06/21 13:26:39 - INFO: [0] <-- STATE_BASE
(784) 16/06/21 13:26:39 - INFO: [0] --> EVENT_OPEN STATE_BASE
(784) 16/06/21 13:26:39 - INFO: [0] IvrBasePath is 'c:\programdata\ffums\fmsrv/data/voice/'
(784) 16/06/21 13:26:39 - INFO: [0] ProjectPath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\'
(784) 16/06/21 13:26:39 - INFO: [0] VoicePath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\audio\de\'
(784) 16/06/21 13:26:39 - INFO: [0] IvrPath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\'
(784) 16/06/21 13:26:39 - INFO: [0] <-- STATE_IVR
(784) 16/06/21 13:26:39 - INFO: [0] --> EVENT_OPEN STATE_IVR
(784) 16/06/21 13:26:39 - INFO: [0] IvrChangeState...Base:'' Name: ''
(784) 16/06/21 13:26:39 - INFO: [0] IvrChangeState...OldPath:'c:\programdata\ffums\fms rv/data/voice/projectvoxdidcpn\'
(784) 16/06/21 13:26:39 - INFO: [0] check for 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\*.lua'
(784) 16/06/21 13:26:39 - INFO: [0] LUA: ==> EVENT_OPEN ( 1) STATE=' ' ('c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua')
(784) 16/06/21 13:26:39 - INFO: [0] GetUserInfoReady loaded=1 foam=1 gateway=1
(784) 16/06/21 13:26:39 - INFO: [0] CmdIsGatewayReady
(784) 16/06/21 13:26:39 - INFO: [0] UserInfoAvailable
(784) 16/06/21 13:26:39 - INFO: [0] CmdSetTimer: Timeout='1' PlayListSync=0
(784) 16/06/21 13:26:39 - INFO: [0] LUA: <== EVENT_WAITFORNEXTEVENT( 28) STATE='projectVoxDidCpn:wait ' ('c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua') false at 4079710
(784) 16/06/21 13:26:39 - INFO: [0] <-- STATE_IVR
(280) 16/06/21 13:26:39 - INFO: [0] VoicePath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\audio\de\'
(3988) 16/06/21 13:26:39 - INFO: [0] --> EVENT_TIMER STATE_IVR
(3988) 16/06/21 13:26:39 - INFO: [0] IvrChangeState...Base:'' Name: ''
(3988) 16/06/21 13:26:39 - INFO: [0] IvrChangeState...OldPath:'c:\programdata\ffums\fms rv/data/voice/projectvoxdidcpn\'
(3988) 16/06/21 13:26:39 - INFO: [0] check for 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\*.lua'
(3988) 16/06/21 13:26:39 - INFO: [0] LUA: ==> EVENT_TIMER ( 15) STATE='projectVoxDidCpn:wait ' ('c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua')
(3988) 16/06/21 13:26:39 - ERROR: [0] LUA: ...srv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua:12: attempt to compare nil with number

(3988) 16/06/21 13:26:39 - INFO: [0] HangUpCall: start
(3988) 16/06/21 13:26:39 - INFO: [0] LUA: <== EVENT_NOEVENT ( 0) STATE=' ' ('c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua') false at 4080070
(3988) 16/06/21 13:26:39 - INFO: [0] <-- STATE_IVR
(3032) 16/06/21 13:26:39 - INFO: [0] SetUserInfoReady ok=1 OffhookonAnswer=0

Kann mir hier jemand weiterhelfen?

Gruß

Cheesy

[Chris Helbing]

Hallo Cheesy,

ein einfacher, wenn auch interessanter Fehler in den Scripten. Es scheint ein Initialisierungsproblem bei einer Variable zu geben, daher kommt nun diese Meldung und das entsprechende Fehlverhalten.

Für das kommende Release 6.0.2 haben wir diese Stelle bereits gefixt, solange bitte einfach die Projektdatei unter "..\data\voice\projectvoxdidcpn" austauschen.

VG

[cheesy33]

Hallo,

Vielen Dank für die Hilfe.
Jetzt komme ich zwar auf die Voicebox, aber es kommt nicht die Abfrage und Konfiguration, sondern die Ansage für den Anrufer.

Gruß

Cheesy

[cheesy33]

Hallo,

Die andere Frage wäre, wann steht 6.0.2 wohl zur Verfügung? Wenn dort das Problem gefixt ist, wäre mir ja auch geholfen.

Gruß

Cheesy

[Chris Helbing]

Das Release ist nun verfügbar. Was die Abfrage der Konfiguration betrifft, denke ich,dass es sich um eine Herausforderung mit den abfrageberechtigten Rufnummern handelt.

[cheesy33]

Hallo,

Nachdem die 6.0.0.2 draussen ist, habe ich mir noch einmal die Mühe gemacht mein Backup von 5.3 einzuspielen und die 6er neu zu installieren.
Leider tritt das Problem wieder auf:

(2628) 16/06/28 13:07:08 - DEBUG: [255] rx: HT_ID_REQ ack needed
(2628) 16/06/28 13:07:08 - DEBUG: [1959522958] HT_ID_REQ
(2628) 16/06/28 13:07:08 - DEBUG: AllocNewIndex(): enter critical section at: 5152178
(2628) 16/06/28 13:07:08 - DEBUG: AllocNewIndex(): voice vars: id: 0, inUse: 0, Commid: -1, IsOutgoing: 0, TransferIndex: -1
(2628) 16/06/28 13:07:08 - DEBUG: [0] Alloc Index
(2628) 16/06/28 13:07:08 - DEBUG: AllocNewIndex(): leave critical section after 0 ms
(2628) 16/06/28 13:07:08 - DEBUG: [255] rx: HT_ID_REQ ack needed
(2628) 16/06/28 13:07:08 - DEBUG: [libagent] ExchangeMsg(): send cfg server message on port = 3216
(2628) 16/06/28 13:07:08 - DEBUG: [0] Set Project to 'projectvoxdidcpn'
(2628) 16/06/28 13:07:08 - DEBUG: [0] Set Language to 'de'
(2628) 16/06/28 13:07:08 - INFO: [0] VoicePath is 'audio\de\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] tx: HT_ID_ACK
(2628) 16/06/28 13:07:08 - DEBUG: [0] after send. Send 520 bytes.
(2628) 16/06/28 13:07:08 - DEBUG: [0] rx: HT_ID_OK
(2628) 16/06/28 13:07:08 - DEBUG: [0] started packet loss information
(2628) 16/06/28 13:07:08 - DEBUG: [0] is not callback
(2628) 16/06/28 13:07:08 - DEBUG: [0] send EVENT_PRE_OPEN (1)
(2628) 16/06/28 13:07:08 - INFO: [0] --> EVENT_PRE_OPEN STATE_BASE
(2628) 16/06/28 13:07:08 - INFO: [0] SetUserInfoReady ok=1 OffhookonAnswer=0
(2628) 16/06/28 13:07:08 - INFO: [0] <-- STATE_BASE
(2628) 16/06/28 13:07:08 - DEBUG: [0] leave StateMaschine:STATE_BASE | time: 5152194
(2628) 16/06/28 13:07:08 - DEBUG: [0] VoiceActive IsFax=0,TransferNumber='',UserInfoAvailable=1
(2628) 16/06/28 13:07:08 - DEBUG: [0] send EVENT_OPEN (4)
(2628) 16/06/28 13:07:08 - INFO: [0] --> EVENT_OPEN STATE_BASE
(2628) 16/06/28 13:07:08 - DEBUG: [0] Get Project
(2628) 16/06/28 13:07:08 - DEBUG: [0] the ProjectPath is 'c:\programdata\ffums\fmsrv/data/voice/'
(2628) 16/06/28 13:07:08 - DEBUG: [0] the GreetingPath is 'c:\programdata\ffums\fmsrv/data/voice/\'
(2628) 16/06/28 13:07:08 - INFO: [0] IvrBasePath is 'c:\programdata\ffums\fmsrv/data/voice/'
(2628) 16/06/28 13:07:08 - INFO: [0] ProjectPath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\'
(2628) 16/06/28 13:07:08 - INFO: [0] VoicePath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\audio\de\'
(2628) 16/06/28 13:07:08 - INFO: [0] IvrPath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\'
(2628) 16/06/28 13:07:08 - INFO: [0] <-- STATE_IVR
(2628) 16/06/28 13:07:08 - INFO: [0] --> EVENT_OPEN STATE_IVR
(2628) 16/06/28 13:07:08 - DEBUG: [0] Delete Playlist
(2628) 16/06/28 13:07:08 - DEBUG: [0] DeletePromptPlayList
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...Base:'' Name: ''
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...OldPath:'c:\programdata\ffums\fms rv/data/voice/projectvoxdidcpn\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] IvrPath is 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: IvrChangeState() at 5152194
(2628) 16/06/28 13:07:08 - INFO: [0] check for 'c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\*.lua'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: init.lua
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: init.lua::main() requesting state (5152194)
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: user.lua
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: ==> EVENT_OPEN ( 1) STATE=' ' ('c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua')
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdStoreNumberAsDid
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdStoreNumberAsDid: store CallingPartyNumber(99289) as DID
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdStoreNumberAsDid: new did is '99289'
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].Connected=1
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].Loaded=1
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].Foam=1
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].Type=20
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].Whichdata=1
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].Whichuser=1
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].FoamInitString=tcp/49317/127.0.0.1
(2628) 16/06/28 13:07:08 - DEBUG: gwsubs[1].ComponentName=msx2kgate0
(2628) 16/06/28 13:07:08 - INFO: [0] GetUserInfoReady loaded=1 foam=1 gateway=1
(2628) 16/06/28 13:07:08 - DEBUG: (GW:1) snd
(
STRING MessageType rpc ;
STRING Function GetUserInfo ;
STRING CalledPartyNumber 99289 ;
STRING CallingPartyNumber 99289 ;
LONG UserLong 0 ;
LONG idx 0 ;
STRING ComponentName voice0 ;
BOOL GetAvailability 1 ;
)

(648) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmGetUserLong ;
LONG idx 0 ;
)

(648) 16/06/28 13:07:08 - DEBUG: snd
(
ULONG SmGetUserLong 0 ;
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(2628) 16/06/28 13:07:08 - DEBUG: (GW:1) rcv at 5152194
(
LONG Status 0 ;
BOOL SupportAsyncFileTransfer 1 ;
LONG GetUserInfo 1 ;
)

(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: SetFmsState(): '' -> 'eVoice_projectStart' (5152194)
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGotoNode: NextNode='\\eVoice_projectStart' at 5152194
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGotoNode: NoCmdPlay='0'
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGotoNode: Reinit=''
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...Base:'' Name: '\\eVoice_projectStart'
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...OldPath:'c:\programdata\ffums\fms rv/data/voice/projectvoxdidcpn\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] IvrPath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectstart\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: returned EVENT_IVRGOTONODE (1018) at 5152210
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: <== EVENT_IVRGOTONODE ( 18) STATE='eVoice_projectStart ' ('c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua') false at 5152210
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: close script c:\programdata\ffums\fmsrv/data/voice/projectvoxdidcpn\projectvoxdidcpn.lua, ReenterWithEvent:EVENT_IVRGOTONODE at 5152210
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: vor IvrFindClose at 5152210
(2628) 16/06/28 13:07:08 - INFO: [0] <-- STATE_IVR
(2628) 16/06/28 13:07:08 - INFO: [0] --> EVENT_IVRGOTONODE STATE_IVR
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...Base:'' Name: ''
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...OldPath:'c:\programdata\ffums\fms rv/data/voice/evoice_projectstart\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] IvrPath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectstart\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: IvrChangeState() at 5152210
(2628) 16/06/28 13:07:08 - INFO: [0] check for 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectstart\*.lua'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: init.lua
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: init.lua::main() requesting state (5152210)
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGetVariables
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: user.lua
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: ==> EVENT_IVRGOTONODE ( 18) STATE='eVoice_projectStart ' ('c:\programdata\ffums\fmsrv/data/voice/evoice_projectstart\eVoice_projectStart.lua')
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: eVoice_projectStart.lua: eVoice_projectStart (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] CheckPin: Pin is not set!
(2628) 16/06/28 13:07:08 - INFO: [0] CmdCheckPin ret=EVENT_IVRNOK
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdIsAvailabilityInfo
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: found json '99289' file in user folder
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found non empty json file (5152210)
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a dayofweek range (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found a active dayofweek range
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a dayofweek range (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found no dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a single dayofweek (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found no dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a single dayofweek (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found a active single dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a single dayofweek (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found no dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a single dayofweek (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found no dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a single dayofweek (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found no dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a single dayofweek (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found no dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a single dayofweek (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: Found no dayofweek
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found a date range (5152210)
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found profile with uuid 'Dienstags_0a549683-de89-4d76-bbc4-4018dfed6051' and name 'Ansagen für dienstags' (5152210)
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: No valid element in profileAnsagen für dienstags
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Found profile with uuid 'Mo_Fr_96ccdfd2-fb4f-4fb5-a4ff-8b0556da8a45' and name 'Ansagen für mo-fr' (5152210)
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Do voicemail without record! (5152210)
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: SetFmsState(): 'eVoice_projectStart' -> 'eVoice_projectPlayAudio' (5152210)
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGotoNode: NextNode='\\eVoice_projectPlayAudio' at 5152210
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGotoNode: NoCmdPlay='0'
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGotoNode: Reinit=''
(2628) 16/06/28 13:07:08 - DEBUG: [0] -------------------------------------------------
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...Base:'' Name: '\\eVoice_projectPlayAudio'
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...OldPath:'c:\programdata\ffums\fms rv/data/voice/evoice_projectstart\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] IvrPath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: returned EVENT_IVRGOTONODE (1018) at 5152225
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: <== EVENT_IVRGOTONODE ( 18) STATE='eVoice_projectPlayAudio ' ('c:\programdata\ffums\fmsrv/data/voice/evoice_projectstart\eVoice_projectStart.lua') false at 5152225
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: close script c:\programdata\ffums\fmsrv/data/voice/evoice_projectstart\eVoice_projectStart.lua, ReenterWithEvent:EVENT_IVRGOTONODE at 5152225
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: vor IvrFindClose at 5152225
(2628) 16/06/28 13:07:08 - INFO: [0] <-- STATE_IVR
(2628) 16/06/28 13:07:08 - INFO: [0] --> EVENT_IVRGOTONODE STATE_IVR
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...Base:'' Name: ''
(2628) 16/06/28 13:07:08 - INFO: [0] IvrChangeState...OldPath:'c:\programdata\ffums\fms rv/data/voice/evoice_projectplayaudio\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] IvrPath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: IvrChangeState() at 5152225
(2628) 16/06/28 13:07:08 - INFO: [0] check for 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\*.lua'
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: init.lua
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: init.lua::main() requesting state (5152225)
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGetVariables
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: user.lua
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: ==> EVENT_IVRGOTONODE ( 18) STATE='eVoice_projectPlayAudio ' ('c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\eVoice_projectPlayAudio.lu a')
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: eVoice_projectPlayAudio.lua: eVoice_projectPlayAudio (5152225)
(2628) 16/06/28 13:07:08 - DEBUG: [0] the ProjectPath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdPlayPrompt
(2628) 16/06/28 13:07:08 - DEBUG: [0] the VoicePath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\audio\de\'
(2628) 16/06/28 13:07:08 - WARNING: [0] didn't find 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\audio\de\gap.raw' or 'C:\ProgramData\FFUMS\fmsrv/data/voice/users\99289\gap.raw' or 'C:\ProgramData\FFUMS\fmsrv/data/voice/audio\de\gap.raw'
(2628) 16/06/28 13:07:08 - DEBUG: [0] the VoicePath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\audio\de\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] FoamAddToPromptPlayList(): find 'C:\ProgramData\FFUMS\fmsrv/data/voice/users\99289\Standard.raw'
(2628) 16/06/28 13:07:08 - INFO: [0] CmdPlay: Timed file 'Standard.raw'
(2628) 16/06/28 13:07:08 - DEBUG: [0] the VoicePath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\audio\de\'
(2628) 16/06/28 13:07:08 - DEBUG: [0] FoamAddToPromptPlayList(): find 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\audio\de\empty.raw'
(2628) 16/06/28 13:07:08 - DEBUG: [0] Activate Playlist
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: Info: play init audiofile: Standard.raw (5152225)
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdGetVariables
(2628) 16/06/28 13:07:08 - DEBUG: [0] EnableAutoStopAndDelete(true)
(2628) 16/06/28 13:07:08 - DEBUG: [0] CmdActivatePlayList
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: SetFmsState(): 'eVoice_projectPlayAudio' -> 'eVoice_projectPlayAudio:EvtHandler' (5152225)
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: returned EVENT_WAITFORNEXTEVENT (1028) at 5152225
(2628) 16/06/28 13:07:08 - INFO: [0] LUA: <== EVENT_WAITFORNEXTEVENT( 28) STATE='eVoice_projectPlayAudio:EvtHandler' ('c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\eVoice_projectPlayAudio.lu a') false at 5152225
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: close script c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\eVoice_projectPlayAudio.lu a, ReenterWithEvent:EVENT_NOEVENT at 5152225
(2628) 16/06/28 13:07:08 - DEBUG: [0] LUA: vor IvrFindClose at 5152225
(2628) 16/06/28 13:07:08 - INFO: [0] <-- STATE_IVR
(2628) 16/06/28 13:07:08 - DEBUG: [0] leave StateMaschine:STATE_IVR | time: 5152225
(2628) 16/06/28 13:07:08 - DEBUG: [0] rx: HT_OFFHOOK
(2628) 16/06/28 13:07:08 - DEBUG: [0] rx: HT_DATA_REQ: first packet polled from box
(2628) 16/06/28 13:07:08 - INFO: [0] play prompt index=0 file='C:\ProgramData\FFUMS\fmsrv/data/voice/users\99289\Standard.raw'
(2628) 16/06/28 13:07:08 - DEBUG: [0] tx: HT_DROP_DATA
(2628) 16/06/28 13:07:08 - DEBUG: [0] rx: HT_DATA: first packet arrived from box
(3160) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetUserLong ;
LONG idx 0 ;
ULONG UserLong 58806032 ;
)

(3160) 16/06/28 13:07:08 - DEBUG: snd
(
LONG SmSetUserLong 0 ;
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(1788) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetPin ;
BOOL User_Pin_Global 0 ;
LONG idx 0 ;
STRING Pin HASH1F30D46273E469C74190AF42390862F0CF148484DDB8DB C7CE64046AB17051EF292A2B5B571DD935D83A6E73B9F4A243 0732F43C4DDDECA9FC26F3EB911D28BB52E919ECB3B381DB5C 1A5A1BD35721F22405324F5133C2F89D03B59603C888EDFA73 F289A872A5974658E8C2927ADE7BCDD8DB09AD1E8D2E0420D0 16C06FFA32A9059A75882 ;
)

(1788) 16/06/28 13:07:08 - DEBUG: [0] Set pin to 'HASH1F30D46273E469C74190AF42390862F0CF148484DDB8D BC7CE64046AB17051EF292A2B5B571DD935D83A6E73B9F4A24 30732F43C4DDDECA9FC26F3EB911D28BB52E919ECB3B381DB5 C1A5A1BD35721F22405324F5133C2F89D03B59603C888EDFA7 3F289A872A5974658E8C2927ADE7BCDD8DB09AD1E8D2E0420D 016C06FFA32A9059A75882'
(1788) 16/06/28 13:07:08 - DEBUG: [0] Set pin is initial to 'false'
(1788) 16/06/28 13:07:08 - DEBUG: snd
(
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(3656) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetAllowedNumber ;
LONG idx 0 ;
LONG NumberIndex 0 ;
STRING Number 99289 ;
)

(3656) 16/06/28 13:07:08 - DEBUG: [0] Set allowed number[0] to '99289'
(3656) 16/06/28 13:07:08 - DEBUG: snd
(
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(3744) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetAllowedNumber ;
LONG idx 0 ;
LONG NumberIndex 1 ;
STRING Number ;
)

(3744) 16/06/28 13:07:08 - DEBUG: [0] Set allowed number[1] to ''
(3744) 16/06/28 13:07:08 - DEBUG: snd
(
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(2052) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetAllowedNumber ;
LONG idx 0 ;
LONG NumberIndex 2 ;
STRING Number ;
)

(2052) 16/06/28 13:07:08 - DEBUG: [0] Set allowed number[2] to ''
(2052) 16/06/28 13:07:08 - DEBUG: snd
(
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(2124) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetProject ;
LONG idx 0 ;
STRING Project evoice_projectstart ;
)

(2124) 16/06/28 13:07:08 - DEBUG: [0] Set Project to 'evoice_projectstart'
(2124) 16/06/28 13:07:08 - DEBUG: snd
(
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(3316) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetLanguage ;
LONG idx 0 ;
STRING Language de ;
)

(3316) 16/06/28 13:07:08 - DEBUG: [0] Set Language to 'de'
(3316) 16/06/28 13:07:08 - INFO: [0] VoicePath is 'c:\programdata\ffums\fmsrv/data/voice/evoice_projectplayaudio\audio\de\'
(3316) 16/06/28 13:07:08 - DEBUG: snd
(
STRING ComponentName voice0 ;
LONG Status 0 ;
)

(1124) 16/06/28 13:07:08 - DEBUG: rcv:
(
STRING MessageType rpc ;
STRING Function SmSetUserInfoReady ;
LONG ChannelIDX 0 ;
LONG Ok 1 ;
)

Wenn ich das richtig sehe, wechselt das Skript in den normalen Anrufbeantworter-Modus weil angeblich keine PIN hinterlegt ist.

Sowohl über das AD als auch über die Weboberfläche ist aber die PIN gepflegt worden.

In voice0 habe ich verschiedene Einstellungen ausprobiert, auch "leere PIN zulassen".

Vielleicht ist das aber auch gar nicht die Ursache, aber es sieht zumindest so aus, als ob die Calling Party Number (99289) richtig erkannt wird.

Gruß

Cheesy

[Chris Helbing]

Hallo Cheesy,


Vielen Dank für die ausführlichen Logs und die Schilderung. Wir haben es nachstellen können und werden es schnellstmöglich korrigieren. In der nächsten Woche ist dafür ein Slot eingeplant.

Falls vorher notwendig kann ich mir einen Workaround vorstellen, der die Rufnummern am OMG manipuliert (dort schon dreht) und als Identifizierungshilfe für den Service Voice eine eindeutige Rufnummer in die neue Redirected Number schreibt. Damit bleiben für das Voicesystem die Called und die Calling Party Number korrekt.

VG

[cheesy33]

Das hört sich ja super an. Vielen Dank für die Unterstützung.

Der Workaround ist nicht notwendig, da wir den OM im Moment nur mit wenigen Usern produktiv einsetzen und ich ein Backup des 5.3er zurück gespielt habe.

Gruß

Cheesy