Double Postings for Midnight Timestamp

[WoodburyMan]

I seem to be having a weird issue. Every night at Midnight (00:00) my station produces two entries with 00:00 timestamp. I have it set to log every minute.

I get the following error on the console:

2015-01-26 23:22:10.972 Loading last N hour data from data logs: 1/26/2015 11:18:00 PM
2015-01-26 23:22:29.760 Loaded 61 entries to last hour data list
2015-01-26 23:22:49.111 Loaded 181 entries to last 3 hour data list
2015-01-26 23:23:08.791 Loaded 1442 entries to last 24 hour data list
2015-01-26 23:23:36.510 Error at line 35487 of data/Jan15log.txt : Constraint
2015-01-26 23:23:36.511 Please edit the file to correct the error
2015-01-26 23:23:36.513 Loaded 8596 entries to recent data list
Current culture: English (United States)
Year: 2015
Month: 1
Day: 26
DayOfWeek: Monday
DateTime.Now: 1/26/2015 11:23:36 PM
1/26/2015 11:23:36 PM

When I pop open my data file to the selected line I see this.

Code: Select all

25/01/15	23:57	18	34	-6.5	2.7	4	3	0	0.07	29.99	14.72	71.1	37	3.2	18	18	0	0	0	0	9.9	0	0	360	0	0.07
25/01/15	23:58	18	35	-5.8	2.6	3.2	1	0	0.07	29.98	14.72	71.1	37	3.2	18	18	0	0	0	0	10	0	0	270	0	0.07
25/01/15	23:59	18	34	-6.5	2.5	3.2	358	0	0.07	29.99	14.72	71.1	37	1.6	18	18	0	0	0	0	10	0	0	360	0	0.07
26/01/15	00:00	18	35	-5.8	2.5	3.2	358	0	0	29.99	14.72	71.1	37	2.4	18	18	0	0	0	0	10.1	0	0	360	0	0
26/01/15	00:00	18	35	-5.8	2.5	3.2	358	0	0	29.99	14.72	71.1	37	2.4	18	18	0	0	0	0	10.1	0	0	360	0	0
26/01/15	00:01	18	34	-6.5	2.5	3.2	358	0	0	29.99	14.72	71.1	37	0	18	18	0	0	0	0	10.1	0	0	360	0	0
26/01/15	00:02	18	34	-6.5	2.3	3.2	354	0	0	29.99	14.72	71.1	37	0	18	18	0	0	0	0	10.2	0	0	360	0	0

You can see the two entries for 00:00.
Going back I've found every dataset for the last week or so has had this issue just about.

This is running on my Rasp Pi B+. Has occurred with at least 3012 to the current 3015.

[WoodburyMan]

Additional log files.
(Ignore the grudzien.us errors...)

Code: Select all

1/26/2015 11:59:00 PM Updating log files
Writing today.ini, LastUpdateTime = 1/26/2015 11:59:00 PM raindaystart = 14.71653541806 rain counter = 14.71653541806
Latest reading: 2EE0: 1A 28 CE 00 4F 45 80 D5 26 09 12 00 00 DE 04 00
1/26/2015 11:59:17 PM Within 3 seconds of sensor data change, skipping read
2015-01-26 23:59:40.005 Updating CWOP
Midnight rain reset, count = 14.71653541806 time = 12:00 AM
=== Day reset, today = 27
=== Day reset for 1/26/2015 12:00:00 AM
NOAA reports will be uploaded at next web update
Writing entry to dayfile.txt:
26/01/15,17.7,360,21:26,15.1,07:49,21.4,22:14,29.73,23:57,30.13,10:30,0.00,00:00,0.00,18.1,129.6,10.6,21:47,30,07:47,79,23:52,0.000,0.0,21.4,22:14,11.8,18:40,4.6,09:15,0.00,00:00,5.1,09:15,14.2,23:52,-11.7,07:49,2,46.7,0.0,0,00:00,0.0,00:00
Raincounter = 14.71653541806 Raindaystart = 14.71653541806
Rainyesterday (calibrated) set to 0
RainDayThreshold = -1
rdt1000=10 ry1000=0
Yesterday was a dry day
Consecutive dry days = 1
Raindaystart set to 14.71653541806
StartofdayET set to 0
Writing today.ini, LastUpdateTime = 1/26/2015 11:59:00 PM raindaystart = 14.71653541806 rain counter = 14.71653541806
Latest reading: 2EE0: 1B 28 CE 00 4F 45 80 D3 26 09 0C 00 00 DE 04 00
Creating NOAA monthly report for Monday, January 26, 2015
1/27/2015 12:00:01 AM Updating log files
Writing today.ini, LastUpdateTime = 1/27/2015 12:00:01 AM raindaystart = 14.71653541806 rain counter = 14.71653541806
Latest reading: 2EE0: 1B 28 CE 00 4F 45 80 D3 26 09 0C 00 00 DE 04 00
1/27/2015 12:00:07 AM Within 3 seconds of sensor data change, skipping read
2015-01-27 00:00:17.296 Destination directory not found: /woodburyweather.com
2015-01-27 00:00:18.427 Uploading NOAA reports
2015-01-27 00:00:19.335 Error uploading Reports/NOAAMO0115.txt to /grudzien.us/Reports/NOAAMO0115.txt : /grudzien.us/Reports/NOAAMO0115.txttmp: No such file or directory
2015-01-27 00:00:20.142 Error uploading Reports/NOAAYR2015.txt to /grudzien.us/Reports/NOAAYR2015.txt : /grudzien.us/Reports/NOAAYR2015.txttmp: No such file or directory
Saving monthly report as Reports/NOAAMO0115.txt
Creating NOAA yearly report
1/27/2015 12:01:00 AM Updating log files
Saving yearly report as Reports/NOAAYR2015.txt
=== Day reset complete
Now recording data for day=27 month=1 year=2015
Reset sunshine hours, yesterday = 0
1/27/2015 12:01:04 AM Updating log files
Writing today.ini, LastUpdateTime = 1/27/2015 12:01:04 AM raindaystart = 14.71653541806 rain counter = 14.71653541806
Latest reading: 2EE0: 1C 28 CE 00 4F 45 80 D3 26 15 24 00 00 DE 04 00
2015-01-27 00:01:04.767 Creating backup folder backup/daily/20150127000104/
1/27/2015 12:01:05 AM Updating log files
Writing today.ini, LastUpdateTime = 1/27/2015 12:01:05 AM raindaystart = 14.71653541806 rain counter = 14.71653541806
Latest reading: 2EE0: 1C 28 CE 00 4F 45 80 D3 26 15 24 00 00 DE 04 00
1/27/2015 12:02:00 AM Updating log files
Writing today.ini, LastUpdateTime = 1/27/2015 12:02:00 AM raindaystart = 14.71653541806 rain counter = 14.71653541806

[steve]

Thanks - I'll investigate.

[steve]

When I pop open my data file to the selected line I see this.

Do those entries really not have comma separators, or is that just something to do with how you're displaying it?

[WoodburyMan]

The way I'm displaying it. They're comma separated. When I opened the file I opened it in LibreOffice as a CSV to edit it like a Spreadsheet.

[steve]

OK, thanks. The 'constraint' error is because of the duplicate line. And I think I have a fix for that - it's a timing issue due to the speed of the Pi.

[WoodburyMan]

Confirmed 3016 has fixed this I believe. I've had 3 rollovers since updating to 3016 and not a single one caused duplicate entries. It was doing it at least 3 out of 4 days prior to 3016.

Thanks Steve!