time lost by client

[gsgriffin]

This has started to happen over the past few weeks. Customers have come in claiming that they lost time. Not just a little bit of time but lots. just this morning I think was the most clear evidence that something in fact is happening in FirstSpot that I can't explain.

Yesterday, a customer bought 10 hours of time. They used the computer for 43 minutes. I pulled up their log and saw only 1 login and for 43 minutes, yet there account showed 0 minutes left. The shortest timeframe we sell is 1 hours and they didn't even use that, according to the suer log.

Any ideas of what I'm looking for here?
_________________
Greg

[gsgriffin]

Are there any suggestions? I have people that have come in and paid for 40 hours of usage. They use there computer for 1 hours, log out, come back the next day and it is all gone. Even if they forgot to log off and the system failed to log them out automatically, there is no way they can lose 40 hour in a single day.

What am I looking for? This should be a simple calucation between UNIX time stamps for beginning and ending times, but there appear to be something greater happening at times.
_________________
Greg

[alan]

Do you have a simple reproducible test case?
_________________
~ Patronsoft Limited ~

[gsgriffin]

I wish it was reproducible. Then I could perhaps figure it out on my own. Unfortunately, it happens at completely random times and with different users on different OS and everything. I'm having to assume that it is not a user issue because of the severity of the lost time (or sudden bandwidth accumulation) that must be db related. I was hoping this has happened to someone else and that there might be something to look for with regard to the functions that record and calculate the useage for the db.
_________________
Greg

[alan]

At the very least, try to obtain the username that is having problem, and then capture the correct log file firstspot_gw_srv_[timestamp].log (should be under FirstSpot\log directory if you haven't restarted FirstSpot, otherwise go into FirstSpot\log\archive). Also, please describe the user attributes that you set for that particular username.
_________________
~ Patronsoft Limited ~

[gsgriffin]

Ok, I have had 5 people come and state that they have either lost time, exceeded (I mean WAY OVER) their download limit or even one that came with originally 6 hours, later that day had 32 hours, and then this morning had 1 hour.

I'm digging through the logs, but I'm not sure even what I'm looking for. I can find the person login and logout. Everything appears to be normal. Is there something in particular that I should be trying to find?

Most of our clients have account expiration set to duration and download quota. These are the clients having problems. Most of the machines are Win 2k or higher, though there is a win98se out there too.

I don't know what to look for and don't want to have to dump huge log files on you. What should I be comparing or looking for?
_________________
Greg

[alan]

Well, maybe you can paste portion of the log that shows a problematic user account login (and logout, if available).
_________________
~ Patronsoft Limited ~

[gsgriffin]

05/02/2008 10:28:02 ** AuthLoginODBC: Authenticate[natacha]
05/02/2008 10:28:07 ** MacGetName: SQLFetch SQL_NO_DATA[SELECT name from fsusr where reserved1 = '26610638987264';]



05/02/2008 10:39:18 ** AuthLoginODBC: Authenticate[natacha]
05/02/2008 10:39:18 ** AuthLoginODBC: Current Time [Tue Feb 05 10:39:18 2008], EDate [N/A]
05/02/2008 10:39:18 ** InsertEntry(2392): Add user mac(00-00-E8-EC-34-17) ,timeleft (950) ,User Upload Limit(100KB) ,Download Limit(100KB) ,Data transfer D/L Count On, Accumulated[0KB], Quota[500000KB] ,Data transfer U/L Count Off ,Expire Date [NEVER] ,ACL [OFF]
05/02/2008 10:39:18 ** insert_entry=>
05/02/2008 10:39:18 ** <=insert_entry
05/02/2008 10:39:18 ** CreateBWUsageMemory: BWUsage[0]
05/02/2008 10:39:18 ** CreateBWUsageMemory_ul: BWUsage[0]

05/02/2008 10:40:37 ** LogoutByNameODBC: Logout By Name[natacha]
05/02/2008 10:40:37 ** LogoutByNameODBC: Logout Fail, [natacha] is not online
05/02/2008 10:40:38 ** MacGetName: SQLFetch SQL_NO_DATA[SELECT name from fsusr where reserved1 = '25516080365568';]




05/02/2008 10:39:58 ** MacGetName: SQLFetch SQL_NO_DATA[SELECT name from fsusr where reserved1 = '142031592495872';]
05/02/2008 10:39:58 ** LogoutByNameODBC: Logout By Name[natacha]
05/02/2008 10:39:58 ** LogoutByNameODBC: Logout Fail, [natacha] is not online

******************************
05/02/2008 10:40:59 ** AuthLoginODBC: Authenticate[natacha]
05/02/2008 10:40:59 ** AuthLoginODBC: Current Time [Tue Feb 05 10:40:59 2008], EDate [N/A]
05/02/2008 10:40:59 ** InsertEntry(2276): Add user mac(00-D0-B7-49-86-60) ,timeleft (949) ,User Upload Limit(100KB) ,Download Limit(100KB) ,Data transfer D/L Count On, Accumulated[58KB], Quota[500000KB] ,Data transfer U/L Count Off ,Expire Date [NEVER] ,ACL [OFF]
05/02/2008 10:40:59 ** insert_entry=>
05/02/2008 10:40:59 ** <=insert_entry
05/02/2008 10:40:59 ** CreateBWUsageMemory: BWUsage[0]
05/02/2008 10:40:59 ** CreateBWUsageMemory_ul: BWUsage[0]
05/02/2008 10:41:09 ** MacGetName: SQLFetch SQL_NO_DATA[SELECT name from fsusr where reserved1 = '65392400074752';]
05/02/2008 10:41:09 ** ServiceExecutionThread: Client IP[10.20.7.13][3868] Connected
05/02/2008 10:41:09 ** ClientThread(3868)=>
05/02/2008 10:41:09 ** RecvRequest(3868)=>
05/02/2008 10:41:09 ** <=RecvRequest(3868)
05/02/2008 10:41:09 ** isProxyConnection(3868)=>
05/02/2008 10:41:09 ** <=isProxyConnection(3868)
05/02/2008 10:41:09 ** ParseRequest(3868)=>
05/02/2008 10:41:09 ** <=ParseRequest(3868)
05/02/2008 10:41:09 ** ip_query_mac=>
05/02/2008 10:41:09 ** <=ip_query_mac
05/02/2008 10:41:09 ** redirect_org: src_ip 10.20.7.13 src_port 2624
05/02/2008 10:41:09 ** match_allowed_hosts ->
05/02/2008 10:41:09 ** match_allowed_hosts: domain {www.yahoo.com} NOT Matched Any Keywords
05/02/2008 10:41:09 ** redirect_org: match_allowed_hosts FAIL
05/02/2008 10:41:09 ** SendResponse(3868)=>
05/02/2008 10:41:09 ** <=SendResponse(3868)
05/02/2008 10:41:09 ** ClientThread(3868): close socket[2152]
05/02/2008 10:41:09 ** remove_ipport_entry=>
05/02/2008 10:41:09 ** <=remove_ipport_entry
05/02/2008 10:41:09 ** <=ClientThread(3868)
05/02/2008 10:49:57 ** AuthLoginODBC: Authenticate[natacha]
05/02/2008 10:49:57 ** AuthLoginODBC: Current Time [Tue Feb 05 10:49:57 2008], EDate [N/A]
05/02/2008 10:49:57 ** InsertEntry(2124): Add user mac(00-20-AF-C5-33-18) ,timeleft (948) ,User Upload Limit(100KB) ,Download Limit(100KB) ,Data transfer D/L Count On, Accumulated[94KB], Quota[500000KB] ,Data transfer U/L Count Off ,Expire Date [NEVER] ,ACL [OFF]
05/02/2008 10:49:57 ** insert_entry=>
05/02/2008 10:49:57 ** <=insert_entry
05/02/2008 10:49:57 ** CreateBWUsageMemory: BWUsage[0]
05/02/2008 10:49:57 ** CreateBWUsageMemory_ul: BWUsage[0]
05/02/2008 10:50:08 ** LogoutODBC: Logout By MAC(00-20-AF-C5-33-18) IP(10.20.7.15)
05/02/2008 10:50:09 ** remove_entry ->
05/02/2008 10:50:09 ** <- remove_entry
_________________
Greg

[alan]

Can you also post the first 300 lines of firstspot_gw_srv_[timestamp].log? We want to know more information about your configuration.
_________________
~ Patronsoft Limited ~

[alan]

It looks like there are some issues with the database access during logout. There are 2 possibilities:

1) bug in FirstSpot code
2) database problem. In particular, table or index corruption

BTW, what is the datasource you are currently using?
_________________
~ Patronsoft Limited ~

[gsgriffin]

The lines that have always bothered me are:
04/02/2008 18:52:51 ** restartRRAS: waiting lock

This did not happen when the system was first installed. When I completely rebuild the computer and start fresh, I don't have this for the first few times that I run FirstSpot, but it always comes back eventually and then stays with me. Not sure where to start or if that has anything to do with anything.


04/02/2008 18:50:35 ** Starting Print Log **
04/02/2008 18:50:35 ** FirstSpot Version (4.0.14) **
04/02/2008 18:50:35 ** Service: Read Session Log
04/02/2008 18:50:35 ** Service: Read DataBase Info
04/02/2008 18:50:35 ** Service: Read BlockICMP_UDP
04/02/2008 18:50:35 ** Service: BLOCK_ICMP Disabled
04/02/2008 18:50:35 ** Service: BLOCK_UDP Enabled
04/02/2008 18:50:35 ** Service: Read Driver Param
04/02/2008 18:50:35 ** Service: Read Global BandWidth Throttle
04/02/2008 18:50:35 ** Service: Read PW Offloading
04/02/2008 18:50:35 ** Service: Read Session Handling
04/02/2008 18:50:35 ** Service: Read Anonymous Login
04/02/2008 18:50:35 ** Service: Read Lisence Path
04/02/2008 18:50:35 ** Service: Read IP
04/02/2008 18:50:35 ** Service: Read Multiple Network Segment
04/02/2008 18:50:35 ** Service: Read Allow Disconnect
04/02/2008 18:50:35 ** Service: Read Allow Disconnect: allow private disconnect[NO][]
04/02/2008 18:50:35 ** Service: Read Allow Disconnect: allow public disconnect[NO][]
04/02/2008 18:50:35 ** Service: Read NIC
04/02/2008 18:50:35 ** Service: Read Allowed Hosts
04/02/2008 18:50:35 ** Service: Read Default Login Page
04/02/2008 18:50:35 ** Service: Read DHCP
04/02/2008 18:50:35 ** Service: Read Skip Nic D/E
04/02/2008 18:50:35 ** Service: Read RADIUS
04/02/2008 18:50:35 ** Service: Read Packet Drop Period
04/02/2008 18:50:35 ** Service: Packet Drop Period [20]
04/02/2008 18:50:35 ** Service: Read SMTP
04/02/2008 18:50:35 ** Service: SMTP Roaming OFF
04/02/2008 18:50:35 ** Service: Read AuthServ
04/02/2008 18:50:35 ** Service: Read Global Data Transfer
04/02/2008 18:50:35 ** Service: Read URL Tracking
04/02/2008 18:50:35 ** Service: URL Tracking ON
04/02/2008 18:50:35 ** System Info for Verification **
04/02/2008 18:50:35 ** System Info for Verification:Microsoft Windows XP
04/02/2008 18:50:35 ** System Info for Verification:Professional
04/02/2008 18:50:35 ** System Info for Verification:{Service Pack 2} (Build 2600)
04/02/2008 18:50:35 ** SUCCESS **
04/02/2008 18:50:35 ** Hardware Info for Verification **
04/02/2008 18:50:35 ** Hardware Info for Verification: Number of processors: {2}
04/02/2008 18:50:35 ** Hardware Info for Verification: Page size: {4096}
04/02/2008 18:50:35 ** Hardware Info for Verification: Processor type: {Alpha]
04/02/2008 18:50:35 ** Hardware Info for Verification: wProcessorLevel: {Alpha}
04/02/2008 18:50:35 ** Hardware Info for Verification: CPU Hz {2994}MHz
04/02/2008 18:50:35 ** Hardware Info for Verification: CPU Name { Intel(R) Pentium(R) 4 CPU 3.00GHz}
04/02/2008 18:50:35 ** Hardware Info for Verification: CPU Model {x86 Family 15 Model 6 Stepping 2}
04/02/2008 18:50:35 ** Memory Status: Total Physical Memory 958 MBytes
04/02/2008 18:50:35 ** Memory Status: Free Physical Memory 791 MBytes
04/02/2008 18:50:35 ** Memory Status: Total Page File 1548 MBytes
04/02/2008 18:50:35 ** Memory Status: Available Page File 1452 MBytes
04/02/2008 18:50:35 ** Memory Status: Total Virtual Memory 2047 MBytes
04/02/2008 18:50:35 ** Memory Status: Available Virtual Memory 2000 MBytes
04/02/2008 18:50:35 ** Memory Status: Total Memory Loaded 17 Bytes
04/02/2008 18:50:35 ** Memory Mapping: [C:\Program Files\FirstSpot\log\firstspot_gw_srv_2008.02.04_18.50.35.log]
04/02/2008 18:50:35 ** Reset All Accounts
04/02/2008 18:50:35 ** verifyPublicNicStatus=>
04/02/2008 18:50:35 ** firstspotconOperation2=>
04/02/2008 18:50:35 ** firstspotconOperation2: Connection Name[ADSL]
04/02/2008 18:50:36 ** firstspotconOperation2: Hardware Name Obtained[Realtek RTL8139 Family PCI Fast Ethernet NIC #2]
04/02/2008 18:50:37 ** firstspotconOperation2: Hardware ID Obtained[PCI\VEN_1186&DEV_1300&SUBSYS_13001186&REV_10\3&13C0B0C5&0&48]
04/02/2008 18:50:37 ** firstspotconOperation2{STA_PUBLIC_NIC}: Getting Status
04/02/2008 18:50:37 ** firstspotconOperation2{STA_PUBLIC_NIC}: Public NIC started
04/02/2008 18:50:37 ** <=firstspotconOperation2
04/02/2008 18:50:37 ** <=verifyPublicNicStatus
04/02/2008 18:50:37 ** probePublicIP=>
04/02/2008 18:50:37 ** probePublicIP: Connection Name[ADSL]
04/02/2008 18:50:37 ** probePublicIP: Hardware Guid Obtained[{982A6041-754F-465F-8DA9-E76CD2AE7345}]
04/02/2008 18:50:37 ** <=probePublicIP
04/02/2008 18:50:37 ** startDemoMode=>
04/02/2008 18:50:37 ** <=startDemoMode
04/02/2008 18:50:37 ** writeRedirectPort=>
04/02/2008 18:50:37 ** <=writeRedirectPort
04/02/2008 18:50:37 ** validate_SubnetAddr =>
04/02/2008 18:50:37 ** validate_SubnetAddr <=
04/02/2008 18:50:37 ** EnableDisableAssignIPs=>
04/02/2008 18:50:37 ** firstspotconOperation2=>
04/02/2008 18:50:37 ** firstspotconOperation2: Connection Name[LAN]
04/02/2008 18:50:37 ** firstspotconOperation2: Hardware Name Obtained[Realtek RTL8139 Family PCI Fast Ethernet NIC]
04/02/2008 18:50:37 ** firstspotconOperation2: Hardware ID Obtained[PCI\VEN_1186&DEV_1300&SUBSYS_13001186&REV_10\3&13C0B0C5&0&38]
04/02/2008 18:50:37 ** firstspotconOperation2{DIS_PRIVATE_NIC}: Disabling
04/02/2008 18:50:39 ** firstspotconOperation2{DIS_PRIVATE_NIC}:Private NIC disabled successfully
04/02/2008 18:50:39 ** <=firstspotconOperation2
04/02/2008 18:50:39 ** EnableDisableAssignIPs: Configuring Private NIC
04/02/2008 18:50:50 ** EnableDisableAssignIPs: Finished Configuring Private NIC
04/02/2008 18:50:50 ** firstspotconOperation2=>
04/02/2008 18:50:50 ** firstspotconOperation2: Hardware ID Obtained[PCI\VEN_1186&DEV_1300&SUBSYS_13001186&REV_10\3&13C0B0C5&0&38]
04/02/2008 18:50:50 ** firstspotconOperation2{EN_PRIVATE_NIC}: Enabling
04/02/2008 18:50:54 ** firstspotconOperation2{EN_PRIVATE_NIC}:Private NIC enabled successfully
04/02/2008 18:50:54 ** <=firstspotconOperation2
04/02/2008 18:50:54 ** <=EnableDisableAssignIPs
04/02/2008 18:50:54 ** verifyPrivateNicStatus=>
04/02/2008 18:50:54 ** firstspotconOperation2=>
04/02/2008 18:50:54 ** firstspotconOperation2: Hardware ID Obtained[PCI\VEN_1186&DEV_1300&SUBSYS_13001186&REV_10\3&13C0B0C5&0&38]
04/02/2008 18:50:54 ** firstspotconOperation2{STA_PRIVATE_NIC}: Getting Status
04/02/2008 18:50:54 ** firstspotconOperation2{STA_PRIVATE_NIC}: Private NIC started
04/02/2008 18:50:54 ** <=firstspotconOperation2
04/02/2008 18:50:54 ** <=verifyPrivateNicStatus
04/02/2008 18:50:54 entering verify NIC
04/02/2008 18:50:54 verify NIC : Public NIC{{982A6041-754F-465F-8DA9-E76CD2AE7345}}
04/02/2008 18:50:54 verify NIC : Private NIC{{AFB6F439-A893-45C0-A3D8-9E89A46BE6D0}}
04/02/2008 18:50:54 IoctlNdisQueryGlobalStats ->
04/02/2008 18:50:54 <- IoctlNdisQueryGlobalStats
04/02/2008 18:50:54 IoctlNdisQueryGlobalStats ->
04/02/2008 18:50:54 <- IoctlNdisQueryGlobalStats
04/02/2008 18:50:54 leaving verify NIC
04/02/2008 18:50:54 IoctlNdisQueryGlobalStats ->
04/02/2008 18:50:54 <- IoctlNdisQueryGlobalStats
04/02/2008 18:50:54 ** GetMacAddress: (mac 00:50:ba:be:25:9f)
04/02/2008 18:50:54 ** RestartApacheService=>
04/02/2008 18:50:54 ** RestartApacheService: restart gracefully
04/02/2008 18:50:55 ** <=RestartApacheService
04/02/2008 18:50:55 ** StopUnusedServices=>
04/02/2008 18:50:55 ** StopUnusedServices: SharedAccess
04/02/2008 18:50:55 ** StopUnusedServices: DNS
04/02/2008 18:50:55 ** StopUnusedServices: DHCPServer
04/02/2008 18:50:55 ** <=StopUnusedServices
04/02/2008 18:50:55 ** modifyRRAS ->
04/02/2008 18:50:55 ** resetRRAS ->
04/02/2008 18:51:02 ** resetRRAS: remove private NIC
04/02/2008 18:51:04 ** resetRRAS: add private NIC
04/02/2008 18:51:08 ** <- resetRRAS
04/02/2008 18:51:08 ** modifyRRAS: Configuring
04/02/2008 18:51:21 ** restartRRAS->
04/02/2008 18:51:21 ** restartRRAS: failed the lock
04/02/2008 18:51:51 ** restartRRAS: waiting lock
04/02/2008 18:52:21 ** restartRRAS: waiting lock
04/02/2008 18:52:51 ** restartRRAS: waiting lock
04/02/2008 18:53:15 ** restartRRAS: OpenService
04/02/2008 18:53:15 ** restartRRAS: Stopping
04/02/2008 18:53:15 ** restartRRAS: starting
04/02/2008 18:53:16 ** <-restartRRAS
04/02/2008 18:53:16 ** <- modifyRRAS
04/02/2008 18:53:16 ** modifyDNS ->
04/02/2008 18:53:16 using DNS 172.16.0.1
04/02/2008 18:53:16 ** WriteBindCfg: bindpath[C:\Program Files\FirstSpot\dns\etc\]
04/02/2008 18:53:16 ** <- modifyDNS
04/02/2008 18:53:17 Multiple Network Segment Count = (0)
04/02/2008 18:53:17 Quit WriteROUTERCfg!
04/02/2008 18:53:18 license key valid, running Advance edition
04/02/2008 18:53:18 ** Service: Read DataBase Info
04/02/2008 18:53:18 <------ batch_fP_RetValue stats : 0.
04/02/2008 18:53:18 no Batch File or Failed the execution
04/02/2008 18:53:18 ** StartServiceThread=>
04/02/2008 18:53:18 ** setdispatcher=>
04/02/2008 18:53:18 ** setdispatcher : SMTP Roaming OFF
04/02/2008 18:53:18 ** setdispatcher : MAC-base session handling
04/02/2008 18:53:18 ** setdispatcher : Private IP(10.20.7.0)
04/02/2008 18:53:18 ** setdispatcher : idle timeout(30)
04/02/2008 18:53:18 ** setdispatcher : driver timeout(1)
04/02/2008 18:53:18 ** setdispatcher : Gateway IP(10.20.7.1)
04/02/2008 18:53:18 ** setdispatcher : Listen Port(5786)
04/02/2008 18:53:18 ** setdispatcher : Auth Port(5788)
04/02/2008 18:53:18 ** setdispatcher : Redirect Port(5790)
04/02/2008 18:53:18 ** setdispatcher : Authenicate IP(10.20.7.1)
04/02/2008 18:53:18 IoctlNdisQueryGlobalStats ->
04/02/2008 18:53:18 ** setdispatcher : Up Load Limit(100)
04/02/2008 18:53:18 ** setdispatcher : Down Load Limit(100)
04/02/2008 18:53:18 ** GetMacAddress: (mac 00:50:ba:be:25:9f)
04/02/2008 18:53:18 ** setdispatcher : Interval(1)
04/02/2008 18:53:18 ** setdispatcher : Drop Period(2)
04/02/2008 18:53:18 ** setdispatcher : PKTInterval(20)
04/02/2008 18:53:18 ** setdispatcher : Restricted Redirect ON
04/02/2008 18:53:18 ** <=setdispatcher
04/02/2008 18:53:18 ** AddPassiveLoginMAC=>
04/02/2008 18:53:18 ** AddPassiveLoginMAC: IOCTL_CMD_PL_MAC_RESET SUCCESS
04/02/2008 18:53:18 ** <=AddPassiveLoginMAC
04/02/2008 18:53:18 ** StartServiceThread: setdispatcher Success(1)
04/02/2008 18:53:18 ** EnableDrvBlock: BLOCK_ICMP DISABLED
04/02/2008 18:53:18 ** EnableDrvBlock: IOCTL_CMD_DISABLE_BLOCK_ICMP SUCCESS
04/02/2008 18:53:18 ** EnableDrvBlock: BLOCK_UDP ENABLED
04/02/2008 18:53:18 ** EnableDrvBlock: IOCTL_CMD_ENABLE_BLOCK_UDP SUCCESS
04/02/2008 18:53:18 ** EnableDrvDP: IOCTL_CMD_ENABLE_DEBUG SUCCESS, debug_level=1
04/02/2008 18:53:18 ** InitialCaptureThread:=>
04/02/2008 18:53:18 ** DeviceNotifyDBLogin=>
>
04/02/2008 18:53:18 ** ObtainBWReportThread=>
04/02/2008 18:53:18 ** <=InitialCaptureThread:
BUG_PATH_INIT SUCCESS
04/02/2008 18:53:18 ** InitAllowIPTable: IOCTL_CMD_ALLOWED_IP_INIT SUCCESS
04/02/2008 18:53:18 ** read_client_pass ->
04/02/2008 18:53:18 ** read_client_pass: file name=C:\Program Files\FirstSpot\config.ini, session=0
04/02/2008 18:53:18 ** read_client_pass: MAC={00-08-C7-5A-79-3B}{65392400074752}{65392400074752}
04/02/2008 18:53:18 ** read_client_pass: IOCTL_CMD_ADD_CLIENT_PASS SUCCESS
04/02/2008 18:53:18 ** DeviceNotifyDB=>
04/02/2008 18:53:18 ** PipeListenThread=>

04/02/2008 18:53:18 ** ServiceExecutionThread=>
04/02/2008 18:53:18 ** <- read_qos_entry
04/02/2008 18:53:18 ** read_port_filter ->
04/02/2008 18:53:18 ** <- read_port_filter
04/02/2008 18:53:18 ** read_acl ->
04/02/2008 18:53:18 ** <- read_acl
04/02/2008 18:53:18 ** indicate_subnetmask: IOCTL_CMD_GET_SUBNETMASK SUCCESS, sm.smask={255.255.255.0}
04/02/2008 18:53:18 ** read_allowed_hosts: host_path{C:\Program Files\FirstSpot\AllowedHostsIps.txt}
04/02/2008 18:53:18 ** read_allowed_hosts: host_path{C:\Program Files\FirstSpot\AllowedHostsKeywords.txt}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {0}={patronsoft.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {1}={search.msn.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {2}={www.paypal.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {3}={www.paypalobjects.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {4}={select.worldpay.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {5}={secure.authorize.net}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {6}={account.authorize.net}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {7}={companion.yahoo.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {8}={toolbar.yahoo.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {9}={toolbar.msn.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {10}={data.alexa.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {11}={www.printspots.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {12}={www.printeron.net}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {13}={ixwebhosting.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {14}={wunderground.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {15}={telkomsa.net}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {16}={opentransfer.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {17}={ywamworcester.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {18}={ywamworcester.org.za}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {19}={ywamafrica.org}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {20}={ywam.org}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {21}={grisoft.com}
04/02/2008 18:53:18 ** read_allowed_hosts: Allowed Host {22}={grisoft.cz}
04/02/2008 18:53:19 ** ServiceExecutionThread: Client IP[10.20.7.135][3484] Connected
04/02/2008 18:53:19 ** ClientThread(3484)=>
04/02/2008 18:53:19 ** RecvRequest(3484)=>
04/02/2008 18:53:19 ** <=RecvRequest(3484)
04/02/2008 18:53:19 ** isProxyConnection(3484)=>
04/02/2008 18:53:19 ** <=isProxyConnection(3484)
04/02/2008 18:53:19 ** ParseRequest(3484)=>
04/02/2008 18:53:19 ** ParseRequest(3484): cpfilename=NULL
04/02/2008 18:53:19 ** remove_ipport_entry=>
04/02/2008 18:53:19 ** <=remove_ipport_entry
04/02/2008 18:53:19 ** <=ClientThread(3484) Close Connection
04/02/2008 18:53:19 ** AuthLoginODBC: Authenticate[jstephens]
_________________
Greg

[alan]

The RRAS message shouldn't create any problem, please ignore it.

So what is your feedback on my database question?
_________________
~ Patronsoft Limited ~

[gsgriffin]

Well, really the only two option are code and Db. I thought the Db could be the problem so on Monday I started with a fresh install of the program (default Db provided with your program) and then used the CSV import for the users. Same day started having the same problems.

This was a fresh, install from scratch Win Xp Pro on a Pent 4, 3Ghz with 1G ram. I can't see anything in between a person that logs on in the morning with over 900 minutes and then 2 hours later logs on again with less than 60 min. The only way I can imagine that is a logout problem is if the program is incorrectly storing the UNIX timestamp value and making a problem with the math. I also can't see how a fresh database can become corrupt so quickly by just watching it.
_________________
Greg

[alan]

But the key is : did you somehow access the dbf (and the related mdx and inf) files using 3rd party tools? See
http://patronsoft.com/forum/viewtopic.php?t=1514 for a guideline.
_________________
~ Patronsoft Limited ~

[gsgriffin]

I only access the data through SQL in PHP and never write anything....only read.

I only read data to make it easier to look at the user and url data. That is it.
_________________
Greg