devicehive-java-server icon indicating copy to clipboard operation
devicehive-java-server copied to clipboard
verified publisher icon devicehive

some commands are dropped during polling

Open Pilatuz opened this issue 10 years ago • 1 comments

Go test should be used to reproduce:

cd $GOPATH/src/github.com/devicehive/devicehive-go/devicehive
go test -run TestBatchCommandInsert --access-key "<put access key here>" --rest-url "<REST URL>" --ws-url "<WS URL>" --network-name "<name>" --network-key "" --network-desc "<description>" --gap 40 --batch-len 100 --log-level INF

This test sends N commands and expects all of them are received. For now some commands are dropped by the server:

2015/12/17 02:10:23.565567 INFO TEST/RX: started
2015/12/17 02:10:25.565934 INFO TEST/TX: started
2015/12/17 02:10:25.616216 INFO TEST/TX: Command{Id:1814822879, Name:"batch-command", Timestamp:"2015-12-17T10:10:38.947", UserId:1, Parameters:0}
2015/12/17 02:10:25.617079 INFO TEST/RX: Command{Id:1814822879, Name:"batch-command", Timestamp:"2015-12-17T10:10:38.947", UserId:1, Parameters:0}
2015/12/17 02:10:25.772227 INFO TEST/TX: Command{Id:1697873645, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.099", UserId:1, Parameters:1}
2015/12/17 02:10:25.783600 INFO TEST/RX: Command{Id:1697873645, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.099", UserId:1, Parameters:1}
2015/12/17 02:10:25.864478 INFO TEST/TX: Command{Id:1329085480, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.197", UserId:1, Parameters:2}
2015/12/17 02:10:25.938401 INFO TEST/TX: Command{Id:565706513, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.268", UserId:1, Parameters:3}
2015/12/17 02:10:25.942544 INFO TEST/RX: Command{Id:565706513, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.268", UserId:1, Parameters:3}
2015/12/17 02:10:26.043926 INFO TEST/TX: Command{Id:805008530, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.376", UserId:1, Parameters:4}
2015/12/17 02:10:26.066034 INFO TEST/RX: Command{Id:805008530, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.376", UserId:1, Parameters:4}
2015/12/17 02:10:26.129230 INFO TEST/TX: Command{Id:1881569190, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.459", UserId:1, Parameters:5}
2015/12/17 02:10:26.225702 INFO TEST/TX: Command{Id:527505636, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.557", UserId:1, Parameters:6}
2015/12/17 02:10:26.234270 INFO TEST/RX: Command{Id:527505636, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.557", UserId:1, Parameters:6}
2015/12/17 02:10:26.351578 INFO TEST/TX: Command{Id:1583780267, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.684", UserId:1, Parameters:7}
2015/12/17 02:10:26.359823 INFO TEST/RX: Command{Id:1583780267, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.684", UserId:1, Parameters:7}
2015/12/17 02:10:26.534313 INFO TEST/TX: Command{Id:384797712, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.858", UserId:1, Parameters:8}
2015/12/17 02:10:26.571218 INFO TEST/RX: Command{Id:384797712, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.858", UserId:1, Parameters:8}
2015/12/17 02:10:26.630649 INFO TEST/TX: Command{Id:69558184, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.944", UserId:1, Parameters:9}
2015/12/17 02:10:26.640417 INFO TEST/RX: Command{Id:69558184, Name:"batch-command", Timestamp:"2015-12-17T10:10:39.944", UserId:1, Parameters:9}
2015/12/17 02:10:26.762103 INFO TEST/TX: Command{Id:1804852595, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.094", UserId:1, Parameters:10}
2015/12/17 02:10:26.765038 INFO TEST/RX: Command{Id:1804852595, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.094", UserId:1, Parameters:10}
2015/12/17 02:10:26.844080 INFO TEST/TX: Command{Id:1451914790, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.176", UserId:1, Parameters:11}
2015/12/17 02:10:26.919855 INFO TEST/TX: Command{Id:221883278, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.250", UserId:1, Parameters:12}
2015/12/17 02:10:26.922500 INFO TEST/RX: Command{Id:221883278, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.250", UserId:1, Parameters:12}
2015/12/17 02:10:26.996873 INFO TEST/TX: Command{Id:905470283, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.328", UserId:1, Parameters:13}
2015/12/17 02:10:27.006535 INFO TEST/RX: Command{Id:905470283, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.328", UserId:1, Parameters:13}
2015/12/17 02:10:27.085366 INFO TEST/TX: Command{Id:1410251817, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.417", UserId:1, Parameters:14}
2015/12/17 02:10:27.099672 INFO TEST/RX: Command{Id:1410251817, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.417", UserId:1, Parameters:14}
2015/12/17 02:10:27.174288 INFO TEST/TX: Command{Id:1630794457, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.506", UserId:1, Parameters:15}
2015/12/17 02:10:27.179883 INFO TEST/RX: Command{Id:1630794457, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.506", UserId:1, Parameters:15}
2015/12/17 02:10:27.307170 INFO TEST/TX: Command{Id:536756510, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.639", UserId:1, Parameters:16}
2015/12/17 02:10:27.311881 INFO TEST/RX: Command{Id:536756510, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.639", UserId:1, Parameters:16}
2015/12/17 02:10:27.401007 INFO TEST/TX: Command{Id:505795442, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.733", UserId:1, Parameters:17}
2015/12/17 02:10:27.508563 INFO TEST/TX: Command{Id:972913571, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.841", UserId:1, Parameters:18}
2015/12/17 02:10:27.523967 INFO TEST/RX: Command{Id:972913571, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.841", UserId:1, Parameters:18}
2015/12/17 02:10:27.582332 INFO TEST/TX: Command{Id:882512581, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.914", UserId:1, Parameters:19}
2015/12/17 02:10:27.630902 INFO TEST/RX: Command{Id:882512581, Name:"batch-command", Timestamp:"2015-12-17T10:10:40.914", UserId:1, Parameters:19}
2015/12/17 02:10:27.721521 INFO TEST/TX: Command{Id:365571898, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.048", UserId:1, Parameters:20}
2015/12/17 02:10:27.834980 INFO TEST/TX: Command{Id:865163330, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.163", UserId:1, Parameters:21}
2015/12/17 02:10:27.850891 INFO TEST/RX: Command{Id:865163330, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.163", UserId:1, Parameters:21}
2015/12/17 02:10:27.962289 INFO TEST/TX: Command{Id:651281915, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.291", UserId:1, Parameters:22}
2015/12/17 02:10:27.987953 INFO TEST/RX: Command{Id:651281915, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.291", UserId:1, Parameters:22}
2015/12/17 02:10:28.034938 INFO TEST/TX: Command{Id:377594375, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.363", UserId:1, Parameters:23}
2015/12/17 02:10:28.035015 INFO TEST/RX: Command{Id:377594375, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.363", UserId:1, Parameters:23}
2015/12/17 02:10:28.111252 INFO TEST/TX: Command{Id:966177887, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.443", UserId:1, Parameters:24}
2015/12/17 02:10:28.121304 INFO TEST/RX: Command{Id:966177887, Name:"batch-command", Timestamp:"2015-12-17T10:10:41.443", UserId:1, Parameters:24}
2015/12/17 02:10:28.151688 INFO TEST/TX: stopped
2015/12/17 02:10:58.122019 INFO TEST/RX: stopped
--- FAIL: TestBatchCommandInsert (34.66s)
    service_test.go:439: failed to wait command: timed out
    service_test.go:453: TX:25 != RX:20 commands length mismatch
FAIL

Pilatuz avatar Dec 17 '15 10:12 Pilatuz

@Pilatuz it might be related to the https://github.com/devicehive/devicehive-java-server/issues/147

zubrabubra avatar Dec 17 '15 10:12 zubrabubra