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

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] Build failed in Jenkins: 2.0.x #363
From: hudsonbuild@xxxxxxxxxxx
Date: Thu, 14 Dec 2017 10:19:33 -0500 (EST)
Delivered-to: cf-dev@xxxxxxxxxxx
List-archive: <https://dev.eclipse.org/mailman/private/cf-dev>
List-help: <mailto:cf-dev-request@eclipse.org?subject=help>
List-subscribe: <https://dev.eclipse.org/mailman/listinfo/cf-dev>, <mailto:cf-dev-request@eclipse.org?subject=subscribe>
List-unsubscribe: <https://dev.eclipse.org/mailman/options/cf-dev>, <mailto:cf-dev-request@eclipse.org?subject=unsubscribe>
See <https://ci.eclipse.org/californium/job/2.0.x/363/display/redirect?page=changes>

Changes:

[Achim Kraus] Remove unused proxy "std" mappings.

------------------------------------------
[...truncated 244.38 KB...]
CON [MID=23347, T=eb1752fb49c17d68], GET, /test, 2:1/0/16    ----->
<-----   CON [MID=8001, T=4313c22db340e894], 2.05, observe(0)
ACK [MID=8001]   ----->
<-----   ACK [MID=23347, T=eb1752fb49c17d68], 2.05, 2:1/0/16
<-----   CON [MID=8002, T=4313c22db340e894], 2.05, observe(3)
ACK [MID=8002]   ----->
<-----   CON [MID=8003, T=4313c22db340e894], 2.05, 2:0/1/16, observe(4)
ACK [MID=8003]   ----->
CON [MID=23348, T=6ce80d2005fc4ca6], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=23348, T=6ce80d2005fc4ca6], 2.05, 2:1/0/16

Client binds to port 8250
Blockwise Observe:
Wait until deduplicator should be empty (3.77 seconds)
CON [MID=48949, T=e1775f108019698d], GET, /test, observe(0)    ----->
<-----   ACK [MID=48949, T=e1775f108019698d], 2.05, 2:0/1/16, observe(1)
CON [MID=48950, T=89b571e120224ca2], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=48950, T=89b571e120224ca2], 2.05, 2:1/0/16
<-----   CON [MID=8001, T=e1775f108019698d], 2.05, 2:0/1/16, observe(2)
ACK [MID=8001]   ----->
CON [MID=48951, T=a5c2cd7c0c28869d], GET, /test, 2:1/0/16    ----->
CON [MID=48951, T=a5c2cd7c0c28869d], GET, /test, 2:1/0/16    ----->
CON [MID=48951, T=a5c2cd7c0c28869d], GET, /test, 2:1/0/16    ----->
<-----   CON [MID=8002, T=e1775f108019698d], 2.05, 2:0/1/16, observe(3)
ACK [MID=8002]   ----->
CON [MID=48952, T=92f94cc3a26160d6], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=48952, T=92f94cc3a26160d6], 2.05, 2:1/0/16

Client binds to port 8252
Wait until deduplicator should be empty (3.77 seconds)
CON [MID=41352, T=815e3cdc72f65aae], GET, /test, observe(0)    ----->
<-----   ACK [MID=41352, T=815e3cdc72f65aae], 2.05, 2:0/1/16, observe(1)
CON [MID=41353, T=fb8bdc4d87f1a552], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=41353, T=fb8bdc4d87f1a552], 2.05, 2:1/0/16
CON [MID=41354, T=720683693496f806], GET, /test    ----->
<-----   ACK [MID=41354, T=720683693496f806], 2.05, 2:0/1/16
CON [MID=41355, T=720683693496f806], GET, /test, 2:1/0/16    ----->
<-----   CON [MID=8001, T=815e3cdc72f65aae], 2.05, 2:0/1/16, observe(2)
ACK [MID=8001]   ----->
CON [MID=41356, T=0662c176ba96f82d], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=41355, T=720683693496f806], 2.05, 2:1/0/16
<-----   ACK [MID=41356, T=0662c176ba96f82d], 2.05, 2:1/0/16

Client binds to port 8254
Blockwise Observe:
CON [MID=47575, T=9c5c256127bc7ab4], GET, /test, observe(0)    ----->
<-----   ACK [MID=47575, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(0), size2(40)
CON [MID=47576, T=06b03ddbd4a87ed7], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=47576, T=06b03ddbd4a87ed7], 2.05, 2:1/1/16
CON [MID=47577, T=06b03ddbd4a87ed7], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=47577, T=06b03ddbd4a87ed7], 2.05, 2:2/0/16

observe relation has been established, server now sends a notification
<-----   CON [MID=8001, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(1), size2(45)
ACK [MID=8001]   ----->
CON [MID=47578, T=3cd982364bd6d573], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=47578, T=3cd982364bd6d573], 2.05, 2:1/1/16
CON [MID=47579, T=3cd982364bd6d573], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=47579, T=3cd982364bd6d573], 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
<-----   CON [MID=8002, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(2), size2(42)
ACK [MID=8002]   ----->
CON [MID=47580, T=ceaaca035a34a1d1], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=47580, T=ceaaca035a34a1d1], 2.05, 2:1/1/16
CON [MID=47581, T=ceaaca035a34a1d1], GET, /test, 2:2/0/16    ----->
//////// Overriding notification ////////
<-----   CON [MID=8003, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(3), size2(40)
ACK [MID=8003]   ----->
CON [MID=47582, T=00372169912784d6], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=47581, T=ceaaca035a34a1d1], 2.05, 2:2/0/16
<-----   ACK [MID=47582, T=00372169912784d6], 2.05, 2:1/1/16
CON [MID=47583, T=00372169912784d6], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=47583, T=00372169912784d6], 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
<-----   CON [MID=8004, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(4), size2(38)
ACK [MID=8004]   ----->
CON [MID=47584, T=54df7e2a99bd35b4], GET, /test, 2:1/0/16    ----->
//////// Overriding notification (4) ////////
<-----   CON [MID=8005, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(5), size2(40)
ACK [MID=8005]   ----->
CON [MID=47585, T=a404860a70ffcc46], GET, /test, 2:1/0/16    ----->
//////// Conflicting notification block ////////
<-----   ACK [MID=47584, T=54df7e2a99bd35b4], 2.05, 2:1/1/16
<-----   ACK [MID=47585, T=a404860a70ffcc46], 2.05, 2:1/1/16
CON [MID=47586, T=a404860a70ffcc46], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=47586, T=a404860a70ffcc46], 2.05, 2:2/0/16

//////// Notification after cancellation ////////
<-----   CON [MID=8006, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(6), size2(34)
ACK [MID=8006]   ----->
CON [MID=47587, T=46c492851fed853c], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=47587, T=46c492851fed853c], 2.05, 2:1/1/16

Wait until deduplicator should be empty (3.77 seconds)
<-----   CON [MID=8007, T=9c5c256127bc7ab4], 2.05, 2:0/1/16, observe(7)
RST [MID=8007]   ----->

Client binds to port 8256
cancelled block2 transfer:
CON [MID=12843, T=565e2459eea666a4], GET, /test, observe(0)    ----->
<-----   ACK [MID=12843, T=565e2459eea666a4], 2.05, 2:0/1/16, observe(0), size2(300)
CON [MID=12844, T=cb30eaa80c67dc94], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=12844, T=cb30eaa80c67dc94], 2.05, 2:1/1/16
CON [MID=12845, T=cb30eaa80c67dc94], GET, /test, 2:2/0/16    ----->
<-----   ACK [MID=12845, T=cb30eaa80c67dc94], 2.05, 2:2/0/16

<-----   CON [MID=8001, T=565e2459eea666a4], 2.05, 2:0/1/16, observe(2), size2(42)
ACK [MID=8001]   ----->
CON [MID=20317, T=f8583a15c5f15c46], GET, /test, 2:1/0/16    ----->
<-----   ACK [MID=20317, T=f8583a15c5f15c46], 2.05, 2:1/1/16
CON [MID=20318, T=f8583a15c5f15c46], GET, /test, 2:2/0/16    ----->

Cancel observation 565e2459eea666a4
Wait until deduplicator should be empty (3.77 seconds)

End ObserveClientSideTest
Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 43.291 sec - in org.eclipse.californium.core.test.lockstep.ObserveClientSideTest
Running org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest

StartLossyBlockwiseTransferTest
Starting man in the middle...
client at localhost:8259, middle at 127.0.0.1:8261, server at localhost:8260
Man in the middle will drop packets [5, 6, 8, 9, 15]
doing a blockwise GET on: coap://localhost:8261/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
Dropping packet 15 (burst 1) from server
Received 250 bytes after 2754ms
Man in the middle will drop packets [2, 4, 6, 9, 12, 13, 13, 13, 15, 15]
doing a blockwise GET on: coap://localhost:8261/test
Dropping packet 2 (burst 1) from client
Dropping packet 4 (burst 2) from server
Dropping packet 6 (burst 3) from server
Dropping packet 9 (burst 3) from client
Dropping packet 12 (burst 3) from client
Dropping packet 13 (burst 4) from client
Received 250 bytes after 2489ms
Man in the middle will drop packets [0, 0, 5, 8, 8, 8, 8, 10, 14, 15]
doing a blockwise GET on: coap://localhost:8261/test
Dropping packet 0 (burst 1) from client
Dropping packet 5 (burst 1) from client
Dropping packet 8 (burst 1) from client
Dropping packet 10 (burst 2) from server
Dropping packet 14 (burst 1) from server
Dropping packet 15 (burst 2) from client
Received 250 bytes after 2113ms
Man in the middle will drop packets [4, 5, 7, 8, 8, 10, 10, 14, 14, 15]
doing a blockwise GET on: coap://localhost:8261/test
Dropping packet 4 (burst 1) from client
Dropping packet 5 (burst 2) from client
Dropping packet 7 (burst 3) from server
Dropping packet 8 (burst 4) from client
Dropping packet 14 (burst 1) from server
Dropping packet 15 (burst 2) from client
Received 250 bytes after 3201ms
Man in the middle will drop packets [1, 3, 3, 4, 6, 10, 10, 10, 12, 15]
doing a blockwise GET on: coap://localhost:8261/test
Dropping packet 1 (burst 1) from server
Dropping packet 3 (burst 2) from server
Dropping packet 4 (burst 3) from client
Dropping packet 6 (burst 4) from server
Dropping packet 10 (burst 1) from server
Dropping packet 12 (burst 2) from server
Dropping packet 15 (burst 2) from client
Received 250 bytes after 3505ms
Man in the middle will drop packets [0, 2, 3, 3, 4, 6, 7, 11, 12, 12]
doing a blockwise GET on: coap://localhost:8261/test
Dropping packet 0 (burst 1) from client
Dropping packet 2 (burst 2) from server
Dropping packet 3 (burst 3) from client
Dropping packet 4 (burst 4) from client
Dropping packet 11 (burst 1) from client
Dropping packet 12 (burst 2) from server

End LossyBlockwiseTransferTestTests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 25.116 sec <<< FAILURE! - in org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest
testBlockwiseTransferToleratesLostMessages(org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest)  Time elapsed: 25.115 sec  <<< FAILURE!
java.lang.AssertionError: Blockwise GET timed out after 9561ms
Expected: is not null
     but: was null
	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
	at org.junit.Assert.assertThat(Assert.java:956)
	at org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.getResourceAndAssertPayload(LossyBlockwiseTransferTest.java:154)
	at org.eclipse.californium.core.test.maninmiddle.LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages(LossyBlockwiseTransferTest.java:143)


Results :

Failed tests: 
  LossyBlockwiseTransferTest.testBlockwiseTransferToleratesLostMessages:143->getResourceAndAssertPayload:154 Blockwise GET timed out after 9561ms
Expected: is not null
     but: was null

Tests run: 33, Failures: 1, Errors: 0, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Californium (Cf) Parent ............................ SUCCESS [  2.921 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  4.051 s]
[INFO] element-connector .................................. SUCCESS [ 28.856 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [ 37.159 s]
[INFO] element-connector-tcp .............................. SUCCESS [ 39.002 s]
[INFO] Californium (Cf) Core .............................. FAILURE [02:11 min]
[INFO] Californium (Cf) Proxy ............................. SKIPPED
[INFO] Californium (Cf) OSGi .............................. SKIPPED
[INFO] Californium (Cf) Demo Applications ................. 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-NAT ............................................. SKIPPED
[INFO] Cf-SecureServer .................................... SKIPPED
[INFO] Cf-ExampleCrossProxy ............................... SKIPPED
[INFO] Cf-BenchmarkServer ................................. SKIPPED
[INFO] Cf-ObserveBenchmarkClient .......................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 04:08 min
[INFO] Finished at: 2017-12-14T10:19:30-05:00
[INFO] Final Memory: 44M/1931M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.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] -> [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] Jenkins build is back to normal : 2.0.x #364
  - From: hudsonbuild
Prev by Date: [cf-dev] Jenkins build is back to normal : 2.0.x #359
Next by Date: [cf-dev] Jenkins build is back to normal : 2.0.x #364
Previous by thread: [cf-dev] Build failed in Jenkins: master #288
Next by thread: [cf-dev] Jenkins build is back to normal : 2.0.x #364
Index(es):
- Date
- Thread
Breadcrumbs
