See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2574/changes>
Changes: [tnachen] Fixed typo in DockerInfo comment. ------------------------------------------ [...truncated 16796 lines...] I1119 02:59:31.912489 15758 slave.cpp:323] Slave hostname: penates.apache.org I1119 02:59:31.912503 15758 slave.cpp:324] Slave checkpoint: false W1119 02:59:31.912526 15758 slave.cpp:326] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag I1119 02:59:31.913110 15768 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/meta' I1119 02:59:31.913322 15757 status_update_manager.cpp:197] Recovering status update manager I1119 02:59:31.913612 15760 slave.cpp:3483] Finished recovery I1119 02:59:31.914028 15762 status_update_manager.cpp:171] Pausing sending status updates I1119 02:59:31.914027 15766 slave.cpp:607] New master detected at [email protected]:37156 I1119 02:59:31.914083 15766 slave.cpp:670] Authenticating with master [email protected]:37156 I1119 02:59:31.914098 15766 slave.cpp:675] Using default CRAM-MD5 authenticatee I1119 02:59:31.914204 15766 slave.cpp:643] Detecting new master I1119 02:59:31.914296 15768 authenticatee.hpp:138] Creating new client SASL connection I1119 02:59:31.914536 15764 master.cpp:3898] Authenticating slave(35)@67.195.81.186:37156 I1119 02:59:31.914569 15764 master.cpp:3909] Using default CRAM-MD5 authenticator I1119 02:59:31.914778 15771 authenticator.hpp:170] Creating new server SASL connection I1119 02:59:31.914965 15766 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I1119 02:59:31.915004 15766 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I1119 02:59:31.915109 15766 authenticator.hpp:276] Received SASL authentication start I1119 02:59:31.915176 15766 authenticator.hpp:398] Authentication requires more steps I1119 02:59:31.915277 15766 authenticatee.hpp:275] Received SASL authentication step I1119 02:59:31.915379 15766 authenticator.hpp:304] Received SASL authentication step I1119 02:59:31.915406 15766 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1119 02:59:31.915419 15766 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1119 02:59:31.915463 15766 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1119 02:59:31.915503 15766 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1119 02:59:31.915546 15766 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1119 02:59:31.915555 15766 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1119 02:59:31.915573 15766 authenticator.hpp:390] Authentication success I1119 02:59:31.915699 15764 authenticatee.hpp:315] Authentication success I1119 02:59:31.915726 15763 master.cpp:3956] Successfully authenticated principal 'test-principal' at slave(35)@67.195.81.186:37156 I1119 02:59:31.916049 15769 slave.cpp:741] Successfully authenticated with master [email protected]:37156 I1119 02:59:31.916174 15769 slave.cpp:1069] Will retry registration in 16.434983ms if necessary I1119 02:59:31.916359 15767 master.cpp:3065] Registering slave at slave(35)@67.195.81.186:37156 (penates.apache.org) with id 20141119-025931-3125920579-37156-15742-S0 I1119 02:59:31.916836 15768 registrar.cpp:445] Applied 1 operations in 66300ns; attempting to update the 'registry' I1119 02:59:31.919772 15765 log.cpp:680] Attempting to append 318 bytes to the log I1119 02:59:31.919987 15757 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I1119 02:59:31.920851 15759 replica.cpp:508] Replica received write request for position 3 I1119 02:59:31.921396 15759 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took 510835ns I1119 02:59:31.921422 15759 replica.cpp:676] Persisted action at 3 I1119 02:59:31.922036 15763 replica.cpp:655] Replica received learned notice for position 3 I1119 02:59:31.922458 15763 leveldb.cpp:343] Persisting action (339 bytes) to leveldb took 397677ns I1119 02:59:31.922480 15763 replica.cpp:676] Persisted action at 3 I1119 02:59:31.922499 15763 replica.cpp:661] Replica learned APPEND action at position 3 I1119 02:59:31.923450 15766 registrar.cpp:490] Successfully updated the 'registry' in 6.55616ms I1119 02:59:31.923745 15768 log.cpp:699] Attempting to truncate the log to 3 I1119 02:59:31.923864 15758 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I1119 02:59:31.923981 15764 slave.cpp:2555] Received ping from slave-observer(37)@67.195.81.186:37156 I1119 02:59:31.924119 15763 master.cpp:3119] Registered slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1119 02:59:31.924207 15757 hierarchical_allocator_process.hpp:442] Added slave 20141119-025931-3125920579-37156-15742-S0 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I1119 02:59:31.924293 15768 slave.cpp:775] Registered with master [email protected]:37156; given slave ID 20141119-025931-3125920579-37156-15742-S0 I1119 02:59:31.924329 15757 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141119-025931-3125920579-37156-15742-S0 in 50857ns I1119 02:59:31.924382 15764 status_update_manager.cpp:178] Resuming sending status updates I1119 02:59:31.925037 15767 replica.cpp:508] Replica received write request for position 4 I1119 02:59:31.925439 15767 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 370797ns I1119 02:59:31.925464 15767 replica.cpp:676] Persisted action at 4 I1119 02:59:31.926019 15760 replica.cpp:655] Replica received learned notice for position 4 I1119 02:59:31.926561 15760 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 462087ns I1119 02:59:31.926631 15760 leveldb.cpp:401] Deleting ~2 keys from leveldb took 45800ns I1119 02:59:31.926651 15760 replica.cpp:676] Persisted action at 4 I1119 02:59:31.926671 15760 replica.cpp:661] Replica learned TRUNCATE action at position 4 I1119 02:59:31.930570 15742 sched.cpp:148] Version: 0.22.0 I1119 02:59:31.931162 15763 sched.cpp:245] New master detected at [email protected]:37156 I1119 02:59:31.931202 15763 sched.cpp:301] Authenticating with master [email protected]:37156 I1119 02:59:31.931216 15763 sched.cpp:308] Using default CRAM-MD5 authenticatee I1119 02:59:31.931495 15756 authenticatee.hpp:138] Creating new client SASL connection I1119 02:59:31.931715 15762 master.cpp:3898] Authenticating [email protected]:37156 I1119 02:59:31.931756 15762 master.cpp:3909] Using default CRAM-MD5 authenticator I1119 02:59:31.931998 15761 authenticator.hpp:170] Creating new server SASL connection I1119 02:59:31.932162 15765 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I1119 02:59:31.932190 15765 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I1119 02:59:31.932289 15764 authenticator.hpp:276] Received SASL authentication start I1119 02:59:31.932354 15764 authenticator.hpp:398] Authentication requires more steps I1119 02:59:31.932431 15762 authenticatee.hpp:275] Received SASL authentication step I1119 02:59:31.932549 15768 authenticator.hpp:304] Received SASL authentication step I1119 02:59:31.932579 15768 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1119 02:59:31.932596 15768 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1119 02:59:31.932631 15768 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1119 02:59:31.932657 15768 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1119 02:59:31.932668 15768 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1119 02:59:31.932677 15768 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1119 02:59:31.932693 15768 authenticator.hpp:390] Authentication success I1119 02:59:31.932804 15769 authenticatee.hpp:315] Authentication success I1119 02:59:31.932821 15770 master.cpp:3956] Successfully authenticated principal 'test-principal' at [email protected]:37156 I1119 02:59:31.933116 15758 sched.cpp:389] Successfully authenticated with master [email protected]:37156 I1119 02:59:31.933145 15758 sched.cpp:512] Sending registration request to [email protected]:37156 I1119 02:59:31.933208 15758 sched.cpp:545] Will retry registration in 1.216518162secs if necessary I1119 02:59:31.933315 15756 master.cpp:1383] Received registration request for framework 'default' at [email protected]:37156 I1119 02:59:31.933379 15756 master.cpp:1342] Authorizing framework principal 'test-principal' to receive offers for role '*' I1119 02:59:31.933747 15771 master.cpp:1447] Registering framework 20141119-025931-3125920579-37156-15742-0000 (default) at [email protected]:37156 I1119 02:59:31.933945 15770 hierarchical_allocator_process.hpp:329] Added framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.934069 15770 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141119-025931-3125920579-37156-15742-S0 to framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.934293 15768 sched.cpp:439] Framework registered with 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.968308 15768 sched.cpp:453] Scheduler::registered took 59573ns I1119 02:59:31.968502 15770 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 34.531229ms I1119 02:59:31.968915 15761 master.cpp:3840] Sending 1 offers to framework 20141119-025931-3125920579-37156-15742-0000 (default) at [email protected]:37156 I1119 02:59:31.969691 15761 sched.cpp:602] Scheduler::resourceOffers took 465196ns I1119 02:59:31.970494 15761 master.cpp:2356] Processing reply for offers: [ 20141119-025931-3125920579-37156-15742-O0 ] on slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) for framework 20141119-025931-3125920579-37156-15742-0000 (default) at [email protected]:37156 I1119 02:59:31.970546 15761 master.cpp:2454] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins' W1119 02:59:31.971421 15759 master.cpp:1991] Executor default for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1119 02:59:31.971474 15759 master.cpp:2003] Executor default for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1119 02:59:31.971773 15759 master.hpp:753] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20141119-025931-3125920579-37156-15742-S0 (penates.apache.org) I1119 02:59:31.971849 15759 master.cpp:2524] Launching task 0 of framework 20141119-025931-3125920579-37156-15742-0000 (default) at [email protected]:37156 with resources cpus(*):2; mem(*):1024 on slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:31.972167 15762 slave.cpp:1100] Got assigned task 0 for framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.972385 15758 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20141119-025931-3125920579-37156-15742-S0 from framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.972446 15758 hierarchical_allocator_process.hpp:599] Framework 20141119-025931-3125920579-37156-15742-0000 filtered slave 20141119-025931-3125920579-37156-15742-S0 for 5secs I1119 02:59:31.972719 15762 slave.cpp:1210] Launching task 0 for framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.975322 15762 slave.cpp:4014] Launching executor default of framework 20141119-025931-3125920579-37156-15742-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default/runs/f37d5cbe-a5d7-44aa-a038-c0df02b8d428' I1119 02:59:31.978160 15762 exec.cpp:132] Version: 0.22.0 I1119 02:59:31.978435 15765 exec.cpp:182] Executor started at: executor(24)@67.195.81.186:37156 with pid 15742 I1119 02:59:31.978632 15762 slave.cpp:1333] Queuing task '0' for executor default of framework '20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.978747 15762 slave.cpp:560] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default/runs/f37d5cbe-a5d7-44aa-a038-c0df02b8d428' I1119 02:59:31.978863 15762 slave.cpp:2857] Monitoring executor 'default' of framework '20141119-025931-3125920579-37156-15742-0000' in container 'f37d5cbe-a5d7-44aa-a038-c0df02b8d428' I1119 02:59:31.979090 15762 slave.cpp:1877] Got registration for executor 'default' of framework 20141119-025931-3125920579-37156-15742-0000 from executor(24)@67.195.81.186:37156 I1119 02:59:31.979413 15762 slave.cpp:1996] Flushing queued task 0 for executor 'default' of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.979583 15769 exec.cpp:206] Executor registered on slave 20141119-025931-3125920579-37156-15742-S0 I1119 02:59:31.981736 15769 exec.cpp:218] Executor::registered took 30494ns I1119 02:59:31.981909 15769 exec.cpp:293] Executor asked to run task '0' I1119 02:59:31.981983 15769 exec.cpp:302] Executor::launchTask took 52028ns I1119 02:59:31.984194 15769 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.984436 15763 slave.cpp:2232] Handling status update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 from executor(24)@67.195.81.186:37156 I1119 02:59:31.984705 15768 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.984755 15768 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.984959 15768 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 to the slave I1119 02:59:31.985205 15769 slave.cpp:2475] Forwarding the update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 to [email protected]:37156 I1119 02:59:31.985388 15769 slave.cpp:2402] Status update manager successfully handled status update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.985426 15769 slave.cpp:2408] Sending acknowledgement for status update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 to executor(24)@67.195.81.186:37156 I1119 02:59:31.985486 15759 master.cpp:3443] Forwarding status update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.985712 15757 exec.cpp:339] Executor received status update acknowledgement ed5e43e1-6819-4f97-9b79-1ea67d891256 for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.985838 15759 master.cpp:3415] Status update TASK_RUNNING (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 from slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:31.985891 15759 master.cpp:4690] Updating the latest state of task 0 of framework 20141119-025931-3125920579-37156-15742-0000 to TASK_RUNNING I1119 02:59:31.985975 15771 sched.cpp:693] Scheduler::statusUpdate took 60201ns I1119 02:59:31.986179 15771 master.cpp:2915] Forwarding status update acknowledgement ed5e43e1-6819-4f97-9b79-1ea67d891256 for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 (default) at [email protected]:37156 to slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:31.986732 15761 status_update_manager.cpp:389] Received status update acknowledgement (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:31.986855 15763 slave.cpp:2915] Executor 'default' of framework 20141119-025931-3125920579-37156-15742-0000 exited with status 0 I1119 02:59:32.041244 15763 slave.cpp:2232] Handling status update TASK_LOST (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 from @0.0.0.0:0 I1119 02:59:32.041393 15763 slave.cpp:4322] Terminating task 0 I1119 02:59:32.041899 15763 slave.cpp:1817] Status update manager successfully handled status update acknowledgement (UUID: ed5e43e1-6819-4f97-9b79-1ea67d891256) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.042140 15769 master.cpp:3495] Executor default of framework 20141119-025931-3125920579-37156-15742-0000 on slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) exited with status 0 I1119 02:59:32.042253 15769 master.cpp:4778] Removing executor 'default' with resources of framework 20141119-025931-3125920579-37156-15742-0000 on slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:32.042270 15764 status_update_manager.cpp:317] Received status update TASK_LOST (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.042366 15764 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 to the slave I1119 02:59:32.042608 15769 slave.cpp:2475] Forwarding the update TASK_LOST (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 to [email protected]:37156 I1119 02:59:32.042780 15769 slave.cpp:2402] Status update manager successfully handled status update TASK_LOST (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.042935 15763 master.cpp:3443] Forwarding status update TASK_LOST (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.043079 15763 master.cpp:3415] Status update TASK_LOST (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 from slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:32.043192 15763 master.cpp:4690] Updating the latest state of task 0 of framework 20141119-025931-3125920579-37156-15742-0000 to TASK_LOST I1119 02:59:32.043195 15757 sched.cpp:693] Scheduler::statusUpdate took 22670ns I1119 02:59:32.043453 15757 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141119-025931-3125920579-37156-15742-S0 from framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.043570 15763 master.cpp:4749] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20141119-025931-3125920579-37156-15742-0000 on slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:32.043726 15763 master.cpp:2915] Forwarding status update acknowledgement 996ffd49-6321-478a-b211-4168ddb9abbf for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 (default) at [email protected]:37156 to slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:32.044047 15759 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.044219 15759 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.044585 15759 slave.cpp:1817] Status update manager successfully handled status update acknowledgement (UUID: 996ffd49-6321-478a-b211-4168ddb9abbf) for task 0 of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.044634 15759 slave.cpp:4361] Completing task 0 I1119 02:59:32.044666 15759 slave.cpp:3024] Cleaning up executor 'default' of framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.044916 15757 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default/runs/f37d5cbe-a5d7-44aa-a038-c0df02b8d428' for gc 1.00000002239238weeks in the future I1119 02:59:32.045053 15759 slave.cpp:3101] Cleaning up framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.045150 15757 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default' for gc 1.00000002239238weeks in the future I1119 02:59:32.045217 15768 status_update_manager.cpp:279] Closing status update streams for framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.045274 15757 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000' for gc 1.00000002239238weeks in the future I1119 02:59:32.058728 15763 master.cpp:126] No whitelist given. Advertising offers for all slaves I1119 02:59:32.058851 15771 hierarchical_allocator_process.hpp:734] Offering disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20141119-025931-3125920579-37156-15742-S0 to framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.059254 15771 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 654084ns I1119 02:59:32.059844 15765 slave.cpp:3254] Framework 20141119-025929-3125920579-37156-15742-0000 seems to have exited. Ignoring registration timeout for executor 'default' I1119 02:59:32.059828 15761 slave.cpp:3254] Framework 20141119-025931-3125920579-37156-15742-0000 seems to have exited. Ignoring registration timeout for executor 'default' I1119 02:59:32.059897 15759 master.cpp:3840] Sending 1 offers to framework 20141119-025931-3125920579-37156-15742-0000 (default) at [email protected]:37156 I1119 02:59:32.059965 15760 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_wZTB3C/slaves/20141119-025929-3125920579-37156-15742-S0/frameworks/20141119-025929-3125920579-37156-15742-0000/executors/default/runs/8bd40b24-8aec-4e2e-8b0a-1270c5ccb431 I1119 02:59:32.059991 15761 slave.cpp:2630] No pings from master received within 75secs I1119 02:59:32.060104 15760 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_wZTB3C/slaves/20141119-025929-3125920579-37156-15742-S0/frameworks/20141119-025929-3125920579-37156-15742-0000/executors/default/runs/8bd40b24-8aec-4e2e-8b0a-1270c5ccb431' I1119 02:59:32.060112 15765 slave.cpp:2630] No pings from master received within 75secs I1119 02:59:32.060181 15761 slave.cpp:2555] Received ping from slave-observer(37)@67.195.81.186:37156 I1119 02:59:32.060235 15760 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_wZTB3C/slaves/20141119-025929-3125920579-37156-15742-S0/frameworks/20141119-025929-3125920579-37156-15742-0000/executors/default I1119 02:59:32.060307 15756 sched.cpp:602] Scheduler::resourceOffers took 42221ns I1119 02:59:32.060305 15765 slave.cpp:3338] Current usage 7.68%. Max allowed age: 5.762085742598680days I1119 02:59:32.060369 15761 slave.cpp:3338] Current usage 7.68%. Max allowed age: 5.762085742598680days I1119 02:59:32.060422 15760 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_wZTB3C/slaves/20141119-025929-3125920579-37156-15742-S0/frameworks/20141119-025929-3125920579-37156-15742-0000/executors/default' I1119 02:59:32.060462 15760 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_wZTB3C/slaves/20141119-025929-3125920579-37156-15742-S0/frameworks/20141119-025929-3125920579-37156-15742-0000 I1119 02:59:32.060461 15765 slave.cpp:3338] Current usage 7.68%. Max allowed age: 5.762085742598680days I1119 02:59:32.060561 15758 gc.cpp:167] Pruning directories with remaining removal time 13.542912ms I1119 02:59:32.165886 15760 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_wZTB3C/slaves/20141119-025929-3125920579-37156-15742-S0/frameworks/20141119-025929-3125920579-37156-15742-0000' *** Aborted at 1416365972 (unix time) try "date -d @1416365972" if you are using GNU date *** I1119 02:59:32.060668 15762 status_update_manager.cpp:171] Pausing sending status updates I1119 02:59:32.060581 15761 slave.cpp:596] Re-detecting master I1119 02:59:32.165956 15758 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default/runs/f37d5cbe-a5d7-44aa-a038-c0df02b8d428 I1119 02:59:32.165976 15761 slave.cpp:643] Detecting new master I1119 02:59:32.166049 15758 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default/runs/f37d5cbe-a5d7-44aa-a038-c0df02b8d428' PC: @ 0x2b77a45422d2 (unknown) I1119 02:59:32.166167 15758 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default *** SIGSEGV (@0x0) received by PID 15742 (TID 0x2b77a81c9700) from PID 0; stack trace: *** I1119 02:59:32.166357 15758 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000/executors/default' I1119 02:59:32.166394 15758 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.166396 15756 slave.cpp:607] New master detected at [email protected]:37156 I1119 02:59:32.166448 15756 slave.cpp:670] Authenticating with master [email protected]:37156 I1119 02:59:32.166462 15756 slave.cpp:675] Using default CRAM-MD5 authenticatee I1119 02:59:32.166471 15770 status_update_manager.cpp:171] Pausing sending status updates I1119 02:59:32.166494 15758 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_yKvlmo/slaves/20141119-025931-3125920579-37156-15742-S0/frameworks/20141119-025931-3125920579-37156-15742-0000' @ 0x2b77a4d15340 (unknown) @ 0x2b77a45422d2 (unknown) I1119 02:59:32.166728 15756 slave.cpp:643] Detecting new master I1119 02:59:32.166844 15768 authenticatee.hpp:138] Creating new client SASL connection I1119 02:59:32.167078 15767 master.cpp:3898] Authenticating slave(35)@67.195.81.186:37156 I1119 02:59:32.167134 15767 master.cpp:3909] Using default CRAM-MD5 authenticator I1119 02:59:32.167429 15763 authenticator.hpp:170] Creating new server SASL connection I1119 02:59:32.167687 15756 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I1119 02:59:32.167717 15756 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I1119 02:59:32.167814 15762 authenticator.hpp:276] Received SASL authentication start I1119 02:59:32.167865 15762 authenticator.hpp:398] Authentication requires more steps I1119 02:59:32.167951 15762 authenticatee.hpp:275] Received SASL authentication step I1119 02:59:32.168047 15762 authenticator.hpp:304] Received SASL authentication step I1119 02:59:32.168081 15762 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1119 02:59:32.168102 15762 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1119 02:59:32.168148 15762 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1119 02:59:32.168174 15762 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1119 02:59:32.168185 15762 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1119 02:59:32.168193 15762 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1119 02:59:32.168210 15762 authenticator.hpp:390] Authentication success I1119 02:59:32.168323 15762 authenticatee.hpp:315] Authentication success I1119 02:59:32.168365 15756 master.cpp:3956] Successfully authenticated principal 'test-principal' at slave(35)@67.195.81.186:37156 I1119 02:59:32.168592 15758 slave.cpp:741] Successfully authenticated with master [email protected]:37156 I1119 02:59:32.168683 15758 slave.cpp:1027] Reregistering completed framework 20141119-025931-3125920579-37156-15742-0000 I1119 02:59:32.168954 15758 slave.cpp:1069] Will retry registration in 2.874671ms if necessary @ 0x2b77a2024cb3 process::Process<>::self() I1119 02:59:32.169278 15763 master.cpp:3192] Re-registering slave 20141119-025931-3125920579-37156-15742-S0 at slave(35)@67.195.81.186:37156 (penates.apache.org) I1119 02:59:32.169451 15758 slave.cpp:843] Re-registered with master [email protected]:37156 I1119 02:59:32.169610 15770 status_update_manager.cpp:178] Resuming sending status updates @ 0x2b77a2022d8c process::dispatch<>() @ 0x2b77a201e528 mesos::internal::Files::detach() @ 0x2b77a23319a9 mesos::internal::slave::Slave::detachFile() @ 0x2b77a23796e0 _ZZN7process8dispatchI7NothingN5mesos8internal5slave5SlaveERKSsSsEENS_6FutureIT_EERKNS_3PIDIT0_EEMSC_FS9_T1_ET2_ENKUlPNS_11ProcessBaseEE_clESL_ @ 0x2b77a239de1d _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingN5mesos8internal5slave5SlaveERKSsSsEENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSG_FSD_T1_ET2_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ @ 0x2b77a29ad48d std::function<>::operator()() @ 0x2b77a299480b process::ProcessBase::visit() @ 0x2b77a2999036 process::DispatchEvent::visit() @ 0x8fb932 process::ProcessBase::serve() @ 0x2b77a29912a4 process::ProcessManager::resume() @ 0x2b77a2985a63 process::schedule() @ 0x2b77a4d0d182 start_thread @ 0x2b77a501dfbd (unknown) make[3]: *** [check-local] Segmentation fault make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 Test finished Exit status: 2 Build step 'Execute shell' marked build as failure Recording test results
