Home » Forums » Community Edition » Open Discussion

tribox doesnt seem to work post windows sbs2003 to sbs2008 upgrade

ambroisie
Posts: 134
 Member Since:
2007-10-30
Submitted by ambroisie on Mon, 01/11/2010 - 1:31am.

Hi

Earlier today i cutover my SBS2003 server to a new SBS2008 server. As far as i can ascertain the configuration as far as phones is concerned is the same. the domain name is the same. DNS is the same. static IP of the server is the same...etc

The SBS acts as DHCP with reserved addressed for specific phones. The DHCP settings appear to be downloaded correctly to the phones so these then use the same old config files in Trixbox. no changes have been made to trixbox, the phones, or any other part of the network. all i did was basically disconnect the old server, reconnect the new, and go around and reboot everything. however no calls in or out of trixbox work. all lines and trunks show as unreaachable in trixbox status and there are no logs egernated on trixbox itself in respect of the phones.

this suggests to me that the phones have the problem, but i cannot guess what it might be, as the configurations inspected from the phone menus appear unchanged (i verified that they were downloading correcntly by changing the DNS to another ISP, for example, and then changin back to the required DNS). the phones i have are a mixture of Cisco 7960 and Polycom 550, 650. no difference in way of workin for each. calls just seem to time ou and go to a fast busy tone.

any ideas on how i might debug or resolve this ? i am sure it should be something simple...

any suggestions would be much appreciated.

cheers

david

‹ fail2ban causing high cpu usage operating system ›

&nbsp

SkykingOH
Posts: 9677
 Member Since:
2007-12-17
Did you remember to setup
Mon, 01/11/2010 - 8:48am

Did you remember to setup option 66 in the DHCP scope on the new server?

Are the phones really pulling the config files (watch /var/log/atftpd.log while you reboot a phone).

--

Scott

aka "Skyking"


&nbsp

ambroisie
Posts: 134
 Member Since:
2007-10-30
well i think so...
Mon, 01/11/2010 - 12:10pm

i will double check this today...but i believed this was correct otherwise changing eg DNS address in the SBS server would not have appeared in the phone config memory ???


&nbsp

ambroisie
Posts: 134
 Member Since:
2007-10-30
update on aftpd
Tue, 01/12/2010 - 3:50am

hi, i have taken the contetn of the log file and pasted it below...seems to confirm that config is bein downloaded

1 Jan 11 09:00:09 ambroisietrixalt.local atftpd[12237.-1208179008]: Advanced Trivial FTP server started (0.7)
2 Jan 11 09:00:09 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving CTLSEP0015FADCA5BF.tlv to 10.10.99.161:50795
3 Jan 11 09:00:09 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving SEP0015FADCA5BF.cnf.xml to 10.10.99.161:50796
4 Jan 11 09:00:09 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving SIP0015FADCA5BF.cnf to 10.10.99.161:50797
5 Jan 11 09:00:09 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving SIPDefault.cnf to 10.10.99.161:50798
6 Jan 11 09:00:09 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving ./SIP0015FADCA5BF.cnf to 10.10.99.161:50799
7 Jan 11 09:00:09 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving P0S3-08-9-00.loads to 10.10.99.161:50800
8 Jan 11 09:00:30 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving SIPDefault.cnf to 10.10.99.161:50784
9 Jan 11 09:00:30 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving ./SIP0015FADCA5BF.cnf to 10.10.99.161:50785
10 Jan 11 09:00:33 ambroisietrixalt.local atftpd[12237.-1208181872]: Serving RINGLIST.DAT to 10.10.99.161:50792
11 Jan 11 09:00:33 ambroisietrixalt.local atftpd[12237.-1218671728]: Serving dialplan.xml to 10.10.99.161:50793
12 Jan 11 09:05:32 ambroisietrixalt.local atftpd[12237.-1208179008]: atftpd terminating after 300 seconds
13 Jan 11 09:05:32 ambroisietrixalt.local atftpd[12237.-1208179008]: Main thread exiting
14 Jan 11 09:15:14 ambroisietrixalt.local atftpd[5551.-1208600896]: Advanced Trivial FTP server started (0.7)
15 Jan 11 09:15:14 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving 2345-12600-001.bootrom.ld to 10.10.99.170:1026
16 Jan 11 09:15:14 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving bootrom.ld to 10.10.99.170:1027
17 Jan 11 09:15:14 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving 0004f21452fa.cfg to 10.10.99.170:1028
18 Jan 11 09:15:14 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving 2345-12600-001.sip.ld to 10.10.99.170:1029
19 Jan 11 09:15:19 ambroisietrixalt.local atftpd[5551.-1208603760]: Fetching from 10.10.99.170 to polycom/logs/0004f21452fa-boot.log
20 Jan 11 09:15:35 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving 2345-12600-001.bootrom.ld to 10.10.99.170:1026
21 Jan 11 09:15:35 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving bootrom.ld to 10.10.99.170:1027
22 Jan 11 09:15:36 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving 0004f21452fa.cfg to 10.10.99.170:1028
23 Jan 11 09:15:36 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving 2345-12600-001.sip.ld to 10.10.99.170:1029
24 Jan 11 09:15:36 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving 0004f21452fareg.cfg to 10.10.99.170:1030
25 Jan 11 09:15:36 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving server.cfg to 10.10.99.170:1031
26 Jan 11 09:15:36 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving local-settings.cfg to 10.10.99.170:1032
27 Jan 11 09:15:36 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving sip.cfg to 10.10.99.170:1033
28 Jan 11 09:15:37 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving polycom/overrides/0004f21452fa-phone.cfg to 10.10.99.170:1034
29 Jan 11 09:15:38 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving polycom/contacts/0004f21452fa-directory.xml to 10.10.99.170:1036
30 Jan 11 09:15:42 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving SoundPointIPWelcome.wav to 10.10.99.170:1037
31 Jan 11 09:15:43 ambroisietrixalt.local atftpd[5551.-1219093616]: Serving 000000000000-license.cfg to 10.10.99.170:1038
32 Jan 11 09:15:43 ambroisietrixalt.local atftpd[5551.-1219093616]: Serving 0004f21452fa-license.cfg to 10.10.99.170:1039
33 Jan 11 09:15:44 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving Leaf.bmp to 10.10.99.170:1040
34 Jan 11 09:15:44 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving World.bmp to 10.10.99.170:1041
35 Jan 11 09:20:06 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving CTLSEP0015FADCA5BF.tlv to 10.10.99.161:50795
36 Jan 11 09:20:06 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving SEP0015FADCA5BF.cnf.xml to 10.10.99.161:50796
37 Jan 11 09:20:06 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving SIP0015FADCA5BF.cnf to 10.10.99.161:50797
38 Jan 11 09:20:06 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving SIPDefault.cnf to 10.10.99.161:50798
39 Jan 11 09:20:06 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving ./SIP0015FADCA5BF.cnf to 10.10.99.161:50799
40 Jan 11 09:20:06 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving P0S3-08-9-00.loads to 10.10.99.161:50800
41 Jan 11 09:20:27 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving SIPDefault.cnf to 10.10.99.161:50785
42 Jan 11 09:20:27 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving ./SIP0015FADCA5BF.cnf to 10.10.99.161:50786
43 Jan 11 09:20:29 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving RINGLIST.DAT to 10.10.99.161:50793
44 Jan 11 09:20:30 ambroisietrixalt.local atftpd[5551.-1208603760]: Serving dialplan.xml to 10.10.99.161:50794
45 Jan 11 09:25:30 ambroisietrixalt.local atftpd[5551.-1208600896]: atftpd terminating after 300 seconds
46 Jan 11 09:25:30 ambroisietrixalt.local atftpd[5551.-1208600896]: Main thread exiting
47 Jan 11 09:53:51 ambroisietrixalt.local atftpd[5657.-1208187200]: Advanced Trivial FTP server started (0.7)
48 Jan 11 09:53:51 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving CTLSEP0015FADCA5BF.tlv to 10.10.99.161:50795
49 Jan 11 09:53:51 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving SEP0015FADCA5BF.cnf.xml to 10.10.99.161:50796
50 Jan 11 09:53:51 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving SIP0015FADCA5BF.cnf to 10.10.99.161:50797
51 Jan 11 09:53:51 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving SIPDefault.cnf to 10.10.99.161:50798
52 Jan 11 09:53:51 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving ./SIP0015FADCA5BF.cnf to 10.10.99.161:50799
53 Jan 11 09:53:51 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving P0S3-08-9-00.loads to 10.10.99.161:50800
54 Jan 11 09:54:12 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving SIPDefault.cnf to 10.10.99.161:50785
55 Jan 11 09:54:12 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving ./SIP0015FADCA5BF.cnf to 10.10.99.161:50786
56 Jan 11 09:54:14 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving RINGLIST.DAT to 10.10.99.161:50793
57 Jan 11 09:54:15 ambroisietrixalt.local atftpd[5657.-1208190064]: Serving dialplan.xml to 10.10.99.161:50794
58 Jan 11 09:59:15 ambroisietrixalt.local atftpd[5657.-1208187200]: atftpd terminating after 300 seconds
59 Jan 11 09:59:15 ambroisietrixalt.local atftpd[5657.-1208187200]: Main thread exiting

and what follows is a cut and paste from earlier in the year when these were last rebooted...

Jul 16 14:23:02 ambroisietrixalt.local atftpd[26328.-1209018688]: Advanced Trivial FTP server started (0.7)
Jul 16 14:23:02 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving 2345-12600-001.bootrom.ld to 10.10.99.170:1026
Jul 16 14:23:02 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving bootrom.ld to 10.10.99.170:1027
Jul 16 14:23:02 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving 0004f21452fa.cfg to 10.10.99.170:1028
Jul 16 14:23:02 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving 2345-12600-001.sip.ld to 10.10.99.170:1029
Jul 16 14:23:07 ambroisietrixalt.local atftpd[26328.-1209021552]: Fetching from 10.10.99.170 to polycom/logs/0004f21452fa-boot.log
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving 2345-12600-001.bootrom.ld to 10.10.99.170:1026
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving bootrom.ld to 10.10.99.170:1027
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving 0004f21452fa.cfg to 10.10.99.170:1028
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving 2345-12600-001.sip.ld to 10.10.99.170:1029
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving 0004f21452fareg.cfg to 10.10.99.170:1030
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving server.cfg to 10.10.99.170:1031
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving local-settings.cfg to 10.10.99.170:1032
Jul 16 14:23:23 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving sip.cfg to 10.10.99.170:1033
Jul 16 14:23:24 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving polycom/overrides/0004f21452fa-phone.cfg to 10.10.99.170:1034
Jul 16 14:23:25 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving polycom/contacts/0004f21452fa-directory.xml to 10.10.99.170:1036
Jul 16 14:23:29 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving SoundPointIPWelcome.wav to 10.10.99.170:1037
Jul 16 14:23:30 ambroisietrixalt.local atftpd[26328.-1219511408]: Serving 000000000000-license.cfg to 10.10.99.170:1038
Jul 16 14:23:30 ambroisietrixalt.local atftpd[26328.-1219511408]: Serving 0004f21452fa-license.cfg to 10.10.99.170:1039
Jul 16 14:23:31 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving Leaf.bmp to 10.10.99.170:1040
Jul 16 14:23:31 ambroisietrixalt.local atftpd[26328.-1209021552]: Serving World.bmp to 10.10.99.170:1041
Jul 16 14:28:31 ambroisietrixalt.local atftpd[26328.-1209018688]: atftpd terminating after 300 seconds

so does anyone spot any material difference or understand what might be going on here ?

thanks in advance

cheers

david


&nbsp

ambroisie
Posts: 134
 Member Since:
2007-10-30
/tftpboot/polycom phone log
Tue, 01/12/2010 - 3:59am

not sure if this is useful or not...or what period of time it ran over....i think this could cover old data as my testing was from the 11th,..nothing prior (and the timestamp tha tis mentioned at the end says Jan 10 !)

[ambroisietrixalt.local logs]# more *21452*
::::::::::::::
0004f21452fa-appFlash.log
::::::::::::::
0716040717|so |*|03|---------- Initial log entry ----------
0716040717|so |*|03|Platform: Model=SoundPoint IP 650, Assembly=2345-12600-001 Rev=B
0716040717|so |*|03|Platform: MAC=0004f21452fa, IP=10.10.99.170, Subnet Mask=255.255.255.0
0716040717|so |*|03|Platform: BootBlock=2.7.0 (12600_001) 23-Oct-06 10:45
0716040717|so |*|03|Platform: Bootrom=4.0.0.0423 08-Aug-07 17:50
0716040717|so |*|03|Application, main: Label=SIP, Version=2.2.0.0047 07-Aug-07 15:24
0716040717|so |*|03|Application, main: P/N=3150-11530-220
0716040717|so |*|03|System Info Reports:
0716040717|so |*|03| CPU is TNETV1050/C55x, rev 2 running at 162MHz with memory at 125MHz.
0716040717|so |*|03| Board is identified as PolycomSoundPointIP-SPIP_650.
0716040717|so |*|03| DRAM_LO: 0x94000000. DRAM_SIZE: 32 MB
0716040717|so |*|03| Clocks are VBUSP: 125MHz, USB: 25MHz, LCD: 62MHz.
0716040717|ethf |*|03|Initial log entry. Current logging level 4
0716040717|so |5|03|utilCertificateInit failed.
0716040717|hw |*|03|Initial log entry. Current logging level 4
0716040717|ares |*|03|Initial log entry. Current logging level 4
0716040717|dns |*|03|Initial log entry. Current logging level 3
0716040717|cfg |*|03|Initial log entry. Current logging level 3
0716040717|cfg |3|03|RT|Runtime basic IP parameters updated.
0716040717|cfg |3|03|RT|Runtime provisioning server parameters updated.
0716040717|cfg |3|03|RT|Runtime SNTP parameters updated.
0716040717|dns |*|03|DNS resolver servers are '192.231.203.3' '192.231.203.132'
0716040717|dns |*|03|DNS resolver search domain is 'ambroisie.local'
0716040717|log |*|03|Initial log entry. Current logging level 4
0716040717|so |4|03|[SoFontsC]: Font item (6)(1) is NULL.
0716040717|curl |*|03|Initial log entry. Current logging level 3
0716040717|utilm|*|03|Initial log entry. Current logging level 4
0716040717|copy |*|03|Initial log entry. Current logging level 3
0716040717|rtos |*|03|Initial log entry. Current logging level 4
0716040717|sec |*|03|Initial log entry. Current logging level 4
0716040717|cfg |3|03|Prm|Beginning to provision phone
0716040717|copy |3|03|'tftp://10.10.99.109/2345-12600-001.bootrom.ld' from '10.10.99.109'
0716040717|copy |3|03|TFTP download error
0716040717|copy |3|03|tftpLib error: tftp transfer failed: error 0x4b0007

0716040717|copy |3|03|Making further download attempts for '2345-12600-001.bootrom.ld'
0716040717|copy |4|03|Download of '2345-12600-001.bootrom.ld' FAILED on attempt 1
0716040717|copy |3|03|'tftp://10.10.99.109/2345-12600-001.bootrom.ld' from '10.10.99.109'
0716040717|copy |3|03|TFTP download error
0716040717|copy |3|03|tftpLib error: tftp transfer failed: error 0x43

0716040717|copy |3|03|Making further download attempts for '2345-12600-001.bootrom.ld'
0716040717|copy |4|03|Download of '2345-12600-001.bootrom.ld' FAILED on attempt 2
0716040717|copy |3|03|'tftp://10.10.99.109/2345-12600-001.bootrom.ld' from '10.10.99.109'
0716040717|copy |3|03|TFTP download error
0716040717|copy |3|03|tftpLib error: tftp transfer failed: error 0x43

0716040717|copy |4|03|Download of '2345-12600-001.bootrom.ld' FAILED on attempt 3
::::::::::::::
0004f21452fa-boot.log
::::::::::::::
0100000000|so |4|00|---------- Initial log entry ----------
0100000000|so |4|00|+++ Note that bootrom log times are in GMT +++
0100000000|cfg |4|00|Initial log entry
0100000000|copy |3|00|Initial log entry
0100000000|hw |4|00|Initial log entry.
0100000000|ethf |4|00|Initial log entry.
0610001556|wdog |4|00|Initial log entry
0610001556|cdp |3|00|CDP is DISABLED.
0610001556|so |3|00|Platform: Model=SoundPoint IP 650, Assembly=2345-12600-001 Rev=B
0610001556|so |3|00|Platform: Board=2345-12600-001 1
0610001556|so |3|00|Platform: MAC=0004f21452fa, IP=Resolving, Subnet Mask=Resolving
0610001556|so |3|00|Platform: BootBlock=2.7.0 (12600_001) 23-Oct-06 10:45
0610001556|so |3|00|Application, main: Label=BOOT, Version=4.1.0.0219 10-Dec-07 12:52
0610001556|so |3|00|Application, main: P/N=3150-11069-410
0610001556|app1 |4|00|Initial log entry.
0610001557|so |3|00|Link status is Net down, PC down.
0610001558|so |3|00|Link status is Net up Speed 100 full Duplex, PC down.
0610001558|cdp |3|00|CDP is disabled.
0610001604|app1 |3|00|DNS resolver servers are '192.231.203.3' '192.231.203.132'
0610001604|app1 |3|00|DNS resolver search domain is 'ambroisie.local'
0610001604|app1 |3|00|Bootline: esw(3,0)bootHost:flash e=10.10.99.170:ffffff00:a8c00:5 h=10.10.99.108 g=10.10.99.69 u=PlcmSpIp pw=**** f=0xc0 tn=Circa
0610001604|app1 |3|00|Bootline: IP
0610001604|app1 |3|00|DHCP returned result 0x3EF from server 10.10.99.119.
0610001604|app1 |3|00| Phone IP address is 10.10.99.170.
0610001604|app1 |3|00| Subnet mask is 255.255.255.0.
0610001604|app1 |3|00| Gateway address is 10.10.99.69.
0610001604|app1 |3|00| Boot server address is 10.10.99.108.
0610001604|app1 |3|00| Time server is 192.231.203.132.
0610001604|app1 |3|00| GMT offset is 0 seconds.
0610001604|app1 |3|00| DNS server is 192.231.203.3.
0610001604|app1 |3|00| DNS alternate server is 192.231.203.132.
0610001604|app1 |3|00| DNS domain is ambroisie.local.
0610001606|cfg |3|00|Beginning to provision phone
0610001606|copy |3|00|'tftp://10.10.99.108/2345-12600-001.bootrom.ld' from '10.10.99.108'
0610001606|copy |3|00|TFTP download error
0610001606|copy |3|00|tftpLib error: tftp transfer failed: error 0x4b0008

0610001606|copy |4|00|Download of '2345-12600-001.bootrom.ld' FAILED on attempt 1
0610001606|cfg |4|00|Could not get all 512 bytes of the header
0610001606|copy |3|00|'tftp://10.10.99.108/bootrom.ld' from '10.10.99.108'
0610001606|cfg |3|00|Image bootrom.ld has not changed
0610001606|copy |3|00|buffered_write: transfer terminated
0610001606|copy |3|00|Download of 'bootrom.ld' succeeded on attempt 1 (addr 1 of 1)
0610001606|cfg |3|00|Downloaded bootROM is identical to current version 4.1.0
0610001606|copy |3|00|'tftp://10.10.99.108/0004f21452fa.cfg' from '10.10.99.108'
0610001606|copy |3|00|Download of '0004f21452fa.cfg' succeeded on attempt 1 (addr 1 of 1)
0610001606|copy |3|00|'tftp://10.10.99.108/2345-12600-001.sip.ld' from '10.10.99.108'
0610001606|cfg |3|00|Image 2345-12600-001.sip.ld has not changed
0610001606|copy |3|00|buffered_write: transfer terminated
0610001606|copy |3|00|Download of '2345-12600-001.sip.ld' succeeded on attempt 1 (addr 1 of 1)
0610001606|cfg |3|00|Downloaded application image is identical to current version
0610001606|cfg |3|00|Phone successfully provisioned
0610001606|app1 |3|00|Application, load: Type=SIP, Version=3.0.1.0032 05-Mar-08 13:31
0610002949|app1 |3|00|Time has been set from 192.231.203.132 (192.231.203.132).
0610002949|app1 |4|00|Loaded application sip.ld successfully, errors 0x20.
0610002949|app1 |6|00|Uploading boot log, time is WED JUN 10 00:29:50 2009

0610002950|copy |3|00|'tftp://10.10.99.108/polycom/logs/0004f21452fa-boot.log' to '10.10.99.108'
0610002950|copy |3|00|Upload of 'polycom/logs/0004f21452fa-boot.log' succeeded on attempt 1 (addr 1 of 1)
0100000000|so |4|00|---------- Initial log entry ----------
0100000000|so |4|00|+++ Note that bootrom log times are in GMT +++
0100000000|cfg |4|00|Initial log entry
0100000000|copy |3|00|Initial log entry
0100000000|hw |4|00|Initial log entry.
0100000000|ethf |4|00|Initial log entry.
0621003103|wdog |4|00|Initial log entry
0621003103|cdp |3|00|CDP is DISABLED.
0621003103|so |3|00|Platform: Model=SoundPoint IP 650, Assembly=2345-12600-001 Rev=B
0621003103|so |3|00|Platform: Board=2345-12600-001 1
0621003103|so |3|00|Platform: MAC=0004f21452fa, IP=Resolving, Subnet Mask=Resolving
0621003103|so |3|00|Platform: BootBlock=2.7.0 (12600_001) 23-Oct-06 10:45
0621003103|so |3|00|Application, main: Label=BOOT, Version=4.1.0.0219 10-Dec-07 12:52
0621003103|so |3|00|Application, main: P/N=3150-11069-410
0621003103|app1 |4|00|Initial log entry.
0621003104|so |3|00|Link status is Net down, PC down.
0621003105|so |3|00|Link status is Net up Speed 100 full Duplex, PC down.
0621003105|cdp |3|00|CDP is disabled.
0621003111|app1 |3|00|DNS resolver servers are '192.231.203.132' '192.231.203.3'
0621003111|app1 |3|00|DNS resolver search domain is 'ambroisie.local'
0621003111|app1 |3|00|Bootline: esw(3,0)bootHost:flash e=10.10.99.170:ffffff00:a8c00:5 h=10.10.99.108 g=10.10.99.69 u=PlcmSpIp pw=**** f=0xc0 tn=Circa
0621003111|app1 |3|00|Bootline: IP
0621003250|app1 |3|00|Time has been set from 10.10.99.108 (10.10.99.108).
0621003250|app1 |3|00|DHCP returned result 0x3E7 from server 10.10.99.119.
0621003250|app1 |3|00| Phone IP address is 10.10.99.170.
0621003250|app1 |3|00| Subnet mask is 255.255.255.0.
0621003250|app1 |3|00| Gateway address is 10.10.99.69.
0621003250|app1 |3|00| Time server is 10.10.99.108.
0621003250|app1 |3|00| GMT offset is 0 seconds.
0621003250|app1 |3|00| DNS server is 192.231.203.132.
0621003250|app1 |3|00| DNS alternate server is 192.231.203.3.
0621003250|app1 |3|00| DNS domain is ambroisie.local.
0621003251|cfg |3|00|Beginning to provision phone
0621003251|copy |3|00|'tftp://10.10.99.108/2345-12600-001.bootrom.ld' from '10.10.99.108'
0621003251|copy |3|00|TFTP download error
0621003251|copy |3|00|tftpLib error: tftp transfer failed: error 0x4b0008

0621003251|copy |4|00|Download of '2345-12600-001.bootrom.ld' FAILED on attempt 1
0621003251|cfg |4|00|Could not get all 512 bytes of the header
0621003251|copy |3|00|'tftp://10.10.99.108/bootrom.ld' from '10.10.99.108'
0621003251|cfg |3|00|Image bootrom.ld has not changed
0621003251|copy |3|00|buffered_write: transfer terminated
0621003251|copy |3|00|Download of 'bootrom.ld' succeeded on attempt 1 (addr 1 of 1)
0621003251|cfg |3|00|Downloaded bootROM is identical to current version 4.1.0
0621003251|copy |3|00|'tftp://10.10.99.108/0004f21452fa.cfg' from '10.10.99.108'
0621003251|copy |3|00|Download of '0004f21452fa.cfg' succeeded on attempt 1 (addr 1 of 1)
0621003251|copy |3|00|'tftp://10.10.99.108/2345-12600-001.sip.ld' from '10.10.99.108'
0621003251|cfg |3|00|Image 2345-12600-001.sip.ld has not changed
0621003251|copy |3|00|buffered_write: transfer terminated
0621003251|copy |3|00|Download of '2345-12600-001.sip.ld' succeeded on attempt 1 (addr 1 of 1)
0621003251|cfg |3|00|Downloaded application image is identical to current version
0621003251|cfg |3|00|Phone successfully provisioned
0621003251|app1 |3|00|Application, load: Type=SIP, Version=3.0.1.0032 05-Mar-08 13:31
0621003255|app1 |4|00|Loaded application sip.ld successfully, errors 0x20.
0621003255|app1 |6|00|Uploading boot log, time is SUN JUN 21 00:32:56 2009

0621003255|copy |3|00|'tftp://10.10.99.108/polycom/logs/0004f21452fa-boot.log' to '10.10.99.108'
0621003255|copy |3|00|Upload of 'polycom/logs/0004f21452fa-boot.log' succeeded on attempt 1 (addr 1 of 1)
0100000000|so |4|00|---------- Initial log entry ----------
0100000000|so |4|00|+++ Note that bootrom log times are in GMT +++
0100000000|cfg |4|00|Initial log entry
0100000000|copy |3|00|Initial log entry
0100000000|hw |4|00|Initial log entry.
0100000000|ethf |4|00|Initial log entry.
0110214517|wdog |4|00|Initial log entry
0110214517|cdp |3|00|CDP is DISABLED.
0110214517|so |3|00|Platform: Model=SoundPoint IP 650, Assembly=2345-12600-001 Rev=B
0110214517|so |3|00|Platform: Board=2345-12600-001 1
0110214517|so |3|00|Platform: MAC=0004f21452fa, IP=Resolving, Subnet Mask=Resolving
0110214517|so |3|00|Platform: BootBlock=2.7.0 (12600_001) 23-Oct-06 10:45
0110214517|so |3|00|Application, main: Label=BOOT, Version=4.1.0.0219 10-Dec-07 12:52
0110214517|so |3|00|Application, main: P/N=3150-11069-410
0110214517|app1 |4|00|Initial log entry.
0110214518|so |3|00|Link status is Net down, PC down.
0110214519|so |3|00|Link status is Net up Speed 100 full Duplex, PC down.
0110214519|cdp |3|00|CDP is disabled.
0110214525|app1 |3|00|DNS resolver servers are '192.231.203.132' '192.231.203.3'
0110214525|app1 |3|00|DNS resolver search domain is 'ambroisie.local'
0110214525|app1 |3|00|Bootline: esw(3,0)bootHost:flash e=10.10.99.170:ffffff00:7e900:5 h=10.10.99.108 g=10.10.99.69 u=PlcmSpIp pw=**** f=0xc0 tn=Circa
0110214525|app1 |3|00|Bootline: IP
0110214525|app1 |3|00|DHCP returned result 0x3E7 from server 10.10.99.119.
0110214525|app1 |3|00| Phone IP address is 10.10.99.170.
0110214525|app1 |3|00| Subnet mask is 255.255.255.0.
0110214525|app1 |3|00| Gateway address is 10.10.99.69.
0110214525|app1 |3|00| Time server is 192.231.203.132.
0110214525|app1 |3|00| GMT offset is 0 seconds.
0110214525|app1 |3|00| DNS server is 192.231.203.132.
0110214525|app1 |3|00| DNS alternate server is 192.231.203.3.
0110214525|app1 |3|00| DNS domain is ambroisie.local.
0110221512|app1 |3|00|Time has been set from 192.231.203.132 (192.231.203.132).
0110221513|cfg |3|00|Beginning to provision phone
0110221513|copy |3|00|'tftp://10.10.99.108/2345-12600-001.bootrom.ld' from '10.10.99.108'
0110221513|copy |3|00|TFTP download error
0110221513|copy |3|00|tftpLib error: tftp transfer failed: error 0x4b0008

0110221513|copy |4|00|Download of '2345-12600-001.bootrom.ld' FAILED on attempt 1
0110221513|cfg |4|00|Could not get all 512 bytes of the header
0110221513|copy |3|00|'tftp://10.10.99.108/bootrom.ld' from '10.10.99.108'
0110221513|cfg |3|00|Image bootrom.ld has not changed
0110221513|copy |3|00|buffered_write: transfer terminated
0110221513|copy |3|00|Download of 'bootrom.ld' succeeded on attempt 1 (addr 1 of 1)
0110221513|cfg |3|00|Downloaded bootROM is identical to current version 4.1.0
0110221513|copy |3|00|'tftp://10.10.99.108/0004f21452fa.cfg' from '10.10.99.108'
0110221513|copy |3|00|Download of '0004f21452fa.cfg' succeeded on attempt 1 (addr 1 of 1)
0110221513|copy |3|00|'tftp://10.10.99.108/2345-12600-001.sip.ld' from '10.10.99.108'
0110221513|cfg |3|00|Image 2345-12600-001.sip.ld has not changed
0110221513|copy |3|00|buffered_write: transfer terminated
0110221513|copy |3|00|Download of '2345-12600-001.sip.ld' succeeded on attempt 1 (addr 1 of 1)
0110221513|cfg |3|00|Downloaded application image is identical to current version
0110221513|cfg |3|00|Phone successfully provisioned
0110221513|app1 |3|00|Application, load: Type=SIP, Version=3.0.1.0032 05-Mar-08 13:31
0110221517|app1 |4|00|Loaded application sip.ld successfully, errors 0x20.
0110221517|app1 |6|00|Uploading boot log, time is SUN JAN 10 22:15:18 2010


&nbsp

solstars
Posts: 57
 Member Since:
2009-07-23
Tail the log
Tue, 01/12/2010 - 8:30am

Do "tail -f /var/log/atftpd.log" and watch it in real time.


&nbsp

Kbedford
Posts: 187
 Member Since:
2008-06-12
Are the phones actually not
Tue, 01/12/2010 - 3:24pm

Are the phones actually not registered or can you call between extensions but external calls don't work?

Also is the Windows server also the gateway? If you are running ISA server as the firewall it may not be allowing traffic from your Trixbox to the Internet. This would kill your trunks and if your Trixbox uses an external DNS it may also kill all SIP and stop your phones registering.


&nbsp

ambroisie
Posts: 134
 Member Since:
2007-10-30
more info
Tue, 01/12/2010 - 8:26pm

Hi, no NO CALLS at all work...exttn or external

SBS2008 is standard not premium so no ISA

here is some output in realtime from the real time tail -f /var/log/atftpd.log

Jan 13 14:24:16 ambroisietrixalt.local atftpd[24552.-1208719680]: Advanced Trivial FTP server started (0.7)
Jan 13 14:24:16 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving 2345-12500-001.bootrom.ld to 10.10.99.13:1026
Jan 13 14:24:16 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving bootrom.ld to 10.10.99.13:1027
Jan 13 14:24:16 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving 0004f213ded9.cfg to 10.10.99.13:1028
Jan 13 14:24:16 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving 2345-12500-001.sip.ld to 10.10.99.13:1029
Jan 13 14:24:21 ambroisietrixalt.local atftpd[24552.-1208722544]: Fetching from 10.10.99.13 to polycom/logs/0004f213ded9-boot.log
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving 2345-12500-001.bootrom.ld to 10.10.99.13:1026
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving bootrom.ld to 10.10.99.13:1027
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving 0004f213ded9.cfg to 10.10.99.13:1028
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving 2345-12500-001.sip.ld to 10.10.99.13:1029
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving 0004f213ded9reg.cfg to 10.10.99.13:1030
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving server.cfg to 10.10.99.13:1031
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving local-settings.cfg to 10.10.99.13:1032
Jan 13 14:24:49 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving sip.cfg to 10.10.99.13:1033
Jan 13 14:24:51 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving polycom/overrides/0004f213ded9-phone.cfg to 10.10.99.13:1034
Jan 13 14:24:53 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving polycom/contacts/0004f213ded9-directory.xml to 10.10.99.13:1036
Jan 13 14:24:56 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving SoundPointIPWelcome.wav to 10.10.99.13:1037
Jan 13 14:24:57 ambroisietrixalt.local atftpd[24552.-1219212400]: Serving 000000000000-license.cfg to 10.10.99.13:1038
Jan 13 14:24:57 ambroisietrixalt.local atftpd[24552.-1219212400]: Serving 0004f213ded9-license.cfg to 10.10.99.13:1039
Jan 13 14:24:59 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving Leaf.bmp to 10.10.99.13:1040
Jan 13 14:24:59 ambroisietrixalt.local atftpd[24552.-1208722544]: Serving World.bmp to 10.10.99.13:1041


&nbsp

Comment viewing options

Select your preferred way to display the comments and click "Save settings" to activate your changes.