[
Date Prev][
Date Next][
Thread Prev][
Thread Next][
Date Index][
Thread Index]
[
List Home]
|
[cf-dev] Build failed in Jenkins: 2.0.x #629
|
See <https://ci.eclipse.org/californium/job/2.0.x/629/display/redirect?page=changes>
Changes:
[Achim Kraus] Adjust android app for dtls configuration changes.
------------------------------------------
[...truncated 361.99 KB...]
0005: <----- CON [MID=8001, T=[01f9b24cfdfac8a4]], 2.05, 2:0/1/16, observe(3)
0006: ACK [MID=8001] ----->
0006: CON [MID=43336, T=[6a0763d8b1284325]], GET, /test, 2:1/0/16 ----->
0008: <----- ACK [MID=43336, T=[6a0763d8b1284325]], 2.05, 2:1/1/16
0009: CON [MID=43337, T=[6a0763d8b1284325]], GET, /test, 2:2/0/16 (should be dropped by error)
0012: CON [MID=43337, T=[6a0763d8b1284325]], GET, /test, 2:2/0/16 -----> (dropped)
Client binds to port 8256
Observe fails before we send request:
1 Errors!
Wait until deduplicator should be empty (2.97 seconds)
Wait until deduplicator should be empty (2.97 seconds)
0003: CON [MID=30434, T=[f78eb8e7de875796]], GET, /test, observe(0) (should be dropped by error)
0004: CON [MID=30434, T=[f78eb8e7de875796]], GET, /test, observe(0) -----> (dropped)
Client binds to port 8258
Notification is not consider as a response of proactive cancel
Wait until deduplicator should be empty (2.97 seconds)
Wait until deduplicator should be empty (2.97 seconds)
0002: CON [MID=64066, T=[af6a539aa0463cc5]], GET, /test, observe(0) ----->
0003: <----- ACK [MID=64066, T=[af6a539aa0463cc5]], 2.05, observe(1)
0004: <----- CON [MID=8001, T=[af6a539aa0463cc5]], 2.05, observe(3)
0005: ACK [MID=8001] ----->
0006: CON [MID=64067, T=[af6a539aa0463cc5]], GET, /test, observe(1) ----->
0007: <----- CON [MID=8002, T=[af6a539aa0463cc5]], 2.05, observe(4)
0008: <----- NON [MID=8003, T=[af6a539aa0463cc5]], 2.05, observe(5)
0207: CON [MID=64067, T=[af6a539aa0463cc5]], GET, /test, observe(1) ----->
0407: CON [MID=64067, T=[af6a539aa0463cc5]], GET, /test, observe(1) ----->
0507: <----- ACK [MID=64067, T=[af6a539aa0463cc5]], 2.05
Client binds to port 8260
Wait until deduplicator should be empty (2.97 seconds)
Wait until deduplicator should be empty (2.97 seconds)
0002: CON [MID=61190, T=[5b2426c66c502a19]], GET, /test, observe(0) ----->
0003: <----- ACK [MID=61190, T=[5b2426c66c502a19]], 2.05, 2:0/1/16, observe(1)
0004: CON [MID=61191, T=[fe93a0d158228bc5]], GET, /test, 2:1/0/16 ----->
0005: <----- ACK [MID=61191, T=[fe93a0d158228bc5]], 2.05, 2:1/0/16
0006: CON [MID=61192, T=[26921fec68320bb9]], GET, /test ----->
0007: <----- ACK [MID=61192, T=[26921fec68320bb9]], 2.05, 2:0/1/16
0008: CON [MID=61193, T=[26921fec68320bb9]], GET, /test, 2:1/0/16 ----->
0009: <----- CON [MID=8001, T=[5b2426c66c502a19]], 2.05, 2:0/1/16, observe(2)
0009: ACK [MID=8001] ----->
0010: CON [MID=61194, T=[ccf0376b3094b9a9]], GET, /test, 2:1/0/16 ----->
0012: <----- ACK [MID=61193, T=[26921fec68320bb9]], 2.05, 2:1/0/16
0012: <----- ACK [MID=61194, T=[ccf0376b3094b9a9]], 2.05, 2:1/0/16
Client binds to port 8262
Response with observe option is accepted as response for a GET
Wait until deduplicator should be empty (2.97 seconds)
Wait until deduplicator should be empty (2.97 seconds)
0002: CON [MID=58019, T=[d252bcd3cda55443]], GET, /test ----->
0003: <----- ACK [MID=58019, T=[d252bcd3cda55443]], 2.05, observe(3)
0004: CON [MID=58020, T=[72036e4789c40811]], GET, /test ----->
0005: <----- CON [MID=8000, T=[72036e4789c40811]], 2.05, observe(4)
0006: ACK [MID=8000] ----->
0007: CON [MID=58021, T=[2ecebdfee7f52051]], GET, /test ----->
0008: <----- NON [MID=58021, T=[2ecebdfee7f52051]], 2.05, observe(5)
Client binds to port 8264
Blockwise Observe:
0003: CON [MID=54623, T=[0fac4f350764b1a7]], GET, /test, observe(0) ----->
0004: <----- ACK [MID=54623, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(0), size2(40)
0005: CON [MID=54624, T=[ecb845948509d8e3]], GET, /test, 2:1/0/16 ----->
0006: <----- ACK [MID=54624, T=[ecb845948509d8e3]], 2.05, 2:1/1/16
0007: CON [MID=54625, T=[ecb845948509d8e3]], GET, /test, 2:2/0/16 ----->
0008: <----- ACK [MID=54625, T=[ecb845948509d8e3]], 2.05, 2:2/0/16
observe relation has been established, server now sends a notification
0010: <----- CON [MID=8001, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(1), size2(45)
0010: ACK [MID=8001] ----->
0011: CON [MID=54626, T=[e8c026df69c0c837]], GET, /test, 2:1/0/16 ----->
0012: <----- ACK [MID=54626, T=[e8c026df69c0c837]], 2.05, 2:1/1/16
0013: CON [MID=54627, T=[e8c026df69c0c837]], GET, /test, 2:2/0/16 ----->
0014: <----- ACK [MID=54627, T=[e8c026df69c0c837]], 2.05, 2:2/0/16
client has successfully retrieved content for notification using blockwise transfer
server now sends notifications interfering with ongoing blockwise transfer
0015: <----- CON [MID=8002, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(2), size2(42)
0016: ACK [MID=8002] ----->
0016: CON [MID=54628, T=[96de39e94960461e]], GET, /test, 2:1/0/16 ----->
0017: <----- ACK [MID=54628, T=[96de39e94960461e]], 2.05, 2:1/1/16
0018: CON [MID=54629, T=[96de39e94960461e]], GET, /test, 2:2/0/16 ----->
0019: //////// Overriding notification ////////
0019: <----- CON [MID=8003, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(3), size2(40)
0020: ACK [MID=8003] ----->
0020: CON [MID=54630, T=[ea673dd7f0c241cd]], GET, /test, 2:1/0/16 ----->
0021: <----- ACK [MID=54629, T=[96de39e94960461e]], 2.05, 2:2/0/16
0022: <----- ACK [MID=54630, T=[ea673dd7f0c241cd]], 2.05, 2:1/1/16
0022: CON [MID=54631, T=[ea673dd7f0c241cd]], GET, /test, 2:2/0/16 ----->
0023: <----- ACK [MID=54631, T=[ea673dd7f0c241cd]], 2.05, 2:2/0/16
client has detected newly arriving notification while doing blockwise transfer of previous notification
server now sends notifications interfering with ongoing blockwise transfer using conflicting block numbers
0074: <----- CON [MID=8004, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(4), size2(38)
0074: ACK [MID=8004] ----->
0075: CON [MID=54632, T=[dc572568f880b9ac]], GET, /test, 2:1/0/16 ----->
0075: //////// Overriding notification (4) ////////
0076: <----- CON [MID=8005, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(5), size2(40)
0076: ACK [MID=8005] ----->
0077: CON [MID=54633, T=[521644d8c4a4dce1]], GET, /test, 2:1/0/16 ----->
0078: //////// Conflicting notification block ////////
0078: <----- ACK [MID=54632, T=[dc572568f880b9ac]], 2.05, 2:1/1/16
0078: <----- ACK [MID=54633, T=[521644d8c4a4dce1]], 2.05, 2:1/1/16
0079: CON [MID=54634, T=[521644d8c4a4dce1]], GET, /test, 2:2/0/16 ----->
0080: <----- ACK [MID=54634, T=[521644d8c4a4dce1]], 2.05, 2:2/0/16
0130: //////// Notification after cancellation ////////
0131: <----- CON [MID=8006, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(6), size2(34)
0131: ACK [MID=8006] ----->
0131: CON [MID=54635, T=[02107c8db6f4d9d7]], GET, /test, 2:1/0/16 ----->
0133: <----- ACK [MID=54635, T=[02107c8db6f4d9d7]], 2.05, 2:1/1/16
Wait until deduplicator should be empty (2.97 seconds)
Wait until deduplicator should be empty (2.97 seconds)
0533: <----- CON [MID=8007, T=[0fac4f350764b1a7]], 2.05, 2:0/1/16, observe(7)
0534: RST [MID=8007] ----->
Client binds to port 8266
cancelled block2 transfer:
0002: CON [MID=10301, T=[1bfbb8fbff958aef]], GET, /test, observe(0) ----->
0003: <----- ACK [MID=10301, T=[1bfbb8fbff958aef]], 2.05, 2:0/1/16, observe(0), size2(300)
0004: CON [MID=10302, T=[009e8d3bec6ffe31]], GET, /test, 2:1/0/16 ----->
0005: <----- ACK [MID=10302, T=[009e8d3bec6ffe31]], 2.05, 2:1/1/16
0006: CON [MID=10303, T=[009e8d3bec6ffe31]], GET, /test, 2:2/0/16 ----->
0007: <----- ACK [MID=10303, T=[009e8d3bec6ffe31]], 2.05, 2:2/0/16
0009: <----- CON [MID=8001, T=[1bfbb8fbff958aef]], 2.05, 2:0/1/16, observe(2), size2(42)
0010: ACK [MID=8001] ----->
0010: CON [MID=27251, T=[88d124cf8caac09c]], GET, /test, 2:1/0/16 ----->
0011: <----- ACK [MID=27251, T=[88d124cf8caac09c]], 2.05, 2:1/1/16
0012: CON [MID=27252, T=[88d124cf8caac09c]], GET, /test, 2:2/0/16 ----->
Cancel observation [1bfbb8fbff958aef]
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)
0002: CON [MID=51276, T=[fb66bf8113b24b04]], GET, /test, observe(0) ----->
0003: <----- ACK [MID=51276, T=[fb66bf8113b24b04]], 2.05, observe(1)
0004: <----- CON [MID=8001, T=[fb66bf8113b24b04]], 2.05, observe(3)
0005: ACK [MID=8001] ----->
0005: CON [MID=51277, T=[fb66bf8113b24b04]], GET, /test, observe(1) ----->
0006: <----- ACK [MID=51277, T=[fb66bf8113b24b04]], 2.05
0007: <----- CON [MID=8002, T=[fb66bf8113b24b04]], 2.05, observe(4)
0008: RST [MID=8002] ----->
0008: <----- NON [MID=8003, T=[fb66bf8113b24b04]], 2.05, observe(5)
0008: RST [MID=8003] ----->
End ObserveClientSideTest
[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.539 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
Man in the middle will drop packets [5, 6, 8, 9, 15]
doing a blockwise GET on: coap://localhost:8273/test
Dropping packet 5 (burst 1) from server
Dropping packet 6 (burst 2) from client
Dropping packet 8 (burst 3) from server
Dropping packet 9 (burst 4) from client
End LossyBlockwiseTransferTest[ERROR] Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.062 s <<< FAILURE! - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
[ERROR] testBlockwiseTransferToleratesLostMessages(org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest) Time elapsed: 0.061 s <<< FAILURE!
java.lang.AssertionError:
Blockwise GET timed out after 4531ms
Expected: is not null
but: was null
at org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.getResourceAndAssertPayload(LossyBlockwiseTransferTest.java:162)
at org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages(LossyBlockwiseTransferTest.java:142)
[INFO]
[INFO] Results:
[INFO]
[ERROR] Failures:
[ERROR] LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages:142->getResourceAndAssertPayload:162 Blockwise GET timed out after 4531ms
Expected: is not null
but: was null
[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.226 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [ 2.897 s]
[INFO] element-connector .................................. SUCCESS [ 50.292 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [01:41 min]
[INFO] element-connector-tcp .............................. SUCCESS [ 33.300 s]
[INFO] Californium (Cf) Core .............................. FAILURE [01:32 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] oscore-cf 2.0.0-SNAPSHOT ........................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 04:51 min
[INFO] Finished at: 2019-01-17T14:10:08-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
