cancel
Showing results for 
Search instead for 
Did you mean: 
Distinguished Expert
Distinguished Expert
4,892 Views
Message 1 of 35

Strange HH5 TR069 Log entries.

01:12:15, 07 Feb.(133394.140000) CWMP: Set Parameter by TR069 Success
01:11:54, 07 Feb.(133372.840000) CWMP: Set Parameter by TR069 Success
01:11:53, 07 Feb.(133371.310000) CWMP: Set Parameter by TR069 Success
01:11:27, 07 Feb.(133345.840000) CWMP: Set Parameter by TR069 Success
00:42:13, 07 Feb.(131592.070000) CWMP: Set Parameter by TR069 Success
00:41:52, 07 Feb.(131570.770000) CWMP: Set Parameter by TR069 Success
00:41:51, 07 Feb.(131569.250000) CWMP: Set Parameter by TR069 Success
00:41:25, 07 Feb.(131543.720000) CWMP: Set Parameter by TR069 Success
00:21:19, 07 Feb.(130338.140000) CWMP: session closed due to error: HTTP authentication error
00:21:19, 07 Feb.(130338.140000) CWMP: HTTP authentication fail from https://pbthdm.bt.mo
00:21:17, 07 Feb.(130335.730000) CWMP: Server URL: https://pbthdm.bt.mo; Connecting as user: ACS username
00:21:17, 07 Feb.(130335.720000) CWMP: Session start now. Event code(s): '1 BOOT,4 VALUE CHANGE,2 PERIODIC'
00:12:12, 07 Feb.(129790.850000) CWMP: Set Parameter by TR069 Success
00:11:51, 07 Feb.(129769.550000) CWMP: Set Parameter by TR069 Success
00:11:49, 07 Feb.(129768.040000) CWMP: Set Parameter by TR069 Success
00:11:23, 07 Feb.(129741.830000) CWMP: Set Parameter by TR069 Success

 

Iv'e previously posted on another thread regarding .203 HH5 firmware update, but this is a new problem, which is strange.

 

Hence a new topic.

 

Two or three times per day there's a log entry suggesting an imminent reboot and parameter change. The authentication always fails so the boot is not implemented. This has been going on for 2 days following some sort of wifi checking software imposed on the hub - another TR069 event.

Also I get the Set Parameter by TR069 Success every 30 mins - only when the laptop and every other device is off or not connected to wifi - e.g. overnight.

At this rate there will be a stack overflow in the logs, which could overwrite everything else. Hope that part the firmware can handle this situation!!

 

Any ideas - I performed a factory reset of the Hub on 05-02-2015. 

0 Ratings
Reply
34 REPLIES 34
mossywell
Contributor
4,862 Views
Message 2 of 35

Re: Strange HH5 TR069 Log entries.

Excellent - I'm not the only one then. Smiley Frustrated

 

I've been running some detailed logging and basically, my wifi disconnects every 30 minutes and it coincides with the "Set Parameter by TR069 Success" message in the logs. I don't know exactly when it started - roughly the past couple of days I think - but it is making the wifi unusable.

 

I have phoned BT once already and had to go through the usual line checks ("it's not a broadband problem - it's a wifi problem" - but they still insisted on a line check) and a factory reset of the router. I'm going to have to call them again and it fills me with utter dread... "my wifi keeps disconnecting" "how do you spell that, Sir?" "d-i-s-c-o-n-n-e-c-t-i-n-g" "thank you very much Sir, I am very happy to be helping you with this problem. [pause] Have you tried painting the walls a different colour..?" etc etc.

 

Sigh...

Was: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.204 | Last updated 21/01/15
Then: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.173.1.6 | Last updated Unknown
Now: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.204 | Last updated 17/02/15
0 Ratings
Reply
mossywell
Contributor
4,854 Views
Message 3 of 35

Re: Strange HH5 TR069 Log entries.

Well, I just spoke to BT. Despite explaining the fact that I've tried all channels on both frequencies and auto-selected channel, and despite saying that there is a definite correlation between the TR069 log entry and the disconnects, he insisted on trying to set the wifi channel automatically for 48 hours. When I asked explicitly if he'd made a note of the error (three times), he asked me to repeat the error again and then (get this) asked which computer I was seeing it on.

 

And BT wonder why their customer get so frustrated. It is so simple: it is because their call handlers appear to specifically be trained NOT to do three things:

1. Listen

2. Think

3. Respond accordingly

 

Was: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.204 | Last updated 21/01/15
Then: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.173.1.6 | Last updated Unknown
Now: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.204 | Last updated 17/02/15
0 Ratings
Reply
Distinguished Expert
Distinguished Expert
4,850 Views
Message 4 of 35

Re: Strange HH5 TR069 Log entries.


@mossywell wrote:

Excellent - I'm not the only one then. Smiley Frustrated

 

I've been running some detailed logging and basically, my wifi disconnects every 30 minutes and it coincides with the "Set Parameter by TR069 Success" message in the logs. I don't know exactly when it started - roughly the past couple of days I think - but it is making the wifi unusable.

 

I have phoned BT once already and had to go through the usual line checks ("it's not a broadband problem - it's a wifi problem" - but they still insisted on a line check) and a factory reset of the router. I'm going to have to call them again and it fills me with utter dread... "my wifi keeps disconnecting" "how do you spell that, Sir?" "d-i-s-c-o-n-n-e-c-t-i-n-g" "thank you very much Sir, I am very happy to be helping you with this problem. [pause] Have you tried painting the walls a different colour..?" etc etc.

 

Sigh...


Was it anything to do with this change?

 

 
02:40:15, 05 Feb.(1123245.870000) CWMP: Server URL: https://pbthdm.bt.mo; Connecting as user: ACS username
02:40:15, 05 Feb.(1123245.860000) CWMP: Session start now. Event code(s): '7 TRANSFER COMPLETE,M Download,4 VALUE CHANGE'
02:40:15, 05 Feb.(1123245.520000) CWMP: Initializing transaction for event code M Download
02:40:15, 05 Feb.(1123245.520000) transfer completed successfully
02:40:13, 05 Feb.(1123243.500000) CWMP: session completed successfully
02:40:13, 05 Feb.(1123243.280000) CWMP: Download file, FileType=4, FileName=5a-​SpeedTestWiFi-​Packagev1.4.tar.gz.aes​.rsa.signed, Username=, CommandKey=1920816647
02:40:11, 05 Feb.(1123241.590000) CWMP: Set Parameter by TR069 Success
02:40:10, 05 Feb.(1123240.630000) CWMP: HTTP authentication success from https://pbthdm.bt.mo
02:40:08, 05 Feb.(1123239.010000) CWMP: Server URL: https://pbthdm.bt.mo; Connecting as user: ACS username

 

Thanks for the post. There's something odd going on since this change.

Iv'e done a factory reset, but there's little or no real improvement.

 

There's only one or two wifi disconnects per day, however.

It will be interesting if there are more posts showing this problem, although if you look through, there's lot of posts regarding wifi recently. It's a case of waiting, and commenting, then someone will see that there's a real problem.

It's only an irritation at the moment.

 

0 Ratings
Reply
Ragbuster
Aspiring Contributor
4,828 Views
Message 5 of 35

Re: Strange HH5 TR069 Log entries.

I have noticed the same log entries and have also been experiencing a lot of wifi drops from all devices on the network.  I wonder if this issue is related to the recent firmware update on the HH5 as we have only recently started noticing this. Wifi was stable before. 

0 Ratings
Reply
Distinguished Expert
Distinguished Expert
4,813 Views
Message 6 of 35

Re: Strange HH5 TR069 Log entries.

Yet another busy night for remote administration:-

 

07:13:58, 08 Feb.(241493.020000) CWMP: Set Parameter by TR069 Success
07:13:37, 08 Feb.(241471.720000) CWMP: Set Parameter by TR069 Success
07:13:35, 08 Feb.(241470.170000) CWMP: Set Parameter by TR069 Success
07:13:10, 08 Feb.(241444.720000) CWMP: Set Parameter by TR069 Success
06:43:57, 08 Feb.(239692.280000) CWMP: Set Parameter by TR069 Success
06:43:36, 08 Feb.(239670.980000) CWMP: Set Parameter by TR069 Success
06:43:34, 08 Feb.(239669.420000) CWMP: Set Parameter by TR069 Success
06:43:08, 08 Feb.(239643.210000) CWMP: Set Parameter by TR069 Success
06:13:56, 08 Feb.(237891.580000) CWMP: Set Parameter by TR069 Success
06:13:35, 08 Feb.(237870.150000) CWMP: Set Parameter by TR069 Success
06:13:33, 08 Feb.(237868.110000) CWMP: Set Parameter by TR069 Success
06:13:06, 08 Feb.(237841.100000) CWMP: Set Parameter by TR069 Success
05:43:56, 08 Feb.(236091.520000) CWMP: Set Parameter by TR069 Success
05:43:35, 08 Feb.(236070.220000) CWMP: Set Parameter by TR069 Success
05:43:33, 08 Feb.(236068.660000) CWMP: Set Parameter by TR069 Success
05:43:08, 08 Feb.(236043.220000) CWMP: Set Parameter by TR069 Success
05:13:57, 08 Feb.(234291.710000) CWMP: Set Parameter by TR069 Success
05:13:35, 08 Feb.(234270.400000) CWMP: Set Parameter by TR069 Success
05:13:33, 08 Feb.(234268.480000) CWMP: Set Parameter by TR069 Success
05:13:07, 08 Feb.(234242.610000) CWMP: Set Parameter by TR069 Success
04:43:56, 08 Feb.(232490.760000) CWMP: Set Parameter by TR069 Success
04:43:34, 08 Feb.(232469.460000) CWMP: Set Parameter by TR069 Success
04:43:33, 08 Feb.(232467.900000) CWMP: Set Parameter by TR069 Success
04:43:06, 08 Feb.(232441.670000) CWMP: Set Parameter by TR069 Success
04:13:56, 08 Feb.(230690.840000) CWMP: Set Parameter by TR069 Success
04:13:34, 08 Feb.(230669.530000) CWMP: Set Parameter by TR069 Success
04:13:32, 08 Feb.(230667.480000) CWMP: Set Parameter by TR069 Success
04:13:05, 08 Feb.(230639.870000) CWMP: Set Parameter by TR069 Success
03:43:59, 08 Feb.(228893.970000) CWMP: Set Parameter by TR069 Success
03:43:37, 08 Feb.(228872.670000) CWMP: Set Parameter by TR069 Success
03:43:36, 08 Feb.(228871.110000) CWMP: Set Parameter by TR069 Success

 

etc, etc.....

 

The above follows another aborted attempt at a reboot (by remote admin), and a connection time now showing  ~ 6 hours.

 

0 Ratings
Reply
Distinguished Expert
Distinguished Expert
4,773 Views
Message 7 of 35

Re: Strange HH5 TR069 Log entries.

Further progress.

Performed factory reset at 8-00am today.

 

This time the reset was done with a wooden cocktail stick pushed into the recess at the rear of the hub.

There was no backup or restore of settings, as this in done quickly here, just disable 5GHz sync, separate SSids, disable smat setup , disable smart wireless, change admin password.

 

A factory reset should in principle, just be changing the logic input into the hub CPU reset pin.

That's how it was always done in the past, from my own experience in industry with industrial CPUs.

Perhaps the HH series has its' own rules, as it does with remote management - keep the customer in the dark, etc, as you would with mushrooms.

 

Results after being out for 2 hours:-

09:12:46, 09 Feb.( 3794.970000) CWMP: Set Parameter by TR069 Success
09:12:22, 09 Feb.( 3770.890000) CWMP: Set Parameter by TR069 Success
08:43:08, 09 Feb.( 2016.490000) CWMP: Set Parameter by TR069 Success
08:42:46, 09 Feb.( 1995.190000) CWMP: Set Parameter by TR069 Success
08:42:45, 09 Feb.( 1994.150000) CWMP: Set Parameter by TR069 Success
08:42:22, 09 Feb.( 1970.530000) CWMP: Set Parameter by TR069 Success
08:13:36, 09 Feb.( 245.150000) CWMP: session completed successfully
08:13:33, 09 Feb.( 242.460000) CWMP: HTTP authentication success from https://pbthdm.bt.mo
08:13:32, 09 Feb.( 240.940000) CWMP: Server URL: https://pbthdm.bt.mo; Connecting as user: ACS username
08:13:32, 09 Feb.( 240.930000) CWMP: Session start now. Event code(s): '6 CONNECTION REQUEST'
08:13:32, 09 Feb.( 240.660000) CWMP: Initializing transaction for event code 6 CONNECTION REQUEST
08:13:31, 09 Feb.( 240.130000) CWMP: session completed successfully
08:13:31, 09 Feb.( 239.940000) CWMP: HTTP authentication success from https://pbthdm.bt.mo
08:13:29, 09 Feb.( 238.260000) CWMP: Server URL: https://pbthdm.bt.mo; Connecting as user: ACS username
08:13:29, 09 Feb.( 238.260000) CWMP: Session start now. Event code(s): '6 CONNECTION REQUEST'
08:13:29, 09 Feb.( 237.990000) CWMP: session completed successfully
08:13:16, 09 Feb.( 225.020000) CWMP: Set Parameter by TR069 Success
08:13:13, 09 Feb.( 221.890000) CWMP: Set Parameter by TR069 Success
08:13:09, 09 Feb.( 217.660000) CWMP: Initializing transaction for event code 6 CONNECTION REQUEST
08:13:07, 09 Feb.( 215.470000) CWMP: Set Parameter by TR069 Success
08:13:06, 09 Feb.( 215.290000) CWMP: HTTP authentication success from https://pbthdm.bt.mo
08:13:04, 09 Feb.( 213.190000) CWMP: Initializing transaction for event code 6 CONNECTION REQUEST
08:13:04, 09 Feb.( 213.170000) CWMP: Server URL: https://pbthdm.bt.mo; Connecting as user: ACS username
08:13:04, 09 Feb.( 213.170000) CWMP: Session start now. Event code(s): '6 CONNECTION REQUEST'
08:13:04, 09 Feb.( 212.900000) CWMP: Initializing transaction for event code 6 CONNECTION REQUEST
08:12:59, 09 Feb.( 208.320000) CWMP: session completed successfully
08:12:59, 09 Feb.( 208.140000) CWMP: HTTP authentication success from https://pbthdm.bt.mo
08:12:57, 09 Feb.( 205.760000) CWMP: Server URL: https://pbthdm.bt.mo; Connecting as user: ACS username
08:12:57, 09 Feb.( 205.760000) CWMP: Session start now. Event code(s): '6 CONNECTION REQUEST,4 VALUE CHANGE'
08:12:57, 09 Feb.( 205.490000) CWMP: session completed successfully
08:12:46, 09 Feb.( 194.650000) CWMP: Set Parameter by TR069 Success
08:12:45, 09 Feb.( 194.150000) CWMP: Set Parameter by TR069 Success
08:12:44, 09 Feb.( 193.200000) CWMP: Set Parameter by TR069 Success
08:12:43, 09 Feb.( 192.020000) CWMP: Set Parameter by TR069 Success
08:12:42, 09 Feb.( 191.390000) CWMP: HTTP authentication success from https://pbthdm.bt.mo

 

Hub is now authenticating with pbthdm.bt.mo. The Set Parameter entry repeats itself every 1/2 hour.

It's just a case of waiting.

 

 

 

0 Ratings
Reply
mossywell
Contributor
4,762 Views
Message 8 of 35

Re: Strange HH5 TR069 Log entries.

@Ribblelancs:

No, I have not seen any downloads along the lines you posted. Here's what happened with me: they sent out a remote command to change 2.4 Ghz and 5 Ghz to "auto" (prior to this both were set to 1 and 48 respectively) on Friday. Following this, I had one more "Set Parameter by TR069 Success" which was, as ever, followed by a wifi disconnect. But then I had no more "Set Parameter by TR069 Success" over the weekend and no more wifi disconnects until... this morning when it all kicked off again. And guess what? The wifi disconnected again. (The problem I have is that after a number of disconnects, the PC at home refuses to reconnect automatically, so I can't access it from work. I wrote a small powershell script to monitor the wifi network card state, so maybe I should add something that says that if the wifi card is in "media disconnected" state for let's say 10 minutes, reboot the PC or disable/ree-enable the network card. Hmmm. yeah, that's probably a good idea.)

 

I was expecting a call from BT at 10:00 this morning to follow up, but so far na da. 😕

 

EDIT: "as you would with mushrooms" - LMAO! 🙂

Was: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.204 | Last updated 21/01/15
Then: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.173.1.6 | Last updated Unknown
Now: BT Home Hub 5 (Type A) | Software version 4.7.5.1.83.8.204 | Last updated 17/02/15
0 Ratings
Reply
Dode
Aspiring Expert
4,743 Views
Message 9 of 35

Re: Strange HH5 TR069 Log entries.

Screen Shot .png

 

Check the times on this one. It goes from 09.02 to 10.03 then back to 9.05. All this morning. It's kept the same IP address. Could this be a fault at the exchange? Where does it get it's time?

0 Ratings
Reply
Distinguished Expert
Distinguished Expert
4,732 Views
Message 10 of 35

Re: Strange HH5 TR069 Log entries.

The 1 hour ahead will relate to the location of the remote administrator, Alcatel Lucent (?) whose HQ was / is in Paris.
They are in CET.

(Think that they also have a significant input to the upcoming Vectoring.)
0 Ratings
Reply