Skip to content
New issue

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

Sign up for GitHub

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

Already on GitHub? Sign in to your account

Jump to bottom

RaspiBlitz slow boot and acts weird after boot #898

Closed
got3nks opened this issue Dec 4, 2019 · 9 comments
Closed

RaspiBlitz slow boot and acts weird after boot #898

got3nks opened this issue Dec 4, 2019 · 9 comments
Labels
hardware Related e.g. to Power Supplies, Screens..

Comments

@got3nks
Copy link

got3nks commented Dec 4, 2019 •
edited
Loading

I have setup three times this RaspBerry PI 4 (4GB ram + 1 TB SSD + 32 GB Micro-SD) with latest version of RaspiBlitz (raspiblitz-v1.3-2019-09-05.img.gz).

Setup is always fast, but after initialization and reboot it takes very long before I can log in SSH. Even after succesfull boot I sometime gets error like "bitcoind is not running" while some other times I can see main menu (and node is sync'd: bitcoin v0.18.1 mainnet Sync OK 100.00%).

The LCD screen also acts weird. I dont see the boot logo and after long time even if node is running correctly (I can see the status in SSH as said before) this is what I get on the screen.

raspi

I think the culprit is in systemd, maybe some services are not booting up correctly.

admin@ViresInNumeris:~ $ sudo systemctl status bitcoind
Failed to get properties: Connection timed out

I already tried clearing HDD (except blockchain) and re-flash SD card with RaspiBlitz several times. I always end up having this issue.

In /var/log/boot.log I see these errors:

[FAILED] Failed to start Terminate Plymouth Boot Screen.
See 'systemctl status plymouth-quit.service' for details.
[FAILED] Failed to start LSB: Resiz… filesystem to fill partition.
See 'systemctl status resize2fs_once.service' for details.
[FAILED] Failed to start LSB: Switc…(unless shift key is pressed).
See 'systemctl status raspi-config.service' for details.

So I tried:

admin@ViresInNumeris:~ $ sudo journalctl -u resize2fs_once
-- Logs begin at Wed 2019-12-04 13:52:27 GMT, end at Wed 2019-12-04 15:16:29 GMT. --
Dec 04 14:33:50 ViresInNumeris systemd[1]: Starting LSB: Resize the root filesystem to fill partition...
Dec 04 14:45:40 ViresInNumeris systemd[1]: resize2fs_once.service: Start operation timed out. Terminating.
Dec 04 14:45:40 ViresInNumeris systemd[1]: resize2fs_once.service: Control process exited, code=killed, status=15/TERM
Dec 04 14:45:40 ViresInNumeris systemd[1]: resize2fs_once.service: Failed with result 'timeout'.
Dec 04 14:45:40 ViresInNumeris systemd[1]: Failed to start LSB: Resize the root filesystem to fill partition.

admin@ViresInNumeris:~ $ sudo journalctl -u raspi-config
-- Logs begin at Wed 2019-12-04 13:52:27 GMT, end at Wed 2019-12-04 15:16:52 GMT. --
Dec 04 14:33:50 ViresInNumeris systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Dec 04 14:45:40 ViresInNumeris systemd[1]: raspi-config.service: Start operation timed out. Terminating.
Dec 04 14:45:40 ViresInNumeris systemd[1]: raspi-config.service: Control process exited, code=killed, status=15/TERM
Dec 04 14:45:40 ViresInNumeris systemd[1]: raspi-config.service: Failed with result 'timeout'.
Dec 04 14:45:40 ViresInNumeris systemd[1]: Failed to start LSB: Switch to ondemand cpu governor (unless shift key is pressed).

admin@ViresInNumeris:~ $ sudo journalctl -u plymouth-quit
-- Logs begin at Wed 2019-12-04 13:52:27 GMT, end at Wed 2019-12-04 15:17:01 GMT. --
Dec 04 14:34:02 ViresInNumeris systemd[1]: Starting Terminate Plymouth Boot Screen...
Dec 04 14:34:37 ViresInNumeris systemd[1]: plymouth-quit.service: Start operation timed out. Terminating.
Dec 04 14:34:37 ViresInNumeris systemd[1]: plymouth-quit.service: Main process exited, code=killed, status=15/TERM
Dec 04 14:34:37 ViresInNumeris systemd[1]: plymouth-quit.service: Failed with result 'timeout'.
Dec 04 14:34:37 ViresInNumeris systemd[1]: Failed to start Terminate Plymouth Boot Screen.

XXdebugLogs.sh sometime could check systemd status, sometime could not (without reboot, it seems being an on-and-off issue). When it works this is the output (as you can see all services are actually running fine).

***************************************************************
* RASPIBLITZ DEBUG LOGS
***************************************************************
blitzversion: 1.3
chainnetwork: bitcoin / main
 14:53:44 up  1:01,  2 users,  load average: 2.00, 2.08, 1.52

*** BLOCKCHAIN SYSTEMD STATUS ***
● bitcoind.service - Bitcoin daemon
   Loaded: loaded (/etc/systemd/system/bitcoind.service; enabled; vendor preset: enabled)
   Active: active (running) since Wed 2019-12-04 14:36:47 GMT; 17min ago
  Process: 1694 ExecStartPre=/home/admin/config.scripts/blitz.systemd.sh log blockchain STARTED (code=exited, status=0/SUCCESS)
  Process: 1700 ExecStart=/usr/local/bin/bitcoind -daemon -conf=/home/bitcoin/.bitcoin/bitcoin.conf -pid=/home/bitcoin/.bitcoin/bitcoind.pid (code=exited, status=0/SUCCESS)
 Main PID: 1727 (bitcoind)
    Tasks: 18 (limit: 4915)
   Memory: 3.2G
   CGroup: /system.slice/bitcoind.service
           └─1727 /usr/local/bin/bitcoind -daemon -conf=/home/bitcoin/.bitcoin/bitcoin.conf -pid=/home/bitcoin/.bitcoin/bitcoind.pid

Dec 04 14:36:47 ViresInNumeris systemd[1]: Starting Bitcoin daemon...
Dec 04 14:36:47 ViresInNumeris systemd[1]: Started Bitcoin daemon.

*** LAST BLOCKCHAIN ERROR LOGS ***
sudo journalctl -u bitcoind -b --no-pager -n8
-- Logs begin at Wed 2019-12-04 13:52:27 GMT, end at Wed 2019-12-04 14:53:56 GMT. --
Dec 04 14:36:47 ViresInNumeris systemd[1]: Starting Bitcoin daemon...
Dec 04 14:36:47 ViresInNumeris systemd[1]: Started Bitcoin daemon.
1575470634 ERROR: Error found in Logs
1575471065 ERROR: Error found in Logs

*** LAST BLOCKCHAIN 20 INFO LOGS ***
sudo tail -n 20 /mnt/hdd/bitcoin/debug.log
2019-12-04T14:38:14Z UpdateTip: new best=00000000000000000008f9948017a6aa334b7ac1c8e1be8eafce1fd58064b146 height=606618 version=0x20000000 log2_work=91.404403 tx=480690884 date='2019-12-04T14:01:14Z' progress=0.999983 cache=1.7MiB(15137txo) warning='62 of last 100 blocks have unexpected version'
2019-12-04T14:38:20Z UpdateTip: new best=000000000000000000092898b48ab1e9ca45ab0d1edcd9cc0eed9638d498a016 height=606619 version=0x20002000 log2_work=91.404427 tx=480693188 date='2019-12-04T14:03:48Z' progress=0.999984 cache=2.7MiB(23732txo) warning='62 of last 100 blocks have unexpected version'
2019-12-04T14:38:27Z UpdateTip: new best=00000000000000000009adc38ce91a682772ef6359622f654fec3c77e8dfbb6e height=606620 version=0x20000000 log2_work=91.404452 tx=480695421 date='2019-12-04T14:04:03Z' progress=0.999984 cache=3.5MiB(30808txo) warning='61 of last 100 blocks have unexpected version'
2019-12-04T14:38:33Z UpdateTip: new best=0000000000000000000f855b5fe7165f89ba28de0d34207a85c21e22cdbd1254 height=606621 version=0x20400000 log2_work=91.404476 tx=480698284 date='2019-12-04T14:24:47Z' progress=0.999994 cache=4.5MiB(39882txo) warning='62 of last 100 blocks have unexpected version'
2019-12-04T14:38:40Z UpdateTip: new best=00000000000000000014a66e3ca0413f7e46100fc3c697c715c52bf2b51b8cb8 height=606622 version=0x3fffe000 log2_work=91.404501 tx=480700808 date='2019-12-04T14:27:35Z' progress=0.999995 cache=5.5MiB(49097txo) warning='62 of last 100 blocks have unexpected version'
2019-12-04T14:38:40Z New outbound peer connected: version: 70015, blocks=606623, peer=2
2019-12-04T14:38:41Z Pre-allocating up to position 0x2000000 in blk01885.dat
2019-12-04T14:38:49Z Pre-allocating up to position 0x300000 in rev01885.dat
2019-12-04T14:38:49Z UpdateTip: new best=0000000000000000000019083464a4c259f8057f84a55e3ac137b47a6883454e height=606623 version=0x20c00000 log2_work=91.404525 tx=480702774 date='2019-12-04T14:28:29Z' progress=0.999995 cache=6.6MiB(57575txo) warning='63 of last 100 blocks have unexpected version'
2019-12-04T14:38:49Z New outbound peer connected: version: 70015, blocks=606623, peer=4
2019-12-04T14:38:55Z New outbound peer connected: version: 70015, blocks=606623, peer=6
2019-12-04T14:38:56Z New outbound peer connected: version: 70015, blocks=606623, peer=7
2019-12-04T14:39:14Z New outbound peer connected: version: 70015, blocks=606623, peer=8
2019-12-04T14:39:16Z New outbound peer connected: version: 70015, blocks=606623, peer=9
2019-12-04T14:39:22Z New outbound peer connected: version: 70015, blocks=606623, peer=10
2019-12-04T14:42:59Z UpdateTip: new best=00000000000000000002a43bc2d047a50c7d620f931a899c03e813a117a4807b height=606624 version=0x3fffe000 log2_work=91.40455 tx=480705821 date='2019-12-04T14:42:08Z' progress=1.000000 cache=7.6MiB(66645txo) warning='64 of last 100 blocks have unexpected version'
2019-12-04T14:49:02Z UpdateTip: new best=00000000000000000012a0401b977c3ef83116325a815dde38fdd1a8bae21d07 height=606625 version=0x3fffe000 log2_work=91.404575 tx=480708814 date='2019-12-04T14:49:12Z' progress=1.000000 cache=8.4MiB(74777txo) warning='65 of last 100 blocks have unexpected version'
2019-12-04T14:50:46Z UpdateTip: new best=0000000000000000000d81463e0e644f3f9f4a3be9cb4c06c6d41c516d6a5d32 height=606626 version=0x20800000 log2_work=91.404599 tx=480710985 date='2019-12-04T14:50:28Z' progress=1.000000 cache=9.0MiB(80181txo) warning='65 of last 100 blocks have unexpected version'
2019-12-04T14:51:48Z New outbound peer connected: version: 70015, blocks=606626, peer=12
2019-12-04T14:53:15Z UpdateTip: new best=0000000000000000000af4a1ef60ef69aaf8e803da0dfc2d3a85090e73d1668c height=606627 version=0x20000000 log2_work=91.404624 tx=480712974 date='2019-12-04T14:53:06Z' progress=1.000000 cache=9.7MiB(86908txo) warning='64 of last 100 blocks have unexpected version'

*** LND SYSTEMD STATUS ***
● lnd.service - LND Lightning Daemon
   Loaded: loaded (/etc/systemd/system/lnd.service; enabled; vendor preset: enabled)
   Active: active (running) since Wed 2019-12-04 14:36:47 GMT; 17min ago
  Process: 1728 ExecStartPre=/home/admin/config.scripts/blitz.systemd.sh log lightning STARTED (code=exited, status=0/SUCCESS)
 Main PID: 1730 (lnd)
    Tasks: 14 (limit: 4915)
   Memory: 270.5M
   CGroup: /system.slice/lnd.service
           └─1730 /usr/local/bin/lnd --externalip=x.x.x.x:9735

Dec 04 14:36:47 ViresInNumeris systemd[1]: Starting LND Lightning Daemon...
Dec 04 14:36:47 ViresInNumeris systemd[1]: Started LND Lightning Daemon.

*** LAST LND ERROR LOGS ***
sudo journalctl -u lnd -b --no-pager -n12
-- Logs begin at Wed 2019-12-04 13:52:27 GMT, end at Wed 2019-12-04 14:53:56 GMT. --
Dec 04 14:36:47 ViresInNumeris systemd[1]: Starting LND Lightning Daemon...
Dec 04 14:36:47 ViresInNumeris systemd[1]: Started LND Lightning Daemon.

*** LAST 30 LND INFO LOGS ***
sudo tail -n 30 /mnt/hdd/lnd/logs/bitcoin/mainnet/lnd.log
2019-12-04 14:52:59.306 [DBG] LNWL: Filtering block 606391 (00000000000000000013b0df1db9826191e6e9ac2383823acfc13131be7b933d) with 2897 transactions
2019-12-04 14:53:01.363 [DBG] LNWL: Filtering block 606392 (0000000000000000000a669e40d4d9da0dada12cddf6f7c33f15ca98b73997b3) with 1587 transactions
2019-12-04 14:53:03.483 [DBG] LNWL: Filtering block 606393 (0000000000000000000b6f5de550601eb67e2842331e960e9ae4efe5cf54a5b1) with 3059 transactions
2019-12-04 14:53:06.217 [DBG] LNWL: Filtering block 606394 (00000000000000000003ac8b5708802c16b7dedc095a8b5bdec0e4b732e6c730) with 3081 transactions
2019-12-04 14:53:08.443 [DBG] LNWL: Filtering block 606395 (00000000000000000012f1774204a858abf5240472c70f310319020b3f124b6a) with 2152 transactions
2019-12-04 14:53:09.826 [DBG] LNWL: Filtering block 606396 (00000000000000000007c4d348cd0f2852afd7dfe791956400cd8db46b69a7f3) with 342 transactions
2019-12-04 14:53:15.665 [DBG] LNWL: Filtering block 606397 (00000000000000000006d7030420e54764bedd01c2ad9088feee9cc8f769a08e) with 546 transactions
2019-12-04 14:53:16.046 [DBG] LNWL: Filtering block 606627 (0000000000000000000af4a1ef60ef69aaf8e803da0dfc2d3a85090e73d1668c) with 1989 transactions
2019-12-04 14:53:16.473 [DBG] LNWL: Filtering block 606398 (0000000000000000000f462a86611294d4fd3000afbb5b3eb114cf1c2bc46534) with 389 transactions
2019-12-04 14:53:17.364 [DBG] LNWL: Filtering block 606399 (000000000000000000064a6d6412c4baa6a73f52ab77359467cd56f1722148b0) with 1350 transactions
2019-12-04 14:53:18.241 [DBG] LNWL: Received block connected notification for height 606627 while rescanning
2019-12-04 14:53:19.291 [DBG] LNWL: Filtering block 606400 (0000000000000000000fa88a9b5554b1b2a4f53ba65c9ce9036f266ca03c3748) with 1603 transactions
2019-12-04 14:53:21.182 [DBG] LNWL: Filtering block 606401 (0000000000000000000db7a095ebf122d2869eed566f56123abc4bb4f82aa0df) with 2072 transactions
2019-12-04 14:53:22.568 [DBG] LNWL: Filtering block 606402 (00000000000000000002c52869a81ea68b5b6a9afa8cb0ffd7403c205b48373b) with 1247 transactions
2019-12-04 14:53:24.038 [DBG] LNWL: Filtering block 606403 (00000000000000000011cfeede28c331028189a0b83c0a516dc8c25262b2f996) with 2612 transactions
2019-12-04 14:53:26.344 [DBG] LNWL: Filtering block 606404 (0000000000000000000bfdcdf5d1207d8af1798c609588d003018e6463fa53e6) with 2442 transactions
2019-12-04 14:53:29.261 [DBG] LNWL: Filtering block 606405 (00000000000000000009ab905fd1f6723fdbbc11b29f74917c132696822e0cdd) with 2934 transactions
2019-12-04 14:53:31.778 [DBG] LNWL: Filtering block 606406 (0000000000000000001487b833aa974261ff109f78f99f85d557d83567fda624) with 2381 transactions
2019-12-04 14:53:33.004 [DBG] LNWL: Filtering block 606407 (000000000000000000019b2510413dc6e47e88566af2746204536c07df06ad02) with 174 transactions
2019-12-04 14:53:34.203 [DBG] LNWL: Filtering block 606408 (0000000000000000000cbbd4a9faf1457068ff2ea9a35f8d423e5ee367e5f3d4) with 2684 transactions
2019-12-04 14:53:36.865 [DBG] LNWL: Filtering block 606409 (00000000000000000010493528a2c31f94eb8439cad19fa21e1f1a64b483bdc3) with 2560 transactions
2019-12-04 14:53:39.698 [DBG] LNWL: Filtering block 606410 (0000000000000000000cb7bd142a9728b5965f406621129a65576ffaa94fddfe) with 2656 transactions
2019-12-04 14:53:42.087 [DBG] LNWL: Filtering block 606411 (0000000000000000000e6a88172e3bfcebdc5275842b8d9c684621b5092ba552) with 994 transactions
2019-12-04 14:53:43.723 [DBG] LNWL: Filtering block 606412 (000000000000000000013fcbcb176d3aae7946d483cc31b35cbf1f433ffdff1a) with 3096 transactions
2019-12-04 14:53:46.632 [DBG] LNWL: Filtering block 606413 (0000000000000000000e356179381742e61527e2564b0505fcabe669f02b82ba) with 2901 transactions
2019-12-04 14:53:48.620 [DBG] LNWL: Filtering block 606414 (00000000000000000012453219c2154e03d53160380a7b5fc7c0777444c31365) with 1255 transactions
2019-12-04 14:53:49.946 [DBG] LNWL: Filtering block 606415 (0000000000000000001532ee434f3c3ff64ccbb11d983bdf77269ffec16b0d94) with 1683 transactions
2019-12-04 14:53:51.384 [DBG] LNWL: Filtering block 606416 (00000000000000000003e27820f9ee0d25cb7eeaaef05a285064ca328a461043) with 1433 transactions
2019-12-04 14:53:52.909 [DBG] LNWL: Filtering block 606417 (00000000000000000008d3d6a0f93121fe69a63dd046b5eb4a4c51af4b0e977c) with 2435 transactions
2019-12-04 14:53:55.279 [DBG] LNWL: Filtering block 606418 (0000000000000000000482c7f7a9f2260f297649a739f5db511fb989db2d2105) with 3265 transactions

- RTL is OFF by config

*** HARDWARE TEST RESULTS ***
UndervoltageReports in Logs: 0
raspberryPi=4
powerFAIL=0
powerWARN=0
powerMIN='875300 microVolt'
tempFAIL=0
tempWARN=0
tempMAX='4700 centiGrad'

*** SYSTEM STATUS (can take some seconds to gather) ***
localIP='192.168.1.175'
tempCelsius='45.7'
uptime=3681
upsStatus='OFF'
startcountBlockchain=1
bitcoinActive=1
blockchainHeight=606627
initialSync=0
syncProgress=100.00
startcountLightning=1
lndActive=1
walletLocked=0
syncedToChain=0
scanTimestamp=1575359979
scanDate='Tue  3 Dec 07:59:39 GMT 2019'
scanProgress=99.96
lndRPCReady=1
scriptRuntime=1

And sometimes it cannot:

***************************************************************
* RASPIBLITZ DEBUG LOGS
***************************************************************
blitzversion: 1.3
chainnetwork: bitcoin / main
 15:07:10 up  1:14,  2 users,  load average: 2.72, 2.47, 1.97

*** BLOCKCHAIN SYSTEMD STATUS ***
Failed to get properties: Connection timed out

*** LAST BLOCKCHAIN ERROR LOGS ***
sudo journalctl -u bitcoind -b --no-pager -n8
-- Logs begin at Wed 2019-12-04 13:52:27 GMT, end at Wed 2019-12-04 15:08:40 GMT. --
Dec 04 14:36:47 ViresInNumeris systemd[1]: Starting Bitcoin daemon...
Dec 04 14:36:47 ViresInNumeris systemd[1]: Started Bitcoin daemon.
1575471572 ERROR: Error found in Logs
1575471980 ERROR: Error found in Logs

*** LAST BLOCKCHAIN 20 INFO LOGS ***
sudo tail -n 20 /mnt/hdd/bitcoin/debug.log
2019-12-04T14:38:40Z New outbound peer connected: version: 70015, blocks=606623, peer=2
2019-12-04T14:38:41Z Pre-allocating up to position 0x2000000 in blk01885.dat
2019-12-04T14:38:49Z Pre-allocating up to position 0x300000 in rev01885.dat
2019-12-04T14:38:49Z UpdateTip: new best=0000000000000000000019083464a4c259f8057f84a55e3ac137b47a6883454e height=606623 version=0x20c00000 log2_work=91.404525 tx=480702774 date='2019-12-04T14:28:29Z' progress=0.999995 cache=6.6MiB(57575txo) warning='63 of last 100 blocks have unexpected version'
2019-12-04T14:38:49Z New outbound peer connected: version: 70015, blocks=606623, peer=4
2019-12-04T14:38:55Z New outbound peer connected: version: 70015, blocks=606623, peer=6
2019-12-04T14:38:56Z New outbound peer connected: version: 70015, blocks=606623, peer=7
2019-12-04T14:39:14Z New outbound peer connected: version: 70015, blocks=606623, peer=8
2019-12-04T14:39:16Z New outbound peer connected: version: 70015, blocks=606623, peer=9
2019-12-04T14:39:22Z New outbound peer connected: version: 70015, blocks=606623, peer=10
2019-12-04T14:42:59Z UpdateTip: new best=00000000000000000002a43bc2d047a50c7d620f931a899c03e813a117a4807b height=606624 version=0x3fffe000 log2_work=91.40455 tx=480705821 date='2019-12-04T14:42:08Z' progress=1.000000 cache=7.6MiB(66645txo) warning='64 of last 100 blocks have unexpected version'
2019-12-04T14:49:02Z UpdateTip: new best=00000000000000000012a0401b977c3ef83116325a815dde38fdd1a8bae21d07 height=606625 version=0x3fffe000 log2_work=91.404575 tx=480708814 date='2019-12-04T14:49:12Z' progress=1.000000 cache=8.4MiB(74777txo) warning='65 of last 100 blocks have unexpected version'
2019-12-04T14:50:46Z UpdateTip: new best=0000000000000000000d81463e0e644f3f9f4a3be9cb4c06c6d41c516d6a5d32 height=606626 version=0x20800000 log2_work=91.404599 tx=480710985 date='2019-12-04T14:50:28Z' progress=1.000000 cache=9.0MiB(80181txo) warning='65 of last 100 blocks have unexpected version'
2019-12-04T14:51:48Z New outbound peer connected: version: 70015, blocks=606626, peer=12
2019-12-04T14:53:15Z UpdateTip: new best=0000000000000000000af4a1ef60ef69aaf8e803da0dfc2d3a85090e73d1668c height=606627 version=0x20000000 log2_work=91.404624 tx=480712974 date='2019-12-04T14:53:06Z' progress=1.000000 cache=9.7MiB(86908txo) warning='64 of last 100 blocks have unexpected version'
2019-12-04T14:55:15Z UpdateTip: new best=0000000000000000000044c4fcdc99bf60066df1f53f27839cb90d4ec4a719b0 height=606628 version=0x20800000 log2_work=91.404648 tx=480714066 date='2019-12-04T14:54:39Z' progress=1.000000 cache=10.2MiB(91523txo) warning='64 of last 100 blocks have unexpected version'
2019-12-04T15:01:29Z UpdateTip: new best=0000000000000000000a2a2fa3d8c7bb56d18a8db580295afe839526b5b22da0 height=606629 version=0x20c00000 log2_work=91.404673 tx=480716606 date='2019-12-04T15:01:14Z' progress=1.000000 cache=11.1MiB(99371txo) warning='64 of last 100 blocks have unexpected version'
2019-12-04T15:02:51Z Pre-allocating up to position 0x400000 in rev01885.dat
2019-12-04T15:02:52Z UpdateTip: new best=000000000000000000002b111c105fa7e080a11e3a97d55c76916b1404a5c577 height=606630 version=0x20000000 log2_work=91.404697 tx=480718522 date='2019-12-04T15:02:06Z' progress=1.000000 cache=12.7MiB(110310txo) warning='63 of last 100 blocks have unexpected version'
2019-12-04T15:04:46Z UpdateTip: new best=0000000000000000000f2bf7a99ddbd44573547063dc0e73cf0d4844335a8b59 height=606631 version=0x20000000 log2_work=91.404722 tx=480720438 date='2019-12-04T15:04:35Z' progress=1.000000 cache=13.2MiB(114919txo) warning='62 of last 100 blocks have unexpected version'

*** LND SYSTEMD STATUS ***
Failed to get properties: Connection timed out

*** LAST LND ERROR LOGS ***
sudo journalctl -u lnd -b --no-pager -n12
-- Logs begin at Wed 2019-12-04 13:52:27 GMT, end at Wed 2019-12-04 15:10:11 GMT. --
Dec 04 14:36:47 ViresInNumeris systemd[1]: Starting LND Lightning Daemon...
Dec 04 14:36:47 ViresInNumeris systemd[1]: Started LND Lightning Daemon.

*** LAST 30 LND INFO LOGS ***
sudo tail -n 30 /mnt/hdd/lnd/logs/bitcoin/mainnet/lnd.log
2019-12-04 15:10:07.685 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567669057409253377) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.685 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567672355997220864) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.686 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567672355997548544) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.686 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567676753982062592) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.687 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567676753988681728) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.687 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567676753988943872) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.687 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567677853461643264) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.688 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567681152052559872) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.688 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567681152052625408) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.689 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567681152052690944) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.689 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567681152052887552) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.690 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567692147110576128) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.690 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567692147114573824) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.691 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567692147114704896) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.691 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567694346114301953) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.692 [DBG] PEER: Received ChannelUpdate(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567694346114301953, mflags=00000001, cflags=00000010, update_time=2019-12-04 04:07:02 +0000 GMT) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.692 [DBG] PEER: Received NodeAnnouncement(node=03e8f8e8666030a241a5d790cef81625620650621fb4aa7f0dca867c90e2e2c083, update_time=2018-12-04 11:41:27 +0000 GMT) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.693 [DBG] PEER: Received ChannelUpdate(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567694346114301953, mflags=00000000, cflags=00000001, update_time=2018-12-06 06:44:44 +0000 GMT) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.693 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567695445686484992) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.694 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567695445686616064) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.694 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567696545261551616) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.695 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567696545261617152) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.695 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567696545261682688) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.696 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567696545261748224) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.696 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567696545261813760) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.697 [DBG] PEER: Received ChannelUpdate(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567696545261813760, mflags=00000001, cflags=00000010, update_time=2019-12-04 01:39:10 +0000 GMT) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.697 [DBG] PEER: Received ChannelUpdate(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567696545261813760, mflags=00000000, cflags=00000011, update_time=2018-07-31 16:27:59 +0100 BST) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.698 [DBG] PEER: Received NodeAnnouncement(node=031d244a60d61936834f290656cdf0ed4b9979260c19b07804bde681819f8e14ca, update_time=2019-12-03 15:09:10 +0000 GMT) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.698 [DBG] PEER: Received ChannelAnnouncement(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, short_chan_id=567702042763198464) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735
2019-12-04 15:10:07.699 [DBG] PEER: Received ReplyShortChanIDsEnd(chain_hash=000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, complete=1) from 03db7afbcf3d7ab282d976d2a73c6537cc2cb26b96cdc08f88d05bb492f53caa6b@75.26.11.172:9735

- RTL is OFF by config

*** HARDWARE TEST RESULTS ***
UndervoltageReports in Logs: 0
raspberryPi=4
powerFAIL=0
powerWARN=0
powerMIN='875300 microVolt'
tempFAIL=0
tempWARN=0
tempMAX='4700 centiGrad'

*** SYSTEM STATUS (can take some seconds to gather) ***
localIP='192.168.1.175'
tempCelsius='46.7'
uptime=4656
upsStatus='OFF'
startcountBlockchain=1
bitcoinActive=0
bitcoinErrorShort='Error found in Logs'
bitcoinErrorFull='0'
# OK: log 'ERROR: Error found in Logs' written to /home/admin/systemd.blockchain.log
startcountLightning=1
lndActive=0
scriptRuntime=181
@openoms
Copy link
Collaborator

openoms commented Dec 4, 2019

Are you using a shim fan? Try removing it! Very much looks like the random issues documented in this thead: #796

For now don`t worry about the RPi getting hot without the fan, it will slow down instead of getting damaged. Use and open case and possibly a USB fan or else. We are looking for the ideal solution. Also recently ordered another shim-fan to see if it is just (quite common) sporadic issue or a design problem.

@got3nks
Copy link
Author

got3nks commented Dec 4, 2019 •
edited
Loading

Yes I'm using a shim fan, indeed.

I checked the thread you mentioned and it looks like a workout would be to uninstall Plymouth?

P.S. I bought a pre-assembled node. How do I remove the shim fan after opening the case?

@openoms
Copy link
Collaborator

openoms commented Dec 4, 2019

As you can see from my comments #796 (comment) I had further problems after removing plymouth got me through the boot process.
The cause is not plymouth, but likely the shimfan interfering with the LCD on the GPIO.
Not sure if it affects all the new fans, but as far as I can tell only removing/changing can solve the problem completely.

To remove plymouth run:
sudo apt-get purge plymouth*
and restart.

@got3nks
Copy link
Author

got3nks commented Dec 4, 2019 •
edited
Loading

What issues did you experience after removing plymouth?

After uninstalling plymouth and installing FanShim drivers I didn't experience any issues so far.

@openoms
Copy link
Collaborator

openoms commented Dec 5, 2019

What issues did you experience after removing plymouth?

After uninstalling plymouth and installing FanShim drivers I didn't experience any issues so far.

See this comment #796 (comment)
and please report if you experience similar.

@got3nks
Copy link
Author

got3nks commented Dec 5, 2019

Only shutdown and reboot issues? I have shut down my node from the RaspiBlitz menu succesfully a few times.

@got3nks
Copy link
Author

got3nks commented Dec 6, 2019 •
edited
Loading

Well you are right, having issues with shuthdown now.

admin@ViresInNumeris:~ $ sudo shutdown
Shutdown scheduled for Fri 2019-12-06 14:46:52 CET, use 'shutdown -c' to cancel.

But node is not shutting down (~ 5 mins passed already).

@openoms
Copy link
Collaborator

openoms commented Dec 6, 2019

Suboptimal but best way to pull the plug is to disconnect the network cable first. Wait for the HDD led to stop blinking. Pull the power only then.

sudo reboot -f found to work for restart.

@openoms openoms mentioned this issue Feb 13, 2020
Closed
@openoms openoms added the hardware Related e.g. to Power Supplies, Screens.. label Feb 13, 2020
@openoms
Copy link
Collaborator

openoms commented Nov 8, 2021

solved and outdated

@openoms openoms closed this as completed Nov 8, 2021
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
hardware Related e.g. to Power Supplies, Screens..
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@got3nks @openoms