FTPD32 and Cisco IP Phones
#1
Posted 13 March 2009 - 12:46 PM
I am trying to connect my Cisco IP 7965 to grab newest firmware files from the TFTP server.
I can see in the log viewer is trying to get an IP from the DHCP server but it doesn't get one. The DHCP server works, my laptop connects just fine.
See attached images.
Thanks in advance!
--
It also says:
Rcvd DHCP Discover Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [13/03 14:21:48.778]
DHCP: proposed address 192.168.178.42 [13/03 14:22:00.727]
330176 Request 2 not processed [13/03 14:22:00.779]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [13/03 14:22:00.829]
Previously allocated address 192.168.178.42 acked [13/03 14:22:12.727]
Connection received from 192.168.178.42 on port 49152 [13/03 14:22:12.771]
Read request for file <term65.default.loads>. Mode octet [13/03 14:22:12.771]
File <term65.default.loads> : error 2 in system call CreateFile The system cannot find the file specified. [13/03 14:22:12.771]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [13/03 14:22:12.778]
Suppress pingable address 192.168.178.42 [13/03 14:22:12.790]
Connection received from 192.168.178.42 on port 49153 [13/03 14:22:12.946]
Read request for file <term65.default.loads>. Mode octet [13/03 14:22:12.946]
File <term65.default.loads> : error 2 in system call CreateFile The system cannot find the file specified. [13/03 14:22:12.946]
Previously allocated address 192.168.178.43 acked [13/03 14:22:24.727]
330176 Request 2 not processed [13/03 14:22:24.778]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [13/03 14:22:24.828]
Client requested address 192.168.178.42 which was not allocated by tftpd32 and is either outside our pool or is used by someone else [13/03 14:22:24.828]
330176 Request 2 not processed [13/03 14:22:24.878]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [13/03 14:22:24.928]
Client requested address 192.168.178.42 which was not allocated by tftpd32 and is either outside our pool or is used by someone else [13/03 14:22:24.929]
330176 Request 2 not processed [13/03 14:22:24.979]
330176 Request 2 not processed [13/03 14:22:25.029]
#2
Posted 16 March 2009 - 11:23 AM
Here is the "Hello World" section and I think you're looking for the TFTPD32 section., that is probably the reason why it is probably ignored.
Have you already tried using wireshark and see what is the output given by the IP phone?
This might give some clues.
Also, is this issue also occuring to other IP phones or just this one?
#3
Posted 16 March 2009 - 11:50 AM
jaclaz
#4
Posted 18 March 2009 - 06:26 PM
This is occuring on just this one phone, since I so far haven't connected the other phones. First want to figure out what I did wrong before I kill another phone, haha.
I'll give Wireshark a go!
#5
Posted 08 April 2009 - 06:12 PM
Also make sure to do a good RTFM on a firmware update procedure on a particular phone. Cisco like changing small details around their model range and since you're doing it for the first time I would recommend following all the steps in a procedure exactly as it says and using recommended tftp server. Only when you succeed with a "stock" method you can try introducing some "tweaks".
Regards,
Andrius
#7
Posted 13 March 2010 - 11:06 PM
#8
Posted 13 March 2010 - 11:09 PM
Shall I make some screenshots of the total settings I have set?
#9
Posted 13 March 2010 - 11:15 PM
#11
Posted 13 March 2010 - 11:32 PM
#12
Posted 13 March 2010 - 11:41 PM
Ethernet adapter Local Area Connection:
Connection-specific DNS Suffix . :
Description . . . . . . . . . . . : Marvell Yukon 88E8053 PCI-E Gigabit Ether
net Controller
Physical Address. . . . . . . . . : 00-17-31-AF-A6-2E
DHCP Enabled. . . . . . . . . . . : Yes
Autoconfiguration Enabled . . . . : Yes
Link-local IPv6 Address . . . . . : fe80::1da0:6841:fa80:4216%8(Preferred)
IPv4 Address. . . . . . . . . . . : 192.168.178.47(Preferred)
Subnet Mask . . . . . . . . . . . : 255.255.255.0
Lease Obtained. . . . . . . . . . : maandag 8 maart 2010 4:15:06
Lease Expires . . . . . . . . . . : dinsdag 23 maart 2010 4:15:01
Default Gateway . . . . . . . . . : 192.168.178.1
DHCP Server . . . . . . . . . . . : 192.168.178.1
DNS Servers . . . . . . . . . . . : 192.168.178.1
NetBIOS over Tcpip. . . . . . . . : Enabled
I've cleared all the allocated IP addresses, problem remains.
I do notice that when I connect the Cisco 2 IP's are given. one for the phone, and one for a mac address I don't know (the 45:45 in the screenshot). Maybe it's the TFTPD server?
#13
Posted 13 March 2010 - 11:50 PM
#14
Posted 13 March 2010 - 11:55 PM
I'll try with the notebook!
#15
Posted 14 March 2010 - 12:26 AM
Rcvd DHCP Discover Msg for IP 0.0.0.0, Mac 00:13:A9:3B:CA:17 [14/03 01:07:08.656]
Client requested address 192.168.178.27 [14/03 01:07:08.656]
DHCP: proposed address 192.168.178.200 [14/03 01:07:11.140]
3596 Request 2 not processed [14/03 01:07:11.203]
Rcvd DHCP Discover Msg for IP 0.0.0.0, Mac 00:13:A9:3B:CA:17 [14/03 01:07:11.250]
Client requested address 192.168.178.27 [14/03 01:07:11.250]
DHCP: proposed address 192.168.178.200 [14/03 01:07:12.750]
3596 Request 2 not processed [14/03 01:07:12.812]
Rcvd DHCP Discover Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [14/03 01:07:13.703]
Suppress pingable address 192.168.178.201 [14/03 01:07:15.765]
DHCP: proposed address 192.168.178.202 [14/03 01:07:17.328]
3596 Request 2 not processed [14/03 01:07:17.390]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [14/03 01:07:17.453]
Previously allocated address 192.168.178.202 acked [14/03 01:07:18.953]
3596 Request 2 not processed [14/03 01:07:19.015]
Rcvd DHCP Discover Msg for IP 0.0.0.0, Mac 00:13:A9:3B:CA:17 [14/03 01:07:20.953]
Client requested address 192.168.178.27 [14/03 01:07:20.953]
DHCP: proposed address 192.168.178.200 [14/03 01:07:23.140]
3596 Request 2 not processed [14/03 01:07:23.218]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:13:A9:3B:CA:17 [14/03 01:07:23.265]
Previously allocated address 192.168.178.200 acked [14/03 01:07:24.765]
3596 Request 2 not processed [14/03 01:07:24.890]
Freeing item 192.168.178.200 00:13:A9:3B:CA:17 [14/03 01:08:24.515]
Message truncated (length was 8) [14/03 01:08:24.546]
Freeing item 192.168.178.202 00:21:55:D5:B2:FC [14/03 01:08:26.984]
Message truncated (length was 8) [14/03 01:08:27.000]
Freeing item 192.168.178.201 46:46:3A:46:46:3A [14/03 01:08:29.328]
Message truncated (length was 8) [14/03 01:08:29.328]
Rcvd DHCP Discover Msg for IP 0.0.0.0, Mac 00:13:A9:3B:CA:17 [14/03 01:11:19.656]
Client requested address 192.168.178.200 [14/03 01:11:19.656]
DHCP: proposed address 192.168.178.200 [14/03 01:11:22.140]
3596 Request 2 not processed [14/03 01:11:22.203]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:13:A9:3B:CA:17 [14/03 01:11:22.250]
Previously allocated address 192.168.178.200 acked [14/03 01:11:23.750]
3596 Request 2 not processed [14/03 01:11:23.843]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [14/03 01:11:24.078]
Rcvd DHCP Discover Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [14/03 01:11:56.078]
DHCP: proposed address 192.168.178.201 [14/03 01:11:58.140]
3596 Request 2 not processed [14/03 01:11:58.187]
Rcvd DHCP Rqst Msg for IP 0.0.0.0, Mac 00:21:55:D5:B2:FC [14/03 01:11:58.234]
Previously allocated address 192.168.178.201 acked [14/03 01:11:59.750]
Connection received from 192.168.178.201 on port 49152 [14/03 01:11:59.781]
Read request for file <term65.default.loads>. Mode octet [14/03 01:11:59.781]
Using local port 4591 [14/03 01:11:59.781]
3596 Request 2 not processed [14/03 01:11:59.796]
<term65.default.loads>: sent 2 blks, 642 bytes in 0 s. 0 blk resent [14/03 01:11:59.859]
Connection received from 192.168.178.201 on port 49153 [14/03 01:12:00.765]
Read request for file <term65.default.loads>. Mode octet [14/03 01:12:00.765]
Using local port 4592 [14/03 01:12:00.765]
<term65.default.loads>: sent 2 blks, 642 bytes in 0 s. 0 blk resent [14/03 01:12:00.828]
Rcvd DHCP inform Msg for IP 192.168.178.200, Mac 00:13:A9:3B:CA:17 [14/03 01:16:00.203]
Rcvd DHCP inform Msg for IP 192.168.178.200, Mac 00:13:A9:3B:CA:17 [14/03 01:16:03.218]
Now it doesn't look like it's doing much...
#16
Posted 14 March 2010 - 01:28 AM
I suspect you have another DHCP server on your network which was supplying IP address to the phone quicker than TFTPD32, hence the confusing messages on the previous log. Ipconfig dump you posted suggests that there's an active DHCP server too.
#17
Posted 14 March 2010 - 03:32 PM
The DHCP thing I don't get, router is switched off. Last log is from the notebook though so that should work.. (although FTPD32 keeps giving it's server interface a different IP with every try from the phone..)
#18
Posted 14 March 2010 - 05:13 PM
#19
Posted 14 March 2010 - 06:06 PM
The way I'm going for this should load all the firmware files according to several articles I found online but somehow it's not happening.
edit:
the term65.default.loads tells the phone what do download next:
But somehow after getting the term65.default.loads the phone restarts (which means it started updating) but then TFTPD32 interfears and it looks like it then messes up the updating (see log above?)#
# This file contains a list of archive image files that will be requested by the
# RELEASE load version 9-0-2ES2
#
jar45sip.9-0-2ES2.sbn
cnu45.9-0-2ES2.sbn
apps45.9-0-2ES2.sbn
dsp45.9-0-2ES2.sbn
cvm45sip.9-0-2ES2.sbn
#20
Posted 14 March 2010 - 07:09 PM
#21
Posted 14 March 2010 - 07:20 PM
But for some reason the proces stops after that files is put on the phone, the rest of the files are untouched.
Edit: I just downloaded the oldest firmware that Cisco is offering. It worke! Now I'll update to the newer versions.
#22
Posted 14 March 2010 - 09:18 PM
0 user(s) are reading this topic
0 members, 0 guests, 0 anonymous users