Turned out that the issue is not strictly related to MESOS-1257. This specific problem was triggered by a build configured with —disable-python. I have proposed a fix at https://reviews.apache.org/r/20823/
The issue visible in https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2096 should also be fixed, the fix already got committed: https://reviews.apache.org/r/20819/ So now all the issues that popped up today, caused by the ExternalContainerizer(test) should be fixed. Sorry for any inconveniences these have caused. On Apr 29, 2014, at 12:34 AM, Jie Yu <yujie....@gmail.com> wrote: > Should be related to > https://issues.apache.org/jira/browse/MESOS-1257 > > > On Mon, Apr 28, 2014 at 3:32 PM, Vinod Kone <vinodk...@gmail.com> wrote: > +till > > can't seem to figure out what the error is here. @till, mind taking a look? > > > On Mon, Apr 28, 2014 at 1:59 PM, Apache Jenkins Server < > jenk...@builds.apache.org> wrote: > > > See < > > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2097/changes > > > > > > > Changes: > > > > [bmahler] Added count to metric statistics. > > > > [benjamin.hindman] [MESOS-1252]: Support ENV MAVEN_HOME on the build. > > > > ------------------------------------------ > > [...truncated 5332 lines...] > > I0428 20:59:24.682000 15960 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.682694 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.683544 15959 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.683954 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.686210 15961 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.687655 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.688208 15953 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.688606 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.689003 15955 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.689481 15977 process.cpp:1013] Socket closed while receiving > > [ OK ] FilesTest.ReadTest (11 ms) > > [ RUN ] FilesTest.ResolveTest > > Using temporary directory '/tmp/FilesTest_ResolveTest_OtVcdW' > > I0428 20:59:24.693207 15963 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.693959 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.694476 15965 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.695181 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.695780 15976 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.696813 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.697413 15972 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.698425 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.699270 15976 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.700206 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.700775 15957 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.701326 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.702167 15959 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.702430 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.703665 15970 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.704807 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.705673 15967 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.705952 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.706401 15960 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/read.json' > > I0428 20:59:24.707334 15977 process.cpp:1013] Socket closed while receiving > > [ OK ] FilesTest.ResolveTest (18 ms) > > [ RUN ] FilesTest.BrowseTest > > Using temporary directory '/tmp/FilesTest_BrowseTest_09PycP' > > I0428 20:59:24.710582 15958 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/browse.json' > > I0428 20:59:24.711107 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.712327 15975 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/browse.json' > > I0428 20:59:24.713183 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.713877 15953 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/browse.json' > > I0428 20:59:24.714687 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.715458 15964 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/browse.json' > > I0428 20:59:24.716001 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.717205 15968 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/browse.json' > > I0428 20:59:24.718169 15977 process.cpp:1013] Socket closed while receiving > > [ OK ] FilesTest.BrowseTest (11 ms) > > [ RUN ] FilesTest.DownloadTest > > Using temporary directory '/tmp/FilesTest_DownloadTest_YE82dI' > > I0428 20:59:24.721535 15960 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/download.json' > > I0428 20:59:24.722095 15975 process.cpp:1746] Sending file at > > '/tmp/FilesTest_DownloadTest_YE82dI/binary' with length 17 > > I0428 20:59:24.722409 15977 process.cpp:1013] Socket closed while receiving > > I0428 20:59:24.723152 15961 process.cpp:3201] Handling HTTP event for > > process 'files' with path: '/files/download.json' > > I0428 20:59:24.724043 15961 process.cpp:1746] Sending file at > > '/tmp/FilesTest_DownloadTest_YE82dI/black.gif' with length 42 > > I0428 20:59:24.724460 15977 process.cpp:1013] Socket closed while receiving > > [ OK ] FilesTest.DownloadTest (6 ms) > > [ RUN ] FilesTest.AttachTest > > Using temporary directory '/tmp/FilesTest_AttachTest_rofKgB' > > [ OK ] FilesTest.AttachTest (7 ms) > > [----------] 6 tests from FilesTest (55 ms total) > > > > [----------] 1 test from ExternalContainerizerTest > > [ RUN ] ExternalContainerizerTest.Launch > > I0428 20:59:24.772775 15732 leveldb.cpp:174] Opened db in 39.638232ms > > I0428 20:59:24.785150 15732 leveldb.cpp:181] Compacted db in 12.344983ms > > I0428 20:59:24.785184 15732 leveldb.cpp:196] Created db iterator in 3296ns > > I0428 20:59:24.785197 15732 leveldb.cpp:202] Seeked to beginning of db in > > 455ns > > I0428 20:59:24.785202 15732 leveldb.cpp:271] Iterated through 0 keys in > > the db in 127ns > > I0428 20:59:24.785215 15732 replica.cpp:729] Replica recovered with log > > positions 0 -> 0 with 1 holes and 0 unlearned > > I0428 20:59:24.785486 15970 recover.cpp:425] Starting replica recovery > > I0428 20:59:24.785936 15957 master.cpp:266] Master > > 20140428-205924-453759884-46217-15732 (hemera.apache.org) started on > > 140.211.11.27:46217 > > I0428 20:59:24.785960 15957 master.cpp:303] Master only allowing > > authenticated frameworks to register > > I0428 20:59:24.785967 15957 master.cpp:308] Master only allowing > > authenticated slaves to register > > I0428 20:59:24.785974 15957 credentials.hpp:35] Loading credentials for > > authentication > > W0428 20:59:24.786023 15957 credentials.hpp:48] Failed to stat credentials > > file 'file:///tmp/ExternalContainerizerTest_Launch_NEMSku/credentials': No > > such file or directory > > I0428 20:59:24.786319 15965 hierarchical_allocator_process.hpp:302] > > Initializing hierarchical allocator process with master : > > master@140.211.11.27:46217 > > I0428 20:59:24.786321 15964 master.cpp:104] No whitelist given. > > Advertising offers for all slaves > > I0428 20:59:24.786841 15953 recover.cpp:451] Replica is in EMPTY status > > I0428 20:59:24.786959 15957 master.cpp:922] The newly elected leader is > > master@140.211.11.27:46217 with id 20140428-205924-453759884-46217-15732 > > I0428 20:59:24.786977 15957 master.cpp:932] Elected as the leading master! > > I0428 20:59:24.786984 15957 master.cpp:753] Recovering from registrar > > I0428 20:59:24.787045 15954 registrar.cpp:275] Recovering registrar > > I0428 20:59:24.789015 15962 replica.cpp:626] Replica in EMPTY status > > received a broadcasted recover request > > I0428 20:59:24.789623 15954 recover.cpp:188] Received a recover response > > from a replica in EMPTY status > > I0428 20:59:24.790539 15955 recover.cpp:542] Updating replica status to > > STARTING > > I0428 20:59:24.801323 15954 leveldb.cpp:304] Persisting metadata (8 bytes) > > to leveldb took 10.406883ms > > I0428 20:59:24.801355 15954 replica.cpp:320] Persisted replica status to > > STARTING > > I0428 20:59:24.801453 15954 recover.cpp:451] Replica is in STARTING status > > I0428 20:59:24.802309 15969 replica.cpp:626] Replica in STARTING status > > received a broadcasted recover request > > I0428 20:59:24.802391 15964 recover.cpp:188] Received a recover response > > from a replica in STARTING status > > I0428 20:59:24.802681 15954 recover.cpp:542] Updating replica status to > > VOTING > > I0428 20:59:24.813487 15963 leveldb.cpp:304] Persisting metadata (8 bytes) > > to leveldb took 10.134698ms > > I0428 20:59:24.813516 15963 replica.cpp:320] Persisted replica status to > > VOTING > > I0428 20:59:24.813585 15963 recover.cpp:556] Successfully joined the Paxos > > group > > I0428 20:59:24.813649 15963 recover.cpp:440] Recover process terminated > > I0428 20:59:24.813904 15963 log.cpp:656] Attempting to start the writer > > I0428 20:59:24.815146 15958 replica.cpp:474] Replica received implicit > > promise request with proposal 1 > > I0428 20:59:24.825553 15958 leveldb.cpp:304] Persisting metadata (8 bytes) > > to leveldb took 10.387839ms > > I0428 20:59:24.825580 15958 replica.cpp:342] Persisted promised to 1 > > I0428 20:59:24.826100 15972 coordinator.cpp:229] Coordinator attemping to > > fill missing position > > I0428 20:59:24.827474 15957 replica.cpp:375] Replica received explicit > > promise request for position 0 with proposal 2 > > I0428 20:59:24.841668 15957 leveldb.cpp:341] Persisting action (8 bytes) > > to leveldb took 14.170623ms > > I0428 20:59:24.841699 15957 replica.cpp:664] Persisted action at 0 > > I0428 20:59:24.843376 15968 replica.cpp:508] Replica received write > > request for position 0 > > I0428 20:59:24.843410 15968 leveldb.cpp:436] Reading position from leveldb > > took 11625ns > > I0428 20:59:24.858222 15968 leveldb.cpp:341] Persisting action (14 bytes) > > to leveldb took 14.794701ms > > I0428 20:59:24.858254 15968 replica.cpp:664] Persisted action at 0 > > I0428 20:59:24.858547 15961 replica.cpp:643] Replica received learned > > notice for position 0 > > I0428 20:59:24.870362 15961 leveldb.cpp:341] Persisting action (16 bytes) > > to leveldb took 11.789972ms > > I0428 20:59:24.870393 15961 replica.cpp:664] Persisted action at 0 > > I0428 20:59:24.870404 15961 replica.cpp:649] Replica learned NOP action at > > position 0 > > I0428 20:59:24.871217 15956 log.cpp:672] Writer started with ending > > position 0 > > I0428 20:59:24.872596 15971 leveldb.cpp:436] Reading position from leveldb > > took 14921ns > > I0428 20:59:24.873814 15955 registrar.cpp:308] Successfully recovered > > registrar > > I0428 20:59:24.873834 15955 registrar.cpp:379] Attempting to update the > > 'registry' > > I0428 20:59:24.875111 15966 log.cpp:680] Attempting to append 138 bytes to > > the log > > I0428 20:59:24.875331 15957 coordinator.cpp:339] Coordinator attempting to > > write APPEND action at position 1 > > I0428 20:59:24.876457 15958 replica.cpp:508] Replica received write > > request for position 1 > > I0428 20:59:24.890455 15958 leveldb.cpp:341] Persisting action (157 bytes) > > to leveldb took 13.858394ms > > I0428 20:59:24.890476 15958 replica.cpp:664] Persisted action at 1 > > I0428 20:59:24.891136 15974 replica.cpp:643] Replica received learned > > notice for position 1 > > I0428 20:59:24.906605 15974 leveldb.cpp:341] Persisting action (159 bytes) > > to leveldb took 15.443876ms > > I0428 20:59:24.906637 15974 replica.cpp:664] Persisted action at 1 > > I0428 20:59:24.906649 15974 replica.cpp:649] Replica learned APPEND action > > at position 1 > > I0428 20:59:24.907068 15974 registrar.cpp:427] Successfully updated > > 'registry' > > I0428 20:59:24.907209 15971 log.cpp:699] Attempting to truncate the log to > > 1 > > I0428 20:59:24.907500 15971 coordinator.cpp:339] Coordinator attempting to > > write TRUNCATE action at position 2 > > I0428 20:59:24.909026 15971 master.cpp:780] Recovered 0 slaves from the > > Registry (100B) ; allowing 10mins for slaves to re-register > > I0428 20:59:24.909487 15970 replica.cpp:508] Replica received write > > request for position 2 > > I0428 20:59:24.912192 15971 slave.cpp:140] Slave started on 14)@ > > 140.211.11.27:46217 > > I0428 20:59:24.912210 15971 credentials.hpp:35] Loading credentials for > > authentication > > W0428 20:59:24.912390 15971 credentials.hpp:48] Failed to stat credentials > > file 'file:///tmp/ExternalContainerizerTest_Launch_Xlp4Xn/credential': No > > such file or directory > > I0428 20:59:24.912412 15971 slave.cpp:231] Slave using credential for: > > test-principal > > I0428 20:59:24.912499 15971 slave.cpp:244] Slave resources: cpus(*):2; > > mem(*):1024; disk(*):1024; ports(*):[31000-32000] > > I0428 20:59:24.912636 15971 slave.cpp:272] Slave hostname: > > hemera.apache.org > > I0428 20:59:24.912662 15971 slave.cpp:273] Slave checkpoint: false > > I0428 20:59:24.913218 15971 state.cpp:33] Recovering state from > > '/tmp/ExternalContainerizerTest_Launch_Xlp4Xn/meta' > > I0428 20:59:24.913355 15966 status_update_manager.cpp:193] Recovering > > status update manager > > I0428 20:59:24.913879 15966 slave.cpp:2943] Finished recovery > > I0428 20:59:24.914572 15732 sched.cpp:121] Version: 0.19.0 > > I0428 20:59:24.914970 15955 sched.cpp:217] New master detected at > > master@140.211.11.27:46217 > > I0428 20:59:24.915102 15955 sched.cpp:268] Authenticating with master > > master@140.211.11.27:46217 > > I0428 20:59:24.915230 15954 authenticatee.hpp:128] Creating new client > > SASL connection > > I0428 20:59:24.915446 15954 master.cpp:2795] Authenticating scheduler(11)@ > > 140.211.11.27:46217 > > I0428 20:59:24.915745 15969 authenticator.hpp:148] Creating new server > > SASL connection > > I0428 20:59:24.916017 15969 authenticatee.hpp:219] Received SASL > > authentication mechanisms: CRAM-MD5 > > I0428 20:59:24.916031 15969 authenticatee.hpp:245] Attempting to > > authenticate with mechanism 'CRAM-MD5' > > I0428 20:59:24.916050 15969 authenticator.hpp:254] Received SASL > > authentication start > > I0428 20:59:24.916157 15969 authenticator.hpp:342] Authentication requires > > more steps > > I0428 20:59:24.916192 15969 authenticatee.hpp:265] Received SASL > > authentication step > > I0428 20:59:24.916241 15969 authenticator.hpp:282] Received SASL > > authentication step > > I0428 20:59:24.916254 15969 auxprop.cpp:81] Request to lookup properties > > for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: ' > > hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > > I0428 20:59:24.916260 15969 auxprop.cpp:153] Looking up auxiliary property > > '*userPassword' > > I0428 20:59:24.916266 15969 auxprop.cpp:153] Looking up auxiliary property > > '*cmusaslsecretCRAM-MD5' > > I0428 20:59:24.916272 15969 auxprop.cpp:81] Request to lookup properties > > for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: ' > > hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > > I0428 20:59:24.916276 15969 auxprop.cpp:103] Skipping auxiliary property > > '*userPassword' since SASL_AUXPROP_AUTHZID == true > > I0428 20:59:24.916280 15969 auxprop.cpp:103] Skipping auxiliary property > > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > > I0428 20:59:24.916288 15969 authenticator.hpp:334] Authentication success > > I0428 20:59:24.916367 15953 authenticatee.hpp:305] Authentication success > > I0428 20:59:24.916576 15963 master.cpp:2835] Successfully authenticated > > scheduler(11)@140.211.11.27:46217 > > I0428 20:59:24.916590 15956 sched.cpp:342] Successfully authenticated with > > master master@140.211.11.27:46217 > > I0428 20:59:24.916671 15956 sched.cpp:461] Sending registration request to > > master@140.211.11.27:46217 > > I0428 20:59:24.916857 15963 master.cpp:981] Received registration request > > from scheduler(11)@140.211.11.27:46217 > > I0428 20:59:24.916887 15963 master.cpp:999] Registering framework > > 20140428-205924-453759884-46217-15732-0000 at scheduler(11)@ > > 140.211.11.27:46217 > > I0428 20:59:24.917032 15974 sched.cpp:392] Framework registered with > > 20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.917067 15963 hierarchical_allocator_process.hpp:332] Added > > framework 20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.917083 15963 hierarchical_allocator_process.hpp:726] No > > resources available to allocate! > > I0428 20:59:24.917093 15963 hierarchical_allocator_process.hpp:688] > > Performed allocation for 0 slaves in 10385ns > > I0428 20:59:24.917156 15974 sched.cpp:406] Scheduler::registered took > > 104111ns > > I0428 20:59:24.918953 15966 slave.cpp:525] New master detected at > > master@140.211.11.27:46217 > > I0428 20:59:24.918984 15966 slave.cpp:585] Authenticating with master > > master@140.211.11.27:46217 > > I0428 20:59:24.919020 15954 status_update_manager.cpp:167] New master > > detected at master@140.211.11.27:46217 > > I0428 20:59:24.919047 15961 authenticatee.hpp:128] Creating new client > > SASL connection > > I0428 20:59:24.919028 15966 slave.cpp:558] Detecting new master > > I0428 20:59:24.919312 15968 master.cpp:2795] Authenticating slave(14)@ > > 140.211.11.27:46217 > > I0428 20:59:24.919371 15968 authenticator.hpp:148] Creating new server > > SASL connection > > I0428 20:59:24.919842 15955 authenticatee.hpp:219] Received SASL > > authentication mechanisms: CRAM-MD5 > > I0428 20:59:24.919857 15955 authenticatee.hpp:245] Attempting to > > authenticate with mechanism 'CRAM-MD5' > > I0428 20:59:24.919914 15955 authenticator.hpp:254] Received SASL > > authentication start > > I0428 20:59:24.919999 15955 authenticator.hpp:342] Authentication requires > > more steps > > I0428 20:59:24.920488 15973 authenticatee.hpp:265] Received SASL > > authentication step > > I0428 20:59:24.920598 15973 authenticator.hpp:282] Received SASL > > authentication step > > I0428 20:59:24.920613 15973 auxprop.cpp:81] Request to lookup properties > > for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: ' > > hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > > I0428 20:59:24.920617 15973 auxprop.cpp:153] Looking up auxiliary property > > '*userPassword' > > I0428 20:59:24.920624 15973 auxprop.cpp:153] Looking up auxiliary property > > '*cmusaslsecretCRAM-MD5' > > I0428 20:59:24.920629 15973 auxprop.cpp:81] Request to lookup properties > > for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: ' > > hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > > I0428 20:59:24.920632 15973 auxprop.cpp:103] Skipping auxiliary property > > '*userPassword' since SASL_AUXPROP_AUTHZID == true > > I0428 20:59:24.920635 15973 auxprop.cpp:103] Skipping auxiliary property > > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > > I0428 20:59:24.920642 15973 authenticator.hpp:334] Authentication success > > I0428 20:59:24.920753 15973 authenticatee.hpp:305] Authentication success > > I0428 20:59:24.920769 15955 master.cpp:2835] Successfully authenticated > > slave(14)@140.211.11.27:46217 > > I0428 20:59:24.921393 15976 slave.cpp:642] Successfully authenticated with > > master master@140.211.11.27:46217 > > I0428 20:59:24.921447 15976 slave.cpp:871] Will retry registration in > > 6.085179816secs if necessary > > I0428 20:59:24.921664 15953 registrar.cpp:379] Attempting to update the > > 'registry' > > I0428 20:59:24.922801 15970 leveldb.cpp:341] Persisting action (16 bytes) > > to leveldb took 13.290713ms > > I0428 20:59:24.922821 15970 replica.cpp:664] Persisted action at 2 > > I0428 20:59:24.923069 15968 replica.cpp:643] Replica received learned > > notice for position 2 > > I0428 20:59:24.939198 15968 leveldb.cpp:341] Persisting action (18 bytes) > > to leveldb took 16.114121ms > > I0428 20:59:24.939267 15968 leveldb.cpp:399] Deleting ~1 keys from leveldb > > took 47686ns > > I0428 20:59:24.939277 15968 replica.cpp:664] Persisted action at 2 > > I0428 20:59:24.939283 15968 replica.cpp:649] Replica learned TRUNCATE > > action at position 2 > > I0428 20:59:24.939585 15974 log.cpp:680] Attempting to append 332 bytes to > > the log > > I0428 20:59:24.939746 15975 coordinator.cpp:339] Coordinator attempting to > > write APPEND action at position 3 > > I0428 20:59:24.941287 15958 replica.cpp:508] Replica received write > > request for position 3 > > I0428 20:59:24.955303 15958 leveldb.cpp:341] Persisting action (351 bytes) > > to leveldb took 14.000606ms > > I0428 20:59:24.955324 15958 replica.cpp:664] Persisted action at 3 > > I0428 20:59:24.956228 15957 replica.cpp:643] Replica received learned > > notice for position 3 > > I0428 20:59:24.971446 15957 leveldb.cpp:341] Persisting action (353 bytes) > > to leveldb took 15.190795ms > > I0428 20:59:24.971477 15957 replica.cpp:664] Persisted action at 3 > > I0428 20:59:24.971489 15957 replica.cpp:649] Replica learned APPEND action > > at position 3 > > I0428 20:59:24.971849 15976 registrar.cpp:427] Successfully updated > > 'registry' > > I0428 20:59:24.972170 15974 master.cpp:2169] Admitted slave on > > hemera.apache.org at slave(14)@140.211.11.27:46217 > > I0428 20:59:24.972194 15974 master.cpp:3283] Adding slave > > 20140428-205924-453759884-46217-15732-0 at hemera.apache.org with > > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] > > I0428 20:59:24.972244 15967 log.cpp:699] Attempting to truncate the log to > > 3 > > I0428 20:59:24.972283 15959 slave.cpp:675] Registered with master > > master@140.211.11.27:46217; given slave ID > > 20140428-205924-453759884-46217-15732-0 > > I0428 20:59:24.972452 15972 hierarchical_allocator_process.hpp:445] Added > > slave 20140428-205924-453759884-46217-15732-0 (hemera.apache.org) with > > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and > > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) > > I0428 20:59:24.972508 15971 coordinator.cpp:339] Coordinator attempting to > > write TRUNCATE action at position 4 > > I0428 20:59:24.972529 15972 hierarchical_allocator_process.hpp:752] > > Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on > > slave 20140428-205924-453759884-46217-15732-0 to framework > > 20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.972769 15972 hierarchical_allocator_process.hpp:708] > > Performed allocation for slave 20140428-205924-453759884-46217-15732-0 in > > 267569ns > > I0428 20:59:24.972786 15955 master.hpp:586] Adding offer > > 20140428-205924-453759884-46217-15732-0 with resources cpus(*):2; > > mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > > 20140428-205924-453759884-46217-15732-0 (hemera.apache.org) > > I0428 20:59:24.972829 15955 master.cpp:2744] Sending 1 offers to framework > > 20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.972977 15955 sched.cpp:529] Scheduler::resourceOffers took > > 49221ns > > I0428 20:59:24.973989 15970 replica.cpp:508] Replica received write > > request for position 4 > > I0428 20:59:24.974783 15960 master.hpp:596] Removing offer > > 20140428-205924-453759884-46217-15732-0 with resources cpus(*):2; > > mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > > 20140428-205924-453759884-46217-15732-0 (hemera.apache.org) > > I0428 20:59:24.974851 15960 master.cpp:1806] Processing reply for offers: > > [ 20140428-205924-453759884-46217-15732-0 ] on slave > > 20140428-205924-453759884-46217-15732-0 (hemera.apache.org) for framework > > 20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.974926 15960 master.hpp:558] Adding task 1 with resources > > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > > 20140428-205924-453759884-46217-15732-0 (hemera.apache.org) > > I0428 20:59:24.974983 15960 master.cpp:2919] Launching task 1 of framework > > 20140428-205924-453759884-46217-15732-0000 with resources cpus(*):2; > > mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > > 20140428-205924-453759884-46217-15732-0 (hemera.apache.org) > > I0428 20:59:24.975106 15964 slave.cpp:905] Got assigned task 1 for > > framework 20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.975277 15964 slave.cpp:1015] Launching task 1 for framework > > 20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.976796 15964 slave.cpp:1125] Queuing task '1' for executor > > 1 of framework '20140428-205924-453759884-46217-15732-0000 > > I0428 20:59:24.976812 15960 external_containerizer.cpp:266] Launching > > container 'cf7caacc-a6cd-4111-a09d-32f3f59e23b7' > > I0428 20:59:24.976842 15964 slave.cpp:486] Successfully attached file > > '/tmp/ExternalContainerizerTest_Launch_Xlp4Xn/slaves/20140428-205924-453759884-46217-15732-0/frameworks/20140428-205924-453759884-46217-15732-0000/executors/1/runs/cf7caacc-a6cd-4111-a09d-32f3f59e23b7' > > I0428 20:59:24.976888 15960 external_containerizer.cpp:804] Invoking > > external containerizer for method 'launch' > > I0428 20:59:24.976913 15960 external_containerizer.cpp:818] user: jenkins > > I0428 20:59:24.988150 15970 leveldb.cpp:341] Persisting action (16 bytes) > > to leveldb took 14.136354ms > > I0428 20:59:24.988204 15970 replica.cpp:664] Persisted action at 4 > > I0428 20:59:24.991509 15954 replica.cpp:643] Replica received learned > > notice for position 4 > > I0428 20:59:25.004228 15954 leveldb.cpp:341] Persisting action (18 bytes) > > to leveldb took 12.701325ms > > I0428 20:59:25.004286 15954 leveldb.cpp:399] Deleting ~2 keys from leveldb > > took 34958ns > > I0428 20:59:25.004297 15954 replica.cpp:664] Persisted action at 4 > > I0428 20:59:25.004302 15954 replica.cpp:649] Replica learned TRUNCATE > > action at position 4 > > make[3]: *** [check-local] Broken pipe > > make[3]: Leaving directory > > `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' > > make[2]: *** [check-am] Error 2 > > make[2]: Leaving directory > > `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' > > make[1]: *** [check] Error 2 > > make[1]: Leaving directory > > `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' > > make: *** [check-recursive] Error 1 > > Build step 'Execute shell' marked build as failure > > >