General Discussion
  >> Fibre Broadband


Register (or login) on our website and you will not see this ad.


Pages in this thread: 1 | 2 | (show all)   Print Thread
Standard User Bald_Eagle1
(experienced) Mon 23-Jun-14 05:53:43
Print Post

HG612 Modem Stats - Release v 3.0 for EXISTING users


[link to this post]
 
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 Bald_Eagle1 (Mon 23-Jun-14 05:55:50)

Standard User Bald_Eagle1
(experienced) Mon 23-Jun-14 23:11:04
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: Bald_Eagle1] [link to this post]
 
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 Bald_Eagle1 (Mon 23-Jun-14 23:11:24)

Standard User kasg
(fountain of knowledge) Fri 27-Jun-14 11:11:24
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: Bald_Eagle1] [link to this post]
 
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?

Kevin

plusnet Unlimited Fibre - sync approx 70000/20000 at 450m - BQM
Using OpenDNS
Domains and web hosting with TSOHOST

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


Register (or login) on our website and you will not see this ad.

Standard User R0NSKI
(fountain of knowledge) Fri 27-Jun-14 18:11:33
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: kasg] [link to this post]
 
The isrunning file is created every minute, but should also be deleted, I'll leave BE1 to look into the error.

Standard User kasg
(fountain of knowledge) Fri 27-Jun-14 18:34:46
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: R0NSKI] [link to this post]
 
Thanks - it's probably not being deleted because the process isn't finishing cleanly.

Kevin

plusnet Unlimited Fibre - sync approx 70000/20000 at 450m - BQM
Using OpenDNS
Domains and web hosting with TSOHOST
Standard User Bald_Eagle1
(experienced) Sat 28-Jun-14 10:53:27
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: kasg] [link to this post]
 
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.
Standard User kasg
(fountain of knowledge) Sat 28-Jun-14 19:13:34
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: Bald_Eagle1] [link to this post]
 
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

Kevin

plusnet Unlimited Fibre - sync 72152/19999 at 450m - BQM
Using OpenDNS
Domains and web hosting with TSOHOST

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

Standard User Bald_Eagle1
(experienced) Sat 28-Jun-14 23:32:09
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: kasg] [link to this post]
 
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
Standard User kasg
(fountain of knowledge) Sun 29-Jun-14 06:33:13
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: Bald_Eagle1] [link to this post]
 
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.

Kevin

plusnet Unlimited Fibre - sync 72152/19999 at 450m - BQM
Using OpenDNS
Domains and web hosting with TSOHOST
Standard User Bald_Eagle1
(experienced) Sun 29-Jun-14 10:21:03
Print Post

Re: HG612 Modem Stats - Release v 3.0 for EXISTING users


[re: kasg] [link to this post]
 
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)
Pages in this thread: 1 | 2 | (show all)   Print Thread

Jump to