Skip to main content

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] Build failed in Jenkins: 2.0.x #707

See <https://ci.eclipse.org/californium/job/2.0.x/707/display/redirect?page=changes>

Changes:

[Achim Kraus] Fix rare test-race condition.

------------------------------------------
[...truncated 336.77 KB...]
4304: <-----   ACK [MID=18056, T=B46A0565DEF34915], 2.05, 2:0/1/32, size2(152)
4305: CON [MID=18057, T=B46A0565DEF34915], GET, /test, 2:1/0/32    ----->
4305: <-----   ACK [MID=18057, T=B46A0565DEF34915], 2.05, 2:1/1/32
4307: CON [MID=18058, T=B46A0565DEF34915], GET, /test, 2:2/0/32    ----->
4307: <-----   ACK [MID=18058, T=B46A0565DEF34915], 2.05, 2:2/1/32
4308: CON [MID=18059, T=B46A0565DEF34915], GET, /test, 2:3/0/32    ----->
4308: <-----   ACK [MID=18059, T=B46A0565DEF34915], 2.05, 2:3/1/32
4309: CON [MID=18060, T=B46A0565DEF34915], GET, /test, 2:4/0/32    ----->
4310: <-----   ACK [MID=18060, T=B46A0565DEF34915], 2.05, 2:4/0/32

Server received GET request
Client received payload [81670246200157991671860046039108413725646455811507582080094875159995869955136699327279497751158304188586016539144118009545245099742293553181373971845009]

4412: CON [MID=18061, T=927328B14034A6FC], GET, /test    ----->
4412: <-----   ACK [MID=18061, T=927328B14034A6FC], 2.05, 2:0/1/32, size2(152)
4413: CON [MID=18062, T=927328B14034A6FC], GET, /test, 2:1/0/32    ----->
4413: <-----   ACK [MID=18062, T=927328B14034A6FC], 2.05, 2:1/1/32
4415: CON [MID=18063, T=927328B14034A6FC], GET, /test, 2:2/0/32    ----->
4415: <-----   ACK [MID=18063, T=927328B14034A6FC], 2.05, 2:2/1/32
4416: CON [MID=18064, T=927328B14034A6FC], GET, /test, 2:3/0/32    ----->
4416: <-----   ACK [MID=18064, T=927328B14034A6FC], 2.05, 2:3/1/32
4417: CON [MID=18065, T=927328B14034A6FC], GET, /test, 2:4/0/32    ----->
4418: <-----   ACK [MID=18065, T=927328B14034A6FC], 2.05, 2:4/0/32

-- POST long short --
Server received 471703485238477993563657931880881755108182382448612989526952245673800145136808595200269241834148937267509901613137852871905444931081382484806754362137
Client received payload [6434427055006388]

4523: CON [MID=22858, T=660E93C48D70E354], POST, /test, 1:0/1/32, size1(150)    ----->
4523: <-----   ACK [MID=22858, T=660E93C48D70E354], 2.31, 1:0/1/32
4525: CON [MID=22859, T=660E93C48D70E354], POST, /test, 1:1/1/32    ----->
4525: <-----   ACK [MID=22859, T=660E93C48D70E354], 2.31, 1:1/1/32
4526: CON [MID=22860, T=660E93C48D70E354], POST, /test, 1:2/1/32    ----->
4527: <-----   ACK [MID=22860, T=660E93C48D70E354], 2.31, 1:2/1/32
4528: CON [MID=22861, T=660E93C48D70E354], POST, /test, 1:3/1/32    ----->
4528: <-----   ACK [MID=22861, T=660E93C48D70E354], 2.31, 1:3/1/32
4529: CON [MID=22862, T=660E93C48D70E354], POST, /test, 1:4/0/32    ----->
4530: <-----   ACK [MID=22862, T=660E93C48D70E354], 2.05, 1:4/0/32

End BlockwiseTransferTest
[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.257 s - in org.eclipse.californium.core.test.BlockwiseTransferTest
[INFO] Running org.eclipse.californium.core.test.MemoryLeakingHashMapTest
Wait until deduplicator should be empty (0.647 seconds)

0006: NON [MID=26356, T=A49863DCF589B086], POST, /test, 1:0/1/16, size1(20)    ----->
0008: <-----   NON [MID=47416, T=A49863DCF589B086], 2.31, 1:0/1/16
0008: NON [MID=26357, T=A49863DCF589B086], POST, /test, 1:1/0/16    ----->
0010: <-----   NON [MID=47417, T=A49863DCF589B086], 2.01, 1:1/0/16, 2:0/1/16, size2(40)
0011: NON [MID=26358, T=A49863DCF589B086], POST, /test, 2:1/0/16    ----->
0012: <-----   NON [MID=47418, T=A49863DCF589B086], 2.01, 2:1/1/16
0013: NON [MID=26359, T=A49863DCF589B086], POST, /test, 2:2/0/16    ----->
0014: <-----   NON [MID=47419, T=A49863DCF589B086], 2.01, 2:2/0/16
Test observe relation with a reactive cancelation to coap://127.0.0.1:8320/test
Wait until deduplicator should be empty (0.647 seconds)

0147: CON [MID=26360, T=D7C1302CFA16304F], GET, /test, observe(0)    ----->
0154: <-----   ACK [MID=26360, T=D7C1302CFA16304F], 2.05, observe(0)
0347: <-----   NON [MID=47420, T=D7C1302CFA16304F], 2.05, observe(1)
0547: <-----   NON [MID=47421, T=D7C1302CFA16304F], 2.05, observe(2)
0748: <-----   NON [MID=47422, T=D7C1302CFA16304F], 2.05, observe(3)
0748: RST [MID=47422]   ----->
Wait until deduplicator should be empty (0.647 seconds)

0806: CON [MID=26361, T=B8A618A3E32977CD], POST, /test, 1:0/1/16, size1(20)    ----->
0808: <-----   ACK [MID=26361, T=B8A618A3E32977CD], 2.31, 1:0/1/16
0808: CON [MID=26362, T=B8A618A3E32977CD], POST, /test, 1:1/0/16    ----->
0810: <-----   ACK [MID=26362, T=B8A618A3E32977CD], 2.01, 1:1/0/16, 2:0/1/16, size2(40)
0810: CON [MID=26363, T=B8A618A3E32977CD], POST, /test, 2:1/0/16    ----->
0812: <-----   ACK [MID=26363, T=B8A618A3E32977CD], 2.01, 2:1/1/16
0812: CON [MID=26364, T=B8A618A3E32977CD], POST, /test, 2:2/0/16    ----->
0813: <-----   ACK [MID=26364, T=B8A618A3E32977CD], 2.01, 2:2/0/16
Wait until deduplicator should be empty (0.647 seconds)

0943: CON [MID=26365, T=64E215C8B4619C57], GET, /test    ----->
0944: <-----   ACK [MID=26365]
0945: <-----   CON [MID=47423, T=64E215C8B4619C57], 2.05
0945: ACK [MID=47423]   ----->
Wait until deduplicator should be empty (0.647 seconds)

1075: NON [MID=26366, T=4667303937C70E3C], GET, /test    ----->
1076: <-----   NON [MID=47424, T=4667303937C70E3C], 2.05
Wait until deduplicator should be empty (0.647 seconds)

1206: CON [MID=26367, T=8F8FFE25DEBC96C1], GET, /test, observe(0)    ----->
1208: <-----   ACK [MID=26367, T=8F8FFE25DEBC96C1], 2.05, 2:0/1/16, observe(3), size2(40)
1208: CON [MID=26368, T=2CAF1FCF85350B8A], GET, /test, 2:1/0/16    ----->
1209: <-----   ACK [MID=26368, T=2CAF1FCF85350B8A], 2.05, 2:1/1/16
1209: CON [MID=26369, T=2CAF1FCF85350B8A], GET, /test, 2:2/0/16    ----->
1210: <-----   ACK [MID=26369, T=2CAF1FCF85350B8A], 2.05, 2:2/0/16
1806: <-----   NON [MID=47425, T=8F8FFE25DEBC96C1], 2.05, 2:0/1/16, observe(4), size2(40)
1807: CON [MID=26370, T=B0ECD9DB01ECCD7E], GET, /test, 2:1/0/16    ----->
1808: <-----   ACK [MID=26370, T=B0ECD9DB01ECCD7E], 2.05, 2:1/1/16
1808: CON [MID=26371, T=B0ECD9DB01ECCD7E], GET, /test, 2:2/0/16    ----->
1810: <-----   ACK [MID=26371, T=B0ECD9DB01ECCD7E], 2.05, 2:2/0/16
2407: <-----   NON [MID=47426, T=8F8FFE25DEBC96C1], 2.05, 2:0/1/16, observe(5), size2(40)
2407: CON [MID=26372, T=543CEE44DB7435AD], GET, /test, 2:1/0/16    ----->
2408: <-----   ACK [MID=26372, T=543CEE44DB7435AD], 2.05, 2:1/1/16
2409: CON [MID=26373, T=543CEE44DB7435AD], GET, /test, 2:2/0/16    ----->
2410: <-----   ACK [MID=26373, T=543CEE44DB7435AD], 2.05, 2:2/0/16
2411: CON [MID=26374, T=8F8FFE25DEBC96C1], GET, /test, observe(1)    ----->
2412: <-----   ACK [MID=26374, T=8F8FFE25DEBC96C1], 2.05, 2:0/1/16, size2(40)
2412: CON [MID=26375, T=8F8FFE25DEBC96C1], GET, /test, 2:1/0/16    ----->
2415: <-----   ACK [MID=26375, T=8F8FFE25DEBC96C1], 2.05, 2:1/1/16
2415: CON [MID=26376, T=8F8FFE25DEBC96C1], GET, /test, 2:2/0/16    ----->
2416: <-----   ACK [MID=26376, T=8F8FFE25DEBC96C1], 2.05, 2:2/0/16
Wait until deduplicator should be empty (0.647 seconds)

2546: CON [MID=26377, T=D1A369DFBFD49861], GET, /test, observe(0)    ----->
2547: <-----   ACK [MID=26377, T=D1A369DFBFD49861], 2.05, observe(5)
2747: <-----   NON [MID=47427, T=D1A369DFBFD49861], 2.05, observe(6)
2947: <-----   NON [MID=47428, T=D1A369DFBFD49861], 2.05, observe(7)
2947: CON [MID=26378, T=D1A369DFBFD49861], GET, /test, observe(1)    ----->
2948: <-----   ACK [MID=26378, T=D1A369DFBFD49861], 2.05
Wait until deduplicator should be empty (0.647 seconds)

3078: CON [MID=26379, T=8CB888F41A68725D], GET, /test    ----->
3079: <-----   ACK [MID=26379, T=8CB888F41A68725D], 2.05
Wait until deduplicator should be empty (0.647 seconds)

3209: CON [MID=26380, T=A077B019B1EE5B93], POST, /test, 1:0/1/16, size1(20)    ----->
3210: <-----   ACK [MID=26380, T=A077B019B1EE5B93], 2.31, 1:0/1/16
3210: CON [MID=26381, T=A077B019B1EE5B93], POST, /test, 1:1/0/16    ----->
3211: <-----   ACK [MID=26381]
3211: <-----   CON [MID=47429, T=A077B019B1EE5B93], 2.01, 1:1/0/16, 2:0/1/16, size2(40)
3212: ACK [MID=47429]   ----->
3212: CON [MID=26382, T=A077B019B1EE5B93], POST, /test, 2:1/0/16    ----->
3213: <-----   ACK [MID=26382, T=A077B019B1EE5B93], 2.01, 2:1/1/16
3213: CON [MID=26383, T=A077B019B1EE5B93], POST, /test, 2:2/0/16    ----->
3214: <-----   ACK [MID=26383, T=A077B019B1EE5B93], 2.01, 2:2/0/16

Start ClientSynchronousTest on coap://127.0.0.1:8321
[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.339 s - in org.eclipse.californium.core.test.MemoryLeakingHashMapTest
[INFO] Running org.eclipse.californium.core.test.ClientSynchronousTest
End ClientSynchronousTest
[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.818 s - in org.eclipse.californium.core.test.ClientSynchronousTest
[INFO] Running org.eclipse.californium.core.test.RandomAccessBlockTest

Start org.eclipse.californium.core.test.RandomAccessBlockTest
End org.eclipse.californium.core.test.RandomAccessBlockTest

Start org.eclipse.californium.core.test.RandomAccessBlockTest
Request block number 2
Request block number 1
Request block number 5
Request block number 3
End org.eclipse.californium.core.test.RandomAccessBlockTest

Start org.eclipse.californium.core.test.RandomAccessBlockTest
End org.eclipse.californium.core.test.RandomAccessBlockTest

Start org.eclipse.californium.core.test.RandomAccessBlockTest
Request block number 2
Request block number 1
Request block number 5
Request block number 3
End org.eclipse.californium.core.test.RandomAccessBlockTest
[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.026 s - in org.eclipse.californium.core.test.RandomAccessBlockTest
[INFO] Running org.eclipse.californium.core.test.StartStopTest

Start StartStopTest
Socket port: 8331
Start server 1

Stop server 1 and start server 2

Stop server 2 and start server 1

Stop server 1 and start server 2

Stop server 2 and start server 1

Stop server 1 and start server 2

Stop server 2 and start server 1

Stop server 1
End StartStopTest

Start MessageTypeTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.851 s - in org.eclipse.californium.core.test.StartStopTest
[INFO] Running org.eclipse.californium.core.test.MessageTypeTest
gotit
gotit

End MessageTypeTest
[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in org.eclipse.californium.core.test.MessageTypeTest
[INFO] 
[INFO] Results:
[INFO] 
[ERROR] Failures: 
[ERROR]   BlockwiseClientSideTest.testPUTFailsWith413IfBodyExceedsMaxBodySize:469 
Expected: is not null
     but: was null
[INFO] 
[ERROR] Tests run: 118, Failures: 1, Errors: 0, Skipped: 0
[INFO] 
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary for Californium (Cf) Parent 2.0.0-SNAPSHOT:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  8.856 s]
[INFO] Legal Documents .................................... SUCCESS [  5.930 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  0.796 s]
[INFO] element-connector .................................. SUCCESS [01:22 min]
[INFO] Scandium (Sc) Core ................................. SUCCESS [01:54 min]
[INFO] element-connector-tcp .............................. SUCCESS [ 49.462 s]
[INFO] Californium (Cf) Core .............................. FAILURE [01:45 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] Californium (Cf) Demo Setup for Unix ............... SKIPPED
[INFO] Cf-BenchmarkServer ................................. SKIPPED
[INFO] Cf-ObserveBenchmarkClient .......................... SKIPPED
[INFO] Cf-OSCORE .......................................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  06:16 min
[INFO] Finished at: 2019-04-11T00:51:43-04:00
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.22.1:test (medium-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


Back to the top