Avaya IPO Voicemail to Email debug

[4vayaMan]

I have a 500v2 that Voicemail to Email is not working on with known good settings. This network does not have any restrictions, so I took to monitor to help me trace out this problem. I setup my filter to skim out any unneeded data, but I cannot find any problems with the trace. Here it is:

1617437mS LIC: Licence 43 is inhibited in PARTNER mode
1617438mS PRN: Config Write Completed
1624830mS RES: Mon 7/11/2011 09:10:52 FreeMem=67135732(3) CMMsg=4 (6) Buff=200 949 1000 7463 5 Links=1440
1624830mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1630909mS LVM(recorder): LVM Recorder 3: constructor called
1630909mS LVMail: MailBox3: constructor called
1630909mS LVMail: AutoBox3: constructor called
1630909mS LVM(audio): Audio3: constructor called
1630909mS LVMail: MailBox3: constructor called
1630909mS LVMail: Client3: constructor called:
ACallId.lid: 0 ACallId.id: 1011 ACallId.in: 0
ALineId.type: 4 Alineid.id: 1
1630910mS LVM(audio): Audio3: Open: called: packet size 80
1630910mS LVMail: Ticker: RegisterLVMClient: 3 List size = 1
1630910mS CMMap: a=21.42 b=1.254 T
1630911mS LVMail: Client3: ProcessMsg: CMSetup Call: lid = 0, id = 1011
1630911mS LVMail: Client3: ProcessMsg: CMSetup - called_party: #AAEAA1 calling_party: 8186125070
1630911mS LVMail: Client3: ProcessMsg: PEAA1 access: AUTOATTENDANT greeting:
1630911mS LVMail: Client3: ProcessMsg: ProcessMsg modifier=() keypad=()
1630911mS LVMail: Client3: Open: opentype: 1
1630911mS LVMail: AutoBox3: Open: called
1630912mS LVMail: AutoBox3: Saved AMP_greeting found: Mailbox: PEAA1 File 31002
1630912mS LVMail: AutoBox3: Saved NT_greeting found: Mailbox: PEAA1 File 31003
1630913mS LVMail: AutoBox3: Saved NT_greeting found: Mailbox: PEAA1 File 31003
1630913mS LVMail: Client3: SendMsg: CMConnect to CM
1630913mS LVMail: Client3: ConfigureRASPort: called
1630916mS CMMap: PCG::MapBChan pcp[77]b1r0 cp_b f5989380 other_cp_b 0 type CGTypeSimple
1630917mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f59a0934 other_cp_b f5989380 type CGTypeSimple
1630917mS CMMap: a=5.1 b=21.42 M1
1630917mS LVMail: Client3: ProcessMsg: CMConnectAck Call: lid = 0, id = 1011
1630917mS LVMail: Client3: CallConnected: invoked
1630917mS LVMail: Client3: CallConnected: Call Locale: ENU
1630918mS LVMail: Client3: CallConnected: Caller Can Not Terminate
1630918mS LVMail: Client3: Open: opentype: 9
1630918mS LVMail: Client3: StartRASPort: called
1630918mS LVMail: Client3: AssertSpeechMonitor: On
1630918mS LVM(audio): Audio3: RequestDialog: invoked dialog type: ATTENDANT
1630918mS LVM(audio): Audio3: RequestDialog: locale: ENU Selected
1630918mS LVM(audio): Audio3: Auto_Attend: called; action: ATTENDANT, greeting modifier
1630918mS LVM(audio): Audio3: ChangeNode( AutoAttIntroNode, 0)
1630919mS LVM(audio): Audio3: AutoAttIntroNode::Begin: called: action = 0
1630919mS LVM(audio): Audio3: AddWAV: clip found: File: 5070 clipname: ENU:MC_01
1630919mS LVM(audio): Audio3: AddWAV: clip supplied via File: 31003 Clipname: PEAA1
1630919mS LVM(audio): Audio3: AddWAV: clip found: File: 5071 clipname: ENU:MC_02
1630919mS LVM(audio): Audio3: RunNode: called
1630920mS LVM(audio): Audio3: StartNextClip: called File: 5070 clipname: ENU:MC_01
1630920mS CMMap: a=5.1 b=21.42 CheckSpeechPathMonitor (pcp[32]b1r0) register
1630921mS PRN: Confer DSP, channel 0: allocated DTMF detector 0, 23 free
1630921mS PRN: Confer DSP: enabled DTMF detector 0
1630921mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f59a0934 other_cp_b f5989380 type CGTypeSimple
1630921mS CMMap: a=5.1 b=21.42 M1
1630950mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 15 clip_unu: 3243
1630951mS LVMail: Client3: StartSendToPhone: called
1630951mS LVMail: Client3: ConfigureRASPort: called
1630951mS LVMail: Client3: CallConnected: - Call State: CMCSConnected
1631137mS LVM(audio): Audio3: StartNextClip: called File: 31003 clipname: PEAA1
1631189mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 214 clip_unu: 287168
1631330mS RES: Mon 7/11/2011 09:10:59 FreeMem=67094856(3) CMMsg=4 (6) Buff=200 917 1000 7463 5 Links=1409
1631330mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1636258mS LVM(audio): Audio3: DTMFHit: digit: 1
1636258mS LVM(audio): Audio3: AutoAttIntroNode:TMFHit: digit: 1
1636258mS LVM(audio): Audio3: ChangeNode( DialByNumberNode, 0)
1636258mS LVM(audio): Audio3: AADialByNumberNode::Begin: called: modifier = 0
1636258mS LVM(audio): Audio3: AADialByNumberNode::Started (type=Direct)
1636258mS LVM(audio): Audio3: AADialByNumberNode:TMFHit: digit: 1
1636259mS LVM(audio): Audio3: AddWAV: clip found: File: 5071 clipname: ENU:MC_02
1636259mS LVM(audio): Audio3: RunNode: called
1636259mS LVM(audio): Audio3: StartNextClip: called File: 5071 clipname: ENU:MC_02
1636290mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 15 clip_unu: 8052
1636290mS LVMail: Client3: StartSendToPhone: called
1636290mS LVMail: Client3: ConfigureRASPort: called
1636801mS LVM(audio): Audio3: StartNextClip: end of clip list
1636866mS LVM(audio): Audio3: DTMFHit: digit: 0
1636866mS LVM(audio): Audio3: AADialByNumberNode:TMFHit: digit: 0
1636866mS LVMail: Client3: AutoAction: called: DialByNumber 10
1636866mS LVMail: Client3: Transfer Status: Idle -> AlertWait
1636866mS LVMail: Client3: SendMsg: CMFacility to CM
1636867mS LVMail: Client3: SendMsg: CMSetup to CM
1636871mS LVMail: Client3: ProcessMsg: CMSetupAck Call: lid = 0, id = 1011
1636872mS LVM(recorder): LVM Recorder 4: constructor called
1636872mS LVMail: MailBox4: constructor called
1636872mS LVMail: AutoBox4: constructor called
1636873mS LVM(audio): Audio4: constructor called
1636873mS LVMail: MailBox4: constructor called
1636873mS LVMail: Client4: constructor called:
ACallId.lid: 0 ACallId.id: 1012 ACallId.in: 0
ALineId.type: 4 Alineid.id: 1
1636873mS LVM(audio): Audio4: Open: called: packet size 80
1636873mS LVMail: Ticker: RegisterLVMClient: 4 List size = 2
1636873mS CMMap: a=21.11 b=1.254 T
1636874mS LVMail: Client4: ProcessMsg: CMSetup Call: lid = 0, id = 1012
1636874mS LVMail: Client4: ProcessMsg: CMSetup - called_party: #Extn10 calling_party: 8186125070
1636875mS LVMail: Client4: ProcessMsg: access: LEAVE_VOICEMAIL greeting:
1636875mS LVMail: Client4: ProcessMsg: ProcessMsg modifier=() keypad=()
1636875mS LVMail: Client4: Open: opentype: 1
1636875mS LVM(recorder): LVM Recorder 4: Open: called
1636875mS LVMail: MailBox4: Open: called
1636875mS LVMail: MailBox4: Saved GREETING found: Mailbox: Extn10 File 31008
1636875mS LVMail: Notify: Extn10 New: 3 Old: 1 Saved: 0
1636876mS LVMail: MailBox4: Mailbox data:
1636876mS LVMail: Flash Memory Management List size = 9
1636876mS LVMail: Mailbox "Extn10" retrieving voicemail - has 4 calls
1636876mS LVMail: 3 new, 1 old, 0 saved (any others are discards)
1636876mS LVMail: Client4: SendMsg: CMAlerting to CM
1636877mS LVMail: Client4: ConfigureRASPort: called
1636878mS LVMail: Client3: ProcessMsg: CMAlerting Call: lid = 0, id = 1011
1636879mS LVMail: Client3: AssertSpeechMonitor: Off
1636879mS LVMail: Client3: Transfer Status: AlertWait -> Idle
1636879mS LVMail: Client3: SendMsg: CMTransferComplete to CM
1636882mS LVMail: Client4: ProcessMsg: CMFacility Call: lid = 0, id = 1012
1636883mS LVMail: Client4: AssertSpeechMonitor: On
1636884mS LVMail: Client3: ProcessMsg: CMReleaseComp Call: lid = 0, id = 1011
1636884mS LVMail: Client3: ProcessMsg: CLOSE (CMReleaseComp) end: False pause: False
1636884mS LVMail: Client3: Open: opentype: 2
1636884mS LVMail: Client3: ProcessMsg: CLOSE - listening packet count 0
1636884mS LVMail: Client3: Complete: called
1636884mS LVMail: Client3: AssertSpeechMonitor: Off
1636884mS LVMail: Client3: StopRASPort: called
1636884mS LVMail: AutoBox3: Close: called
1636885mS LVMail: ~Client3: destructor called
1636885mS LVMail: Ticker: UnRegisterLVMClient: 3 List size = 1
1636885mS LVMail: ~MailBox3: destructor called
1636885mS LVM(audio): ~Audio3: destructor called
1636885mS LVMail: ~AutoBox3: destructor called
1636885mS LVMail: ~MailBox3: destructor called
1636885mS LVM(recorder): ~LVM Recorder 3: destructor called
1636886mS CMMap: a=21.42 b=0.0 T0
1636888mS CMMap: a=5.1 b=21.42 CheckSpeechPathMonitor (pcp[32]b1r0) unregister
1636888mS PRN: Confer DSP: disabled DTMF detector 0
1636889mS PRN: Confer DSP channel 0: freed DTMF detector 0, 24 free
1636889mS CMMap: PCG::UnmapBChan pcp[77]b1r0 cp_b f5989380 other_cp_b f59a0934
1636889mS CMMap: a=21.42 b=5.1 M0
1636889mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f59a0934 other_cp_b 0
1636889mS CMMap: a=5.1 b=0.0 R1
1636892mS PRN: Config Write Wake Up
1637330mS RES: Mon 7/11/2011 09:11:05 FreeMem=67069528(3) CMMsg=4 (6) Buff=200 949 1000 7462 5 Links=1442
1637330mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=50 Poll=0 Ready=1 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1637392mS PRN: Updates IO list size 0 updated list size 1
1637393mS LIC: Licence 43 is inhibited in PARTNER mode
1637394mS PRN: Config Write Completed
1642330mS RES: Mon 7/11/2011 09:11:10 FreeMem=67071368(3) CMMsg=4 (6) Buff=200 949 1000 7462 5 Links=1447
1642331mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=50 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1642876mS LVMail: Client4: SendMsg: CMConnect to CM
1642878mS LIC: Licence 43 is inhibited in PARTNER mode
1642879mS LVMail: Client4: ProcessMsg: CMConnectAck Call: lid = 0, id = 1012
1642879mS LVMail: Client4: CallConnected: invoked
1642879mS LVMail: Client4: CallConnected: User: Extn10 Access Code: xxxx
1642879mS LVMail: Client4: CallConnected: Call Locale: enu
1642879mS LVMail: Client4: CallConnected: Caller Can Not Terminate
1642879mS LVMail: Client4: Open: opentype: 9
1642880mS LVMail: Client4: StartRASPort: called
1642880mS LVMail: Client4: AssertSpeechMonitor: On
1642880mS LVM(audio): Audio4: RequestDialog: invoked dialog type: GREETINGS
1642880mS LVM(audio): Audio4: RequestDialog: locale: ENU Selected
1642880mS LVM(audio): Audio4: Greetings: called
1642880mS LVM(audio): Audio4: ChangeNode( GreetingsNode, 0)
1642880mS LVM(audio): Audio4: GreetingsNode::Begin: (0) called
1642881mS LVM(audio): Audio4: AddWAV: clip found: File: 5070 clipname: ENU:MC_01
1642881mS LVM(audio): Audio4: AddWAV: clip supplied via File: 31008 Clipname: Extn10
1642881mS LVM(audio): Audio4: AddWAV: clip found: File: 5069 clipname: ENU:MC_00
1642881mS LVM(audio): Audio4: RunNode: called
1642881mS LVM(audio): Audio4: StartNextClip: called File: 5070 clipname: ENU:MC_01
1642883mS CMMap: a=5.1 b=0.0 R0
1642883mS CMMap: PCG::MapBChan pcp[77]b1r0 cp_b f5989380 other_cp_b 0 type CGTypeSimple
1642883mS CMMap: PCG::MapBChan pcp[33]b1r0 cp_b f59a01f4 other_cp_b f5989380 type CGTypeSimple
1642883mS CMMap: a=5.1 b=21.11 M1
1642883mS CMMap: a=5.1 b=21.11 CheckSpeechPathMonitor (pcp[33]b1r0) register
1642884mS PRN: Confer DSP, channel 0: allocated DTMF detector 0, 23 free
1642884mS PRN: Confer DSP: enabled DTMF detector 0
1642913mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 15 clip_unu: 3243
1642913mS LVMail: Client4: StartSendToPhone: called
1642913mS LVMail: Client4: ConfigureRASPort: called
1642913mS LVMail: Client4: CallConnected: - Call State: CMCSConnected
1643095mS LVM(audio): Audio4: StartNextClip: called File: 31008 clipname: Extn10
1643149mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 214 clip_unu: 49998
1648330mS RES: Mon 7/11/2011 09:11:16 FreeMem=67085484(3) CMMsg=4 (6) Buff=200 899 1000 7463 5 Links=1389
1648331mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1649054mS LVM(audio): SoundPlay: NextByte: clip_unu == 0
1649064mS LVM(audio): SoundPlay: GetNextMailFrag: END-OF-DATA
1649064mS LVM(audio): Audio4: StartNextClip: called File: 5069 clipname: ENU:MC_00
1649094mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 15 clip_unu: 1448
1649244mS LVM(audio): Audio4: StartNextClip: end of clip list
1649694mS LVMail: Client4: SendToPhone: end of sound sequence
1649694mS LVM(audio): Audio4: RequestDialog: invoked dialog type: END_PLAY
1649694mS LVM(audio): Audio4: RequestDialog: locale: ENU Selected
1649694mS LVM(audio): Audio4: EndPlay: called
1649694mS LVM(audio): Audio4: GreetingsNode::EndPlay: called
1649694mS LVM(audio): Audio4: StartRecording: called
1649694mS LVM(recorder): LVM Recorder 4: StartRecording called - 8186125070 => Extn10
1649695mS LVM(recorder): LVM Recorder 4: StartRecording: Timestamp: 2011/11/07 09:11:17 (1320657077 secs, day 1)
1649695mS LVM(flash): FlashA: NewFileNumber: 31012
1649695mS LVM(audio): Audio4: ChangeNode( RecordingPlayNode, 0)
1649695mS LVM(audio): Audio4: RecordingPlayNode::Begin: called: Timestamp: 2011/11/07 09:11:17 (1320657077 secs, day 1)
1649696mS LVM(flash): FlashA: WriteItemAnySector: (208) File: 31012 nBodyLen: 0 cType: ea file: 31012
1649696mS LVM(flash): Approx. 0 Seconds
1649783mS LVM(flash): FlashA: WriteItemAnySector: exitting after 87 ms
1650974mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 10256 file: 31012
1651012mS LVM(flash): FlashA: AppendFlashItem: exitting after 38 ms
1652258mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 10256 file: 31012
1652288mS LVM(flash): FlashA: AppendFlashItem: exitting after 29 ms
1653538mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 10256 file: 31012
1653564mS LVM(flash): FlashA: AppendFlashItem: exitting after 26 ms
1654330mS RES: Mon 7/11/2011 09:11:21 FreeMem=67085484(3) CMMsg=4 (6) Buff=200 949 1000 7463 5 Links=1439
1654330mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1654814mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 10256 file: 31012
1655169mS LVM(flash): FlashA: AppendFlashItem: exitting after 355 ms
1656095mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 10256 file: 31012
1656122mS LVM(flash): FlashA: AppendFlashItem: exitting after 27 ms
1657377mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 10256 file: 31012
1657400mS LVM(flash): FlashA: AppendFlashItem: exitting after 23 ms
1658655mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 10256 file: 31012
1658693mS LVM(flash): FlashA: AppendFlashItem: exitting after 38 ms
1659391mS LVMail: Client4: ProcessMsg: CMReleaseComp Call: lid = 0, id = 1012
1659391mS LVMail: Client4: ProcessMsg: CLOSE (CMReleaseComp) end: True pause: False
1659391mS LVMail: Client4: Open: opentype: 2
1659391mS LVMail: Client4: ProcessMsg: CLOSE - listening packet count 968
1659391mS LVMail: Client4: Complete: called
1659391mS LVMail: Client4: AssertSpeechMonitor: Off
1659391mS LVMail: Client4: StopRASPort: called
1659391mS LVM(recorder): LVM Recorder 4: Close called - t_bodylen 77561
1659392mS LVM(recorder): LVM Recorder 4: Close: Clipping 641 bytes from Extn10
1659392mS LVMail: ~Client4: destructor called
1659392mS LVMail: Ticker: UnRegisterLVMClient: 4 List size = 0
1659392mS LVMail: ~MailBox4: destructor called
1659392mS LVMail: Notify: New: 0 Old: 0 Saved: 0
1659392mS LVM(audio): ~Audio4: destructor called
1659393mS LVMail: ~AutoBox4: destructor called
1659393mS LVMail: ~MailBox4: destructor called
1659393mS LVMail: MailBox4: Saved GREETING found: Mailbox: Extn10 File 31008
1659393mS LVMail: Notify: Extn10 New: 3 Old: 1 Saved: 0
1659393mS LVM(recorder): ~LVM Recorder 4: destructor called
1659393mS CMMap: a=21.11 b=0.0 T0
1659396mS CMMap: a=5.1 b=21.11 CheckSpeechPathMonitor (pcp[33]b1r0) unregister
1659396mS PRN: Confer DSP: disabled DTMF detector 0
1659396mS PRN: Confer DSP channel 0: freed DTMF detector 0, 24 free
1659397mS CMMap: PCG::UnmapBChan pcp[33]b1r0 cp_b f59a01f4 other_cp_b f5989380
1659397mS CMMap: a=5.1 b=21.11 M0
1659397mS CMMap: PCG::UnmapBChan pcp[77]b1r0 cp_b f5989380 other_cp_b 0
1659397mS LVM(flash): FlashA: AppendFlashItem: File: 31012 nBodyLen: 5769 file: 31012
1659425mS LVM(flash): FlashA: AppendFlashItem: exitting after 28 ms
1659426mS LVM(flash): FlashA: CloseFlashItem: File: 31012 nNameLen: 208 nBodyLen: 76920
1659472mS LVM(flash): FlashA: CloseFlashItem: exitting after 46 ms
1659472mS LVM(flash): FlashA: ReadFirstSector: file: 31012
1659483mS LVM(flash): FlashA: ReadFirstSector: exitting after 11 ms
1659483mS LVM(flash): FlashMan: AddFMEntry: File: 31012: Extn10 type: U-Law New Mess
1659484mS LVMail: Notify: Extn10 New: 4 Old: 1 Saved: 0
1659484mS LVMail: Message recorded. Email mode(1) Addr(Jramos@corptelecom.com) Locale(enu)
1659484mS LVM(email): VM Email request added to queue - size = 1
1659486mS VMAIL: ProcessVMMData data=<VMM$SECT31012$N$2011/11/07 09:11$8186125070$11$0$0$10$>
1659487mS LVM(email): LvmEmailManager woken up...
1659487mS LVM(email): LvmEmailManager ProcessNewMessage: From(8186125070) To(Extn10) Mode(1)
1659518mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 214 clip_unu: 76920
1659779mS LVM(audio): SoundPlay: NextByte: clip_unu == 0
1659779mS LVM(audio): SoundPlay: GetNextMailFrag: END-OF-DATA
1659830mS RES: Mon 7/11/2011 09:11:28 FreeMem=67133732(3) CMMsg=4 (6) Buff=200 948 1000 7463 5 Links=1430
1659830mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
1659857mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 214 clip_unu: 76920
1660171mS LVM(audio): SoundPlay: NextByte: clip_unu == 0
1660172mS LVM(audio): SoundPlay: GetNextMailFrag: END-OF-DATA
1660175mS LVM(email): Send SMTP message
1660181mS LVM(email): SMTPServer New Email...
1660181mS LVM(email): LvmEmailManager ProcessNewMessage: Message start(19525f) 1st read(19525f) end read(195383) audio(19550f) end(195515) duration(694) audio size(76800)
1660182mS LVM(email): LvmEmailManager snoozing...
1660182mS LVM(email): SMTPServer woken up... Session 00000000 Client 00000000 State 0 Q Size 1 current_message 00000000
1660182mS LVM(email): SMTPServer Initialise Session...
1660182mS LVM(email): SMTPServer Connecting... Session f54a5a90 Client f54a2bd4
1660182mS LVM(email): SMTPServer snoozing...
1660552mS LVM(email): SMTPServer Rx in Connecting State...
1660552mS LVM(email): SMTPServer Connected...
1661347mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 7 Q Size 1 current_message 00000000
1661347mS LVM(email): SMTPServer: Initiate msg send
1661994mS PRN: Sending body size=211216
1661994mS LVM(email): Sending packet 1500 (1)
1661995mS LVM(email): Sending packet 1500 (2)
1661996mS LVM(email): Sending packet 1500 (3)
1661996mS LVM(email): Sending packet 1500 (4)
1661997mS LVM(email): Sending packet 1500 (5)
1661997mS LVM(email): Sending packet 1500 (6)
1661998mS LVM(email): Sending packet 1500 (7)
1661999mS LVM(email): Sending packet 1500 (8)
1661999mS LVM(email): Sending packet 1500 (9)
1662000mS LVM(email): Sending packet 1500 (10)
1662000mS LVM(email): Sending packet 1500 (11)
1662001mS LVM(email): Sending packet 1500 (12)
1662001mS LVM(email): Sending packet 1500 (13)
1662001mS LVM(email): Sending packet 1500 (14)
1662002mS LVM(email): Sending packet 1500 (15)
1662002mS LVM(email): Sending packet 1500 (16)
1662003mS LVM(email): Sending packet 1500 (17)
1662003mS LVM(email): Sending packet 1500 (18)
1662004mS LVM(email): Sending packet 1500 (19)
1662005mS LVM(email): Sending packet 1500 (20)
1662005mS LVM(email): Sending packet 1500 (21)
1662006mS LVM(email): Sending packet 1500 (22)
1662006mS LVM(email): Sending packet 1500 (23)
1662006mS LVM(email): Sending packet 1500 (24)
1662007mS LVM(email): Sending packet 1500 (25)
1662007mS LVM(email): SMTPServer snoozing...
1662363mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1662364mS LVM(email): Sending packet 1500 (26)
1662365mS LVM(email): Sending packet 1500 (27)
1662366mS LVM(email): Sending packet 1500 (28)
1662366mS LVM(email): Sending packet 1500 (29)
1662367mS LVM(email): Sending packet 1500 (30)
1662367mS LVM(email): Sending packet 1500 (31)
1662368mS LVM(email): Sending packet 1500 (32)
1662369mS LVM(email): Sending packet 1500 (33)
1662369mS LVM(email): Sending packet 1500 (34)
1662369mS LVM(email): Sending packet 1500 (35)
1662370mS LVM(email): Sending packet 1500 (36)
1662370mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1662370mS LVM(email): SMTPServer snoozing...
1662542mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1662542mS LVM(email): Sending packet 1500 (37)
1662543mS LVM(email): Sending packet 1500 (38)
1662544mS LVM(email): Sending packet 1500 (39)
1662545mS LVM(email): Sending packet 1500 (40)
1662545mS LVM(email): Sending packet 1500 (41)
1662546mS LVM(email): Sending packet 1500 (42)
1662546mS LVM(email): Sending packet 1500 (43)
1662547mS LVM(email): Sending packet 1500 (44)
1662547mS LVM(email): Sending packet 1500 (45)
1662548mS LVM(email): Sending packet 1500 (46)
1662548mS LVM(email): Sending packet 1500 (47)
1662549mS LVM(email): Sending packet 1500 (48)
1662549mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1662549mS LVM(email): SMTPServer snoozing...
1662569mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1662569mS LVM(email): Sending packet 1500 (49)
1662570mS LVM(email): Sending packet 1500 (50)
1662571mS LVM(email): Sending packet 1500 (51)
1662572mS LVM(email): Sending packet 1500 (52)
1662573mS LVM(email): Sending packet 1500 (53)
1662573mS LVM(email): Sending packet 1500 (54)
1662574mS LVM(email): Sending packet 1500 (55)
1662574mS LVM(email): Sending packet 1500 (56)
1662574mS LVM(email): Sending packet 1500 (57)
1662575mS LVM(email): Sending packet 1500 (58)
1662575mS LVM(email): Sending packet 1500 (59)
1662576mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1662576mS LVM(email): SMTPServer snoozing...
1662741mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1662742mS LVM(email): Sending packet 1500 (60)
1662743mS LVM(email): Sending packet 1500 (61)
1662744mS LVM(email): Sending packet 1500 (62)
1662744mS LVM(email): Sending packet 1500 (63)
1662745mS LVM(email): Sending packet 1500 (64)
1662746mS LVM(email): Sending packet 1500 (65)
1662746mS LVM(email): Sending packet 1500 (66)
1662746mS LVM(email): Sending packet 1500 (67)
1662747mS LVM(email): Sending packet 1500 (68)
1662747mS LVM(email): Sending packet 1500 (69)
1662748mS LVM(email): Sending packet 1500 (70)
1662748mS LVM(email): Sending packet 1500 (71)
1662749mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1662749mS LVM(email): SMTPServer snoozing...
1662769mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1662769mS LVM(email): Sending packet 1500 (72)
1662770mS LVM(email): Sending packet 1500 (73)
1662771mS LVM(email): Sending packet 1500 (74)
1662772mS LVM(email): Sending packet 1500 (75)
1662772mS LVM(email): Sending packet 1500 (76)
1662773mS LVM(email): Sending packet 1500 (77)
1662773mS LVM(email): Sending packet 1500 (78)
1662773mS LVM(email): Sending packet 1500 (79)
1662774mS LVM(email): Sending packet 1500 (80)
1662774mS LVM(email): Sending packet 1500 (81)
1662775mS LVM(email): Sending packet 1500 (82)
1662775mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1662775mS LVM(email): SMTPServer snoozing...
1662934mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1662934mS LVM(email): Sending packet 1500 (83)
1662935mS LVM(email): Sending packet 1500 (84)
1662936mS LVM(email): Sending packet 1500 (85)
1662937mS LVM(email): Sending packet 1500 (86)
1662937mS LVM(email): Sending packet 1500 (87)
1662938mS LVM(email): Sending packet 1500 (88)
1662938mS LVM(email): Sending packet 1500 (89)
1662939mS LVM(email): Sending packet 1500 (90)
1662939mS LVM(email): Sending packet 1500 (91)
1662940mS LVM(email): Sending packet 1500 (92)
1662940mS LVM(email): Sending packet 1500 (93)
1662941mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1662941mS LVM(email): SMTPServer snoozing...
1662958mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1662959mS LVM(email): Sending packet 1500 (94)
1662959mS LVM(email): Sending packet 1500 (95)
1662960mS LVM(email): Sending packet 1500 (96)
1662961mS LVM(email): Sending packet 1500 (97)
1662962mS LVM(email): Sending packet 1500 (98)
1662962mS LVM(email): Sending packet 1500 (99)
1662962mS LVM(email): Sending packet 1500 (100)
1662963mS LVM(email): Sending packet 1500 (101)
1662963mS LVM(email): Sending packet 1500 (102)
1662964mS LVM(email): Sending packet 1500 (103)
1662965mS LVM(email): Sending packet 1500 (104)
1662965mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1662965mS LVM(email): SMTPServer snoozing...
1663008mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1663008mS LVM(email): Sending packet 1500 (105)
1663009mS LVM(email): Sending packet 1500 (106)
1663010mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1663010mS LVM(email): SMTPServer snoozing...
1663131mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1663131mS LVM(email): Sending packet 1500 (107)
1663132mS LVM(email): Sending packet 1500 (108)
1663133mS LVM(email): Sending packet 1500 (109)
1663134mS LVM(email): Sending packet 1500 (110)
1663134mS LVM(email): Sending packet 1500 (111)
1663134mS LVM(email): Sending packet 1500 (112)
1663135mS LVM(email): Sending packet 1500 (113)
1663135mS LVM(email): Sending packet 1500 (114)
1663136mS LVM(email): Sending packet 1500 (115)
1663136mS LVM(email): Sending packet 1500 (116)
1663137mS LVM(email): Sending packet 1500 (117)
1663137mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1663137mS LVM(email): SMTPServer snoozing...
1663153mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1663154mS LVM(email): Sending packet 1500 (118)
1663155mS LVM(email): Sending packet 1500 (119)
1663156mS LVM(email): Sending packet 1500 (120)
1663156mS LVM(email): Sending packet 1500 (121)
1663157mS LVM(email): Sending packet 1500 (122)
1663157mS LVM(email): Sending packet 1500 (123)
1663158mS LVM(email): Sending packet 1500 (124)
1663158mS LVM(email): Sending packet 1500 (125)
1663159mS LVM(email): Sending packet 1500 (126)
1663159mS LVM(email): Sending packet 1500 (127)
1663160mS LVM(email): Sending packet 1500 (128)
1663160mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1663160mS LVM(email): SMTPServer snoozing...
1663324mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1663324mS LVM(email): Sending packet 1500 (129)
1663325mS LVM(email): Sending packet 1500 (130)
1663326mS LVM(email): Sending packet 1500 (131)
1663326mS LVM(email): Sending packet 1500 (132)
1663327mS LVM(email): Sending packet 1500 (133)
1663327mS LVM(email): Sending packet 1500 (134)
1663328mS LVM(email): Sending packet 1500 (135)
1663328mS LVM(email): Sending packet 1500 (136)
1663329mS LVM(email): Sending packet 1500 (137)
1663329mS LVM(email): Sending packet 1500 (138)
1663330mS LVM(email): Sending packet 1500 (139)
1663330mS LVM(email): Sending packet 1500 (140)
1663331mS LVM(email): SMTPServer: waiting for 25 buffers to be sent
1663331mS LVM(email): SMTPServer snoozing...
1663346mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1663346mS LVM(email): Sending packet 1216 (141)
1663347mS LVM(email): SMTPServer: waiting for 15 buffers to be sent
1663347mS LVM(email): SMTPServer snoozing...
1663349mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1663349mS LVM(email): SMTPServer: waiting for 14 buffers to be sent
1663349mS LVM(email): SMTPServer snoozing...
1663499mS LVM(email): SMTPServer woken up... Session f54a5a90 Client f54a2bd4 State 8 Q Size 1 current_message f54a1c8c
1663499mS LVM(email): SMTPServer: waiting for 13 buffers to be sent
1663499mS LVM(email): SMTPServer snoozing...

Does anyone else see a problem with this log?

 

[flyguy2]

you might be better off running a debugview rather than Monitor to catch vmail traces.

 

[CarGoSki]

You are using Partner Edition:
1624830mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=49 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034

You appear to have some error regarding licensing:
1617437mS LIC: Licence 43 is inhibited in PARTNER mode

dbugview is for vmproflyguy2

It appears that the email is finally set to send and then traces are cut off. May need the traces after.

Perhaps you could check the smtp server to be sure that message size is not restricted to a particular size.

1659484mS LVMail: Message recorded. Email mode(1) Addr(Jramos@corptelecom.com) Locale(enu)
1659484mS LVM(email): VM Email request added to queue - size = 1
1659486mS VMAIL: ProcessVMMData data=<VMM$SECT31012$N$2011/11/07 09:11$8186125070$11$0$0$10$>
1659487mS LVM(email): LvmEmailManager woken up...
1659487mS LVM(email): LvmEmailManager ProcessNewMessage: From(8186125070) To(Extn10) Mode(1)
1659518mS LVM(audio): SoundPlay: Restart: setup sound-clip: b_header: 0x00 sect_ix: 214 clip_unu: 76920
1659779mS LVM(audio): SoundPlay: NextByte: clip_unu == 0
1659779mS LVM(audio): SoundPlay: GetNextMailFrag: END-OF-DATA
1659830mS RES: Mon 7/11/2011 09:11:28 FreeMem=67133732(3) CMMsg=4 (6) Buff=200 948 1000 7463 5 Links=1430
1659830mS RES2: (PE) IP 500 V2 7.0(12) Tasks=42 RTEngine=0 C

 

[4vayaMan]

Hey guys, thank you for your timely responses. Actually turned out to be an exchange thing. I tried a known good setup, but according to our support you cannot have the smtp server address be an address on another subnet if you are using Partner Mode. When I reverted back to the internal exchange, it still did not work because of an exchange issue.