broken ordering log

gistfile1.txt

Running: geth --port 30304 --rpc --rpcaddr 127.0.0.1 --rpcport 8101 --rpccorsdomain http://127.0.0.1:8000 --genesis config/development/genesis.json --datadir datadir/development/ --networkid 5473 --bootnodes enode://05a87dcaf7a5014b38d4eec48119fa2afd942a2b4a69c0e7fdcebeac00de7827e7da64a17dbce2238ec1b84870163ccde76d730a90334762c2da9c0c9c920567@40.117.36.75:30303 --unlock 42c35b6c220d570bd8898a540406e0b026479f7b --password config/development/password --vmodule downloader=6,fetcher=6
I0216 19:32:33.591320   26863 database.go:71] Alloted 16MB cache to datadir/development/chaindata
I0216 19:32:34.253622   26863 database.go:71] Alloted 16MB cache to datadir/development/dapp
I0216 19:32:34.263135   26863 backend.go:314] Protocol Versions: [63 62 61], Network Id: 5473
I0216 19:32:34.279367   26863 genesis.go:89] Genesis block already in chain. Writing canonical number
I0216 19:32:34.279467   26863 backend.go:326] Successfully wrote genesis block. New genesis hash = 661ecc133725444e7e8afad49342f96a5f6315eab7b58f8dd868f88e5830fd0f
I0216 19:32:34.300261   26863 backend.go:362] Blockchain DB Version: 3
I0216 19:32:34.301429   26863 blockchain.go:214] Last header: #3447 [ee4aed57…] TD=997063449
I0216 19:32:34.301466   26863 blockchain.go:215] Last block: #3447 [ee4aed57…] TD=997063449
I0216 19:32:34.301486   26863 blockchain.go:216] Fast block: #3447 [ee4aed57…] TD=997063449
I0216 19:32:34.307754   26863 cmd.go:114] Starting Geth/v1.3.3/darwin/go1.5.3
I0216 19:32:34.307931   26863 server.go:311] Starting Server
I0216 19:32:36.048330   26863 nat.go:111] mapped network port udp:30304 -> 30304 (ethereum discovery) using UPNP IGDv1-IP1
I0216 19:32:36.419384   26863 udp.go:204] Listening, enode://5eeb2f6a485f03fe0e1fbafe719c59a153460109358e0d5c766dc2823aec48bed4551b3bb9778efab28b2b5d2d725a1f0fad914483568f6e366321e391db5440@207.38.149.213:30304
I0216 19:32:36.419627   26863 backend.go:526] Server started
I0216 19:32:36.419808   26863 server.go:552] Listening on [::]:30304
I0216 19:32:36.647017   26863 nat.go:111] mapped network port tcp:30304 -> 30304 (ethereum p2p) using UPNP IGDv1-IP1
Account '42c35b6c220d570bd8898a540406e0b026479f7b' (42c35b6c220d570bd8898a540406e0b026479f7b) unlocked.
I0216 19:32:39.099211   26863 ipc.go:112] IPC service started (datadir/development/geth.ipc)
I0216 19:33:40.372967   26863 downloader.go:228] Registering peer 05a87dcaf7a5014b
I0216 19:33:46.426128   26863 downloader.go:252] Attempting synchronisation: 05a87dcaf7a5014b, head [cd089ed5…], TD 1039711303
I0216 19:33:46.426244   26863 downloader.go:288] Block synchronisation started
I0216 19:33:46.426456   26863 downloader.go:337] Synchronising with the network using: 05a87dcaf7a5014b [eth/63]
I0216 19:33:46.472296   26863 downloader.go:905] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: retrieving remote chain height
I0216 19:33:46.702310   26863 fetcher.go:258] [eth/62] filtering 1 headers
I0216 19:33:46.714674   26863 downloader.go:953] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: looking for common ancestor
I0216 19:33:48.614852   26863 downloader.go:1015] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: common ancestor: #3447 [ee4aed57…]
I0216 19:33:48.615042   26863 downloader.go:1267] Downloading block bodies from #3448
I0216 19:33:48.615029   26863 downloader.go:1084] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: downloading headers from #3448
I0216 19:33:48.615110   26863 downloader.go:1291] Downloading receipts from #3448
I0216 19:33:48.615019   26863 downloader.go:1315] Downloading node state data
I0216 19:33:48.615174   26863 downloader.go:1118] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: fetching 192 headers from #3448
I0216 19:33:49.402989   26863 downloader.go:1186] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: schedule 88 headers from #3448
I0216 19:33:49.454321   26863 downloader.go:1118] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: fetching 192 headers from #3536
I0216 19:33:49.457126   26863 downloader.go:1527] Inserting chain with 88 items (#3448 [5f28906a…] - #3535 [cd089ed5…])
I0216 19:33:49.537088   26863 downloader.go:1144] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: no available headers
I0216 19:33:49.537219   26863 downloader.go:1180] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: header download terminated
I0216 19:33:49.537298   26863 downloader.go:1448] State fetching completed
I0216 19:33:49.537354   26863 downloader.go:1354] Node state data download terminated: <nil>
I0216 19:33:49.537373   26863 downloader.go:1448] Body fetching completed
I0216 19:33:49.537392   26863 downloader.go:1283] Block body download terminated: <nil>
I0216 19:33:49.537412   26863 downloader.go:1448] Receipt fetching completed
I0216 19:33:49.537449   26863 downloader.go:1307] Receipt download terminated: <nil>
I0216 19:33:50.804033   26863 blockchain.go:1243] imported 88 block(s) (0 queued 0 ignored) including 0 txs in 1.300431593s. #3535 [5f28906a / cd089ed5]
I0216 19:33:50.820574   26863 downloader.go:339] Synchronisation terminated after 4.394074359s
I0216 19:33:50.820609   26863 downloader.go:257] Synchronisation completed
I0216 19:34:41.507290   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3536 [d3b7db57…], total 1
I0216 19:34:41.507429   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3536 [d3b7db57…]
I0216 19:34:41.516252   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 4.308425ms. #3536 [d3b7db57 / d3b7db57]
I0216 19:34:41.977820   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3537 [b5a10136…], total 1
I0216 19:34:41.977940   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3537 [b5a10136…]
I0216 19:34:41.986208   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 4.129527ms. #3537 [b5a10136 / b5a10136]
I0216 19:34:42.007114   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3538 [87e75bdf…], total 1
I0216 19:34:42.007280   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3538 [87e75bdf…]
I0216 19:34:42.017530   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 5.63291ms. #3538 [87e75bdf / 87e75bdf]
I0216 19:34:43.134907   26863 downloader.go:252] Attempting synchronisation: 05a87dcaf7a5014b, head [53e575ef…], TD 1042162696
I0216 19:34:43.135003   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3540 [53e575ef…], total 1
I0216 19:34:43.135019   26863 downloader.go:337] Synchronising with the network using: 05a87dcaf7a5014b [eth/63]
I0216 19:34:43.135065   26863 downloader.go:905] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: retrieving remote chain height
I0216 19:34:43.447573   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3539 [59f4e13d…], total 2
I0216 19:34:43.447694   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3539 [59f4e13d…]
I0216 19:34:43.457710   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 5.871296ms. #3539 [59f4e13d / 59f4e13d]
I0216 19:34:43.457802   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3540 [53e575ef…]
I0216 19:34:43.466073   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 4.424329ms. #3540 [53e575ef / 53e575ef]
I0216 19:34:44.995901   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3541 [495cf245…], total 1
I0216 19:34:44.996012   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3541 [495cf245…]
I0216 19:34:45.005258   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 4.332913ms. #3541 [495cf245 / 495cf245]
I0216 19:34:45.018451   26863 fetcher.go:258] [eth/62] filtering 1 headers
I0216 19:34:45.018542   26863 downloader.go:953] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: looking for common ancestor
I0216 19:34:46.169884   26863 downloader.go:1015] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: common ancestor: #3541 [495cf245…]
I0216 19:34:46.169999   26863 downloader.go:1315] Downloading node state data
I0216 19:34:46.170032   26863 downloader.go:1267] Downloading block bodies from #3542
I0216 19:34:46.170033   26863 downloader.go:1291] Downloading receipts from #3542
I0216 19:34:46.170004   26863 downloader.go:1084] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: downloading headers from #3542
I0216 19:34:46.170111   26863 downloader.go:1118] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: fetching 192 headers from #3542
I0216 19:34:46.711898   26863 downloader.go:1144] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: no available headers
I0216 19:34:46.712056   26863 downloader.go:1180] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: header download terminated
I0216 19:34:46.712094   26863 downloader.go:1448] Body fetching completed
I0216 19:34:46.712149   26863 downloader.go:1283] Block body download terminated: <nil>
I0216 19:34:46.712134   26863 downloader.go:1448] State fetching completed
I0216 19:34:46.712134   26863 downloader.go:1448] Receipt fetching completed
I0216 19:34:46.712220   26863 downloader.go:1307] Receipt download terminated: <nil>
I0216 19:34:46.712174   26863 downloader.go:1354] Node state data download terminated: <nil>
I0216 19:34:46.712309   26863 downloader.go:339] Synchronisation terminated after 3.577246931s
I0216 19:34:46.712442   26863 downloader.go:257] Synchronisation completed
I0216 19:34:47.318946   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3543 [031bc966…], total 1
I0216 19:34:47.318946   26863 downloader.go:252] Attempting synchronisation: 05a87dcaf7a5014b, head [031bc966…], TD 1043636113
I0216 19:34:47.319077   26863 downloader.go:337] Synchronising with the network using: 05a87dcaf7a5014b [eth/63]
I0216 19:34:47.319098   26863 downloader.go:905] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: retrieving remote chain height
I0216 19:34:47.845093   26863 fetcher.go:428] [eth/62] Peer 05a87dcaf7a5014b: fetching headers [031bc966…]
I0216 19:34:47.919458   26863 fetcher.go:731] Peer 05a87dcaf7a5014b: queued block #3542 [24026678…], total 2
I0216 19:34:47.919825   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3542 [24026678…]
I0216 19:34:47.929657   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 4.48667ms. #3542 [24026678 / 24026678]
I0216 19:34:47.929751   26863 fetcher.go:743] Peer 05a87dcaf7a5014b: importing block #3543 [031bc966…]
I0216 19:34:47.938699   26863 blockchain.go:1243] imported 1 block(s) (0 queued 0 ignored) including 0 txs in 4.849281ms. #3543 [031bc966 / 031bc966]
I0216 19:34:48.280142   26863 fetcher.go:258] [eth/62] filtering 1 headers
I0216 19:34:48.280236   26863 downloader.go:953] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: looking for common ancestor
I0216 19:34:48.576124   26863 fetcher.go:258] [eth/62] filtering 1 headers
I0216 19:34:48.576285   26863 downloader.go:1015] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: common ancestor: #3543 [031bc966…]
I0216 19:34:48.576369   26863 downloader.go:1315] Downloading node state data
I0216 19:34:48.576412   26863 downloader.go:1267] Downloading block bodies from #3544
I0216 19:34:48.576445   26863 downloader.go:1291] Downloading receipts from #3544
I0216 19:34:48.576418   26863 downloader.go:1084] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: downloading headers from #3544
I0216 19:34:48.576526   26863 downloader.go:1118] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: fetching 192 headers from #3544
I0216 19:34:48.837345   26863 downloader.go:1186] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: schedule 192 headers from #3544
I0216 19:34:48.837441   26863 queue.go:331] Header #3352 [e55c1079] broke chain ordering, expected 3544
I0216 19:34:48.837473   26863 downloader.go:1214] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: stale headers
I0216 19:34:48.837497   26863 downloader.go:1215] Peer 05a87dcaf7a5014b [blocks 0.00/s, receipts 0.00/s, states 0.00/s, lacking    0]: header download terminated
I0216 19:34:48.837627   26863 downloader.go:1283] Block body download terminated: block body download canceled (requested)
I0216 19:34:48.837533   26863 downloader.go:1354] Node state data download terminated: state data download canceled (requested)
I0216 19:34:48.837542   26863 downloader.go:1307] Receipt download terminated: receipt download canceled (requested)
I0216 19:34:48.922234   26863 downloader.go:339] Synchronisation terminated after 1.603135616s
I0216 19:34:48.922379   26863 downloader.go:263] Removing peer 05a87dcaf7a5014b: action from bad peer ignored
I0216 19:34:48.922406   26863 downloader.go:240] Unregistering peer 05a87dcaf7a5014b