Kitz Forum

Broadband Related => Router Monitoring Software => Topic started by: Bald_Eagle1 on October 12, 2013, 12:03:26 PM

Title: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 12, 2013, 12:03:26 PM
I am currently updating the HG612 Modem Stats logging/graphing programs to take account of these changes.

It appears that current/snapshot data logs can still be obtained via HG612_current_stats.exe (for both Huawei & ECI DSLAMS & the attached version of GRAPH6.exe should be able to graph the data accordingly (not fully tested for higher speed connections able to use the D3 band yet).


For the time being, Ongoing data logging should be disabled to avoid multiple instances of HG612_stats.exe etc. ending up 'stuck' due to not handling the new/additional ongoing data being harvested.



Any feedback from anyone wishing to test things out during development of updated programs will be more welcome
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 13, 2013, 12:53:43 PM
I have now amended my programs' coding to hopefully allow for both pre-updated HG612s & updated HG612s without needing any user intervention.

It is undergoing testing at the moment & if shown to be reliable, I'll post it here.

This is an interim version, not fully using the new data as obtained via an updated HG612.

If it proves to be reliable, I'll include the new data & graph it accordingly.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 13, 2013, 03:55:19 PM
Hi BE, just updated my copy of Graph6 and current stats seems to be working, I've emailed you a copy of the graphs.

HG612_Stats is also running nicely.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 13, 2013, 04:01:06 PM
Yep,

Our emails seem to have crossed.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 13, 2013, 08:04:34 PM
Yes it seems the current stats is working on an updated HG612 -> modem stats



Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 14, 2013, 10:09:27 AM
My current stats didn't run at midnight last night, anybody else have this happen.

I've now also installed the updates at work, so we'll see how this goes as well.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: kitz on October 14, 2013, 12:46:29 PM
I dont seem to have been upgraded yet (ECI dslam)

Quote
Discovery Phase (Initial) Band Plan
US: (0,95) (880,1195) (1984,2771)
DS: (32,859) (1216,1959) (2792,4083)

Ive installed the new graph6.exe and so far it seems to be working ok. :)
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 14, 2013, 01:00:51 PM
I've just had HG612_Stats crashing every minute - it throws up a Microsoft error report window each time.

Isrunning files are created, not deleted but are blank.
Error log is not updated
Modem_Stats Log is updated
xlogfile attached.
Snippet of error log attached - which does show two instance running.

I don't like coincidences, but at the same time our connection speed seems to have plummeted to around 0.1 Mb/s (http://www.speedtest.net/my-result/3032558604) 

Now I don't think this is anything to do with HG612 stats, I've turned that off, and rebooted both modem and router and the problem persists.

I don't want to derail this thread, so if I need help with this speed related problem I'll start another thread.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: ryant704 on October 14, 2013, 01:10:13 PM
I don't like coincidences, but at the same time our connection speed seems to have plummeted to around 0.1 Mb/s (http://www.speedtest.net/my-result/3032558604) 

Now I don't think this is anything to do with HG612 stats, I've turned that off, and rebooted both modem and router and the problem persists.

I don't want to derail this thread, so if I need help with this speed related problem I'll start another thread.

You say you've rebooted the modem, this is the old xlogfile. Any chance you can upload the new one please?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 14, 2013, 01:41:10 PM
You say you've rebooted the modem, this is the old xlogfile. Any chance you can upload the new one please?

Just manually run HG612_Stats and it ran fine, also produced snapshot logs.

latest xlogfile attached.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: ryant704 on October 14, 2013, 01:47:14 PM
You say you've rebooted the modem, this is the old xlogfile. Any chance you can upload the new one please?

Just manually run HG612_Stats and it ran fine, also produced snapshot logs.

latest xlogfile attached.

Possible reason...

Since Link time = 1 hours 17 min 53 sec

FEC:      0      2

CRC:      784012      1

ES:      4673      1

SES:      4671      0

UAS:      4673      0

As you can see that is far from normal... got access to QLN and Hlog graphs now or? Your header count is rather high as well at 75144.

Are you normally on Fastpath?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 14, 2013, 02:10:14 PM
To save derailing this thread anymore I have posted a thread here (http://forum.kitz.co.uk/index.php/topic,13061.0.html) regarding my speed issues.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 15, 2013, 08:14:19 AM
HG612 stats crashed at work (running on XP), I had another Windows error reporting window, which obviously caused two copies to be running, thus stopping all further logging.

Things stopped at 01:17, this line was written to the modem log:

Code: [Select]
15/10/2013 01:17 35883 6176 6.3 6.0 11.0 3.6 41400 6188 PTM 51 14 283400868 46 661 0 36327 0 0 466777 0 10.091 283400868 46 466777 12261451 170842 20253 51 14 0 3138845885 3821516 5184744 3255 0 0 703 1 283 14 260 0 0 0 0 240480 3700 0 3.8 41.7 60.2 0.0 19.1 52.6 85.5 3.8 41.7 0.0 0.0 20.0 52.1 0.0 5.9 6.0 0.0 0.0 6.3 6.3 0.0 1.9 -1.2 0.0 0.0 8.4 7.4 0.0 Showtime 1 0 L0 17a ON ON No Showtime 24.5 0.0 51 193 1 1 64 5 12 16 0.0461 0.9982 11104 1683 37 0 37 0 2506327366 0 5414733 0 0 0 0 302 42 3.00 0.00 2.96 16.28 8.00 0.00 64.80 19.15 25131 83 VDSL2 43 0

But there was nothing written to the error log for 01:17, and the isrunning files are all blank.

Code: [Select]
15/10/2013 01:16:02.296 - Scheduled Daily_Graphing is switched ON via the ini file
15/10/2013 01:16:02.296 - Daily_Graphing_hour    = 23
15/10/2013 01:16:02.296 - Daily_Graphing_minutes = 58
15/10/2013 01:16:02.296 - Current Time is Hours 01, Minutes 16
15/10/2013 01:16:02.296 - scheduled Daily_Graphing is NOT due yet
*********************************************************************************************************************
15/10/2013 01:16:02.296 - End of HG612_stats.exe program, EXIT error code = 0
15/10/2013 01:16:02.296 - End of HG612_stats.exe program, closing ERROR.LOG


15/10/2013 01:18:00.437 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
15/10/2013 01:18:00.546 - HG612_current_stats.exe was NOT running
15/10/2013 01:18:00.546 - Temp File ONGOING-ISRUNNING-011800-546.TXT was created
15/10/2013 01:18:00.734 - *** Now in exit_2_instances as there are 2 instances of HG612_stats.exe running. Status = 1.
15/10/2013 01:18:00.750 - *** In exit_2_instances - Closing ERROR.LOG. Status = 1.


15/10/2013 01:19:00.375 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
15/10/2013 01:19:00.453 - HG612_current_stats.exe was NOT running
15/10/2013 01:19:00.453 - Temp File ONGOING-ISRUNNING-011900-453.TXT was created
15/10/2013 01:19:00.656 - *** Now in exit_2_instances as there are 2 instances of HG612_stats.exe running. Status = 1.
15/10/2013 01:19:00.687 - *** In exit_2_instances - Closing ERROR.LOG. Status = 1.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 15, 2013, 11:34:26 AM
I've started get HG612 stats crashing again on every run, it would occasionally do it, then every time it ran.

Running from a command prompt it produces this prior to crashing, hope it's some help. Perhaps I shouldn't say it, but the copy at home has been running fine.

Code: [Select]
Microsoft Windows XP [Version 5.1.2600]
(C) Copyright 1985-2001 Microsoft Corp.

C:\Documents and Settings\Ron>cd C:\HG612_Modem_Stats\Scripts\

C:\HG612_Modem_Stats\Scripts>hg612_stats
progname = hg612_stats

The local time is: 11:28:35. 0
A log file wasn't specified
argv[1] = (null)

path_only is C:\HG612_Modem_Stats\Scripts

At the start, working_directory (i.e. log_file_path) is

error from ini_parse_file() = 0

Before setting from config.Extensive_event_logging, DEBUG_COMMENTS = YES
From HG612_stats.ini hard coding, the ongoing log_file = C:\HG612_Modem_Stats\On
going_Stats\modem_stats.log

error_log_file = C:\HG612_Modem_Stats\Ongoing_Stats\ERROR.LOG

xlog_file = C:\HG612_Modem_Stats\Ongoing_Stats\xlogfile.txt
Temp File ONGOING-ISRUNNING-112835-171.TXT was created

'tasklist' is not recognized as an internal or external command,
operable program or batch file.
From HG612_stats.ini, Mode = extensive
Modex = 2

In switch, from HG612_stats.ini, Mode = extensive

Modem in use = HG622
VDSL2
Bearer_or Path = Bearer:, US sync = 6076, DS sync = 33690
Profile = 17a
MSGc = 18 DS & 33 US
firmware updated = YES
Bitswap DS = 18244 / 23
parse_PbParams UPDATED FIRMWARE
firmware updated = YES
hgp.us_discovery_bp1_from = 6
hgp.us_discovery_bp1_to   = 31
hgp.us_discovery_bp2_from = 882
hgp.us_discovery_bp2_to   = 1193
hgp.us_discovery_bp3_from = 1984
hgp.us_discovery_bp3_to   = 2770

hgp.ds_discovery_bp1_from = 33
hgp.ds_discovery_bp1_to   = 857
hgp.ds_discovery_bp2_from = 1218
hgp.ds_discovery_bp2_to   = 1959
hgp.ds_discovery_bp3_from = 2795
hgp.ds_discovery_bp3_to   = 4083


hgp.us_line_attenuation_U0 = 3.8
hgp.us_line_attenuation_U1 = 41.6
hgp.us_line_attenuation_U2 = 60.1
hgp.us_line_attenuation_U3 = 0.0
hgp.us_line_attenuation_U4 = 0.0

hgp.ds_line_attenuation_D1 = 19.1
hgp.ds_line_attenuation_D2 = 52.5
hgp.ds_line_attenuation_D3 = 85.2


hgp.us_signal_attenuation_U0 = 3.8
hgp.us_signal_attenuation_U1 = 41.6
hgp.us_signal_attenuation_U2 = 0.0
hgp.us_signal_attenuation_U3 = 0.0
hgp.us_signal_attenuation_U4 = 0.0

hgp.ds_signal_attenuation_D1 = 19.9
hgp.ds_signal_attenuation_D2 = 52.1
hgp.ds_signal_attenuation_D3 = 0.0


SNR itch_buffer1 =              SNR Margin(dB):  6.0     6.0      N/A     N/A
  N/A    6.6     6.4      N/A
itch_buffer2 = xx 6.0    6.0      N/A     N/A     N/A    6.6     6.4      N/A
xx


hgp.us_snr_margin_U0 = 6.0
hgp.us_snr_margin_U1 = 6.0
hgp.us_snr_margin_U2 = 0.0
hgp.us_snr_margin_U3 = 0.0
hgp.us_snr_margin_U4 = 0.0

hgp.ds_snr_margin_D1 = 6.6
hgp.ds_snr_margin_D2 = 6.4
hgp.ds_snr_margin_D3 = 0.0


tx_power itch_buffer1 =                  TX Power(dBm):  2.0    -1.2      N/A
  N/A     N/A    8.5     7.3      N/A
itch_buffer2 = xx 2.0   -1.2      N/A     N/A     N/A    8.5     7.3      N/A
xx


hgp.us_tx_power_U0 = 2.0
hgp.us_tx_power_U1 = -1.2
hgp.us_tx_power_U2 = 0.0
hgp.us_tx_power_U3 = 0.0
hgp.us_tx_power_U4 = 0.0

hgp.ds_tx_power_D1 = 8.5
hgp.ds_tx_power_D2 = 7.3
hgp.ds_tx_power_D3 = 0.0
15/10/2013 11:28 Showtime 1 42104 6041 0 33690 6076 L0 VDSL2 VDSL2 B 17a PTM ON
ON No Showtime 6.5 6.0 24.5 0.0 11.0 3.7 47 190 1 1 64 5 16 16 0.0453 0.9988 112
96 1658 1429 1 64 207 64 207 9069141 593721 31 23 2321613687 2671529 238045481 8
6 115 0 26 0 0 0 0 0 1709503677 0 5264357 0 0 0 0 307 29 271 0 333 62 26413 8.00
 0.00 2.91 16.29 16.00 0.00 65.92 19.14 18244 23 598531971 139 3420 39 307 29 27
1 0 333 62 2 0 12 0 3823283 49 17 13 1 5 0 0 0 0 0 0 0 0 7995007 0 0 0 0 0 0 0 0
 0 0 0 0 0 598531971 139 3420 39 307 29 271 0 333 62 2 0 12 0 0 0 0 0 0 0 0 0 0
0 0 0 0 0 238045481 86 31 23 5 13 0 0 0 0 0 0 0 0 seconds since the Epoch: 13818
32880


 size of modem_stats.log is 79842186


fseek_success = 0
captured:
15/10/2013 11:28 33690 6076 6.5 6.0 11.0 3.7 42104 6034 PTM 3420 39 598229938 13
9 26 0 26379 0 0 522992 0 7.327 237743448 86 522992 9057466 591619 20604 31 23 0
 2318624880 2534973 5274415 115 0 0 1429 1 307 29 271 0 0 0 0 240640 3704 0 3.8
41.6 60.1 0.0 19.1 52.5 85.2 3.8 41.6 0.0 0.0 19.9 52.1 0.0 6.0 6.0 0.0 0.0 6.5
6.4 0.0 2.0 -1.2 0.0 0.0 8.5 7.3 0.0 Showtime 1 0 L0 17a ON ON No Showtime 24.5
0.0 47 190 1 1 64 5 16 16 0.0453 0.9988 11296 1658 0 0 0 0 1707302958 0 5264147
0 0 0 0 333 62 8.00 0.00 2.91 16.29 16.00 0.00 65.92 19.14 18220 23 VDSL2 39 0

previous_day as string     = 15
previous_month as string   = 10
previous_year as string    = 2013

previous_hour as string     = 11
previous_minute as string   = 28


seconds since the Epoch: 1381832880
time_difference between current time & previous log time = 0 minutes


Total DS ES       = 307
Total US ES       = 29
PREVIOUS_DS_ES    = 307
PREVIOUS_US_ES    = 29
At START, DS_ES_THIS_PERIOD = 0
At START, US_ES_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_ES_THIS_PERIOD = 0

After calculating, US_ES_THIS_PERIOD = 0
DS_ES_THIS_PERIOD = 0, i.e. LESS THAN OR EQUAL TO 60
US_ES_THIS_PERIOD = 0, i.e. LESS THAN OR EQUAL TO 60


Total DS RSCorr                 = 238045481
Total US RSCorr                 = 86
PREVIOUS_DS_RSCorr              = 237743448
PREVIOUS_US_RSCorr              = 86
At START, DS_RSCorr_THIS_PERIOD = 0
At START, US_RSCorr_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_RSCorr_THIS_PERIOD = 302033


Total DS RSUnCorr                 = 115
Total US RSUnCorr                 = 0
PREVIOUS_DS_RSUnCorr              = 115
PREVIOUS_US_RSUnCorr              = 0
At START, DS_RSUnCorr_THIS_PERIOD = 0
At START, US_RSUnCorr_THIS_PERIOD = 0
file EXISTS!



Total DS RS                 = 2321613687
Total US RS                 = 2671529
PREVIOUS_DS_RS              = 2318624880
PREVIOUS_US_RS              = 2534973
At START, DS_RS_THIS_PERIOD = 0
At START, US_RS_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_RS_THIS_PERIOD = 2988807
After calculating, US_RS_THIS_PERIOD = 136556


Total DS OHF                 = 9069141
Total US OHF                 = 593721
PREVIOUS_DS_OHF              = 9057466
PREVIOUS_US_OHF              = 591619
At START, DS_OHF_THIS_PERIOD = 0
At START, US_OHF_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_OHF_THIS_PERIOD = 11675

After calculating, US_OHF_THIS_PERIOD = 2102


Total DS OHFErr                 = 31
Total US OHFErr                 = 23
PREVIOUS_DS_OHFErr              = 31
PREVIOUS_US_OHFErr              = 23
At START, DS_OHFErr_THIS_PERIOD = 0
At START, US_OHFErr_THIS_PERIOD = 0
time_difference                 = 0
file EXISTS!



Total DS HEC                 = 26
Total US HEC                 = 0
PREVIOUS_DS_HEC              = 26
PREVIOUS_US_HEC              = 0
At START, DS_HEC_THIS_PERIOD = 0
At START, US_HEC_THIS_PERIOD = 0
file EXISTS!



Total DS CRC                 = 3420
Total US CRC                 = 39
PREVIOUS_DS_CRC              = 3420
PREVIOUS_US_CRC              = 39
At START, DS_CRC_THIS_PERIOD = 0
At START, US_CRC_THIS_PERIOD = 0
file EXISTS!



Total DS FEC                 = 598531971
Total US FEC                 = 139
PREVIOUS_DS_FEC              = 598229938
PREVIOUS_US_FEC              = 139
At START, DS_FEC_THIS_PERIOD = 0
At START, US_FEC_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_FEC_THIS_PERIOD = 302033


log_stats.mode = VDSL2

Total DS bitswap                 = 18244
Total US bitswap                 = 23
PREVIOUS_DS_bitswap              = 18220
PREVIOUS_US_bitswap              = 23
At START, DS_bitswap_THIS_PERIOD = 0
At START, US_bitswap_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_bitswap_THIS_PERIOD = 24


Now writing data to MSLOGFILE.log (modem_stats.log)

Data has now been written to MSLOGFILE (modem_stats.log)


sockfd has been closed
freeaddrinfo(res) completed
WSACleanup has completed
txbuf has been freed

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 15, 2013, 12:52:58 PM
It does help (a lot).

I'll investigate further when I get home from work tonight.

The output does state that the data is written to modem_stats.log before the program crashes.

Are you able to confirm if that is actually the case?



It does seem to be XP or XP server versions that are more affected.

AFAICT, Windows 7 (& possibly Vista) versions seem O.K.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 15, 2013, 01:30:41 PM
Yes, it does write the line to the modem log.  See reply 12 above for a line which was written to the log when it crashed.

I'm only running 32bit Windows here at work, but I doubt it's a 32/64 bit thing.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: burakkucat on October 15, 2013, 09:43:37 PM
I've started get HG612 stats crashing again on every run, it would occasionally do it, then every time it ran.

Running from a command prompt it produces this prior to crashing, hope it's some help. Perhaps I shouldn't say it, but the copy at home has been running fine.

Code: [Select]
Microsoft Windows XP [Version 5.1.2600]
(C) Copyright 1985-2001 Microsoft Corp.

C:\Documents and Settings\Ron>cd C:\HG612_Modem_Stats\Scripts\

C:\HG612_Modem_Stats\Scripts>

<snip>

Modem in use = HG622

<snip>

I am intrigued to see the line that I have snipped from the output, above.

Using a Huawei HG622?  :-\
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 15, 2013, 10:17:38 PM

I am intrigued to see the line that I have snipped from the output, above.

Using a Huawei HG622?  :-\



I was hoping nobody would notice that  :-[

It's on my 'things to do' list, but quite a fair way down.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 15, 2013, 10:20:03 PM
Yes, it does write the line to the modem log.  See reply 12 above for a line which was written to the log when it crashed.

I'm only running 32bit Windows here at work, but I doubt it's a 32/64 bit thing.

The programs are compiled for 32 bit systems anyway, so it won't be that.
I've emailed a couple of test programs for you to try, when/if you get chance.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: mikecrawford71 on October 15, 2013, 10:52:16 PM
Any chance I can get a copy of the updated binaries too?

Noticed my stats were not working today either (application crash whenever it runs, and seems erratic creating the xlogfile.txt).

Running from command line outputs the following;

Code: [Select]

C:\HG612_Modem_Stats\Scripts>HG612_stats.exe
progname = HG612_stats.exe

The local time is: 22:45:00.291
A log file wasn't specified
argv[1] = (null)

path_only is C:\HG612_Modem_Stats\Scripts

At the start, working_directory (i.e. log_file_path) is


[Login] loaded from 'HG612_stats.ini':
    IP_address = 192.168.0.3
    Username   = admin
    Password   = admin
    Shell      = sh

[Locations] loaded from 'HG612_stats.ini':
    Main_folder          = C:\HG612_Modem_Stats
    Scripts_and_Programs = C:\HG612_Modem_Stats\Scripts
    Apps_folder          = C:\HG612_Modem_Stats\Apps
    Ongoing_Stats_folder = C:\HG612_Modem_Stats\Ongoing_Stats
    Current_Stats_folder = C:\HG612_Modem_Stats\Current_Stats


 [Mode] loaded from 'HG612_stats.ini':
    Mode                 = extensive

[Graphing] loaded from 'HG612_stats.ini':
  Pause_after_obtaining_snapshot_data   = NO
  Auto_graph_Snapshot_data              = YES
  Pause_after_snapshot_graphs_plotted   = NO
  Pause_after_ongoing_graphs_plotted    = NO
  Plot_area_background_colour           = 31
  Daily_Graphing                        = YES
  Daily_Graphing_Time                   = 06:00



  Delay_snapshot_data_harvest_seconds   = 10
  Delay_ongoing_data_harvest_seconds    = 0

  Ongoing_Logging         = YES
  Current_Stats           = NO
  Current_Stats_Interval  = 8
  Current_Stats_Datum     = 06
  Use_own_tasks           = NO
  Extensive_event_logging = YES
DEBUG_COMMENTS = YES
From HG612_stats.ini hard coding, the ongoing log_file = C:\HG612_Modem_Stats\Ongoing_Stats\modem_stats.log

error_log_file = C:\HG612_Modem_Stats\Ongoing_Stats\ERROR.LOG

xlog_file = C:\HG612_Modem_Stats\Ongoing_Stats\xlogfile.txt
Temp File ONGOING-ISRUNNING-224500-538.TXT was created

From HG612_stats.ini, Mode = extensive
Modex = 2

In switch, from HG612_stats.ini, Mode = extensive
Modem in use = HG622
Bearer_or Path = Max:, US sync = 0, DS sync = 0
Profile =

C:\HG612_Modem_Stats\Scripts>

ERROR.LOG file

Code: [Select]


15/10/2013 22:51:09.323 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling
15/10/2013 22:51:09.537 - HG612_current_stats.exe was NOT running
15/10/2013 22:51:09.537 - Temp File ONGOING-ISRUNNING-225109-537.TXT was created
15/10/2013 22:51:10.080 - malloc() O.K.
15/10/2013 22:51:10.080 - Initializing Winsock
15/10/2013 22:51:10.084 - WSAStartup() SUCCESSFUL!!!
15/10/2013 22:51:10.084 - Starting getaddrinfo()
15/10/2013 22:51:10.084 - getaddrinfo() O.K.
15/10/2013 22:51:10.084 - Starting socket()
15/10/2013 22:51:10.090 - socket() O.K.
15/10/2013 22:51:10.090 - Starting connect()
15/10/2013 22:51:10.092 - connect() O.K.
15/10/2013 22:51:10.925 - About to reply(xdslcmd info --stats)
15/10/2013 22:51:10.925 - reply(xdslcmd info --stats) O.K.
15/10/2013 22:51:10.940 - In get_data(), ERROR - recv() FAILED - numbytes = -1!!!
15/10/2013 22:51:10.942 - ERROR - get_data() FAILED!!!! - exiting the program
15/10/2013 22:51:10.942 - About to close(sockfd)
15/10/2013 22:51:10.943 - About to freeadddrinfo(res)
15/10/2013 22:51:10.943 - About to WSACleanup()
15/10/2013 22:51:10.943 - About to free(txbuf)
15/10/2013 22:51:10.943 - About to free(rxbuf)
15/10/2013 22:51:10.944 - Current  sync speeds are     DS 0 kbps      US 0 kbps
15/10/2013 22:51:10.944 - Previous sync speeds are     DS 0 kbps      US 0 kbps
15/10/2013 22:51:10.944 - Sync speeds have NOT changed since the previous data harvest
15/10/2013 22:51:10.945 - ONGOING-ISRUNNING-225109-537.TXT DELETED
*********************************************************************************************************************
15/10/2013 22:51:10.945 - Scheduled Current_Stats logging is NOT switched on via the ini file
*********************************************************************************************************************
15/10/2013 22:51:10.945 - Scheduled Daily_Graphing is switched ON via the ini file
15/10/2013 22:51:10.945 - Daily_Graphing_hour    = 06
15/10/2013 22:51:10.945 - Daily_Graphing_minutes = 00
15/10/2013 22:51:10.945 - Current Time is Hours 22, Minutes 51
15/10/2013 22:51:10.945 - scheduled Daily_Graphing is NOT due yet
*********************************************************************************************************************
15/10/2013 22:51:10.948 - End of HG612_stats.exe program, EXIT error code = 0
15/10/2013 22:51:10.948 - End of HG612_stats.exe program, closing ERROR.LOG
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 15, 2013, 11:13:30 PM
I'd rather hang on for a short while until the latest versions have been tested.

Possibly tomorrow????

Which Windows version(s) do you use?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 16, 2013, 07:50:22 AM
Unfortunately that didn't fix it, running from a command prompt this is all I get.

Code: [Select]
C:\HG612_Modem_Stats\Scripts>hg612_stats
progname = hg612_stats

The local time is: 07:46:28.156
A log file wasn't specified
argv[1] = (null)

path_only is C:\HG612_Modem_Stats\Scripts

At the start, working_directory (i.e. log_file_path) is

error from ini_parse_file() = 0

Before setting from config.Extensive_event_logging, DEBUG_COMMENTS = YES
From HG612_stats.ini hard coding, the ongoing log_file = C:\HG612_Modem_Stats\On
going_Stats\modem_stats.log

error_log_file = C:\HG612_Modem_Stats\Ongoing_Stats\ERROR.LOG

xlog_file = C:\HG612_Modem_Stats\Ongoing_Stats\xlogfile.txt
Temp File ONGOING-ISRUNNING-074628-312.TXT was created

'tasklist' is not recognized as an internal or external command,
operable program or batch file.
From HG612_stats.ini, Mode = extensive
Modex = 2

In switch, from HG612_stats.ini, Mode = extensive

Modem in use = HG622
VDSL2
Bearer_or Path = Bearer:, US sync = 6076, DS sync = 33690
Profile = 17a
MSGc = 18 DS & 33 US
October 2013 Firmware update = YES
Bitswap DS = 68679 / 56
parse_PbParams UPDATED FIRMWARE
firmware updated = YES
hgp.us_discovery_bp1_from = 6
hgp.us_discovery_bp1_to   = 31
hgp.us_discovery_bp2_from = 882
hgp.us_discovery_bp2_to   = 1193
hgp.us_discovery_bp3_from = 1984
hgp.us_discovery_bp3_to   = 2770

hgp.ds_discovery_bp1_from = 33
hgp.ds_discovery_bp1_to   = 857
hgp.ds_discovery_bp2_from = 1218
hgp.ds_discovery_bp2_to   = 1959
hgp.ds_discovery_bp3_from = 2795
hgp.ds_discovery_bp3_to   = 4083


hgp.us_line_attenuation_U0 = 3.8
hgp.us_line_attenuation_U1 = 41.6
hgp.us_line_attenuation_U2 = 60.1
hgp.us_line_attenuation_U3 = 0.0
hgp.us_line_attenuation_U4 = 0.0

hgp.ds_line_attenuation_D1 = 19.1
hgp.ds_line_attenuation_D2 = 52.5
hgp.ds_line_attenuation_D3 = 85.2


hgp.us_signal_attenuation_U0 = 3.8
hgp.us_signal_attenuation_U1 = 41.6
hgp.us_signal_attenuation_U2 = 0.0
hgp.us_signal_attenuation_U3 = 0.0
hgp.us_signal_attenuation_U4 = 0.0

hgp.ds_signal_attenuation_D1 = 19.9
hgp.ds_signal_attenuation_D2 = 52.1
hgp.ds_signal_attenuation_D3 = 0.0


SNR itch_buffer1 =              SNR Margin(dB):  5.1     6.0      N/A     N/A
  N/A    6.2     6.4      N/A
SNR itch_buffer2 =  5.1  6.0      N/A     N/A     N/A    6.2     6.4      N/A



hgp.us_snr_margin_U0 = 5.1
hgp.us_snr_margin_U1 = 6.0
hgp.us_snr_margin_U2 = 0.0
hgp.us_snr_margin_U3 = 0.0
hgp.us_snr_margin_U4 = 0.0

hgp.ds_snr_margin_D1 = 6.2
hgp.ds_snr_margin_D2 = 6.4
hgp.ds_snr_margin_D3 = 0.0


tx_power itch_buffer1 =                  TX Power(dBm):  2.0    -1.2      N/A
  N/A     N/A    8.5     7.3      N/A
tx_power itch_buffer2 =  2.0    -1.2      N/A     N/A     N/A    8.5     7.3
  N/A


hgp.us_tx_power_U0 = 2.0
hgp.us_tx_power_U1 = -1.2
hgp.us_tx_power_U2 = 0.0
hgp.us_tx_power_U3 = 0.0
hgp.us_tx_power_U4 = 0.0

hgp.ds_tx_power_D1 = 8.5
hgp.ds_tx_power_D2 = 7.3
hgp.ds_tx_power_D3 = 0.0
16/10/2013 07:46 Showtime 1 41876 6064 0 33690 6076 L0 VDSL2 VDSL2 B 17a PTM ON
ON No Showtime 6.4 6.0 24.5 0.0 11.0 3.7 47 190 1 1 64 5 16 16 0.0453 0.9988 112
96 1658 1429 1 64 207 64 207 34161328 961457 54 45 155279157 3209897 909540546 1
57 2083 0 457 0 23 0 23 0 2144376083 0 13703564 0 0 0 0 312 44 271 0 333 62 9948
8 8.00 0.00 2.91 16.29 16.00 0.00 65.92 19.14 68679 56 1270027036 210 3443 61 31
2 44 271 0 333 62 2 0 12 0 5510585 0 0 0 0 0 0 0 0 0 0 0 0 0 7988937 0 0 0 0 0 0
 0 0 0 0 0 0 0 555684351 66 17 21 4 14 0 0 0 0 0 0 0 0 714342685 144 3426 40 308
 30 271 0 333 62 2 0 12 0 909540546 157 54 45 10 28 0 0 0 0 0 0 0 0 seconds sinc
e the Epoch: 1381905960


 size of modem_stats.log is 79844953


fseek_success = 0
captured:
16/10/2013 07:45 33690 6076 6.5 6.0 11.0 3.7 42104 6078 PTM 3443 61 1269314849 2
10 457 0 99408 0 0 0 0 27.613 908828359 157 0 34133858 956523 22727638 54 45 0 1
48246647 2889317 0 2083 0 0 1429 1 312 44 271 0 0 0 0 0 0 0 3.8 41.6 60.1 0.0 19
.1 52.5 85.2 3.8 41.6 0.0 0.0 19.9 52.1 0.0 5.1 6.0 0.0 0.0 6.5 6.4 0.0 2.0 -1.2
 0.0 0.0 8.5 7.3 0.0 Showtime 1 0 L0 17a ON ON No Showtime 24.5 0.0 47 190 1 1 6
4 5 16 16 0.0453 0.9988 11296 1658 23 0 23 0 2139197906 0 13701254 0 0 0 0 333 6
2 8.00 0.00 2.91 16.29 16.00 0.00 65.92 19.14 68621 56 VDSL2 0 0

previous_day as string     = 16
previous_month as string   = 10
previous_year as string    = 2013

previous_hour as string     = 07
previous_minute as string   = 45


seconds since the Epoch: 1381905900
time_difference between current time & previous log time = 1 minutes


Total DS ES       = 312
Total US ES       = 44
PREVIOUS_DS_ES    = 312
PREVIOUS_US_ES    = 44
At START, DS_ES_THIS_PERIOD = 0
At START, US_ES_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_ES_THIS_PERIOD = 0

After calculating, US_ES_THIS_PERIOD = 0
DS_ES_THIS_PERIOD = 0, i.e. LESS THAN OR EQUAL TO 60
US_ES_THIS_PERIOD = 0, i.e. LESS THAN OR EQUAL TO 60


Total DS RSCorr                 = 909540546
Total US RSCorr                 = 157
PREVIOUS_DS_RSCorr              = 908828359
PREVIOUS_US_RSCorr              = 157
At START, DS_RSCorr_THIS_PERIOD = 0
At START, US_RSCorr_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_RSCorr_THIS_PERIOD = 712187


Total DS RSUnCorr                 = 2083
Total US RSUnCorr                 = 0
PREVIOUS_DS_RSUnCorr              = 2083
PREVIOUS_US_RSUnCorr              = 0
At START, DS_RSUnCorr_THIS_PERIOD = 0
At START, US_RSUnCorr_THIS_PERIOD = 0
file EXISTS!



Total DS RS                 = 155279157
Total US RS                 = 3209897
PREVIOUS_DS_RS              = 148246647
PREVIOUS_US_RS              = 2889317
At START, DS_RS_THIS_PERIOD = 0
At START, US_RS_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_RS_THIS_PERIOD = 7032510
After calculating, US_RS_THIS_PERIOD = 320580


Total DS OHF                 = 34161328
Total US OHF                 = 961457
PREVIOUS_DS_OHF              = 34133858
PREVIOUS_US_OHF              = 956523
At START, DS_OHF_THIS_PERIOD = 0
At START, US_OHF_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_OHF_THIS_PERIOD = 27470

After calculating, US_OHF_THIS_PERIOD = 4934


Total DS OHFErr                 = 54
Total US OHFErr                 = 45
PREVIOUS_DS_OHFErr              = 54
PREVIOUS_US_OHFErr              = 45
At START, DS_OHFErr_THIS_PERIOD = 0
At START, US_OHFErr_THIS_PERIOD = 0
time_difference                 = 60
file EXISTS!



Total DS HEC                 = 457
Total US HEC                 = 0
PREVIOUS_DS_HEC              = 457
PREVIOUS_US_HEC              = 0
At START, DS_HEC_THIS_PERIOD = 0
At START, US_HEC_THIS_PERIOD = 0
file EXISTS!



Total DS CRC                 = 3443
Total US CRC                 = 61
PREVIOUS_DS_CRC              = 3443
PREVIOUS_US_CRC              = 61
At START, DS_CRC_THIS_PERIOD = 0
At START, US_CRC_THIS_PERIOD = 0
file EXISTS!



Total DS FEC                 = 1270027036
Total US FEC                 = 210
PREVIOUS_DS_FEC              = 1269314849
PREVIOUS_US_FEC              = 210
At START, DS_FEC_THIS_PERIOD = 0
At START, US_FEC_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_FEC_THIS_PERIOD = 712187


log_stats.mode = VDSL2

Total DS bitswap                 = 68679
Total US bitswap                 = 56
PREVIOUS_DS_bitswap              = 68621
PREVIOUS_US_bitswap              = 56
At START, DS_bitswap_THIS_PERIOD = 0
At START, US_bitswap_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_bitswap_THIS_PERIOD = 58


Now writing data to MSLOGFILE.log (modem_stats.log)

Data has now been written to MSLOGFILE (modem_stats.log)


sockfd has been closed
freeaddrinfo(res) completed
WSACleanup has completed
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: mikecrawford71 on October 16, 2013, 08:51:47 AM
Possibly tomorrow?

Which Windows version(s) do you use?

No rush, would just like to assist with testing.

Running a Windows 7 (32bit) VM, but can try it on Windows 8 or Server 2012.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 17, 2013, 08:14:37 AM
I can now report that my HG612's firmware was updated overnight.

My recently amended versions of the programs continued running on my W 7 setup, but caused a 'stuck' instance of HG612_stats.exe on a Vista laptop running a previous version.

Ending the stuck process allowed logging to continue & I have now installed the latest version on the Vista laptop.
It is running happily at the moment.

I have attached various updated programs if users wish to test them out & provide feedback, especially XP users, where there does seem to still be an issue.

All 3 programs need to be used to hopefully avoid issues, or at least pinpoint where issues are found.

The programs do create a number of debugging log files, stored in the Scripts, Ongoing_Stats & Current_stats folders.

It would be helpful to post details from these logs if issues are indeed still present, particularly "ERROR.LOG_file_ERROR.TXT", located in the Ongoing_Stats folder.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 17, 2013, 08:23:21 AM
BE, I've tested last nights version.

This is the contents of the ERROR_LOG_file_ERROR.TXT when it crashes.

Code: [Select]
17/10/2013  8:04:00.20 - In [HG612_stats.exe] - After ini_parse(). ERROR.LOG status = 0,
17/10/2013  8:04:00.23 - ERROR.LOG successfully opened. Status = 1,
17/10/2013  8:04:00.46 - ONGOING-ISRUNNING-080400-328.TXT - There are 1 instances of HG612_stats.exe running. Status = 1,
17/10/2013  8:04:00.50 - ONGOING-ISRUNNING-080400-328.TXT - ** Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1,
17/10/2013  8:04:01.51 - ONGOING-ISRUNNING-080400-328.TXT - **** Reaching this point means logging in to the modem was successful. Status = 1,
17/10/2013  8:04:01.53 - ONGOING-ISRUNNING-080400-328.TXT - After xdslcmd info --stats(), ERROR.LOG status = 1
17/10/2013  8:04:01.70 - ONGOING-ISRUNNING-080400-328.TXT - After xdslcmd info --pbParams(), ERROR.LOG status = 1
17/10/2013  8:04:02.10 - ONGOING-ISRUNNING-080400-328.TXT - Logged out of modem O.K. Status = 1,
17/10/2013  8:04:02.18 - ONGOING-ISRUNNING-080400-328.TXT - Now in exit. Status = 1,
17/10/2013  8:04:02.21 - ONGOING-ISRUNNING-080400-328.TXT - *** WSACleanup has completed.

This is the contents of the CMD window.

Code: [Select]
C:\HG612_Modem_Stats\Scripts>hg612_stats
progname = hg612_stats

The local time is: 08:04:00.140
A log file wasn't specified
argv[1] = (null)

path_only is C:\HG612_Modem_Stats\Scripts

At the start, working_directory (i.e. log_file_path) is

error from ini_parse_file() = 0

Before setting from config.Extensive_event_logging, DEBUG_COMMENTS = YES
From HG612_stats.ini hard coding, the ongoing log_file = C:\HG612_Modem_Stats\On
going_Stats\modem_stats.log

error_log_file = C:\HG612_Modem_Stats\Ongoing_Stats\ERROR.LOG

xlog_file = C:\HG612_Modem_Stats\Ongoing_Stats\xlogfile.txt
Temp File ONGOING-ISRUNNING-080400-328.TXT was created

'tasklist' is not recognized as an internal or external command,
operable program or batch file.
From HG612_stats.ini, Mode = extensive
Modex = 2

In switch, from HG612_stats.ini, Mode = extensive

Modem in use = HG622
VDSL2
Bearer_or Path = Bearer:, US sync = 6076, DS sync = 33690
Profile = 17a
MSGc = 18 DS & 33 US
October 2013 Firmware update = YES
Bitswap DS = 128495 / 93
parse_PbParams UPDATED FIRMWARE
firmware updated = YES
hgp.us_discovery_bp1_from = 6
hgp.us_discovery_bp1_to   = 31
hgp.us_discovery_bp2_from = 882
hgp.us_discovery_bp2_to   = 1193
hgp.us_discovery_bp3_from = 1984
hgp.us_discovery_bp3_to   = 2770

hgp.ds_discovery_bp1_from = 33
hgp.ds_discovery_bp1_to   = 857
hgp.ds_discovery_bp2_from = 1218
hgp.ds_discovery_bp2_to   = 1959
hgp.ds_discovery_bp3_from = 2795
hgp.ds_discovery_bp3_to   = 4083


hgp.us_line_attenuation_U0 = 3.8
hgp.us_line_attenuation_U1 = 41.6
hgp.us_line_attenuation_U2 = 60.1
hgp.us_line_attenuation_U3 = 0.0
hgp.us_line_attenuation_U4 = 0.0

hgp.ds_line_attenuation_D1 = 19.1
hgp.ds_line_attenuation_D2 = 52.5
hgp.ds_line_attenuation_D3 = 85.2


hgp.us_signal_attenuation_U0 = 3.8
hgp.us_signal_attenuation_U1 = 41.6
hgp.us_signal_attenuation_U2 = 0.0
hgp.us_signal_attenuation_U3 = 0.0
hgp.us_signal_attenuation_U4 = 0.0

hgp.ds_signal_attenuation_D1 = 19.9
hgp.ds_signal_attenuation_D2 = 52.1
hgp.ds_signal_attenuation_D3 = 0.0


SNR itch_buffer1 =              SNR Margin(dB):  6.1     6.0      N/A     N/A
  N/A    6.6     6.4      N/A
SNR itch_buffer2 =  6.1  6.0      N/A     N/A     N/A    6.6     6.4      N/A



hgp.us_snr_margin_U0 = 6.1
hgp.us_snr_margin_U1 = 6.0
hgp.us_snr_margin_U2 = 0.0
hgp.us_snr_margin_U3 = 0.0
hgp.us_snr_margin_U4 = 0.0

hgp.ds_snr_margin_D1 = 6.6
hgp.ds_snr_margin_D2 = 6.4
hgp.ds_snr_margin_D3 = 0.0


tx_power itch_buffer1 =                  TX Power(dBm):  2.0    -1.2      N/A
  N/A     N/A    8.5     7.3      N/A
tx_power itch_buffer2 =  2.0    -1.2      N/A     N/A     N/A    8.5     7.3
  N/A


hgp.us_tx_power_U0 = 2.0
hgp.us_tx_power_U1 = -1.2
hgp.us_tx_power_U2 = 0.0
hgp.us_tx_power_U3 = 0.0
hgp.us_tx_power_U4 = 0.0

hgp.ds_tx_power_D1 = 8.5
hgp.ds_tx_power_D2 = 7.3
hgp.ds_tx_power_D3 = 0.0
17/10/2013 08:04 Showtime 1 42220 6058 0 33690 6076 L0 VDSL2 VDSL2 B 17a PTM ON
ON No Showtime 6.6 6.0 24.5 0.0 11.0 3.7 47 190 1 1 64 5 16 16 0.0453 0.9988 112
96 1658 1429 1 64 207 64 207 64191396 147514 125 164 3548009048 1195602 17402644
35 495 6062 0 1300 0 63 0 63 0 3510031809 0 28579124 0 0 0 0 329 105 271 0 333 6
2 186944 8.00 0.00 2.91 16.29 16.00 0.00 65.92 19.14 128495 93 2100750925 548 35
14 180 329 105 271 0 333 62 2 0 12 0 7229835 0 0 0 0 0 0 0 0 0 0 0 0 0 8346195 2
6 0 9 0 5 0 0 0 0 0 0 0 0 588903598 107 10 31 3 18 0 0 0 0 0 0 0 0 797504642 297
 78 109 18 57 0 0 0 0 0 0 0 0 1740264435 495 125 164 27 89 0 0 0 0 0 0 0 0 secon
ds since the Epoch: 1381993440


 size of modem_stats.log is 79848361


fseek_success = 0
captured:
17/10/2013 08:03 33690 6076 6.4 6.0 11.0 3.7 41876 6061 PTM 3514 180 2100172596
548 1300 0 186884 0 0 560095 0 51.912 1739686106 495 560095 64170792 143836 2025
9 125 164 0 3542734654 956603 5186497 6062 0 0 1429 1 329 105 271 0 0 0 0 239519
 3687 0 3.8 41.6 60.1 0.0 19.1 52.5 85.2 3.8 41.6 0.0 0.0 19.9 52.1 0.0 6.0 6.0
0.0 0.0 6.4 6.3 0.0 2.0 -1.2 0.0 0.0 8.5 7.3 0.0 Showtime 1 0 L0 17a ON ON No Sh
owtime 24.5 0.0 47 190 1 1 64 5 16 16 0.0453 0.9988 11296 1658 63 0 63 0 3506148
141 0 28578769 0 0 0 0 333 62 8.00 0.00 2.91 16.29 16.00 0.00 65.92 19.14 128452
 93 VDSL2 42 0

previous_day as string     = 17
previous_month as string   = 10
previous_year as string    = 2013

previous_hour as string     = 08
previous_minute as string   = 03


seconds since the Epoch: 1381993380
time_difference between current time & previous log time = 1 minutes


Total DS ES       = 329
Total US ES       = 105
PREVIOUS_DS_ES    = 329
PREVIOUS_US_ES    = 105
At START, DS_ES_THIS_PERIOD = 0
At START, US_ES_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_ES_THIS_PERIOD = 0

After calculating, US_ES_THIS_PERIOD = 0
DS_ES_THIS_PERIOD = 0, i.e. LESS THAN OR EQUAL TO 60
US_ES_THIS_PERIOD = 0, i.e. LESS THAN OR EQUAL TO 60


Total DS RSCorr                 = 1740264435
Total US RSCorr                 = 495
PREVIOUS_DS_RSCorr              = 1739686106
PREVIOUS_US_RSCorr              = 495
At START, DS_RSCorr_THIS_PERIOD = 0
At START, US_RSCorr_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_RSCorr_THIS_PERIOD = 578329


Total DS RSUnCorr                 = 6062
Total US RSUnCorr                 = 0
PREVIOUS_DS_RSUnCorr              = 6062
PREVIOUS_US_RSUnCorr              = 0
At START, DS_RSUnCorr_THIS_PERIOD = 0
At START, US_RSUnCorr_THIS_PERIOD = 0
file EXISTS!



Total DS RS                 = 3548009048
Total US RS                 = 1195602
PREVIOUS_DS_RS              = 3542734654
PREVIOUS_US_RS              = 956603
At START, DS_RS_THIS_PERIOD = 0
At START, US_RS_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_RS_THIS_PERIOD = 5274394
After calculating, US_RS_THIS_PERIOD = 238999


Total DS OHF                 = 64191396
Total US OHF                 = 147514
PREVIOUS_DS_OHF              = 64170792
PREVIOUS_US_OHF              = 143836
At START, DS_OHF_THIS_PERIOD = 0
At START, US_OHF_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_OHF_THIS_PERIOD = 20604

After calculating, US_OHF_THIS_PERIOD = 3678


Total DS OHFErr                 = 125
Total US OHFErr                 = 164
PREVIOUS_DS_OHFErr              = 125
PREVIOUS_US_OHFErr              = 164
At START, DS_OHFErr_THIS_PERIOD = 0
At START, US_OHFErr_THIS_PERIOD = 0
time_difference                 = 60
file EXISTS!



Total DS HEC                 = 1300
Total US HEC                 = 0
PREVIOUS_DS_HEC              = 1300
PREVIOUS_US_HEC              = 0
At START, DS_HEC_THIS_PERIOD = 0
At START, US_HEC_THIS_PERIOD = 0
file EXISTS!



Total DS CRC                 = 3514
Total US CRC                 = 180
PREVIOUS_DS_CRC              = 3514
PREVIOUS_US_CRC              = 180
At START, DS_CRC_THIS_PERIOD = 0
At START, US_CRC_THIS_PERIOD = 0
file EXISTS!



Total DS FEC                 = 2100750925
Total US FEC                 = 548
PREVIOUS_DS_FEC              = 2100172596
PREVIOUS_US_FEC              = 548
At START, DS_FEC_THIS_PERIOD = 0
At START, US_FEC_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_FEC_THIS_PERIOD = 578329


log_stats.mode = VDSL2

Total DS bitswap                 = 128495
Total US bitswap                 = 93
PREVIOUS_DS_bitswap              = 128452
PREVIOUS_US_bitswap              = 93
At START, DS_bitswap_THIS_PERIOD = 0
At START, US_bitswap_THIS_PERIOD = 0
file EXISTS!


After calculating, DS_bitswap_THIS_PERIOD = 43


Now writing data to MSLOGFILE.log (modem_stats.log)

Data has now been written to MSLOGFILE (modem_stats.log)


sockfd has been closed
freeaddrinfo(res) completed
WSACleanup has completed

I've not seen this problem before mentioning TaskList

Code: [Select]
C:\HG612_Modem_Stats\Scripts>hg612_stats
progname = hg612_stats

The local time is: 08:15:49.984
A log file wasn't specified
argv[1] = (null)

path_only is C:\HG612_Modem_Stats\Scripts

At the start, working_directory (i.e. log_file_path) is

error from ini_parse_file() = 0

Before setting from config.Extensive_event_logging, DEBUG_COMMENTS = YES
From HG612_stats.ini hard coding, the ongoing log_file = C:\HG612_Modem_Stats\On
going_Stats\modem_stats.log

error_log_file = C:\HG612_Modem_Stats\Ongoing_Stats\ERROR.LOG

xlog_file = C:\HG612_Modem_Stats\Ongoing_Stats\xlogfile.txt
Temp File ONGOING-ISRUNNING-081550-218.TXT was created

'tasklist' is not recognized as an internal or external command,
operable program or batch file.

C:\HG612_Modem_Stats\Scripts>

Another run also had an extra line in the ERROR_LOG_file_ERROR.TXT

Code: [Select]
17/10/2013  8:13:01.07 - In [HG612_stats.exe] - After ini_parse(). ERROR.LOG status = 0,
17/10/2013  8:13:01.10 - ERROR.LOG successfully opened. Status = 1,
17/10/2013  8:13:01.40 - ONGOING-ISRUNNING-081301-250.TXT - There are 1 instances of HG612_stats.exe running. Status = 1,
17/10/2013  8:13:01.42 - ONGOING-ISRUNNING-081301-250.TXT - ** Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1,
17/10/2013  8:13:02.25 - ONGOING-ISRUNNING-081301-250.TXT - **** Reaching this point means logging in to the modem was successful. Status = 1,
17/10/2013  8:13:02.28 - ONGOING-ISRUNNING-081301-250.TXT - After xdslcmd info --stats(), ERROR.LOG status = 1
17/10/2013  8:13:02.45 - ONGOING-ISRUNNING-081301-250.TXT - After xdslcmd info --pbParams(), ERROR.LOG status = 1
17/10/2013  8:13:02.85 - ONGOING-ISRUNNING-081301-250.TXT - Logged out of modem O.K. Status = 1,
17/10/2013  8:13:02.89 - ONGOING-ISRUNNING-081301-250.TXT - Now in exit. Status = 1,
17/10/2013  8:13:02.92 - ONGOING-ISRUNNING-081301-250.TXT - *** WSACleanup has completed.
17/10/2013  8:13:02.93 - ONGOING-ISRUNNING-081301-250.TXT - *** txbuf has been freed.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Korben_dallas on October 17, 2013, 08:26:14 AM
Many thanks for all your hard work in updating the programs, will download them and give them a go.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 17, 2013, 08:35:02 AM
BE, are you running Windows 7 Professional? If so you can download the free XP virtual machine (http://windows.microsoft.com/en-GB/windows7/install-and-use-windows-xp-mode-in-windows-7). That will save you trying to source an XP system to test with at home.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 17, 2013, 12:28:22 PM
Alas, no.

It is a Home Premium version.


I had considered ways of running XP via Mrs Eagle's laptop but that wouldn't work out too well as a) I'd never get chance to hog it while fiddling with programs & b) it's a bit too clapped out to use for XP dual booting/VMs etc.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 17, 2013, 12:39:12 PM

'tasklist' is not recognized as an internal or external command,
operable program or batch file.



That sounds like a XP Home version.
Tasklist should be included in XP Pro.


http://answers.microsoft.com/en-us/windows/forum/windows_xp-performance/how-and-where-do-i-download-and-run-missing/4f11c462-ca6a-4472-8713-b91c107441a5


It won't cause any errors other than to flag up that it wasn't found (unless it is installed, in which case it's something I need to look into a bit further).


Did the program crash immediately following the message?



If it isn't installed, any left over ISRUNNING files are likely to be blank.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 17, 2013, 01:31:41 PM
Yes, it is XP home, many years ago I made the mistake of getting home instead of professional. That also explains why the isrunning files are blank.


The text in the code window above is what happened, so it must have crashed out, and I'm sure there was an error, although I haven't seen.

Does C allow the use of Option Strict and Option Explicit or similar?  With vb.net these default to off, which is a very bad idea. With them set to on when the program is compiled it checks the validity of a lot of things which may cause problems at some point.  If you have these options it might highlight potential problems when compiling.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Chrysalis on October 17, 2013, 03:40:49 PM
I have a XP vm, I can test if you struggling for XP testers.

Please give me instructions to mirror your setup, and what to look out for.

note my modem is on old firmware.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 17, 2013, 05:39:14 PM
If your IsRunningVB program can be adapted to list ALL running processes, we could do away with tasklist.exe altogether.
Is that feasible?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 17, 2013, 07:22:30 PM
installed those files into my vista v1.1 stats no windows errors pop ups and task schedule is running and current graphs fine but still no ongoing stats are being logged



Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 17, 2013, 07:25:26 PM
If your IsRunningVB program can be adapted to list ALL running processes, we could do away with tasklist.exe altogether.
Is that feasible?

I've found a suitable method, and adjusted it accordingly - file attached, it will create info.txt and list just the process names, no other info.

I've tested it on XP,W7,W8 & WHS 2011
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 17, 2013, 07:31:31 PM
If your IsRunningVB program can be adapted to list ALL running processes, we could do away with tasklist.exe altogether.
Is that feasible?

I've found a suitable method, and adjusted it accordingly - file attached, it will create info.txt and list just the process names, no other info.

I've tested it on XP,W7,W8 & WHS 2011

Oh dear you have not tested it on vista !
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 17, 2013, 07:36:09 PM
I'll leave that to BE, if he can get his hands on his wife's laptop  ::)
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 17, 2013, 07:43:55 PM

Ending the stuck process allowed logging to continue & I have now installed the latest version on the Vista laptop.
It is running happily at the moment.
.

what did you do to end the stuck process on Vista ?

I will also boot into windows 8.1 preview and amend those files and let you know if any errors come into play  ;)

edit never mind the error on Vista windows has just come on screen  :(
but the xlog file has appeared in ongoing folder

edit sorry to keep editing but the modem_stats files has not been created in the ongoing stats folder which is very strange.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 17, 2013, 09:30:51 PM

what did you do to end the stuck process on Vista ?


Run Task Manager, Right click on HG612_stats.exe & click "End Process"


Quote
I will also boot into windows 8.1 preview and amend those files and let you know if any errors come into play  ;)

edit never mind the error on Vista windows has just come on screen  :(
but the xlog file has appeared in ongoing folder

edit sorry to keep editing but the modem_stats files has not been created in the ongoing stats folder which is very strange.


It looks like the get_data() function failed each time.


The attached version may not fix that, but it will hopefully show exactly where it failed, so I might be able to suss out what to do about it.


Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 17, 2013, 09:38:56 PM
I have a XP vm, I can test if you struggling for XP testers.

Please give me instructions to mirror your setup, and what to look out for.

note my modem is on old firmware.


Thanks for the offer, but it's modems with the new firmware that seem to be causing these issues.
If I'm not mistaken, you have chosen to block any updates on your HG612, haven't you?

The default setup is just fine, as long as the latest program test versions are used.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 17, 2013, 11:49:27 PM
Update:-


I started seeing almost identical issues with my own connection this evening, after running quite nicely all day.

I didn't realise this when I was attempting to resolve the XP issues, but now it has happened to me, I think I might have tracked down the issue.

The get_data() function runs through a loop a few times until it has obtained all the data.

It used a buffer of 1024 maximum bytes per run.

I increased the buffer size to 2048 bytes & it suddenly started behaving properly again.
i.e. It seems that the new firmware now (sometimes) delivers more than 1024 bytes per run.


I don't know whether this will act as a permanent fix or not, but I have attached yet another version of HG612_stats.exe if anyone wishes to try it out.

The usual error log files would be appreciated if providing feedback.

I have also attached the latest 24 hours montage for 'interest'

Ignoring the gaps from when the program was misbehaving, there has been quite a change in some of the stats delivered via this firmware update, namely DS Interleaving depth, DS Signal attenuation & DS Bitswaps.

It has produced a DS tiny sync speed increase, although that may have just been due to it resyncing at a 'quiter' time of day (04:36 this morning).

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 18, 2013, 12:54:17 AM
Sorry BE1 running it now on Win8.1 the move from Win8.1 preview to full 8.1 took longer than expected  :D
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 18, 2013, 12:56:56 AM
OK BE1  :clap2: I can see ongoing stats coming in  ;D

No errors as yet on screen and some error logs for you ->
and using the latest HG612_stats.exe you posted and seems stable after 40 minutes of logging will try it out on Vista tomorrow

cheers

edit:   :clap: the ongoing stats are also starting to come in on Vista very good work BE
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Chrysalis on October 18, 2013, 12:31:44 PM
I have a XP vm, I can test if you struggling for XP testers.

Please give me instructions to mirror your setup, and what to look out for.

note my modem is on old firmware.


Thanks for the offer, but it's modems with the new firmware that seem to be causing these issues.
If I'm not mistaken, you have chosen to block any updates on your HG612, haven't you?

The default setup is just fine, as long as the latest program test versions are used.



no worries and yep I killed BT agent.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 18, 2013, 03:30:26 PM
BE, I didn't spot your update until lunchtime  :no:, but since installing it's been running fine, prior to that it had crashed every time I'd run it recently. So it is certainly looking like you might have fixed it  :thumbs:

I'll leave it running over the weekend and see how it's going on Monday.

It very strange how it works fine in other OSes but caused problems in XP, also it only crashed (in my case) after writing the log.

Did you see my TaskListVB program a few posts back?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 18, 2013, 06:06:45 PM
BE, I didn't spot your update until lunchtime  :no:, but since installing it's been running fine, prior to that it had crashed every time I'd run it recently. So it is certainly looking like you might have fixed it  :thumbs:

I'll leave it running over the weekend and see how it's going on Monday.



That's great news  :) - fingers crossed.........

Quote

It very strange how it works fine in other OSes but caused problems in XP, also it only crashed (in my case) after writing the log.


Maybe as cumulative stats build up over a few hours/days, the harvested data ends up too large for the original buffer size.


Quote

Did you see my TaskListVB program a few posts back?


I did indeed & I have tested it as working just fine.

I'll include it instead of tasklist as soon as I have confidence that the updated program(s) are definitelly running reliably.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 19, 2013, 12:56:15 AM
Just checked and it's still working - set my works PC to upload the files to Dropbox every four hours  ;D
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 19, 2013, 01:34:27 AM
Just posting ongoing stats to show all is well, two of the gaps is me switching over the OS (Vista to Windows 8.1) and back again, the others may be PC going through Virus scans or disk defrag schedule.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 19, 2013, 09:00:19 AM
Still going well on my works PC  :thumbs:, I'll post a graph when it's got 24 hours worth of stats.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 19, 2013, 06:47:53 PM
Have to say Ronski I do like the settings editor as I have a Dual boot system both running the stats and being able to set the ongoing stats folder to one partition, before I was copying and pasting the modem_stats file from each partition  :thumbs:
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 19, 2013, 06:59:18 PM
Thanks NewtronStar, glad you like it and find it useful. I'm currently working on some updates allowing stats to be viewed (http://forum.kitz.co.uk/index.php/topic,13078.0.html) from within the editor now that we've lost the GUI of the modem.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 19, 2013, 08:56:08 PM
Thanks NewtronStar, glad you like it and find it useful. I'm currently working on some updates allowing stats to be viewed (http://forum.kitz.co.uk/index.php/topic,13078.0.html) from within the editor now that we've lost the GUI of the modem.

Yes a quick glance is very good as most of us just need the bare necessities with the knowledge that's the stats are being built up in the background, to analyse when we have the time or when things don't look so good and need a full Graph to see what's going on with my line  ;) 
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 20, 2013, 10:01:24 AM
I can confirm that my XP version is still running. I've downloaded the modem log and plotted these graphs using yesterdays data. Won't be able to check if there are any isrunning files until tomorrow.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 20, 2013, 10:23:06 AM
Graphing-wise, it's good not to see any gaps - suggesting none or very few missing data harvests.

Performance-wise, it doesn't look that great though:-

High DS Interleaving depth

Massive amount of DS RSCorr/FEC errors (I imagine cumulative values will fairly regularly reset to zero whenever the maximum value that the modem can store is reached. Delta values between samples should still continue being reported though)

Quite high amount of DS bitswapping

Do these cause any issues regarding actual throughput or 'feel' of the connection?

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 20, 2013, 10:37:29 AM
Hi Be, the connection feels fine now, however it didn't when the problems hit - see this thread for the details. (http://forum.kitz.co.uk/index.php/topic,13061.msg246454.html#msg246454) Unfortunately due to the firmware being updated I didn't manage to get much in the way of stats when it happened. PN/BTor say there is no problem, obviously there is somewhere. I do wonder if someone got FTTC and it's a bad case of cross talk. Please post any replies relating to this in the thread linked to.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 21, 2013, 08:03:47 AM
Back at work now, so can check on things properly. It's been logging fine and producing the graphs, I had only one isrunning file from Saturday at 12:01

It seems the midday current stats took a long time to complete, thus keeping HG612_Stats open.

Looking at the current stats error log I can see that it was graph6 that took over a minute, this looks very similar to the problem I've been having on my server. No other programs were open, no virus scans either.

Looking through the other Graph6 log entries, it normally take 7 - 10 seconds to complete, and it is only this one entry which takes a lot longer, though there are not many entries - the first entry is 14-10-2013

Snippets from all three logs are below.

Code: [Select]
19/10/2013 11:59:02.562 - End of HG612_stats.exe program, EXIT error code = 0
19/10/2013 11:59:02.593 - End of HG612_stats.exe program, closing ERROR.LOG


19/10/2013 12:01:01.031 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
19/10/2013 12:01:01.140 - From IsRunningVB.exe, HG612_current_stats.exe is running 1 instances.
19/10/2013 12:01:19.093 - HG612_current_stats.exe has now completed, so HG612_stats.exe is resuming.
19/10/2013 12:01:19.093 - Temp File ONGOING-ISRUNNING-120119-093.TXT was created
19/10/2013 12:01:19.281 - *** Now in exit_2_instances as there are 2 instances of HG612_stats.exe running. Status = 1.
19/10/2013 12:01:19.312 - *** In exit_2_instances - Closing ERROR.LOG. Status = 1.


19/10/2013 12:00:00.859 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
19/10/2013 12:00:00.968 - HG612_current_stats.exe was NOT running
19/10/2013 12:00:00.968 - Temp File ONGOING-ISRUNNING-120000-968.TXT was created
19/10/2013 12:00:01.234 - malloc() O.K.
19/10/2013 12:00:01.234 - Initializing Winsock
19/10/2013 12:00:01.234 - WSAStartup() SUCCESSFUL!!!
19/10/2013 12:00:01.234 - Starting getaddrinfo()
19/10/2013 12:00:01.234 - getaddrinfo() O.K.
19/10/2013 12:00:01.234 - Starting socket()
19/10/2013 12:00:01.234 - socket() O.K.
19/10/2013 12:00:01.234 - Starting connect()
19/10/2013 12:00:01.250 - connect() O.K.
19/10/2013 12:00:02.078 - About to reply(xdslcmd info --stats)
19/10/2013 12:00:02.078 - reply(xdslcmd info --stats) O.K.
19/10/2013 12:00:02.281 - get_data() O.K.
19/10/2013 12:00:02.281 - About to determine the xDSL mode
19/10/2013 12:00:02.281 - Modem in use = HG622
19/10/2013 12:00:02.281 - About to parse_stats()
19/10/2013 12:00:02.281 - parse_stats completed
19/10/2013 12:00:02.281 - VDSL2 mode detected so about to reply(xdslcmd info --pbParams)
19/10/2013 12:00:02.281 - reply(xdslcmd info --pbParams) O.K.
19/10/2013 12:00:02.484 - get_data() O.K.
19/10/2013 12:00:02.484 - About to parse_pbParams_data()
19/10/2013 12:00:02.484 - parse_pbParams_data() completed
19/10/2013 12:00:02.484 - About to exit Busybox
19/10/2013 12:00:02.484 - Busybox exited
19/10/2013 12:00:02.750 - About to exit ATP
19/10/2013 12:00:02.750 - ATP exited
19/10/2013 12:00:02.750 - Logged out of modem O.K.
19/10/2013 12:00:02.781 - Current data harvested at 1382180400 seconds past the epoch
19/10/2013 12:00:02.781 - About to get_log_data()
19/10/2013 12:00:02.781 - Previous log stats date/time = 1382180340 seconds past the epoch
19/10/2013 12:00:02.781 - time_difference = 1 minutes
19/10/2013 12:00:02.781 - About to calc_ES_delta()
19/10/2013 12:00:02.781 - About to calc_RSCorr_delta()
19/10/2013 12:00:02.781 - ds_RSCorr_THIS_PERIOD = 595053
19/10/2013 12:00:02.781 - us_RSCorr_THIS_PERIOD = 0
19/10/2013 12:00:02.781 - About to calc_RSUnCorr_delta()
19/10/2013 12:00:02.781 - About to calc_RS_delta()
19/10/2013 12:00:02.781 - About to calc_OHF_delta()
19/10/2013 12:00:02.781 - About to calc_OHFErr_delta()
19/10/2013 12:00:02.781 - About to calc_HEC_delta()
19/10/2013 12:00:02.781 - About to calc_CRC_delta()
19/10/2013 12:00:02.781 - About to calc_FEC_delta()
19/10/2013 12:00:02.781 - About to calc_Bitswap_delta()
19/10/2013 12:00:02.781 - Appending data to C:\HG612_Modem_Stats\Ongoing_Stats\modem_stats.log
19/10/2013 12:00:02.781 - Closing MSLOGFILE.log
19/10/2013 12:00:02.781 - END of 1 minute sampling
19/10/2013 12:00:02.812 - About to close(sockfd)
19/10/2013 12:00:02.812 - About to freeadddrinfo(res)
19/10/2013 12:00:02.812 - About to WSACleanup()
19/10/2013 12:00:02.843 - About to free(txbuf)
19/10/2013 12:00:02.906 - About to free(rxbuf)
19/10/2013 12:00:03.000 - Current  sync speeds are     DS 33690 kbps      US 6076 kbps, AS = 373909
19/10/2013 12:00:03.000 - Previous sync speeds were    DS 33690 kbps      US 6076 kbps, AS = 373849
19/10/2013 12:00:03.031 - Sync speeds have NOT changed since the previous data harvest
19/10/2013 12:00:03.062 - ONGOING-ISRUNNING-120000-968.TXT DELETED
*********************************************************************************************************************
19/10/2013 12:00:03.062 - Scheduled Current_Stats logging is switched ON via the ini file
19/10/2013 12:00:03.062 - Current_Stats_Datum    = 00
19/10/2013 12:00:03.062 - Current_Stats_Interval = 12
19/10/2013 12:00:03.093 - (Time in Hours - Datum) Modulus Interval       i.e. (12 - 0) % 12 = 0
19/10/2013 12:00:03.093 - (Time in Minutes) = 00
19/10/2013 12:00:03.109 - RUN HG612_current_stats.exe
*********************************************************************************************************************
19/10/2013 12:01:28.609 - Scheduled Daily_Graphing is switched ON via the ini file
19/10/2013 12:01:28.609 - Daily_Graphing_hour    = 23
19/10/2013 12:01:28.609 - Daily_Graphing_minutes = 58
19/10/2013 12:01:28.609 - Current Time is Hours 12, Minutes 01
19/10/2013 12:01:28.625 - scheduled Daily_Graphing is NOT due yet
*********************************************************************************************************************
19/10/2013 12:01:28.625 - End of HG612_stats.exe program, EXIT error code = 0
19/10/2013 12:01:28.656 - End of HG612_stats.exe program, closing ERROR.LOG


19/10/2013 12:02:00.375 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
19/10/2013 12:02:00.468 - HG612_current_stats.exe was NOT running
19/10/2013 12:02:00.468 - Temp File ONGOING-ISRUNNING-120200-468.TXT was created
19/10/2013 12:02:00.625 - malloc() O.K.
19/10/2013 12:02:00.625 - Initializing Winsock
19/10/2013 12:02:00.625 - WSAStartup() SUCCESSFUL!!!
19/10/2013 12:02:00.625 - Starting getaddrinfo()
19/10/2013 12:02:00.625 - getaddrinfo() O.K.
19/10/2013 12:02:00.625 - Starting socket()
19/10/2013 12:02:00.640 - socket() O.K.
19/10/2013 12:02:00.640 - Starting connect()
19/10/2013 12:02:00.640 - connect() O.K.
19/10/2013 12:02:01.375 - About to reply(xdslcmd info --stats)
19/10/2013 12:02:01.375 - reply(xdslcmd info --stats) O.K.
19/10/2013 12:02:01.562 - get_data() O.K.
19/10/2013 12:02:01.562 - About to determine the xDSL mode
19/10/2013 12:02:01.562 - Modem in use = HG622
19/10/2013 12:02:01.562 - About to parse_stats()
19/10/2013 12:02:01.562 - parse_stats completed
19/10/2013 12:02:01.562 - VDSL2 mode detected so about to reply(xdslcmd info --pbParams)
19/10/2013 12:02:01.562 - reply(xdslcmd info --pbParams) O.K.
19/10/2013 12:02:01.765 - get_data() O.K.
19/10/2013 12:02:01.765 - About to parse_pbParams_data()
19/10/2013 12:02:01.765 - parse_pbParams_data() completed
19/10/2013 12:02:01.765 - About to exit Busybox
19/10/2013 12:02:01.765 - Busybox exited
19/10/2013 12:02:01.953 - About to exit ATP
19/10/2013 12:02:01.953 - ATP exited
19/10/2013 12:02:01.953 - Logged out of modem O.K.
19/10/2013 12:02:01.968 - Current data harvested at 1382180520 seconds past the epoch
19/10/2013 12:02:01.968 - About to get_log_data()
19/10/2013 12:02:01.968 - Previous log stats date/time = 1382180400 seconds past the epoch
19/10/2013 12:02:01.968 - time_difference = 2 minutes
19/10/2013 12:02:01.968 - WARNING!!! time_difference is more than 1 minute - Ignoring previous logged time
19/10/2013 12:02:01.968 - About to calc_ES_delta()
19/10/2013 12:02:01.968 - About to calc_RSCorr_delta()
19/10/2013 12:02:01.968 - ds_RSCorr_THIS_PERIOD = 0
19/10/2013 12:02:01.968 - us_RSCorr_THIS_PERIOD = 0
19/10/2013 12:02:01.968 - About to calc_RSUnCorr_delta()
19/10/2013 12:02:01.968 - About to calc_RS_delta()
19/10/2013 12:02:01.968 - About to calc_OHF_delta()
19/10/2013 12:02:01.968 - About to calc_OHFErr_delta()
19/10/2013 12:02:01.968 - About to calc_HEC_delta()
19/10/2013 12:02:01.968 - About to calc_CRC_delta()
19/10/2013 12:02:01.968 - About to calc_FEC_delta()
19/10/2013 12:02:01.968 - About to calc_Bitswap_delta()
19/10/2013 12:02:01.968 - Appending data to C:\HG612_Modem_Stats\Ongoing_Stats\modem_stats.log
19/10/2013 12:02:01.968 - Closing MSLOGFILE.log
19/10/2013 12:02:01.968 - END of 1 minute sampling
19/10/2013 12:02:02.000 - About to close(sockfd)
19/10/2013 12:02:02.000 - About to freeadddrinfo(res)
19/10/2013 12:02:02.000 - About to WSACleanup()
19/10/2013 12:02:02.031 - About to free(txbuf)
19/10/2013 12:02:02.078 - About to free(rxbuf)
19/10/2013 12:02:02.156 - Current  sync speeds are     DS 33690 kbps      US 6076 kbps, AS = 374029
19/10/2013 12:02:02.156 - Previous sync speeds were    DS 33690 kbps      US 6076 kbps, AS = 373909
19/10/2013 12:02:02.187 - Sync speeds have NOT changed since the previous data harvest
19/10/2013 12:02:02.203 - ONGOING-ISRUNNING-120200-468.TXT DELETED
*********************************************************************************************************************
19/10/2013 12:02:02.203 - Scheduled Current_Stats logging is switched ON via the ini file
19/10/2013 12:02:02.203 - Current_Stats_Datum    = 00
19/10/2013 12:02:02.203 - Current_Stats_Interval = 12
19/10/2013 12:02:02.234 - (Time in Hours - Datum) Modulus Interval       i.e. (12 - 0) % 12 = 0
19/10/2013 12:02:02.234 - (Time in Minutes) = 02
19/10/2013 12:02:02.234 - Modulus & minutes *BOTH* have to be zero, so scheduled snapshot logging is NOT due yet
*********************************************************************************************************************
19/10/2013 12:02:02.281 - Scheduled Daily_Graphing is switched ON via the ini file
19/10/2013 12:02:02.281 - Daily_Graphing_hour    = 23
19/10/2013 12:02:02.281 - Daily_Graphing_minutes = 58
19/10/2013 12:02:02.281 - Current Time is Hours 12, Minutes 02
19/10/2013 12:02:02.312 - scheduled Daily_Graphing is NOT due yet
*********************************************************************************************************************
19/10/2013 12:02:02.312 - End of HG612_stats.exe program, EXIT error code = 0
19/10/2013 12:02:02.343 - End of HG612_stats.exe program, closing ERROR.LOG

Code: [Select]
19/10/2013 12:00:05.953 - Start of Current Stats Harvesting
19/10/2013 12:00:06.062 - From IsRunningVB.exe, HG612_stats.exe was running 1 instances.
19/10/2013 12:00:06.109 - The program full path is      C:\HG612_Modem_Stats\Scripts\HG612_current_stats.exe
19/10/2013 12:00:06.109 - The program directory path is C:\HG612_Modem_Stats\Scripts
19/10/2013 12:00:06.109 - DEBUG_COMMENTS = YES
19/10/2013 12:00:06.109 - Temp File CURRENT-ISRUNNING-120006-062 was created
19/10/2013 12:00:06.109 - Before checking for RESYNC or SCHEDULED events, Delay_snapshot_data_harvest_seconds = 0
19/10/2013 12:00:06.109 - A SCHEDULED run was detected via HG612_stats.exe. Initial Pause setting from ini file = NO
19/10/2013 12:00:06.109 - Pause setting for this instance only has been forced to be NO
19/10/2013 12:00:06.109 - After checking for RESYNC or SCHEDULED events, Delay_snapshot_data_harvest_seconds = 0
19/10/2013 12:00:06.109 - malloc() O.K.
19/10/2013 12:00:06.109 - Initializing Winsock
19/10/2013 12:00:06.109 - WSAStartup() SUCCESSFUL!!!
19/10/2013 12:00:06.109 - Starting getaddrinfo()
19/10/2013 12:00:06.109 - getaddrinfo() O.K.
19/10/2013 12:00:06.109 - Starting socket()
19/10/2013 12:00:06.125 - socket() O.K.
19/10/2013 12:00:06.125 - Starting connect()
19/10/2013 12:00:06.125 - connect() O.K.
19/10/2013 12:00:07.078 - About to reply(xdslcmd info --stats)
19/10/2013 12:00:07.078 - reply(xdslcmd info --stats) O.K.
19/10/2013 12:00:07.281 - get_data() for --stats O.K.
19/10/2013 12:00:07.281 - Modem in use = HG622
19/10/2013 12:00:07.281 - About to determine the xDSL mode
19/10/2013 12:00:07.281 - About to parse_stats()
19/10/2013 12:00:07.281 - parse_stats completed
19/10/2013 12:00:07.281 - VDSL2 mode detected so about to reply(xdslcmd info --pbParams)
19/10/2013 12:00:07.281 - reply(xdslcmd info --pbParams) O.K.
19/10/2013 12:00:07.578 - get_data() for --pbParams O.K.
19/10/2013 12:00:07.578 - About to parse_pbParams_data()
19/10/2013 12:00:07.578 - parse_pbParams_data() completed
19/10/2013 12:00:07.578 - reply(xdslcmd info --Bits) O.K.
19/10/2013 12:00:07.875 - get_data() for --Bits O.K.
19/10/2013 12:00:07.890 - reply(xdslcmd info --linediag) O.K.
19/10/2013 12:00:09.593 - get_data() for --linediag O.K.
19/10/2013 12:00:09.593 - reply(xdslcmd info --SNR) O.K.
19/10/2013 12:00:10.093 - get_data() for --SNR O.K.
19/10/2013 12:00:10.093 - reply(xdslcmd info --QLN) O.K.
19/10/2013 12:00:10.593 - get_data() for --QLN O.K.
19/10/2013 12:00:10.593 - reply(xdslcmd info --Hlog) O.K.
19/10/2013 12:00:11.093 - get_data() for --Hlog O.K.
19/10/2013 12:00:11.093 - reply(xdslcmd info --show) O.K.
19/10/2013 12:00:11.296 - get_data() for --show O.K.
19/10/2013 12:00:11.296 - About to exit Busybox
19/10/2013 12:00:11.296 - Busybox exited
19/10/2013 12:00:11.500 - About to exit ATP
19/10/2013 12:00:11.500 - ATP exited
19/10/2013 12:00:11.500 - About to close (sockfd)
19/10/2013 12:00:11.500 - About to WSACleanup()
19/10/2013 12:00:11.500 - About to free(txbuf)
19/10/2013 12:00:11.500 - About to free(rxbuf)
19/10/2013 12:00:11.500 - CURRENT-ISRUNNING-120006-062 DELETED
19/10/2013 12:00:11.500 - Auto_graph_Snapshot_data = YES, now graphing snapshot stats
19/10/2013 12:00:11.500 - Successfully changed directory to C:\HG612_Modem_Stats\Current_Stats\Current_Stats_20131019-1200-SCHEDULED
19/10/2013 12:00:11.500 - Pause_after_obtaining_snapshot_data = NO
19/10/2013 12:00:11.500 - GRAPHING_COMMAND = ""C:\HG612_Modem_Stats\Scripts\GRAPH6.exe" "C:\HG612_Modem_Stats\Current_Stats\Current_Stats_20131019-1200-SCHEDULED\Plink_20131019-1200.log""
19/10/2013 12:01:28.531 - Snapshot graphs should now have been plotted
19/10/2013 12:01:28.531 - End of HG612_current_stats.exe program, closing ERROR.LOG

Code: [Select]
19/10/2013 12:00:13.234 - Start of GRAPH6.exe
19/10/2013 12:00:13.234 - The program full path is      C:\HG612_Modem_Stats\Scripts\GRAPH6.exe
19/10/2013 12:00:13.234 - The program directory path is C:\HG612_Modem_Stats\Scripts
19/10/2013 12:00:13.234 - DEBUG_COMMENTS = YES
19/10/2013 12:00:13.234 - Using C:\HG612_Modem_Stats\Current_Stats\Current_Stats_20131019-1200-SCHEDULED\Plink_20131019-1200.log
19/10/2013 12:00:13.234 - About to change folder to the one with the log file stored in it
19/10/2013 12:00:13.234 - Successfully changed directory to C:\HG612_Modem_Stats\Current_Stats\Current_Stats_20131019-1200-SCHEDULED
19/10/2013 12:01:28.515 - The Plink log being used came from a RESYNC or SCHEDULED event, so any pauses were disabled
19/10/2013 12:01:28.515 - End of GRAPH6.exe program, closing ERROR.LOG
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 21, 2013, 07:48:59 PM
Updated programs are attached, if anyone wishes to test them & provide feedback.


They should take account of the recent modem firmware updates & also still work with non-updated HG612 modems.


I'm currently working on a method to start HG612_current_stats & GRAPH6.exe from HG612_stats.exe for scheduled or RESYNC logging, yet allowing HG612_stats.exe to complete unhindered.

I believe that for Windows systems, a 'C' function named CreateProcess can be used via the calling program (HG612_stats.exe in our case), with a 'NOWAIT' switch.

Do any of you know how to use this function?
Example 'C' (not C++) code would be much appreciated to help speed this along.



The final release versions of the programs will do away with all/most of the debugging log files & RONSKI's updated Settings Editor will also be included.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 22, 2013, 12:35:13 AM
Just copied the working files into my backup folder just incase  ;) and replaced them with the new ones via your download zip folder and all is working and Ronski's new editor also working fine no errors and stats building up in ongoing folder  ;D

createProcess 'C' http://www.perlmonks.org/?node_id=936683

and this http://www.statistics101.net/userguide/UserGuide127.html

thats google for ya
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: DeadMan on October 22, 2013, 01:12:15 AM
Win 7 64

Replaced 1.1 with newest graph6.exe

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 22, 2013, 01:18:47 AM
Win 7 64

Replaced 1.1 with newest graph6.exe

did you also replace Hg612_stats.exe and HG612_currentstats.exe and graphpd.exe
BE1 has those in his beta zipped programs folder above.

and also wondering as you have 64bit OS could this be a problem as I am still using 32bit OS  :-\
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: DeadMan on October 22, 2013, 01:34:42 AM
Win 7 64

Replaced 1.1 with newest graph6.exe

did you also replace Hg612_stats.exe and HG612_currentstats.exe and graphpd.exe
BE1 has those in his beta zipped programs folder above.

and also wondering as you have 64bit OS could this be a problem as I am still using 32bit OS  :-\

Ah! That worked. But now my graphs look 'odd'.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 22, 2013, 01:40:42 AM
Win 7 64

Replaced 1.1 with newest graph6.exe

did you also replace Hg612_stats.exe and HG612_currentstats.exe and graphpd.exe
BE1 has those in his beta zipped programs folder above.

and also wondering as you have 64bit OS could this be a problem as I am still using 32bit OS  :-\

Ah! That worked. But now my graphs look 'odd'.

It will look odd as your stats have not been logged since the firmware update it's going to take time for the graphs to make sence (24 hours)
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: One2Go on October 25, 2013, 10:41:53 PM
Just tested new version. looks good so far.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 26, 2013, 01:15:26 AM
still some work to be done on the VDSL Band Status columns in the --pbParams it looks messy compared to old version
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 26, 2013, 09:51:02 AM
still some work to be done on the VDSL Band Status columns in the --pbParams it looks messy compared to old version

That's sorted now & will be included in the next beta update.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 26, 2013, 11:05:39 PM

Update 26/10/2013:


I have attached updated beta test programs that hopefully address the issue of HG612_stats.exe occasionally taking too long on some systems during SCHEDULED & RESYNC events that meant it could creep into the next minute's data harvesting time, causing it to exit & miss the new data sample & sometimes the graphing of the SCHEDULED event.

All 4 programs need to be extracted to the Scripts folder to work properly.

These updates have also addressed the issue of the 'snapshot' graph montages having grown too large to be attached to messages in this forum.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 27, 2013, 12:09:17 AM
Thanks have installed them on Vista and Windows 8.1 and looking good though the bpParams page still looks out of focus to me the others are fine.

 
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 27, 2013, 01:07:03 AM
If this one is clearer, I'll include it in the next update.

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: burakkucat on October 27, 2013, 01:40:06 AM
If this one is clearer, I'll include it in the next update.

Much better.  :)
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Chrysalis on October 27, 2013, 12:44:40 PM
thanks, these are newer than what you sent me before BE?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 27, 2013, 01:12:00 PM
thanks, these are newer than what you sent me before BE?

Yes, some are very recent (i.e. yesterday).

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: kitz on October 27, 2013, 02:32:07 PM

Update 26/10/2013:


I have attached updated beta test programs that hopefully address the issue of HG612_stats.exe occasionally taking too long on some systems during SCHEDULED & RESYNC events that meant it could creep into the next minute's data harvesting time, causing it to exit & miss the new data sample & sometimes the graphing of the SCHEDULED event.

All 4 programs need to be extracted to the Scripts folder to work properly.

These updates have also addressed the issue of the 'snapshot' graph montages having grown too large to be attached to messages in this forum.

Cheers BE - duly installed.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 27, 2013, 10:10:50 PM
I seem to have lost 3 hours worth of graphing on HG612 stats the only thing that I used was DSLstats V4.1 at 19:11  :o

Do they not work together any more ?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Ronski on October 27, 2013, 10:25:37 PM
NewtronStar, one other user has mentioned that the stats stopped being  recorded when he used my latest GUI, could it be related to this?
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 27, 2013, 10:31:01 PM
NewtronStar, one other user has mentioned that the stats stopped being  recorded when he used my latest GUI, could it be related to this?

No Ronski I have not updated to your latest GUI as I am still using the one from 2 days ago.
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on October 27, 2013, 10:42:58 PM
‎I am using this version of Ronski's GUI:-

27 ‎October ‎2013, ‏‎16:31:02

I haven't lost any stats at all today, apart from when the clocks went back at around 01:00.

I haven't been running DSLStats recently though.


EDIT:

Eric did mention that the HG612-Modem-Stats co-operation feature of DSLStats will also have been put out of synchronisation when the clocks went back:-

http://forum.kitz.co.uk/index.php?topic=13164.msg248029#msg248029

I don't know how much, if any effect that might have had?

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: NewtronStar on October 27, 2013, 11:06:16 PM
‎I am using this version of Ronski's GUI:-

27 ‎October ‎2013, ‏‎16:31:02

I haven't lost any stats at all today, apart from when the clocks went back at around 01:00.

I haven't been running DSLStats recently though.

Ok i have just fired up DSLstats again at 22:49, I only noticed this the gap when doing a 8 hour graph and there both working together, it may have been when I was on Windows 8.1 as it looks like i forgot to allow the HG612_stats.exe to run after BE1 updated files last night and was watching F1 on 8.1 Media Center.
sorry my mistake "issue closed"  :-[
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: skeffling on November 08, 2013, 04:53:31 PM
Hi!
I work for AAISP, anyone seen anything odd (broken) with UDP on updated firmware on these modems?
Info: http://status.aa.net.uk/1854
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: DeadMan on November 10, 2013, 09:15:09 PM
I am no longer able to run snapshot stats (again) it just crashes with the error I had last time around. I am using all the files in the beta zip file (which did work for a while. Well on the 20th of October when it was last run that is).

EDIT: This would not be due to them updating the firmware again because of that VPN issue? Ronski's newest GUI reports firmware version A2pv6C038m.d24j

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on November 11, 2013, 12:24:40 AM
What's the date of the beta zip file that you are using?

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: DeadMan on November 11, 2013, 04:03:21 PM
What's the date of the beta zip file that you are using?

Programs-2013-11-08.zip

I originally tried with the October version but it still does it with this version.

"HG612_current_stats.exe has stopped working

The problem caused the program to stop working correctly.
Windows will close the program and notify you if a solution is available"

Yeah right. :P
Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: Bald_Eagle1 on November 11, 2013, 07:06:59 PM
Hmmm,

If you are willing to zip a few log file extracts from before, during & after the issue & post them here I'll have a look into this.

The relevant files would be:-

Login_events.TXT               (Scripts folder)
HG612_stats.ini                (Scripts folder)
ERROR.LOG                      (Ongoing_Stats folder)
ERROR.LOG_file_ERROR.TXT       (Ongoing_Stats folder)
graphpd_ERROR.LOG              (Ongoing_Stats folder)
modem_stats.log                (Ongoing_Stats folder)
GRAPH6_ERROR.LOG               (Current_Stats folder)
Current_ERROR.LOG              (Current_Stats folder)




It might also be worth checking in Task Manager that there are no stuck instances of any of the programs.

Also, which version of Windows are you using?

Title: Re: HG612 modem firmware update & band plan changes October 2013
Post by: kitz on November 13, 2013, 08:51:37 PM
Hi!
I work for AAISP, anyone seen anything odd (broken) with UDP on updated firmware on these modems?
Info: http://status.aa.net.uk/1854

Hi Andrew and welcome. :)

I dont use a VPN these days so cant comment Im afraid, dunno if any of the other guys do.