NHZ V3.2 stopping ... without me causing it

Discussion in 'Wallet, Blockchain And API Coders' started by AndreasKae, Oct 30, 2014.

  1. AndreasKae

    Please move this to "support" if you want to, for some reason I cannot create a thread there.

    Heyho!
    I was successful with installing NHZ on a RaspberryPi, but after many many MANY hours of downloading the blockchain, I encounter a curious problem: NHZ is starting up, goes through all the blocks (which takes 5 hours) and then ...

    ... NHZ just terminates itself.

    See the protocol below.

    Any ideas why this happens? What to do?

    Thanks!
    Andreas

    ---



    2014-10-30 15:00:52 INFO: nhz.enableStackTraces = "true"
    2014-10-30 15:00:53 INFO: nhz.enableLogTraceback = "false"
    2014-10-30 15:00:53 INFO: logging enabled
    2014-10-30 15:00:53 INFO: nhz.enableUPNP = "true"
    2014-10-30 15:00:53 INFO: starting upnp detection
    2014-10-30 15:01:02 INFO: upnp detection failed
    2014-10-30 15:01:02 INFO: nhz.dbCacheKB = "0"
    2014-10-30 15:01:02 INFO: nhz.isTestnet = "false"
    2014-10-30 15:01:02 INFO: nhz.dbUrl = "jdbc:h2:nhz_db/nhz;DB_CLOSE_ON_EXIT=FALSE"
    2014-10-30 15:01:02 INFO: nhz.maxDbConnections = "10"
    2014-10-30 15:01:02 INFO: nhz.dbLoginTimeout = "70"
    2014-10-30 15:01:02 INFO: nhz.dbDefaultLockTimeout = "10"
    2014-10-30 15:03:33 INFO: Database update may take a while if needed, current db version 51...
    2014-10-30 15:03:33 INFO: nhz.forceValidate = "false"
    2014-10-30 15:03:33 INFO: nhz.myPlatform = "RaspberrPi"
    2014-10-30 15:03:33 INFO: nhz.myAddress = [...]
    2014-10-30 15:03:33 INFO: nhz.peerServerPort = "7774"
    2014-10-30 15:03:33 INFO: nhz.shareMyAddress = "true"
    2014-10-30 15:03:33 INFO: nhz.myHallmark = [...]
    2014-10-30 15:03:41 INFO: nhz.wellKnownPeers = "horizon.nhzcrypto.org;digital.nhzcrypto.org;82.118.242.244;37.187.237.56;37.187.237.211;5.45.97.233;137.117.84.21;54.193.59.188;188.226.217.137;85.25.67.39;198.50.146.167;128.199.197.56;54.186.81.151;188.226.207.183;85.214.65.220;168.63.251.208;80.241.220.178;23.97.166.145;107.170.116.134;37.187.237.150;198.211.122.85;31.19.188.145;37.187.237.114;37.187.225.106;191.235.134.92;37.187.18.97;146.185.168.63;pool.nhzcrypto.org"
    2014-10-30 15:03:41 INFO: nhz.knownBlacklistedPeers not defined
    2014-10-30 15:03:41 INFO: nhz.maxNumberOfConnectedPublicPeers = "20"
    2014-10-30 15:03:41 INFO: nhz.connectTimeout = "4000"
    2014-10-30 15:03:41 INFO: nhz.readTimeout = "8000"
    2014-10-30 15:03:41 INFO: nhz.enableHallmarkProtection = "true"
    2014-10-30 15:03:41 INFO: nhz.pushThreshold = "0"
    2014-10-30 15:03:41 INFO: nhz.pullThreshold = "0"
    2014-10-30 15:03:41 INFO: nhz.blacklistingPeriod = "600000"
    2014-10-30 15:03:41 INFO: nhz.communicationLoggingMask = "0"
    2014-10-30 15:03:41 INFO: nhz.sendToPeersLimit = "10"
    2014-10-30 15:03:41 INFO: nhz.usePeersDb = "true"
    2014-10-30 15:03:59 INFO: nhz.savePeers = "true"
    2014-10-30 15:03:59 INFO: Logging initialized @189314ms
    2014-10-30 15:04:00 INFO: nhz.peerServerHost = "0.0.0.0"
    2014-10-30 15:04:00 INFO: nhz.peerServerIdleTimeout = "30000"
    2014-10-30 15:04:00 INFO: nhz.enablePeerServerDoSFilter = "true"
    2014-10-30 15:04:00 INFO: nhz.peerServerDoSFilter.maxRequestsPerSec = "30"
    2014-10-30 15:04:01 INFO: nhz.peerServerDoSFilter.delayMs = "1000"
    2014-10-30 15:04:01 INFO: nhz.peerServerDoSFilter.maxRequestMs = "300000"
    2014-10-30 15:04:01 INFO: nhz.allowedBotHosts = "127.0.0.1; localhost; [0:0:0:0:0:0:0:1]; 192.168.0.7"
    2014-10-30 15:04:01 INFO: nhz.enableAPIServer = "true"
    2014-10-30 15:04:01 INFO: nhz.apiServerPort = "7776"
    2014-10-30 15:04:01 INFO: nhz.apiServerHost = "0.0.0.0"
    2014-10-30 15:04:01 INFO: nhz.apiSSL = "false"
    2014-10-30 15:04:01 INFO: nhz.apiServerIdleTimeout = "30000"
    2014-10-30 15:04:01 INFO: nhz.apiResourceBase = "html/ui"
    2014-10-30 15:04:01 INFO: nhz.javadocResourceBase = "html/doc"
    2014-10-30 15:04:01 INFO: nhz.apiServerCORS = "false"
    2014-10-30 15:04:01 INFO: nhz.allowedUserHosts = "127.0.0.1; localhost; [0:0:0:0:0:0:0:1]; 192.168.0.7"
    2014-10-30 15:04:01 INFO: nhz.enableUIServer = "true"
    2014-10-30 15:04:01 INFO: nhz.uiServerPort = "7775"
    2014-10-30 15:04:01 INFO: nhz.uiServerHost = "127.0.0.1"
    2014-10-30 15:04:01 INFO: nhz.uiSSL = "false"
    2014-10-30 15:04:01 INFO: nhz.uiServerIdleTimeout = "30000"
    2014-10-30 15:04:01 INFO: nhz.uiResourceBase = "html/nrs"
    2014-10-30 15:04:01 INFO: nhz.javadocResourceBase = "html/doc"
    2014-10-30 15:04:01 INFO: nhz.uiServerCORS = "false"
    2014-10-30 15:04:02 INFO: nhz.debugTraceQuote = """
    2014-10-30 15:04:02 INFO: nhz.debugTraceSeparator = " "
    2014-10-30 15:04:02 INFO: nhz.debugLogUnconfirmed = "false"
    2014-10-30 15:04:02 INFO: nhz.debugTraceAccounts not defined
    2014-10-30 15:04:02 INFO: nhz.debugTraceLog = "nhz-trace.csv"
    2014-10-30 15:04:02 INFO: Genesis block already in database
    2014-10-30 15:04:02 INFO: Scanning blockchain...
    2014-10-30 15:09:35 INFO: processed block 0
    2014-10-30 15:10:59 INFO: processed block 5000
    2014-10-30 15:12:30 INFO: processed block 10000
    2014-10-30 15:14:19 INFO: processed block 15000
    2014-10-30 15:16:26 INFO: processed block 20000
    2014-10-30 15:22:02 INFO: processed block 25000
    2014-10-30 15:33:41 INFO: processed block 30000
    2014-10-30 15:37:15 INFO: processed block 35000
    2014-10-30 15:41:05 INFO: processed block 40000
    2014-10-30 15:45:04 INFO: processed block 45000
    2014-10-30 15:48:49 INFO: processed block 50000
    2014-10-30 15:54:43 INFO: processed block 55000
    2014-10-30 16:02:30 INFO: processed block 60000
    2014-10-30 16:10:01 INFO: processed block 65000
    2014-10-30 16:13:41 INFO: processed block 70000
    2014-10-30 16:17:58 INFO: processed block 75000
    2014-10-30 16:26:14 INFO: processed block 80000
    2014-10-30 16:34:19 INFO: processed block 85000
    2014-10-30 16:42:34 INFO: processed block 90000
    2014-10-30 16:50:52 INFO: processed block 95000
    2014-10-30 16:58:28 INFO: processed block 100000
    2014-10-30 17:05:46 INFO: processed block 105000
    2014-10-30 17:13:53 INFO: processed block 110000
    2014-10-30 17:21:42 INFO: processed block 115000
    2014-10-30 17:29:34 INFO: processed block 120000
    2014-10-30 17:37:02 INFO: processed block 125000
    2014-10-30 17:44:31 INFO: processed block 130000
    2014-10-30 17:51:20 INFO: processed block 135000
    2014-10-30 17:57:25 INFO: processed block 140000
    2014-10-30 18:00:37 INFO: nhz.dumpPeersVersion not defined
    2014-10-30 18:05:34 INFO: processed block 145000
    2014-10-30 18:13:06 INFO: processed block 150000
    2014-10-30 18:25:34 INFO: processed block 155000
    2014-10-30 18:47:08 INFO: processed block 160000
    2014-10-30 19:06:02 INFO: processed block 165000
    2014-10-30 19:35:05 INFO: processed block 170000
    2014-10-30 20:06:08 INFO: processed block 175000
    2014-10-30 20:20:35 INFO: ...done
    2014-10-30 20:20:35 INFO: shutdown already commenced
    2014-10-30 20:20:35 INFO: jetty-9.1.5.v20140505
    2014-10-30 20:20:36 INFO: Started ServerConnector@a76583{HTTP/1.1}{0.0.0.0:7774}
    2014-10-30 20:20:36 INFO: Started @19186339ms
    2014-10-30 20:20:36 INFO: Started peer networking server at 0.0.0.0:7774
    2014-10-30 20:20:36 INFO: shutdown already commenced
    2014-10-30 20:20:36 INFO: jetty-9.1.5.v20140505
    2014-10-30 20:20:36 INFO: Started o.e.j.s.h.ContextHandler@121f043{/doc,null,AVAILABLE}
    2014-10-30 20:20:36 INFO: Started ServerConnector@180cfa7{HTTP/1.1}{0.0.0.0:7776}
    2014-10-30 20:20:36 INFO: Started @19186421ms
    2014-10-30 20:20:36 INFO: Started API server at 0.0.0.0:7776
    2014-10-30 20:20:36 INFO: shutdown already commenced
    2014-10-30 20:20:36 INFO: jetty-9.1.5.v20140505
    2014-10-30 20:20:36 INFO: Started o.e.j.s.h.ContextHandler@17e3336{/doc,null,AVAILABLE}
    2014-10-30 20:20:36 INFO: Started ServerConnector@17b2615{HTTP/1.1}{127.0.0.1:7775}
    2014-10-30 20:20:36 INFO: Started @19186489ms
    2014-10-30 20:20:36 INFO: Started user interface server at 127.0.0.1:7775
    2014-10-30 20:20:36 INFO: Nhz server NHZ V3.2 started successfully.
    2014-10-30 20:21:09 INFO: Database shutdown completed
    2014-10-30 20:21:09 INFO: Nhz server NHZ V3.2 stopped.
     
    AndreasKae, Oct 30, 2014
    #1

  2. AndreasKae

    no idea, anyone?
     
    AndreasKae, Oct 31, 2014
    #2

  3. AndreasKae

    perhaps ... is there a setting which shuts down the server if a timeout threshold is reached?
     
    AndreasKae, Nov 2, 2014
    #3

  4. AndreasKae

    Some more info about the system:

    Code:
    java -version
java version "1.7.0_65"
OpenJDK Runtime Environment (IcedTea 2.5.1) (7u65-2.5.1-2~deb7u1+rpi1)
OpenJDK Zero VM (build 24.65-b04, mixed mode)
    Code:
    uname -a
Linux rpi001ak 3.12.28+ #709 PREEMPT Mon Sep 8 15:28:00 BST 2014 armv6l GNU/Linux
    Code:
    cat /proc/version
Linux version 3.12.28+ (dc4@dc4-XPS13-9333) (gcc version 4.8.3 20140303 (prerelease) (crosstool-NG linaro-1.13.1+bzr2650 - Linaro GCC 2014.03) ) #709 PREEMPT Mon Sep 8 15:28:00 BST 2014
    Code:
    cat /etc/issue.net
Raspbian GNU/Linux 7
    Code:
    cat /proc/cpuinfo
processor       : 0
model name      : ARMv6-compatible processor rev 7 (v6l)
Features        : swp half thumb fastmult vfp edsp java tls
CPU implementer : 0x41
CPU architecture: 7
CPU variant     : 0x0
CPU part        : 0xb76
CPU revision    : 7
Hardware        : BCM2708
    Code:
    gcc --version
gcc (Debian 4.6.3-14+rpi1) 4.6.3
     
    AndreasKae, Nov 10, 2014
    #4
  5. KrisNHZfan

    KrisNHZfan

    maybe delete your blockchain, and sync it again?
     
    KrisNHZfan, Nov 10, 2014
    #5

  6. AndreasKae

    done that ... been there ... :)
     
    AndreasKae, Nov 10, 2014
    #6

  7. AndreasKae

    I tried again, now with this "nhz.properties" file,
    all other "nhz-default.properties" unchanged:

    Code:
    nhz.dbCacheKB=10000
nhz.myAddress=(ME)
nhz.myHallmark=(ME)
nhz.allowedBotHosts=127.0.0.1; localhost; [0:0:0:0:0:0:0:1]; 192.168.0.7
nhz.allowedUserHosts=127.0.0.1; localhost; [0:0:0:0:0:0:0:1]; 192.168.0.7
nhz.apiServerHost=0.0.0.0
nhz.myPlatform=RaspberrPi
    same result:
     
    Last edited: Nov 11, 2014
    AndreasKae, Nov 11, 2014
    #7

  8. AndreasKae

    Code:
    2014-11-10 18:50:01 INFO: nhz.enableStackTraces = "true"
2014-11-10 18:50:02 INFO: nhz.enableLogTraceback = "false"
2014-11-10 18:50:02 INFO: logging enabled
2014-11-10 18:50:02 INFO: nhz.enableUPNP = "true"
2014-11-10 18:50:02 INFO: starting upnp detection
2014-11-10 18:50:11 INFO: upnp detection failed
2014-11-10 18:50:11 INFO: nhz.dbCacheKB = "10000"
2014-11-10 18:50:11 INFO: nhz.isTestnet = "false"
2014-11-10 18:50:11 INFO: nhz.dbUrl = "jdbc:h2:nhz_db/nhz;DB_CLOSE_ON_EXIT=FALSE"
2014-11-10 18:50:12 INFO: nhz.maxDbConnections = "10"
2014-11-10 18:50:12 INFO: nhz.dbLoginTimeout = "70"
2014-11-10 18:50:12 INFO: nhz.dbDefaultLockTimeout = "10"
2014-11-10 18:50:26 INFO: Database update may take a while if needed, current db version 51...
2014-11-10 18:50:26 INFO: nhz.forceValidate = "false"
2014-11-10 18:50:27 INFO: nhz.myPlatform = "RaspberrPi"
2014-11-10 18:50:27 INFO: nhz.myAddress = "ME"
2014-11-10 18:50:27 INFO: nhz.peerServerPort = "7774"
2014-11-10 18:50:27 INFO: nhz.shareMyAddress = "true"
2014-11-10 18:50:27 INFO: nhz.myHallmark = "ME"
2014-11-10 18:50:42 INFO: nhz.wellKnownPeers = "horizon.nhzcrypto.org;digital.nhzcrypto.org;82.118.242.244;37.187.237.56;37.187.237.211;5.45.97.233;137.117.84.21;54.193.59.188;188.226.217.137;85.25.67.39;198.50.146.167;128.199.197.56;54.186.81.151;188.226.207.183;85.214.65.220;168.63.251.208;80.241.220.178;23.97.166.145;107.170.116.134;37.187.237.150;198.211.122.85;31.19.188.145;37.187.237.114;37.187.225.106;191.235.134.92;37.187.18.97;146.185.168.63;pool.nhzcrypto.org"
2014-11-10 18:50:42 INFO: nhz.knownBlacklistedPeers not defined
2014-11-10 18:50:42 INFO: nhz.maxNumberOfConnectedPublicPeers = "20"
2014-11-10 18:50:42 INFO: nhz.connectTimeout = "4000"
2014-11-10 18:50:42 INFO: nhz.readTimeout = "8000"
2014-11-10 18:50:42 INFO: nhz.enableHallmarkProtection = "true"
2014-11-10 18:50:42 INFO: nhz.pushThreshold = "0"
2014-11-10 18:50:42 INFO: nhz.pullThreshold = "0"
2014-11-10 18:50:42 INFO: nhz.blacklistingPeriod = "600000"
2014-11-10 18:50:42 INFO: nhz.communicationLoggingMask = "0"
2014-11-10 18:50:42 INFO: nhz.sendToPeersLimit = "10"
2014-11-10 18:50:42 INFO: nhz.usePeersDb = "true"
2014-11-10 18:51:23 INFO: nhz.savePeers = "true"
2014-11-10 18:51:24 INFO: Logging initialized @85627ms
2014-11-10 18:51:25 INFO: nhz.peerServerHost = "0.0.0.0"
2014-11-10 18:51:25 INFO: nhz.peerServerIdleTimeout = "30000"
2014-11-10 18:51:26 INFO: nhz.enablePeerServerDoSFilter = "true"
2014-11-10 18:51:26 INFO: nhz.peerServerDoSFilter.maxRequestsPerSec = "30"
2014-11-10 18:51:26 INFO: nhz.peerServerDoSFilter.delayMs = "1000"
2014-11-10 18:51:26 INFO: nhz.peerServerDoSFilter.maxRequestMs = "300000"
2014-11-10 18:51:26 INFO: nhz.allowedBotHosts = "127.0.0.1; localhost; [0:0:0:0:0:0:0:1]; 192.168.0.7"
2014-11-10 18:51:26 INFO: nhz.enableAPIServer = "true"
2014-11-10 18:51:26 INFO: nhz.apiServerPort = "7776"
2014-11-10 18:51:26 INFO: nhz.apiServerHost = "0.0.0.0"
2014-11-10 18:51:26 INFO: nhz.apiSSL = "false"
2014-11-10 18:51:26 INFO: nhz.apiServerIdleTimeout = "30000"
2014-11-10 18:51:26 INFO: nhz.apiResourceBase = "html/ui"
2014-11-10 18:51:27 INFO: nhz.javadocResourceBase = "html/doc"
2014-11-10 18:51:27 INFO: nhz.apiServerCORS = "false"
2014-11-10 18:51:28 INFO: nhz.allowedUserHosts = "127.0.0.1; localhost; [0:0:0:0:0:0:0:1]; 192.168.0.7"
2014-11-10 18:51:28 INFO: nhz.enableUIServer = "true"
2014-11-10 18:51:28 INFO: nhz.uiServerPort = "7775"
2014-11-10 18:51:28 INFO: nhz.uiServerHost = "127.0.0.1"
2014-11-10 18:51:28 INFO: nhz.uiSSL = "false"
2014-11-10 18:51:28 INFO: nhz.uiServerIdleTimeout = "30000"
2014-11-10 18:51:28 INFO: nhz.uiResourceBase = "html/nrs"
2014-11-10 18:51:28 INFO: nhz.javadocResourceBase = "html/doc"
2014-11-10 18:51:28 INFO: nhz.uiServerCORS = "false"
2014-11-10 18:51:28 INFO: nhz.debugTraceQuote = """
2014-11-10 18:51:28 INFO: nhz.debugTraceSeparator = "	"
2014-11-10 18:51:28 INFO: nhz.debugLogUnconfirmed = "false"
2014-11-10 18:51:28 INFO: nhz.debugTraceAccounts not defined
2014-11-10 18:51:28 INFO: nhz.debugTraceLog = "nhz-trace.csv"
2014-11-10 18:51:28 INFO: Genesis block already in database
2014-11-10 18:51:28 INFO: Scanning blockchain...
2014-11-10 19:01:56 INFO: processed block 0
2014-11-10 19:04:39 INFO: processed block 5000
2014-11-10 19:07:46 INFO: processed block 10000
2014-11-10 19:11:20 INFO: processed block 15000
2014-11-10 19:15:38 INFO: processed block 20000
2014-11-10 19:27:18 INFO: processed block 25000
2014-11-10 19:53:11 INFO: processed block 30000
2014-11-10 20:01:05 INFO: processed block 35000
2014-11-10 20:08:39 INFO: nhz.dumpPeersVersion not defined
2014-11-10 20:09:32 INFO: processed block 40000
2014-11-10 20:18:15 INFO: processed block 45000
2014-11-10 20:26:34 INFO: processed block 50000
2014-11-10 20:39:32 INFO: processed block 55000
2014-11-10 20:56:26 INFO: processed block 60000
2014-11-10 21:12:43 INFO: processed block 65000
2014-11-10 21:20:35 INFO: processed block 70000
2014-11-10 21:29:51 INFO: processed block 75000
2014-11-10 21:47:38 INFO: processed block 80000
2014-11-10 22:05:12 INFO: processed block 85000
2014-11-10 22:23:04 INFO: processed block 90000
2014-11-10 22:41:04 INFO: processed block 95000
2014-11-10 22:57:34 INFO: processed block 100000
2014-11-10 23:13:26 INFO: processed block 105000
2014-11-10 23:31:03 INFO: processed block 110000
2014-11-10 23:48:02 INFO: processed block 115000
2014-11-11 00:05:11 INFO: processed block 120000
2014-11-11 00:20:52 INFO: processed block 125000
2014-11-11 00:35:39 INFO: processed block 130000
2014-11-11 00:48:49 INFO: processed block 135000
2014-11-11 01:02:00 INFO: processed block 140000
2014-11-11 01:19:49 INFO: processed block 145000
2014-11-11 01:36:15 INFO: processed block 150000
2014-11-11 02:03:28 INFO: processed block 155000
2014-11-11 02:50:27 INFO: processed block 160000
2014-11-11 03:31:42 INFO: processed block 165000
2014-11-11 04:33:31 INFO: processed block 170000
2014-11-11 05:40:54 INFO: processed block 175000
2014-11-11 06:12:07 INFO: ...done
2014-11-11 06:12:07 INFO: shutdown already commenced
2014-11-11 06:12:07 INFO: jetty-9.1.5.v20140505
2014-11-11 06:12:09 INFO: Started ServerConnector@123b632{HTTP/1.1}{0.0.0.0:7774}
2014-11-11 06:12:09 INFO: Started @40931016ms
2014-11-11 06:12:09 INFO: Started peer networking server at 0.0.0.0:7774
2014-11-11 06:12:09 INFO: shutdown already commenced
2014-11-11 06:12:09 INFO: jetty-9.1.5.v20140505
2014-11-11 06:12:09 INFO: Started o.e.j.s.h.ContextHandler@331017{/doc,null,AVAILABLE}
2014-11-11 06:12:09 INFO: Started ServerConnector@1a115d{HTTP/1.1}{0.0.0.0:7776}
2014-11-11 06:12:09 INFO: Started @40931165ms
2014-11-11 06:12:09 INFO: Started API server at 0.0.0.0:7776
2014-11-11 06:12:09 INFO: shutdown already commenced
2014-11-11 06:12:09 INFO: jetty-9.1.5.v20140505
2014-11-11 06:12:09 INFO: Started o.e.j.s.h.ContextHandler@1a8ec64{/doc,null,AVAILABLE}
2014-11-11 06:12:09 INFO: Started ServerConnector@4a25ab{HTTP/1.1}{127.0.0.1:7775}
2014-11-11 06:12:09 INFO: Started @40931271ms
2014-11-11 06:12:09 INFO: Started user interface server at 127.0.0.1:7775
2014-11-11 06:12:09 INFO: Nhz server NHZ V3.2 started successfully.
2014-11-11 06:12:17 INFO: Database shutdown completed
2014-11-11 06:12:17 INFO: Nhz server NHZ V3.2 stopped.
     
    AndreasKae, Nov 11, 2014
    #8

  9. AndreasKae

    It takes >10 hours to scan the blockchain:
    Code:
    2014-11-10 18:51:28 INFO: Scanning blockchain...
2014-11-10 19:01:56 INFO: processed block 0
...
2014-11-11 05:40:54 INFO: processed block 175000
2014-11-11 06:12:07 INFO: ...done
2014-11-11 06:12:07 INFO: shutdown already commenced
    and then it says "shutdown already commenced"

    - but why?

    Where (and why) is the shutdown triggered?
     
    AndreasKae, Nov 11, 2014
    #9

Share This Page