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

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index] [List Home]
[cf-dev] Build failed in Jenkins: 2.0.x-nightly #752
From: hudsonbuild@xxxxxxxxxxx
Date: Thu, 4 Apr 2019 02:52:25 -0400 (EDT)
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-nightly/752/display/redirect?page=changes>

Changes:

[Achim Kraus] Fix typo.

------------------------------------------
[...truncated 468.69 KB...]
0004: CON [MID=7001, T=15], GET, /test, observe(0)    ----->
0012: <-----   ACK [MID=7001, T=15], 2.05, 2:0/1/128, observe(0), size2(300)
0015: CON [MID=7002, T=16], GET, /test, 2:1/0/128    ----->
0016: <-----   ACK [MID=7002, T=16], 2.05, 2:1/1/128
0017: CON [MID=7003, T=16], GET, /test, 2:2/0/128    ----->
0018: <-----   ACK [MID=7003, T=16], 2.05, 2:2/0/128
0019: ... time passes ...
0022: <-----   NON [MID=34607, T=15], 2.05, 2:0/1/128, observe(1), size2(280)
0026: CON [MID=7004, T=17], GET, /test, 2:1/0/128    ----->
0026: <-----   ACK [MID=7004, T=17], 2.05, 2:1/1/128
0028: CON [MID=7005, T=17], GET, /test, 2:2/0/128    ----->
0028: <-----   ACK [MID=7005, T=17], 2.05, 2:2/0/128
0029: ... time passes ...
0031: <-----   NON [MID=34608, T=15], 2.05, 2:0/1/128, observe(2), size2(290)
0032: CON [MID=7006, T=18], GET, /test, 2:1/0/128    ----->
0033: <-----   ACK [MID=7006, T=18], 2.05, 2:1/1/128
0034: CON [MID=7007, T=18], GET, /test, 2:2/0/128    ----->
0035: <-----   ACK [MID=7007, T=18], 2.05, 2:2/0/128

Server binds to port 8250
Atomic blockwise POST with blockwise response using late negotiation:
Wait until deduplicator should be empty (0.647 seconds)

0005: CON [MID=7001, T=19], POST, /test, 1:0/1/128, size1(300)    ----->
0006: <-----   ACK [MID=7001, T=19], 2.31, 1:0/1/128
0008: CON [MID=7002, T=19], POST, /test, 1:1/1/128    ----->
0009: <-----   ACK [MID=7002, T=19], 2.31, 1:1/1/128
0010: CON [MID=7003, T=19], POST, /test, 1:2/0/128    ----->
0011: <-----   ACK [MID=7003, T=19], 2.04, 1:2/0/128, 2:0/1/128, size2(300), ETags([0001])
0025: CON [MID=7004, T=19], POST, /test, 2:2/0/64, ETags([0001])    ----->
0026: <-----   ACK [MID=7004, T=19], 2.04, 2:2/1/64, ETags([0001])// late negotiation
0028: CON [MID=7005, T=19], POST, /test, 2:3/0/64, ETags([0001])    ----->
0028: <-----   ACK [MID=7005, T=19], 2.04, 2:3/1/64, ETags([0001])
0030: CON [MID=7006, T=19], POST, /test, 2:4/0/64, ETags([0001])    ----->
0030: <-----   ACK [MID=7006, T=19], 2.04, 2:4/0/64, ETags([0001])

Server binds to port 8252
Observe sequence with early negotiation:
Establish observe relation to test
Send first notification
Send second notification
Wait until deduplicator should be empty (0.647 seconds)

0003: CON [MID=7001, T=1A], GET, /test, 2:0/0/64, observe(0)    ----->
0004: <-----   ACK [MID=7001, T=1A], 2.05, 2:0/1/64, observe(0), size2(150)
0006: CON [MID=7002, T=1A], GET, /test, 2:1/0/64    ----->
0007: <-----   ACK [MID=7002, T=1A], 2.05, 2:1/1/64
0008: CON [MID=7003, T=1A], GET, /test, 2:2/0/64    ----->
0008: <-----   ACK [MID=7003, T=1A], 2.05, 2:2/0/64
0009: ... time passes ...
0010: <-----   NON [MID=11365, T=1A], 2.05, 2:0/1/64, observe(1), size2(140)
0011: CON [MID=7004, T=1B], GET, /test, 2:1/0/64    ----->
0012: <-----   ACK [MID=7004, T=1B], 2.05, 2:1/1/64
0013: CON [MID=7005, T=1B], GET, /test, 2:2/0/64    ----->
0013: <-----   ACK [MID=7005, T=1B], 2.05, 2:2/0/64
0014: ... time passes ...
0015: <-----   NON [MID=11366, T=1A], 2.05, 2:0/1/64, observe(2), size2(145)
0016: CON [MID=7006, T=1C], GET, /test, 2:1/0/64    ----->
0017: <-----   ACK [MID=7006, T=1C], 2.05, 2:1/1/64
0018: CON [MID=7007, T=1C], GET, /test, 2:2/0/64    ----->
0019: <-----   ACK [MID=7007, T=1C], 2.05, 2:2/0/64

Server binds to port 8254
Blockwise GET with late negotiation:
Wait until deduplicator should be empty (0.647 seconds)

0005: CON [MID=7001, T=1D], GET, /test    ----->
0006: <-----   ACK [MID=7001, T=1D], 2.05, 2:0/1/128, size2(170)
0008: CON [MID=7002, T=1D], GET, /test, 2:2/0/64    ----->
0009: <-----   ACK [MID=7002, T=1D], 2.05, 2:2/0/64

Server binds to port 8256
2 consecutive complete PUT with block1 transfer:
Wait until deduplicator should be empty (0.647 seconds)

0006: CON [MID=7001, T=1E], PUT, /test, 1:0/1/128, size1(300)    ----->
0006: <-----   ACK [MID=7001, T=1E], 2.31, 1:0/1/128
0384: CON [MID=7002, T=1E], PUT, /test, 1:1/1/128    ----->
0385: <-----   ACK [MID=7002, T=1E], 2.31, 1:1/1/128
0761: CON [MID=7003, T=1E], PUT, /test, 1:2/0/128    ----->
0762: <-----   ACK [MID=7003, T=1E], 2.04, 1:2/0/128
1138: // next transfer
1140: CON [MID=7004, T=1F], PUT, /test, 1:0/1/128, size1(300)    ----->
1140: <-----   ACK [MID=7004, T=1F], 2.31, 1:0/1/128
1517: CON [MID=7005, T=1F], PUT, /test, 1:1/1/128    ----->
1518: <-----   ACK [MID=7005, T=1F], 2.31, 1:1/1/128
1895: CON [MID=7006, T=1F], PUT, /test, 1:2/0/128    ----->
1896: <-----   ACK [MID=7006, T=1F], 2.04, 1:2/0/128

Server binds to port 8258
Simple blockwise GET:
Wait until deduplicator should be empty (0.647 seconds)

0005: CON [MID=7001, T=20], GET, /test    ----->
0006: <-----   ACK [MID=7001, T=20], 2.05, 2:0/1/128, size2(300), ETags([0001])
0008: CON [MID=7002, T=20], GET, /test, 2:1/0/128, ETags([0001])    ----->
0009: <-----   ACK [MID=7002, T=20], 2.05, 2:1/1/128, ETags([0001])
0012: CON [MID=7003, T=20], GET, /test, 2:2/0/128, ETags([0001])    ----->
0012: <-----   ACK [MID=7003, T=20], 2.05, 2:2/0/128, ETags([0001])

Server binds to port 8260
Blockwise PUT fails for incomplete transfer
02:52:22.607 WARN [BlockwiseLayer]: peer sent wrong block, expected no. 1 but got 2. Responding with 4.08 (Request Entity Incomplete) (o.e.c.c.n.s.BlockwiseLayer.handleInboundBlockwiseUpload:424)
Wait until deduplicator should be empty (0.647 seconds)

0006: CON [MID=7001, T=21], PUT, /test, 1:0/1/128, size1(300)    ----->
0007: <-----   ACK [MID=7001, T=21], 2.31, 1:0/1/128
0009: CON [MID=7002, T=21], PUT, /test, 1:2/0/128    ----->
0010: <-----   ACK [MID=7002, T=21], 4.08, 1:2/0/128

Server binds to port 8262
Blockwise PUT fails for excessive body size
Wait until deduplicator should be empty (0.647 seconds)

0006: CON [MID=7001, T=22], PUT, /test, 1:0/1/128, size1(1034)    ----->
0007: <-----   ACK [MID=7001, T=22], 4.13, size1(1024)

Server binds to port 8264
Atomic blockwise POST with blockwise response:
Wait until deduplicator should be empty (0.647 seconds)

0006: CON [MID=7001, T=23], POST, /test, 1:0/1/128, size1(300)    ----->
0006: <-----   ACK [MID=7001, T=23], 2.31, 1:0/1/128
0008: CON [MID=7002, T=23], POST, /test, 1:1/1/128    ----->
0009: <-----   ACK [MID=7002, T=23], 2.31, 1:1/1/128
0011: CON [MID=7003, T=23], POST, /test, 1:2/0/128    ----->
0011: <-----   ACK [MID=7003, T=23], 2.04, 1:2/0/128, 2:0/1/128, size2(500), ETags([0001])
0013: CON [MID=7004, T=23], POST, /test, 2:1/0/128, ETags([0001])    ----->
0014: <-----   ACK [MID=7004, T=23], 2.04, 2:1/1/128, ETags([0001])
0016: CON [MID=7005, T=23], POST, /test, 2:2/0/128, ETags([0001])    ----->
0017: <-----   ACK [MID=7005, T=23], 2.04, 2:2/1/128, ETags([0001])
0018: CON [MID=7006, T=23], POST, /test, 2:3/0/128, ETags([0001])    ----->
0019: <-----   ACK [MID=7006, T=23], 2.04, 2:3/0/128, ETags([0001])

Server binds to port 8266
Atomic blockwise POST with blockwise response using early negotiation:
Wait until deduplicator should be empty (0.647 seconds)

0006: CON [MID=7001, T=24], POST, /test, 1:0/1/128    ----->
0007: <-----   ACK [MID=7001, T=24], 2.31, 1:0/1/128
0009: CON [MID=7002, T=24], POST, /test, 1:1/1/128    ----->
0010: <-----   ACK [MID=7002, T=24], 2.31, 1:1/1/128
0012: CON [MID=7003, T=24], POST, /test, 1:2/0/128, 2:0/0/64    ----->
0013: <-----   ACK [MID=7003, T=24], 2.04, 1:2/0/128, 2:0/1/64, size2(250), ETags([0001])// early negotiation
0015: CON [MID=7004, T=24], POST, /test, 2:1/0/64, ETags([0001])    ----->
0017: <-----   ACK [MID=7004, T=24], 2.04, 2:1/1/64, ETags([0001])
0018: CON [MID=7005, T=24], POST, /test, 2:2/0/64, ETags([0001])    ----->
0019: <-----   ACK [MID=7005, T=24], 2.04, 2:2/1/64, ETags([0001])
0020: CON [MID=7006, T=24], POST, /test, 2:3/0/64, ETags([0001])    ----->
0020: <-----   ACK [MID=7006, T=24], 2.04, 2:3/0/64, ETags([0001])

Server binds to port 8268
Random access PUT attempt: (try to put block 2 first is now allowed)
02:52:23.188 WARN [BlockwiseLayer]: peer sent wrong block, expected no. 0 but got 2. Responding with 4.08 (Request Entity Incomplete) (o.e.c.c.n.s.BlockwiseLayer.handleInboundBlockwiseUpload:424)
Wait until deduplicator should be empty (0.647 seconds)

0005: CON [MID=7001, T=25], PUT, /test, 1:2/1/64    ----->
0007: <-----   ACK [MID=7001, T=25], 4.08, 1:2/1/64

Server binds to port 8270
Simple atomic blockwise PUT restart of the blockwise transfer
02:52:23.331 WARN [BlockwiseLayer]: inbound block1 transfer reset at [currentNum=2, currentSzx=0, bufferSize=300, complete=false, random access=false] by peer: CON-PUT    MID= 7003, Token=26, OptionSet={"Uri-Path":"test", "Block1":"(szx=3/128, m=true, num=0)", "Size1":300}, "504544506545027122340857".. 128 bytes (o.e.c.c.n.s.BlockwiseLayer.resetInboundBlock1Status:1052)
Wait until deduplicator should be empty (0.647 seconds)

0004: CON [MID=7001, T=26], PUT, /test, 1:0/1/128, size1(300)    ----->
0005: <-----   ACK [MID=7001, T=26], 2.31, 1:0/1/128
0006: CON [MID=7002, T=26], PUT, /test, 1:1/1/128    ----->
0007: <-----   ACK [MID=7002, T=26], 2.31, 1:1/1/128
0008: ... client crashes or whatever and restarts transfer
0009: CON [MID=7003, T=26], PUT, /test, 1:0/1/128, size1(300)    ----->
0011: <-----   ACK [MID=7003, T=26], 2.31, 1:0/1/128
0012: CON [MID=7004, T=26], PUT, /test, 1:1/1/128    ----->
0013: <-----   ACK [MID=7004, T=26], 2.31, 1:1/1/128
0014: CON [MID=7005, T=26], PUT, /test, 1:2/0/128    ----->
0015: <-----   ACK [MID=7005, T=26], 2.04, 1:2/0/128

Server binds to port 8272
Simple atomic blockwise PUT
Wait until deduplicator should be empty (0.647 seconds)

0004: CON [MID=7001, T=27], PUT, /test, 1:0/1/128, size1(300)    ----->
0015: <-----   ACK [MID=7001, T=27], 2.31, 1:0/1/128
0019: CON [MID=7002, T=28], PUT, /test, 1:1/1/128    ----->
0020: <-----   ACK [MID=7002, T=28], 2.31, 1:1/1/128
0022: CON [MID=7003, T=29], PUT, /test, 1:2/0/128    ----->
0023: <-----   ACK [MID=7003, T=29], 2.04, 1:2/0/128

End BlockwiseServerSideTest
[INFO] Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.572 s - in org.eclipse.californium.core.test.lockstep.BlockwiseServerSideTest
[INFO] 
[INFO] Results:
[INFO] 
[ERROR] Failures: 
[ERROR]   ObserveClientSideTest.testIncompleteBlock2NotificationAckNoResponse:1108->assertAllEndpointExchangesAreCompleted:1476 endpoint still contains states
[INFO] 
[ERROR] Tests run: 39, 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 [  7.090 s]
[INFO] Legal Documents .................................... SUCCESS [  2.742 s]
[INFO] Californium (Cf) Demo Certificates ................. SUCCESS [  0.195 s]
[INFO] element-connector .................................. SUCCESS [ 42.017 s]
[INFO] Scandium (Sc) Core ................................. SUCCESS [01:23 min]
[INFO] element-connector-tcp .............................. SUCCESS [ 37.166 s]
[INFO] Californium (Cf) Core .............................. FAILURE [02:13 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 .......................................... SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  05:14 min
[INFO] Finished at: 2019-04-04T02:52:24-04: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 <https://ci.eclipse.org/californium/job/2.0.x-nightly/ws/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] Jenkins build is back to normal : 2.0.x-nightly #753
  - From: hudsonbuild
Prev by Date: [cf-dev] Jenkins build is back to normal : 2.0.x #702
Next by Date: [cf-dev] Jenkins build is back to normal : 2.0.x-nightly #753
Previous by thread: [cf-dev] Build failed in Jenkins: 2.0.x #699
Next by thread: [cf-dev] Jenkins build is back to normal : 2.0.x-nightly #753
Index(es):
- Date
- Thread
Breadcrumbs
