I ran that routine the port sniffer doesn't pick up any traffic and I have pasted the debug file below.
24/08/2012: 14:00:16: Phone Manager Version : 4.2.42
24/08/2012: 14:00:16: Debug Mode enabled.
24/08/2012: 14:00:16: Trace file = C:\Users\CStokes\Documents\Avaya\IP 400\PhoneManager\PMTrace.txt
24/08/2012: 14:00:16: Current Language File = C:\Program Files (x86)\Avaya\IP Office\Phone Manager\Langs\eng\Data\PhoneManager.iniC:\Program Files (x86)\Avaya\IP Office\Phone Manager\Langs\eng\Data\PhoneManager.ini
24/08/2012: 14:00:16: using new message file 'C:\Program Files (x86)\Avaya\IP Office\Phone Manager\Langs\ENG\Waves\newmessage.wav
24/08/2012: 14:00:16: Speeddials in list=0
24/08/2012: 14:00:19: Microsoft Outlook 2007 detected.
24/08/2012: 14:00:19: <--Sending TFTP Request nasystem/who_is(In TFTP queue=0)
24/08/2012: 14:00:20: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:21: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:22: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:23: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:24: WARNING: LoginLogout key not found! Default to FALSE...
24/08/2012: 14:00:24: Creating VMGMessageHandler
24/08/2012: 14:00:24: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:25: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:26: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:27: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:28: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=1)
24/08/2012: 14:00:39: volctrl.dll loaded successfully
24/08/2012: 14:00:39: <--Sending TFTP Request nasystem/who_is(In TFTP queue=1)
24/08/2012: 14:00:39: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=2)
24/08/2012: 14:00:40: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=2)
24/08/2012: 14:00:41: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=2)
24/08/2012: 14:00:42: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=2)
24/08/2012: 14:00:43: Resend TFTP: 'nasystem/who_is'(tftp_r=0, tftp_w=2)
24/08/2012: 14:00:44: Connecting(Extn201)...
24/08/2012: 14:00:44: Start GetASCIICMCommand with char
24/08/2012: 14:00:44: Start GetASCIICMCommand
24/08/2012: 14:00:44: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:45: Start GetASCIICMCommand with char
24/08/2012: 14:00:45: Start GetASCIICMCommand
24/08/2012: 14:00:45: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:46: Start GetASCIICMCommand with char
24/08/2012: 14:00:46: Start GetASCIICMCommand
24/08/2012: 14:00:46: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:47: Start GetASCIICMCommand with char
24/08/2012: 14:00:47: Start GetASCIICMCommand
24/08/2012: 14:00:47: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:48: PostLicenceCheck
24/08/2012: 14:00:48: Final Licence Level = -1
24/08/2012: 14:00:48: ReadRegistry:LicenceLevel=-1
24/08/2012: 14:00:48: DTMF play file (ding.wav) OK
24/08/2012: 14:00:48: Licence Level = -1 (Unlicenced)
24/08/2012: 14:00:49: Phone Manager (Unlicenced) [] Build 4.2.42 (Feb 9 2012 06:03:05)
24/08/2012: 14:00:49: Professional
24/08/2012: 14:00:49: (Build 7600)
24/08/2012: 14:00:49: Form Show (first_time=1)
24/08/2012: 14:00:49: ButtonAnswer->Enabled = False before mychange
24/08/2012: 14:00:49: Stop blinking
24/08/2012: 14:00:49: Done Form Show (first_time=0)
24/08/2012: 14:00:49: Start GetASCIICMCommand with char
24/08/2012: 14:00:49: Start GetASCIICMCommand
24/08/2012: 14:00:49: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:51: Start GetASCIICMCommand with char
24/08/2012: 14:00:51: Start GetASCIICMCommand
24/08/2012: 14:00:51: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:52: Start GetASCIICMCommand with char
24/08/2012: 14:00:52: Start GetASCIICMCommand
24/08/2012: 14:00:52: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:53: Start GetASCIICMCommand with char
24/08/2012: 14:00:53: Start GetASCIICMCommand
24/08/2012: 14:00:53: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:54: Start GetASCIICMCommand with char
24/08/2012: 14:00:54: Start GetASCIICMCommand
24/08/2012: 14:00:54: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:55: Start GetASCIICMCommand with char
24/08/2012: 14:00:55: Start GetASCIICMCommand
24/08/2012: 14:00:55: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:56: Start GetASCIICMCommand with char
24/08/2012: 14:00:56: Start GetASCIICMCommand
24/08/2012: 14:00:56: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:57: Disconnect(bServerDisconnect=0)
24/08/2012: 14:00:57: WriteRegistry:LicenceLevel=-1
24/08/2012: 14:00:57: Controls Count = 113
24/08/2012: 14:00:57: Disconnected
24/08/2012: 14:00:57: Connecting(Extn201)...
24/08/2012: 14:00:57: Start GetASCIICMCommand with char
24/08/2012: 14:00:57: Start GetASCIICMCommand
24/08/2012: 14:00:57: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:58: Start GetASCIICMCommand with char
24/08/2012: 14:00:58: Start GetASCIICMCommand
24/08/2012: 14:00:58: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:00:59: Start GetASCIICMCommand with char
24/08/2012: 14:00:59: Start GetASCIICMCommand
24/08/2012: 14:00:59: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:00: Start GetASCIICMCommand with char
24/08/2012: 14:01:00: Start GetASCIICMCommand
24/08/2012: 14:01:00: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:01: Start GetASCIICMCommand with char
24/08/2012: 14:01:01: Start GetASCIICMCommand
24/08/2012: 14:01:01: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:02: Start GetASCIICMCommand with char
24/08/2012: 14:01:02: Start GetASCIICMCommand
24/08/2012: 14:01:02: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:03: Start GetASCIICMCommand with char
24/08/2012: 14:01:03: Start GetASCIICMCommand
24/08/2012: 14:01:03: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:04: Start GetASCIICMCommand with char
24/08/2012: 14:01:04: Start GetASCIICMCommand
24/08/2012: 14:01:04: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:05: Start GetASCIICMCommand with char
24/08/2012: 14:01:05: Start GetASCIICMCommand
24/08/2012: 14:01:05: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:06: Start GetASCIICMCommand with char
24/08/2012: 14:01:06: Start GetASCIICMCommand
24/08/2012: 14:01:06: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:07: Start GetASCIICMCommand with char
24/08/2012: 14:01:07: Start GetASCIICMCommand
24/08/2012: 14:01:07: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:08: Disconnect(bServerDisconnect=0)
24/08/2012: 14:01:08: Disconnected
24/08/2012: 14:01:08: Connecting(Extn201)...
24/08/2012: 14:01:08: Start GetASCIICMCommand with char
24/08/2012: 14:01:08: Start GetASCIICMCommand
24/08/2012: 14:01:08: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:09: Start GetASCIICMCommand with char
24/08/2012: 14:01:09: Start GetASCIICMCommand
24/08/2012: 14:01:09: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:10: Start GetASCIICMCommand with char
24/08/2012: 14:01:10: Start GetASCIICMCommand
24/08/2012: 14:01:10: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:11: Start GetASCIICMCommand with char
24/08/2012: 14:01:11: Start GetASCIICMCommand
24/08/2012: 14:01:11: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:12: Start GetASCIICMCommand with char
24/08/2012: 14:01:12: Start GetASCIICMCommand
24/08/2012: 14:01:12: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:13: Start GetASCIICMCommand with char
24/08/2012: 14:01:13: Start GetASCIICMCommand
24/08/2012: 14:01:13: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:14: Start GetASCIICMCommand with char
24/08/2012: 14:01:14: Start GetASCIICMCommand
24/08/2012: 14:01:14: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:15: Start GetASCIICMCommand with char
24/08/2012: 14:01:15: Start GetASCIICMCommand
24/08/2012: 14:01:15: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:16: Start GetASCIICMCommand with char
24/08/2012: 14:01:16: Start GetASCIICMCommand
24/08/2012: 14:01:16: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:17: Start GetASCIICMCommand with char
24/08/2012: 14:01:17: Start GetASCIICMCommand
24/08/2012: 14:01:17: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:18: Start GetASCIICMCommand with char
24/08/2012: 14:01:18: Start GetASCIICMCommand
24/08/2012: 14:01:18: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:19: Disconnect(bServerDisconnect=0)
24/08/2012: 14:01:19: Disconnected
24/08/2012: 14:01:19: Connecting(Extn201)...
24/08/2012: 14:01:19: Start GetASCIICMCommand with char
24/08/2012: 14:01:19: Start GetASCIICMCommand
24/08/2012: 14:01:19: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:19: GetPBXConfig
24/08/2012: 14:01:19: <--Sending TFTP Request nasystem/who_is(In TFTP queue=0)
24/08/2012: 14:01:20: Start GetASCIICMCommand with char
24/08/2012: 14:01:20: Start GetASCIICMCommand
24/08/2012: 14:01:20: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:20: ConfigPBXOpen timeout
24/08/2012: 14:01:21: Start GetASCIICMCommand with char
24/08/2012: 14:01:21: Start GetASCIICMCommand
24/08/2012: 14:01:21: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:22: Start GetASCIICMCommand with char
24/08/2012: 14:01:22: Start GetASCIICMCommand
24/08/2012: 14:01:22: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:23: Start GetASCIICMCommand with char
24/08/2012: 14:01:23: Start GetASCIICMCommand
24/08/2012: 14:01:23: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:24: Start GetASCIICMCommand with char
24/08/2012: 14:01:24: Start GetASCIICMCommand
24/08/2012: 14:01:24: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:24: <--Sending TFTP Request nasystem/who_is(In TFTP queue=0)
24/08/2012: 14:01:25: Start GetASCIICMCommand with char
24/08/2012: 14:01:25: Start GetASCIICMCommand
24/08/2012: 14:01:25: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:25: ConfigPBXOpen timeout
24/08/2012: 14:01:26: Start GetASCIICMCommand with char
24/08/2012: 14:01:26: Start GetASCIICMCommand
24/08/2012: 14:01:26: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:27: Start GetASCIICMCommand with char
24/08/2012: 14:01:27: Start GetASCIICMCommand
24/08/2012: 14:01:27: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:28: Start GetASCIICMCommand with char
24/08/2012: 14:01:28: Start GetASCIICMCommand
24/08/2012: 14:01:28: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:29: Start GetASCIICMCommand with char
24/08/2012: 14:01:29: Start GetASCIICMCommand
24/08/2012: 14:01:29: <--SendQueuedCMMsg(seq=0, cmd=CMPCPktOpenExtended)(Queued=1)
24/08/2012: 14:01:29: GetPBXConfig
24/08/2012: 14:01:30: Disconnect(bServerDisconnect=0)
24/08/2012: 14:01:30: Disconnected
24/08/2012: 14:01:32: <--Sending TFTP Request nasystem/who_is(In TFTP queue=0)
24/08/2012: 14:01:32: <--Sending TFTP Request nasystem/who_is(In TFTP queue=1)
24/08/2012: 14:01:32: <--Sending TFTP Request nasystem/who_is(In TFTP queue=2)
24/08/2012: 14:01:33: ConfigPBXOpen timeout
24/08/2012: 14:01:33: <--Sending TFTP Request nasystem/who_is(In TFTP queue=2)
24/08/2012: 14:01:33: <--Sending TFTP Request nasystem/who_is(In TFTP queue=3)
24/08/2012: 14:01:34: <--Sending TFTP Request nasystem/who_is(In TFTP queue=4)
24/08/2012: 14:01:34: <--Sending TFTP Request nasystem/who_is(In TFTP queue=5)
24/08/2012: 14:01:34: ConfigPBXOpen timeout
24/08/2012: 14:01:34: <--Sending TFTP Request nasystem/who_is(In TFTP queue=5)
24/08/2012: 14:01:35: <--Sending TFTP Request nasystem/who_is(In TFTP queue=6)
24/08/2012: 14:01:35: <--Sending TFTP Request nasystem/who_is(In TFTP queue=7)
24/08/2012: 14:01:35: ConfigPBXOpen timeout
24/08/2012: 14:01:35: <--Sending TFTP Request nasystem/who_is(In TFTP queue=7)
24/08/2012: 14:01:36: ConfigPBXOpen timeout
24/08/2012: 14:01:37: ConfigPBXOpen timeout
24/08/2012: 14:01:38: ConfigPBXOpen timeout
24/08/2012: 14:01:39: ConfigPBXOpen timeout
24/08/2012: 14:01:40: ConfigPBXOpen timeout
24/08/2012: 14:01:41: <--Sending TFTP Request nasystem/who_is(In TFTP queue=3)
24/08/2012: 14:01:41: ConfigPBXOpen timeout
24/08/2012: 14:01:41: <--Sending TFTP Request nasystem/who_is(In TFTP queue=3)
24/08/2012: 14:01:42: <--Sending TFTP Request nasystem/who_is(In TFTP queue=4)
24/08/2012: 14:01:42: ConfigPBXOpen timeout
24/08/2012: 14:01:42: <--Sending TFTP Request nasystem/who_is(In TFTP queue=4)
24/08/2012: 14:01:43: ConfigPBXOpen timeout
24/08/2012: 14:01:44: ConfigPBXOpen timeout
24/08/2012: 14:01:45: ConfigPBXOpen timeout
24/08/2012: 14:01:46: ConfigPBXOpen timeout
24/08/2012: 14:01:46: Refresh
24/08/2012: 14:01:46: TIPOBrowse::GetSubnets ip = 172.22.222.108
24/08/2012: 14:01:47: ConfigPBXOpen timeout
24/08/2012: 14:01:57: TIPOBrowse:

opulateList count=0
24/08/2012: 14:02:08: <--Sending TFTP Request nasystem/who_is(In TFTP queue=0)