Steve commented above, and perhaps elsewhere. I got the impression the next logical step was to investigate the HTTP traffic - hence Fiddler. I've yet to get that working on my Pi.
Apologies for the detail that follows, but I wanted to document what I'm seeing - which is a failure rate as high as 80%.
Over the past hour it looks like around 12 updates succeeded and 48 failed. Here I just pulled out (and truncated) the
WU lines.
Code: Select all
2018-01-08 15:24:01.028 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:25:22.802 WU Response: OK: success
2018-01-08 15:26:01.039 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:27:01.037 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:28:13.305 WU Response: OK: success
2018-01-08 15:29:01.042 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:30:01.056 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:31:54.445 WU Response: OK: success
2018-01-08 15:32:01.052 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:33:01.057 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:34:01.056 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:35:53.705 WU Response: OK: success
2018-01-08 15:36:01.061 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:37:01.064 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:38:01.074 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:39:01.078 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:40:26.116 WU Response: OK: success
2018-01-08 15:41:01.091 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:42:01.093 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:43:01.095 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:44:53.497 WU Response: OK: success
2018-01-08 15:45:01.105 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:46:01.111 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:47:01.117 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:49:01.678 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:50:01.127 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:51:01.124 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:52:01.119 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:53:01.124 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:54:01.132 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:55:01.138 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:56:01.131 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:57:01.139 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:58:01.134 WU update: at System.Net.WebConnection.HandleError
2018-01-08 15:59:01.139 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:01:01.683 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:02:01.153 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:03:01.158 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:05:01.681 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:06:01.165 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:07:01.168 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:08:01.171 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:09:01.177 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:10:01.181 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:11:01.174 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:12:01.257 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:13:01.162 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:14:15.424 WU Response: OK: success
2018-01-08 16:16:01.719 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:17:01.179 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:18:11.691 WU Response: OK: success
2018-01-08 16:19:01.194 WU update: at System.Net.WebConnection.HandleError
2018-01-08 16:20:15.443 WU Response: OK: success
2018-01-08 16:21:40.789 WU Response: OK: success
2018-01-08 16:22:50.826 WU Response: OK: success
2018-01-08 16:23:20.496 WU Response: OK: success
2018-01-08 16:24:00.221 WU update: at System.Net.WebConnection.HandleError
Looks like between 15:45:01 and 16:13:01 every call to WU failed. Still, when I look at the Weather History Table in WU I find a record from 16:04
20180108_WU_Table_Fragment.jpg
Drilling into the Diags log in more detail around that time, there's a
gap that aligns exactly with that data point at 16:04 - I inserted the "<<<<<<<<<" row to point to the gap...
Code: Select all
2018-01-08 16:02:01.153 WU update: at System.Net.WebConnection.HandleError(WebExceptionStatus st, System.Exception e, System.String where)
at System.Net.WebConnection.ReadDone(IAsyncResult result)
2018-01-08 16:03:01.158 WU update: at System.Net.WebConnection.HandleError(WebExceptionStatus st, System.Exception e, System.String where)
at System.Net.WebConnection.ReadDone(IAsyncResult result)
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
2018-01-08 16:05:00.963 Writing log entry for 1/8/2018 4:05:00 PM
2018-01-08 16:05:00.964 Written log entry for 1/8/2018 4:05:00 PM
2018-01-08 16:05:00.968 Writing today.ini, LastUpdateTime = 1/8/2018 4:05:00 PM raindaystart = 36.4960629549 rain counter = 36.54330704934
2018-01-08 16:05:00.968 Latest reading: E300: 07 1D C5 00 54 13 00 3A 26 0E 14 00 0A 16 0C 80
2018-01-08 16:05:01.681 WU update: at System.Net.WebConnection.HandleError(WebExceptionStatus st, System.Exception e, System.String where)
at System.Net.WebConnection.ReadDone(IAsyncResult result)
Finally, in the first, distilled log fragment there are a few similar log gaps at
15:48
16:00
16:04
16:15
I'm not quite sure what it means, but there's some doc on the situation I'm seeing.
Bob
You do not have the required permissions to view the files attached to this post.