HG612 Modem Stats - Release v 3.0 for EXISTING users

A small program for existing v 2.x users to update to v 3.0 can be dowloaded from here:-

http://www.ronski.me.uk/Downloads/v3.0.0.0ProgramUpd...


Save it to & run it from the Scripts folder.


Existing ini file settings will be preserved, along with the addition of any other settings needed for v 3.0.0.0

Existing versions of the programs will be date stamped & saved in a subfolder of the Scripts folder, named Program Backups


In due course, when using the GUI, general program updates will be optionally automated.
This is not fully functional yet but in the meantime, the GUI itself can still be auto-updated.


Apart from a few minor bug fixes, improved reliability, improved compatibility with the DSLStats program & addition of ADSL compatibility for SOME Broadcom routers, not much has changed from v 2.x

It also removes the annoying every minute 'beep' or 'critical error' sound that some users have heard when using beta test versions for logging ongoing stats.


A full package version 3.0 will be uploaded to the usual FreewareFiles.com download site later this week, but there will be no need to download it for users that update from v 2.x via the updater program.


As always, constructive feedback is welcomed.

Edited by deleted (Mon 23-Jun-14 05:55:50)

Please note:

The original program linked to was a 64 bit version that didn't work on all Windows systems.

It has been replaced with a 32 bit version that should work for all systems, XP upward.

The download link is still the same for the newer version.

Edited by deleted (Mon 23-Jun-14 23:11:24)

This seemed to be OK when I first installed it but today I'm getting this error after every run:

Faulting application name: HG612_stats.exe, version: 3.0.0.0, time stamp: 0x53a62785
Faulting module name: msvcrt.dll, version: 7.0.7601.17744, time stamp: 0x4eeaf722
Exception code: 0xc0000005
Fault offset: 0x0001ed82
Faulting process id: 0x1720
Faulting application start time: 0x01cf91ef892a54b7
Faulting application path: C:\HG612_Modem_stats\Scripts\HG612_stats.exe
Faulting module path: C:\Windows\syswow64\msvcrt.dll
Report Id: ca56fb5f-fde2-11e3-b7ca-bcaec56d946f

The logging still seems to be working OK. I am also getting a text file created every minute in the Scripts folder called ONGOING-ISRUNNING-nnnnnn-nnn.txt - I don't think it used to do this.

Any ideas?

Edited by kasg (Fri 27-Jun-14 13:36:38)

The isrunning file is created every minute, but should also be deleted, I'll leave BE1 to look into the error.

Thanks - it's probably not being deleted because the process isn't finishing cleanly.

In reply to a post by kasg:

Thanks - it's probably not being deleted because the process isn't finishing cleanly.

That's correct.
As mentioned by Ronski, those files should be auto-deleted if HG612_stats.exe completes properly.


Which Windows version are you using?


Could you post the details from your HG612_stats.ini file (Scripts folder) along with working & not working examples from your ONGOING_ERROR.LOG_file_ERROR.TXT file (Event & Error Logs folder)?


It might also be worth checking that you don't have any of the log files open in Notepad as that could lock them, preventing proper completion.

In reply to a post by Bald_Eagle1:

Which Windows version are you using?

Widnows 7 SP1, 64-bit, fully patched.

In reply to a post by Bald_Eagle1:

Could you post the details from your HG612_stats.ini file (Scripts folder) along with working & not working examples from your ONGOING_ERROR.LOG_file_ERROR.TXT file (Event & Error Logs folder)?

HG612_stats.ini

[Login]
IP_address                            = 192.168.1.1
Username                              = admin
Password                              = admin
Shell                                 = sh

[Locations]
Main_folder                           = C:\HG612_Modem_Stats
Scripts_and_Programs                  = C:\HG612_Modem_Stats\Scripts
Apps_folder                           = C:\HG612_Modem_Stats\Apps
Current_Stats_folder                  = C:\HG612_Modem_Stats\Current_Stats
Ongoing_Stats_folder                  = C:\HG612_Modem_Stats\Ongoing_Stats
Modem_Log                             = C:\HG612_Modem_Stats\Ongoing_Stats

[Mode]
Mode                                  = extensive

[Graphing]
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                        = NO
Daily_Graphing_Time                   = 06:00

[Delays]
Delay_snapshot_data_harvest_seconds   = 6
Delay_ongoing_data_harvest_seconds    = 0

[Logging]
Ongoing_Logging                       = YES
Current_Stats                         = YES
Current_Stats_Interval                = 8
Current_Stats_Datum                   = 06

[Event/Error_logging]
Extensive_event_logging               = YES
Extra_debugging_info                  = YES

[Modem]
Modem_type                            = HG612

[PC_Speed]
Speed                                 = SLOW

Failure log

27-Jun-14 20:27:05.60 - ONGOING-ISRUNNING-202701-143.TXT - Start of [HG612_stats.exe] - **** Version 3.0.0.0 22/06/14 **** 
27-Jun-14 20:27:07.63 - ONGOING-ISRUNNING-202701-143.TXT - Immediately after ini_parse() - About to determine the OS version. ERROR.LOG status = 0, 
27-Jun-14 20:27:07.89 - ONGOING-ISRUNNING-202701-143.TXT - *** Windows version = [6.1.7601] *** 
27-Jun-14 20:27:08.14 - ONGOING-ISRUNNING-202701-143.TXT - In [HG612_stats.exe] - After obtaining the OS version. ERROR.LOG status = 0, 
27-Jun-14 20:27:08.60 - ONGOING-ISRUNNING-202701-143.TXT - ERROR.LOG successfully opened. Status = 1, 
27-Jun-14 20:27:09.61 - ONGOING-ISRUNNING-202701-143.TXT - **** From IsRunningVB.exe, dslstatssampling.exe is *NOT* running 
27-Jun-14 20:27:10.59 - ONGOING-ISRUNNING-202701-143.TXT - ONGOING-ISRUNNING-202701-143.TXT created. Status = 1, 
27-Jun-14 20:27:11.65 - ONGOING-ISRUNNING-202701-143.TXT - There are 1 instances of HG612_stats.exe running. Status = 1, 
27-Jun-14 20:27:11.71 - ONGOING-ISRUNNING-202701-143.TXT - Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1, 
27-Jun-14 20:27:11.76 - ONGOING-ISRUNNING-202701-143.TXT - WSAStartup() SUCCESSFUL!!! Status = 1, 
27-Jun-14 20:27:11.81 - ONGOING-ISRUNNING-202701-143.TXT - getaddrinfo() O.K. Status = 1, 
27-Jun-14 20:27:11.85 - ONGOING-ISRUNNING-202701-143.TXT - connect() O.K. Status = 1, 
27-Jun-14 20:27:11.90 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:11.95 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("gin:"), times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:12.00 - ONGOING-ISRUNNING-202701-143.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:12.04 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("gin:"), sleeping for 0.000 seconds *** numbytes = 0 
27-Jun-14 20:27:12.09 - ONGOING-ISRUNNING-202701-143.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 100 
27-Jun-14 20:27:12.13 - ONGOING-ISRUNNING-202701-143.TXT - End of expect("gin:") 
27-Jun-14 20:27:12.18 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:12.22 - ONGOING-ISRUNNING-202701-143.TXT - expect("gin:") O.K. Status = 1. 
27-Jun-14 20:27:12.27 - ONGOING-ISRUNNING-202701-143.TXT - reply(config.Username) O.K. Status = 1. 
27-Jun-14 20:27:12.31 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:12.36 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("ssword:"), times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:12.42 - ONGOING-ISRUNNING-202701-143.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:12.47 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("ssword:"), sleeping for 0.000 seconds *** numbytes = 0 
27-Jun-14 20:27:12.51 - ONGOING-ISRUNNING-202701-143.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 16 
27-Jun-14 20:27:12.56 - ONGOING-ISRUNNING-202701-143.TXT - End of expect("ssword:") 
27-Jun-14 20:27:12.61 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:12.65 - ONGOING-ISRUNNING-202701-143.TXT - expect("ssword:") O.K. Status = 1. 
27-Jun-14 20:27:12.69 - ONGOING-ISRUNNING-202701-143.TXT - reply(config.Password) O.K. Status = 1. 
27-Jun-14 20:27:12.74 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:12.79 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("ATP>"), times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:12.83 - ONGOING-ISRUNNING-202701-143.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:12.88 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("ATP>"), sleeping for 0.000 seconds *** numbytes = 0 
27-Jun-14 20:27:12.93 - ONGOING-ISRUNNING-202701-143.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 7 
27-Jun-14 20:27:12.97 - ONGOING-ISRUNNING-202701-143.TXT - End of expect("ATP>") 
27-Jun-14 20:27:13.02 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:13.06 - ONGOING-ISRUNNING-202701-143.TXT - expect("ATP>") O.K. Status = 1. 
27-Jun-14 20:27:13.40 - ONGOING-ISRUNNING-202701-143.TXT - **** reply(config.Shell) O.K. Status = 1. 
27-Jun-14 20:27:13.45 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:13.50 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("# "), times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:13.54 - ONGOING-ISRUNNING-202701-143.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:13.59 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("# "), sleeping for 0.000 seconds *** numbytes = 0 
27-Jun-14 20:27:13.64 - ONGOING-ISRUNNING-202701-143.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 122 
27-Jun-14 20:27:13.68 - ONGOING-ISRUNNING-202701-143.TXT - End of expect("# ") 
27-Jun-14 20:27:13.73 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:13.77 - ONGOING-ISRUNNING-202701-143.TXT - The prompt "# " *WAS* received. Sleeping for 0.100 seconds. Status = 1, 
27-Jun-14 20:27:13.95 - ONGOING-ISRUNNING-202701-143.TXT - Reaching this point means logging in to the modem was successful. Status = 1, 
27-Jun-14 20:27:13.99 - ONGOING-ISRUNNING-202701-143.TXT - reply(xdslcmd info --stats) O.K. Status = 1, return value = 0 
27-Jun-14 20:27:14.05 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#01). *** This was included in data received:- xdslcmd info --stats - (numbyes = 1560, index = 1560) 
27-Jun-14 20:27:14.25 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#02). *** This was included in data received:- xdslcmd info --stats - (numbyes = 546, index = 2106) 
27-Jun-14 20:27:14.31 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#02). *** This was included in data received:-  # - (numbytes = 546, index = 2106) 
27-Jun-14 20:27:14.36 - ONGOING-ISRUNNING-202701-143.TXT - get_data() for xdslcmd info --stats O.K. Status = 1, times in get_data() loop was 02 
27-Jun-14 20:27:14.41 - ONGOING-ISRUNNING-202701-143.TXT - After xdslcmd info --stats, ERROR.LOG status = 1 
27-Jun-14 20:27:14.47 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#01). *** This was included in data received:- xdslcmd info --pbParams - (numbyes = 25, index = 25) 
27-Jun-14 20:27:14.67 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#02). *** This was included in data received:- xdslcmd info --pbParams - (numbyes = 1047, index = 1072) 
27-Jun-14 20:27:14.71 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#02). *** This was included in data received:-  # - (numbytes = 1047, index = 1072) 
27-Jun-14 20:27:14.76 - ONGOING-ISRUNNING-202701-143.TXT - get_data() for xdslcmd info --pbParams O.K. Status = 1, times in get_data() loop was 02 
27-Jun-14 20:27:14.81 - ONGOING-ISRUNNING-202701-143.TXT - After xdslcmd info --pbParams, ERROR.LOG status = 1 
27-Jun-14 20:27:14.86 - ONGOING-ISRUNNING-202701-143.TXT - reply(xdslcmd --version) O.K. Status = 1, times in get_data() loop was 00 
27-Jun-14 20:27:14.91 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#01). *** This was included in data received:- xdslcmd --version - (numbyes = 107, index = 107) 
27-Jun-14 20:27:15.09 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#02). *** This was included in data received:- xdslcmd --version - (numbyes = 2, index = 109) 
27-Jun-14 20:27:15.14 - ONGOING-ISRUNNING-202701-143.TXT - In get_data(#02). *** This was included in data received:-  # - (numbytes = 2, index = 109) 
27-Jun-14 20:27:15.19 - ONGOING-ISRUNNING-202701-143.TXT - get_data() for xdslcmd --version O.K..Status = 1, times in get_data() loop was 02 
27-Jun-14 20:27:15.24 - ONGOING-ISRUNNING-202701-143.TXT - After xdslcmd --version, ERROR.LOG status = 1 
27-Jun-14 20:27:15.29 - ONGOING-ISRUNNING-202701-143.TXT - *** reply("exit") after "xdslcmd --version" O.K. Status = 1, 
27-Jun-14 20:27:15.33 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:15.38 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("ATP>"), times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:15.43 - ONGOING-ISRUNNING-202701-143.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
27-Jun-14 20:27:15.48 - ONGOING-ISRUNNING-202701-143.TXT - At start of expect("ATP>"), sleeping for 0.000 seconds *** numbytes = 0 
27-Jun-14 20:27:15.52 - ONGOING-ISRUNNING-202701-143.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 12 
27-Jun-14 20:27:15.57 - ONGOING-ISRUNNING-202701-143.TXT - End of expect("ATP>") 
27-Jun-14 20:27:15.62 - ONGOING-ISRUNNING-202701-143.TXT - ************************************************************************* 
27-Jun-14 20:27:15.66 - ONGOING-ISRUNNING-202701-143.TXT - *** reply("exit") from "ATP>" O.K. Status = 1, 
27-Jun-14 20:27:15.70 - ONGOING-ISRUNNING-202701-143.TXT - Logged out of modem O.K. Status = 1,

Success log

28-Jun-14  3:17:40.10 - ONGOING-ISRUNNING-031739-849.TXT - Start of [HG612_stats.exe] - **** Version 3.0.0.0 22/06/14 **** 
28-Jun-14  3:17:41.43 - ONGOING-ISRUNNING-031739-849.TXT - Immediately after ini_parse() - About to determine the OS version. ERROR.LOG status = 0, 
28-Jun-14  3:17:41.49 - ONGOING-ISRUNNING-031739-849.TXT - *** Windows version = [6.1.7601] *** 
28-Jun-14  3:17:41.56 - ONGOING-ISRUNNING-031739-849.TXT - In [HG612_stats.exe] - After obtaining the OS version. ERROR.LOG status = 0, 
28-Jun-14  3:17:41.70 - ONGOING-ISRUNNING-031739-849.TXT - ERROR.LOG successfully opened. Status = 1, 
28-Jun-14  3:17:42.41 - ONGOING-ISRUNNING-031739-849.TXT - **** From IsRunningVB.exe, dslstatssampling.exe is *NOT* running 
28-Jun-14  3:17:42.71 - ONGOING-ISRUNNING-031739-849.TXT - ONGOING-ISRUNNING-031739-849.TXT created. Status = 1, 
28-Jun-14  3:17:43.32 - ONGOING-ISRUNNING-031739-849.TXT - There are 1 instances of HG612_stats.exe running. Status = 1, 
28-Jun-14  3:17:43.39 - ONGOING-ISRUNNING-031739-849.TXT - Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1, 
28-Jun-14  3:17:43.45 - ONGOING-ISRUNNING-031739-849.TXT - WSAStartup() SUCCESSFUL!!! Status = 1, 
28-Jun-14  3:17:43.51 - ONGOING-ISRUNNING-031739-849.TXT - getaddrinfo() O.K. Status = 1, 
28-Jun-14  3:17:43.59 - ONGOING-ISRUNNING-031739-849.TXT - connect() ERROR!!! - Exiting HG612_stats.exe. Status = 1, 
28-Jun-14  3:17:43.64 - ONGOING-ISRUNNING-031739-849.TXT - Now in exit. Status = 1, 
28-Jun-14  3:17:43.70 - ONGOING-ISRUNNING-031739-849.TXT - *** WSACleanup has completed. 
28-Jun-14  3:17:43.75 - ONGOING-ISRUNNING-031739-849.TXT - *** About to free(txbuf). 
28-Jun-14  3:17:43.82 - ONGOING-ISRUNNING-031739-849.TXT - *** txbuf has been freed. 
28-Jun-14  3:17:43.86 - ONGOING-ISRUNNING-031739-849.TXT - *** About to free(rxbuf). 
28-Jun-14  3:17:43.91 - ONGOING-ISRUNNING-031739-849.TXT - *** rxbuf has been freed. 
28-Jun-14  3:17:43.98 - ONGOING-ISRUNNING-031739-849.TXT - *** Now checking for resyncs - There are 1 instances of HG612_stats.exe running. 
28-Jun-14  3:17:44.03 - ONGOING-ISRUNNING-031739-849.TXT - ONGOING-ISRUNNING-031739-849.TXT DELETED - Status = 1, 
28-Jun-14  3:17:44.07 - ONGOING-ISRUNNING-031739-849.TXT - *** Scheduled snapshot logging is switched ON. 
28-Jun-14  3:17:44.12 - ONGOING-ISRUNNING-031739-849.TXT - *** Scheduled snapshot logging is NOT due yet. 
28-Jun-14  3:17:44.17 - ONGOING-ISRUNNING-031739-849.TXT - *** Scheduled Daily graphing is NOT switched on. 
28-Jun-14  3:17:44.23 - ONGOING-ISRUNNING-031739-849.TXT - End of [HG612_stats.exe], Closing ERROR.LOG. Status = 1, 
28-Jun-14  3:17:44.28 - ONGOING-ISRUNNING-031739-849.TXT - ERROR.LOG successfully closed. Status = 0, 
28-Jun-14  3:17:44.33 - ONGOING-ISRUNNING-031739-849.TXT - total_sleep_time = 0.000 seconds 
28-Jun-14  3:17:44.38 - ONGOING-ISRUNNING-031739-849.TXT - Normal End of [HG612_stats.exe]

Interestingly, it seems to be working OK again today.

Edit: I spoke too soon, it's just crashed again. I have changed the schedule from once a minute to once an hour until I can solve the problem.

In reply to a post by Bald_Eagle1:

It might also be worth checking that you don't have any of the log files open in Notepad as that could lock them, preventing proper completion.

No, not that.

Thanks for your help

Edited by kasg (Sat 28-Jun-14 19:27:56)

Is it possible that you got the Success & Failure headings the wrong way round?


This is from the Success log:-

28-Jun-14 3:17:43.59 - ONGOING-ISRUNNING-031739-849.TXT - connect() ERROR!!! - Exiting HG612_stats.exe. Status = 1,
28-Jun-14 3:17:43.64 - ONGOING-ISRUNNING-031739-849.TXT - Now in exit. Status = 1,

No stats will have been obtained during that instance, but at least the program exited cleanly:-

28-Jun-14 3:17:44.38 - ONGOING-ISRUNNING-031739-849.TXT - Normal End of [HG612_stats.exe]





In what you had named Failure log, was this actually the last row:-

27-Jun-14 20:27:15.70 - ONGOING-ISRUNNING-202701-143.TXT - Logged out of modem O.K. Status = 1,

In other words, did it not exit cleanly?


Also, this seems an 'unusual' time for HG612_stats.exe to start:-

28-Jun-14 3:17:40.10 - ONGOING-ISRUNNING-031739-849.TXT - Start of [HG612_stats.exe]


Do you have 'other' things running around that time in the morning that could be slowing the PC down & delaying the start of HG612_stats.exe?


It might just be worth deleting the logging task & recreating it via the GUI to ensure all 'new' settings have been fully picked up.
e.g. it 'appears' that HG612_stats.exe starts at 5 seconds past the minute, despite this setting in the ini file:-

Delay_ongoing_data_harvest_seconds = 0

In reply to a post by Bald_Eagle1:

Is it possible that you got the Success & Failure headings the wrong way round?

I'm not sure now. Here's one that just crashed:

29-Jun-14  6:27:01.47 - ONGOING-ISRUNNING-062701-142.TXT - Start of [HG612_stats.exe] - **** Version 3.0.0.0 22/06/14 **** 
29-Jun-14  6:27:03.40 - ONGOING-ISRUNNING-062701-142.TXT - Immediately after ini_parse() - About to determine the OS version. ERROR.LOG status = 0, 
29-Jun-14  6:27:03.57 - ONGOING-ISRUNNING-062701-142.TXT - *** Windows version = [6.1.7601] *** 
29-Jun-14  6:27:03.75 - ONGOING-ISRUNNING-062701-142.TXT - In [HG612_stats.exe] - After obtaining the OS version. ERROR.LOG status = 0, 
29-Jun-14  6:27:04.14 - ONGOING-ISRUNNING-062701-142.TXT - ERROR.LOG successfully opened. Status = 1, 
29-Jun-14  6:27:05.16 - ONGOING-ISRUNNING-062701-142.TXT - **** From IsRunningVB.exe, dslstatssampling.exe is *NOT* running 
29-Jun-14  6:27:06.20 - ONGOING-ISRUNNING-062701-142.TXT - ONGOING-ISRUNNING-062701-142.TXT created. Status = 1, 
29-Jun-14  6:27:07.97 - ONGOING-ISRUNNING-062701-142.TXT - There are 1 instances of HG612_stats.exe running. Status = 1, 
29-Jun-14  6:27:08.19 - ONGOING-ISRUNNING-062701-142.TXT - Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1, 
29-Jun-14  6:27:08.37 - ONGOING-ISRUNNING-062701-142.TXT - WSAStartup() SUCCESSFUL!!! Status = 1, 
29-Jun-14  6:27:08.55 - ONGOING-ISRUNNING-062701-142.TXT - getaddrinfo() O.K. Status = 1, 
29-Jun-14  6:27:08.72 - ONGOING-ISRUNNING-062701-142.TXT - connect() O.K. Status = 1, 
29-Jun-14  6:27:08.90 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:09.08 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("gin:"), times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:09.27 - ONGOING-ISRUNNING-062701-142.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:09.44 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("gin:"), sleeping for 0.000 seconds *** numbytes = 0 
29-Jun-14  6:27:09.66 - ONGOING-ISRUNNING-062701-142.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 100 
29-Jun-14  6:27:09.83 - ONGOING-ISRUNNING-062701-142.TXT - End of expect("gin:") 
29-Jun-14  6:27:10.01 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:10.20 - ONGOING-ISRUNNING-062701-142.TXT - expect("gin:") O.K. Status = 1. 
29-Jun-14  6:27:10.37 - ONGOING-ISRUNNING-062701-142.TXT - reply(config.Username) O.K. Status = 1. 
29-Jun-14  6:27:10.56 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:10.74 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("ssword:"), times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:10.92 - ONGOING-ISRUNNING-062701-142.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:11.10 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("ssword:"), sleeping for 0.000 seconds *** numbytes = 0 
29-Jun-14  6:27:11.28 - ONGOING-ISRUNNING-062701-142.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 16 
29-Jun-14  6:27:11.45 - ONGOING-ISRUNNING-062701-142.TXT - End of expect("ssword:") 
29-Jun-14  6:27:11.64 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:11.82 - ONGOING-ISRUNNING-062701-142.TXT - expect("ssword:") O.K. Status = 1. 
29-Jun-14  6:27:11.99 - ONGOING-ISRUNNING-062701-142.TXT - reply(config.Password) O.K. Status = 1. 
29-Jun-14  6:27:12.17 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:12.34 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("ATP>"), times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:12.52 - ONGOING-ISRUNNING-062701-142.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:12.70 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("ATP>"), sleeping for 0.000 seconds *** numbytes = 0 
29-Jun-14  6:27:12.88 - ONGOING-ISRUNNING-062701-142.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 7 
29-Jun-14  6:27:13.04 - ONGOING-ISRUNNING-062701-142.TXT - End of expect("ATP>") 
29-Jun-14  6:27:13.22 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:13.39 - ONGOING-ISRUNNING-062701-142.TXT - expect("ATP>") O.K. Status = 1. 
29-Jun-14  6:27:14.05 - ONGOING-ISRUNNING-062701-142.TXT - **** reply(config.Shell) O.K. Status = 1. 
29-Jun-14  6:27:14.23 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:14.41 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("# "), times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:14.59 - ONGOING-ISRUNNING-062701-142.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:14.78 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("# "), sleeping for 0.000 seconds *** numbytes = 0 
29-Jun-14  6:27:14.96 - ONGOING-ISRUNNING-062701-142.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 122 
29-Jun-14  6:27:15.13 - ONGOING-ISRUNNING-062701-142.TXT - End of expect("# ") 
29-Jun-14  6:27:15.31 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:15.50 - ONGOING-ISRUNNING-062701-142.TXT - The prompt "# " *WAS* received. Sleeping for 0.100 seconds. Status = 1, 
29-Jun-14  6:27:15.78 - ONGOING-ISRUNNING-062701-142.TXT - Reaching this point means logging in to the modem was successful. Status = 1, 
29-Jun-14  6:27:15.95 - ONGOING-ISRUNNING-062701-142.TXT - reply(xdslcmd info --stats) O.K. Status = 1, return value = 0 
29-Jun-14  6:27:16.16 - ONGOING-ISRUNNING-062701-142.TXT - In get_data(#01). *** This was included in data received:- xdslcmd info --stats - (numbyes = 2070, index = 2070) 
29-Jun-14  6:27:16.35 - ONGOING-ISRUNNING-062701-142.TXT - In get_data(#01). *** This was included in data received:-  # - (numbytes = 2070, index = 2070) 
29-Jun-14  6:27:16.54 - ONGOING-ISRUNNING-062701-142.TXT - get_data() for xdslcmd info --stats O.K. Status = 1, times in get_data() loop was 01 
29-Jun-14  6:27:16.74 - ONGOING-ISRUNNING-062701-142.TXT - After xdslcmd info --stats, ERROR.LOG status = 1 
29-Jun-14  6:27:16.94 - ONGOING-ISRUNNING-062701-142.TXT - In get_data(#01). *** This was included in data received:- xdslcmd info --pbParams - (numbyes = 25, index = 25) 
29-Jun-14  6:27:17.14 - ONGOING-ISRUNNING-062701-142.TXT - In get_data(#02). *** This was included in data received:- xdslcmd info --pbParams - (numbyes = 1044, index = 1069) 
29-Jun-14  6:27:17.33 - ONGOING-ISRUNNING-062701-142.TXT - In get_data(#02). *** This was included in data received:-  # - (numbytes = 1044, index = 1069) 
29-Jun-14  6:27:17.52 - ONGOING-ISRUNNING-062701-142.TXT - get_data() for xdslcmd info --pbParams O.K. Status = 1, times in get_data() loop was 02 
29-Jun-14  6:27:17.72 - ONGOING-ISRUNNING-062701-142.TXT - After xdslcmd info --pbParams, ERROR.LOG status = 1 
29-Jun-14  6:27:17.91 - ONGOING-ISRUNNING-062701-142.TXT - reply(xdslcmd --version) O.K. Status = 1, times in get_data() loop was 00 
29-Jun-14  6:27:18.12 - ONGOING-ISRUNNING-062701-142.TXT - In get_data(#01). *** This was included in data received:- xdslcmd --version - (numbyes = 109, index = 109) 
29-Jun-14  6:27:18.32 - ONGOING-ISRUNNING-062701-142.TXT - In get_data(#01). *** This was included in data received:-  # - (numbytes = 109, index = 109) 
29-Jun-14  6:27:18.70 - ONGOING-ISRUNNING-062701-142.TXT - get_data() for xdslcmd --version O.K..Status = 1, times in get_data() loop was 01 
29-Jun-14  6:27:18.93 - ONGOING-ISRUNNING-062701-142.TXT - After xdslcmd --version, ERROR.LOG status = 1 
29-Jun-14  6:27:19.12 - ONGOING-ISRUNNING-062701-142.TXT - *** reply("exit") after "xdslcmd --version" O.K. Status = 1, 
29-Jun-14  6:27:19.34 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:19.52 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("ATP>"), times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:19.70 - ONGOING-ISRUNNING-062701-142.TXT - ****** PC_Speed is set as "SLOW" in the ini file, times in loop = 01 *** numbytes = 0 
29-Jun-14  6:27:20.09 - ONGOING-ISRUNNING-062701-142.TXT - At start of expect("ATP>"), sleeping for 0.000 seconds *** numbytes = 0 
29-Jun-14  6:27:20.57 - ONGOING-ISRUNNING-062701-142.TXT - numbytes received from recv(sockfd, rxbuf, RXBUFSIZE-1, 0) = 12 
29-Jun-14  6:27:20.74 - ONGOING-ISRUNNING-062701-142.TXT - End of expect("ATP>") 
29-Jun-14  6:27:20.92 - ONGOING-ISRUNNING-062701-142.TXT - ************************************************************************* 
29-Jun-14  6:27:21.10 - ONGOING-ISRUNNING-062701-142.TXT - *** reply("exit") from "ATP>" O.K. Status = 1, 
29-Jun-14  6:27:21.27 - ONGOING-ISRUNNING-062701-142.TXT - Logged out of modem O.K. Status = 1,

However, it did not leave an "ONGOING..." text file behind, which is what I was using to determine success or failure.

In reply to a post by Bald_Eagle1:

Also, this seems an 'unusual' time for HG612_stats.exe to start:-

28-Jun-14 3:17:40.10 - ONGOING-ISRUNNING-031739-849.TXT - Start of [HG612_stats.exe]

Do you have 'other' things running around that time in the morning that could be slowing the PC down & delaying the start of HG612_stats.exe?

I don't think so - the PC woke up in the middle of the night for some reason, which it does occasionally.

In reply to a post by Bald_Eagle1:

It might just be worth deleting the logging task & recreating it via the GUI to ensure all 'new' settings have been fully picked up.

I'll have a go at this later, thanks again for your help.

I have just noticed the format of the date in your logs:-

29-Jun-14 6:27:01.47 - ONGOING-ISRUNNING-062701-142.TXT - Start of [HG612_stats.exe] - **** Version 3.0.0.0 22/06/14 ****


It should look like this (from my connection):-

29/06/2014 6:27:00.14 - ONGOING-ISRUNNING-062700-098.TXT - Start of [HG612_stats.exe] - **** Version 3.0.0.0 22/06/14 ****

29/06/2014 6:27:03.95 - ONGOING-ISRUNNING-062700-098.TXT - Normal End of [HG612_stats.exe]


Is it always recorded with this format (29-Jun-14) or did you paste the log data into another program that might have changed the format before posting it here?

Also, is the date format correct (dd/mm/yyyy) in your actual modem_stats.log?


HG612_stats.exe uses the date format dd/mm/yyyy for certain functions & the dd-Mon-yy format might be causing an issue.


Other than you having Daily_Graphing = NO in your ini file (mine is set to YES), it is identical to mine.

We are both using W 7 64 bit so that shouldn't be an issue.
However, it does appear to take quite a long time to harvest/store the data on your setup.


Even with all the extra logging data swirched on, HG612_stats.exe always completes in less than 4 seconds at my end (except when my AV program (AVG) hogs a lot of CPU resource).
Even then, it only takes around 12 seconds or so.

(Intel i3 CPU 550 @ 3.20 GHz with 3.00 GB RAM)

In reply to a post by kasg:

I don't think so - the PC woke up in the middle of the night for some reason, which it does occasionally.

AV default virus scan - 2 a.m. Sunday?

In reply to a post by BatBoy:

In reply to a post by kasg:

I don't think so - the PC woke up in the middle of the night for some reason, which it does occasionally.

AV default virus scan - 2 a.m. Sunday?

No, it's not that, but I don't think it's relevant to the problem anyway.

In reply to a post by Bald_Eagle1:

I have just noticed the format of the date in your logs:-

29-Jun-14 6:27:01.47 - ONGOING-ISRUNNING-062701-142.TXT - Start of [HG612_stats.exe] - **** Version 3.0.0.0 22/06/14 ****


It should look like this (from my connection):-

29/06/2014 6:27:00.14 - ONGOING-ISRUNNING-062700-098.TXT - Start of [HG612_stats.exe] - **** Version 3.0.0.0 22/06/14 ****

29/06/2014 6:27:03.95 - ONGOING-ISRUNNING-062700-098.TXT - Normal End of [HG612_stats.exe]


Is it always recorded with this format (29-Jun-14) or did you paste the log data into another program that might have changed the format before posting it here?

No, that's how it is recorded, I assume it is taking it from the Windows default short date format, yours is probably different.

In reply to a post by Bald_Eagle1:

Also, is the date format correct (dd/mm/yyyy) in your actual modem_stats.log?

Yes.

Edited by kasg (Sun 29-Jun-14 13:44:41)

Quick note:


Existing users (v 2.1 upward) who choose to enable 'Receive beta updates' in the GUI will now be able to download the latest 'beta' versions of the programs directly to the Scripts folder without interrupting any running older versions of the programs (including the latest GUI).

As always, any feedback regarding the updated programs is welcomed.

Edited by deleted (Mon 21-Jul-14 19:47:43)

I've emailed you about this, but for the benefit of anyone else, it seems that Trusteer Endpoint Protection (aka Rapport) may have been interfering with the correct running of the scripts. Is anyone else here running HG612_stats AND Trusteer and having any problems (or not)?

I've been avoiding Rapport like the plague since RBS first started pushing it! (Not running the scripts since the modem software update).

Try disabling keylogger blocking http://www.trusteer.com/User-Guides/Rapport-User-Gui...

Thanks, I'll bear it in mind if I am tempted to reinstall!

In reply to a post by RobertoS:

I've been avoiding Rapport like the plague since RBS first started pushing it! <snip>.

I have left several companies that refuse to stop nagging me about using the dreck .

In reply to a post by RobertoS:

I've been avoiding Rapport like the plague since RBS first started pushing it!

I've avoided banks and card companies that push it. Don't trust such low level software not to make my system unstable itself!