[Behoben] Brickd bricht zusammen (arm 2.1.0)

Loetkolben · July 12, 2014 at 11:46

Hallo zusammen,

habe hier einen Cubietruck (A20) mit dem Brickd seit Monaten nonstop am laufen. Minuetlich greifen andere Rechner auf den Stack zu. Alles ok.

Gestern habe ich zusaetzlich von einem weit entfernten Rechnen den Brickv gestartet und die neue LED Funktion getestet. Ich habe die LED hin und her laufen lassen und ploetzlich (Nach Minuten bzw. Stunden, aber ohne erkennbaren Grund) ist der brickd zusammengebrochen. Warum?

Logauszug 1

[Neu gestartet da vorher auch schon abgebrochen]
2014-07-11 16:24:50.986217 <I> <other|main_linux.c:399> Brick Daemon 2.1.0 started (daemonized)
2014-07-11 16:24:51.319809 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-11 16:24:51.330258 <E> <usb|usb_stack.c:54> Read transfer 0x7a6190 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-11 16:24:51.330478 <E> <usb|usb_stack.c:54> Read transfer 0x7a61fc returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-11 16:24:51.330571 <E> <usb|usb_stack.c:62> Read transfer 0x7a6268 returned response with length mismatch (actual: 20 != expected: 10) from Master Brick [6qATV3]
2014-07-11 16:24:54.888251 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-11 16:24:58.324227 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-11 16:24:59.294640 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-11 16:25:01.657628 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.20.142, A: disabled)
2014-07-11 16:25:01.658294 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-11 16:25:03.277237 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.23.81, A: disabled)
[...]
2014-07-11 17:57:04.340986 <I> <network|client.c:242> Client (S: 65, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-11 17:57:04.717419 <I> <network|network.c:105> Added new client (S: 65, T: plain, P: 192.168.20.158, A: disabled)
2014-07-11 17:57:04.725188 <I> <network|client.c:242> Client (S: 65, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-11 17:57:05.242788 <I> <network|network.c:105> Added new client (S: 65, T: plain, P: 192.168.20.156, A: disabled)
2014-07-11 17:57:05.248923 <I> <network|client.c:242> Client (S: 65, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-11 17:57:06.482374 <I> <network|network.c:105> Added new client (S: 65, T: plain, P: 192.168.23.81, A: disabled)
2014-07-11 17:57:33.198430 <I> <network|client.c:242> Client (S: 65, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.199064 <I> <network|client.c:242> Client (S: 64, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.262793 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.263410 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.269394 <I> <network|client.c:242> Client (S: 23, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.270230 <I> <network|client.c:242> Client (S: 24, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.271161 <I> <network|client.c:242> Client (S: 45, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.272722 <I> <network|client.c:242> Client (S: 46, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.481449 <I> <network|client.c:242> Client (S: 60, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.644438 <I> <network|client.c:242> Client (S: 59, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.848978 <I> <network|client.c:242> Client (S: 61, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.896792 <I> <network|client.c:242> Client (S: 63, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:33.906556 <I> <network|client.c:242> Client (S: 28, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:34.015000 <I> <network|client.c:242> Client (S: 27, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:34.015747 <I> <network|client.c:242> Client (S: 26, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:34.016607 <I> <network|client.c:242> Client (S: 29, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:34.017469 <I> <network|client.c:242> Client (S: 30, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:35.876514 <I> <network|client.c:242> Client (S: 62, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:35.928508 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:35.929350 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:36.096414 <I> <network|client.c:242> Client (S: 33, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:36.595562 <I> <network|client.c:242> Client (S: 49, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:41.461377 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 17:57:41.461574 <W> <network|client.c:495> Destroying client (S: 50, T: plain, P: 192.168.23.81, A: disabled) while 4 response(s) have not beed send
[und weg ist er]

[Manueller Neustart]
2014-07-11 20:35:25.148457 <I> <other|main_linux.c:399> Brick Daemon 2.1.0 started (daemonized)
2014-07-11 20:35:25.480027 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-11 20:35:25.483273 <E> <usb|usb_stack.c:54> Read transfer 0xc6d190 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-11 20:35:25.483493 <E> <usb|usb_stack.c:54> Read transfer 0xc6d1fc returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-11 20:35:25.483885 <E> <usb|usb_stack.c:62> Read transfer 0xc6d268 returned response with length mismatch (actual: 20 != expected: 10) from Master Brick [6qATV3]
2014-07-11 20:35:41.461904 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-11 20:36:01.808988 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.20.142, A: disabled)
2014-07-11 20:36:01.809928 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-11 20:36:03.720722 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.142.81, A: disabled)
2014-07-11 20:36:03.721962 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-11 20:36:04.023568 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.23.81, A: disabled)
2014-07-11 20:36:04.024999 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 20:36:04.315608 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.70.150, A: disabled)
[...]
2014-07-11 21:22:04.655027 <I> <network|client.c:242> Client (S: 64, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-11 21:22:04.965097 <I> <network|network.c:105> Added new client (S: 64, T: plain, P: 192.168.20.158, A: disabled)
2014-07-11 21:22:04.971990 <I> <network|client.c:242> Client (S: 64, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-11 21:22:07.038151 <I> <network|network.c:105> Added new client (S: 64, T: plain, P: 192.168.23.81, A: disabled)
2014-07-11 21:22:16.038111 <I> <network|client.c:242> Client (S: 57, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.038378 <I> <network|client.c:242> Client (S: 58, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.038839 <I> <network|client.c:242> Client (S: 59, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.038945 <I> <network|client.c:242> Client (S: 60, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.040259 <I> <network|client.c:242> Client (S: 61, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.041077 <I> <network|client.c:242> Client (S: 62, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.454644 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.455385 <W> <network|client.c:495> Destroying client (S: 20, T: plain, P: 192.168.23.81, A: disabled) while 2 response(s) have not beed send
[und weg ist er]

[Manueller Neustart]
2014-07-11 21:33:49.867743 <I> <other|main_linux.c:399> Brick Daemon 2.1.0 started (daemonized)
2014-07-11 21:33:50.210163 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-11 21:33:50.238491 <E> <usb|usb_stack.c:54> Read transfer 0x35e190 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-11 21:33:50.238731 <E> <usb|usb_stack.c:54> Read transfer 0x35e1fc returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-11 21:33:50.238819 <E> <usb|usb_stack.c:62> Read transfer 0x35e268 returned response with length mismatch (actual: 20 != expected: 10) from Master Brick [6qATV3]
2014-07-11 21:33:50.259328 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-11 21:34:01.602747 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.20.142, A: disabled)
2014-07-11 21:34:01.603669 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-11 21:34:03.277598 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.23.81, A: disabled)
2014-07-11 21:34:03.280389 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:34:04.202591 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.142.81, A: disabled)
[...]
2014-07-12 01:00:05.095423 <I> <network|client.c:242> Client (S: 67, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-12 01:00:05.146476 <I> <network|network.c:105> Added new client (S: 67, T: plain, P: 192.168.20.156, A: disabled)
2014-07-12 01:00:05.148135 <I> <network|client.c:242> Client (S: 67, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-12 01:00:06.871316 <I> <network|network.c:105> Added new client (S: 67, T: plain, P: 192.168.23.81, A: disabled)
2014-07-12 01:00:11.906864 <I> <network|network.c:105> Added new client (S: 68, T: plain, P: 192.168.23.81, A: disabled)
2014-07-12 01:00:22.788823 <I> <network|client.c:242> Client (S: 49, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 01:00:22.789838 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 01:00:22.790632 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 01:00:22.791123 <W> <network|client.c:495> Destroying client (S: 17, T: plain, P: 192.168.23.81, A: disabled) while 14 response(s) have not beed send
[und weg ist er]

[Manueller Neustart]
2014-07-12 04:17:06.106847 <I> <other|main_linux.c:399> Brick Daemon 2.1.0 started (daemonized)
2014-07-12 04:17:06.442410 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-12 04:17:06.452552 <E> <usb|usb_stack.c:54> Read transfer 0x6ef190 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-12 04:17:06.452783 <E> <usb|usb_stack.c:54> Read transfer 0x6ef1fc returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-12 04:17:06.452886 <E> <usb|usb_stack.c:62> Read transfer 0x6ef268 returned response with length mismatch (actual: 20 != expected: 10) from Master Brick [6qATV3]
2014-07-12 04:17:06.584489 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-12 04:17:06.654508 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.23.81, A: disabled)
2014-07-12 04:17:43.114548 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-12 04:18:02.121975 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.142, A: disabled)
[...]

Aufgrund dieses Fehlers geht es im 2. Beitrag weiter:

"Fehler beim Schreiben des Beitrages. Beitrag hat die max. Länge erreicht (20000 Zeichen)."

Loetkolben · July 12, 2014 at 11:49

Im zweiten Beispiel habe ich an der Situation nichts veraendert, aber die Logfiles sehen anders aus. Vielleicht haengt es bei diesem mal damit zusammen, dass der weit entfernte Brickv in der Nacht vielleicht mal nicht erreichtbar war. (Providerprobleme)

[...]
2014-07-12 05:10:06.129957 <I> <network|network.c:105> Added new client (S: 70, T: plain, P: 192.168.70.150, A: disabled)
2014-07-12 05:10:06.140868 <I> <network|client.c:242> Client (S: 70, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-12 05:10:06.452147 <I> <network|network.c:105> Added new client (S: 70, T: plain, P: 192.168.23.81, A: disabled)
2014-07-12 05:10:14.060499 <I> <network|client.c:242> Client (S: 44, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.060691 <I> <network|client.c:242> Client (S: 45, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.076709 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.077244 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.078239 <I> <network|client.c:242> Client (S: 18, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.079139 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.080047 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.080975 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.081860 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.276522 <I> <network|client.c:242> Client (S: 23, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.277138 <I> <network|client.c:242> Client (S: 39, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.277227 <W> <network|client.c:495> Destroying client (S: 39, T: plain, P: 192.168.23.81, A: disabled) while 16 response(s) have not beed send
2014-07-12 05:10:14.277472 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.277636 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.277744 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.277834 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.277924 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.278117 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.278297 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.278400 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.278491 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.278578 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
[...]
2014-07-12 05:10:14.332228 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.332315 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.332398 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.332480 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.332561 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.333790 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.334005 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.334119 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:14.334231 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
[...]
2014-07-12 05:10:15.032396 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:15.032477 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:15.032558 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-12 05:10:15.032677 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)
[und weg ist er]

[Manueller Neustart]
2014-07-12 13:22:03.466557 <I> <other|main_linux.c:399> Brick Daemon 2.1.0 started (daemonized)
2014-07-12 13:22:03.799956 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-12 13:22:03.821729 <E> <usb|usb_stack.c:54> Read transfer 0x1fa0190 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-12 13:22:03.821973 <E> <usb|usb_stack.c:54> Read transfer 0x1fa01fc returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-12 13:22:03.822075 <E> <usb|usb_stack.c:62> Read transfer 0x1fa0268 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-12 13:22:03.822158 <E> <usb|usb_stack.c:62> Read transfer 0x1fa02d4 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-12 13:22:03.822235 <E> <usb|usb_stack.c:62> Read transfer 0x1fa0340 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-12 13:22:03.822818 <E> <usb|usb_stack.c:62> Read transfer 0x1fa0190 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-12 13:22:03.823803 <E> <usb|usb_stack.c:62> Read transfer 0x1fa01fc returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-12 13:22:04.176290 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.10.158, A: disabled)
2014-07-12 13:22:04.181477 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-12 13:22:04.707175 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.70.150, A: disabled)
2014-07-12 13:22:04.708541 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-12 13:22:05.231387 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.158, A: disabled)

Die Anzahl der Zeilen die in den 2 Sekunden geschrieben wurden ist recht bemerkenswert.

[# cat brickd.log | grep 7281224 | wc -l ==> 8220 Zeilen]

Andere Logfiles geben garnichts her. Habt ihr eine Idee?

Der Loetkolben

photron · July 14, 2014 at 09:25

Diese beiden Zeilen sind interessant:

2014-07-12 05:10:14.277472 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: P^Mo, A: disabled), disconnecting client: EBADF (9)

2014-07-12 05:10:14.334119 <E> <network|client.c:260> Could not receive from client (S: 7281224, T: plain, P: PTî¶PTî¶23.81, A: disabled), disconnecting client: EBADF (9)

"S: 7281224" heißt der File Descriptor des Sockets diese Clients hat einen Wert von 7281224, das ist sehr hoch und ungewöhnlich, daher kommt da dann auch ein EBADF (ungültiger File Descriptor) als Fehler bei rum.

"P: PTî¶PTî¶23.81" ist der Peername des Clients, da sollte eine IP Adresse stehen, das sieht aber nach Schrott aus.

Das sieht auf den ersten Blick nach Heap Corruption aus. Irgendwie bringst du brickd dazu irgendwo falsch im Speicher herumzuschreiben.

Kannst du das Problem reproduzieren? Es wäre hilfreich dazu ein Valgrind Log zu sehen. Dazu brickd manuell mit Valgrind starten:

sudo valgrind brickd

Loetkolben · July 14, 2014 at 13:34

Hallo photron,

reproduzieren kann ich es im Prinzip "nicht", aber es tritt immer wieder auf.

Nochmals zur Erinnerung: Der brickd hat 2 Monate problemlos gelaufen. Erst als ich mit dem Brickv von einem anderen Recher "dazwischengefunkt" habe ist das passiert.

Hier der letzte Absturz:

2014-07-12 14:10:03.374878 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-12 14:10:04.320905 <I> <network|network.c:105> Added new client (S: 19, T: plain, P: 192.168.23.81, A: disabled)
2014-07-12 14:10:04.322835 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 14:10:04.337956 <I> <network|network.c:105> Added new client (S: 19, T: plain, P: 192.168.10.158, A: disabled)
2014-07-12 14:10:04.339626 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-12 14:10:04.782470 <I> <network|network.c:105> Added new client (S: 19, T: plain, P: 192.168.20.158, A: disabled)
2014-07-12 14:10:04.784317 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-12 14:10:05.466541 <I> <network|network.c:105> Added new client (S: 19, T: plain, P: 192.168.20.156, A: disabled)
2014-07-12 14:10:05.468242 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-12 14:10:05.802158 <I> <network|network.c:105> Added new client (S: 19, T: plain, P: 192.168.70.150, A: disabled)
2014-07-12 14:10:05.803450 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-12 14:10:06.895196 <I> <network|client.c:242> Client (S: 38, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 14:10:06.905604 <I> <network|client.c:242> Client (S: 39, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 14:10:06.905732 <I> <network|client.c:242> Client (S: 40, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 14:10:06.905781 <I> <network|client.c:242> Client (S: 36, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 14:10:07.118505 <I> <network|network.c:105> Added new client (S: 19, T: plain, P: 192.168.23.81, A: disabled)
2014-07-12 14:10:08.211082 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.23.81, A: disabled)
2014-07-12 14:10:22.639772 <I> <network|client.c:242> Client (S: 33, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 14:10:22.639963 <W> <network|client.c:495> Destroying client (S: 33, T: plain, P: 192.168.23.81, A: disabled) while 3 response(s) have not beed send

Dann schauen wir mal.

[Zur Sicherheit nochmals "gestoppt"]
# /etc/init.d/brickd stop
Stopping Brick Daemon: start-stop-daemon: warning: failed to kill 24391: No such process
1 pids were not killed
No process in pidfile '/var/run/brickd.pid' found running; none killed.


[starten]
# valgrind brickd
==24206== Memcheck, a memory error detector
==24206== Copyright (C) 2002-2011, and GNU GPL'd, by Julian Seward et al.
==24206== Using Valgrind-3.7.0 and LibVEX; rerun with -h for copyright info
==24206== Command: brickd
==24206==
2014-07-14 15:32:42.282090 <I> <other|main_linux.c:401> Brick Daemon 2.1.0 started
2014-07-14 15:32:43.832974 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-14 15:32:44.343778 <E> <usb|usb_stack.c:54> Read transfer 0x499e448 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-14 15:32:44.366113 <E> <usb|usb_stack.c:54> Read transfer 0x499e4b4 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-14 15:32:44.371668 <E> <usb|usb_stack.c:62> Read transfer 0x499e520 returned response with length mismatch (actual: 20 != expected: 10) from Master Brick [6qATV3]
2014-07-14 15:33:01.491268 <I> <network|network.c:105> Added new client (S: 15, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 15:33:01.551670 <I> <network|client.c:242> Client (S: 15, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
[...]

Der Loetkolben

photron · July 14, 2014 at 14:06

Okay, ich erwarte das Valgrind Invalid Read oder Write Operationen meldet wenn das Problem auftritt. Die Ausgabe von Valgrind dazu mit samt den Backtraces interessiert mich, um zu sehen wo das Problem her kommt.

Ich habe mit deine Logs nochmal angesehen und zwei Dinge sind mir aufgefallen. Die letzten zwei Logausgabe bevor das Problem auftritt sind immer:

2014-07-11 21:22:16.454644 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-11 21:22:16.455385 <W> <network|client.c:495> Destroying client (S: 20, T: plain, P: 192.168.23.81, A: disabled) while 2 response(s) have not beed send

Ein Client schließt seine Verbindung bevor brickd im alle Response Pakete schicken konnte.

Auch ist auffällig, dass kurz vorher viele Verbindungen in sehr kurzer Zeit geschlossen werden:

2014-07-12 05:10:14.060499 <I> <network|client.c:242> Client (S: 44, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.060691 <I> <network|client.c:242> Client (S: 45, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.076709 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.077244 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.078239 <I> <network|client.c:242> Client (S: 18, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.079139 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.080047 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.080975 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-12 05:10:14.081860 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer

Da frage ich mich gerade wie das zustande kommt. Wie lange bleiben die Verbindungen der minütlich zugreifenden anderen Rechner bestehen?

Loetkolben · July 14, 2014 at 14:27

Hallo photron,

"dazu mit samt den Backtraces"? Wo werden die stehen?

Also die verschiedenen Rechner machen eigentlich nur "eine Sekunde" die Verbindung auf.

Zum einen machen minuetlich verschiedene Rechner die LEDs an dem IO-16 aus (Port setzen),

zum anderen wird minuetlich die Temperatur gelesen und

stuendlich Temperatur, Humidity und Light.

Parallel dazu laueft im Moment der Brickviewer mit dem LED Test.

Hier mal die gerade kompletten Logs von 3 Minuten:

[Minute 16:10h]
2014-07-14 16:10:01.661885 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 16:10:01.672418 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 16:10:03.350873 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 16:10:03.353988 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 16:10:04.045736 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 16:10:04.048737 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 16:10:04.150668 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 16:10:04.153882 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 16:10:04.281305 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 16:10:04.290124 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 16:10:04.934774 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 16:10:04.937936 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 16:10:05.997459 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 16:10:06.000572 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 16:10:07.208034 <I> <network|network.c:105> Added new client (S: 20, T: plain, P: 192.168.23.81, A: disabled)

[Minute 16:11h]
2014-07-14 16:11:01.796758 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 16:11:01.799480 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 16:11:03.450018 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 16:11:03.453168 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 16:11:03.648031 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 16:11:03.651402 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 16:11:03.723464 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 16:11:03.748812 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 16:11:03.925089 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 16:11:03.928146 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 16:11:05.191133 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 16:11:05.194473 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 16:11:06.418001 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 16:11:06.427306 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 16:11:06.659925 <I> <network|network.c:105> Added new client (S: 21, T: plain, P: 192.168.23.81, A: disabled)

[Minute 16:12h]
2014-07-14 16:12:01.943874 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 16:12:01.949680 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 16:12:03.469227 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 16:12:03.472801 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 16:12:03.864073 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 16:12:03.867497 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 16:12:04.325956 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 16:12:04.371815 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 16:12:04.380284 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 16:12:04.383560 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 16:12:05.106432 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 16:12:05.114134 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 16:12:05.536294 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 16:12:05.541223 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 16:12:06.726974 <I> <network|network.c:105> Added new client (S: 22, T: plain, P: 192.168.23.81, A: disabled)

Der Loetkolben

photron · July 14, 2014 at 14:36

Sorry, das was ungeschickt formuliert. Valgrind gibt das alles an einem Stück aus, das sieht dann z.B. so aus:

==19182== Invalid write of size 4
==19182==    at 0x804838F: f (example.c:6)
==19182==    by 0x80483AB: main (example.c:11)
==19182==  Address 0x1BA45050 is 0 bytes after a block of size 40 alloc'd
==19182==    at 0x1B8FF5CD: malloc (vg_replace_malloc.c:130)
==19182==    by 0x8048385: f (example.c:5)
==19182==    by 0x80483AB: main (example.c:11)

Das Log sieht jetzt aus wie ich es erwarten würde, nach deiner Beschreibung. Connect und Disconnect schön abwechselnd.

Im Fehlerfall sind dann aber im Log Häufungen von Disconnects, was ich komische finde. Das passt nicht so wirklich zum beschriebenen Verhalten deines Aufbaus.

Loetkolben · July 14, 2014 at 15:12

Hallo photron,

ich habe nichts gemacht, ausser den LEDs zuzusehen. :-)

In der Minute :05 kommen wieder diese "disconnects", aber alles funktioniert weiterhin.

Sorry fuer die langen Logfiles, aber das meiste kann man ja uebersehen.

Warten wir mal ab. ...

[Volle Stunde mit mehreren Abfragen]
2014-07-14 17:00:01.719486 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:00:01.722799 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:00:03.301372 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:00:03.305750 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:00:04.028550 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 17:00:04.032780 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 17:00:04.166246 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:00:04.179089 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:00:04.790461 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 17:00:04.794799 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 17:00:05.203741 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 17:00:05.207508 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 17:00:05.557610 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 17:00:05.561692 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 17:00:07.159151 <I> <network|network.c:105> Added new client (S: 50, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:00:12.120037 <I> <network|network.c:105> Added new client (S: 51, T: plain, P: 192.168.23.81, A: disabled)

[Naechste Minute]
2014-07-14 17:01:01.856973 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:01:01.859988 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:01:03.579989 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:01:03.584022 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:01:03.922890 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 17:01:03.927424 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 17:01:03.953458 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:01:03.960411 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:01:04.293426 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 17:01:04.302518 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 17:01:04.761174 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 17:01:04.764992 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 17:01:05.744945 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 17:01:05.749566 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 17:01:06.549841 <I> <network|network.c:105> Added new client (S: 52, T: plain, P: 192.168.23.81, A: disabled)

[Naechste Minute]
2014-07-14 17:02:01.998804 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:02:02.003687 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:02:03.274799 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:02:03.278715 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:02:03.768898 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:02:03.772358 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:02:03.900456 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 17:02:03.904479 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 17:02:04.969101 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 17:02:04.972990 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 17:02:05.435191 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 17:02:05.439994 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 17:02:05.534142 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 17:02:05.538010 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 17:02:06.768969 <I> <network|network.c:105> Added new client (S: 53, T: plain, P: 192.168.23.81, A: disabled)

[Naechste Minute]
2014-07-14 17:03:01.145006 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:03:01.153532 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:03:03.128293 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:03:03.132615 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:03:04.075109 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:03:04.078832 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:03:04.427176 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 17:03:04.430902 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 17:03:04.491576 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 17:03:04.496614 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 17:03:05.126683 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 17:03:05.130431 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 17:03:05.144597 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 17:03:05.150428 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 17:03:07.166439 <I> <network|network.c:105> Added new client (S: 54, T: plain, P: 192.168.23.81, A: disabled)

[Naechste Minute]
2014-07-14 17:04:01.283218 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:04:01.286781 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:04:03.120357 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:04:03.124548 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:04:03.432849 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:04:03.436676 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:04:04.281523 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 17:04:04.285634 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 17:04:04.313750 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 17:04:04.317674 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 17:04:04.322376 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 17:04:04.329247 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 17:04:04.836636 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 17:04:04.840574 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 17:04:06.456575 <I> <network|network.c:105> Added new client (S: 55, T: plain, P: 192.168.23.81, A: disabled)

[Naechste Minute]
2014-07-14 17:05:01.437986 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:05:01.441452 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:05:03.242145 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:05:03.245880 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:05:03.672410 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:05:03.676201 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:03.933376 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 17:05:03.938263 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 17:05:04.330718 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 17:05:04.335208 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 17:05:05.498665 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 17:05:05.509569 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 17:05:05.629372 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 17:05:05.636125 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 17:05:06.798982 <I> <network|network.c:105> Added new client (S: 56, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:05:27.493023 <I> <network|client.c:242> Client (S: 52, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.496349 <I> <network|client.c:242> Client (S: 53, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.498551 <I> <network|client.c:242> Client (S: 54, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.500419 <I> <network|client.c:242> Client (S: 55, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.654690 <I> <network|client.c:242> Client (S: 56, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.657963 <I> <network|client.c:242> Client (S: 15, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.660388 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.662532 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.664822 <I> <network|client.c:242> Client (S: 18, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.670804 <I> <network|client.c:242> Client (S: 19, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.673393 <I> <network|client.c:242> Client (S: 20, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.675656 <I> <network|client.c:242> Client (S: 21, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.678000 <I> <network|client.c:242> Client (S: 22, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.680084 <I> <network|client.c:242> Client (S: 23, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.682122 <I> <network|client.c:242> Client (S: 24, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.684203 <I> <network|client.c:242> Client (S: 25, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.686124 <I> <network|client.c:242> Client (S: 26, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.688116 <I> <network|client.c:242> Client (S: 27, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.690025 <I> <network|client.c:242> Client (S: 28, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.691992 <I> <network|client.c:242> Client (S: 29, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.694226 <I> <network|client.c:242> Client (S: 30, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.696240 <I> <network|client.c:242> Client (S: 31, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.698155 <I> <network|client.c:242> Client (S: 32, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.700077 <I> <network|client.c:242> Client (S: 34, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.701859 <I> <network|client.c:242> Client (S: 35, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.707557 <I> <network|client.c:242> Client (S: 36, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.709932 <I> <network|client.c:242> Client (S: 37, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.712176 <I> <network|client.c:242> Client (S: 38, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.714420 <I> <network|client.c:242> Client (S: 39, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.716571 <I> <network|client.c:242> Client (S: 40, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.718503 <I> <network|client.c:242> Client (S: 41, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.720302 <I> <network|client.c:242> Client (S: 42, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.722622 <I> <network|client.c:242> Client (S: 43, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.724806 <I> <network|client.c:242> Client (S: 44, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.726746 <I> <network|client.c:242> Client (S: 45, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.728723 <I> <network|client.c:242> Client (S: 46, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.730564 <I> <network|client.c:242> Client (S: 47, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.732393 <I> <network|client.c:242> Client (S: 48, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.734508 <I> <network|client.c:242> Client (S: 49, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.736548 <I> <network|client.c:242> Client (S: 50, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:27.738482 <I> <network|client.c:242> Client (S: 51, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:05:39.498087 <I> <network|network.c:105> Added new client (S: 15, T: plain, P: 192.168.23.81, A: disabled)

[Naechste Minute]
2014-07-14 17:06:01.575833 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:06:01.578760 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:06:03.302158 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:06:03.305245 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:06:03.627278 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:06:03.630389 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:06:04.216758 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 17:06:04.220346 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 17:06:04.369501 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 17:06:04.373568 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 17:06:04.676885 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 17:06:04.681333 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 17:06:06.178755 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 17:06:06.186388 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 17:06:06.745065 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.23.81, A: disabled)
[Alles funktioniert noch]

Edit:

Kann es sein, dass pro Minute ein "Added new client" mehr hinzukommt, als dass es "disconnects" gibt?

Kann es sein, dass die ueberzaehligen "Added new client" irgendwann am Stueck abgebaut werden?

Das muesste ja irgendwo in meiner Logik liegen, aber ich wuesste nicht wo?!

Aber egal, der bickd sollte nicht abstuerzen. Warten wir mal weiter ab.

Der Loetkolben

photron · July 14, 2014 at 15:48

Laufen die Programme die die Anfragen machen durch oder startest du die für jede Anfrage neu?

Wenn dein Programm durchläuft und ab und zu "vergisst" Sockets/IPConnections wieder zu schließen, dann könnte sich so eine Liste offener Verbindungen ansammeln. Diese würden dann vom Betriebssystem wieder geschlossen, wenn das Programm beendet wird.

Da ich aber annehme, dass du das Protokoll von Hand mit einzelnen netcat Aufrufen sprichst kann das eigentlich nicht das Problem sein.

Loetkolben · July 14, 2014 at 16:17

Hallo photron.

Ich mache pro Anfrage eine neue Verbindung auf, bzw. ich nutze nc (netcat).

Es kann aber sein, dass ich bei "set" Befehlen keine Antwort verarbeite, da keine Antwort definiert ist.

Aber hier die neuesten Entwicklungen. Ploetzlich ging es los. S.u. Den Anfang dieses rasenden Durchscrollens habe ich nicht, da ueber 100 Meldungen pro Sekunde im Moment hier durchlaufen. Erstaunlicherweise funktioniert noch alles. Die LEDs gehen aus (IO-16) und der Colortest laeuft bunt durch.

Soll ich mal mit einem CTRL-C abbrechen und neu starten?

[Ploetzlich ging es los: Ueber 100 Meldungen pro Sekunde]
2014-07-14 17:52:56.221111 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.223142 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.225051 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.226947 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.228801 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.230661 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.232545 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.234491 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.236401 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.238260 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.240134 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.241995 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.243973 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.245835 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.247719 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.249584 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.251454 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.253347 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.255257 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.257096 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.258922 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.260779 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.262633 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.264412 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.265990 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.267523 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.269278 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.271100 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.272972 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.275054 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.277551 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.280010 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.282874 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.284944 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.286858 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.288731 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.290582 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.292448 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.294396 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.296280 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.299013 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.301008 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.302893 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.304861 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.306699 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.308556 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.310376 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.312207 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.314246 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.316149 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.318484 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.320902 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.322861 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.324839 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.326722 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.328576 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.330396 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.332273 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.334238 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.336102 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:52:56.337954 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)


[... Normale Meldungen mittendrin ...]
2014-07-14 17:54:01.707544 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.711177 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.715042 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.718682 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.723201 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.727670 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.733908 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 17:54:01.738788 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.741545 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.744768 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.747394 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 17:54:01.750506 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.753277 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.756008 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.758690 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.761280 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.764191 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:01.766831 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)


[... Normale Meldungen mittendrin ...]
2014-07-14 17:54:03.771691 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.774262 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.776932 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.779513 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.782188 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.784872 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.788410 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 17:54:03.790642 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.793531 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.796635 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.799247 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 17:54:03.802314 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.809957 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.813554 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 17:54:03.815799 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.818475 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.821455 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.823939 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 17:54:03.826942 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.830385 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.833842 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.836576 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.839239 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 17:54:03.841793 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)


[... 10 Minuten spaeter immernoch...]
2014-07-14 18:03:50.030142 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.033250 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.035855 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.038448 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.040942 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.043552 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.046037 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.048586 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:03:50.050941 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)

Der Loetkolben

Loetkolben · July 14, 2014 at 16:33

Ich hab mal ein CTRL-C gemacht:

[...]
2014-07-14 18:30:18.668157 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.669841 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.671519 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.673270 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.674968 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.676667 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.678335 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.680016 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.681694 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 18:30:18.683448 <E> <network|client.c:260> Could not receive from client (S: 41, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)

^C2014-07-14 18:30:18.694041 <I> <event|event_posix.c:53> Received SIGINT
2014-07-14 18:30:18.699659 <W> <event|event_posix.c:278> Handled only 1 of 2 ready event source(s)
2014-07-14 18:30:18.710218 <W> <network|client.c:490> Destroying client (S: 33, T: plain, P: 192.168.142.81, A: disabled) while 11 request(s) are still pending
2014-07-14 18:30:18.864013 <W> <usb|usb_transfer.c:172> Could not cancel pending read transfer 0x499e448 for Master Brick [6qATV3]: LIBUSB_ERROR_NOT_FOUND (-5)
2014-07-14 18:30:18.872744 <W> <usb|usb_transfer.c:205> Leaking pending read transfer 0x499e448 for Master Brick [6qATV3]
2014-07-14 18:30:18.957442 <W> <event|event.c:82> Leaking 1 event sources
2014-07-14 18:30:18.962159 <I> <other|main_linux.c:461> Brick Daemon 2.1.0 stopped
==24206==
==24206== HEAP SUMMARY:
==24206==     in use at exit: 136 bytes in 2 blocks
==24206==   total heap usage: 458,823 allocs, 458,821 frees, 16,264,428 bytes allocated
==24206==
==24206== LEAK SUMMARY:
==24206==    definitely lost: 116 bytes in 1 blocks
==24206==    indirectly lost: 0 bytes in 0 blocks
==24206==      possibly lost: 0 bytes in 0 blocks
==24206==    still reachable: 20 bytes in 1 blocks
==24206==         suppressed: 0 bytes in 0 blocks
==24206== Rerun with --leak-check=full to see details of leaked memory
==24206==
==24206== For counts of detected and suppressed errors, rerun with: -v
==24206== ERROR SUMMARY: 10000000 errors from 15 contexts (suppressed: 25 from 9)

und neu gestartet

# valgrind brickd
==27981== Memcheck, a memory error detector
==27981== Copyright (C) 2002-2011, and GNU GPL'd, by Julian Seward et al.
==27981== Using Valgrind-3.7.0 and LibVEX; rerun with -h for copyright info
==27981== Command: brickd
==27981==
2014-07-14 18:31:15.926183 <I> <other|main_linux.c:401> Brick Daemon 2.1.0 started
2014-07-14 18:31:17.467489 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-14 18:31:17.956169 <E> <usb|usb_stack.c:54> Read transfer 0x499e448 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-14 18:31:17.978731 <E> <usb|usb_stack.c:54> Read transfer 0x499e4b4 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-14 18:31:17.984327 <E> <usb|usb_stack.c:62> Read transfer 0x499e520 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-14 18:31:17.988254 <E> <usb|usb_stack.c:62> Read transfer 0x499e58c returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-14 18:31:17.991231 <E> <usb|usb_stack.c:62> Read transfer 0x499e5f8 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-14 18:31:17.994197 <E> <usb|usb_stack.c:62> Read transfer 0x499e448 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-14 18:31:17.997133 <E> <usb|usb_stack.c:62> Read transfer 0x499e4b4 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-14 18:31:17.999930 <E> <usb|usb_stack.c:62> Read transfer 0x499e520 returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-14 18:31:18.003005 <E> <usb|usb_stack.c:62> Read transfer 0x499e58c returned response with length mismatch (actual: 10 != expected: 181) from Master Brick [6qATV3]
2014-07-14 18:31:18.070422 <I> <network|network.c:105> Added new client (S: 15, T: plain, P: 192.168.142.81, A: disabled)

2014-07-14 18:32:01.347678 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 18:32:01.364776 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 18:32:03.269878 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 18:32:03.272334 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 18:32:03.711898 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.23.81, A: disabled)
2014-07-14 18:32:03.714554 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.23.81, A: disabled) disconnected by peer
2014-07-14 18:32:04.146327 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.10.158, A: disabled)
2014-07-14 18:32:04.150801 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.10.158, A: disabled) disconnected by peer
2014-07-14 18:32:04.705988 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.70.150, A: disabled)
2014-07-14 18:32:04.710394 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.70.150, A: disabled) disconnected by peer
2014-07-14 18:32:04.718667 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.158, A: disabled)
2014-07-14 18:32:04.721136 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.20.158, A: disabled) disconnected by peer
2014-07-14 18:32:04.786894 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.20.156, A: disabled)
2014-07-14 18:32:04.793877 <I> <network|client.c:242> Client (S: 16, T: plain, P: 192.168.20.156, A: disabled) disconnected by peer
2014-07-14 18:32:06.696869 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.23.81, A: disabled)

Alles sieht "normal" aus.

Der Loetkolben

photron · July 14, 2014 at 16:38

==24206== ERROR SUMMARY: 10000000 errors from 15 contexts (suppressed: 25 from 9)

10000000 Errors!

Da ist irgendwas oberfaul. Es ist vom Aufbau von brickd her nicht möglich das diese Fehlermeldung zweimal für den gleichen Socket auftritt. Auch das es Fehlercode EBADF ist ist mit höchst Suspekt.

Ich könnte mir vorstellen es reicht an einer Stelle passend eine der Datenstrukturen von brickd zu zerschreiben, dabei diese Problem zu erzeugen, den Rest aber weiterlaufen zu lassen.

Starte Valgrind mal mit Logfile, damit dessen Ausgaben nicht in der Menge von brickd Meldungen verloren gehen:

sudo valgrind --log-file=valgrind.log brickd

Loetkolben · July 14, 2014 at 16:56

Hallo photron.

Starte Valgrind mal mit Logfile, damit dessen Ausgaben nicht in der Menge von brickd Meldungen verloren gehen:

So, neu gestartet.

Ich hatte bisher keine valgrind Meldungen ausser am Start und am CTRL-C Ende gesehen. Was beim Start des Massenlogs passierte konnte ich nicht sehen. Auch der Scrollback im Terminalfenster war nicht gross genug.

Mal sehen ...

# valgrind --log-file=/storage/valgrind_tinkerforge.log brickd
2014-07-14 18:51:28.984961 <I> <other|main_linux.c:401> Brick Daemon 2.1.0 started
2014-07-14 18:51:30.557170 <I> <usb|usb.c:144> Added USB device (bus: 2, device: 4) at index 0: Master Brick [6qATV3]
2014-07-14 18:51:31.032827 <E> <usb|usb_stack.c:54> Read transfer 0x499e448 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-14 18:51:31.054101 <E> <usb|usb_stack.c:54> Read transfer 0x499e4b4 returned response with incomplete header (actual: 0 < minimum:  from Master Brick [6qATV3]
2014-07-14 18:51:31.058883 <E> <usb|usb_stack.c:62> Read transfer 0x499e520 returned response with length mismatch (actual: 20 != expected: 10) from Master Brick [6qATV3]
2014-07-14 18:51:31.182864 <I> <network|network.c:105> Added new client (S: 16, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 18:52:02.091354 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.20.142, A: disabled)
2014-07-14 18:52:02.107790 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.20.142, A: disabled) disconnected by peer
2014-07-14 18:52:03.743783 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.142.81, A: disabled)
2014-07-14 18:52:03.747370 <I> <network|client.c:242> Client (S: 17, T: plain, P: 192.168.142.81, A: disabled) disconnected by peer
2014-07-14 18:52:04.069562 <I> <network|network.c:105> Added new client (S: 17, T: plain, P: 192.168.23.81, A: disabled)

 tail -f valgrind_tinkerforge.log
==28412== Memcheck, a memory error detector
==28412== Copyright (C) 2002-2011, and GNU GPL'd, by Julian Seward et al.
==28412== Using Valgrind-3.7.0 and LibVEX; rerun with -h for copyright info
==28412== Command: brickd
==28412== Parent PID: 23188
==28412==

Der Loetkolben

Loetkolben · July 14, 2014 at 18:15

Da war man gerade mal einkaufen und schon gehen die Probleme wieder los.

20:07h - Am Bildschirm (Wer weiss wie lange schon) rennt das hier durch bis zum CTRL-C

[s: Parameter in diesem Fall wiederholen sich die Nummern]
2014-07-14 20:08:01.964932 <E> <network|client.c:260> Could not receive from client (S: 61, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.966417 <E> <network|client.c:260> Could not receive from client (S: 49, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.967705 <E> <network|client.c:260> Could not receive from client (S: 50, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.969208 <E> <network|client.c:260> Could not receive from client (S: 28, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.970719 <E> <network|client.c:260> Could not receive from client (S: 29, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.972228 <E> <network|client.c:260> Could not receive from client (S: 39, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.973853 <E> <network|client.c:260> Could not receive from client (S: 60, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)

[s: Parameter in diesem Fall wiederholen sich die Nummern]
2014-07-14 20:08:01.975396 <E> <network|client.c:260> Could not receive from client (S: 61, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.977167 <E> <network|client.c:260> Could not receive from client (S: 49, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.978752 <E> <network|client.c:260> Could not receive from client (S: 50, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.980280 <E> <network|client.c:260> Could not receive from client (S: 28, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.981799 <E> <network|client.c:260> Could not receive from client (S: 29, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.983402 <E> <network|client.c:260> Could not receive from client (S: 39, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.984886 <E> <network|client.c:260> Could not receive from client (S: 60, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)

[s: Parameter in diesem Fall wiederholen sich die Nummern]
2014-07-14 20:08:01.986395 <E> <network|client.c:260> Could not receive from client (S: 61, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.989602 <E> <network|client.c:260> Could not receive from client (S: 49, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.991326 <E> <network|client.c:260> Could not receive from client (S: 50, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.992868 <E> <network|client.c:260> Could not receive from client (S: 28, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.994426 <E> <network|client.c:260> Could not receive from client (S: 29, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.995911 <E> <network|client.c:260> Could not receive from client (S: 39, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:01.997382 <E> <network|client.c:260> Could not receive from client (S: 60, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)

[s: Parameter in diesem Fall wiederholen sich die Nummern]
2014-07-14 20:08:01.998900 <E> <network|client.c:260> Could not receive from client (S: 61, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:02.000747 <E> <network|client.c:260> Could not receive from client (S: 49, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:02.002384 <E> <network|client.c:260> Could not receive from client (S: 50, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:02.004044 <E> <network|client.c:260> Could not receive from client (S: 28, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:02.005614 <E> <network|client.c:260> Could not receive from client (S: 29, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:02.007128 <E> <network|client.c:260> Could not receive from client (S: 39, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:02.008666 <E> <network|client.c:260> Could not receive from client (S: 60, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)


^C2014-07-14 20:08:02.010216 <E> <network|client.c:260> Could not receive from client (S: 61, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 20:08:02.021412 <I> <event|event_posix.c:53> Received SIGINT
2014-07-14 20:08:02.027114 <W> <event|event_posix.c:278> Handled only 1 of 9 ready event source(s)
2014-07-14 20:08:02.268574 <W> <event|event.c:82> Leaking 7 event sources
2014-07-14 20:08:02.273916 <I> <other|main_linux.c:461> Brick Daemon 2.1.0 stopped

Hier der valgrind, leider gibt es keine Timestamps, aber es ist alles was zu sehen ist. (Mehr steht nicht auf dem Bildschirm, bzw. im Logfile)

# tail -f valgrind_tinkerforge.log
==28412== Memcheck, a memory error detector
==28412== Copyright (C) 2002-2011, and GNU GPL'd, by Julian Seward et al.
==28412== Using Valgrind-3.7.0 and LibVEX; rerun with -h for copyright info
==28412== Command: brickd
==28412== Parent PID: 23188
[Hier stehen wir direkt nach dem starten]

==28412==
==28412== Invalid read of size 4
==28412==    at 0xBC2C:  (in /usr/bin/brickd)
==28412==  Address 0x5272984 is 92 bytes inside a block of size 144 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xAEA3:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 4
==28412==    at 0xBC4C:  (in /usr/bin/brickd)
==28412==  Address 0x5272928 is 0 bytes inside a block of size 144 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xAEA3:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 4
==28412==    at 0x15FE0:  (in /usr/bin/brickd)
==28412==  Address 0x52728e0 is 0 bytes inside a block of size 20 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB73F:  (in /usr/bin/brickd)
==28412==
==28412== Syscall param socketcall.recv(buf) points to unaddressable byte(s)
==28412==    at 0x486C76C: recv (syscall-template.S:82)
==28412==  Address 0x5272934 is 12 bytes inside a block of size 144 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xAEA3:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 4
==28412==    at 0xC460:  (in /usr/bin/brickd)
==28412==  Address 0x52729a0 is 120 bytes inside a block of size 144 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xAEA3:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 4
==28412==    at 0xC464:  (in /usr/bin/brickd)
==28412==  Address 0x5272928 is 0 bytes inside a block of size 144 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xAEA3:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 4
==28412==    at 0xC46C:  (in /usr/bin/brickd)
==28412==  Address 0x527292c is 4 bytes inside a block of size 144 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xAEA3:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 4
==28412==    at 0xC470:  (in /usr/bin/brickd)
==28412==  Address 0x52728e0 is 0 bytes inside a block of size 20 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB73F:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 4
==28412==    at 0xC474:  (in /usr/bin/brickd)
==28412==  Address 0x52728e4 is 4 bytes inside a block of size 20 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB73F:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 1
==28412==    at 0x482E370: strlen (mc_replace_strmem.c:390)
==28412==    by 0x48B02BD: vfprintf (vfprintf.c:1623)
==28412==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==28412==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==28412==    by 0xE4D7:  (in /usr/bin/brickd)
==28412==  Address 0x5272b18 is 0 bytes inside a block of size 14 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB74F:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 1
==28412==    at 0x482E388: strlen (mc_replace_strmem.c:390)
==28412==    by 0x48B02BD: vfprintf (vfprintf.c:1623)
==28412==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==28412==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==28412==    by 0xE4D7:  (in /usr/bin/brickd)
==28412==  Address 0x5272b19 is 1 bytes inside a block of size 14 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB74F:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 1
==28412==    at 0x482E394: strlen (mc_replace_strmem.c:390)
==28412==    by 0x48B02BD: vfprintf (vfprintf.c:1623)
==28412==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==28412==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==28412==    by 0xE4D7:  (in /usr/bin/brickd)
==28412==  Address 0x5272b1b is 3 bytes inside a block of size 14 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB74F:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 1
==28412==    at 0x48CE292: _IO_default_xsputn (genops.c:480)
==28412==    by 0x48B01B1: vfprintf (vfprintf.c:1623)
==28412==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==28412==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==28412==    by 0xE4D7:  (in /usr/bin/brickd)
==28412==  Address 0x5272b18 is 0 bytes inside a block of size 14 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB74F:  (in /usr/bin/brickd)
==28412==
==28412== Invalid read of size 1
==28412==    at 0x48CE29C: _IO_default_xsputn (genops.c:479)
==28412==    by 0x48B01B1: vfprintf (vfprintf.c:1623)
==28412==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==28412==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==28412==    by 0xE4D7:  (in /usr/bin/brickd)
==28412==  Address 0x5272b1a is 2 bytes inside a block of size 14 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xB74F:  (in /usr/bin/brickd)
==28412==
==28412== Invalid write of size 4
==28412==    at 0xC210:  (in /usr/bin/brickd)
==28412==  Address 0x5272930 is 8 bytes inside a block of size 144 free'd
==28412==    at 0x482C80C: free (vg_replace_malloc.c:427)
==28412==    by 0xAEA3:  (in /usr/bin/brickd)
==28412==
==28412==
==28412== More than 10000000 total errors detected.  I'm not reporting any more.
==28412== Final error counts will be inaccurate.  Go fix your program!
==28412== Rerun with --error-limit=no to disable this cutoff.  Note
==28412== that errors may occur in your program without prior warning from
==28412== Valgrind, because errors are no longer being displayed.
==28412==
==28412==
==28412== HEAP SUMMARY:
==28412==     in use at exit: 20 bytes in 1 blocks
==28412==   total heap usage: 197,590 allocs, 197,589 frees, 7,131,559 bytes allocated
==28412==
==28412== LEAK SUMMARY:
==28412==    definitely lost: 0 bytes in 0 blocks
==28412==    indirectly lost: 0 bytes in 0 blocks
==28412==      possibly lost: 0 bytes in 0 blocks
==28412==    still reachable: 20 bytes in 1 blocks
==28412==         suppressed: 0 bytes in 0 blocks
==28412== Rerun with --leak-check=full to see details of leaked memory
==28412==
==28412== For counts of detected and suppressed errors, rerun with: -v
==28412== ERROR SUMMARY: 10000000 errors from 15 contexts (suppressed: 25 from 9)

Der Loetkolben

Loetkolben · July 14, 2014 at 22:03

Schon wieder passiert. Eigentlich das gleiche wie Eintrag vorher. Vielleicht hilft es ja. Warum bei den "valgrind" Aufrufen der brickd nicht mehr abbricht sondern endloses Fehlermeldungen ausgibt weiss ich auch nicht.

2014-07-14 23:59:21.796342 <E> <network|client.c:260> Could not receive from client (S: 48, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.797795 <E> <network|client.c:260> Could not receive from client (S: 49, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.799330 <E> <network|client.c:260> Could not receive from client (S: 50, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.801140 <E> <network|client.c:260> Could not receive from client (S: 22, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)

^C2014-07-14 23:59:21.802680 <E> <network|client.c:260> Could not receive from client (S: 23, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.813054 <E> <network|client.c:260> Could not receive from client (S: 42, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.814727 <E> <network|client.c:260> Could not receive from client (S: 43, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.816296 <E> <network|client.c:260> Could not receive from client (S: 44, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.817854 <E> <network|client.c:260> Could not receive from client (S: 45, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.819363 <E> <network|client.c:260> Could not receive from client (S: 46, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.820874 <E> <network|client.c:260> Could not receive from client (S: 47, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.822356 <E> <network|client.c:260> Could not receive from client (S: 48, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.823906 <E> <network|client.c:260> Could not receive from client (S: 49, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.825399 <E> <network|client.c:260> Could not receive from client (S: 50, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-14 23:59:21.839137 <I> <event|event_posix.c:53> Received SIGINT
2014-07-14 23:59:21.857113 <W> <event|event_posix.c:278> Handled only 1 of 13 ready event source(s)
2014-07-14 23:59:22.037810 <W> <usb|usb_transfer.c:172> Could not cancel pending read transfer 0x499e448 for Master Brick [6qATV3]: LIBUSB_ERROR_NOT_FOUND (-5)
2014-07-14 23:59:22.046583 <W> <usb|usb_transfer.c:205> Leaking pending read transfer 0x499e448 for Master Brick [6qATV3]
2014-07-14 23:59:22.049147 <W> <usb|usb_transfer.c:172> Could not cancel pending read transfer 0x499e4b4 for Master Brick [6qATV3]: LIBUSB_ERROR_NOT_FOUND (-5)
2014-07-14 23:59:22.050636 <W> <usb|usb_transfer.c:205> Leaking pending read transfer 0x499e4b4 for Master Brick [6qATV3]
2014-07-14 23:59:22.052016 <W> <usb|usb_transfer.c:172> Could not cancel pending read transfer 0x499e520 for Master Brick [6qATV3]: LIBUSB_ERROR_NOT_FOUND (-5)
2014-07-14 23:59:22.053398 <W> <usb|usb_transfer.c:205> Leaking pending read transfer 0x499e520 for Master Brick [6qATV3]
2014-07-14 23:59:22.146833 <W> <event|event.c:82> Leaking 11 event sources
2014-07-14 23:59:22.151517 <I> <other|main_linux.c:461> Brick Daemon 2.1.0 stopped

# tail -f valgrind_tinkerforge_2.log
==30228== Memcheck, a memory error detector
==30228== Copyright (C) 2002-2011, and GNU GPL'd, by Julian Seward et al.
==30228== Using Valgrind-3.7.0 and LibVEX; rerun with -h for copyright info
==30228== Command: brickd
==30228== Parent PID: 23188
==30228==

==30228== Invalid read of size 4
==30228==    at 0xBC2C:  (in /usr/bin/brickd)
==30228==  Address 0x71d731c is 92 bytes inside a block of size 144 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xAEA3:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 4
==30228==    at 0xBC4C:  (in /usr/bin/brickd)
==30228==  Address 0x71d72c0 is 0 bytes inside a block of size 144 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xAEA3:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 4
==30228==    at 0x15FE0:  (in /usr/bin/brickd)
==30228==  Address 0x71d7278 is 0 bytes inside a block of size 20 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB73F:  (in /usr/bin/brickd)
==30228==
==30228== Syscall param socketcall.recv(buf) points to unaddressable byte(s)
==30228==    at 0x486C76C: recv (syscall-template.S:82)
==30228==  Address 0x71d72cc is 12 bytes inside a block of size 144 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xAEA3:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 4
==30228==    at 0xC460:  (in /usr/bin/brickd)
==30228==  Address 0x71d7338 is 120 bytes inside a block of size 144 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xAEA3:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 4
==30228==    at 0xC464:  (in /usr/bin/brickd)
==30228==  Address 0x71d72c0 is 0 bytes inside a block of size 144 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xAEA3:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 4
==30228==    at 0xC46C:  (in /usr/bin/brickd)
==30228==  Address 0x71d72c4 is 4 bytes inside a block of size 144 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xAEA3:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 4
==30228==    at 0xC470:  (in /usr/bin/brickd)
==30228==  Address 0x71d7278 is 0 bytes inside a block of size 20 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB73F:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 4
==30228==    at 0xC474:  (in /usr/bin/brickd)
==30228==  Address 0x71d727c is 4 bytes inside a block of size 20 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB73F:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 1
==30228==    at 0x482E370: strlen (mc_replace_strmem.c:390)
==30228==    by 0x48B02BD: vfprintf (vfprintf.c:1623)
==30228==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==30228==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==30228==    by 0xE4D7:  (in /usr/bin/brickd)
==30228==  Address 0x71d74b0 is 0 bytes inside a block of size 14 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB74F:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 1
==30228==    at 0x482E388: strlen (mc_replace_strmem.c:390)
==30228==    by 0x48B02BD: vfprintf (vfprintf.c:1623)
==30228==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==30228==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==30228==    by 0xE4D7:  (in /usr/bin/brickd)
==30228==  Address 0x71d74b1 is 1 bytes inside a block of size 14 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB74F:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 1
==30228==    at 0x482E394: strlen (mc_replace_strmem.c:390)
==30228==    by 0x48B02BD: vfprintf (vfprintf.c:1623)
==30228==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==30228==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==30228==    by 0xE4D7:  (in /usr/bin/brickd)
==30228==  Address 0x71d74b3 is 3 bytes inside a block of size 14 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB74F:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 1
==30228==    at 0x48CE292: _IO_default_xsputn (genops.c:480)
==30228==    by 0x48B01B1: vfprintf (vfprintf.c:1623)
==30228==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==30228==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==30228==    by 0xE4D7:  (in /usr/bin/brickd)
==30228==  Address 0x71d74b0 is 0 bytes inside a block of size 14 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB74F:  (in /usr/bin/brickd)
==30228==
==30228== Invalid read of size 1
==30228==    at 0x48CE29C: _IO_default_xsputn (genops.c:479)
==30228==    by 0x48B01B1: vfprintf (vfprintf.c:1623)
==30228==    by 0x48B14B1: buffered_vfprintf (vfprintf.c:2289)
==30228==    by 0x48AD899: vfprintf (vfprintf.c:1309)
==30228==    by 0xE4D7:  (in /usr/bin/brickd)
==30228==  Address 0x71d74b2 is 2 bytes inside a block of size 14 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xB74F:  (in /usr/bin/brickd)
==30228==
==30228== Invalid write of size 4
==30228==    at 0xC210:  (in /usr/bin/brickd)
==30228==  Address 0x71d72c8 is 8 bytes inside a block of size 144 free'd
==30228==    at 0x482C80C: free (vg_replace_malloc.c:427)
==30228==    by 0xAEA3:  (in /usr/bin/brickd)
==30228==
==30228==
==30228== More than 10000000 total errors detected.  I'm not reporting any more.
==30228== Final error counts will be inaccurate.  Go fix your program!
==30228== Rerun with --error-limit=no to disable this cutoff.  Note
==30228== that errors may occur in your program without prior warning from
==30228== Valgrind, because errors are no longer being displayed.
==30228==
==30228==
==30228== HEAP SUMMARY:
==30228==     in use at exit: 368 bytes in 4 blocks
==30228==   total heap usage: 599,009 allocs, 599,005 frees, 21,166,843 bytes allocated
==30228==
==30228== LEAK SUMMARY:
==30228==    definitely lost: 116 bytes in 1 blocks
==30228==    indirectly lost: 232 bytes in 2 blocks
==30228==      possibly lost: 0 bytes in 0 blocks
==30228==    still reachable: 20 bytes in 1 blocks
==30228==         suppressed: 0 bytes in 0 blocks
==30228== Rerun with --leak-check=full to see details of leaked memory
==30228==
==30228== For counts of detected and suppressed errors, rerun with: -v
==30228== ERROR SUMMARY: 10000000 errors from 15 contexts (suppressed: 25 from 9)

Ich werde den brickd jetzt als "/etc/init.d/brickd start" starten und schauen was passiert.

Der Loetkolben

photron · July 15, 2014 at 08:33

Okay, da sind einige Invalid Reads zu erkennen im Valgrind Log. Leider kann Valgrind mit der brickd Version aus dem Debian Package keinen ordentlichen Backtrace erzeugen, so dass man sehen kann wo im Code das Problem ist. Daran hatte ich vorher nicht gedacht, sorry.

Kannst du brickd selber kompilieren, so dass es Symbols usw. hat damit Valgrind besser anzeigen kann wo das Problem ist? Hier wie's geht:

sudo apt-get install build-essential pkg-config libusb-1.0-0-dev libudev-dev pm-utils
wget https://github.com/Tinkerforge/brickd/archive/v2.1.0.zip
unzip v2.1.0.zip
cd brickd-2.1.0/src/brickd
CFLAGS=-g\ -ggdb make

Danach liegt im aktuellen Verzeichnis der neue brickd. Und dann nochmal mit Valgrind:

sudo valgrind --time-stamp=yes --log-file=valgrind.log ./brickd

Zeitliche Zuordnung der Valgrind und brickd Ausgaben ist nicht unbedingt nötig, wenn Valgrind mir sagt wo im Code das Problem ist sollte das reichen, um es verstehen und beheben zu können. Aber es schadet nicht Valgrind einen Timestamp im Log ausgeben zu lassen. Der ist aber im Gegensatz zu brickd relative zur Startzeit von Valgrind.

Loetkolben · July 15, 2014 at 13:10

So, erledigt. Abbruch des neu compilierten brickd. Danke fuer die passenden Compileschritte!

Was mich stutzig mach: Alles hat 2 Monate problemlos gelaufen. Dann gab es bei dem LED Bricklet Test diese Probleme. Bei den letzten beiden Abbruechen habe ich nicht mehr mit dem Brickviewer zugegriffen, sondern alles so gelassen wie es die letzten 2 Monate war. Wo liegt das Problem? Der Rechner ist nicht durchgebootet worden. Das ist aber auch Absicht.

2014-07-15 15:06:02.894927 <E> <network|client.c:260> Could not receive from client (S: 54, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-15 15:06:02.896676 <E> <network|client.c:260> Could not receive from client (S: 55, T: plain, P: 192.168.23.81, A: disabled), disconnecting client: EBADF (9)
2014-07-15 15:06:02.904790 <I> <event|event_posix.c:53> Received SIGINT
2014-07-15 15:06:02.911993 <W> <event|event_posix.c:278> Handled only 1 of 28 ready event source(s)
2014-07-15 15:06:02.981236 <W> <usb|usb_transfer.c:172> Could not cancel pending read transfer 0x49d4448 for Master Brick [6qATV3]: LIBUSB_ERROR_NOT_FOUND (-5)
2014-07-15 15:06:02.994300 <W> <usb|usb_transfer.c:205> Leaking pending read transfer 0x49d4448 for Master Brick [6qATV3]
2014-07-15 15:06:02.997484 <W> <usb|usb_transfer.c:172> Could not cancel pending read transfer 0x49d44b4 for Master Brick [6qATV3]: LIBUSB_ERROR_NOT_FOUND (-5)
2014-07-15 15:06:02.998956 <W> <usb|usb_transfer.c:205> Leaking pending read transfer 0x49d44b4 for Master Brick [6qATV3]
2014-07-15 15:06:03.091292 <W> <event|event.c:82> Leaking 26 event sources
2014-07-15 15:06:03.096460 <I> <other|main_linux.c:461> Brick Daemon 2.1.0 stopped

# tail -f /storage/localSATA/valgrind_new_brickd_1.log
==00:00:00:00.000 22857== Memcheck, a memory error detector
==00:00:00:00.005 22857== Copyright (C) 2002-2011, and GNU GPL'd, by Julian Seward et al.
==00:00:00:00.005 22857== Using Valgrind-3.7.0 and LibVEX; rerun with -h for copyright info
==00:00:00:00.005 22857== Command: ./brickd
==00:00:00:00.005 22857== Parent PID: 28417
==00:00:00:00.005 22857==
==00:01:25:09.206 22857== Invalid read of size 4
==00:01:25:09.207 22857==    at 0xB8A0: client_dispatch_response (client.c:236)
==00:01:25:09.208 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.209 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.209 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.209 22857==  Address 0x5039914 is 92 bytes inside a block of size 144 free'd
==00:01:25:09.210 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.210 22857==    by 0xAF4B: array_remove (array.c:187)
==00:01:25:09.210 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.211 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.211 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.211 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.211 22857==
==00:01:25:09.215 22857== Invalid read of size 4
==00:01:25:09.216 22857==    at 0xB8B6: client_handle_receive (client.c:236)
==00:01:25:09.217 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.217 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.217 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.218 22857==  Address 0x50398b8 is 0 bytes inside a block of size 144 free'd
==00:01:25:09.218 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.218 22857==    by 0xAF4B: array_remove (array.c:187)
==00:01:25:09.219 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.219 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.219 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.219 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.220 22857==
==00:01:25:09.223 22857== Invalid read of size 4
==00:01:25:09.224 22857==    at 0x12B38: socket_accept_platform (socket_posix.c:86)
==00:01:25:09.224 22857==    by 0xF3D9: socket_receive (socket.c:82)
==00:01:25:09.225 22857==    by 0xB8BD: client_handle_receive (client.c:236)
==00:01:25:09.225 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.225 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.226 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.226 22857==  Address 0x5039870 is 0 bytes inside a block of size 20 free'd
==00:01:25:09.226 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.227 22857==    by 0xB50D: client_destroy (client.c:500)
==00:01:25:09.227 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.227 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.228 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.228 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.228 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.228 22857==
==00:01:25:09.232 22857== Syscall param socketcall.recv(buf) points to unaddressable byte(s)
==00:01:25:09.233 22857==    at 0x487E76C: recv (syscall-template.S:82)
==00:01:25:09.233 22857==  Address 0x50398c4 is 12 bytes inside a block of size 144 free'd
==00:01:25:09.233 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.234 22857==    by 0xAF4B: array_remove (array.c:187)
==00:01:25:09.234 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.235 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.235 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.235 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.236 22857==
==00:01:25:09.245 22857== Invalid read of size 4
==00:01:25:09.246 22857==    at 0xBEA8: client_handle_receive (client.c:259)
==00:01:25:09.246 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.247 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.247 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.247 22857==  Address 0x5039930 is 120 bytes inside a block of size 144 free'd
==00:01:25:09.248 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.248 22857==    by 0xAF4B: array_remove (array.c:187)
==00:01:25:09.249 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.249 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.249 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.250 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.250 22857==
==00:01:25:09.254 22857== Invalid read of size 4
==00:01:25:09.255 22857==    at 0xBEAA: client_handle_receive (client.c:259)
==00:01:25:09.255 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.255 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.256 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.256 22857==  Address 0x50398b8 is 0 bytes inside a block of size 144 free'd
==00:01:25:09.256 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.257 22857==    by 0xAF4B: array_remove (array.c:187)
==00:01:25:09.257 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.257 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.258 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.258 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.258 22857==
==00:01:25:09.262 22857== Invalid read of size 4
==00:01:25:09.263 22857==    at 0xBEAE: client_handle_receive (client.c:259)
==00:01:25:09.263 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.264 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.264 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.265 22857==  Address 0x50398bc is 4 bytes inside a block of size 144 free'd
==00:01:25:09.265 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.265 22857==    by 0xAF4B: array_remove (array.c:187)
==00:01:25:09.266 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.266 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.267 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.267 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.267 22857==
==00:01:25:09.271 22857== Invalid read of size 4
==00:01:25:09.272 22857==    at 0xBEB0: client_handle_receive (client.c:259)
==00:01:25:09.272 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.273 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.273 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.273 22857==  Address 0x5039870 is 0 bytes inside a block of size 20 free'd
==00:01:25:09.274 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.274 22857==    by 0xB50D: client_destroy (client.c:500)
==00:01:25:09.275 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.275 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.275 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.276 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.276 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.276 22857==
==00:01:25:09.280 22857== Invalid read of size 4
==00:01:25:09.281 22857==    at 0xBEB4: client_handle_receive (client.c:259)
==00:01:25:09.281 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.282 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.282 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.282 22857==  Address 0x5039874 is 4 bytes inside a block of size 20 free'd
==00:01:25:09.283 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.283 22857==    by 0xB50D: client_destroy (client.c:500)
==00:01:25:09.283 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.284 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.284 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.285 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.285 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.285 22857==
==00:01:25:09.296 22857== Invalid read of size 1
==00:01:25:09.297 22857==    at 0x482E370: strlen (mc_replace_strmem.c:390)
==00:01:25:09.298 22857==    by 0x48C22BD: vfprintf (vfprintf.c:1623)
==00:01:25:09.298 22857==    by 0x48C34B1: buffered_vfprintf (vfprintf.c:2289)
==00:01:25:09.298 22857==    by 0x48BF899: vfprintf (vfprintf.c:1309)
==00:01:25:09.299 22857==    by 0xD43B: log_message (log.c:103)
==00:01:25:09.299 22857==    by 0xBEF5: client_handle_receive (client.c:259)
==00:01:25:09.299 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.300 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.300 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.300 22857==  Address 0x5039aa8 is 0 bytes inside a block of size 14 free'd
==00:01:25:09.300 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.301 22857==    by 0xB517: client_destroy (client.c:503)
==00:01:25:09.301 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.301 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.302 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.302 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.302 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.303 22857==
==00:01:25:09.306 22857== Invalid read of size 1
==00:01:25:09.307 22857==    at 0x482E388: strlen (mc_replace_strmem.c:390)
==00:01:25:09.307 22857==    by 0x48C22BD: vfprintf (vfprintf.c:1623)
==00:01:25:09.308 22857==    by 0x48C34B1: buffered_vfprintf (vfprintf.c:2289)
==00:01:25:09.308 22857==    by 0x48BF899: vfprintf (vfprintf.c:1309)
==00:01:25:09.308 22857==    by 0xD43B: log_message (log.c:103)
==00:01:25:09.309 22857==    by 0xBEF5: client_handle_receive (client.c:259)
==00:01:25:09.309 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.309 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.310 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.310 22857==  Address 0x5039aa9 is 1 bytes inside a block of size 14 free'd
==00:01:25:09.310 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.310 22857==    by 0xB517: client_destroy (client.c:503)
==00:01:25:09.311 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.311 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.311 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.312 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.312 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.312 22857==
==00:01:25:09.316 22857== Invalid read of size 1
==00:01:25:09.316 22857==    at 0x482E394: strlen (mc_replace_strmem.c:390)
==00:01:25:09.317 22857==    by 0x48C22BD: vfprintf (vfprintf.c:1623)
==00:01:25:09.317 22857==    by 0x48C34B1: buffered_vfprintf (vfprintf.c:2289)
==00:01:25:09.318 22857==    by 0x48BF899: vfprintf (vfprintf.c:1309)
==00:01:25:09.318 22857==    by 0xD43B: log_message (log.c:103)
==00:01:25:09.318 22857==    by 0xBEF5: client_handle_receive (client.c:259)
==00:01:25:09.319 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.319 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.319 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.320 22857==  Address 0x5039aab is 3 bytes inside a block of size 14 free'd
==00:01:25:09.320 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.320 22857==    by 0xB517: client_destroy (client.c:503)
==00:01:25:09.320 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.321 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.321 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.321 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.322 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.322 22857==
==00:01:25:09.326 22857== Invalid read of size 1
==00:01:25:09.326 22857==    at 0x48E0292: _IO_default_xsputn (genops.c:480)
==00:01:25:09.327 22857==    by 0x48C21B1: vfprintf (vfprintf.c:1623)
==00:01:25:09.327 22857==    by 0x48C34B1: buffered_vfprintf (vfprintf.c:2289)
==00:01:25:09.328 22857==    by 0x48BF899: vfprintf (vfprintf.c:1309)
==00:01:25:09.328 22857==    by 0xD43B: log_message (log.c:103)
==00:01:25:09.328 22857==    by 0xBEF5: client_handle_receive (client.c:259)
==00:01:25:09.329 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.329 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.329 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.330 22857==  Address 0x5039aa8 is 0 bytes inside a block of size 14 free'd
==00:01:25:09.330 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.330 22857==    by 0xB517: client_destroy (client.c:503)
==00:01:25:09.330 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.331 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.331 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.331 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.332 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.332 22857==
==00:01:25:09.335 22857== Invalid read of size 1
==00:01:25:09.336 22857==    at 0x48E029C: _IO_default_xsputn (genops.c:479)
==00:01:25:09.337 22857==    by 0x48C21B1: vfprintf (vfprintf.c:1623)
==00:01:25:09.337 22857==    by 0x48C34B1: buffered_vfprintf (vfprintf.c:2289)
==00:01:25:09.337 22857==    by 0x48BF899: vfprintf (vfprintf.c:1309)
==00:01:25:09.338 22857==    by 0xD43B: log_message (log.c:103)
==00:01:25:09.338 22857==    by 0xBEF5: client_handle_receive (client.c:259)
==00:01:25:09.338 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.339 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.339 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.339 22857==  Address 0x5039aaa is 2 bytes inside a block of size 14 free'd
==00:01:25:09.340 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.340 22857==    by 0xB517: client_destroy (client.c:503)
==00:01:25:09.340 22857==    by 0xAEFF: array_remove (array.c:183)
==00:01:25:09.341 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.341 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.341 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.341 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.342 22857==
==00:01:25:09.346 22857== Invalid write of size 4
==00:01:25:09.347 22857==    at 0xBD10: client_handle_receive (client.c:282)
==00:01:25:09.348 22857==    by 0x126FD: event_run_platform (event_posix.c:263)
==00:01:25:09.348 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.349 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.349 22857==  Address 0x50398c0 is 8 bytes inside a block of size 144 free'd
==00:01:25:09.349 22857==    at 0x482C80C: free (vg_replace_malloc.c:427)
==00:01:25:09.350 22857==    by 0xAF4B: array_remove (array.c:187)
==00:01:25:09.350 22857==    by 0xDAED: network_cleanup_clients (network.c:318)
==00:01:25:09.350 22857==    by 0x126D5: event_run_platform (event_posix.c:283)
==00:01:25:09.350 22857==    by 0xCEB7: event_run (event.c:218)
==00:01:25:09.351 22857==    by 0xA815: main (main_linux.c:435)
==00:01:25:09.351 22857==
==00:01:31:21.014 22857==
==00:01:31:21.015 22857== HEAP SUMMARY:
==00:01:31:21.015 22857==     in use at exit: 252 bytes in 3 blocks
==00:01:31:21.015 22857==   total heap usage: 114,525 allocs, 114,522 frees, 4,356,812 bytes allocated
==00:01:31:21.015 22857==
==00:01:31:21.031 22857== LEAK SUMMARY:
==00:01:31:21.032 22857==    definitely lost: 116 bytes in 1 blocks
==00:01:31:21.032 22857==    indirectly lost: 116 bytes in 1 blocks
==00:01:31:21.032 22857==      possibly lost: 0 bytes in 0 blocks
==00:01:31:21.033 22857==    still reachable: 20 bytes in 1 blocks
==00:01:31:21.033 22857==         suppressed: 0 bytes in 0 blocks
==00:01:31:21.033 22857== Rerun with --leak-check=full to see details of leaked memory
==00:01:31:21.034 22857==
==00:01:31:21.034 22857== For counts of detected and suppressed errors, rerun with: -v
==00:01:31:21.034 22857== ERROR SUMMARY: 7399593 errors from 15 contexts (suppressed: 23 from 6)

photron · July 15, 2014 at 14:10

Ahhhhh, jetzt weiß ich was los ist. Das ist ein Problem, dass im git schon behoben habe. Allerdings habe ich die Folgen diese Problems wohl nicht richtig beurteilt, sonst gäbe es schon brickd 2.1.1 in der es behoben wäre, sorry.

Hier die Schritte den Patch dafür zu testen:

cd brickd-2.1.0/src/brickd
sudo apt-get install patch
wget https://github.com/Tinkerforge/brickd/commit/442321f6d6619c0ff80770776f108d50dc53e6e2.patch
patch -p3 < 442321f6d6619c0ff80770776f108d50dc53e6e2.patch
CFLAGS=-g\ -ggdb make

Dann kannst du wieder testen, das Problem sollte nicht mehr auftreten.

Der Auslöser ist, dass der Client disconnected, wenn im Send Queue noch Antworten für den Client sind. In diesem Fall wird der Socket des Clients nicht richtig aus dem Event Loop entfernt. Was im Endeffekt dann dazu führt, dass Funktionen für einen Client aufgerufen werden, dessen Speicher schon längst freigegeben wurde => Crash.

Es wird dann in Kürze eine neu brickd Version geben.

Warum das Problem erst jetzt auftritt mag damit zusammenhängen, dass sich das Timing deines ganzen Aufbaus vielleicht etwas verändert hat, oder sich die Belastung etwas geändert hat, so dass es jetzt vorkommt, dass Clients disconnecten wenn brickd noch Antworten für sie hat.

[Behoben] Brickd bricht zusammen (arm 2.1.0)

Recommended Posts

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Link zu diesem Kommentar

Share on other sites

Join the conversation