You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/05/01 09:00:55 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1827
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1827/changes>
Changes:
[yujie.jay] Updated the 'contains' method in stout IntervalSet to support more
[yujie.jay] Added stream functions for stout Interval and IntervalSet.
[benjamin.hindman] Introduced base64::encode/decode in stout.
[benjamin.hindman] Decode percent-encoded paths.
[yujie.jay] Fixed gcc-4.8 compilation error for stout interval set.
------------------------------------------
[...truncated 13182 lines...]
I0501 07:00:51.211580 13641 replica.cpp:508] Replica received write request for position 0
I0501 07:00:51.211614 13641 leveldb.cpp:436] Reading position from leveldb took 13199ns
I0501 07:00:51.226241 13641 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 14.611042ms
I0501 07:00:51.226274 13641 replica.cpp:664] Persisted action at 0
I0501 07:00:51.226887 13626 replica.cpp:643] Replica received learned notice for position 0
I0501 07:00:51.242285 13626 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.373628ms
I0501 07:00:51.242307 13626 replica.cpp:664] Persisted action at 0
I0501 07:00:51.242316 13626 replica.cpp:649] Replica learned NOP action at position 0
I0501 07:00:51.242728 13626 log.cpp:672] Writer started with ending position 0
I0501 07:00:51.243640 13626 leveldb.cpp:436] Reading position from leveldb took 11261ns
I0501 07:00:51.245748 13642 registrar.cpp:306] Successfully recovered registrar
I0501 07:00:51.245782 13642 registrar.cpp:377] Attempting to update the 'registry'
I0501 07:00:51.248141 13636 log.cpp:680] Attempting to append 138 bytes to the log
I0501 07:00:51.248666 13628 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1
I0501 07:00:51.249835 13632 replica.cpp:508] Replica received write request for position 1
I0501 07:00:51.266405 13632 leveldb.cpp:341] Persisting action (157 bytes) to leveldb took 16.554549ms
I0501 07:00:51.266427 13632 replica.cpp:664] Persisted action at 1
I0501 07:00:51.266996 13625 replica.cpp:643] Replica received learned notice for position 1
I0501 07:00:51.282517 13625 leveldb.cpp:341] Persisting action (159 bytes) to leveldb took 15.503747ms
I0501 07:00:51.282538 13625 replica.cpp:664] Persisted action at 1
I0501 07:00:51.282547 13625 replica.cpp:649] Replica learned APPEND action at position 1
I0501 07:00:51.282857 13626 registrar.cpp:423] Successfully updated 'registry'
I0501 07:00:51.283257 13645 log.cpp:699] Attempting to truncate the log to 1
I0501 07:00:51.283357 13631 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2
I0501 07:00:51.284178 13625 master.cpp:780] Recovered 0 slaves from the Registry (100B) ; allowing 10mins for slaves to re-register
I0501 07:00:51.285364 13648 replica.cpp:508] Replica received write request for position 2
I0501 07:00:51.288507 13643 slave.cpp:140] Slave started on 25)@140.211.11.27:42848
I0501 07:00:51.288525 13643 credentials.hpp:35] Loading credentials for authentication
W0501 07:00:51.288687 13643 credentials.hpp:48] Failed to stat credentials file 'file:///tmp/ExternalContainerizerTest_Launch_G0T7HS/credential': No such file or directory
I0501 07:00:51.288748 13643 slave.cpp:231] Slave using credential for: test-principal
I0501 07:00:51.288836 13643 slave.cpp:244] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 07:00:51.288911 13643 slave.cpp:272] Slave hostname: hemera.apache.org
I0501 07:00:51.288944 13643 slave.cpp:273] Slave checkpoint: false
I0501 07:00:51.289327 13635 state.cpp:33] Recovering state from '/tmp/ExternalContainerizerTest_Launch_G0T7HS/meta'
I0501 07:00:51.289527 13632 status_update_manager.cpp:193] Recovering status update manager
I0501 07:00:51.290359 13627 slave.cpp:2943] Finished recovery
I0501 07:00:51.291780 13372 sched.cpp:121] Version: 0.19.0
I0501 07:00:51.291949 13645 sched.cpp:217] New master detected at master@140.211.11.27:42848
I0501 07:00:51.291965 13645 sched.cpp:268] Authenticating with master master@140.211.11.27:42848
I0501 07:00:51.292112 13632 authenticatee.hpp:128] Creating new client SASL connection
I0501 07:00:51.292457 13637 master.cpp:2795] Authenticating scheduler(25)@140.211.11.27:42848
I0501 07:00:51.292531 13637 authenticator.hpp:148] Creating new server SASL connection
I0501 07:00:51.292620 13637 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0501 07:00:51.292665 13637 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0501 07:00:51.292721 13637 authenticator.hpp:254] Received SASL authentication start
I0501 07:00:51.292760 13637 authenticator.hpp:342] Authentication requires more steps
I0501 07:00:51.292790 13637 authenticatee.hpp:265] Received SASL authentication step
I0501 07:00:51.292999 13637 authenticator.hpp:282] Received SASL authentication step
I0501 07:00:51.293027 13637 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
I0501 07:00:51.293033 13637 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0501 07:00:51.293041 13637 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0501 07:00:51.293047 13637 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
I0501 07:00:51.293051 13637 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0501 07:00:51.293054 13637 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0501 07:00:51.293061 13637 authenticator.hpp:334] Authentication success
I0501 07:00:51.293083 13637 authenticatee.hpp:305] Authentication success
I0501 07:00:51.293144 13636 master.cpp:2835] Successfully authenticated scheduler(25)@140.211.11.27:42848
I0501 07:00:51.293171 13637 sched.cpp:342] Successfully authenticated with master master@140.211.11.27:42848
I0501 07:00:51.293246 13637 sched.cpp:461] Sending registration request to master@140.211.11.27:42848
I0501 07:00:51.293509 13636 master.cpp:981] Received registration request from scheduler(25)@140.211.11.27:42848
I0501 07:00:51.293542 13636 master.cpp:999] Registering framework 20140501-070051-453759884-42848-13372-0000 at scheduler(25)@140.211.11.27:42848
I0501 07:00:51.293658 13636 hierarchical_allocator_process.hpp:332] Added framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.293679 13636 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:00:51.293690 13636 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 11397ns
I0501 07:00:51.293761 13638 sched.cpp:392] Framework registered with 20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.293809 13638 sched.cpp:406] Scheduler::registered took 32363ns
I0501 07:00:51.294795 13627 slave.cpp:525] New master detected at master@140.211.11.27:42848
I0501 07:00:51.294816 13627 slave.cpp:585] Authenticating with master master@140.211.11.27:42848
I0501 07:00:51.294843 13644 status_update_manager.cpp:167] New master detected at master@140.211.11.27:42848
I0501 07:00:51.294862 13627 slave.cpp:558] Detecting new master
I0501 07:00:51.294953 13650 authenticatee.hpp:128] Creating new client SASL connection
I0501 07:00:51.295195 13650 master.cpp:2795] Authenticating slave(25)@140.211.11.27:42848
I0501 07:00:51.295284 13642 authenticator.hpp:148] Creating new server SASL connection
I0501 07:00:51.295533 13647 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0501 07:00:51.295635 13647 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0501 07:00:51.295660 13647 authenticator.hpp:254] Received SASL authentication start
I0501 07:00:51.295799 13647 authenticator.hpp:342] Authentication requires more steps
I0501 07:00:51.295912 13650 authenticatee.hpp:265] Received SASL authentication step
I0501 07:00:51.296104 13627 authenticator.hpp:282] Received SASL authentication step
I0501 07:00:51.296123 13627 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
I0501 07:00:51.296128 13627 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0501 07:00:51.296133 13627 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0501 07:00:51.296139 13627 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
I0501 07:00:51.296141 13627 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0501 07:00:51.296144 13627 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0501 07:00:51.296151 13627 authenticator.hpp:334] Authentication success
I0501 07:00:51.296680 13624 master.cpp:2835] Successfully authenticated slave(25)@140.211.11.27:42848
I0501 07:00:51.296762 13635 authenticatee.hpp:305] Authentication success
I0501 07:00:51.297106 13644 slave.cpp:642] Successfully authenticated with master master@140.211.11.27:42848
I0501 07:00:51.297161 13644 slave.cpp:871] Will retry registration in 7.460902695secs if necessary
I0501 07:00:51.297330 13636 registrar.cpp:377] Attempting to update the 'registry'
I0501 07:00:51.298668 13648 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 13.257495ms
I0501 07:00:51.298686 13648 replica.cpp:664] Persisted action at 2
I0501 07:00:51.299573 13644 replica.cpp:643] Replica received learned notice for position 2
I0501 07:00:51.314925 13644 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.332463ms
I0501 07:00:51.314982 13644 leveldb.cpp:399] Deleting ~1 keys from leveldb took 33719ns
I0501 07:00:51.314996 13644 replica.cpp:664] Persisted action at 2
I0501 07:00:51.315006 13644 replica.cpp:649] Replica learned TRUNCATE action at position 2
I0501 07:00:51.315353 13637 log.cpp:680] Attempting to append 332 bytes to the log
I0501 07:00:51.315500 13650 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3
I0501 07:00:51.316478 13640 replica.cpp:508] Replica received write request for position 3
I0501 07:00:51.331027 13640 leveldb.cpp:341] Persisting action (351 bytes) to leveldb took 14.48401ms
I0501 07:00:51.331049 13640 replica.cpp:664] Persisted action at 3
I0501 07:00:51.331428 13646 replica.cpp:643] Replica received learned notice for position 3
I0501 07:00:51.347137 13646 leveldb.cpp:341] Persisting action (353 bytes) to leveldb took 15.685414ms
I0501 07:00:51.347172 13646 replica.cpp:664] Persisted action at 3
I0501 07:00:51.347189 13646 replica.cpp:649] Replica learned APPEND action at position 3
I0501 07:00:51.347950 13646 registrar.cpp:423] Successfully updated 'registry'
I0501 07:00:51.348151 13645 master.cpp:2169] Admitted slave on hemera.apache.org at slave(25)@140.211.11.27:42848
I0501 07:00:51.348168 13645 master.cpp:3283] Adding slave 20140501-070051-453759884-42848-13372-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0501 07:00:51.348234 13646 log.cpp:699] Attempting to truncate the log to 3
I0501 07:00:51.348423 13642 slave.cpp:675] Registered with master master@140.211.11.27:42848; given slave ID 20140501-070051-453759884-42848-13372-0
I0501 07:00:51.348464 13630 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4
I0501 07:00:51.348515 13650 hierarchical_allocator_process.hpp:445] Added slave 20140501-070051-453759884-42848-13372-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)
I0501 07:00:51.348570 13650 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140501-070051-453759884-42848-13372-0 to framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.348737 13650 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140501-070051-453759884-42848-13372-0 in 187676ns
I0501 07:00:51.348834 13641 master.hpp:586] Adding offer 20140501-070051-453759884-42848-13372-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140501-070051-453759884-42848-13372-0 (hemera.apache.org)
I0501 07:00:51.348894 13641 master.cpp:2744] Sending 1 offers to framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.350368 13626 sched.cpp:529] Scheduler::resourceOffers took 21818ns
I0501 07:00:51.351338 13638 master.hpp:596] Removing offer 20140501-070051-453759884-42848-13372-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140501-070051-453759884-42848-13372-0 (hemera.apache.org)
I0501 07:00:51.351408 13638 master.cpp:1806] Processing reply for offers: [ 20140501-070051-453759884-42848-13372-0 ] on slave 20140501-070051-453759884-42848-13372-0 (hemera.apache.org) for framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.351485 13638 master.hpp:558] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140501-070051-453759884-42848-13372-0 (hemera.apache.org)
I0501 07:00:51.351537 13638 master.cpp:2919] Launching task 1 of framework 20140501-070051-453759884-42848-13372-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140501-070051-453759884-42848-13372-0 (hemera.apache.org)
I0501 07:00:51.351639 13650 replica.cpp:508] Replica received write request for position 4
I0501 07:00:51.351678 13644 slave.cpp:905] Got assigned task 1 for framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.351935 13644 slave.cpp:1015] Launching task 1 for framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.354292 13644 slave.cpp:1125] Queuing task '1' for executor 1 of framework '20140501-070051-453759884-42848-13372-0000
I0501 07:00:51.354296 13636 external_containerizer.cpp:266] Launching container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:51.354468 13644 slave.cpp:486] Successfully attached file '/tmp/ExternalContainerizerTest_Launch_G0T7HS/slaves/20140501-070051-453759884-42848-13372-0/frameworks/20140501-070051-453759884-42848-13372-0000/executors/1/runs/c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:51.354500 13636 external_containerizer.cpp:804] Invoking external containerizer for method 'launch'
I0501 07:00:51.354524 13636 external_containerizer.cpp:818] user: jenkins
I0501 07:00:51.367812 13650 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 16.116556ms
I0501 07:00:51.367878 13650 replica.cpp:664] Persisted action at 4
I0501 07:00:51.370427 13644 replica.cpp:643] Replica received learned notice for position 4
I0501 07:00:51.372298 13636 external_containerizer.cpp:378] Wait triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:51.383888 13644 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 13.433016ms
I0501 07:00:51.383946 13644 leveldb.cpp:399] Deleting ~2 keys from leveldb took 34437ns
I0501 07:00:51.383961 13644 replica.cpp:664] Persisted action at 4
I0501 07:00:51.383972 13644 replica.cpp:649] Replica learned TRUNCATE action at position 4
I0501 07:00:52.140389 13626 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 85862ns
I0501 07:00:53.140753 13629 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 47914ns
I0501 07:00:53.414579 13650 slave.cpp:1598] Got registration for executor '1' of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:53.415818 13637 external_containerizer.cpp:476] Update triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.415915 13650 slave.cpp:1717] Flushing queued task 1 for executor '1' of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:53.421033 13639 slave.cpp:1953] Handling status update TASK_RUNNING (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 from executor(1)@140.211.11.27:39840
I0501 07:00:53.421875 13645 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:53.421900 13645 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:53.421999 13645 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 to master@140.211.11.27:42848
I0501 07:00:53.422152 13645 slave.cpp:2070] Status update manager successfully handled status update TASK_RUNNING (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:53.422169 13645 slave.cpp:2076] Sending acknowledgement for status update TASK_RUNNING (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 to executor(1)@140.211.11.27:39840
I0501 07:00:53.422209 13639 master.cpp:2444] Status update TASK_RUNNING (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 from slave(25)@140.211.11.27:42848
I0501 07:00:53.422808 13639 sched.cpp:620] Scheduler::statusUpdate took 524610ns
I0501 07:00:53.423457 13639 status_update_manager.cpp:398] Received status update acknowledgement (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:53.423647 13629 external_containerizer.cpp:550] Usage triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.424018 13639 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: fa665b26-ac04-44ae-baf5-3cdf12c0f103) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:53.641095 13636 external_containerizer.cpp:341] Launch validation callback triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.641125 13636 external_containerizer.cpp:350] Launch finishing up for container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.641343 13626 slave.cpp:2282] Monitoring executor '1' of framework '20140501-070051-453759884-42848-13372-0000' in container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.641368 13636 external_containerizer.cpp:396] Wait continuation triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.641584 13636 external_containerizer.cpp:804] Invoking external containerizer for method 'wait'
I0501 07:00:53.641598 13636 external_containerizer.cpp:818] user: jenkins
I0501 07:00:53.656523 13636 external_containerizer.cpp:496] Update continuation triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.656579 13636 external_containerizer.cpp:804] Invoking external containerizer for method 'update'
I0501 07:00:53.656589 13636 external_containerizer.cpp:818] user: jenkins
I0501 07:00:53.670213 13636 external_containerizer.cpp:568] Usage continuation on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:53.670243 13636 external_containerizer.cpp:804] Invoking external containerizer for method 'usage'
I0501 07:00:53.670279 13636 external_containerizer.cpp:818] user: jenkins
I0501 07:00:53.684376 13636 external_containerizer.cpp:364] Launch confirmation callback triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:54.141948 13647 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 33056ns
I0501 07:00:54.641621 13647 external_containerizer.cpp:532] Update callback triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:54.643296 13631 external_containerizer.cpp:611] Usage callback triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:54.643612 13631 external_containerizer.cpp:550] Usage triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:54.644527 13631 external_containerizer.cpp:568] Usage continuation on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:54.644574 13631 external_containerizer.cpp:804] Invoking external containerizer for method 'usage'
I0501 07:00:54.644587 13631 external_containerizer.cpp:818] user: jenkins
I0501 07:00:54.647001 13648 master.cpp:1912] Asked to kill task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:54.647168 13648 master.cpp:1995] Telling slave 20140501-070051-453759884-42848-13372-0 (hemera.apache.org) to kill task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:54.647289 13648 slave.cpp:1181] Asked to kill task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.142683 13631 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 68195ns
I0501 07:00:55.422521 13642 slave.cpp:1953] Handling status update TASK_KILLED (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 from executor(1)@140.211.11.27:39840
I0501 07:00:55.422757 13642 slave.cpp:3444] Terminating task 1
I0501 07:00:55.424351 13639 status_update_manager.cpp:320] Received status update TASK_KILLED (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.424350 13628 external_containerizer.cpp:476] Update triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:55.424420 13639 status_update_manager.cpp:373] Forwarding status update TASK_KILLED (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 to master@140.211.11.27:42848
I0501 07:00:55.424490 13628 external_containerizer.cpp:496] Update continuation triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:55.424608 13639 slave.cpp:2070] Status update manager successfully handled status update TASK_KILLED (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.424695 13639 slave.cpp:2076] Sending acknowledgement for status update TASK_KILLED (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 to executor(1)@140.211.11.27:39840
I0501 07:00:55.424753 13628 external_containerizer.cpp:804] Invoking external containerizer for method 'update'
I0501 07:00:55.424759 13642 master.cpp:2444] Status update TASK_KILLED (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000 from slave(25)@140.211.11.27:42848
I0501 07:00:55.424808 13628 external_containerizer.cpp:818] user: jenkins
I0501 07:00:55.426136 13643 sched.cpp:620] Scheduler::statusUpdate took 1.029266ms
I0501 07:00:55.429708 13642 master.hpp:576] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140501-070051-453759884-42848-13372-0 (hemera.apache.org)
I0501 07:00:55.430090 13624 sched.cpp:731] Stopping framework '20140501-070051-453759884-42848-13372-0000'
I0501 07:00:55.430913 13642 master.cpp:551] Master terminating
I0501 07:00:55.430948 13650 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.431058 13650 status_update_manager.cpp:530] Cleaning up status update stream for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.431485 13638 hierarchical_allocator_process.hpp:637] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20140501-070051-453759884-42848-13372-0 from framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.431962 13642 slave.cpp:2151] master@140.211.11.27:42848 exited
W0501 07:00:55.431980 13642 slave.cpp:2154] Master disconnected! Waiting for a new master to be elected
I0501 07:00:55.432792 13650 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 0ed0a9d2-383e-44ef-9b98-ffba2aaa1c09) for task 1 of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.434615 13650 slave.cpp:3468] Completing task 1
I0501 07:00:55.443136 13639 external_containerizer.cpp:378] Wait triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:55.443490 13639 external_containerizer.cpp:637] Destroy triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:55.443513 13639 external_containerizer.cpp:396] Wait continuation triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:55.443521 13639 external_containerizer.cpp:804] Invoking external containerizer for method 'wait'
I0501 07:00:55.443526 13639 external_containerizer.cpp:818] user: jenkins
I0501 07:00:55.457641 13639 external_containerizer.cpp:655] Destroy continuation on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
I0501 07:00:55.457707 13639 external_containerizer.cpp:804] Invoking external containerizer for method 'destroy'
I0501 07:00:55.457738 13639 external_containerizer.cpp:818] user: jenkins
I0501 07:00:55.643882 13625 external_containerizer.cpp:448] Wait callback triggered on container 'c89752b7-803e-486b-b6e0-94d4f930f494'
E0501 07:00:55.644613 13635 slave.cpp:2324] Termination of executor '1' of framework '20140501-070051-453759884-42848-13372-0000' failed: External containerizer failed (status: 1)
I0501 07:00:55.645313 13635 slave.cpp:2479] Cleaning up executor '1' of framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.646059 13635 slave.cpp:2554] Cleaning up framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.646469 13650 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_G0T7HS/slaves/20140501-070051-453759884-42848-13372-0/frameworks/20140501-070051-453759884-42848-13372-0000/executors/1/runs/c89752b7-803e-486b-b6e0-94d4f930f494' for gc 6.99999252692148days in the future
I0501 07:00:55.646688 13643 status_update_manager.cpp:282] Closing status update streams for framework 20140501-070051-453759884-42848-13372-0000
I0501 07:00:55.646771 13650 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_G0T7HS/slaves/20140501-070051-453759884-42848-13372-0/frameworks/20140501-070051-453759884-42848-13372-0000/executors/1' for gc 6.9999925244days in the future
I0501 07:00:55.646834 13650 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_G0T7HS/slaves/20140501-070051-453759884-42848-13372-0/frameworks/20140501-070051-453759884-42848-13372-0000' for gc 6.99999251878519days in the future
../../src/tests/cluster.hpp:462: Failure
(wait).failure(): External containerizer failed (status: 1)
I0501 07:00:55.665557 13625 external_containerizer.cpp:762] Killed the following process tree/s:
[
--- 14101 ()
]
I0501 07:00:55.665576 13625 external_containerizer.cpp:717] Callback performing final cleanup of running state
F0501 07:00:55.665576 13372 logging.cpp:55] RAW: Pure virtual method called
@ 0x2b4dcd08714d google::LogMessage::Fail()
@ 0x2b4dcd08ecba google::RawLog__()
@ 0x51dff9 mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0x740509 mesos::internal::tests::MesosTest::ShutdownSlaves()
@ 0x8bf6fd testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8b7ce3 testing::Test::Run()
@ 0x8b7e16 testing::TestInfo::Run()
@ 0x8b7f57 testing::TestCase::Run()
@ 0x8b82be testing::internal::UnitTestImpl::RunAllTests()
@ 0x8bf27d testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8b738e testing::UnitTest::Run()
@ 0x4a5540 main
@ 0x2b4dce4d376d (unknown)
@ 0x4b0941 (unknown)
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/mesos-0.19.0/_build'
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1828
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1828/changes>