[cf-dev] Build failed in Jenkins: 2.0.x #679

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] Build failed in Jenkins: 2.0.x #679
From: hudsonbuild@xxxxxxxxxxx
Date: Fri, 8 Mar 2019 05:02:46 -0500 (EST)
Delivered-to: cf-dev@xxxxxxxxxxx
List-archive: <https://www.eclipse.org/mailman/private/cf-dev>
List-help: <mailto:cf-dev-request@eclipse.org?subject=help>
List-subscribe: <https://www.eclipse.org/mailman/listinfo/cf-dev>, <mailto:cf-dev-request@eclipse.org?subject=subscribe>
List-unsubscribe: <https://www.eclipse.org/mailman/options/cf-dev>, <mailto:cf-dev-request@eclipse.org?subject=unsubscribe>
See <https://ci.eclipse.org/californium/job/2.0.x/679/display/redirect?page=changes>

Changes:

[Achim Kraus] Cleanup Record.

------------------------------------------
[...truncated 403.70 KB...]

Cancel observation 6BF73AA687E35850
Wait until deduplicator should be empty (2.97 seconds)
Wait until deduplicator should be empty (2.97 seconds)


Client binds to port 8269
Proactive cancel
Wait until deduplicator should be empty (2.97 seconds)
Wait until deduplicator should be empty (2.97 seconds)

0004: CON [MID=63914, T=53EA16E3ED62E38F], GET, /test, observe(0)    ----->
0005: <-----   ACK [MID=63914, T=53EA16E3ED62E38F], 2.05, observe(1)
0006: <-----   CON [MID=8001, T=53EA16E3ED62E38F], 2.05, observe(3)
0006: ACK [MID=8001]   ----->
0007: CON [MID=63915, T=53EA16E3ED62E38F], GET, /test, observe(1)    ----->
0008: <-----   ACK [MID=63915, T=53EA16E3ED62E38F], 2.05
0008: <-----   CON [MID=8002, T=53EA16E3ED62E38F], 2.05, observe(4)
0009: RST [MID=8002]   ----->
0009: <-----   NON [MID=8003, T=53EA16E3ED62E38F], 2.05, observe(5)
0009: RST [MID=8003]   ----->

End ObserveClientSideTest
[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.032 s - in org.eclipse.californium.core.test.lockstep.ObserveClientSideTest
[INFO] Running org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest

StartLossyBlockwiseTransferTest
Starting man in the middle...
client at localhost:8271, middle at 127.0.0.1:8273, server at localhost:8272


doing a blockwise GET on: coap://localhost:8273/test
Received 250 bytes after 2762 ms
Man in the middle will drop packets [5, 6, 8, 9, 15]
0011: CON [MID=5825, T=6C64DF67CCE2C149], GET, /test    ----->
0013: <-----   ACK [MID=5825, T=6C64DF67CCE2C149], 2.05, 2:0/1/32, size2(250)
0013: CON [MID=5826, T=6C64DF67CCE2C149], GET, /test, 2:1/0/32    ----->
0015: <-----   ACK [MID=5826, T=6C64DF67CCE2C149], 2.05, 2:1/1/32
0016: CON [MID=5827, T=6C64DF67CCE2C149], GET, /test, 2:2/0/32    -----> Dropping packet 5 (burst 1/4) from server
0316: CON [MID=5827, T=6C64DF67CCE2C149], GET, /test, 2:2/0/32    -----> Dropping packet 6 (burst 2/4) from client
0767: CON [MID=5827, T=6C64DF67CCE2C149], GET, /test, 2:2/0/32    -----> Dropping packet 8 (burst 3/4) from server
1443: CON [MID=5827, T=6C64DF67CCE2C149], GET, /test, 2:2/0/32    -----> Dropping packet 9 (burst 4/4) from client
2456: CON [MID=5827, T=6C64DF67CCE2C149], GET, /test, 2:2/0/32    ----->
2458: <-----   ACK [MID=5827, T=6C64DF67CCE2C149], 2.05, 2:2/1/32
2459: CON [MID=5828, T=6C64DF67CCE2C149], GET, /test, 2:3/0/32    ----->
2460: <-----   ACK [MID=5828, T=6C64DF67CCE2C149], 2.05, 2:3/1/32
2461: CON [MID=5829, T=6C64DF67CCE2C149], GET, /test, 2:4/0/32    -----> Dropping packet 15 (burst 1/4) from server
2761: CON [MID=5829, T=6C64DF67CCE2C149], GET, /test, 2:4/0/32    ----->
2763: <-----   ACK [MID=5829, T=6C64DF67CCE2C149], 2.05, 2:4/1/32
2764: CON [MID=5830, T=6C64DF67CCE2C149], GET, /test, 2:5/0/32    ----->
2765: <-----   ACK [MID=5830, T=6C64DF67CCE2C149], 2.05, 2:5/1/32
2766: CON [MID=5831, T=6C64DF67CCE2C149], GET, /test, 2:6/0/32    ----->
2768: <-----   ACK [MID=5831, T=6C64DF67CCE2C149], 2.05, 2:6/1/32
2768: CON [MID=5832, T=6C64DF67CCE2C149], GET, /test, 2:7/0/32    ----->
2769: <-----   ACK [MID=5832, T=6C64DF67CCE2C149], 2.05, 2:7/0/32

doing a blockwise GET on: coap://localhost:8273/test
Received 250 bytes after 2269 ms
Man in the middle will drop packets [1, 3, 3, 7, 7, 8, 14, 14, 15, 15]
2771: CON [MID=5833, T=D86B5F513594A6F9], GET, /test    -----> Dropping packet 1 (burst 1/4) from server
3072: CON [MID=5833, T=D86B5F513594A6F9], GET, /test    -----> Dropping packet 3 (burst 2/4) from server
3523: CON [MID=5833, T=D86B5F513594A6F9], GET, /test    ----->
3524: <-----   ACK [MID=5833, T=D86B5F513594A6F9], 2.05, 2:0/1/32, size2(250)
3525: CON [MID=5834, T=D86B5F513594A6F9], GET, /test, 2:1/0/32    -----> Dropping packet 7 (burst 1/4) from server
3825: CON [MID=5834, T=D86B5F513594A6F9], GET, /test, 2:1/0/32    -----> Dropping packet 8 (burst 2/4) from client
4276: CON [MID=5834, T=D86B5F513594A6F9], GET, /test, 2:1/0/32    ----->
4277: <-----   ACK [MID=5834, T=D86B5F513594A6F9], 2.05, 2:1/1/32
4278: CON [MID=5835, T=D86B5F513594A6F9], GET, /test, 2:2/0/32    ----->
4279: <-----   ACK [MID=5835, T=D86B5F513594A6F9], 2.05, 2:2/1/32
4279: CON [MID=5836, T=D86B5F513594A6F9], GET, /test, 2:3/0/32    -----> Dropping packet 14 (burst 1/4) from server
4580: CON [MID=5836, T=D86B5F513594A6F9], GET, /test, 2:3/0/32    -----> Dropping packet 15 (burst 2/4) from client
5031: CON [MID=5836, T=D86B5F513594A6F9], GET, /test, 2:3/0/32    ----->
5032: <-----   ACK [MID=5836, T=D86B5F513594A6F9], 2.05, 2:3/1/32
5033: CON [MID=5837, T=D86B5F513594A6F9], GET, /test, 2:4/0/32    ----->
5034: <-----   ACK [MID=5837, T=D86B5F513594A6F9], 2.05, 2:4/1/32
5034: CON [MID=5838, T=D86B5F513594A6F9], GET, /test, 2:5/0/32    ----->
5036: <-----   ACK [MID=5838, T=D86B5F513594A6F9], 2.05, 2:5/1/32
5036: CON [MID=5839, T=D86B5F513594A6F9], GET, /test, 2:6/0/32    ----->
5037: <-----   ACK [MID=5839, T=D86B5F513594A6F9], 2.05, 2:6/1/32
5038: CON [MID=5840, T=D86B5F513594A6F9], GET, /test, 2:7/0/32    ----->
5039: <-----   ACK [MID=5840, T=D86B5F513594A6F9], 2.05, 2:7/0/32

doing a blockwise GET on: coap://localhost:8273/test
Received 250 bytes after 3054 ms
Man in the middle will drop packets [1, 3, 3, 4, 4, 6, 7, 12, 15, 15]
5040: CON [MID=5841, T=86432D77B26DE081], GET, /test    -----> Dropping packet 1 (burst 1/4) from server
5341: CON [MID=5841, T=86432D77B26DE081], GET, /test    -----> Dropping packet 3 (burst 2/4) from server
5791: CON [MID=5841, T=86432D77B26DE081], GET, /test    -----> Dropping packet 4 (burst 3/4) from client
6467: CON [MID=5841, T=86432D77B26DE081], GET, /test    -----> Dropping packet 6 (burst 4/4) from server
7480: CON [MID=5841, T=86432D77B26DE081], GET, /test    ----->
7481: <-----   ACK [MID=5841, T=86432D77B26DE081], 2.05, 2:0/1/32, size2(250)
7481: CON [MID=5842, T=86432D77B26DE081], GET, /test, 2:1/0/32    ----->
7482: <-----   ACK [MID=5842, T=86432D77B26DE081], 2.05, 2:1/1/32
7483: CON [MID=5843, T=86432D77B26DE081], GET, /test, 2:2/0/32    -----> Dropping packet 12 (burst 1/4) from server
7783: CON [MID=5843, T=86432D77B26DE081], GET, /test, 2:2/0/32    ----->
7784: <-----   ACK [MID=5843, T=86432D77B26DE081], 2.05, 2:2/1/32
7785: CON [MID=5844, T=86432D77B26DE081], GET, /test, 2:3/0/32    -----> Dropping packet 15 (burst 1/4) from client
8085: CON [MID=5844, T=86432D77B26DE081], GET, /test, 2:3/0/32    ----->
8087: <-----   ACK [MID=5844, T=86432D77B26DE081], 2.05, 2:3/1/32
8087: CON [MID=5845, T=86432D77B26DE081], GET, /test, 2:4/0/32    ----->
8088: <-----   ACK [MID=5845, T=86432D77B26DE081], 2.05, 2:4/1/32
8089: CON [MID=5846, T=86432D77B26DE081], GET, /test, 2:5/0/32    ----->
8090: <-----   ACK [MID=5846, T=86432D77B26DE081], 2.05, 2:5/1/32
8090: CON [MID=5847, T=86432D77B26DE081], GET, /test, 2:6/0/32    ----->
8091: <-----   ACK [MID=5847, T=86432D77B26DE081], 2.05, 2:6/1/32
8092: CON [MID=5848, T=86432D77B26DE081], GET, /test, 2:7/0/32    ----->
8093: <-----   ACK [MID=5848, T=86432D77B26DE081], 2.05, 2:7/0/32

doing a blockwise GET on: coap://localhost:8273/test
Received 250 bytes after 2753 ms
Man in the middle will drop packets [1, 6, 8, 8, 10, 10, 10, 11, 13, 13]
8094: CON [MID=5849, T=34C4A21902E0975C], GET, /test    -----> Dropping packet 1 (burst 1/4) from server
8394: CON [MID=5849, T=34C4A21902E0975C], GET, /test    ----->
8396: <-----   ACK [MID=5849, T=34C4A21902E0975C], 2.05, 2:0/1/32, size2(250)
8396: CON [MID=5850, T=34C4A21902E0975C], GET, /test, 2:1/0/32    ----->
8397: <-----   ACK [MID=5850, T=34C4A21902E0975C], 2.05, 2:1/1/32
8398: CON [MID=5851, T=34C4A21902E0975C], GET, /test, 2:2/0/32    -----> Dropping packet 6 (burst 1/4) from client
8698: CON [MID=5851, T=34C4A21902E0975C], GET, /test, 2:2/0/32    -----> Dropping packet 8 (burst 2/4) from server
9149: CON [MID=5851, T=34C4A21902E0975C], GET, /test, 2:2/0/32    -----> Dropping packet 10 (burst 3/4) from server
9825: CON [MID=5851, T=34C4A21902E0975C], GET, /test, 2:2/0/32    -----> Dropping packet 11 (burst 4/4) from client
10837: CON [MID=5851, T=34C4A21902E0975C], GET, /test, 2:2/0/32    ----->
10839: <-----   ACK [MID=5851, T=34C4A21902E0975C], 2.05, 2:2/1/32
10839: CON [MID=5852, T=34C4A21902E0975C], GET, /test, 2:3/0/32    ----->
10840: <-----   ACK [MID=5852, T=34C4A21902E0975C], 2.05, 2:3/1/32
10841: CON [MID=5853, T=34C4A21902E0975C], GET, /test, 2:4/0/32    ----->
10842: <-----   ACK [MID=5853, T=34C4A21902E0975C], 2.05, 2:4/1/32
10842: CON [MID=5854, T=34C4A21902E0975C], GET, /test, 2:5/0/32    ----->
10843: <-----   ACK [MID=5854, T=34C4A21902E0975C], 2.05, 2:5/1/32
10843: CON [MID=5855, T=34C4A21902E0975C], GET, /test, 2:6/0/32    ----->
10844: <-----   ACK [MID=5855, T=34C4A21902E0975C], 2.05, 2:6/1/32
10845: CON [MID=5856, T=34C4A21902E0975C], GET, /test, 2:7/0/32    ----->
10846: <-----   ACK [MID=5856, T=34C4A21902E0975C], 2.05, 2:7/0/32

doing a blockwise GET on: coap://localhost:8273/test
Received 250 bytes after 4896 ms
Man in the middle will drop packets [0, 2, 3, 5, 8, 10, 11, 13, 13, 14]
10847: CON [MID=5857, T=B29388EB90441F93], GET, /test    -----> Dropping packet 0 (burst 1/4) from client
11148: CON [MID=5857, T=B29388EB90441F93], GET, /test    -----> Dropping packet 2 (burst 2/4) from server
11599: CON [MID=5857, T=B29388EB90441F93], GET, /test    -----> Dropping packet 3 (burst 3/4) from client
12274: CON [MID=5857, T=B29388EB90441F93], GET, /test    -----> Dropping packet 5 (burst 4/4) from server
13287: CON [MID=5857, T=B29388EB90441F93], GET, /test    ----->
13288: <-----   ACK [MID=5857, T=B29388EB90441F93], 2.05, 2:0/1/32, size2(250)
13288: CON [MID=5858, T=B29388EB90441F93], GET, /test, 2:1/0/32    ----->
13289: <-----   ACK [MID=5858, T=B29388EB90441F93], 2.05, 2:1/1/32
13290: CON [MID=5859, T=B29388EB90441F93], GET, /test, 2:2/0/32    -----> Dropping packet 10 (burst 1/4) from client
13590: CON [MID=5859, T=B29388EB90441F93], GET, /test, 2:2/0/32    -----> Dropping packet 11 (burst 2/4) from client
14041: CON [MID=5859, T=B29388EB90441F93], GET, /test, 2:2/0/32    -----> Dropping packet 13 (burst 3/4) from server
14717: CON [MID=5859, T=B29388EB90441F93], GET, /test, 2:2/0/32    -----> Dropping packet 14 (burst 4/4) from client
15730: CON [MID=5859, T=B29388EB90441F93], GET, /test, 2:2/0/32    ----->
15731: <-----   ACK [MID=5859, T=B29388EB90441F93], 2.05, 2:2/1/32
15731: CON [MID=5860, T=B29388EB90441F93], GET, /test, 2:3/0/32    ----->
15733: <-----   ACK [MID=5860, T=B29388EB90441F93], 2.05, 2:3/1/32
15733: CON [MID=5861, T=B29388EB90441F93], GET, /test, 2:4/0/32    ----->
15735: <-----   ACK [MID=5861, T=B29388EB90441F93], 2.05, 2:4/1/32
15735: CON [MID=5862, T=B29388EB90441F93], GET, /test, 2:5/0/32    ----->
15739: <-----   ACK [MID=5862, T=B29388EB90441F93], 2.05, 2:5/1/32
15739: CON [MID=5863, T=B29388EB90441F93], GET, /test, 2:6/0/32    ----->
15740: <-----   ACK [MID=5863, T=B29388EB90441F93], 2.05, 2:6/1/32
15741: CON [MID=5864, T=B29388EB90441F93], GET, /test, 2:7/0/32    ----->
15742: <-----   ACK [MID=5864, T=B29388EB90441F93], 2.05, 2:7/0/32

doing a blockwise GET on: coap://localhost:8273/test
Received 250 bytes after 3513 ms

End LossyBlockwiseTransferTestMan in the middle will drop packets [1, 4, 5, 7, 8, 9, 10, 11, 12, 13]
15743: CON [MID=5865, T=2A06BBB4FC1413BC], GET, /test    -----> Dropping packet 1 (burst 1/4) from server
16050: CON [MID=5865, T=2A06BBB4FC1413BC], GET, /test    ----->
16052: <-----   ACK [MID=5865, T=2A06BBB4FC1413BC], 2.05, 2:0/1/32, size2(250)
16052: CON [MID=5866, T=2A06BBB4FC1413BC], GET, /test, 2:1/0/32    -----> Dropping packet 4 (burst 1/4) from client
16353: CON [MID=5866, T=2A06BBB4FC1413BC], GET, /test, 2:1/0/32    -----> Dropping packet 5 (burst 2/4) from client
16803: CON [MID=5866, T=2A06BBB4FC1413BC], GET, /test, 2:1/0/32    -----> Dropping packet 7 (burst 3/4) from server
17479: CON [MID=5866, T=2A06BBB4FC1413BC], GET, /test, 2:1/0/32    -----> Dropping packet 8 (burst 4/4) from client
18492: CON [MID=5866, T=2A06BBB4FC1413BC], GET, /test, 2:1/0/32    ----->
18493: <-----   ACK [MID=5866, T=2A06BBB4FC1413BC], 2.05, 2:1/1/32
18494: CON [MID=5867, T=2A06BBB4FC1413BC], GET, /test, 2:2/0/32    -----> Dropping packet 12 (burst 1/4) from server
18795: CON [MID=5867, T=2A06BBB4FC1413BC], GET, /test, 2:2/0/32    -----> Dropping packet 13 (burst 2/4) from client
19246: CON [MID=5867, T=2A06BBB4FC1413BC], GET, /test, 2:2/0/32    ----->
19247: <-----   ACK [MID=5867, T=2A06BBB4FC1413BC], 2.05, 2:2/1/32
19248: CON [MID=5868, T=2A06BBB4FC1413BC], GET, /test, 2:3/0/32    ----->
19249: <-----   ACK [MID=5868, T=2A06BBB4FC1413BC], 2.05, 2:3/1/32
19249: CON [MID=5869, T=2A06BBB4FC1413BC], GET, /test, 2:4/0/32    ----->
19250: <-----   ACK [MID=5869, T=2A06BBB4FC1413BC], 2.05, 2:4/1/32
19251: CON [MID=5870, T=2A06BBB4FC1413BC], GET, /test, 2:5/0/32    ----->
19252: <-----   ACK [MID=5870, T=2A06BBB4FC1413BC], 2.05, 2:5/1/32
19253: CON [MID=5871, T=2A06BBB4FC1413BC], GET, /test, 2:6/0/32    ----->
19254: <-----   ACK [MID=5871, T=2A06BBB4FC1413BC], 2.05, 2:6/1/32
19254: CON [MID=5872, T=2A06BBB4FC1413BC], GET, /test, 2:7/0/32    ----->
19255: <-----   ACK [MID=5872, T=2A06BBB4FC1413BC], 2.05, 2:7/0/32
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 19.048 s - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
[INFO] 
[INFO] Results:
[INFO] 
[ERROR] Failures: 
[ERROR]   BlockwiseServerSideTest.testSimpleAtomicBlockwisePUTWithRestartOfTransfer:553 Wrong payload length:  expected:<0> but was:<18>
[INFO] 
[ERROR] Tests run: 39, Failures: 1, Errors: 0, Skipped: 0
[INFO] 
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent 2.0.0-SNAPSHOT ............. SUCCESS [  6.137 s]
[INFO] Legal Documents .................................... SUCCESS [  7.791 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  0.412 s]
[INFO] element-connector .................................. SUCCESS [01:22 min]
[INFO] Scandium (Sc) Core ................................. SUCCESS [02:05 min]
[INFO] element-connector-tcp .............................. SUCCESS [ 49.587 s]
[INFO] Californium (Cf) Core .............................. FAILURE [02:22 min]
[INFO] Californium (Cf) Demo Applications ................. SKIPPED
[INFO] Cf-NAT ............................................. SKIPPED
[INFO] Californium (Cf) Integration Tests ................. SKIPPED
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi .............................. SKIPPED
[INFO] Sc-DTLS-Example-Server ............................. SKIPPED
[INFO] Sc-DTLS-Example-Client ............................. SKIPPED
[INFO] Cf-PlugtestClient .................................. SKIPPED
[INFO] Cf-PlugtestChecker ................................. SKIPPED
[INFO] Cf-PlugtestServer .................................. SKIPPED
[INFO] Cf-ExtendedPlugtestClient .......................... SKIPPED
[INFO] Cf-ExtendedPlugtestServer .......................... SKIPPED
[INFO] Cf-HelloWorldClient ................................ SKIPPED
[INFO] Cf-HelloWorldServer ................................ SKIPPED
[INFO] Cf-SimpleFileServer ................................ SKIPPED
[INFO] Cf-CoCoAClient ..................................... SKIPPED
[INFO] Cf-SecureServer .................................... SKIPPED
[INFO] Cf-ExampleCrossProxy ............................... SKIPPED
[INFO] Cf-BenchmarkServer ................................. SKIPPED
[INFO] Cf-ObserveBenchmarkClient .......................... SKIPPED
[INFO] Cf-OSCORE 2.0.0-SNAPSHOT ........................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 07:04 min
[INFO] Finished at: 2019-03-08T05:02:45-05:00
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.22.1:test (large-tests) on project californium-core: There are test failures.
[ERROR] 
[ERROR] Please refer to /jobs/genie.californium/2.0.x/workspace/californium-core/target/surefire-reports for the individual test results.
[ERROR] Please refer to dump files (if any exist) [date].dump, [date]-jvmRun[N].dump and [date].dumpstream.
[ERROR] -> [Help 1]
[ERROR] 
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR] 
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR] 
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR]   mvn <goals> -rf :californium-core
Build step 'Invoke top-level Maven targets' marked build as failure
Follow-Ups:
- [cf-dev] Build failed in Jenkins: 2.0.x #680
  - From: hudsonbuild
Prev by Date: [cf-dev] Jenkins build is back to normal : 2.0.x #676
Next by Date: [cf-dev] Build failed in Jenkins: 2.0.x #680
Previous by thread: [cf-dev] Build failed in Jenkins: 2.0.x #672
Next by thread: [cf-dev] Build failed in Jenkins: 2.0.x #680
Index(es):
- Date
- Thread
Breadcrumbs
