Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

PicoStill lock in priming steam generator #315

Open
erdoukki opened this issue Nov 19, 2021 · 35 comments
Open

PicoStill lock in priming steam generator #315

erdoukki opened this issue Nov 19, 2021 · 35 comments

Comments

@erdoukki
Copy link
Contributor

I have get some success with PicoStill which is detect, like my PicoBrew pro, but when I start a PicoStill session, the PicoStill start to apply the correct PSI, the server and PicoStill WEBUI reports correct temperatures and psi, but the distillate process never start !
The PicoBrew screen get lock in the first step with the message :
Priming steam generator

In the past, with the cloud PicoBrew, I get success in distillate…

Is there anybody who can help me in my distillate adventures…
;-)

@erdoukki
Copy link
Contributor Author

I start the still process from the PicoPro UI.
Then I click the Green button for the PicoStill.

I get the data logged on the WebUI.

I am still in 0.30 firmware (no upgrade proposal)
I am still locked in the pirming steam generator, and it takes at good default PSI but PicoPro panel never shows up the default PicoStill datas !

@erdoukki
Copy link
Contributor Author

image

image

@erdoukki
Copy link
Contributor Author

image

@erdoukki
Copy link
Contributor Author

image

@erdoukki
Copy link
Contributor Author

erdoukki commented Nov 24, 2021

MORE ...

app_1    | [2021-11-24 09:26:12,624] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:26:12,749] DEBUG in still_polling: DEBUG: Still Datastring: #43.51,43.17,44.65,41.74,2.67,1,1,1,None#
app_1    | (7) accepted ('172.22.0.2', 46344)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:26:21] "POST /socket.io/?EIO=3&transport=polling&t=NrHH7fF&sid=e9dea4f2ba7f4a7cb832625b53557bdb HTTP/1.1" 200 143 0.003128
app_1    | (7) accepted ('172.22.0.2', 46348)
app_1    | \10.4.2.231,172.22.0.2 - - [24/Nov/2021 09:26:24] "GET / HTTP/1.0" 200 15772 0.110276
app_1    | (7) accepted ('172.22.0.2', 46350)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:26:25] "GET /socket.io/?EIO=3&transport=polling&t=NrHH8a7 HTTP/1.1" 200 347 0.513497
app_1    | (7) accepted ('172.22.0.2', 46352)
app_1    | (7) accepted ('172.22.0.2', 46354)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:26:55] "GET /socket.io/?EIO=3&transport=websocket&sid=e9dea4f2ba7f4a7cb832625b53557bdb HTTP/1.1" 400 164 60.051352
app_1    | 172.22.0.2 - - [24/Nov/2021 09:26:55] "GET /socket.io/?EIO=3&transport=polling&t=NrHH1PB&sid=e9dea4f2ba7f4a7cb832625b53557bdb HTTP/1.1" 400 164 60.036191
app_1    | [2021-11-24 09:27:12,814] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:27:12,939] DEBUG in still_polling: DEBUG: Still Datastring: #43.55,43.22,44.71,41.84,2.75,1,1,1,None#
app_1    | 172.22.0.2 - - [24/Nov/2021 09:27:25] "GET /socket.io/?EIO=3&transport=polling&t=NrHH8j5&sid=2b7cca80e1444d8e9a2decb5f9ffa6b6 HTTP/1.1" 400 164 60.054197
app_1    | 172.22.0.2 - - [24/Nov/2021 09:27:25] "GET /socket.io/?EIO=3&transport=websocket&sid=2b7cca80e1444d8e9a2decb5f9ffa6b6 HTTP/1.1" 400 164 60.030482
app_1    | [2021-11-24 09:28:13,014] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:28:13,077] DEBUG in still_polling: DEBUG: Still Datastring: #43.55,43.22,44.77,41.69,2.76,1,1,1,None#
app_1    | [2021-11-24 09:29:13,155] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:29:13,268] DEBUG in still_polling: DEBUG: Still Datastring: #43.36,42.99,44.71,41.35,2.79,1,1,1,None#
app_1    | [2021-11-24 09:30:13,345] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:30:13,428] DEBUG in still_polling: DEBUG: Still Datastring: #43.32,42.94,44.65,41.40,2.77,1,1,1,None#
app_1    | (7) accepted ('172.22.0.2', 46838)
app_1    | (7) accepted ('172.22.0.2', 46840)
app_1    | (7) accepted ('172.22.0.2', 46842)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:31:11] "GET /socket.io/?EIO=3&transport=websocket&sid=2b7cca80e1444d8e9a2decb5f9ffa6b6 HTTP/1.1" 400 201 0.010117
app_1    | 172.22.0.2 - - [24/Nov/2021 09:31:11] "GET /socket.io/?EIO=3&transport=polling&t=NrHH8j5&sid=2b7cca80e1444d8e9a2decb5f9ffa6b6 HTTP/1.1" 400 201 0.007375
app_1    | 172.22.0.2 - - [24/Nov/2021 09:31:11] "POST /socket.io/?EIO=3&transport=polling&t=NrHIESr&sid=2b7cca80e1444d8e9a2decb5f9ffa6b6 HTTP/1.1" 400 201 0.006985
app_1    | (7) accepted ('172.22.0.2', 46844)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:31:12] "POST /socket.io/?EIO=3&transport=polling&t=NrHIEny&sid=2b7cca80e1444d8e9a2decb5f9ffa6b6 HTTP/1.1" 400 201 0.001953
app_1    | (7) accepted ('172.22.0.2', 46846)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:31:13] "GET /socket.io/?EIO=3&transport=polling&t=NrHIF00 HTTP/1.1" 200 347 0.018044
app_1    | (7) accepted ('172.22.0.2', 46848)
app_1    | (7) accepted ('172.22.0.2', 46850)
app_1    | [2021-11-24 09:31:13,495] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:31:13,609] DEBUG in still_polling: DEBUG: Still Datastring: #43.22,42.80,44.71,41.20,2.80,1,1,1,None#
app_1    | (7) accepted ('172.22.0.2', 46894)
app_1    | \10.4.2.231,172.22.0.2 - - [24/Nov/2021 09:31:35] "GET / HTTP/1.0" 200 16463 0.111513
app_1    | (7) accepted ('172.22.0.2', 46896)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:31:35] "GET /socket.io/?EIO=3&transport=polling&t=NrHIKYE HTTP/1.1" 200 347 0.014904
app_1    | (7) accepted ('172.22.0.2', 46898)
app_1    | (7) accepted ('172.22.0.2', 46900)
app_1    | (7) accepted ('172.22.0.2', 46932)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:32:01] "POST /socket.io/?EIO=3&transport=polling&t=NrHIQky&sid=2665ce190f54437da5b26d596b5e94aa HTTP/1.1" 200 143 0.002601
app_1    | 172.22.0.2 - - [24/Nov/2021 09:32:01] "GET /socket.io/?EIO=3&transport=polling&t=NrHIKcc&sid=2665ce190f54437da5b26d596b5e94aa HTTP/1.1" 200 159 25.228913
app_1    | (7) accepted ('172.22.0.2', 46934)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:32:13] "GET /socket.io/?EIO=3&transport=websocket&sid=fbeef409bd2a41edaf1340aac2791f7d HTTP/1.1" 400 164 60.013643
app_1    | 172.22.0.2 - - [24/Nov/2021 09:32:13] "GET /socket.io/?EIO=3&transport=polling&t=NrHIF1Q&sid=fbeef409bd2a41edaf1340aac2791f7d HTTP/1.1" 400 164 60.009742
app_1    | [2021-11-24 09:32:13,684] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:32:13,763] DEBUG in still_polling: DEBUG: Still Datastring: #43.22,42.80,44.71,41.30,2.79,1,1,1,None#
app_1    | (7) accepted ('172.22.0.2', 46992)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:32:26] "POST /socket.io/?EIO=3&transport=polling&t=NrHIWuv&sid=2665ce190f54437da5b26d596b5e94aa HTTP/1.1" 200 143 0.002568
app_1    | 172.22.0.2 - - [24/Nov/2021 09:32:36] "GET /socket.io/?EIO=3&transport=websocket&sid=2665ce190f54437da5b26d596b5e94aa HTTP/1.1" 400 164 60.004874
app_1    | (7) accepted ('172.22.0.2', 47020)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:32:52] "GET /socket.io/?EIO=3&transport=polling&t=NrHIc-b HTTP/1.1" 200 347 0.014358
app_1    | 172.22.0.2 - - [24/Nov/2021 09:33:01] "GET /socket.io/?EIO=3&transport=polling&t=NrHIQnW&sid=2665ce190f54437da5b26d596b5e94aa HTTP/1.1" 400 164 60.003842
app_1    | [2021-11-24 09:33:13,824] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:33:17,023] DEBUG in still_polling: DEBUG: Connection failure 1
app_1    | [2021-11-24 09:34:17,094] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:34:20,288] DEBUG in still_polling: DEBUG: Connection failure 2
app_1    | [2021-11-24 09:35:20,351] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:35:23,567] DEBUG in still_polling: DEBUG: Connection failure 3
app_1    | (7) accepted ('172.22.0.2', 47302)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:35:25] "GET /API/pico/register?uid=baa4a7c24f9aa6b149aadbe63462ebb2 HTTP/1.0" 200 171 0.055583
app_1    | (7) accepted ('172.22.0.2', 47304)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:35:25] "GET /API/pico/picoChangeState?picoUID=baa4a7c24f9aa6b149aadbe63462ebb2&state=2 HTTP/1.0" 200 168 0.013260
app_1    | (7) accepted ('172.22.0.2', 47308)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:35:26] "GET /API/pico/checkFirmware?uid=baa4a7c24f9aa6b149aadbe63462ebb2&version=0.1.34 HTTP/1.0" 200 169 0.029810
app_1    | (7) accepted ('172.22.0.2', 47310)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:35:26] "GET /API/pico/getActionsNeeded?uid=baa4a7c24f9aa6b149aadbe63462ebb2 HTTP/1.0" 200 168 0.035081
app_1    | (7) accepted ('172.22.0.2', 47358)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:35:40] "GET /API/pico/canUsePicoStill?picoUid=baa4a7c24f9aa6b149aadbe63462ebb2&picoStillUid=30aea47edbb8 HTTP/1.0" 200 171 0.033366
app_1    | (7) accepted ('172.22.0.2', 47360)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:35:40] "GET /API/pico/hasCleanedAck?picoUid=baa4a7c24f9aa6b149aadbe63462ebb2&picoStillUid=30aea47edbb8 HTTP/1.0" 200 171 0.030520
app_1    | (7) accepted ('172.22.0.2', 47380)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:35:54] "GET /socket.io/?EIO=3&transport=polling&t=NrHJJY1 HTTP/1.1" 200 347 0.015960
app_1    | (7) accepted ('172.22.0.2', 47382)
app_1    | (7) accepted ('172.22.0.2', 47384)
app_1    | (7) accepted ('172.22.0.2', 47406)
app_1    | \10.4.2.231,172.22.0.2 - - [24/Nov/2021 09:36:07] "GET / HTTP/1.0" 200 16605 0.072106
app_1    | (7) accepted ('172.22.0.2', 47408)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:36:07] "GET /socket.io/?EIO=3&transport=polling&t=NrHJMyY HTTP/1.1" 200 347 0.007618
app_1    | (7) accepted ('172.22.0.2', 47410)
app_1    | (7) accepted ('172.22.0.2', 47412)
app_1    | (7) accepted ('172.22.0.2', 47450)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:36:33] "POST /socket.io/?EIO=3&transport=polling&t=NrHJT7l&sid=12a33a016c91466cb055280270f5545b HTTP/1.1" 200 143 0.002733
app_1    | 172.22.0.2 - - [24/Nov/2021 09:36:33] "GET /socket.io/?EIO=3&transport=polling&t=NrHJN10&sid=12a33a016c91466cb055280270f5545b HTTP/1.1" 200 159 25.117139
app_1    | (7) accepted ('172.22.0.2', 47452)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:36:37] "GET /socket.io/?EIO=3&transport=polling&t=NrHJJhS&sid=17fcd60497304cb79464d22b4dcff700 HTTP/1.1" 200 155 42.967846
app_1    | 172.22.0.2 - - [24/Nov/2021 09:36:55] "GET /socket.io/?EIO=3&transport=websocket&sid=17fcd60497304cb79464d22b4dcff700 HTTP/1.1" 400 164 60.004842
app_1    | (7) accepted ('172.22.0.2', 47488)
app_1    | (7) accepted ('172.22.0.2', 47492)
app_1    | (7) accepted ('172.22.0.2', 47494)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:02] "POST /socket.io/?EIO=3&transport=polling&t=NrHJaCa&sid=12a33a016c91466cb055280270f5545b HTTP/1.1" 200 143 0.003265
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:02] "GET /socket.io/?EIO=3&transport=websocket&sid=12a33a016c91466cb055280270f5545b HTTP/1.1" 200 155 54.319464
app_1    | (7) accepted ('172.22.0.2', 47498)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:03] "GET /socket.io/?EIO=3&transport=polling&t=NrHJaWj HTTP/1.1" 200 347 0.004116
app_1    | (7) accepted ('172.22.0.2', 47500)
app_1    | (7) accepted ('172.22.0.2', 47502)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:33] "GET /socket.io/?EIO=3&transport=polling&t=NrHJT9t&sid=12a33a016c91466cb055280270f5545b HTTP/1.1" 400 164 60.019278
app_1    | (7) accepted ('172.22.0.2', 47578)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:51] "GET /API/pico/canUsePicoStill?picoUid=baa4a7c24f9aa6b149aadbe63462ebb2&picoStillUid=30aea47edbb8 HTTP/1.0" 200 171 0.037364
app_1    | (7) accepted ('172.22.0.2', 47580)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:51] "GET /API/pico/hasCleanedAck?picoUid=baa4a7c24f9aa6b149aadbe63462ebb2&picoStillUid=30aea47edbb8 HTTP/1.0" 200 171 0.022648
app_1    | (7) accepted ('172.22.0.2', 47608)
app_1    | (7) accepted ('172.22.0.2', 47610)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:54] "GET /socket.io/?EIO=3&transport=polling&t=NrHJT9t&sid=12a33a016c91466cb055280270f5545b HTTP/1.1" 400 201 0.002362
app_1    | (7) accepted ('172.22.0.2', 47612)
app_1    | (7) accepted ('172.22.0.2', 47614)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:54] "POST /socket.io/?EIO=3&transport=polling&t=NrHJmsd&sid=a5483760bdb7489bb445fb4157d260a9 HTTP/1.1" 200 143 0.002867
app_1    | 172.22.0.2 - - [24/Nov/2021 09:37:54] "GET /socket.io/?EIO=3&transport=websocket&sid=a5483760bdb7489bb445fb4157d260a9 HTTP/1.1" 200 159 50.846989
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:02] "GET /socket.io/?EIO=3&transport=polling&t=NrHJT9t&sid=12a33a016c91466cb055280270f5545b HTTP/1.1" 400 164 60.008212
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:03] "GET /socket.io/?EIO=3&transport=polling&t=NrHJaXV&sid=a5483760bdb7489bb445fb4157d260a9 HTTP/1.1" 400 164 60.005370
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:03] "GET /socket.io/?EIO=3&transport=websocket&sid=a5483760bdb7489bb445fb4157d260a9 HTTP/1.1" 200 159 9.352514
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:03] "GET /socket.io/?EIO=3&transport=polling&t=NrHJaXV&sid=a5483760bdb7489bb445fb4157d260a9 HTTP/1.1" 200 155 9.102432
app_1    | (7) accepted ('172.22.0.2', 47616)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:03] "GET /socket.io/?EIO=3&transport=polling&t=NrHJpDZ&sid=a5483760bdb7489bb445fb4157d260a9 HTTP/1.1" 400 201 0.002217
app_1    | (7) accepted ('172.22.0.2', 47618)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:03] "POST /socket.io/?EIO=3&transport=polling&t=NrHJpDv&sid=a5483760bdb7489bb445fb4157d260a9 HTTP/1.1" 400 201 0.002559
app_1    | (7) accepted ('172.22.0.2', 47620)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:04] "GET /socket.io/?EIO=3&transport=polling&t=NrHJpX_ HTTP/1.1" 200 347 0.003973
app_1    | (7) accepted ('172.22.0.2', 47622)
app_1    | (7) accepted ('172.22.0.2', 47624)
app_1    | (7) accepted ('172.22.0.2', 47654)
app_1    | \10.4.2.231,172.22.0.2 - - [24/Nov/2021 09:38:09] "GET / HTTP/1.0" 200 14801 0.055084
app_1    | (7) accepted ('172.22.0.2', 47656)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:09] "GET /socket.io/?EIO=3&transport=polling&t=NrHJqex HTTP/1.1" 200 347 0.003609
app_1    | (7) accepted ('172.22.0.2', 47658)
app_1    | (7) accepted ('172.22.0.2', 47660)
app_1    | (7) accepted ('172.22.0.2', 47668)
app_1    | [2021-11-24 09:38:11,452] DEBUG in model: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | \10.4.2.231,172.22.0.2 - - [24/Nov/2021 09:38:11] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 188 0.188370
app_1    | [2021-11-24 09:38:11,630] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:38:11,681] DEBUG in still_polling: DEBUG: Still Datastring: #43.46,43.08,44.65,48.39,3.69,1,1,1,None#
app_1    | (7) accepted ('172.22.0.2', 47674)
app_1    | \10.4.2.231,172.22.0.2 - - [24/Nov/2021 09:38:13] "GET / HTTP/1.0" 200 14937 0.042979
app_1    | (7) accepted ('172.22.0.2', 47676)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:13] "GET /socket.io/?EIO=3&transport=polling&t=NrHJrYP HTTP/1.1" 200 347 0.004889
app_1    | (7) accepted ('172.22.0.2', 47678)
app_1    | (7) accepted ('172.22.0.2', 47680)
app_1    | (7) accepted ('172.22.0.2', 47690)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:38:23] "GET /socket.io/?EIO=3&transport=polling&t=NrHJt-D HTTP/1.1" 200 347 0.003746
app_1    | (7) accepted ('172.22.0.2', 47692)
app_1    | (7) accepted ('172.22.0.2', 47698)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:05] "GET /socket.io/?EIO=3&transport=websocket&sid=bc4200d689f54348adde0737b462eda0 HTTP/1.1" 400 164 60.025989
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:05] "GET /socket.io/?EIO=3&transport=polling&t=NrHJpYa&sid=bc4200d689f54348adde0737b462eda0 HTTP/1.1" 400 164 60.027650
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:09] "GET /socket.io/?EIO=3&transport=polling&t=NrHJqid&sid=86fb0e2e45394d26822cfef97afb929a HTTP/1.1" 400 164 60.006137
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:09] "GET /socket.io/?EIO=3&transport=websocket&sid=86fb0e2e45394d26822cfef97afb929a HTTP/1.1" 400 164 60.003947
app_1    | [2021-11-24 09:39:11,744] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:39:11,848] DEBUG in still_polling: DEBUG: Still Datastring: #43.55,43.32,44.65,42.43,2.87,1,1,1,None#
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:13] "GET /socket.io/?EIO=3&transport=polling&t=NrHJrb3&sid=b21107f8abc8434b8bb00585e7b2181a HTTP/1.1" 400 164 60.006396
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:13] "GET /socket.io/?EIO=3&transport=websocket&sid=b21107f8abc8434b8bb00585e7b2181a HTTP/1.1" 400 164 60.003699
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:23] "GET /socket.io/?EIO=3&transport=polling&t=NrHJu3f&sid=a5a4e90968e840e18552a81c56adfb9f HTTP/1.1" 400 164 60.009661
app_1    | 172.22.0.2 - - [24/Nov/2021 09:39:23] "GET /socket.io/?EIO=3&transport=websocket&sid=a5a4e90968e840e18552a81c56adfb9f HTTP/1.1" 400 164 60.003987
app_1    | [2021-11-24 09:40:11,914] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:40:12,008] DEBUG in still_polling: DEBUG: Still Datastring: #43.60,43.32,44.65,41.89,2.80,1,1,1,None#
app_1    | [2021-11-24 09:41:12,074] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:41:12,177] DEBUG in still_polling: DEBUG: Still Datastring: #43.60,43.36,44.58,41.84,2.77,1,1,1,None#
app_1    | (7) accepted ('172.22.0.2', 48022)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:41:55] "GET /socket.io/?EIO=3&transport=polling&t=NrHJu3f&sid=a5a4e90968e840e18552a81c56adfb9f HTTP/1.1" 400 201 0.002506
app_1    | (7) accepted ('172.22.0.2', 48024)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:41:55] "GET /socket.io/?EIO=3&transport=websocket&sid=a5a4e90968e840e18552a81c56adfb9f HTTP/1.1" 400 201 0.002228
app_1    | (7) accepted ('172.22.0.2', 48026)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:41:55] "POST /socket.io/?EIO=3&transport=polling&t=NrHKha7&sid=a5a4e90968e840e18552a81c56adfb9f HTTP/1.1" 400 201 0.002363
app_1    | (7) accepted ('172.22.0.2', 48028)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:41:55] "POST /socket.io/?EIO=3&transport=polling&t=NrHKhqw&sid=a5a4e90968e840e18552a81c56adfb9f HTTP/1.1" 400 201 0.002344
app_1    | (7) accepted ('172.22.0.2', 48030)
app_1    | 172.22.0.2 - - [24/Nov/2021 09:41:56] "GET /socket.io/?EIO=3&transport=polling&t=NrHKi3J HTTP/1.1" 200 347 0.018295
app_1    | (7) accepted ('172.22.0.2', 48032)
app_1    | (7) accepted ('172.22.0.2', 48034)
app_1    | [2021-11-24 09:42:12,244] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 09:42:12,303] DEBUG in still_polling: DEBUG: Still Datastring: #43.60,43.27,44.65,41.79,2.79,1,1,1,None#

@erdoukki
Copy link
Contributor Author

erdoukki commented Nov 24, 2021

MORE ...

app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:04] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 418 324 3.392784
app_1    | (8) accepted ('172.22.0.3', 39706)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:22] "POST /socket.io/?EIO=3&transport=polling&t=NrHPbxU&sid=33e9ede8dfec4c778f5ed5a14a175973 HTTP/1.1" 200 143 0.015369
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:22] "GET /socket.io/?EIO=3&transport=polling&t=NrHPVqq&sid=33e9ede8dfec4c778f5ed5a14a175973 HTTP/1.1" 200 159 25.024690
app_1    | (8) accepted ('172.22.0.3', 39708)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:27] "GET /socket.io/?EIO=3&transport=websocket&sid=31b429bb3b774c0483002a44b08df61a HTTP/1.1" 400 164 60.004010
app_1    | (8) accepted ('172.22.0.3', 39752)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:42] "GET /new_pico_recipe HTTP/1.0" 200 9945 0.117146
app_1    | (8) accepted ('172.22.0.3', 39754)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:42] "POST /socket.io/?EIO=3&transport=polling&t=NrHPguZ&sid=33e9ede8dfec4c778f5ed5a14a175973 HTTP/1.1" 200 143 0.008003
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:42] "GET /socket.io/?EIO=3&transport=websocket&sid=33e9ede8dfec4c778f5ed5a14a175973 HTTP/1.1" 200 155 45.516271
app_1    | (8) accepted ('172.22.0.3', 39764)
app_1    | (8) accepted ('172.22.0.3', 39766)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:43] "GET /static/js/convert_units.js HTTP/1.0" 200 4701 0.015963
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:43] "GET /static/js/pico_image.js HTTP/1.0" 200 5271 0.015079
app_1    | (8) accepted ('172.22.0.3', 39768)
app_1    | (8) accepted ('172.22.0.3', 39770)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:43] "GET /static/js/base_recipe.js HTTP/1.0" 200 2352 0.014876
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:43] "GET /static/js/pico_recipe.js HTTP/1.0" 200 14642 0.016692
app_1    | (8) accepted ('172.22.0.3', 39772)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:43] "GET /static/css/webfonts/fa-regular-400.woff2 HTTP/1.0" 200 13903 0.043636
app_1    | (8) accepted ('172.22.0.3', 39794)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:49] "GET / HTTP/1.0" 200 14803 0.048510
app_1    | (8) accepted ('172.22.0.3', 39796)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:03:50] "GET /socket.io/?EIO=3&transport=polling&t=NrHPixi HTTP/1.1" 200 347 0.013922
app_1    | (8) accepted ('172.22.0.3', 39798)
app_1    | (8) accepted ('172.22.0.3', 39800)
app_1    | (8) accepted ('172.22.0.3', 39876)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:04:16] "POST /socket.io/?EIO=3&transport=polling&t=NrHPp7y&sid=eb2f2277f94b4aa8b916754b14d2d408 HTTP/1.1" 200 143 0.002985
app_1    | 172.22.0.3 - - [24/Nov/2021 10:04:16] "GET /socket.io/?EIO=3&transport=polling&t=NrHPj1J&sid=eb2f2277f94b4aa8b916754b14d2d408 HTTP/1.1" 200 159 24.947399
app_1    | (8) accepted ('172.22.0.3', 39878)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:04:22] "GET /socket.io/?EIO=3&transport=polling&t=NrHPbyz&sid=33e9ede8dfec4c778f5ed5a14a175973 HTTP/1.1" 400 164 60.010415
app_1    | (8) accepted ('172.22.0.3', 39940)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:04:41] "POST /socket.io/?EIO=3&transport=polling&t=NrHPvFY&sid=eb2f2277f94b4aa8b916754b14d2d408 HTTP/1.1" 200 143 0.003072
app_1    | 172.22.0.3 - - [24/Nov/2021 10:04:41] "GET /socket.io/?EIO=3&transport=websocket&sid=eb2f2277f94b4aa8b916754b14d2d408 HTTP/1.1" 200 159 49.980131
app_1    | 172.22.0.3 - - [24/Nov/2021 10:05:16] "GET /socket.io/?EIO=3&transport=polling&t=NrHPp8w&sid=eb2f2277f94b4aa8b916754b14d2d408 HTTP/1.1" 400 164 60.103644
app_1    | (8) accepted ('172.22.0.3', 40038)
app_1    | Invalid session eb2f2277f94b4aa8b916754b14d2d408 (further occurrences of this error will be logged with level INFO)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:05:16] "POST /socket.io/?EIO=3&transport=polling&t=NrHQ1qb&sid=eb2f2277f94b4aa8b916754b14d2d408 HTTP/1.1" 400 201 0.025358
app_1    | (8) accepted ('172.22.0.3', 40044)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:05:17] "GET /socket.io/?EIO=3&transport=polling&t=NrHQ28E HTTP/1.1" 200 347 0.035164
app_1    | (8) accepted ('172.22.0.3', 40046)
app_1    | (8) accepted ('172.22.0.3', 40048)
app_1    | (8) accepted ('172.22.0.3', 40098)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:05:42] "POST /socket.io/?EIO=3&transport=polling&t=NrHQ8GB&sid=7ff3b1a7f6c440ca954c568aff4ebd9a HTTP/1.1" 200 143 0.003278
app_1    | 172.22.0.3 - - [24/Nov/2021 10:05:42] "GET /socket.io/?EIO=3&transport=polling&t=NrHQ29Z&sid=7ff3b1a7f6c440ca954c568aff4ebd9a HTTP/1.1" 200 159 25.039385
app_1    | (8) accepted ('172.22.0.3', 40102)
app_1    | (8) accepted ('172.22.0.3', 40212)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:07] "POST /socket.io/?EIO=3&transport=polling&t=NrHQENu&sid=7ff3b1a7f6c440ca954c568aff4ebd9a HTTP/1.1" 200 143 0.033222
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:07] "GET /socket.io/?EIO=3&transport=websocket&sid=7ff3b1a7f6c440ca954c568aff4ebd9a HTTP/1.1" 200 159 50.164385
app_1    | (8) accepted ('172.22.0.3', 40276)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:28] "GET /API/pico/register?uid=baa4a7c24f9aa6b149aadbe63462ebb2 HTTP/1.0" 200 171 0.078395
app_1    | (8) accepted ('172.22.0.3', 40278)
app_1    | (8) accepted ('172.22.0.3', 40284)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:29] "GET /API/pico/checkFirmware?uid=baa4a7c24f9aa6b149aadbe63462ebb2&version=0.1.34 HTTP/1.0" 200 169 0.038205
app_1    | (8) accepted ('172.22.0.3', 40286)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:29] "GET /API/pico/getActionsNeeded?uid=baa4a7c24f9aa6b149aadbe63462ebb2 HTTP/1.0" 200 168 0.024118
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:42] "GET /socket.io/?EIO=3&transport=polling&t=NrHQ8HE&sid=7ff3b1a7f6c440ca954c568aff4ebd9a HTTP/1.1" 400 164 60.018227
app_1    | (8) accepted ('172.22.0.3', 40294)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:42] "POST /socket.io/?EIO=3&transport=polling&t=NrHQMx-&sid=7ff3b1a7f6c440ca954c568aff4ebd9a HTTP/1.1" 400 201 0.003792
app_1    | (8) accepted ('172.22.0.3', 40302)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:43] "GET /socket.io/?EIO=3&transport=polling&t=NrHQNCR HTTP/1.1" 200 347 0.021169
app_1    | (8) accepted ('172.22.0.3', 40304)
app_1    | (8) accepted ('172.22.0.3', 40306)
app_1    | (8) accepted ('172.22.0.3', 40338)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:06:58] "GET /API/pico/canUsePicoStill?picoUid=baa4a7c24f9aa6b149aadbe63462ebb2&picoStillUid=30aea47edbb8 HTTP/1.0" 200 171 0.095493
app_1    | (8) accepted ('172.22.0.3', 40346)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:07:00] "GET /API/pico/hasCleanedAck?picoUid=baa4a7c24f9aa6b149aadbe63462ebb2&picoStillUid=30aea47edbb8 HTTP/1.0" 200 171 0.042559
app_1    | (8) accepted ('172.22.0.3', 40356)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:07:08] "POST /socket.io/?EIO=3&transport=polling&t=NrHQTKC&sid=d2cca36be3e04d788dae82880724daec HTTP/1.1" 200 143 0.014066
app_1    | 172.22.0.3 - - [24/Nov/2021 10:07:08] "GET /socket.io/?EIO=3&transport=websocket&sid=d2cca36be3e04d788dae82880724daec HTTP/1.1" 200 159 25.027884
app_1    | 172.22.0.3 - - [24/Nov/2021 10:07:43] "GET /socket.io/?EIO=3&transport=polling&t=NrHQNDb&sid=d2cca36be3e04d788dae82880724daec HTTP/1.1" 400 164 60.034224
app_1    | (8) accepted ('172.22.0.3', 40408)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:07:44] "POST /socket.io/?EIO=3&transport=polling&t=NrHQbuU&sid=d2cca36be3e04d788dae82880724daec HTTP/1.1" 400 201 0.008080
app_1    | (8) accepted ('172.22.0.3', 40414)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:07:45] "GET /socket.io/?EIO=3&transport=polling&t=NrHQcCj HTTP/1.1" 200 347 0.012522
app_1    | (8) accepted ('172.22.0.3', 40416)
app_1    | (8) accepted ('172.22.0.3', 40418)
app_1    | (8) accepted ('172.22.0.3', 40490)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:10] "POST /socket.io/?EIO=3&transport=polling&t=NrHQiK5&sid=2fcc6e362d7d4399bbf1662705a844f8 HTTP/1.1" 200 143 0.008036
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:10] "GET /socket.io/?EIO=3&transport=polling&t=NrHQcDT&sid=2fcc6e362d7d4399bbf1662705a844f8 HTTP/1.1" 200 159 25.020548
app_1    | (8) accepted ('172.22.0.3', 40492)
app_1    | (8) accepted ('172.22.0.3', 40512)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:22] "GET /socket.io/?EIO=3&transport=polling&t=NrHQlCg HTTP/1.1" 200 347 0.011779
app_1    | (8) accepted ('172.22.0.3', 40514)
app_1    | (8) accepted ('172.22.0.3', 40516)
app_1    | (8) accepted ('172.22.0.3', 40518)
app_1    | \10.4.2.231,172.22.0.3 - - [24/Nov/2021 10:08:24] "GET / HTTP/1.0" 200 14803 0.099090
app_1    | (8) accepted ('172.22.0.3', 40522)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:24] "GET /socket.io/?EIO=3&transport=polling&t=NrHQls3 HTTP/1.1" 200 347 0.003351
app_1    | (8) accepted ('172.22.0.3', 40524)
app_1    | (8) accepted ('172.22.0.3', 40526)
app_1    | (8) accepted ('172.22.0.3', 40562)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:35] "POST /socket.io/?EIO=3&transport=polling&t=NrHQoRm&sid=2fcc6e362d7d4399bbf1662705a844f8 HTTP/1.1" 200 143 0.004123
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:35] "GET /socket.io/?EIO=3&transport=websocket&sid=2fcc6e362d7d4399bbf1662705a844f8 HTTP/1.1" 200 159 50.044196
app_1    | (8) accepted ('172.22.0.3', 40570)
app_1    | \10.4.2.231,172.22.0.3 - - [24/Nov/2021 10:08:37] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 201 0.018102
app_1    | (8) accepted ('172.22.0.3', 40576)
app_1    | [2021-11-24 10:08:38,379] DEBUG in model: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | \10.4.2.231,172.22.0.3 - - [24/Nov/2021 10:08:38] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 201 0.288086
app_1    | [2021-11-24 10:08:38,664] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 10:08:38,735] DEBUG in still_polling: DEBUG: Still Datastring: #44.83,44.07,44.52,42.91,2.83,1,1,1,None#
app_1    | (8) accepted ('172.22.0.3', 40586)
app_1    | \10.4.2.231,172.22.0.3 - - [24/Nov/2021 10:08:39] "GET / HTTP/1.0" 200 14932 0.024440
app_1    | (8) accepted ('172.22.0.3', 40588)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:39] "GET /socket.io/?EIO=3&transport=polling&t=NrHQpVk HTTP/1.1" 200 347 0.011089
app_1    | (8) accepted ('172.22.0.3', 40590)
app_1    | (8) accepted ('172.22.0.3', 40592)
app_1    | (8) accepted ('172.22.0.3', 40606)
app_1    | \10.4.2.231,172.22.0.3 - - [24/Nov/2021 10:08:51] "GET / HTTP/1.0" 200 14932 0.020680
app_1    | (8) accepted ('172.22.0.3', 40608)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:08:51] "GET /socket.io/?EIO=3&transport=polling&t=NrHQsMw HTTP/1.1" 200 347 0.003170
app_1    | (8) accepted ('172.22.0.3', 40610)
app_1    | (8) accepted ('172.22.0.3', 40612)
app_1    | (8) accepted ('172.22.0.3', 40674)
app_1    | \10.4.2.231,172.22.0.3 - - [24/Nov/2021 10:09:08] "GET / HTTP/1.0" 200 14932 0.037765
app_1    | (8) accepted ('172.22.0.3', 40676)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:08] "GET /socket.io/?EIO=3&transport=polling&t=NrHQwcW HTTP/1.1" 200 347 0.004359
app_1    | (8) accepted ('172.22.0.3', 40678)
app_1    | (8) accepted ('172.22.0.3', 40680)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:10] "GET /socket.io/?EIO=3&transport=polling&t=NrHQiL8&sid=2fcc6e362d7d4399bbf1662705a844f8 HTTP/1.1" 400 164 60.006499
app_1    | (8) accepted ('172.22.0.3', 40684)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:10] "POST /socket.io/?EIO=3&transport=polling&t=NrHQw_c&sid=2fcc6e362d7d4399bbf1662705a844f8 HTTP/1.1" 400 201 0.007466
app_1    | (8) accepted ('192.241.214.58', 39158)
app_1    | 192.241.214.58 - - [24/Nov/2021 10:09:10] "GET / HTTP/1.1" 200 14913 0.077300
app_1    | (8) accepted ('172.22.0.3', 40688)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:11] "GET /socket.io/?EIO=3&transport=polling&t=NrHQxLB HTTP/1.1" 200 347 0.013917
app_1    | (8) accepted ('172.22.0.3', 40690)
app_1    | (8) accepted ('172.22.0.3', 40692)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:22] "GET /socket.io/?EIO=3&transport=polling&t=NrHQlF6&sid=73893d29ddd2432584281df6a6635fc7 HTTP/1.1" 400 164 60.008204
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:22] "GET /socket.io/?EIO=3&transport=websocket&sid=73893d29ddd2432584281df6a6635fc7 HTTP/1.1" 400 164 60.003513
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:25] "GET /socket.io/?EIO=3&transport=polling&t=NrHQlvO&sid=eac120826c7641fb9598df19f2e56cec HTTP/1.1" 400 164 60.006190
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:25] "GET /socket.io/?EIO=3&transport=websocket&sid=eac120826c7641fb9598df19f2e56cec HTTP/1.1" 400 164 60.003180
app_1    | (8) accepted ('172.22.0.3', 40754)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:34] "POST /socket.io/?EIO=3&transport=polling&t=NrHR0m0&sid=cb93fa806e4740d185227cd5d72cf88d HTTP/1.1" 200 143 0.012600
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:34] "GET /socket.io/?EIO=3&transport=websocket&sid=cb93fa806e4740d185227cd5d72cf88d HTTP/1.1" 200 159 25.114073
app_1    | (8) accepted ('172.22.0.3', 40760)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:36] "POST /socket.io/?EIO=3&transport=polling&t=NrHR1St&sid=9e32e1de9fe94203a7a3273ef771844f HTTP/1.1" 200 143 0.006591
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:36] "GET /socket.io/?EIO=3&transport=polling&t=NrHQxMG&sid=9e32e1de9fe94203a7a3273ef771844f HTTP/1.1" 200 159 25.020822
app_1    | (8) accepted ('172.22.0.3', 40762)
app_1    | [2021-11-24 10:09:38,811] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 10:09:38,933] DEBUG in still_polling: DEBUG: Still Datastring: #44.59,44.07,44.58,42.57,2.81,1,1,1,None#
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:39] "GET /socket.io/?EIO=3&transport=polling&t=NrHQpYJ&sid=785d8ec0a1cd46789d019cc517eaf0ef HTTP/1.1" 400 164 60.018177
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:40] "GET /socket.io/?EIO=3&transport=websocket&sid=785d8ec0a1cd46789d019cc517eaf0ef HTTP/1.1" 400 164 60.003827
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:51] "GET /socket.io/?EIO=3&transport=polling&t=NrHQsPJ&sid=f1ecd464f02e4392aa80bb14cee0268c HTTP/1.1" 400 164 60.008997
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:51] "GET /socket.io/?EIO=3&transport=websocket&sid=f1ecd464f02e4392aa80bb14cee0268c HTTP/1.1" 400 164 60.004091
app_1    | (8) accepted ('172.22.0.3', 40824)
app_1    | \10.4.2.231,172.22.0.3 - - [24/Nov/2021 10:09:59] "GET / HTTP/1.0" 200 15071 0.026406
app_1    | (8) accepted ('172.22.0.3', 40826)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:09:59] "GET /socket.io/?EIO=3&transport=polling&t=NrHR70x HTTP/1.1" 200 347 0.004007
app_1    | (8) accepted ('172.22.0.3', 40828)
app_1    | (8) accepted ('172.22.0.3', 40830)
app_1    | (8) accepted ('172.22.0.3', 40838)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:01] "POST /socket.io/?EIO=3&transport=polling&t=NrHR7ah&sid=9e32e1de9fe94203a7a3273ef771844f HTTP/1.1" 200 143 0.002420
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:09] "GET /socket.io/?EIO=3&transport=polling&t=NrHQwez&sid=cb93fa806e4740d185227cd5d72cf88d HTTP/1.1" 400 164 60.005595
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:11] "GET /socket.io/?EIO=3&transport=websocket&sid=9e32e1de9fe94203a7a3273ef771844f HTTP/1.1" 400 164 60.004870
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:37] "GET /socket.io/?EIO=3&transport=polling&t=NrHR1U3&sid=9e32e1de9fe94203a7a3273ef771844f HTTP/1.1" 400 164 60.008186
app_1    | (8) accepted ('172.22.0.3', 40862)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:37] "POST /socket.io/?EIO=3&transport=polling&t=NrHRG8w&sid=9e32e1de9fe94203a7a3273ef771844f HTTP/1.1" 400 201 0.003396
app_1    | (8) accepted ('172.22.0.3', 40864)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:38] "GET /socket.io/?EIO=3&transport=polling&t=NrHRGOT HTTP/1.1" 200 347 0.006111
app_1    | (8) accepted ('172.22.0.3', 40866)
app_1    | (8) accepted ('172.22.0.3', 40868)
app_1    | [2021-11-24 10:10:39,027] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 10:10:57,977] DEBUG in still_polling: DEBUG: Connection failure 1
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:58] "GET /socket.io/?EIO=3&transport=websocket&sid=bd669380e8c74376815051e1e8bddc9d HTTP/1.1" 200 155 58.827012
app_1    | 172.22.0.3 - - [24/Nov/2021 10:10:59] "GET /socket.io/?EIO=3&transport=polling&t=NrHR74P&sid=bd669380e8c74376815051e1e8bddc9d HTTP/1.1" 400 164 60.014111
app_1    | (8) accepted ('172.22.0.3', 40900)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:11:03] "POST /socket.io/?EIO=3&transport=polling&t=NrHRMW4&sid=78617a728814490899dc177bb3b28d90 HTTP/1.1" 200 143 0.009969
app_1    | 172.22.0.3 - - [24/Nov/2021 10:11:03] "GET /socket.io/?EIO=3&transport=polling&t=NrHRGPR&sid=78617a728814490899dc177bb3b28d90 HTTP/1.1" 200 159 25.052859
app_1    | (8) accepted ('172.22.0.3', 40902)
app_1    | (8) accepted ('172.22.0.3', 40924)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:11:28] "POST /socket.io/?EIO=3&transport=polling&t=NrHRSeU&sid=78617a728814490899dc177bb3b28d90 HTTP/1.1" 200 143 0.008848
app_1    | 172.22.0.3 - - [24/Nov/2021 10:11:28] "GET /socket.io/?EIO=3&transport=polling&t=NrHRMXr&sid=78617a728814490899dc177bb3b28d90 HTTP/1.1" 200 159 25.085755
app_1    | (8) accepted ('172.22.0.3', 40926)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:11:38] "GET /socket.io/?EIO=3&transport=websocket&sid=78617a728814490899dc177bb3b28d90 HTTP/1.1" 400 164 60.010690
app_1    | 172.22.0.3 - - [24/Nov/2021 10:11:38] "GET /socket.io/?EIO=3&transport=polling&t=NrHRSgZ&sid=78617a728814490899dc177bb3b28d90 HTTP/1.1" 200 159 9.797591
app_1    | (8) accepted ('172.22.0.3', 40930)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:11:39] "GET /socket.io/?EIO=3&transport=polling&t=NrHRVOo HTTP/1.1" 200 347 0.005373
app_1    | (8) accepted ('172.22.0.3', 40932)
app_1    | (8) accepted ('172.22.0.3', 40934)
app_1    | [2021-11-24 10:11:58,035] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 10:12:01,248] DEBUG in still_polling: DEBUG: Connection failure 2
app_1    | (8) accepted ('172.22.0.3', 40974)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:12:04] "POST /socket.io/?EIO=3&transport=polling&t=NrHRbWF&sid=d340515f521c404395d7777a8f126744 HTTP/1.1" 200 143 0.005563
app_1    | 172.22.0.3 - - [24/Nov/2021 10:12:04] "GET /socket.io/?EIO=3&transport=polling&t=NrHRVPd&sid=d340515f521c404395d7777a8f126744 HTTP/1.1" 200 159 25.021538
app_1    | (8) accepted ('172.22.0.3', 40976)
app_1    | (8) accepted ('172.22.0.3', 40996)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:12:29] "POST /socket.io/?EIO=3&transport=polling&t=NrHRhe1&sid=d340515f521c404395d7777a8f126744 HTTP/1.1" 200 143 0.011626
app_1    | 172.22.0.3 - - [24/Nov/2021 10:12:29] "GET /socket.io/?EIO=3&transport=polling&t=NrHRbXP&sid=d340515f521c404395d7777a8f126744 HTTP/1.1" 200 159 25.004099
app_1    | (8) accepted ('172.22.0.3', 40998)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:12:39] "GET /socket.io/?EIO=3&transport=websocket&sid=d340515f521c404395d7777a8f126744 HTTP/1.1" 400 164 60.004711
app_1    | 172.22.0.3 - - [24/Nov/2021 10:12:39] "GET /socket.io/?EIO=3&transport=polling&t=NrHRhf2&sid=d340515f521c404395d7777a8f126744 HTTP/1.1" 200 159 9.889492
app_1    | (8) accepted ('172.22.0.3', 41012)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:12:40] "GET /socket.io/?EIO=3&transport=polling&t=NrHRkEX HTTP/1.1" 200 347 0.014002
app_1    | (8) accepted ('172.22.0.3', 41014)
app_1    | (8) accepted ('172.22.0.3', 41016)
app_1    | [2021-11-24 10:13:01,304] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 10:13:04,446] DEBUG in still_polling: DEBUG: Connection failure 3
app_1    | (8) accepted ('172.22.0.3', 41052)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:13:05] "POST /socket.io/?EIO=3&transport=polling&t=NrHRqLr&sid=eec7ad1245214af0841d7a668d840eb8 HTTP/1.1" 200 143 0.002685
app_1    | 172.22.0.3 - - [24/Nov/2021 10:13:05] "GET /socket.io/?EIO=3&transport=websocket&sid=eec7ad1245214af0841d7a668d840eb8 HTTP/1.1" 200 159 24.981579
app_1    | 172.22.0.3 - - [24/Nov/2021 10:13:40] "GET /socket.io/?EIO=3&transport=polling&t=NrHRkFD&sid=eec7ad1245214af0841d7a668d840eb8 HTTP/1.1" 400 164 60.025319
app_1    | (8) accepted ('172.22.0.3', 41076)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:13:40] "POST /socket.io/?EIO=3&transport=polling&t=NrHRywd&sid=eec7ad1245214af0841d7a668d840eb8 HTTP/1.1" 400 201 0.003574
app_1    | (8) accepted ('172.22.0.3', 41078)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:13:41] "GET /socket.io/?EIO=3&transport=polling&t=NrHRzFq HTTP/1.1" 200 347 0.007258
app_1    | (8) accepted ('172.22.0.3', 41080)
app_1    | (8) accepted ('172.22.0.3', 41082)
app_1    | (8) accepted ('172.22.0.3', 41114)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:14:06] "POST /socket.io/?EIO=3&transport=polling&t=NrHS3NB&sid=9602f90816ab426280d02661cbead691 HTTP/1.1" 200 143 0.021029
app_1    | 172.22.0.3 - - [24/Nov/2021 10:14:06] "GET /socket.io/?EIO=3&transport=websocket&sid=9602f90816ab426280d02661cbead691 HTTP/1.1" 200 159 25.013009
app_1    | 172.22.0.3 - - [24/Nov/2021 10:14:41] "GET /socket.io/?EIO=3&transport=polling&t=NrHRzGY&sid=9602f90816ab426280d02661cbead691 HTTP/1.1" 400 164 60.015865
app_1    | (8) accepted ('172.22.0.3', 41142)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:14:41] "POST /socket.io/?EIO=3&transport=polling&t=NrHSBw-&sid=9602f90816ab426280d02661cbead691 HTTP/1.1" 400 201 0.003342
app_1    | (8) accepted ('172.22.0.3', 41144)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:14:42] "GET /socket.io/?EIO=3&transport=polling&t=NrHSC3k HTTP/1.1" 200 347 0.005551
app_1    | (8) accepted ('172.22.0.3', 41146)
app_1    | (8) accepted ('172.22.0.3', 41148)
app_1    | (8) accepted ('172.22.0.3', 41198)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:15:07] "POST /socket.io/?EIO=3&transport=polling&t=NrHSIB8&sid=af816af6db604a478f2fa124d222375d HTTP/1.1" 200 143 0.010273
app_1    | 172.22.0.3 - - [24/Nov/2021 10:15:07] "GET /socket.io/?EIO=3&transport=polling&t=NrHSC4V&sid=af816af6db604a478f2fa124d222375d HTTP/1.1" 200 159 25.099454
app_1    | (8) accepted ('172.22.0.3', 41200)
app_1    | (8) accepted ('172.22.0.3', 41228)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:15:32] "POST /socket.io/?EIO=3&transport=polling&t=NrHSOKC&sid=af816af6db604a478f2fa124d222375d HTTP/1.1" 200 143 0.007262
app_1    | 172.22.0.3 - - [24/Nov/2021 10:15:32] "GET /socket.io/?EIO=3&transport=websocket&sid=af816af6db604a478f2fa124d222375d HTTP/1.1" 200 159 50.218053
app_1    | 172.22.0.3 - - [24/Nov/2021 10:16:07] "GET /socket.io/?EIO=3&transport=polling&t=NrHSIDa&sid=af816af6db604a478f2fa124d222375d HTTP/1.1" 400 164 60.063370
app_1    | (8) accepted ('172.22.0.3', 41274)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:16:07] "POST /socket.io/?EIO=3&transport=polling&t=NrHSWuc&sid=af816af6db604a478f2fa124d222375d HTTP/1.1" 400 201 0.004693
app_1    | (8) accepted ('172.22.0.3', 41276)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:16:09] "GET /socket.io/?EIO=3&transport=polling&t=NrHSXCs HTTP/1.1" 200 347 0.011525
app_1    | (8) accepted ('172.22.0.3', 41278)
app_1    | (8) accepted ('172.22.0.3', 41280)
app_1    | (8) accepted ('172.22.0.3', 41288)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:16:34] "POST /socket.io/?EIO=3&transport=polling&t=NrHSdKO&sid=ca79e9bcdbb14dce904c619f24bfa374 HTTP/1.1" 200 143 0.014148
app_1    | 172.22.0.3 - - [24/Nov/2021 10:16:34] "GET /socket.io/?EIO=3&transport=websocket&sid=ca79e9bcdbb14dce904c619f24bfa374 HTTP/1.1" 200 159 24.982178
app_1    | 172.22.0.3 - - [24/Nov/2021 10:17:09] "GET /socket.io/?EIO=3&transport=polling&t=NrHSXDl&sid=ca79e9bcdbb14dce904c619f24bfa374 HTTP/1.1" 400 164 60.006598
app_1    | (8) accepted ('172.22.0.3', 41336)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:17:09] "POST /socket.io/?EIO=3&transport=polling&t=NrHSluP&sid=ca79e9bcdbb14dce904c619f24bfa374 HTTP/1.1" 400 201 0.003040
app_1    | (8) accepted ('172.22.0.3', 41338)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:17:10] "GET /socket.io/?EIO=3&transport=polling&t=NrHSm7v HTTP/1.1" 200 347 0.004248
app_1    | (8) accepted ('172.22.0.3', 41340)
app_1    | (8) accepted ('172.22.0.3', 41342)
app_1    | (8) accepted ('172.22.0.3', 41346)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:17:35] "POST /socket.io/?EIO=3&transport=polling&t=NrHSsFK&sid=c0877123431543ff8363c9114bb8cb3f HTTP/1.1" 200 143 0.031693
app_1    | 172.22.0.3 - - [24/Nov/2021 10:17:35] "GET /socket.io/?EIO=3&transport=polling&t=NrHSm8i&sid=c0877123431543ff8363c9114bb8cb3f HTTP/1.1" 200 159 25.038579
app_1    | (8) accepted ('172.22.0.3', 41348)
app_1    | (8) accepted ('172.22.0.3', 41446)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:18:00] "POST /socket.io/?EIO=3&transport=polling&t=NrHSyNI&sid=c0877123431543ff8363c9114bb8cb3f HTTP/1.1" 200 143 0.015598
app_1    | 172.22.0.3 - - [24/Nov/2021 10:18:00] "GET /socket.io/?EIO=3&transport=websocket&sid=c0877123431543ff8363c9114bb8cb3f HTTP/1.1" 200 159 50.078107
app_1    | 172.22.0.3 - - [24/Nov/2021 10:18:35] "GET /socket.io/?EIO=3&transport=polling&t=NrHSsGf&sid=c0877123431543ff8363c9114bb8cb3f HTTP/1.1" 400 164 60.009345
app_1    | (8) accepted ('172.22.0.3', 41474)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:18:35] "POST /socket.io/?EIO=3&transport=polling&t=NrHT4x8&sid=c0877123431543ff8363c9114bb8cb3f HTTP/1.1" 400 201 0.003165
app_1    | (8) accepted ('172.22.0.3', 41476)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:18:36] "GET /socket.io/?EIO=3&transport=polling&t=NrHT5Af HTTP/1.1" 200 347 0.016243
app_1    | (8) accepted ('172.22.0.3', 41478)
app_1    | (8) accepted ('172.22.0.3', 41480)
app_1    | (8) accepted ('172.22.0.3', 41520)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:19:01] "POST /socket.io/?EIO=3&transport=polling&t=NrHTBI7&sid=ef75102f99c34b3aae61c3b641e7d4a4 HTTP/1.1" 200 143 0.004585
app_1    | 172.22.0.3 - - [24/Nov/2021 10:19:01] "GET /socket.io/?EIO=3&transport=websocket&sid=ef75102f99c34b3aae61c3b641e7d4a4 HTTP/1.1" 200 159 24.981636
app_1    | 172.22.0.3 - - [24/Nov/2021 10:19:36] "GET /socket.io/?EIO=3&transport=polling&t=NrHT5BV&sid=ef75102f99c34b3aae61c3b641e7d4a4 HTTP/1.1" 400 164 60.018132
app_1    | (8) accepted ('172.22.0.3', 41582)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:19:36] "POST /socket.io/?EIO=3&transport=polling&t=NrHTJr_&sid=ef75102f99c34b3aae61c3b641e7d4a4 HTTP/1.1" 400 201 0.008617
app_1    | (8) accepted ('172.22.0.3', 41584)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:19:37] "GET /socket.io/?EIO=3&transport=polling&t=NrHTJ_D HTTP/1.1" 200 347 0.014872
app_1    | (8) accepted ('172.22.0.3', 41586)
app_1    | (8) accepted ('172.22.0.3', 41588)
app_1    | (8) accepted ('172.22.0.3', 41624)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:02] "POST /socket.io/?EIO=3&transport=polling&t=NrHTQ6o&sid=2b9dbcb28845434ab82d7bd6b5037650 HTTP/1.1" 200 143 0.013210
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:02] "GET /socket.io/?EIO=3&transport=polling&t=NrHTK0B&sid=2b9dbcb28845434ab82d7bd6b5037650 HTTP/1.1" 200 159 25.060074
app_1    | (8) accepted ('172.22.0.3', 41628)
app_1    | (8) accepted ('172.22.0.3', 41644)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:27] "POST /socket.io/?EIO=3&transport=polling&t=NrHTWE_&sid=2b9dbcb28845434ab82d7bd6b5037650 HTTP/1.1" 200 143 0.003754
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:27] "GET /socket.io/?EIO=3&transport=websocket&sid=2b9dbcb28845434ab82d7bd6b5037650 HTTP/1.1" 200 159 50.089626
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:59] "GET /socket.io/?EIO=3&transport=polling&t=NrHTQ8N&sid=2b9dbcb28845434ab82d7bd6b5037650 HTTP/1.1" 200 155 56.812045
app_1    | (8) accepted ('172.22.0.3', 41680)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:59] "GET /socket.io/?EIO=3&transport=polling&t=NrHTe0q&sid=2b9dbcb28845434ab82d7bd6b5037650 HTTP/1.1" 400 201 0.008832
app_1    | (8) accepted ('172.22.0.3', 41682)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:59] "POST /socket.io/?EIO=3&transport=polling&t=NrHTe1p&sid=2b9dbcb28845434ab82d7bd6b5037650 HTTP/1.1" 400 201 0.022355
app_1    | (8) accepted ('172.22.0.3', 41684)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:20:59] "GET /socket.io/?EIO=3&transport=polling&t=NrHTeCI HTTP/1.1" 200 347 0.005592
app_1    | (8) accepted ('172.22.0.3', 41686)
app_1    | (8) accepted ('172.22.0.3', 41688)
app_1    | (8) accepted ('172.22.0.3', 41708)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:21:24] "POST /socket.io/?EIO=3&transport=polling&t=NrHTkJp&sid=c8ccd49a40e743feadd3dfb3ea3ad9dc HTTP/1.1" 200 143 0.003447
app_1    | 172.22.0.3 - - [24/Nov/2021 10:21:24] "GET /socket.io/?EIO=3&transport=websocket&sid=c8ccd49a40e743feadd3dfb3ea3ad9dc HTTP/1.1" 200 159 24.990498
app_1    | 172.22.0.3 - - [24/Nov/2021 10:21:59] "GET /socket.io/?EIO=3&transport=polling&t=NrHTeDB&sid=c8ccd49a40e743feadd3dfb3ea3ad9dc HTTP/1.1" 200 155 59.185395
app_1    | (8) accepted ('172.22.0.3', 41742)
app_1    | 172.22.0.3 - - [24/Nov/2021 10:21:59] "GET /socket.io/?EIO=3&transport=polling&t=NrHTsga&sid=c8ccd49a40e743feadd3dfb3ea3ad9dc HTTP/1.1" 400 201 0.002394


@tmack8001
Copy link
Collaborator

Hrm. I don't have a Pico small device series associated with my PicoStill (only Z).

From those access logs (appreciate that!) there isn't an obvious error that is happening, though there could be an invalid response structure returned to the PicoStill device, though from how I understand how the Still and the Pico communicate is via a direct network connection. How is your network for the Pico devices setup? Are you simply using the community RaspberryPi setup we provide or something more custom?

@erdoukki
Copy link
Contributor Author

The server is an EspressoBin ARM64 with OpenWrt 21.02 with docker.
My master router (also on OpenWrt) is on LAN 10.4.2.x and also the devices are, Pico and PicoStill.
The server has a 10.4.2.x IP but also a docker 172.22.x.x IP.

@tmack8001
Copy link
Collaborator

Do you know if the ip addresses of the PicoStill and the Pico C/s/Pro can communicate to each other?

@erdoukki
Copy link
Contributor Author

The PicoBrew server is starting the PicoStill.
The PicoStill and the PicoPro are on the same LAN/WiFi.

The server shows an empty embedded PicoStill data
image

@erdoukki
Copy link
Contributor Author

image

image

@erdoukki
Copy link
Contributor Author

image

@tmack8001
Copy link
Collaborator

The server shows an empty embedded PicoStill data

That happens if the Server can't communicate outgoing/egress to the detected IP address of the PicoStill.

Sorry I don't have further debug help, from what I've seen so far it is pointing to that the devices on the 10.4.2.x subnet aren't able to communicate to each other.

@erdoukki
Copy link
Contributor Author

erdoukki commented Nov 24, 2021

app_1    | 10.4.2.3 - - [24/Nov/2021 16:36:21] "POST /devices/30aea47edbb8 HTTP/1.0" 302 491 0.646268
app_1    | (7) accepted ('10.4.2.3', 60868)
MORE ...

app_1    | 10.4.2.3 - - [24/Nov/2021 16:36:21] "GET /devices HTTP/1.0" 200 20021 0.040874
app_1    | (7) accepted ('10.4.2.3', 60892)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:36:25] "GET / HTTP/1.0" 200 14801 0.077520
app_1    | (7) accepted ('10.4.2.3', 60894)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:36:26] "GET /socket.io/?EIO=3&transport=polling&t=NrIpZX- HTTP/1.1" 200 347 0.024532
app_1    | (7) accepted ('10.4.2.3', 60896)
app_1    | (7) accepted ('10.4.2.3', 60898)
app_1    | (7) accepted ('10.4.2.3', 60940)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:36:51] "POST /socket.io/?EIO=3&transport=polling&t=NrIpfhw&sid=0808664fe71f49279faff0f78668889a HTTP/1.1" 200 143 0.016899
app_1    | 10.4.2.3 - - [24/Nov/2021 16:36:51] "GET /socket.io/?EIO=3&transport=websocket&sid=0808664fe71f49279faff0f78668889a HTTP/1.1" 200 159 24.979646
app_1    | 10.4.2.3 - - [24/Nov/2021 16:37:06] "GET /socket.io/?EIO=3&transport=polling&t=NrIpUhS&sid=361a7d97d2a24eb7802ae01dace8bb03 HTTP/1.1" 400 164 60.006552
app_1    | 10.4.2.3 - - [24/Nov/2021 16:37:26] "GET /socket.io/?EIO=3&transport=polling&t=NrIpZbF&sid=0808664fe71f49279faff0f78668889a HTTP/1.1" 400 164 60.021675
app_1    | (7) accepted ('10.4.2.3', 32788)
app_1    | Invalid session 0808664fe71f49279faff0f78668889a (further occurrences of this error will be logged with level INFO)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:37:26] "POST /socket.io/?EIO=3&transport=polling&t=NrIpoIY&sid=0808664fe71f49279faff0f78668889a HTTP/1.1" 400 201 0.015902
app_1    | (7) accepted ('10.4.2.3', 32798)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:37:27] "GET /socket.io/?EIO=3&transport=polling&t=NrIpoUm HTTP/1.1" 200 347 0.016181
app_1    | (7) accepted ('10.4.2.3', 32804)
app_1    | (7) accepted ('10.4.2.3', 32806)
app_1    | (7) accepted ('10.4.2.3', 32888)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:37:52] "POST /socket.io/?EIO=3&transport=polling&t=NrIpuck&sid=5470c3277632448f81a01acca62cba86 HTTP/1.1" 200 143 0.009259
app_1    | 10.4.2.3 - - [24/Nov/2021 16:37:52] "GET /socket.io/?EIO=3&transport=polling&t=NrIpoW5&sid=5470c3277632448f81a01acca62cba86 HTTP/1.1" 200 159 24.994580
app_1    | (7) accepted ('10.4.2.3', 32890)
app_1    | (7) accepted ('10.4.2.3', 32996)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:38:17] "POST /socket.io/?EIO=3&transport=polling&t=NrIp-kK&sid=5470c3277632448f81a01acca62cba86 HTTP/1.1" 200 143 0.014007
app_1    | 10.4.2.3 - - [24/Nov/2021 16:38:17] "GET /socket.io/?EIO=3&transport=polling&t=NrIpudi&sid=5470c3277632448f81a01acca62cba86 HTTP/1.1" 200 159 25.016003
app_1    | (7) accepted ('10.4.2.3', 32998)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:38:27] "GET /socket.io/?EIO=3&transport=websocket&sid=5470c3277632448f81a01acca62cba86 HTTP/1.1" 400 164 60.003767
app_1    | 10.4.2.3 - - [24/Nov/2021 16:38:27] "GET /socket.io/?EIO=3&transport=polling&t=NrIp-lE&sid=5470c3277632448f81a01acca62cba86 HTTP/1.1" 200 159 9.948142
app_1    | (7) accepted ('10.4.2.3', 33044)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:38:28] "GET /socket.io/?EIO=3&transport=polling&t=NrIq1PH HTTP/1.1" 200 347 0.003792
app_1    | (7) accepted ('10.4.2.3', 33046)
app_1    | (7) accepted ('10.4.2.3', 33048)
app_1    | (7) accepted ('10.4.2.3', 33114)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:38:53] "POST /socket.io/?EIO=3&transport=polling&t=NrIq7Wv&sid=86a5487e6a624c0680536a3e24e0c255 HTTP/1.1" 200 143 0.019908
app_1    | 10.4.2.3 - - [24/Nov/2021 16:38:53] "GET /socket.io/?EIO=3&transport=polling&t=NrIq1QH&sid=86a5487e6a624c0680536a3e24e0c255 HTTP/1.1" 200 159 25.019674
app_1    | (7) accepted ('10.4.2.3', 33116)
app_1    | (7) accepted ('10.4.2.3', 33200)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:39:18] "POST /socket.io/?EIO=3&transport=polling&t=NrIqDeV&sid=86a5487e6a624c0680536a3e24e0c255 HTTP/1.1" 200 143 0.009177
app_1    | 10.4.2.3 - - [24/Nov/2021 16:39:18] "GET /socket.io/?EIO=3&transport=websocket&sid=86a5487e6a624c0680536a3e24e0c255 HTTP/1.1" 200 159 50.045106
app_1    | (7) accepted ('50.76.109.13', 42732)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:39:53] "GET /socket.io/?EIO=3&transport=polling&t=NrIq7Xt&sid=86a5487e6a624c0680536a3e24e0c255 HTTP/1.1" 400 164 60.014314
app_1    | (7) accepted ('10.4.2.3', 33304)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:39:53] "POST /socket.io/?EIO=3&transport=polling&t=NrIqMCJ&sid=86a5487e6a624c0680536a3e24e0c255 HTTP/1.1" 400 201 0.014084
app_1    | (7) accepted ('10.4.2.3', 33306)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:39:54] "GET /socket.io/?EIO=3&transport=polling&t=NrIqMOY HTTP/1.1" 200 347 0.049969
app_1    | (7) accepted ('10.4.2.3', 33308)
app_1    | (7) accepted ('10.4.2.3', 33310)
app_1    | 50.76.109.13 - - [24/Nov/2021 16:39:55] "GET / HTTP/1.1" 200 14782 0.141556
app_1    | (7) accepted ('10.4.2.3', 33372)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:40:19] "POST /socket.io/?EIO=3&transport=polling&t=NrIqSWz&sid=f3417a5bae6246f59ce1cb5fd996f267 HTTP/1.1" 200 143 0.003431
app_1    | 10.4.2.3 - - [24/Nov/2021 16:40:19] "GET /socket.io/?EIO=3&transport=polling&t=NrIqMQL&sid=f3417a5bae6246f59ce1cb5fd996f267 HTTP/1.1" 200 159 25.029555
app_1    | (7) accepted ('10.4.2.3', 33374)
app_1    | (7) accepted ('10.4.2.3', 33428)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:40:44] "POST /socket.io/?EIO=3&transport=polling&t=NrIqYeq&sid=f3417a5bae6246f59ce1cb5fd996f267 HTTP/1.1" 200 143 0.002468
app_1    | 10.4.2.3 - - [24/Nov/2021 16:40:44] "GET /socket.io/?EIO=3&transport=websocket&sid=f3417a5bae6246f59ce1cb5fd996f267 HTTP/1.1" 200 159 50.026363
app_1    | 10.4.2.3 - - [24/Nov/2021 16:41:19] "GET /socket.io/?EIO=3&transport=polling&t=NrIqSYB&sid=f3417a5bae6246f59ce1cb5fd996f267 HTTP/1.1" 400 164 60.016492
app_1    | (7) accepted ('10.4.2.3', 33486)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:41:20] "POST /socket.io/?EIO=3&transport=polling&t=NrIqhGP&sid=f3417a5bae6246f59ce1cb5fd996f267 HTTP/1.1" 400 201 0.024387
app_1    | (7) accepted ('10.4.2.3', 33488)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:41:21] "GET /socket.io/?EIO=3&transport=polling&t=NrIqhV_ HTTP/1.1" 200 347 0.005046
app_1    | (7) accepted ('10.4.2.3', 33490)
app_1    | (7) accepted ('10.4.2.3', 33492)
app_1    | (7) accepted ('10.4.2.3', 33546)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:41:46] "POST /socket.io/?EIO=3&transport=polling&t=NrIqndP&sid=8d471e7e931d46d79c46abe588b755ef HTTP/1.1" 200 143 0.003077
app_1    | 10.4.2.3 - - [24/Nov/2021 16:41:46] "GET /socket.io/?EIO=3&transport=websocket&sid=8d471e7e931d46d79c46abe588b755ef HTTP/1.1" 200 159 24.988946
app_1    | 10.4.2.3 - - [24/Nov/2021 16:42:21] "GET /socket.io/?EIO=3&transport=polling&t=NrIqhWm&sid=8d471e7e931d46d79c46abe588b755ef HTTP/1.1" 400 164 60.013823
app_1    | (7) accepted ('10.4.2.3', 33600)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:42:21] "POST /socket.io/?EIO=3&transport=polling&t=NrIqwB5&sid=8d471e7e931d46d79c46abe588b755ef HTTP/1.1" 400 201 0.004402
app_1    | (7) accepted ('10.4.2.3', 33602)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:42:22] "GET /socket.io/?EIO=3&transport=polling&t=NrIqwNM HTTP/1.1" 200 347 0.015135
app_1    | (7) accepted ('10.4.2.3', 33604)
app_1    | (7) accepted ('10.4.2.3', 33606)
app_1    | (7) accepted ('10.4.2.3', 33672)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:42:47] "POST /socket.io/?EIO=3&transport=polling&t=NrIr0Um&sid=53dcdb0e07164111a02978708b6be86e HTTP/1.1" 200 143 0.010867
app_1    | 10.4.2.3 - - [24/Nov/2021 16:42:47] "GET /socket.io/?EIO=3&transport=websocket&sid=53dcdb0e07164111a02978708b6be86e HTTP/1.1" 200 159 25.005078
app_1    | (7) accepted ('66.249.76.61', 51364)
app_1    | 66.249.76.61 - - [24/Nov/2021 16:42:58] "GET /static/support/pico-pro/PicoPro_ColdBrew.pdf HTTP/1.1" 200 160279 0.217239
app_1    | 10.4.2.3 - - [24/Nov/2021 16:43:22] "GET /socket.io/?EIO=3&transport=polling&t=NrIqwO9&sid=53dcdb0e07164111a02978708b6be86e HTTP/1.1" 400 164 60.017413
app_1    | (7) accepted ('10.4.2.3', 33750)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:43:22] "POST /socket.io/?EIO=3&transport=polling&t=NrIr92e&sid=53dcdb0e07164111a02978708b6be86e HTTP/1.1" 400 201 0.003087
app_1    | (7) accepted ('10.4.2.3', 33752)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:43:23] "GET /socket.io/?EIO=3&transport=polling&t=NrIr9Pf HTTP/1.1" 200 347 0.014330
app_1    | (7) accepted ('10.4.2.3', 33754)
app_1    | (7) accepted ('10.4.2.3', 33756)
app_1    | (7) accepted ('10.4.2.3', 33810)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:43:48] "POST /socket.io/?EIO=3&transport=polling&t=NrIrFWu&sid=5814561cd9e0480bb923907381a60e5a HTTP/1.1" 200 143 0.003293
app_1    | 10.4.2.3 - - [24/Nov/2021 16:43:48] "GET /socket.io/?EIO=3&transport=polling&t=NrIr9QF&sid=5814561cd9e0480bb923907381a60e5a HTTP/1.1" 200 159 25.019770
app_1    | (7) accepted ('10.4.2.3', 33812)
app_1    | (7) accepted ('10.4.2.3', 33854)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:44:13] "POST /socket.io/?EIO=3&transport=polling&t=NrIrLe8&sid=5814561cd9e0480bb923907381a60e5a HTTP/1.1" 200 143 0.003706
app_1    | 10.4.2.3 - - [24/Nov/2021 16:44:13] "GET /socket.io/?EIO=3&transport=websocket&sid=5814561cd9e0480bb923907381a60e5a HTTP/1.1" 200 159 50.049040
app_1    | 10.4.2.3 - - [24/Nov/2021 16:44:48] "GET /socket.io/?EIO=3&transport=polling&t=NrIrFXX&sid=5814561cd9e0480bb923907381a60e5a HTTP/1.1" 400 164 60.027934
app_1    | (7) accepted ('10.4.2.3', 33936)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:44:48] "POST /socket.io/?EIO=3&transport=polling&t=NrIrUEF&sid=5814561cd9e0480bb923907381a60e5a HTTP/1.1" 400 201 0.007460
app_1    | (7) accepted ('10.4.2.3', 33938)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:44:49] "GET /socket.io/?EIO=3&transport=polling&t=NrIrUP6 HTTP/1.1" 200 347 0.006328
app_1    | (7) accepted ('10.4.2.3', 33940)
app_1    | (7) accepted ('10.4.2.3', 33942)
app_1    | (7) accepted ('10.4.2.3', 34012)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:45:14] "POST /socket.io/?EIO=3&transport=polling&t=NrIraWa&sid=ada91c3404d54aa3be78240b70429f96 HTTP/1.1" 200 143 0.003430
app_1    | 10.4.2.3 - - [24/Nov/2021 16:45:14] "GET /socket.io/?EIO=3&transport=polling&t=NrIrUPx&sid=ada91c3404d54aa3be78240b70429f96 HTTP/1.1" 200 159 24.998503
app_1    | (7) accepted ('10.4.2.3', 34014)
app_1    | (7) accepted ('10.4.2.3', 34088)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:45:39] "POST /socket.io/?EIO=3&transport=polling&t=NrIrgds&sid=ada91c3404d54aa3be78240b70429f96 HTTP/1.1" 200 143 0.003013
app_1    | 10.4.2.3 - - [24/Nov/2021 16:45:39] "GET /socket.io/?EIO=3&transport=polling&t=NrIraXD&sid=ada91c3404d54aa3be78240b70429f96 HTTP/1.1" 200 159 24.994745
app_1    | (7) accepted ('10.4.2.3', 34090)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:45:49] "GET /socket.io/?EIO=3&transport=websocket&sid=ada91c3404d54aa3be78240b70429f96 HTTP/1.1" 400 164 60.003823
app_1    | 10.4.2.3 - - [24/Nov/2021 16:45:49] "GET /socket.io/?EIO=3&transport=polling&t=NrIrgeT&sid=ada91c3404d54aa3be78240b70429f96 HTTP/1.1" 200 159 9.963037
app_1    | (7) accepted ('10.4.2.3', 34108)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:45:50] "GET /socket.io/?EIO=3&transport=polling&t=NrIrjHo HTTP/1.1" 200 347 0.004059
app_1    | (7) accepted ('10.4.2.3', 34110)
app_1    | (7) accepted ('10.4.2.3', 34112)
app_1    | (7) accepted ('10.4.2.3', 34162)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:46:15] "POST /socket.io/?EIO=3&transport=polling&t=NrIrpPL&sid=82c6eb3547b7419cb6f77a19509fc1ad HTTP/1.1" 200 143 0.009023
app_1    | 10.4.2.3 - - [24/Nov/2021 16:46:15] "GET /socket.io/?EIO=3&transport=websocket&sid=82c6eb3547b7419cb6f77a19509fc1ad HTTP/1.1" 200 159 24.996166
app_1    | 10.4.2.3 - - [24/Nov/2021 16:46:50] "GET /socket.io/?EIO=3&transport=polling&t=NrIrjIj&sid=82c6eb3547b7419cb6f77a19509fc1ad HTTP/1.1" 400 164 60.015505
app_1    | (7) accepted ('10.4.2.3', 34220)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:46:50] "POST /socket.io/?EIO=3&transport=polling&t=NrIrxzC&sid=82c6eb3547b7419cb6f77a19509fc1ad HTTP/1.1" 400 201 0.018407
app_1    | (7) accepted ('10.4.2.3', 34222)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:46:51] "GET /socket.io/?EIO=3&transport=polling&t=NrIry7p HTTP/1.1" 200 347 0.004062
app_1    | (7) accepted ('10.4.2.3', 34224)
app_1    | (7) accepted ('10.4.2.3', 34226)
app_1    | (7) accepted ('10.4.2.3', 34276)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:47:16] "POST /socket.io/?EIO=3&transport=polling&t=NrIs2FI&sid=8becaff833c24711ab0dbf634871745b HTTP/1.1" 200 143 0.017844
app_1    | 10.4.2.3 - - [24/Nov/2021 16:47:16] "GET /socket.io/?EIO=3&transport=websocket&sid=8becaff833c24711ab0dbf634871745b HTTP/1.1" 200 159 25.009258
app_1    | 10.4.2.3 - - [24/Nov/2021 16:47:51] "GET /socket.io/?EIO=3&transport=polling&t=NrIry8f&sid=8becaff833c24711ab0dbf634871745b HTTP/1.1" 400 164 60.035058
app_1    | (7) accepted ('10.4.2.3', 34342)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:47:51] "POST /socket.io/?EIO=3&transport=polling&t=NrIsAru&sid=8becaff833c24711ab0dbf634871745b HTTP/1.1" 400 201 0.002743
app_1    | (7) accepted ('10.4.2.3', 34344)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:47:52] "GET /socket.io/?EIO=3&transport=polling&t=NrIsB6T HTTP/1.1" 200 347 0.004359
app_1    | (7) accepted ('10.4.2.3', 34346)
app_1    | (7) accepted ('10.4.2.3', 34348)
app_1    | (7) accepted ('10.4.2.3', 34504)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:48:17] "POST /socket.io/?EIO=3&transport=polling&t=NrIsHDt&sid=8c36896f1c254d99af4ec47bfe181f9b HTTP/1.1" 200 143 0.018512
app_1    | 10.4.2.3 - - [24/Nov/2021 16:48:17] "GET /socket.io/?EIO=3&transport=polling&t=NrIsB7E&sid=8c36896f1c254d99af4ec47bfe181f9b HTTP/1.1" 200 159 25.044774
app_1    | (7) accepted ('10.4.2.3', 34506)
app_1    | (7) accepted ('10.4.2.3', 34576)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:48:42] "POST /socket.io/?EIO=3&transport=polling&t=NrIsNLh&sid=8c36896f1c254d99af4ec47bfe181f9b HTTP/1.1" 200 143 0.007831
app_1    | 10.4.2.3 - - [24/Nov/2021 16:48:42] "GET /socket.io/?EIO=3&transport=websocket&sid=8c36896f1c254d99af4ec47bfe181f9b HTTP/1.1" 200 159 50.078593
app_1    | 10.4.2.3 - - [24/Nov/2021 16:49:17] "GET /socket.io/?EIO=3&transport=polling&t=NrIsHF3&sid=8c36896f1c254d99af4ec47bfe181f9b HTTP/1.1" 400 164 60.008347
app_1    | (7) accepted ('10.4.2.3', 34644)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:49:18] "POST /socket.io/?EIO=3&transport=polling&t=NrIsVyK&sid=8c36896f1c254d99af4ec47bfe181f9b HTTP/1.1" 400 201 0.002668
app_1    | (7) accepted ('10.4.2.3', 34646)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:49:19] "GET /socket.io/?EIO=3&transport=polling&t=NrIsWD5 HTTP/1.1" 200 347 0.004124
app_1    | (7) accepted ('10.4.2.3', 34648)
app_1    | (7) accepted ('10.4.2.3', 34650)
app_1    | (7) accepted ('10.4.2.3', 34704)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:49:44] "POST /socket.io/?EIO=3&transport=polling&t=NrIscKV&sid=7b538c59bcbb41a1b6ef37d0a8057bf9 HTTP/1.1" 200 143 0.004861
app_1    | 10.4.2.3 - - [24/Nov/2021 16:49:44] "GET /socket.io/?EIO=3&transport=websocket&sid=7b538c59bcbb41a1b6ef37d0a8057bf9 HTTP/1.1" 200 159 25.059601
app_1    | 10.4.2.3 - - [24/Nov/2021 16:50:19] "GET /socket.io/?EIO=3&transport=polling&t=NrIsWDs&sid=7b538c59bcbb41a1b6ef37d0a8057bf9 HTTP/1.1" 400 164 60.017555
app_1    | (7) accepted ('10.4.2.3', 34856)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:50:19] "POST /socket.io/?EIO=3&transport=polling&t=NrIskv6&sid=7b538c59bcbb41a1b6ef37d0a8057bf9 HTTP/1.1" 400 201 0.006235
app_1    | (7) accepted ('10.4.2.3', 34872)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:50:19] "GET /socket.io/?EIO=3&transport=polling&t=NrIsl2L HTTP/1.1" 200 347 0.015124
app_1    | (7) accepted ('10.4.2.3', 34876)
app_1    | (7) accepted ('10.4.2.3', 34880)
app_1    | (7) accepted ('10.4.2.3', 34970)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:50:44] "POST /socket.io/?EIO=3&transport=polling&t=NrIsr9s&sid=27a9e79ae67c4b18b53147994e41a1d1 HTTP/1.1" 200 143 0.013795
app_1    | 10.4.2.3 - - [24/Nov/2021 16:50:45] "GET /socket.io/?EIO=3&transport=websocket&sid=27a9e79ae67c4b18b53147994e41a1d1 HTTP/1.1" 200 159 24.987869
app_1    | (7) accepted ('10.4.2.3', 34988)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:07] "GET / HTTP/1.0" 200 14801 0.125276
app_1    | (7) accepted ('10.4.2.3', 35004)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:08] "GET /static/css/bootstrap/bootstrap.min.css HTTP/1.0" 200 160726 0.062201
app_1    | (7) accepted ('10.4.2.3', 35006)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:08] "GET /static/css/fontawesome/all.min.css HTTP/1.0" 200 59675 0.021719
app_1    | (7) accepted ('10.4.2.3', 35008)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:08] "GET /static/css/fontawesome/fontawesome.min.css HTTP/1.0" 200 58244 0.047584
app_1    | (7) accepted ('10.4.2.3', 35010)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:08] "GET /static/css/tabulator/tabulator_midnight.min.css HTTP/1.0" 200 23139 0.052558
app_1    | (7) accepted ('10.4.2.3', 35012)
app_1    | (7) accepted ('10.4.2.3', 35014)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:08] "GET /static/css/main.css HTTP/1.0" 200 1614 0.024561
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:08] "GET /static/js/jquery/jquery.min.js HTTP/1.0" 200 89822 0.043727
app_1    | (7) accepted ('10.4.2.3', 35016)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/bootstrap/bootstrap.min.js HTTP/1.0" 200 63586 0.028719
app_1    | (7) accepted ('10.4.2.3', 35018)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/tabulator/tabulator.min.js HTTP/1.0" 200 359564 0.056670
app_1    | (7) accepted ('10.4.2.3', 35020)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/highcharts/highcharts.js HTTP/1.0" 200 275018 0.034531
app_1    | (7) accepted ('10.4.2.3', 35024)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/highcharts/annotations.js HTTP/1.0" 200 53833 0.045534
app_1    | (7) accepted ('10.4.2.3', 35026)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/highcharts/export-data.js HTTP/1.0" 200 10619 0.027220
app_1    | (7) accepted ('10.4.2.3', 35028)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/highcharts/exporting.js HTTP/1.0" 200 17308 0.021464
app_1    | (7) accepted ('10.4.2.3', 35030)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/highcharts/dark-unica.js HTTP/1.0" 200 3387 0.023980
app_1    | (7) accepted ('10.4.2.3', 35032)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/socketio/socket.io.slim.js HTTP/1.0" 200 53815 0.024385
app_1    | (7) accepted ('10.4.2.3', 35034)
app_1    | (7) accepted ('10.4.2.3', 35036)
app_1    | (7) accepted ('10.4.2.3', 35038)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/index.js HTTP/1.0" 200 2432 0.032680
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/img/picobrew.svg HTTP/1.0" 200 4407 0.020003
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/brew_graph_socketio.js HTTP/1.0" 200 4719 0.031044
app_1    | (7) accepted ('10.4.2.3', 35040)
app_1    | (7) accepted ('10.4.2.3', 35042)
app_1    | (7) accepted ('10.4.2.3', 35044)
app_1    | (7) accepted ('10.4.2.3', 35046)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/img/picoferm.svg HTTP/1.0" 200 1811 0.019802
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/ferm_graph_socketio.js HTTP/1.0" 200 1634 0.017501
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/img/picostill.svg HTTP/1.0" 200 3310 0.017542
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:09] "GET /static/js/still_graph_socketio.js HTTP/1.0" 200 1715 0.017326
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:20] "GET /socket.io/?EIO=3&transport=polling&t=NrIsl3D&sid=27a9e79ae67c4b18b53147994e41a1d1 HTTP/1.1" 400 164 60.011132
app_1    | (7) accepted ('10.4.2.3', 35072)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:20] "POST /socket.io/?EIO=3&transport=polling&t=NrIszjs&sid=27a9e79ae67c4b18b53147994e41a1d1 HTTP/1.1" 400 201 0.003316
app_1    | (7) accepted ('10.4.2.3', 35074)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:21] "GET /socket.io/?EIO=3&transport=polling&t=NrIs-1S HTTP/1.1" 200 347 0.004573
app_1    | (7) accepted ('10.4.2.3', 35076)
app_1    | (7) accepted ('10.4.2.3', 35078)
app_1    | (7) accepted ('10.4.2.3', 35080)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:24] "GET /socket.io/?EIO=3&transport=polling&t=NrIsBCt HTTP/1.1" 200 347 0.003572
app_1    | (7) accepted ('10.4.2.3', 35082)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:25] "GET /static/css/webfonts/fa-solid-900.woff2 HTTP/1.0" 200 79763 0.022732
app_1    | (7) accepted ('10.4.2.3', 35096)
app_1    | [2021-11-24 16:51:29,327] DEBUG in model: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:29] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 200 0.523349
app_1    | (7) accepted ('10.4.2.3', 35098)
app_1    | (7) accepted ('10.4.2.3', 35100)
app_1    | (7) accepted ('10.4.2.3', 35102)
app_1    | [2021-11-24 16:51:29,842] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 16:51:29,878] DEBUG in model: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 16:51:29,927] DEBUG in still_polling: DEBUG: Still Datastring: #46.79,46.60,45.87,45.43,2.58,1,1,1,None#
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:29] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 200 0.101739
app_1    | [2021-11-24 16:51:29,983] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:30] "GET /socket.io/?EIO=3&transport=websocket&sid=c1b8df2a3dff4294a25a0f1df7c2d45d HTTP/1.1" 200 342 0.022810
app_1    | [2021-11-24 16:51:30,042] DEBUG in still_polling: DEBUG: Still Datastring: #46.79,46.60,45.87,45.43,2.58,1,1,1,None#
app_1    | (7) accepted ('10.4.2.3', 35128)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:45] "GET / HTTP/1.0" 200 14929 0.025146
app_1    | (7) accepted ('10.4.2.3', 35130)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:45] "POST /socket.io/?EIO=3&transport=polling&t=NrIsJ7E&sid=c1b8df2a3dff4294a25a0f1df7c2d45d HTTP/1.1" 200 143 0.003831
app_1    | (7) accepted ('10.4.2.3', 35144)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:45] "GET /static/js/jquery/jquery.min.js HTTP/1.0" 304 234 0.041618
app_1    | (7) accepted ('10.4.2.3', 35146)
app_1    | (7) accepted ('10.4.2.3', 35148)
app_1    | (7) accepted ('10.4.2.3', 35150)
app_1    | (7) accepted ('10.4.2.3', 35152)
app_1    | (7) accepted ('10.4.2.3', 35154)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:45] "GET /static/js/bootstrap/bootstrap.min.js HTTP/1.0" 304 234 0.039620
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/tabulator/tabulator.min.js HTTP/1.0" 304 235 0.035771
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/highcharts/highcharts.js HTTP/1.0" 304 234 0.045621
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/highcharts/annotations.js HTTP/1.0" 304 234 0.017822
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/highcharts/export-data.js HTTP/1.0" 304 233 0.017479
app_1    | (7) accepted ('10.4.2.3', 35156)
app_1    | (7) accepted ('10.4.2.3', 35158)
app_1    | (7) accepted ('10.4.2.3', 35160)
app_1    | (7) accepted ('10.4.2.3', 35162)
app_1    | (7) accepted ('10.4.2.3', 35164)
app_1    | (7) accepted ('10.4.2.3', 35166)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/highcharts/exporting.js HTTP/1.0" 304 233 0.017532
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/highcharts/dark-unica.js HTTP/1.0" 304 232 0.016733
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/socketio/socket.io.slim.js HTTP/1.0" 304 234 0.035724
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/index.js HTTP/1.0" 304 232 0.037069
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/brew_graph_socketio.js HTTP/1.0" 304 236 0.015856
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/ferm_graph_socketio.js HTTP/1.0" 304 230 0.015828
app_1    | (7) accepted ('10.4.2.3', 35168)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/js/still_graph_socketio.js HTTP/1.0" 304 232 0.017271
app_1    | (7) accepted ('10.4.2.3', 35170)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "POST /socket.io/?EIO=3&transport=polling&t=NrIt48h&sid=70bebd1bb42b4e81b16ac14e5f18958f HTTP/1.1" 200 143 0.003370
app_1    | (7) accepted ('10.4.2.3', 35172)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /socket.io/?EIO=3&transport=polling&t=NrIsJOh HTTP/1.1" 200 347 0.004196
app_1    | (7) accepted ('10.4.2.3', 35174)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/img/picobrew.svg HTTP/1.0" 304 233 0.015547
app_1    | (7) accepted ('10.4.2.3', 35176)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:46] "GET /static/img/picoferm.svg HTTP/1.0" 304 233 0.037747
app_1    | (7) accepted ('10.4.2.3', 35190)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:48] "GET /static/img/picostill.svg HTTP/1.0" 304 233 0.015868
app_1    | (7) accepted ('10.4.2.3', 35192)
app_1    | (7) accepted ('10.4.2.3', 35194)
app_1    | [2021-11-24 16:51:48,913] DEBUG in model: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | 10.4.2.3 - - [24/Nov/2021 16:51:49] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 200 0.332871
app_1    | (7) accepted ('10.4.2.3', 35198)
app_1    | Exception in thread Thread-3:
app_1    | Traceback (most recent call last):
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 916, in _bootstrap_inner
app_1    |     self.run()
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 25, in run
app_1    |     super().run()
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 864, in run
app_1    |     self._target(*self._args, **self._kwargs)
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 76, in new_still_session
app_1    |     active_still_sessions[uid].cleanup()
app_1    |   File "/picobrew_pico/app/main/model.py", line 100, in cleanup
app_1    |     shutil.move(str(self.filepath), str(still_archive_sessions_path()))
app_1    |   File "/usr/local/lib/python3.6/shutil.py", line 548, in move
app_1    |     raise Error("Destination path '%s' already exists" % real_dst)
app_1    | shutil.Error: Destination path '/picobrew_pico/app/sessions/still/archive/20211124_165129#30aea47edbb8.json' already exists
app_1    | 
app_1    | (7) accepted ('10.4.2.3', 35224)
app_1    | (7) accepted ('10.4.2.3', 35226)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET / HTTP/1.0" 200 14931 0.024000
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "POST /socket.io/?EIO=3&transport=polling&t=NrIsPXP&sid=5c1c4da75b744190854df10b393901ed HTTP/1.1" 200 143 0.003117
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /socket.io/?EIO=3&transport=websocket&sid=5c1c4da75b744190854df10b393901ed HTTP/1.1" 200 155 22.357642
app_1    | (7) accepted ('10.4.2.3', 35228)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/jquery/jquery.min.js HTTP/1.0" 304 234 0.019814
app_1    | (7) accepted ('10.4.2.3', 35230)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/bootstrap/bootstrap.min.js HTTP/1.0" 304 234 0.014767
app_1    | (7) accepted ('10.4.2.3', 35232)
app_1    | (7) accepted ('10.4.2.3', 35234)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/tabulator/tabulator.min.js HTTP/1.0" 304 235 0.014642
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/highcharts/highcharts.js HTTP/1.0" 304 234 0.015090
app_1    | (7) accepted ('10.4.2.3', 35236)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/highcharts/annotations.js HTTP/1.0" 304 234 0.014758
app_1    | (7) accepted ('10.4.2.3', 35238)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/highcharts/export-data.js HTTP/1.0" 304 233 0.015072
app_1    | (7) accepted ('10.4.2.3', 35240)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/highcharts/exporting.js HTTP/1.0" 304 233 0.015346
app_1    | (7) accepted ('10.4.2.3', 35242)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:11] "GET /static/js/highcharts/dark-unica.js HTTP/1.0" 304 232 0.014769
app_1    | (7) accepted ('10.4.2.3', 35244)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/js/socketio/socket.io.slim.js HTTP/1.0" 304 234 0.014770
app_1    | (7) accepted ('10.4.2.3', 35246)
app_1    | (7) accepted ('10.4.2.3', 35248)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/js/index.js HTTP/1.0" 304 232 0.015106
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/js/brew_graph_socketio.js HTTP/1.0" 304 236 0.015128
app_1    | (7) accepted ('10.4.2.3', 35250)
app_1    | (7) accepted ('10.4.2.3', 35252)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/js/ferm_graph_socketio.js HTTP/1.0" 304 230 0.014560
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/js/still_graph_socketio.js HTTP/1.0" 304 232 0.014538
app_1    | (7) accepted ('10.4.2.3', 35254)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /socket.io/?EIO=3&transport=polling&t=NrIsPhx HTTP/1.1" 200 347 0.003269
app_1    | (7) accepted ('10.4.2.3', 35256)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/img/picobrew.svg HTTP/1.0" 304 233 0.014956
app_1    | (7) accepted ('10.4.2.3', 35258)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/img/picoferm.svg HTTP/1.0" 304 233 0.014340
app_1    | (7) accepted ('10.4.2.3', 35260)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:12] "GET /static/img/picostill.svg HTTP/1.0" 304 233 0.014507
app_1    | (7) accepted ('10.4.2.3', 35262)
app_1    | (7) accepted ('10.4.2.3', 35264)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:21] "GET /socket.io/?EIO=3&transport=polling&t=NrIs-23&sid=70bebd1bb42b4e81b16ac14e5f18958f HTTP/1.1" 400 164 60.005745
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:21] "GET /socket.io/?EIO=3&transport=websocket&sid=70bebd1bb42b4e81b16ac14e5f18958f HTTP/1.1" 400 164 60.003749
app_1    | (7) accepted ('10.4.2.3', 35306)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:21] "POST /socket.io/?EIO=3&transport=polling&t=NrItCiE&sid=70bebd1bb42b4e81b16ac14e5f18958f HTTP/1.1" 400 201 0.002058
app_1    | (7) accepted ('10.4.2.3', 35308)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:21] "GET /socket.io/?EIO=3&transport=polling&t=NrItCqw HTTP/1.1" 200 347 0.003081
app_1    | (7) accepted ('10.4.2.3', 35310)
app_1    | (7) accepted ('10.4.2.3', 35312)
app_1    | (7) accepted ('10.4.2.3', 35314)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:23] "GET / HTTP/1.0" 200 14931 0.041735
app_1    | (7) accepted ('10.4.2.3', 35316)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:23] "POST /socket.io/?EIO=3&transport=polling&t=NrIsSVW&sid=f76dce43fe16458686cd4cb35eea1086 HTTP/1.1" 200 143 0.004604
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:23] "GET /socket.io/?EIO=3&transport=polling&t=NrIsPqq&sid=f76dce43fe16458686cd4cb35eea1086 HTTP/1.1" 200 155 10.983520
app_1    | (7) accepted ('10.4.2.3', 35318)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:24] "GET /static/js/jquery/jquery.min.js HTTP/1.0" 304 234 0.017462
app_1    | (7) accepted ('10.4.2.3', 35320)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:24] "GET /static/js/bootstrap/bootstrap.min.js HTTP/1.0" 304 234 0.033746
app_1    | (7) accepted ('10.4.2.3', 35322)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:24] "GET /static/js/tabulator/tabulator.min.js HTTP/1.0" 304 235 0.016371
app_1    | (7) accepted ('10.4.2.3', 35324)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:24] "GET /static/js/highcharts/highcharts.js HTTP/1.0" 304 234 0.015057
app_1    | (7) accepted ('10.4.2.3', 35326)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:24] "GET /static/js/highcharts/annotations.js HTTP/1.0" 304 234 0.028083
app_1    | (7) accepted ('10.4.2.3', 35328)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:24] "GET /static/js/highcharts/export-data.js HTTP/1.0" 304 233 0.017216
app_1    | (7) accepted ('10.4.2.3', 35330)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:25] "GET /static/js/highcharts/exporting.js HTTP/1.0" 304 233 0.018919
app_1    | (7) accepted ('10.4.2.3', 35332)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:25] "GET /static/js/highcharts/dark-unica.js HTTP/1.0" 304 232 0.016850
app_1    | (7) accepted ('10.4.2.3', 35334)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:25] "GET /static/js/socketio/socket.io.slim.js HTTP/1.0" 304 234 0.019990
app_1    | (7) accepted ('10.4.2.3', 35336)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:25] "GET /static/js/index.js HTTP/1.0" 304 232 0.041480
app_1    | (7) accepted ('10.4.2.3', 35338)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:25] "GET /static/js/brew_graph_socketio.js HTTP/1.0" 304 236 0.051119
app_1    | (7) accepted ('10.4.2.3', 35340)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:25] "GET /static/js/ferm_graph_socketio.js HTTP/1.0" 304 230 0.018134
app_1    | (7) accepted ('10.4.2.3', 35342)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:26] "GET /static/js/still_graph_socketio.js HTTP/1.0" 304 232 0.037477
app_1    | (7) accepted ('10.4.2.3', 35344)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:26] "GET /socket.io/?EIO=3&transport=polling&t=NrIsSxC HTTP/1.1" 200 347 0.007670
app_1    | (7) accepted ('10.4.2.3', 35346)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:26] "GET /static/img/picobrew.svg HTTP/1.0" 304 233 0.035899
app_1    | (7) accepted ('10.4.2.3', 35348)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:26] "GET /static/img/picoferm.svg HTTP/1.0" 304 233 0.014888
app_1    | (7) accepted ('10.4.2.3', 35350)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:26] "GET /static/img/picostill.svg HTTP/1.0" 304 233 0.017258
app_1    | (7) accepted ('10.4.2.3', 35352)
app_1    | (7) accepted ('10.4.2.3', 35354)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:29] "GET /socket.io/?EIO=3&transport=polling&t=NrIsEKJ&sid=c1b8df2a3dff4294a25a0f1df7c2d45d HTTP/1.1" 400 164 60.006285
app_1    | [2021-11-24 16:52:29,994] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 16:52:30,074] DEBUG in still_polling: DEBUG: Still Datastring: #46.88,46.65,45.81,45.38,2.58,1,1,1,None#
app_1    | Exception in thread Thread-1:
app_1    | Traceback (most recent call last):
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 916, in _bootstrap_inner
app_1    |     self.run()
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 25, in run
app_1    |     super().run()
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 864, in run
app_1    |     self._target(*self._args, **self._kwargs)
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 80, in new_still_session
app_1    |     result = poll_still(still_ip, uid)
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 62, in poll_still
app_1    |     active_still_sessions[uid].file.write(log_data)
app_1    | ValueError: I/O operation on closed file.
app_1    | 
app_1    | [2021-11-24 16:52:30,106] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-24 16:52:30,178] DEBUG in still_polling: DEBUG: Still Datastring: #46.88,46.65,45.81,45.38,2.58,1,1,1,None#
app_1    | Exception in thread Thread-2:
app_1    | Traceback (most recent call last):
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 916, in _bootstrap_inner
app_1    |     self.run()
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 25, in run
app_1    |     super().run()
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 864, in run
app_1    |     self._target(*self._args, **self._kwargs)
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 80, in new_still_session
app_1    |     result = poll_still(still_ip, uid)
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 62, in poll_still
app_1    |     active_still_sessions[uid].file.write(log_data)
app_1    | ValueError: I/O operation on closed file.
app_1    | 
app_1    | (7) accepted ('10.4.2.3', 35410)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:47] "POST /socket.io/?EIO=3&transport=polling&t=NrItIyI&sid=82ac994d9ab2456bbde6e8fc98995c0d HTTP/1.1" 200 143 0.011205
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:48] "GET /socket.io/?EIO=3&transport=polling&t=NrIsJYn&sid=5c1c4da75b744190854df10b393901ed HTTP/1.1" 400 164 60.005096
app_1    | (7) accepted ('10.4.2.3', 35426)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:52:52] "POST /socket.io/?EIO=3&transport=polling&t=NrIsZQI&sid=31759a502b874089b34e1479f775a75b HTTP/1.1" 200 143 0.006867
app_1    | (7) accepted ('10.4.2.3', 35464)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:11] "GET / HTTP/1.0" 200 15209 0.055632
app_1    | (7) accepted ('10.4.2.3', 35466)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:11] "GET /static/js/jquery/jquery.min.js HTTP/1.0" 304 234 0.035293
app_1    | (7) accepted ('10.4.2.3', 35468)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:11] "GET /static/js/bootstrap/bootstrap.min.js HTTP/1.0" 304 234 0.052641
app_1    | (7) accepted ('10.4.2.3', 35470)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:11] "GET /static/js/tabulator/tabulator.min.js HTTP/1.0" 304 235 0.052111
app_1    | (7) accepted ('10.4.2.3', 35472)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:11] "GET /static/js/highcharts/highcharts.js HTTP/1.0" 304 234 0.051046
app_1    | (7) accepted ('10.4.2.3', 35474)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:11] "GET /static/js/highcharts/annotations.js HTTP/1.0" 304 234 0.062169
app_1    | (7) accepted ('10.4.2.3', 35476)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/highcharts/export-data.js HTTP/1.0" 304 233 0.051768
app_1    | (7) accepted ('10.4.2.3', 35478)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/highcharts/exporting.js HTTP/1.0" 304 233 0.058009
app_1    | (7) accepted ('10.4.2.3', 35480)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/highcharts/dark-unica.js HTTP/1.0" 304 232 0.067698
app_1    | (7) accepted ('10.4.2.3', 35482)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/socketio/socket.io.slim.js HTTP/1.0" 304 234 0.035569
app_1    | (7) accepted ('10.4.2.3', 35484)
app_1    | (7) accepted ('10.4.2.3', 35486)
app_1    | (7) accepted ('10.4.2.3', 35488)
app_1    | (7) accepted ('10.4.2.3', 35490)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/index.js HTTP/1.0" 304 232 0.030940
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/brew_graph_socketio.js HTTP/1.0" 304 236 0.031713
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/ferm_graph_socketio.js HTTP/1.0" 304 230 0.042987
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/js/still_graph_socketio.js HTTP/1.0" 304 232 0.030509
app_1    | (7) accepted ('10.4.2.3', 35492)
app_1    | (7) accepted ('10.4.2.3', 35494)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /socket.io/?EIO=3&transport=polling&t=NrIseOS HTTP/1.1" 200 347 0.016989
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/img/picobrew.svg HTTP/1.0" 304 233 0.034956
app_1    | (7) accepted ('10.4.2.3', 35502)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/img/picoferm.svg HTTP/1.0" 304 233 0.037035
app_1    | (7) accepted ('10.4.2.3', 35508)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /static/img/picostill.svg HTTP/1.0" 304 233 0.051946
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:12] "GET /socket.io/?EIO=3&transport=websocket&sid=f76dce43fe16458686cd4cb35eea1086 HTTP/1.1" 400 164 60.054155
app_1    | (7) accepted ('10.4.2.3', 35512)
app_1    | (7) accepted ('10.4.2.3', 35514)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:22] "GET /socket.io/?EIO=3&transport=polling&t=NrItCrf&sid=82ac994d9ab2456bbde6e8fc98995c0d HTTP/1.1" 400 164 60.008021
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:22] "GET /socket.io/?EIO=3&transport=websocket&sid=82ac994d9ab2456bbde6e8fc98995c0d HTTP/1.1" 400 164 60.003254
app_1    | (7) accepted ('10.4.2.3', 35532)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:22] "POST /socket.io/?EIO=3&transport=polling&t=NrItRYk&sid=82ac994d9ab2456bbde6e8fc98995c0d HTTP/1.1" 400 201 0.018643
app_1    | (7) accepted ('10.4.2.3', 35534)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:23] "GET /socket.io/?EIO=3&transport=polling&t=NrItRsS HTTP/1.1" 200 347 0.004090
app_1    | (7) accepted ('10.4.2.3', 35536)
app_1    | (7) accepted ('10.4.2.3', 35538)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:27] "GET /socket.io/?EIO=3&transport=polling&t=NrIsTJE&sid=31759a502b874089b34e1479f775a75b HTTP/1.1" 400 164 60.005252
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:27] "GET /socket.io/?EIO=3&transport=websocket&sid=31759a502b874089b34e1479f775a75b HTTP/1.1" 400 164 60.003513
app_1    | (7) accepted ('10.4.2.3', 35564)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:38] "POST /socket.io/?EIO=3&transport=polling&t=NrIskm_&sid=d792841af3f740a884b43058480ab5ca HTTP/1.1" 200 143 0.002433
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:38] "GET /socket.io/?EIO=3&transport=polling&t=NrIsegF&sid=d792841af3f740a884b43058480ab5ca HTTP/1.1" 200 159 24.720717
app_1    | (7) accepted ('10.4.2.3', 35566)
app_1    | (7) accepted ('10.4.2.3', 35592)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:48] "POST /socket.io/?EIO=3&transport=polling&t=NrItXzo&sid=34fd5bccee064cdba5134775b14c35c6 HTTP/1.1" 200 143 0.002683
app_1    | 10.4.2.3 - - [24/Nov/2021 16:53:48] "GET /socket.io/?EIO=3&transport=websocket&sid=34fd5bccee064cdba5134775b14c35c6 HTTP/1.1" 200 159 24.989896
app_1    | (7) accepted ('10.4.2.3', 35610)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:03] "POST /socket.io/?EIO=3&transport=polling&t=NrIsquT&sid=d792841af3f740a884b43058480ab5ca HTTP/1.1" 200 143 0.008772
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:03] "GET /socket.io/?EIO=3&transport=websocket&sid=d792841af3f740a884b43058480ab5ca HTTP/1.1" 200 159 49.551526
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:23] "GET /socket.io/?EIO=3&transport=polling&t=NrItRtA&sid=34fd5bccee064cdba5134775b14c35c6 HTTP/1.1" 400 164 60.013965
app_1    | (7) accepted ('10.4.2.3', 35652)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:23] "POST /socket.io/?EIO=3&transport=polling&t=NrItgXS&sid=34fd5bccee064cdba5134775b14c35c6 HTTP/1.1" 400 201 0.015534
app_1    | (7) accepted ('10.4.2.3', 35654)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:24] "GET /socket.io/?EIO=3&transport=polling&t=NrItgsa HTTP/1.1" 200 347 0.017314
app_1    | (7) accepted ('10.4.2.3', 35656)
app_1    | (7) accepted ('10.4.2.3', 35658)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:38] "GET /socket.io/?EIO=3&transport=polling&t=NrIskni&sid=d792841af3f740a884b43058480ab5ca HTTP/1.1" 400 164 60.006673
app_1    | (7) accepted ('10.4.2.3', 35696)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:38] "POST /socket.io/?EIO=3&transport=polling&t=NrIszRp&sid=d792841af3f740a884b43058480ab5ca HTTP/1.1" 400 201 0.002241
app_1    | (7) accepted ('10.4.2.3', 35698)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:39] "GET /socket.io/?EIO=3&transport=polling&t=NrIszcu HTTP/1.1" 200 347 0.003697
app_1    | (7) accepted ('10.4.2.3', 35700)
app_1    | (7) accepted ('10.4.2.3', 35702)
app_1    | (7) accepted ('10.4.2.3', 35716)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:50] "POST /socket.io/?EIO=3&transport=polling&t=NrItmz_&sid=88db7b9f87ea4dbb83cf89e4de1eb25a HTTP/1.1" 200 143 0.002716
app_1    | 10.4.2.3 - - [24/Nov/2021 16:54:50] "GET /socket.io/?EIO=3&transport=websocket&sid=88db7b9f87ea4dbb83cf89e4de1eb25a HTTP/1.1" 200 159 24.994433
app_1    | (7) accepted ('10.4.2.3', 35750)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:04] "POST /socket.io/?EIO=3&transport=polling&t=NrIt3l7&sid=aa68dd8f703c4704a05e621ed4b4bd7b HTTP/1.1" 200 143 0.016583
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:04] "GET /socket.io/?EIO=3&transport=websocket&sid=aa68dd8f703c4704a05e621ed4b4bd7b HTTP/1.1" 200 159 25.057655
app_1    | (7) accepted ('10.4.2.3', 35764)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:19] "GET / HTTP/1.0" 200 15209 0.066661
app_1    | (7) accepted ('10.4.2.3', 35766)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/jquery/jquery.min.js HTTP/1.0" 304 234 0.026322
app_1    | (7) accepted ('10.4.2.3', 35774)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/bootstrap/bootstrap.min.js HTTP/1.0" 304 234 0.042780
app_1    | (7) accepted ('10.4.2.3', 35780)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/tabulator/tabulator.min.js HTTP/1.0" 304 235 0.045928
app_1    | (7) accepted ('10.4.2.3', 35782)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/highcharts/highcharts.js HTTP/1.0" 304 234 0.044325
app_1    | (7) accepted ('10.4.2.3', 35786)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/highcharts/annotations.js HTTP/1.0" 304 234 0.073981
app_1    | (7) accepted ('10.4.2.3', 35788)
app_1    | (7) accepted ('10.4.2.3', 35790)
app_1    | (7) accepted ('10.4.2.3', 35792)

app_1    | (7) accepted ('10.4.2.3', 35794)
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/highcharts/export-data.js HTTP/1.0" 304 233 0.061153
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/highcharts/exporting.js HTTP/1.0" 304 233 0.035805
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:20] "GET /static/js/highcharts/dark-unica.js HTTP/1.0" 304 232 0.045559
app_1    | 10.4.2.3 - - [24/Nov/2021 16:55:21] "GET /static/js/socketio/socket.io.slim.js HTTP/1.0" 304 234 0.045200
app_1    | (7) accepted ('10.4.2.3', 35796)
app_1    | (7) accepted ('10.4.2.3', 35798)
app_1    | (7) accepted ('10.4.2.3', 35800)

@erdoukki
Copy link
Contributor Author

It looks like the server get values from the PicoStill ?!

@erdoukki
Copy link
Contributor Author

The server shows an empty embedded PicoStill data !

May be it is a client side problem ?
Another computer get the embedded page with data

@erdoukki
Copy link
Contributor Author

erdoukki commented Nov 25, 2021

I have modify he DNS redirection from only picobrew.com to *picobrew.com and the PicoStill had correctly upgraded to the specified version 0.35.
I also have disabled all firewall options on the docker host.
It looks like to partially access the PicoStill from the server.

I have now the PicoStill IP automatically sets to the PicoBrew server IP.
If I correct it manually in devices, I can start monitoring and the embedded data are correctly shown.

app_1    | [2021-11-25 05:36:24,117] DEBUG in routes_devices: machine_type: PicoStill; uid: 30aea47edbb8; alias: PICOSTILL
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:24] "POST /devices/30aea47edbb8 HTTP/1.0" 302 465 0.215397
app_1    | (7) accepted ('10.4.2.3', 56176)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:24] "GET /devices HTTP/1.0" 200 20021 0.017885
app_1    | (7) accepted ('10.4.2.3', 56178)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:27] "GET / HTTP/1.0" 200 14926 0.021007
app_1    | (7) accepted ('10.4.2.3', 56180)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:27] "GET /socket.io/?EIO=3&transport=polling&t=NrLp3WD HTTP/1.1" 200 347 0.003670
app_1    | (7) accepted ('10.4.2.3', 56182)
app_1    | (7) accepted ('10.4.2.3', 56184)
app_1    | (7) accepted ('10.4.2.3', 56186)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:29] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 187 0.010736
app_1    | (7) accepted ('10.4.2.3', 56188)
app_1    | [2021-11-25 05:36:30,686] DEBUG in model: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:30] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 187 0.228384
app_1    | [2021-11-25 05:36:30,905] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-25 05:36:30,980] DEBUG in still_polling: DEBUG: Still Datastring: #42.42,42.04,43.59,40.37,2.71,1,1,1,None#
app_1    | (7) accepted ('10.4.2.3', 56194)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:31] "GET / HTTP/1.0" 200 15065 0.041843
app_1    | (7) accepted ('10.4.2.3', 56196)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:31] "POST /socket.io/?EIO=3&transport=polling&t=NrLp4U7&sid=487c6b8bfc4848389035e7b3b81cb5e2 HTTP/1.1" 200 143 0.002649
app_1    | (7) accepted ('10.4.2.3', 56198)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:32] "GET /socket.io/?EIO=3&transport=polling&t=NrLp4cS HTTP/1.1" 200 347 0.006194
app_1    | (7) accepted ('10.4.2.3', 56200)
app_1    | (7) accepted ('10.4.2.3', 56202)
app_1    | (7) accepted ('10.4.2.3', 56204)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:57] "POST /socket.io/?EIO=3&transport=polling&t=NrLpAsy&sid=fd9e2e83456544f2998f442008d650bb HTTP/1.1" 200 143 0.002777
app_1    | 10.4.2.3 - - [25/Nov/2021 05:36:57] "GET /socket.io/?EIO=3&transport=polling&t=NrLp4m2&sid=fd9e2e83456544f2998f442008d650bb HTTP/1.1" 200 159 24.909367
app_1    | (7) accepted ('10.4.2.3', 56206)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:05] "GET /socket.io/?EIO=3&transport=polling&t=NrLo-Ce&sid=de389af0dc074fb388b95214a180c8a9 HTTP/1.1" 400 164 60.004434
app_1    | (7) accepted ('10.4.2.3', 56208)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:22] "POST /socket.io/?EIO=3&transport=polling&t=NrLpG-H&sid=fd9e2e83456544f2998f442008d650bb HTTP/1.1" 200 143 0.009907
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:22] "GET /socket.io/?EIO=3&transport=websocket&sid=fd9e2e83456544f2998f442008d650bb HTTP/1.1" 200 159 49.901372
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:28] "GET /socket.io/?EIO=3&transport=polling&t=NrLp3eT&sid=487c6b8bfc4848389035e7b3b81cb5e2 HTTP/1.1" 400 164 60.003755
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:28] "GET /socket.io/?EIO=3&transport=websocket&sid=487c6b8bfc4848389035e7b3b81cb5e2 HTTP/1.1" 400 164 60.003064
app_1    | [2021-11-25 05:37:31,046] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-25 05:37:31,315] DEBUG in still_polling: DEBUG: Still Datastring: #42.37,42.08,43.40,40.37,2.69,1,1,1,None#
app_1    | (7) accepted ('10.4.2.3', 56212)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:51] "PUT /device/30aea47edbb8/sessions/still HTTP/1.0" 204 187 0.018394
app_1    | (7) accepted ('10.4.2.3', 56214)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:53] "GET / HTTP/1.0" 200 14801 0.021062
app_1    | (7) accepted ('10.4.2.3', 56216)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:53] "POST /socket.io/?EIO=3&transport=polling&t=NrLpOaH&sid=fd9e2e83456544f2998f442008d650bb HTTP/1.1" 200 143 0.003301
app_1    | (7) accepted ('10.4.2.3', 56218)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:54] "GET /socket.io/?EIO=3&transport=polling&t=NrLpOhU HTTP/1.1" 200 347 0.004003
app_1    | (7) accepted ('10.4.2.3', 56220)
app_1    | (7) accepted ('10.4.2.3', 56222)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:37:57] "GET /socket.io/?EIO=3&transport=polling&t=NrLpAtc&sid=fd9e2e83456544f2998f442008d650bb HTTP/1.1" 400 164 60.003292
app_1    | (7) accepted ('10.4.2.3', 56252)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:38:19] "POST /socket.io/?EIO=3&transport=polling&t=NrLpUuH&sid=d1c57a2b442b450ba1ddb0630fb48ba7 HTTP/1.1" 200 143 0.013853
app_1    | 10.4.2.3 - - [25/Nov/2021 05:38:19] "GET /socket.io/?EIO=3&transport=polling&t=NrLpOnT&sid=d1c57a2b442b450ba1ddb0630fb48ba7 HTTP/1.1" 200 159 25.024592
app_1    | (7) accepted ('10.4.2.3', 56254)
app_1    | [2021-11-25 05:38:31,381] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | (7) accepted ('10.4.2.3', 56282)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:38:44] "POST /socket.io/?EIO=3&transport=polling&t=NrLpa_h&sid=d1c57a2b442b450ba1ddb0630fb48ba7 HTTP/1.1" 200 143 0.002692
app_1    | 10.4.2.3 - - [25/Nov/2021 05:38:44] "GET /socket.io/?EIO=3&transport=websocket&sid=d1c57a2b442b450ba1ddb0630fb48ba7 HTTP/1.1" 200 159 50.112937
app_1    | [2021-11-25 05:38:49,779] DEBUG in still_polling: DEBUG: Connection failure 1
app_1    | 10.4.2.3 - - [25/Nov/2021 05:39:19] "GET /socket.io/?EIO=3&transport=polling&t=NrLpUuv&sid=d1c57a2b442b450ba1ddb0630fb48ba7 HTTP/1.1" 400 164 60.008405
app_1    | (7) accepted ('10.4.2.3', 56308)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:39:19] "POST /socket.io/?EIO=3&transport=polling&t=NrLpjYy&sid=d1c57a2b442b450ba1ddb0630fb48ba7 HTTP/1.1" 400 201 0.002177
app_1    | (7) accepted ('10.4.2.3', 56310)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:39:20] "GET /socket.io/?EIO=3&transport=polling&t=NrLpjn5 HTTP/1.1" 200 347 0.003793
app_1    | (7) accepted ('10.4.2.3', 56312)
app_1    | (7) accepted ('10.4.2.3', 56314)
app_1    | (7) accepted ('10.4.2.3', 56364)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:39:45] "POST /socket.io/?EIO=3&transport=polling&t=NrLppuJ&sid=368aad548d264638bdfa18f29f1f7a72 HTTP/1.1" 200 143 0.019498
app_1    | 10.4.2.3 - - [25/Nov/2021 05:39:45] "GET /socket.io/?EIO=3&transport=polling&t=NrLpjnV&sid=368aad548d264638bdfa18f29f1f7a72 HTTP/1.1" 200 159 25.029528
app_1    | (7) accepted ('10.4.2.3', 56366)
app_1    | [2021-11-25 05:39:49,836] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-25 05:39:52,988] DEBUG in still_polling: DEBUG: Connection failure 2
app_1    | (7) accepted ('10.4.2.3', 56398)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:40:10] "POST /socket.io/?EIO=3&transport=polling&t=NrLpv_i&sid=368aad548d264638bdfa18f29f1f7a72 HTTP/1.1" 200 143 0.002730
app_1    | 10.4.2.3 - - [25/Nov/2021 05:40:10] "GET /socket.io/?EIO=3&transport=websocket&sid=368aad548d264638bdfa18f29f1f7a72 HTTP/1.1" 200 159 50.048883
app_1    | 10.4.2.3 - - [25/Nov/2021 05:40:45] "GET /socket.io/?EIO=3&transport=polling&t=NrLppuz&sid=368aad548d264638bdfa18f29f1f7a72 HTTP/1.1" 400 164 60.007569
app_1    | (7) accepted ('10.4.2.3', 56460)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:40:45] "POST /socket.io/?EIO=3&transport=polling&t=NrLq2Z1&sid=368aad548d264638bdfa18f29f1f7a72 HTTP/1.1" 400 201 0.002252
app_1    | (7) accepted ('10.4.2.3', 56462)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:40:46] "GET /socket.io/?EIO=3&transport=polling&t=NrLq2nT HTTP/1.1" 200 347 0.004040
app_1    | (7) accepted ('10.4.2.3', 56464)
app_1    | (7) accepted ('10.4.2.3', 56466)
app_1    | [2021-11-25 05:40:53,046] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-25 05:40:56,179] DEBUG in still_polling: DEBUG: Connection failure 3
app_1    | (7) accepted ('10.4.2.3', 56494)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:41:11] "POST /socket.io/?EIO=3&transport=polling&t=NrLq8v2&sid=6242b3f581704eaca6c9b5bda9ba5380 HTTP/1.1" 200 143 0.002737
app_1    | 10.4.2.3 - - [25/Nov/2021 05:41:11] "GET /socket.io/?EIO=3&transport=websocket&sid=6242b3f581704eaca6c9b5bda9ba5380 HTTP/1.1" 200 159 25.019052
app_1    | (7) accepted ('218.234.75.126', 36114)
app_1    | 218.234.75.126 - - [25/Nov/2021 05:41:27] "GET / HTTP/1.1" 200 14782 0.045774
app_1    | 10.4.2.3 - - [25/Nov/2021 05:41:46] "GET /socket.io/?EIO=3&transport=polling&t=NrLq2o7&sid=6242b3f581704eaca6c9b5bda9ba5380 HTTP/1.1" 400 164 60.004261
app_1    | (7) accepted ('10.4.2.3', 56520)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:41:46] "POST /socket.io/?EIO=3&transport=polling&t=NrLqHS1&sid=6242b3f581704eaca6c9b5bda9ba5380 HTTP/1.1" 400 201 0.001975
app_1    | (7) accepted ('10.4.2.3', 56522)
app_1    | 10.4.2.3 - - [25/Nov/2021 05:41:48] "GET /socket.io/?EIO=3&transport=polling&t=NrLqHpG HTTP/1.1" 200 347 0.003503
app_1    | (7) accepted ('10.4.2.3', 56524)
app_1    | (7) accepted ('10.4.2.3', 56526)
app_1    | Exception in thread Thread-2:
app_1    | Traceback (most recent call last):
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 916, in _bootstrap_inner
app_1    |     self.run()
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 25, in run
app_1    |     super().run()
app_1    |   File "/usr/local/lib/python3.6/threading.py", line 864, in run
app_1    |     self._target(*self._args, **self._kwargs)
app_1    |   File "/picobrew_pico/app/main/still_polling.py", line 91, in new_still_session
app_1    |     active_still_sessions[uid].file.write('\n]\n')
app_1    | AttributeError: 'NoneType' object has no attribute 'write'
app_1    | 
app_1    | (7) accepted ('10.4.2.3', 56552)

@erdoukki
Copy link
Contributor Author

What is the correct sequence/procedure for Distill ?
Do I need to start the PicoStill monitoring on the PicoBrew server before starting to manually start the distill process on the PicoPro ?

@tmack8001
Copy link
Collaborator

Starting the PicoStill monitoring on the PicoBrew server only populates the graph, there is no need to do this to create distillate. Starting the PicoStill process is done via either the main Pico device connected to it and/or from the embedded UI of the PicoStill itself (turning on the pump and fan, etc which is needed to be done for Zymatics).

Currently "start monitoring" simply starts a background polling job on the server that periodically will ask the PicoStill via an internal API what the data points are (as seen from the following lines taken from your access logs).

app_1    | [2021-11-25 05:37:31,046] DEBUG in still_polling: DEBUG: Retrieve PicoStill Data - http://10.4.2.225/data
app_1    | [2021-11-25 05:37:31,315] DEBUG in still_polling: DEBUG: Still Datastring: #42.37,42.08,43.40,40.37,2.69,1,1,1,None#

@erdoukki
Copy link
Contributor Author

thanks for the advice...

It looks like that this is the PicoPro which is not heating at all...

The pump is working but the "prime steam process" never end because the heat stay cold !

@tmack8001
Copy link
Collaborator

@erdoukki but when you said it would work when connected to the main Picobrew servers? Does that still work now if you connect back to your main WiFi?

@erdoukki
Copy link
Contributor Author

@tmack8001 Do you mean with the official PicoBrew servers ? I will try, because I was thinking that the main servers were now offline...

@tmack8001
Copy link
Collaborator

The main Picobrew servers are infact not offline (mentioned several times in various places). They simply have an expired ssl certificate...

@tmack8001
Copy link
Collaborator

What I want to figure out here is if there is a bug in the offline server solution or if your machine is truly not functioning as it should. Sorry I don't have the same setup as you so can't test that specific configuration.

@erdoukki
Copy link
Contributor Author

Sure, I'll try it and report...

@erdoukki
Copy link
Contributor Author

Screenshot 2021-11-29 at 08-43-57 PicoStill

Same with Officials servers...

Screenshot 2021-11-29 at 07-54-29 PicoBrew Brewhouse

The PicoPro and PicoStill looks to communicate with PicoBrew servers...

@erdoukki
Copy link
Contributor Author

I have tried to reset the PicoStill...
Also reset WiFi...
Reboot my OpenWrt WiFi AP...
Reboot my OpenWrt Gateway...

still stuck !

@tmack8001
Copy link
Collaborator

tmack8001 commented Nov 29, 2021

Basic question are you filling the top reservoir with RO or distilled water?

Are you able to simply brew in the Pico unit?

@erdoukki
Copy link
Contributor Author

erdoukki commented Dec 12, 2021

Some test with my PicoPro get nowhere !
I still get no success.
The problem is not from PicoStill.
The problem is nort with servers, official nor yours also.

The problem looks to be with temperatures censors...
May be I am in a too cold environment ?

I get some access to the factory test menu but no success and no errors reported...

Still need some help of experimentend users.

Sorry to noise your repository with this issue, but I do not know anywhere else to ask for help.

Thanks again for carring of, for clues, and help...

😉

@erdoukki
Copy link
Contributor Author

erdoukki commented Aug 1, 2022

Are you able to simply brew in the Pico unit?

I will check the brew alone...
And report here

@erdoukki
Copy link
Contributor Author

erdoukki commented Feb 9, 2023

Still same ERROR.
Have made a full dismount and deep cleaning, solder check.
I have added a TTL access for quick debug.

Still blocked with the "PRIMING STEAM GENERATOR" which never end.
Same status of latest message in debug console.

I have applied all PICO tests of the SERVICE MODE.
I have fixed the shutter pump priming.

All tests are fully OK.
All checks looks also fine.

But, in MANUAL BREW and in PICOSTILL, I always get stuck at the verry first step with the fatal error message PRIMING STEAM GENERATOR.

I can post more (debugs, ...)

I can also add and play with the JTAG...

But if anyone have any advice, clue, tips or... idea !
Please share...

@msav72
Copy link

msav72 commented Feb 9, 2023

Hello there.

I have had this issue often. Generally after I have not used my Machine in a long time. (few months). I always do 2 deep cleans after a brew with the finish tablets. Before brewing ( after it sits for months) I always run mock brew and monitor if it is heating. If I do not see it raise in temperature after 15-20 mins I know I have a clog in the heater output hose. I have tore apart my other unit and have seen the that heat output hose has a Metal nozzle in it with a small hole to let the heat out. It is easily blocked. I don't recommend taking the whole thing apart like I did as after 3 years it is still sitting in parts waiting to be reassembled. I have 2 other units working and have occasional similar issues with them the procedure below has worked every time for me.

What I do to unclog the hose. I fill the keg with Hot water (about 200 F) and run a mock brew with a finish tablet. The water temp will generally start dropping for a while and if your successful in clearing the clog you will start to see the temp rise.

The heat from both sides of the clog generally loosen up whatever is in there causing a clog.

Another thing if after you brew and you deep clean and you still see particles in your keg. it is not clean, Keep repeating until your water does not have any particles in it or they can create a clog. Any beer with oats or other starchy grains tend to hang around in the system and create clogs after it sits for a while they can be really stubborn cloggs.

Also for support you can try the picobrew Facebook page. I am not on Facebook so I am not a member but I believe it is the only place your going to find an active picobrew user base, Last I heard it was invite only.

@erdoukki
Copy link
Contributor Author

@msav72
Thanks for the detailed tips !
Very thank you...

@ALL...

Still not working but I get a new turn on it...

Some (English language) terms were hardly understandable at first.
I admin I start to understand the all over working mechanism and logical behind the PicoBrew...
I also can said again I find it a very good manufacture.
I have deeper dismount and also clean in some more hidden parts.

The only part I have still keep away from unmount and direct clean is the metal nozzle you point as the source of the problem.
I agree and found some subject about this problem in steam & boil mechanics.

I may still have the problem of the clog.
But I think there is also a potential software 'bug", or fault process...

What make me thinking about a software issue.
MANUAL BREW or COLD BREW tries get faulty

  • I always get the message on screen "PRIMING STEAM GENERATOR"
  • then, removing the step filter or anything get no results... (no stop, nothing at all)
  • then, the only action still possible is a POWER OFF... (wait give, nothing...)
    in [SERVICE MODE].
  • TEST also give some strange results
  • all looks to process and check correctly, also mechanical get the correct target effects.
  • BUT, all THERMAL tests get the GOAL value still at "-" (N/A)
    PICOBREW.COM server get no DATA logs.
  • I actually get the main brew, clean, cold brew shown in my history.

What I may try now.

  • I postpone the possible dismount of the hoze with the nozzle part (I will look around for the necessary tools and components for "resold" the metallic "lock"...)
  • I may reinstall a fresh local server with CHIEFWIGMS sources (it is planned but was less priority...
  • I may decide to try to make an account on FB and look for someone to make me an invite (but I will have to get out of my own values and will potentially get a BRAIN BUG BLUE FATAL ERROR (it's an other and out off topic story, but I promise to share feedback of this huge part of my own ... brain damage ... on this...)

What I will try

  • put again the TTL debug and look for more information
  • repair my own box and server to help the local server part

So, stay tuned...And if any advice came in mind, free it from you and share it...

@erdoukki
Copy link
Contributor Author

morning tests; same problem !

***************************************************************
                        Pico PRO
***************************************************************
[I][MAIN] DATE_TIME = Dec 16 2019 12:59:40
[I][MAIN] VERSION_STR = 0.1.34
[I][MAIN] GIT_HASH = d0da766714f2012e98f8e43ecd6519895caf565e
[I][MAIN] GIT_BRANCH = dev
[I][MAIN] SSD_TYPE = SSD1325
[I][WIFI] connectToStoredWiFi
[I][WIFI] connectToApInternal
in connectToApi
sending: 
/API/pico/register?uid=baa4a7c24f9aa6b149aadbe63462ebb2
readApiResponse()  [closeConnection = true]
HTTP/1.1 200 OK
Content-Length: 5
Connection: close
Content-Type: text/plain; charset=IBM437
Date: Mon, 13 Feb 2023 14:14:28 GMT
Server: Microsoft-IIS/10.0
Access-Control-Expose-Headers: Request-Context
Cache-Control: private
X-AspNetWebPages-Version: 3.0
X-AspNet-Version: 4.0.30319
Request-Context: appId=cid-v1:b51654f1-5f75-4752-82da-97ecb1c4d76d
X-Powered-By: ASP.NET

#T#RECV [T] 1 Bytes
in connectToApi
sending: 
/API/pico/picoChangeState?picoUID=baa4a7c24f9aa6b149aadbe63462ebb2&state=2
[I][RFID] _initRFID()
[I][RCTL] parseWasBrewing
wifi_checkFirmware
IsUpdatedRequired = 0
in connectToApi
sending: 
/API/pico/getActionsNeeded?uid=baa4a7c24f9aa6b149aadbe63462ebb2
readApiResponse()  [closeConnection = true]
HTTP/1.1 200 OK
Content-Length: 3
Connection: close
Content-Type: text/plain; charset=IBM437
Date: Mon, 13 Feb 2023 14:14:32 GMT
Server: Microsoft-IIS/10.0
Access-Control-Expose-Headers: Request-Context
Cache-Control: private
X-AspNetWebPages-Version: 3.0
X-AspNet-Version: 4.0.30319
Request-Context: appId=cid-v1:b51654f1-5f75-4752-82da-97ecb1c4d76d
X-Powered-By: ASP.NET

#7#RECV [7] 1 Bytes
[I][MEN] drawItems
[I][MENU] getActionId(0) 12 StartBrewing
[I][MENU] getActionId(1) 13 StartSousVide
[I][MENU] getActionId(2) 14 StartColdBrew
[I][MENU] getActionId(3) 15 StartManualBrew
[I][MENU] getActionId(4) 16 UtilitiesMenu
[I][MENU] getActionId(5) 17 SettingsMenu
[I][MEN] drawItems
[I][MEN] drawItems
[I][MEN] drawItems
[I][MEN] drawItems
[I][MENU] getActionId(3) 15 StartManualBrew
[I][RCTL] manualBrewProgram
Session: 
  id = 
  programId = ManualBrew (6)
  temperature = 60
  duration    = 0
  location    = 0
  type = ManualBrew (5)
  title = MANUAL BREW
  name = Manual Brew
  state = brassage
  logoText = MANUAL BREW
"ManualBrew" has 3 actions
  - 1 "Continuer"
  - 5 "Pause"
  - 4 "Arreter etape"
[I][LANG] drawScreen 2          MANUAL BREW RemoveInlineFilterScreen
[I][MENU] getActionId(0) 23 Next
[I][MENU] getActionId(1) 34 Exit
[I][MEN] drawItems
[I][MENU] getActionId(0) 23 Next
[I][LANG] drawScreen 2          MANUAL BREW ConnectHosesTogether
[I][MENU] getActionId(0) 23 Next
[I][MENU] getActionId(1) 34 Exit
[I][MEN] drawItems
[I][MENU] getActionId(0) 23 Next
[I][LANG] drawScreen 2          MANUAL BREW FillStepFilter5L
[I][MENU] getActionId(0) 23 Next
[I][MENU] getActionId(1) 34 Exit
[I][MEN] drawItems
[I][MENU] getActionId(0) 23 Next
[I][LANG] drawScreen 2          MANUAL BREW InsertStepFilterAndLid
[I][MENU] getActionId(0) 23 Next
[I][MENU] getActionId(1) 34 Exit
[I][MEN] drawItems
[I][MENU] getActionId(0) 23 Next
in connectToApi
sending: 
/API/pico/getSession?uid=baa4a7c24f9aa6b149aadbe63462ebb2&sesType=5
readApiResponse()  [closeConnection = true]
HTTP/1.1 200 OK
Content-Length: 24
Connection: close
Content-Type: text/plain; charset=IBM437
Date: Mon, 13 Feb 2023 14:14:41 GMT
Server: Microsoft-IIS/10.0
Access-Control-Expose-Headers: Request-Context
Cache-Control: private
X-AspNetWebPages-Version: 3.0
X-AspNet-Version: 4.0.30319
Request-Context: appId=cid-v1:b51654f1-5f75-4752-82da-97ecb1c4d76d
X-Powered-By: ASP.NET

#58173a7b1bde433a9694#RECV [58173a7b1bde433a9694] 20 Bytes
in connectToApi
sending: 
/API/pico/picoChangeState?picoUID=baa4a7c24f9aa6b149aadbe63462ebb2&state=2
[I][MEN] drawItems
[I][STEP] moveStepperToCompartment 2

Then, here, stuck...

no more access...

still in pumping or something noisy...

@erdoukki
Copy link
Contributor Author

Héllo all,

The server is ready in a docker with another container for piHole for the dns redirect.

The PicoPro contact my home server and I tried to force update...
I have made some test with the alternate and mixing version string in the filename.

Looks I may have put a PicoC in my PicoPro firmware.

I get a huge characters screen, also get black screen...

I also get my TTY serial debug in console mode and get some read access (never success to write to console...)

I tried another FTDI like adapter to get the cc32tool working.
I get filesystem lists and read write file also.

I get one time a factory firmware reset, but cannot reproduce...
I am now stuck in the black screen and can only get cc32tool access.

I am not sure what I can try now.

I may search for the full firmware flash from the binary file (actually an ascii content file)

  • How can I convert it to binary.

Can someone share its dump (full dump) from a working PiCoPro please ?
Or, any tips about cc32 flash, firmware, sources (yes, hope is a challenging dream...)

thanks in advance for all...

PS: tips about FB members, it is private, it is closed access, it is fu#k irrespectuous process about unbrain users, so any other trusty, respectuous, minded, network ? (or do I need to code one ?) / HUMOUR, but not only... /

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

3 participants