Print

Please login or register.
1 Hour 1 Day 1 Week 1 Month Forever
Login with username, password and session length

[Chrysalis]

ok logs incoming

from schedule runs

Code: [Select]

09/04/2014 10:50:00.208 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
09/04/2014 10:50:00.246 - HG612_current_stats.exe was NOT running
09/04/2014 10:50:00.246 - Temp File ONGOING-ISRUNNING-105000-066.TXT was created
09/04/2014 10:50:00.371 - malloc() O.K.
09/04/2014 10:50:00.371 - Initializing Winsock
09/04/2014 10:50:00.372 - WSAStartup() SUCCESSFUL!!!
09/04/2014 10:50:00.372 - Starting getaddrinfo()
09/04/2014 10:50:00.372 - getaddrinfo() O.K.
09/04/2014 10:50:00.372 - Starting socket()
09/04/2014 10:50:00.372 - socket() O.K.
09/04/2014 10:50:00.372 - Starting connect()
09/04/2014 10:50:00.373 - connect() O.K.
09/04/2014 10:50:00.558 - About to reply(xdslcmd info --stats)
09/04/2014 10:50:00.558 - reply(xdslcmd info --stats) O.K.
09/04/2014 10:50:00.629 - get_data() O.K.
09/04/2014 10:50:00.629 - Modem in use = HG612
09/04/2014 10:50:00.629 - About to determine the xDSL mode
09/04/2014 10:50:00.629 - About to parse_stats()
09/04/2014 10:50:00.635 - parse_stats completed
09/04/2014 10:50:00.654 - VDSL2 mode detected so about to reply(xdslcmd info --pbParams)
09/04/2014 10:50:00.655 - reply(xdslcmd info --pbParams) O.K.
09/04/2014 10:50:00.725 - get_data() O.K.
09/04/2014 10:50:00.725 - About to parse_pbParams_data()
09/04/2014 10:50:00.786 - parse_pbParams_data() completed
09/04/2014 10:50:00.805 - reply(xdslcmd --version) O.K.
09/04/2014 10:50:00.844 - get_data() O.K.
09/04/2014 10:50:00.857 - parse_version() completed
09/04/2014 10:50:00.857 - About to exit Busybox
09/04/2014 10:50:00.857 - Busybox exited
09/04/2014 10:50:00.860 - About to exit ATP
09/04/2014 10:50:00.860 - ATP exited
09/04/2014 10:50:00.860 - Logged out of modem O.K.
09/04/2014 10:50:00.871 - Current data harvested at 1397037000 seconds past the epoch
09/04/2014 10:50:00.871 - About to get_log_data()
09/04/2014 10:50:00.871 - Previous log stats date/time = 1397036940 seconds past the epoch
09/04/2014 10:50:00.871 - time_difference = 1 minutes
09/04/2014 10:50:00.871 - About to calc_ES_delta()
09/04/2014 10:50:00.872 - About to calc_RSCorr_delta()
09/04/2014 10:50:00.872 - ds_RSCorr_THIS_PERIOD = 0
09/04/2014 10:50:00.872 - us_RSCorr_THIS_PERIOD = 3
09/04/2014 10:50:00.872 - About to calc_RSUnCorr_delta()
09/04/2014 10:50:00.872 - About to calc_RS_delta()
09/04/2014 10:50:00.873 - About to calc_OHF_delta()
09/04/2014 10:50:00.873 - About to calc_OHFErr_delta()
09/04/2014 10:50:00.873 - About to calc_HEC_delta()
09/04/2014 10:50:00.873 - About to calc_CRC_delta()
09/04/2014 10:50:00.873 - About to calc_FEC_delta()
09/04/2014 10:50:00.874 - About to calc_Bitswap_delta()
09/04/2014 10:50:00.874 - Appending data to F:\Drivers\HG612\HG612_Modem_Stats_Programs-r2.0\Ongoing_Stats\modem_stats.log
09/04/2014 10:50:00.874 - Closing MSLOGFILE.log
09/04/2014 10:50:00.874 - END of 1 minute sampling
09/04/2014 10:50:00.886 - About to close(sockfd)
09/04/2014 10:50:00.886 - About to freeadddrinfo(res)
09/04/2014 10:50:00.886 - About to WSACleanup()
09/04/2014 10:50:00.907 - About to free(txbuf)
09/04/2014 10:50:00.925 - About to free(rxbuf)
09/04/2014 10:50:00.942 - Current  sync speeds are     DS 65527 kbps      US 20000 kbps, AS = 3433864
09/04/2014 10:50:00.942 - Previous sync speeds were    DS 65527 kbps      US 20000 kbps, AS = 3433804
09/04/2014 10:50:00.952 - Sync speeds have NOT changed since the previous data harvest
09/04/2014 10:50:00.961 - ONGOING-ISRUNNING-105000-066.TXT DELETED
*********************************************************************************************************************
09/04/2014 10:50:00.970 - Scheduled Current_Stats logging is switched ON via the ini file
09/04/2014 10:50:00.970 - Current_Stats_Datum    = 07
09/04/2014 10:50:00.970 - Current_Stats_Interval = 24
09/04/2014 10:50:00.970 - (Time in Hours - Datum) Modulus Interval       i.e. (10 - 7) % 24 = 3
09/04/2014 10:50:00.970 - (Time in Minutes) = 50
09/04/2014 10:50:00.979 - Modulus & minutes *BOTH* have to be zero, so scheduled snapshot logging is NOT due yet
*********************************************************************************************************************
09/04/2014 10:50:00.988 - Scheduled Daily_Graphing is NOT switched on via the ini file
*********************************************************************************************************************
09/04/2014 10:50:00.996 - End of HG612_stats.exe program, EXIT error code = 0
09/04/2014 10:50:00.996 - End of HG612_stats.exe program, closing ERROR.LOG


09/04/2014 10:51:00.261 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
09/04/2014 10:51:00.300 - HG612_current_stats.exe was NOT running
09/04/2014 10:51:00.300 - Temp File ONGOING-ISRUNNING-105100-098.TXT was created
09/04/2014 10:51:00.425 - malloc() O.K.
09/04/2014 10:51:00.425 - Initializing Winsock
09/04/2014 10:51:00.426 - WSAStartup() SUCCESSFUL!!!
09/04/2014 10:51:00.426 - Starting getaddrinfo()
09/04/2014 10:51:00.426 - getaddrinfo() O.K.
09/04/2014 10:51:00.426 - Starting socket()
09/04/2014 10:51:00.426 - socket() O.K.
09/04/2014 10:51:00.426 - Starting connect()
09/04/2014 10:51:00.427 - connect() O.K.
09/04/2014 10:51:00.643 - About to reply(xdslcmd info --stats)
09/04/2014 10:51:00.643 - reply(xdslcmd info --stats) O.K.
09/04/2014 10:51:00.708 - get_data() O.K.
09/04/2014 10:51:00.708 - Modem in use = HG612
09/04/2014 10:51:00.708 - About to determine the xDSL mode
09/04/2014 10:51:00.708 - About to parse_stats()
09/04/2014 10:51:00.712 - parse_stats completed
09/04/2014 10:51:00.724 - VDSL2 mode detected so about to reply(xdslcmd info --pbParams)
09/04/2014 10:51:00.724 - reply(xdslcmd info --pbParams) O.K.
09/04/2014 10:51:00.770 - get_data() O.K.
09/04/2014 10:51:00.770 - About to parse_pbParams_data()
09/04/2014 10:51:00.809 - parse_pbParams_data() completed
09/04/2014 10:51:00.820 - reply(xdslcmd --version) O.K.
09/04/2014 10:51:00.844 - get_data() O.K.
09/04/2014 10:51:00.856 - parse_version() completed
09/04/2014 10:51:00.856 - About to exit Busybox
09/04/2014 10:51:00.856 - Busybox exited
09/04/2014 10:51:00.859 - About to exit ATP
09/04/2014 10:51:00.859 - ATP exited
09/04/2014 10:51:00.859 - Logged out of modem O.K.
09/04/2014 10:51:00.871 - Current data harvested at 1397037060 seconds past the epoch
09/04/2014 10:51:00.871 - About to get_log_data()
09/04/2014 10:51:00.871 - Previous log stats date/time = 1397037000 seconds past the epoch
09/04/2014 10:51:00.871 - time_difference = 1 minutes
09/04/2014 10:51:00.872 - About to calc_ES_delta()
09/04/2014 10:51:00.872 - About to calc_RSCorr_delta()
09/04/2014 10:51:00.872 - ds_RSCorr_THIS_PERIOD = 0
09/04/2014 10:51:00.872 - us_RSCorr_THIS_PERIOD = 0
09/04/2014 10:51:00.872 - About to calc_RSUnCorr_delta()
09/04/2014 10:51:00.872 - About to calc_RS_delta()
09/04/2014 10:51:00.873 - About to calc_OHF_delta()
09/04/2014 10:51:00.873 - About to calc_OHFErr_delta()
09/04/2014 10:51:00.873 - About to calc_HEC_delta()
09/04/2014 10:51:00.873 - About to calc_CRC_delta()
09/04/2014 10:51:00.874 - About to calc_FEC_delta()
09/04/2014 10:51:00.874 - About to calc_Bitswap_delta()
09/04/2014 10:51:00.874 - Appending data to F:\Drivers\HG612\HG612_Modem_Stats_Programs-r2.0\Ongoing_Stats\modem_stats.log
09/04/2014 10:51:00.875 - Closing MSLOGFILE.log
09/04/2014 10:51:00.875 - END of 1 minute sampling
09/04/2014 10:51:00.886 - About to close(sockfd)
09/04/2014 10:51:00.887 - About to freeadddrinfo(res)
09/04/2014 10:51:00.887 - About to WSACleanup()
09/04/2014 10:51:00.910 - About to free(txbuf)
09/04/2014 10:51:00.927 - About to free(rxbuf)
09/04/2014 10:51:00.945 - Current  sync speeds are     DS 65527 kbps      US 20000 kbps, AS = 3433924
09/04/2014 10:51:00.945 - Previous sync speeds were    DS 65527 kbps      US 20000 kbps, AS = 3433864
09/04/2014 10:51:00.954 - Sync speeds have NOT changed since the previous data harvest
09/04/2014 10:51:00.963 - ONGOING-ISRUNNING-105100-098.TXT DELETED
*********************************************************************************************************************
09/04/2014 10:51:00.971 - Scheduled Current_Stats logging is switched ON via the ini file
09/04/2014 10:51:00.971 - Current_Stats_Datum    = 07
09/04/2014 10:51:00.971 - Current_Stats_Interval = 24
09/04/2014 10:51:00.971 - (Time in Hours - Datum) Modulus Interval       i.e. (10 - 7) % 24 = 3
09/04/2014 10:51:00.971 - (Time in Minutes) = 51
09/04/2014 10:51:00.980 - Modulus & minutes *BOTH* have to be zero, so scheduled snapshot logging is NOT due yet
*********************************************************************************************************************
09/04/2014 10:51:00.988 - Scheduled Daily_Graphing is NOT switched on via the ini file
*********************************************************************************************************************
09/04/2014 10:51:00.997 - End of HG612_stats.exe program, EXIT error code = 0
09/04/2014 10:51:00.997 - End of HG612_stats.exe program, closing ERROR.LOG


09/04/2014 10:52:00.191 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
09/04/2014 10:52:00.227 - HG612_current_stats.exe was NOT running
09/04/2014 10:52:00.227 - Temp File ONGOING-ISRUNNING-105200-053.TXT was created
09/04/2014 10:52:00.342 - malloc() O.K.
09/04/2014 10:52:00.342 - Initializing Winsock
09/04/2014 10:52:00.343 - WSAStartup() SUCCESSFUL!!!
09/04/2014 10:52:00.343 - Starting getaddrinfo()
09/04/2014 10:52:00.343 - getaddrinfo() O.K.
09/04/2014 10:52:00.343 - Starting socket()
09/04/2014 10:52:00.343 - socket() O.K.
09/04/2014 10:52:00.343 - Starting connect()
09/04/2014 10:52:00.345 - connect() O.K.
09/04/2014 10:52:00.536 - About to reply(xdslcmd info --stats)
09/04/2014 10:52:00.536 - reply(xdslcmd info --stats) O.K.
09/04/2014 10:52:00.642 - get_data() O.K.
09/04/2014 10:52:00.642 - Modem in use = HG612
09/04/2014 10:52:00.642 - About to determine the xDSL mode
09/04/2014 10:52:00.642 - About to parse_stats()
09/04/2014 10:52:00.647 - parse_stats completed
09/04/2014 10:52:00.664 - VDSL2 mode detected so about to reply(xdslcmd info --pbParams)
09/04/2014 10:52:00.664 - reply(xdslcmd info --pbParams) O.K.
09/04/2014 10:52:00.727 - get_data() O.K.
09/04/2014 10:52:00.727 - About to parse_pbParams_data()
09/04/2014 10:52:00.782 - parse_pbParams_data() completed
09/04/2014 10:52:00.801 - reply(xdslcmd --version) O.K.
09/04/2014 10:52:00.835 - get_data() O.K.
09/04/2014 10:52:00.852 - parse_version() completed
09/04/2014 10:52:00.852 - About to exit Busybox
09/04/2014 10:52:00.852 - Busybox exited
09/04/2014 10:52:00.856 - About to exit ATP
09/04/2014 10:52:00.856 - ATP exited
09/04/2014 10:52:00.856 - Logged out of modem O.K.
09/04/2014 10:52:00.874 - Current data harvested at 1397037120 seconds past the epoch
09/04/2014 10:52:00.874 - About to get_log_data()
09/04/2014 10:52:00.874 - Previous log stats date/time = 1397037060 seconds past the epoch
09/04/2014 10:52:00.874 - time_difference = 1 minutes
09/04/2014 10:52:00.875 - About to calc_ES_delta()
09/04/2014 10:52:00.875 - About to calc_RSCorr_delta()
09/04/2014 10:52:00.875 - ds_RSCorr_THIS_PERIOD = 0
09/04/2014 10:52:00.875 - us_RSCorr_THIS_PERIOD = 0
09/04/2014 10:52:00.875 - About to calc_RSUnCorr_delta()
09/04/2014 10:52:00.876 - About to calc_RS_delta()
09/04/2014 10:52:00.876 - About to calc_OHF_delta()
09/04/2014 10:52:00.877 - About to calc_OHFErr_delta()
09/04/2014 10:52:00.877 - About to calc_HEC_delta()
09/04/2014 10:52:00.877 - About to calc_CRC_delta()
09/04/2014 10:52:00.878 - About to calc_FEC_delta()
09/04/2014 10:52:00.878 - About to calc_Bitswap_delta()
09/04/2014 10:52:00.878 - Appending data to F:\Drivers\HG612\HG612_Modem_Stats_Programs-r2.0\Ongoing_Stats\modem_stats.log
09/04/2014 10:52:00.879 - Closing MSLOGFILE.log
09/04/2014 10:52:00.879 - END of 1 minute sampling
09/04/2014 10:52:00.896 - About to close(sockfd)
09/04/2014 10:52:00.896 - About to freeadddrinfo(res)
09/04/2014 10:52:00.896 - About to WSACleanup()
09/04/2014 10:52:00.919 - About to free(txbuf)
09/04/2014 10:52:00.935 - About to free(rxbuf)
09/04/2014 10:52:00.952 - Current  sync speeds are     DS 65527 kbps      US 20000 kbps, AS = 3433984
09/04/2014 10:52:00.952 - Previous sync speeds were    DS 65527 kbps      US 20000 kbps, AS = 3433924
09/04/2014 10:52:00.960 - Sync speeds have NOT changed since the previous data harvest
09/04/2014 10:52:00.968 - ONGOING-ISRUNNING-105200-053.TXT DELETED
*********************************************************************************************************************
09/04/2014 10:52:00.977 - Scheduled Current_Stats logging is switched ON via the ini file
09/04/2014 10:52:00.977 - Current_Stats_Datum    = 07
09/04/2014 10:52:00.977 - Current_Stats_Interval = 24
09/04/2014 10:52:00.977 - (Time in Hours - Datum) Modulus Interval       i.e. (10 - 7) % 24 = 3
09/04/2014 10:52:00.977 - (Time in Minutes) = 52
09/04/2014 10:52:00.985 - Modulus & minutes *BOTH* have to be zero, so scheduled snapshot logging is NOT due yet
*********************************************************************************************************************
09/04/2014 10:52:00.994 - Scheduled Daily_Graphing is NOT switched on via the ini file
*********************************************************************************************************************
09/04/2014 10:52:01.002 - End of HG612_stats.exe program, EXIT error code = 0
09/04/2014 10:52:01.002 - End of HG612_stats.exe program, closing ERROR.LOG


09/04/2014 10:53:00.224 - [ IN HG612_stats.EXE ] - Start of 1 minute sampling - delayed by user setting of 0 seconds
09/04/2014 10:53:00.261 - HG612_current_stats.exe was NOT running
09/04/2014 10:53:00.261 - Temp File ONGOING-ISRUNNING-105300-083.TXT was created
09/04/2014 10:53:00.377 - malloc() O.K.
09/04/2014 10:53:00.377 - Initializing Winsock
09/04/2014 10:53:00.377 - WSAStartup() SUCCESSFUL!!!
09/04/2014 10:53:00.377 - Starting getaddrinfo()
09/04/2014 10:53:00.377 - getaddrinfo() O.K.
09/04/2014 10:53:00.377 - Starting socket()
09/04/2014 10:53:00.378 - socket() O.K.
09/04/2014 10:53:00.378 - Starting connect()
09/04/2014 10:53:00.378 - connect() O.K.
09/04/2014 10:53:00.575 - About to reply(xdslcmd info --stats)
09/04/2014 10:53:00.575 - reply(xdslcmd info --stats) O.K.
09/04/2014 10:53:00.656 - get_data() O.K.
09/04/2014 10:53:00.656 - Modem in use = HG612
09/04/2014 10:53:00.656 - About to determine the xDSL mode
09/04/2014 10:53:00.656 - About to parse_stats()
09/04/2014 10:53:00.659 - parse_stats completed
09/04/2014 10:53:00.668 - VDSL2 mode detected so about to reply(xdslcmd info --pbParams)
09/04/2014 10:53:00.668 - reply(xdslcmd info --pbParams) O.K.
09/04/2014 10:53:00.714 - get_data() O.K.
09/04/2014 10:53:00.714 - About to parse_pbParams_data()
09/04/2014 10:53:00.745 - parse_pbParams_data() completed
09/04/2014 10:53:00.754 - reply(xdslcmd --version) O.K.
09/04/2014 10:53:00.779 - get_data() O.K.
09/04/2014 10:53:00.788 - parse_version() completed
09/04/2014 10:53:00.788 - About to exit Busybox
09/04/2014 10:53:00.788 - Busybox exited
09/04/2014 10:53:00.791 - About to exit ATP
09/04/2014 10:53:00.791 - ATP exited
09/04/2014 10:53:00.791 - Logged out of modem O.K.
09/04/2014 10:53:00.801 - Current data harvested at 1397037180 seconds past the epoch
09/04/2014 10:53:00.801 - About to get_log_data()
09/04/2014 10:53:00.801 - Previous log stats date/time = 1397037120 seconds past the epoch
09/04/2014 10:53:00.801 - time_difference = 1 minutes
09/04/2014 10:53:00.802 - About to calc_ES_delta()
09/04/2014 10:53:00.802 - About to calc_RSCorr_delta()
09/04/2014 10:53:00.802 - ds_RSCorr_THIS_PERIOD = 0
09/04/2014 10:53:00.802 - us_RSCorr_THIS_PERIOD = 0
09/04/2014 10:53:00.802 - About to calc_RSUnCorr_delta()
09/04/2014 10:53:00.802 - About to calc_RS_delta()
09/04/2014 10:53:00.802 - About to calc_OHF_delta()
09/04/2014 10:53:00.803 - About to calc_OHFErr_delta()
09/04/2014 10:53:00.803 - About to calc_HEC_delta()
09/04/2014 10:53:00.803 - About to calc_CRC_delta()
09/04/2014 10:53:00.803 - About to calc_FEC_delta()
09/04/2014 10:53:00.804 - About to calc_Bitswap_delta()
09/04/2014 10:53:00.804 - Appending data to F:\Drivers\HG612\HG612_Modem_Stats_Programs-r2.0\Ongoing_Stats\modem_stats.log
09/04/2014 10:53:00.804 - Closing MSLOGFILE.log
09/04/2014 10:53:00.804 - END of 1 minute sampling
09/04/2014 10:53:00.813 - About to close(sockfd)
09/04/2014 10:53:00.813 - About to freeadddrinfo(res)
09/04/2014 10:53:00.813 - About to WSACleanup()
09/04/2014 10:53:00.829 - About to free(txbuf)
09/04/2014 10:53:00.846 - About to free(rxbuf)
09/04/2014 10:53:00.863 - Current  sync speeds are     DS 65527 kbps      US 20000 kbps, AS = 3434044
09/04/2014 10:53:00.863 - Previous sync speeds were    DS 65527 kbps      US 20000 kbps, AS = 3433984
09/04/2014 10:53:00.871 - Sync speeds have NOT changed since the previous data harvest
09/04/2014 10:53:00.880 - ONGOING-ISRUNNING-105300-083.TXT DELETED
*********************************************************************************************************************
09/04/2014 10:53:00.888 - Scheduled Current_Stats logging is switched ON via the ini file
09/04/2014 10:53:00.888 - Current_Stats_Datum    = 07
09/04/2014 10:53:00.888 - Current_Stats_Interval = 24
09/04/2014 10:53:00.888 - (Time in Hours - Datum) Modulus Interval       i.e. (10 - 7) % 24 = 3
09/04/2014 10:53:00.888 - (Time in Minutes) = 53
09/04/2014 10:53:00.896 - Modulus & minutes *BOTH* have to be zero, so scheduled snapshot logging is NOT due yet
*********************************************************************************************************************
09/04/2014 10:53:00.904 - Scheduled Daily_Graphing is NOT switched on via the ini file
*********************************************************************************************************************
09/04/2014 10:53:00.912 - End of HG612_stats.exe program, EXIT error code = 0
09/04/2014 10:53:00.912 - End of HG612_stats.exe program, closing ERROR.LOG


[Chrysalis]

Code: [Select]

09/04/2014 10:50:00.08 - ONGOING-ISRUNNING-105000-066.TXT - Start of [HG612_stats.exe]
09/04/2014 10:50:00.17 - ONGOING-ISRUNNING-105000-066.TXT - Immediately after ini_parse() - About to determine the OS version. ERROR.LOG status = 0,
09/04/2014 10:50:00.18 - ONGOING-ISRUNNING-105000-066.TXT - *** Windows version = [6.1.7601] ***
09/04/2014 10:50:00.19 - ONGOING-ISRUNNING-105000-066.TXT - In [HG612_stats.exe] - After obtaining the OS version. ERROR.LOG status = 0,
09/04/2014 10:50:00.20 - ONGOING-ISRUNNING-105000-066.TXT - ERROR.LOG successfully opened. Status = 1,
09/04/2014 10:50:00.25 - ONGOING-ISRUNNING-105000-066.TXT - ONGOING-ISRUNNING-105000-066.TXT created. Status = 1,
09/04/2014 10:50:00.36 - ONGOING-ISRUNNING-105000-066.TXT - There are 1 instances of HG612_stats.exe running. Status = 1,
09/04/2014 10:50:00.36 - ONGOING-ISRUNNING-105000-066.TXT - ** Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1,
09/04/2014 10:50:00.42 - ONGOING-ISRUNNING-105000-066.TXT - **** In expect("gin"),rxbuf includes Welcome Visiting Huawei, so COULD be a HG612 modem.
09/04/2014 10:50:00.43 - ONGOING-ISRUNNING-105000-066.TXT - **** expect("gin:") O.K. Status = 1.
09/04/2014 10:50:00.55 - ONGOING-ISRUNNING-105000-066.TXT - **** Reaching this point means logging in to the modem was successful. Status = 1,
09/04/2014 10:50:00.58 - ONGOING-ISRUNNING-105000-066.TXT - In get_data(#10). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1023)
09/04/2014 10:50:00.60 - ONGOING-ISRUNNING-105000-066.TXT - In get_data(#11). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1048)
09/04/2014 10:50:00.62 - ONGOING-ISRUNNING-105000-066.TXT - **** - get_data() for --stats O.K..Status = 1, times in get_data() loop was 11
09/04/2014 10:50:00.65 - ONGOING-ISRUNNING-105000-066.TXT - After xdslcmd info --stats(), ERROR.LOG status = 1
09/04/2014 10:50:00.68 - ONGOING-ISRUNNING-105000-066.TXT - In get_data(#19). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 119)
09/04/2014 10:50:00.70 - ONGOING-ISRUNNING-105000-066.TXT - In get_data(#20). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 929)
09/04/2014 10:50:00.72 - ONGOING-ISRUNNING-105000-066.TXT - **** - get_data() for --pbParams O.K..Status = 1, times in get_data() loop was 20
09/04/2014 10:50:00.78 - ONGOING-ISRUNNING-105000-066.TXT - After xdslcmd info --pbParams(), ERROR.LOG status = 1
09/04/2014 10:50:00.80 - ONGOING-ISRUNNING-105000-066.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 20
09/04/2014 10:50:00.82 - ONGOING-ISRUNNING-105000-066.TXT - In get_data(#01). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 109)
09/04/2014 10:50:00.84 - ONGOING-ISRUNNING-105000-066.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 01
09/04/2014 10:50:00.85 - ONGOING-ISRUNNING-105000-066.TXT - After xdslcmd --version(), ERROR.LOG status = 1
09/04/2014 10:50:00.86 - ONGOING-ISRUNNING-105000-066.TXT - Logged out of modem O.K. Status = 1,
09/04/2014 10:50:00.88 - ONGOING-ISRUNNING-105000-066.TXT - Now in exit. Status = 1,
09/04/2014 10:50:00.89 - ONGOING-ISRUNNING-105000-066.TXT - *** WSACleanup has completed.
09/04/2014 10:50:00.90 - ONGOING-ISRUNNING-105000-066.TXT - *** About to free(txbuf).
09/04/2014 10:50:00.91 - ONGOING-ISRUNNING-105000-066.TXT - *** txbuf has been freed.
09/04/2014 10:50:00.92 - ONGOING-ISRUNNING-105000-066.TXT - *** About to free(rxbuf).
09/04/2014 10:50:00.93 - ONGOING-ISRUNNING-105000-066.TXT - *** rxbuf has been freed.
09/04/2014 10:50:00.94 - ONGOING-ISRUNNING-105000-066.TXT - *** Now checking for resyncs - There are 1 instances of HG612_stats.exe running.
09/04/2014 10:50:00.95 - ONGOING-ISRUNNING-105000-066.TXT - *** Resync *NOT* detected.
09/04/2014 10:50:00.96 - ONGOING-ISRUNNING-105000-066.TXT - ONGOING-ISRUNNING-105000-066.TXT DELETED - Status = 1,
09/04/2014 10:50:00.96 - ONGOING-ISRUNNING-105000-066.TXT - *** Scheduled Current_Stats logging is switched ON.
09/04/2014 10:50:00.97 - ONGOING-ISRUNNING-105000-066.TXT - *** Scheduled snapshot logging is NOT due yet.
09/04/2014 10:50:00.98 - ONGOING-ISRUNNING-105000-066.TXT - *** Scheduled Daily graphing is NOT switched on.
09/04/2014 10:50:00.99 - ONGOING-ISRUNNING-105000-066.TXT - End of [HG612_stats.exe], Closing ERROR.LOG. Status = 1,
09/04/2014 10:50:01.00 - ONGOING-ISRUNNING-105000-066.TXT - ERROR.LOG successfully closed. Status = 0,
09/04/2014 10:50:01.01 - ONGOING-ISRUNNING-105000-066.TXT - Normal End of [HG612_stats.exe]



09/04/2014 10:51:00.12 - ONGOING-ISRUNNING-105100-098.TXT - Start of [HG612_stats.exe]
09/04/2014 10:51:00.22 - ONGOING-ISRUNNING-105100-098.TXT - Immediately after ini_parse() - About to determine the OS version. ERROR.LOG status = 0,
09/04/2014 10:51:00.23 - ONGOING-ISRUNNING-105100-098.TXT - *** Windows version = [6.1.7601] ***
09/04/2014 10:51:00.24 - ONGOING-ISRUNNING-105100-098.TXT - In [HG612_stats.exe] - After obtaining the OS version. ERROR.LOG status = 0,
09/04/2014 10:51:00.26 - ONGOING-ISRUNNING-105100-098.TXT - ERROR.LOG successfully opened. Status = 1,
09/04/2014 10:51:00.30 - ONGOING-ISRUNNING-105100-098.TXT - ONGOING-ISRUNNING-105100-098.TXT created. Status = 1,
09/04/2014 10:51:00.41 - ONGOING-ISRUNNING-105100-098.TXT - There are 1 instances of HG612_stats.exe running. Status = 1,
09/04/2014 10:51:00.42 - ONGOING-ISRUNNING-105100-098.TXT - ** Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1,
09/04/2014 10:51:00.48 - ONGOING-ISRUNNING-105100-098.TXT - **** In expect("gin"),rxbuf includes Welcome Visiting Huawei, so COULD be a HG612 modem.
09/04/2014 10:51:00.52 - ONGOING-ISRUNNING-105100-098.TXT - **** expect("gin:") O.K. Status = 1.
09/04/2014 10:51:00.64 - ONGOING-ISRUNNING-105100-098.TXT - **** Reaching this point means logging in to the modem was successful. Status = 1,
09/04/2014 10:51:00.67 - ONGOING-ISRUNNING-105100-098.TXT - In get_data(#10). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1023)
09/04/2014 10:51:00.69 - ONGOING-ISRUNNING-105100-098.TXT - In get_data(#11). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1048)
09/04/2014 10:51:00.70 - ONGOING-ISRUNNING-105100-098.TXT - **** - get_data() for --stats O.K..Status = 1, times in get_data() loop was 11
09/04/2014 10:51:00.72 - ONGOING-ISRUNNING-105100-098.TXT - After xdslcmd info --stats(), ERROR.LOG status = 1
09/04/2014 10:51:00.74 - ONGOING-ISRUNNING-105100-098.TXT - In get_data(#06). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1023)
09/04/2014 10:51:00.75 - ONGOING-ISRUNNING-105100-098.TXT - In get_data(#07). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 41)
09/04/2014 10:51:00.76 - ONGOING-ISRUNNING-105100-098.TXT - **** - get_data() for --pbParams O.K..Status = 1, times in get_data() loop was 07
09/04/2014 10:51:00.80 - ONGOING-ISRUNNING-105100-098.TXT - After xdslcmd info --pbParams(), ERROR.LOG status = 1
09/04/2014 10:51:00.81 - ONGOING-ISRUNNING-105100-098.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 07
09/04/2014 10:51:00.83 - ONGOING-ISRUNNING-105100-098.TXT - In get_data(#01). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 109)
09/04/2014 10:51:00.84 - ONGOING-ISRUNNING-105100-098.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 01
09/04/2014 10:51:00.85 - ONGOING-ISRUNNING-105100-098.TXT - After xdslcmd --version(), ERROR.LOG status = 1
09/04/2014 10:51:00.86 - ONGOING-ISRUNNING-105100-098.TXT - Logged out of modem O.K. Status = 1,
09/04/2014 10:51:00.88 - ONGOING-ISRUNNING-105100-098.TXT - Now in exit. Status = 1,
09/04/2014 10:51:00.89 - ONGOING-ISRUNNING-105100-098.TXT - *** WSACleanup has completed.
09/04/2014 10:51:00.90 - ONGOING-ISRUNNING-105100-098.TXT - *** About to free(txbuf).
09/04/2014 10:51:00.91 - ONGOING-ISRUNNING-105100-098.TXT - *** txbuf has been freed.
09/04/2014 10:51:00.92 - ONGOING-ISRUNNING-105100-098.TXT - *** About to free(rxbuf).
09/04/2014 10:51:00.93 - ONGOING-ISRUNNING-105100-098.TXT - *** rxbuf has been freed.
09/04/2014 10:51:00.94 - ONGOING-ISRUNNING-105100-098.TXT - *** Now checking for resyncs - There are 1 instances of HG612_stats.exe running.
09/04/2014 10:51:00.95 - ONGOING-ISRUNNING-105100-098.TXT - *** Resync *NOT* detected.
09/04/2014 10:51:00.96 - ONGOING-ISRUNNING-105100-098.TXT - ONGOING-ISRUNNING-105100-098.TXT DELETED - Status = 1,
09/04/2014 10:51:00.97 - ONGOING-ISRUNNING-105100-098.TXT - *** Scheduled Current_Stats logging is switched ON.
09/04/2014 10:51:00.97 - ONGOING-ISRUNNING-105100-098.TXT - *** Scheduled snapshot logging is NOT due yet.
09/04/2014 10:51:00.98 - ONGOING-ISRUNNING-105100-098.TXT - *** Scheduled Daily graphing is NOT switched on.
09/04/2014 10:51:00.99 - ONGOING-ISRUNNING-105100-098.TXT - End of [HG612_stats.exe], Closing ERROR.LOG. Status = 1,
09/04/2014 10:51:01.00 - ONGOING-ISRUNNING-105100-098.TXT - ERROR.LOG successfully closed. Status = 0,
09/04/2014 10:51:01.01 - ONGOING-ISRUNNING-105100-098.TXT - Normal End of [HG612_stats.exe]




from batch file - attached due to post size limit


in 5 mins will post some more normal

[Chrysalis]

continued

Code: [Select]



09/04/2014 10:52:00.07 - ONGOING-ISRUNNING-105200-053.TXT - Start of [HG612_stats.exe]
09/04/2014 10:52:00.15 - ONGOING-ISRUNNING-105200-053.TXT - Immediately after ini_parse() - About to determine the OS version. ERROR.LOG status = 0,
09/04/2014 10:52:00.16 - ONGOING-ISRUNNING-105200-053.TXT - *** Windows version = [6.1.7601] ***
09/04/2014 10:52:00.17 - ONGOING-ISRUNNING-105200-053.TXT - In [HG612_stats.exe] - After obtaining the OS version. ERROR.LOG status = 0,
09/04/2014 10:52:00.19 - ONGOING-ISRUNNING-105200-053.TXT - ERROR.LOG successfully opened. Status = 1,
09/04/2014 10:52:00.23 - ONGOING-ISRUNNING-105200-053.TXT - ONGOING-ISRUNNING-105200-053.TXT created. Status = 1,
09/04/2014 10:52:00.33 - ONGOING-ISRUNNING-105200-053.TXT - There are 1 instances of HG612_stats.exe running. Status = 1,
09/04/2014 10:52:00.34 - ONGOING-ISRUNNING-105200-053.TXT - ** Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1,
09/04/2014 10:52:00.40 - ONGOING-ISRUNNING-105200-053.TXT - **** In expect("gin"),rxbuf includes Welcome Visiting Huawei, so COULD be a HG612 modem.
09/04/2014 10:52:00.41 - ONGOING-ISRUNNING-105200-053.TXT - **** expect("gin:") O.K. Status = 1.
09/04/2014 10:52:00.53 - ONGOING-ISRUNNING-105200-053.TXT - **** Reaching this point means logging in to the modem was successful. Status = 1,
09/04/2014 10:52:00.55 - ONGOING-ISRUNNING-105200-053.TXT - In get_data(#06). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 17)
09/04/2014 10:52:00.58 - ONGOING-ISRUNNING-105200-053.TXT - In get_data(#07). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 996)
09/04/2014 10:52:00.60 - ONGOING-ISRUNNING-105200-053.TXT - In get_data(#08). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1048)
09/04/2014 10:52:00.62 - ONGOING-ISRUNNING-105200-053.TXT - In get_data(#09). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 14)
09/04/2014 10:52:00.64 - ONGOING-ISRUNNING-105200-053.TXT - **** - get_data() for --stats O.K..Status = 1, times in get_data() loop was 09
09/04/2014 10:52:00.66 - ONGOING-ISRUNNING-105200-053.TXT - After xdslcmd info --stats(), ERROR.LOG status = 1
09/04/2014 10:52:00.69 - ONGOING-ISRUNNING-105200-053.TXT - In get_data(#10). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1023)
09/04/2014 10:52:00.70 - ONGOING-ISRUNNING-105200-053.TXT - In get_data(#11). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 37)
09/04/2014 10:52:00.72 - ONGOING-ISRUNNING-105200-053.TXT - **** - get_data() for --pbParams O.K..Status = 1, times in get_data() loop was 11
09/04/2014 10:52:00.78 - ONGOING-ISRUNNING-105200-053.TXT - After xdslcmd info --pbParams(), ERROR.LOG status = 1
09/04/2014 10:52:00.79 - ONGOING-ISRUNNING-105200-053.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 11
09/04/2014 10:52:00.81 - ONGOING-ISRUNNING-105200-053.TXT - In get_data(#01). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 109)
09/04/2014 10:52:00.83 - ONGOING-ISRUNNING-105200-053.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 01
09/04/2014 10:52:00.85 - ONGOING-ISRUNNING-105200-053.TXT - After xdslcmd --version(), ERROR.LOG status = 1
09/04/2014 10:52:00.87 - ONGOING-ISRUNNING-105200-053.TXT - Logged out of modem O.K. Status = 1,
09/04/2014 10:52:00.89 - ONGOING-ISRUNNING-105200-053.TXT - Now in exit. Status = 1,
09/04/2014 10:52:00.91 - ONGOING-ISRUNNING-105200-053.TXT - *** WSACleanup has completed.
09/04/2014 10:52:00.91 - ONGOING-ISRUNNING-105200-053.TXT - *** About to free(txbuf).
09/04/2014 10:52:00.92 - ONGOING-ISRUNNING-105200-053.TXT - *** txbuf has been freed.
09/04/2014 10:52:00.93 - ONGOING-ISRUNNING-105200-053.TXT - *** About to free(rxbuf).
09/04/2014 10:52:00.94 - ONGOING-ISRUNNING-105200-053.TXT - *** rxbuf has been freed.
09/04/2014 10:52:00.95 - ONGOING-ISRUNNING-105200-053.TXT - *** Now checking for resyncs - There are 1 instances of HG612_stats.exe running.
09/04/2014 10:52:00.95 - ONGOING-ISRUNNING-105200-053.TXT - *** Resync *NOT* detected.
09/04/2014 10:52:00.96 - ONGOING-ISRUNNING-105200-053.TXT - ONGOING-ISRUNNING-105200-053.TXT DELETED - Status = 1,
09/04/2014 10:52:00.97 - ONGOING-ISRUNNING-105200-053.TXT - *** Scheduled Current_Stats logging is switched ON.
09/04/2014 10:52:00.98 - ONGOING-ISRUNNING-105200-053.TXT - *** Scheduled snapshot logging is NOT due yet.
09/04/2014 10:52:00.99 - ONGOING-ISRUNNING-105200-053.TXT - *** Scheduled Daily graphing is NOT switched on.
09/04/2014 10:52:01.00 - ONGOING-ISRUNNING-105200-053.TXT - End of [HG612_stats.exe], Closing ERROR.LOG. Status = 1,
09/04/2014 10:52:01.01 - ONGOING-ISRUNNING-105200-053.TXT - ERROR.LOG successfully closed. Status = 0,
09/04/2014 10:52:01.02 - ONGOING-ISRUNNING-105200-053.TXT - Normal End of [HG612_stats.exe]



09/04/2014 10:53:00.10 - ONGOING-ISRUNNING-105300-083.TXT - Start of [HG612_stats.exe]
09/04/2014 10:53:00.18 - ONGOING-ISRUNNING-105300-083.TXT - Immediately after ini_parse() - About to determine the OS version. ERROR.LOG status = 0,
09/04/2014 10:53:00.19 - ONGOING-ISRUNNING-105300-083.TXT - *** Windows version = [6.1.7601] ***
09/04/2014 10:53:00.20 - ONGOING-ISRUNNING-105300-083.TXT - In [HG612_stats.exe] - After obtaining the OS version. ERROR.LOG status = 0,
09/04/2014 10:53:00.22 - ONGOING-ISRUNNING-105300-083.TXT - ERROR.LOG successfully opened. Status = 1,
09/04/2014 10:53:00.26 - ONGOING-ISRUNNING-105300-083.TXT - ONGOING-ISRUNNING-105300-083.TXT created. Status = 1,
09/04/2014 10:53:00.36 - ONGOING-ISRUNNING-105300-083.TXT - There are 1 instances of HG612_stats.exe running. Status = 1,
09/04/2014 10:53:00.37 - ONGOING-ISRUNNING-105300-083.TXT - ** Continuing as there are only 1 instances of HG612_stats.exe running **. Status = 1,
09/04/2014 10:53:00.41 - ONGOING-ISRUNNING-105300-083.TXT - **** In expect("gin"),rxbuf includes Welcome Visiting Huawei, so COULD be a HG612 modem.
09/04/2014 10:53:00.42 - ONGOING-ISRUNNING-105300-083.TXT - **** expect("gin:") O.K. Status = 1.
09/04/2014 10:53:00.57 - ONGOING-ISRUNNING-105300-083.TXT - **** Reaching this point means logging in to the modem was successful. Status = 1,
09/04/2014 10:53:00.60 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#20). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1)
09/04/2014 10:53:00.62 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#21). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1009)
09/04/2014 10:53:00.63 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#22). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 797)
09/04/2014 10:53:00.64 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#23). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 254)
09/04/2014 10:53:00.65 - ONGOING-ISRUNNING-105300-083.TXT - **** - get_data() for --stats O.K..Status = 1, times in get_data() loop was 23
09/04/2014 10:53:00.66 - ONGOING-ISRUNNING-105300-083.TXT - After xdslcmd info --stats(), ERROR.LOG status = 1
09/04/2014 10:53:00.68 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#23). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 1)
09/04/2014 10:53:00.69 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#24). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 328)
09/04/2014 10:53:00.70 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#25). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 718)
09/04/2014 10:53:00.71 - ONGOING-ISRUNNING-105300-083.TXT - **** - get_data() for --pbParams O.K..Status = 1, times in get_data() loop was 25
09/04/2014 10:53:00.74 - ONGOING-ISRUNNING-105300-083.TXT - After xdslcmd info --pbParams(), ERROR.LOG status = 1
09/04/2014 10:53:00.75 - ONGOING-ISRUNNING-105300-083.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 25
09/04/2014 10:53:00.76 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#01). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 19)
09/04/2014 10:53:00.77 - ONGOING-ISRUNNING-105300-083.TXT - In get_data(#02). *** This was included in data received:- xxxxxxxxdslcmd info --stats - (numbytes = 90)
09/04/2014 10:53:00.77 - ONGOING-ISRUNNING-105300-083.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 02
09/04/2014 10:53:00.78 - ONGOING-ISRUNNING-105300-083.TXT - After xdslcmd --version(), ERROR.LOG status = 1
09/04/2014 10:53:00.79 - ONGOING-ISRUNNING-105300-083.TXT - Logged out of modem O.K. Status = 1,
09/04/2014 10:53:00.81 - ONGOING-ISRUNNING-105300-083.TXT - Now in exit. Status = 1,
09/04/2014 10:53:00.82 - ONGOING-ISRUNNING-105300-083.TXT - *** WSACleanup has completed.
09/04/2014 10:53:00.82 - ONGOING-ISRUNNING-105300-083.TXT - *** About to free(txbuf).
09/04/2014 10:53:00.83 - ONGOING-ISRUNNING-105300-083.TXT - *** txbuf has been freed.
09/04/2014 10:53:00.84 - ONGOING-ISRUNNING-105300-083.TXT - *** About to free(rxbuf).
09/04/2014 10:53:00.85 - ONGOING-ISRUNNING-105300-083.TXT - *** rxbuf has been freed.
09/04/2014 10:53:00.86 - ONGOING-ISRUNNING-105300-083.TXT - *** Now checking for resyncs - There are 1 instances of HG612_stats.exe running.
09/04/2014 10:53:00.87 - ONGOING-ISRUNNING-105300-083.TXT - *** Resync *NOT* detected.
09/04/2014 10:53:00.87 - ONGOING-ISRUNNING-105300-083.TXT - ONGOING-ISRUNNING-105300-083.TXT DELETED - Status = 1,
09/04/2014 10:53:00.88 - ONGOING-ISRUNNING-105300-083.TXT - *** Scheduled Current_Stats logging is switched ON.
09/04/2014 10:53:00.89 - ONGOING-ISRUNNING-105300-083.TXT - *** Scheduled snapshot logging is NOT due yet.
09/04/2014 10:53:00.90 - ONGOING-ISRUNNING-105300-083.TXT - *** Scheduled Daily graphing is NOT switched on.
09/04/2014 10:53:00.91 - ONGOING-ISRUNNING-105300-083.TXT - End of [HG612_stats.exe], Closing ERROR.LOG. Status = 1,
09/04/2014 10:53:00.92 - ONGOING-ISRUNNING-105300-083.TXT - ERROR.LOG successfully closed. Status = 0,
09/04/2014 10:53:00.92 - ONGOING-ISRUNNING-105300-083.TXT - Normal End of [HG612_stats.exe]


[Chrysalis]

I will attach the next 3 mins of scheduled logs in a txt file as there is posting limits that I had to split into multiple posts on the previous.

[Bald_Eagle1]

Thanks for the logs.

It does definitely appear that your super-duper PC is attempting to obtain the stats before the modem is able to deliver them.

I have now built in a 0.5 second pause whenever retries reach 5, 10 or 15.

That should not affect slower PCs, as they have normally obtained the stats after 2 to 4 retries & it's likely that your fast PC will now be able to obtain the data with far fewer attempts.

The result of your test(s) will help me determine whether to permanently introduce such a pause or simply stick with an increased retry value.


Incidentally, do you see similar issues when running HG612_current_stats.exe, either at scheduled times or manually?


Could you please try the attached program & provide similar feedback as before?

[kitz]

BE

I dont know if this is relevant, but I would still have periods where the ongoing isrunning would suddenly rack up a few hundred files.  I didnt say anything, because on the whole it mostly worked.   
Just every so often I would manually clear out a pile of ongoing isrunning.txt files.  Because no-one else had mentioned it, I thought perhaps just me & the fact that I had a fast processor (i7).   

I didnt really want to hassle you anyhow, because since last Nov Ive been more concerned with my graphics (drivers) related problem, and then the subsequent SSD failure (probably not helped by the graphics issue causing numerous blue screens)...  that obviously kinda took priority. 
I think the only hint I may have given is that at one point I may have mentioned is that the HG612 folder had grown to nearly 10GB due to the isrunning.txt files and logs.   I cleared them all out and got it back down to circa 2.2GB

[Chrysalis]

ok it still works but I think not as good as youy expected, I attach the get data lines, but am tired so if you do need full pastes will do so tommorow.

Code: [Select]

09/04/2014 22:08:00.65 - ONGOING-ISRUNNING-220800-110.TXT - **** - get_data() for --stats O.K..Status = 1, times in get_data() loop was 26
09/04/2014 22:08:00.68 - ONGOING-ISRUNNING-220800-110.TXT - In get_data(#23). *** This was included in data received:- xdslcmd info --pbParams - (numbytes = 1)
09/04/2014 22:08:00.69 - ONGOING-ISRUNNING-220800-110.TXT - In get_data(#24). *** This was included in data received:- xdslcmd info --pbParams - (numbytes = 399)
09/04/2014 22:08:00.70 - ONGOING-ISRUNNING-220800-110.TXT - In get_data(#25). *** This was included in data received:- xdslcmd info --pbParams - (numbytes = 647)
09/04/2014 22:08:00.71 - ONGOING-ISRUNNING-220800-110.TXT - **** - get_data() for --pbParams O.K..Status = 1, times in get_data() loop was 25
09/04/2014 22:08:00.75 - ONGOING-ISRUNNING-220800-110.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 25
09/04/2014 22:08:00.76 - ONGOING-ISRUNNING-220800-110.TXT - In get_data(#01). *** This was included in data received:- xdslcmd --version - (numbytes = 19)
09/04/2014 22:08:00.77 - ONGOING-ISRUNNING-220800-110.TXT - In get_data(#02). *** This was included in data received:- xdslcmd --version - (numbytes = 90)
02


[Bald_Eagle1]

A copy of the text file resulting from running the batch file might be useful for now (HG612_stats.TXT).

[Chrysalis]

ok here

[Bald_Eagle1]

It seems that I had placed the first check on how many times thriugh the loop in the wrong place.


The attached version might sort things out to reduce the number of retries.

Again, feedback is welcomed.
Attaching a copy of the the (HG612_stats.TXT) text file resulting from running the batch file would be most useful.

 

[Chrysalis]

here

get data lines look better

Code: [Select]

1/04/2014 10:41:01.13 - ONGOING-ISRUNNING-104100-101.TXT - In get_data(#06). *** This was included in data received:- xdslcmd info --stats - (numbytes = 2048)
11/04/2014 10:41:01.15 - ONGOING-ISRUNNING-104100-101.TXT - In get_data(#07). *** This was included in data received:- xdslcmd info --stats - (numbytes = 33)
11/04/2014 10:41:01.16 - ONGOING-ISRUNNING-104100-101.TXT - **** - get_data() for --stats O.K..Status = 1, times in get_data() loop was 07
11/04/2014 10:41:01.17 - ONGOING-ISRUNNING-104100-101.TXT - After xdslcmd info --stats(), ERROR.LOG status = 1
11/04/2014 10:41:01.18 - ONGOING-ISRUNNING-104100-101.TXT - In get_data(#01). *** This was included in data received:- xdslcmd info --pbParams - (numbytes = 25)
11/04/2014 10:41:01.19 - ONGOING-ISRUNNING-104100-101.TXT - In get_data(#02). *** This was included in data received:- xdslcmd info --pbParams - (numbytes = 1044)
11/04/2014 10:41:01.20 - ONGOING-ISRUNNING-104100-101.TXT - **** - get_data() for --pbParams O.K..Status = 1, times in get_data() loop was 02
11/04/2014 10:41:01.23 - ONGOING-ISRUNNING-104100-101.TXT - After xdslcmd info --pbParams(), ERROR.LOG status = 1
11/04/2014 10:41:01.24 - ONGOING-ISRUNNING-104100-101.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 02
11/04/2014 10:41:01.25 - ONGOING-ISRUNNING-104100-101.TXT - In get_data(#01). *** This was included in data received:- xdslcmd --version - (numbytes = 109)
11/04/2014 10:41:01.26 - ONGOING-ISRUNNING-104100-101.TXT - **** - get_data() for --version O.K..Status = 1, times in get_data() loop was 01
11/04/2014 10:41:01.27 - ONGOING-ISRUNNING-104100-101.TXT - After xdslcmd --version(), ERROR.LOG status = 1
I think it be good idea to keep the retries to at least 30 preferably 100 tho so is a margin.