[
https://issues.apache.org/jira/browse/MESOS-2179?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14287061#comment-14287061
]
Yan Xu commented on MESOS-2179:
-------------------------------
Probably the same thing, with verbose logging. On fedora 19, gcc 4.8.3.
{noformat:title=}
[ RUN ] ExamplesTest.NoExecutorFramework
Using temporary directory '/tmp/ExamplesTest_NoExecutorFramework_tC0X7s'
Enabling authentication for the framework
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0121 19:51:14.115636 8641 process.cpp:958] libprocess is initialized on
192.168.122.135:37116 for 8 cpus
I0121 19:51:14.120729 8641 logging.cpp:177] Logging to STDERR
I0121 19:51:14.152959 8641 leveldb.cpp:176] Opened db in 8.8168ms
I0121 19:51:14.156491 8641 leveldb.cpp:183] Compacted db in 3.193842ms
I0121 19:51:14.156808 8641 leveldb.cpp:198] Created db iterator in 94922ns
I0121 19:51:14.156992 8641 leveldb.cpp:204] Seeked to beginning of db in
15993ns
I0121 19:51:14.157227 8641 leveldb.cpp:273] Iterated through 0 keys in the db
in 16728ns
I0121 19:51:14.157730 8641 replica.cpp:744] Replica recovered with log
positions 0 -> 0 with 1 holes and 0 unlearned
I0121 19:51:14.160706 8675 recover.cpp:449] Starting replica recovery
I0121 19:51:14.162055 8675 recover.cpp:475] Replica is in EMPTY status
I0121 19:51:14.166187 8674 replica.cpp:641] Replica in EMPTY status received a
broadcasted recover request
I0121 19:51:14.169301 8672 recover.cpp:195] Received a recover response from a
replica in EMPTY status
I0121 19:51:14.181504 8674 recover.cpp:566] Updating replica status to STARTING
I0121 19:51:14.182906 8674 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 161979ns
I0121 19:51:14.183089 8674 replica.cpp:323] Persisted replica status to
STARTING
I0121 19:51:14.183719 8678 recover.cpp:475] Replica is in STARTING status
I0121 19:51:14.188716 8678 replica.cpp:641] Replica in STARTING status
received a broadcasted recover request
I0121 19:51:14.190420 8675 recover.cpp:195] Received a recover response from a
replica in STARTING status
I0121 19:51:14.192960 8675 recover.cpp:566] Updating replica status to VOTING
I0121 19:51:14.193549 8675 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 63576ns
I0121 19:51:14.193707 8675 replica.cpp:323] Persisted replica status to VOTING
I0121 19:51:14.194000 8675 recover.cpp:580] Successfully joined the Paxos group
I0121 19:51:14.194406 8675 recover.cpp:464] Recover process terminated
I0121 19:51:14.227159 8673 master.cpp:262] Master
20150121-195114-2272962752-37116-8641 (fedora-19) started on
192.168.122.135:37116
I0121 19:51:14.227782 8673 master.cpp:308] Master only allowing authenticated
frameworks to register
I0121 19:51:14.227946 8673 master.cpp:315] Master allowing unauthenticated
slaves to register
I0121 19:51:14.228353 8673 credentials.hpp:36] Loading credentials for
authentication from '/tmp/ExamplesTest_NoExecutorFramework_tC0X7s/credentials'
W0121 19:51:14.228602 8673 credentials.hpp:51] Permissions on credentials file
'/tmp/ExamplesTest_NoExecutorFramework_tC0X7s/credentials' are too open. It is
recommended that your credentials file is NOT accessible by others.
I0121 19:51:14.229220 8673 master.cpp:357] Authorization enabled
I0121 19:51:14.231989 8678 hierarchical_allocator_process.hpp:285] Initialized
hierarchical allocator process
I0121 19:51:14.232770 8671 whitelist_watcher.cpp:65] No whitelist given
I0121 19:51:14.243178 8641 containerizer.cpp:103] Using isolation:
posix/cpu,posix/mem
I0121 19:51:14.274590 8670 slave.cpp:173] Slave started on
1)@192.168.122.135:37116
I0121 19:51:14.278079 8670 slave.cpp:300] Slave resources: cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000]
I0121 19:51:14.276814 8675 master.cpp:1219] The newly elected leader is
[email protected]:37116 with id 20150121-195114-2272962752-37116-8641
I0121 19:51:14.278583 8675 master.cpp:1232] Elected as the leading master!
I0121 19:51:14.278762 8675 master.cpp:1050] Recovering from registrar
I0121 19:51:14.279553 8675 registrar.cpp:313] Recovering registrar
I0121 19:51:14.283254 8671 log.cpp:660] Attempting to start the writer
I0121 19:51:14.285531 8670 slave.cpp:329] Slave hostname: fedora-19
I0121 19:51:14.287416 8670 slave.cpp:330] Slave checkpoint: true
I0121 19:51:14.293782 8669 replica.cpp:477] Replica received implicit promise
request with proposal 1
I0121 19:51:14.294481 8669 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 463270ns
I0121 19:51:14.295765 8669 replica.cpp:345] Persisted promised to 1
I0121 19:51:14.305960 8669 coordinator.cpp:230] Coordinator attemping to fill
missing position
I0121 19:51:14.307644 8670 state.cpp:33] Recovering state from
'/tmp/mesos-QmI8OW/0/meta'
I0121 19:51:14.307912 8641 containerizer.cpp:103] Using isolation:
posix/cpu,posix/mem
I0121 19:51:14.309798 8673 status_update_manager.cpp:197] Recovering status
update manager
I0121 19:51:14.310293 8673 containerizer.cpp:300] Recovering containerizer
I0121 19:51:14.314167 8674 replica.cpp:378] Replica received explicit promise
request for position 0 with proposal 2
I0121 19:51:14.314703 8674 leveldb.cpp:343] Persisting action (8 bytes) to
leveldb took 347853ns
I0121 19:51:14.314867 8674 replica.cpp:679] Persisted action at 0
I0121 19:51:14.317888 8678 slave.cpp:3519] Finished recovery
I0121 19:51:14.319116 8678 slave.cpp:613] New master detected at
[email protected]:37116
I0121 19:51:14.319300 8673 status_update_manager.cpp:171] Pausing sending
status updates
I0121 19:51:14.319743 8678 slave.cpp:638] No credentials provided. Attempting
to register without authentication
I0121 19:51:14.320118 8678 slave.cpp:649] Detecting new master
I0121 19:51:14.322443 8673 replica.cpp:511] Replica received write request for
position 0
I0121 19:51:14.322892 8673 leveldb.cpp:438] Reading position from leveldb took
275437ns
I0121 19:51:14.323266 8673 leveldb.cpp:343] Persisting action (14 bytes) to
leveldb took 177558ns
I0121 19:51:14.323432 8673 replica.cpp:679] Persisted action at 0
I0121 19:51:14.325742 8674 replica.cpp:658] Replica received learned notice
for position 0
I0121 19:51:14.330497 8674 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 54427ns
I0121 19:51:14.334075 8674 replica.cpp:679] Persisted action at 0
I0121 19:51:14.334116 8674 replica.cpp:664] Replica learned NOP action at
position 0
I0121 19:51:14.344169 8672 log.cpp:676] Writer started with ending position 0
I0121 19:51:14.350530 8678 slave.cpp:173] Slave started on
2)@192.168.122.135:37116
I0121 19:51:14.351302 8678 slave.cpp:300] Slave resources: cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000]
I0121 19:51:14.352298 8678 slave.cpp:329] Slave hostname: fedora-19
I0121 19:51:14.352496 8678 slave.cpp:330] Slave checkpoint: true
I0121 19:51:14.353791 8678 state.cpp:33] Recovering state from
'/tmp/mesos-QmI8OW/1/meta'
I0121 19:51:14.354337 8678 status_update_manager.cpp:197] Recovering status
update manager
I0121 19:51:14.355545 8669 containerizer.cpp:300] Recovering containerizer
I0121 19:51:14.356991 8678 slave.cpp:3519] Finished recovery
I0121 19:51:14.357880 8670 slave.cpp:613] New master detected at
[email protected]:37116
I0121 19:51:14.358054 8674 status_update_manager.cpp:171] Pausing sending
status updates
I0121 19:51:14.358474 8670 slave.cpp:638] No credentials provided. Attempting
to register without authentication
I0121 19:51:14.358829 8670 slave.cpp:649] Detecting new master
I0121 19:51:14.359315 8669 leveldb.cpp:438] Reading position from leveldb took
47636ns
I0121 19:51:14.367066 8641 containerizer.cpp:103] Using isolation:
posix/cpu,posix/mem
I0121 19:51:14.387127 8670 registrar.cpp:346] Successfully fetched the
registry (0B) in 107.149312ms
I0121 19:51:14.387872 8670 registrar.cpp:445] Applied 1 operations in
112080ns; attempting to update the 'registry'
I0121 19:51:14.398738 8672 log.cpp:684] Attempting to append 130 bytes to the
log
I0121 19:51:14.402883 8675 slave.cpp:173] Slave started on
3)@192.168.122.135:37116
I0121 19:51:14.403641 8675 slave.cpp:300] Slave resources: cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000]
I0121 19:51:14.404497 8675 slave.cpp:329] Slave hostname: fedora-19
I0121 19:51:14.404676 8675 slave.cpp:330] Slave checkpoint: true
I0121 19:51:14.405953 8675 state.cpp:33] Recovering state from
'/tmp/mesos-QmI8OW/2/meta'
I0121 19:51:14.406344 8669 status_update_manager.cpp:197] Recovering status
update manager
I0121 19:51:14.406780 8669 containerizer.cpp:300] Recovering containerizer
I0121 19:51:14.409160 8675 slave.cpp:3519] Finished recovery
I0121 19:51:14.410650 8672 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 1
I0121 19:51:14.412631 8675 slave.cpp:613] New master detected at
[email protected]:37116
I0121 19:51:14.412813 8674 status_update_manager.cpp:171] Pausing sending
status updates
I0121 19:51:14.413090 8675 slave.cpp:638] No credentials provided. Attempting
to register without authentication
I0121 19:51:14.413307 8675 slave.cpp:649] Detecting new master
I0121 19:51:14.414724 8674 replica.cpp:511] Replica received write request for
position 1
I0121 19:51:14.415055 8674 leveldb.cpp:343] Persisting action (149 bytes) to
leveldb took 159096ns
I0121 19:51:14.415213 8674 replica.cpp:679] Persisted action at 1
I0121 19:51:14.420723 8671 replica.cpp:658] Replica received learned notice
for position 1
I0121 19:51:14.421190 8671 leveldb.cpp:343] Persisting action (151 bytes) to
leveldb took 293776ns
I0121 19:51:14.421356 8671 replica.cpp:679] Persisted action at 1
I0121 19:51:14.421741 8671 replica.cpp:664] Replica learned APPEND action at
position 1
I0121 19:51:14.437811 8641 sched.cpp:151] Version: 0.22.0
I0121 19:51:14.439424 8670 sched.cpp:248] New master detected at
[email protected]:37116
I0121 19:51:14.439800 8670 sched.cpp:304] Authenticating with master
[email protected]:37116
I0121 19:51:14.439961 8670 sched.cpp:311] Using default CRAM-MD5 authenticatee
I0121 19:51:14.440927 8670 authenticatee.hpp:114] Initializing client SASL
I0121 19:51:14.442598 8671 registrar.cpp:490] Successfully updated the
'registry' in 54.46912ms
I0121 19:51:14.442919 8671 registrar.cpp:376] Successfully recovered registrar
I0121 19:51:14.443480 8671 master.cpp:1077] Recovered 0 slaves from the
Registry (94B) ; allowing 10mins for slaves to re-register
I0121 19:51:14.444520 8671 log.cpp:703] Attempting to truncate the log to 1
I0121 19:51:14.445137 8672 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 2
I0121 19:51:14.446367 8671 replica.cpp:511] Replica received write request for
position 2
I0121 19:51:14.446646 8671 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 74293ns
I0121 19:51:14.446802 8671 replica.cpp:679] Persisted action at 2
I0121 19:51:14.450125 8670 authenticatee.hpp:138] Creating new client SASL
connection
I0121 19:51:14.451232 8672 replica.cpp:658] Replica received learned notice
for position 2
I0121 19:51:14.451905 8672 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 115169ns
I0121 19:51:14.452100 8672 leveldb.cpp:401] Deleting ~1 keys from leveldb took
41782ns
I0121 19:51:14.452278 8672 replica.cpp:679] Persisted action at 2
I0121 19:51:14.452461 8672 replica.cpp:664] Replica learned TRUNCATE action at
position 2
I0121 19:51:14.453445 8674 master.cpp:4130] Authenticating
[email protected]:37116
I0121 19:51:14.453619 8674 master.cpp:4141] Using default CRAM-MD5
authenticator
I0121 19:51:14.454780 8674 authenticator.hpp:108] Initializing server SASL
I0121 19:51:14.455250 8674 auxprop.cpp:63] Initialized in-memory auxiliary
property plugin
I0121 19:51:14.455417 8674 authenticator.hpp:170] Creating new server SASL
connection
I0121 19:51:14.456435 8671 authenticatee.hpp:229] Received SASL authentication
mechanisms: CRAM-MD5
I0121 19:51:14.456629 8671 authenticatee.hpp:255] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0121 19:51:14.457243 8674 authenticator.hpp:276] Received SASL authentication
start
I0121 19:51:14.457502 8674 authenticator.hpp:398] Authentication requires more
steps
I0121 19:51:14.458173 8674 authenticatee.hpp:275] Received SASL authentication
step
I0121 19:51:14.458603 8671 authenticator.hpp:304] Received SASL authentication
step
I0121 19:51:14.458772 8671 auxprop.cpp:99] Request to lookup properties for
user: 'test-principal' realm: 'fedora-19' server FQDN: 'fedora-19'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0121 19:51:14.458930 8671 auxprop.cpp:171] Looking up auxiliary property
'*userPassword'
I0121 19:51:14.459221 8671 auxprop.cpp:171] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0121 19:51:14.459435 8671 auxprop.cpp:99] Request to lookup properties for
user: 'test-principal' realm: 'fedora-19' server FQDN: 'fedora-19'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0121 19:51:14.459614 8671 auxprop.cpp:121] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0121 19:51:14.459753 8671 auxprop.cpp:121] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0121 19:51:14.459939 8671 authenticator.hpp:390] Authentication success
I0121 19:51:14.460324 8671 master.cpp:4188] Successfully authenticated
principal 'test-principal' at
[email protected]:37116
I0121 19:51:14.461837 8674 authenticatee.hpp:315] Authentication success
I0121 19:51:14.462611 8673 sched.cpp:392] Successfully authenticated with
master [email protected]:37116
I0121 19:51:14.462779 8673 sched.cpp:515] Sending registration request to
[email protected]:37116
I0121 19:51:14.463177 8673 sched.cpp:548] Will retry registration in
423.208575ms if necessary
I0121 19:51:14.463728 8671 master.cpp:1417] Received registration request for
framework 'No Executor Framework (C++)' at
[email protected]:37116
I0121 19:51:14.464301 8671 master.cpp:1298] Authorizing framework principal
'test-principal' to receive offers for role '*'
I0121 19:51:14.466464 8671 master.cpp:1481] Registering framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116
I0121 19:51:14.469638 8675 hierarchical_allocator_process.hpp:319] Added
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.470137 8675 hierarchical_allocator_process.hpp:839] No
resources available to allocate!
I0121 19:51:14.470316 8675 hierarchical_allocator_process.hpp:746] Performed
allocation for 0 slaves in 510280ns
I0121 19:51:14.479981 8673 sched.cpp:442] Framework registered with
20150121-195114-2272962752-37116-8641-0000
Registered!
I0121 19:51:14.480509 8673 sched.cpp:456] Scheduler::registered took 346346ns
2015-01-21
19:51:14,588:6602(0x7fcb6e1d8700):ZOO_ERROR@handle_socket_error_msg@1697:
Socket [127.0.0.1:41330] zk retcode=-4, errno=111(Connection refused): server
refused to accept the client
I0121 19:51:14.653828 8672 slave.cpp:1075] Will retry registration in
1.92618421secs if necessary
I0121 19:51:14.654438 8678 master.cpp:3276] Registering slave at
slave(2)@192.168.122.135:37116 (fedora-19) with id
20150121-195114-2272962752-37116-8641-S0
I0121 19:51:14.655937 8678 registrar.cpp:445] Applied 1 operations in
164811ns; attempting to update the 'registry'
I0121 19:51:14.659087 8670 log.cpp:684] Attempting to append 300 bytes to the
log
I0121 19:51:14.659528 8670 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 3
I0121 19:51:14.660809 8671 replica.cpp:511] Replica received write request for
position 3
I0121 19:51:14.661075 8671 leveldb.cpp:343] Persisting action (319 bytes) to
leveldb took 82479ns
I0121 19:51:14.661232 8671 replica.cpp:679] Persisted action at 3
I0121 19:51:14.662647 8675 replica.cpp:658] Replica received learned notice
for position 3
I0121 19:51:14.663033 8675 leveldb.cpp:343] Persisting action (321 bytes) to
leveldb took 230138ns
I0121 19:51:14.663185 8675 replica.cpp:679] Persisted action at 3
I0121 19:51:14.663348 8675 replica.cpp:664] Replica learned APPEND action at
position 3
I0121 19:51:14.664737 8672 log.cpp:703] Attempting to truncate the log to 3
I0121 19:51:14.665122 8675 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 4
I0121 19:51:14.666667 8671 replica.cpp:511] Replica received write request for
position 4
I0121 19:51:14.667086 8671 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 235337ns
I0121 19:51:14.667232 8671 replica.cpp:679] Persisted action at 4
I0121 19:51:14.675336 8669 replica.cpp:658] Replica received learned notice
for position 4
I0121 19:51:14.675868 8669 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 70519ns
I0121 19:51:14.676066 8669 leveldb.cpp:401] Deleting ~2 keys from leveldb took
42370ns
I0121 19:51:14.676219 8669 replica.cpp:679] Persisted action at 4
I0121 19:51:14.676432 8669 replica.cpp:664] Replica learned TRUNCATE action at
position 4
I0121 19:51:14.677958 8678 registrar.cpp:490] Successfully updated the
'registry' in 21.797888ms
I0121 19:51:14.679576 8673 master.cpp:3330] Registered slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19) with cpus(*):2; mem(*):10240; disk(*):752; ports(*):[31000-32000]
I0121 19:51:14.680138 8674 slave.cpp:781] Registered with master
[email protected]:37116; given slave ID
20150121-195114-2272962752-37116-8641-S0
I0121 19:51:14.680521 8670 hierarchical_allocator_process.hpp:453] Added slave
20150121-195114-2272962752-37116-8641-S0 (fedora-19) with cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000] (and cpus(*):2; mem(*):10240;
disk(*):752; ports(*):[31000-32000] available)
I0121 19:51:14.681748 8674 slave.cpp:797] Checkpointing SlaveInfo to
'/tmp/mesos-QmI8OW/1/meta/slaves/20150121-195114-2272962752-37116-8641-S0/slave.info'
I0121 19:51:14.682660 8674 slave.cpp:2588] Received ping from
slave-observer(1)@192.168.122.135:37116
I0121 19:51:14.683770 8670 hierarchical_allocator_process.hpp:764] Performed
allocation for slave 20150121-195114-2272962752-37116-8641-S0 in 2.204096ms
I0121 19:51:14.684342 8673 master.cpp:4072] Sending 1 offers to framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116
I0121 19:51:14.684422 8671 status_update_manager.cpp:178] Resuming sending
status updates
Received offer 20150121-195114-2272962752-37116-8641-O0 with cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000]
Launching task 0 using offer 20150121-195114-2272962752-37116-8641-O0
Launching task 1 using offer 20150121-195114-2272962752-37116-8641-O0
I0121 19:51:14.687659 8673 sched.cpp:605] Scheduler::resourceOffers took
1.852558ms
I0121 19:51:14.691712 8669 master.cpp:2541] Processing reply for offers: [
20150121-195114-2272962752-37116-8641-O0 ] on slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19) for framework 20150121-195114-2272962752-37116-8641-0000 (No
Executor Framework (C++)) at
[email protected]:37116
I0121 19:51:14.691903 8669 master.cpp:2647] Authorizing framework principal
'test-principal' to launch task 0 as user 'jenkins'
I0121 19:51:14.692648 8669 master.cpp:2647] Authorizing framework principal
'test-principal' to launch task 1 as user 'jenkins'
I0121 19:51:14.695230 8669 master.hpp:766] Adding task 0 with resources
cpus(*):1; mem(*):128 on slave 20150121-195114-2272962752-37116-8641-S0
(fedora-19)
I0121 19:51:14.696316 8678 hierarchical_allocator_process.hpp:610] Updated
allocation of framework 20150121-195114-2272962752-37116-8641-0000 on slave
20150121-195114-2272962752-37116-8641-S0 from cpus(*):2; mem(*):10240;
disk(*):752; ports(*):[31000-32000] to cpus(*):2; mem(*):10240; disk(*):752;
ports(*):[31000-32000]
I0121 19:51:14.696725 8669 master.cpp:2897] Launching task 0 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 with
resources cpus(*):1; mem(*):128 on slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
I0121 19:51:14.697263 8670 slave.cpp:1130] Got assigned task 0 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.698909 8670 slave.cpp:1245] Launching task 0 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.701691 8669 master.hpp:766] Adding task 1 with resources
cpus(*):1; mem(*):128 on slave 20150121-195114-2272962752-37116-8641-S0
(fedora-19)
I0121 19:51:14.702658 8671 hierarchical_allocator_process.hpp:610] Updated
allocation of framework 20150121-195114-2272962752-37116-8641-0000 on slave
20150121-195114-2272962752-37116-8641-S0 from cpus(*):2; mem(*):10240;
disk(*):752; ports(*):[31000-32000] to cpus(*):2; mem(*):10240; disk(*):752;
ports(*):[31000-32000]
I0121 19:51:14.702878 8669 master.cpp:2897] Launching task 1 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 with
resources cpus(*):1; mem(*):128 on slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
I0121 19:51:14.704289 8670 slave.cpp:3921] Launching executor 0 of framework
20150121-195114-2272962752-37116-8641-0000 in work directory
'/tmp/mesos-QmI8OW/1/slaves/20150121-195114-2272962752-37116-8641-S0/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/0/runs/64af806e-92c2-48d7-bc61-7a330b8577c7'
I0121 19:51:14.707749 8673 hierarchical_allocator_process.hpp:653] Recovered
mem(*):9984; disk(*):752; ports(*):[31000-32000] (total allocatable:
mem(*):9984; disk(*):752; ports(*):[31000-32000]) on slave
20150121-195114-2272962752-37116-8641-S0 from framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.708039 8673 hierarchical_allocator_process.hpp:689] Framework
20150121-195114-2272962752-37116-8641-0000 filtered slave
20150121-195114-2272962752-37116-8641-S0 for 5secs
I0121 19:51:14.709586 8671 containerizer.cpp:445] Starting container
'64af806e-92c2-48d7-bc61-7a330b8577c7' for executor '0' of framework
'20150121-195114-2272962752-37116-8641-0000'
W0121 19:51:14.713291 8671 containerizer.cpp:296] CommandInfo.grace_period
flag is not set, using default value: 3secs
I0121 19:51:14.713896 8670 slave.cpp:1368] Queuing task '0' for executor 0 of
framework '20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.721793 8670 slave.cpp:1130] Got assigned task 1 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.722777 8670 slave.cpp:566] Successfully attached file
'/tmp/mesos-QmI8OW/1/slaves/20150121-195114-2272962752-37116-8641-S0/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/0/runs/64af806e-92c2-48d7-bc61-7a330b8577c7'
I0121 19:51:14.722982 8670 slave.cpp:1245] Launching task 1 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.727952 8671 launcher.cpp:137] Forked child with pid '8691' for
container '64af806e-92c2-48d7-bc61-7a330b8577c7'
I0121 19:51:14.732468 8670 slave.cpp:3921] Launching executor 1 of framework
20150121-195114-2272962752-37116-8641-0000 in work directory
'/tmp/mesos-QmI8OW/1/slaves/20150121-195114-2272962752-37116-8641-S0/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/1/runs/701bd453-89fe-4fec-986d-6398e70a52ad'
I0121 19:51:14.740541 8670 slave.cpp:1368] Queuing task '1' for executor 1 of
framework '20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.741684 8670 slave.cpp:566] Successfully attached file
'/tmp/mesos-QmI8OW/1/slaves/20150121-195114-2272962752-37116-8641-S0/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/1/runs/701bd453-89fe-4fec-986d-6398e70a52ad'
I0121 19:51:14.748491 8671 containerizer.cpp:445] Starting container
'701bd453-89fe-4fec-986d-6398e70a52ad' for executor '1' of framework
'20150121-195114-2272962752-37116-8641-0000'
W0121 19:51:14.763424 8671 containerizer.cpp:296] CommandInfo.grace_period
flag is not set, using default value: 3secs
I0121 19:51:14.767704 8673 slave.cpp:1075] Will retry registration in
805.728481ms if necessary
I0121 19:51:14.768028 8674 master.cpp:3276] Registering slave at
slave(3)@192.168.122.135:37116 (fedora-19) with id
20150121-195114-2272962752-37116-8641-S1
I0121 19:51:14.769175 8674 registrar.cpp:445] Applied 1 operations in
176561ns; attempting to update the 'registry'
I0121 19:51:14.773144 8675 log.cpp:684] Attempting to append 464 bytes to the
log
I0121 19:51:14.774767 8675 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 5
I0121 19:51:14.777834 8669 replica.cpp:511] Replica received write request for
position 5
I0121 19:51:14.781543 8669 leveldb.cpp:343] Persisting action (483 bytes) to
leveldb took 107736ns
I0121 19:51:14.781726 8669 replica.cpp:679] Persisted action at 5
I0121 19:51:14.793089 8673 replica.cpp:658] Replica received learned notice
for position 5
I0121 19:51:14.793658 8673 leveldb.cpp:343] Persisting action (485 bytes) to
leveldb took 74616ns
I0121 19:51:14.793808 8673 replica.cpp:679] Persisted action at 5
I0121 19:51:14.793977 8673 replica.cpp:664] Replica learned APPEND action at
position 5
I0121 19:51:14.795280 8672 log.cpp:703] Attempting to truncate the log to 5
I0121 19:51:14.795850 8673 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 6
I0121 19:51:14.798821 8673 replica.cpp:511] Replica received write request for
position 6
I0121 19:51:14.799293 8673 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 288448ns
I0121 19:51:14.803422 8673 replica.cpp:679] Persisted action at 6
I0121 19:51:14.819344 8670 replica.cpp:658] Replica received learned notice
for position 6
I0121 19:51:14.820241 8670 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 310075ns
I0121 19:51:14.820456 8670 leveldb.cpp:401] Deleting ~2 keys from leveldb took
41369ns
I0121 19:51:14.820611 8670 replica.cpp:679] Persisted action at 6
I0121 19:51:14.820808 8670 replica.cpp:664] Replica learned TRUNCATE action at
position 6
I0121 19:51:14.821955 8674 registrar.cpp:490] Successfully updated the
'registry' in 52.439296ms
I0121 19:51:14.823719 8674 master.cpp:3330] Registered slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19) with cpus(*):2; mem(*):10240; disk(*):752; ports(*):[31000-32000]
I0121 19:51:14.826968 8678 hierarchical_allocator_process.hpp:453] Added slave
20150121-195114-2272962752-37116-8641-S1 (fedora-19) with cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000] (and cpus(*):2; mem(*):10240;
disk(*):752; ports(*):[31000-32000] available)
I0121 19:51:14.827091 8673 slave.cpp:781] Registered with master
[email protected]:37116; given slave ID
20150121-195114-2272962752-37116-8641-S1
I0121 19:51:14.843492 8675 status_update_manager.cpp:178] Resuming sending
status updates
I0121 19:51:14.843891 8673 slave.cpp:797] Checkpointing SlaveInfo to
'/tmp/mesos-QmI8OW/2/meta/slaves/20150121-195114-2272962752-37116-8641-S1/slave.info'
I0121 19:51:14.844315 8673 slave.cpp:2588] Received ping from
slave-observer(2)@192.168.122.135:37116
I0121 19:51:14.845726 8678 hierarchical_allocator_process.hpp:764] Performed
allocation for slave 20150121-195114-2272962752-37116-8641-S1 in 10.033255ms
I0121 19:51:14.846364 8673 master.cpp:4072] Sending 1 offers to framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116
Received offer 20150121-195114-2272962752-37116-8641-O1 with cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000]
Launching task 2 using offer 20150121-195114-2272962752-37116-8641-O1
Launching task 3 using offer 20150121-195114-2272962752-37116-8641-O1
I0121 19:51:14.850721 8669 sched.cpp:605] Scheduler::resourceOffers took
3.612766ms
I0121 19:51:14.851791 8673 master.cpp:2541] Processing reply for offers: [
20150121-195114-2272962752-37116-8641-O1 ] on slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19) for framework 20150121-195114-2272962752-37116-8641-0000 (No
Executor Framework (C++)) at
[email protected]:37116
I0121 19:51:14.852443 8673 master.cpp:2647] Authorizing framework principal
'test-principal' to launch task 2 as user 'jenkins'
I0121 19:51:14.856550 8673 master.cpp:2647] Authorizing framework principal
'test-principal' to launch task 3 as user 'jenkins'
I0121 19:51:14.852241 8671 launcher.cpp:137] Forked child with pid '8726' for
container '701bd453-89fe-4fec-986d-6398e70a52ad'
I0121 19:51:14.860193 8673 master.hpp:766] Adding task 2 with resources
cpus(*):1; mem(*):128 on slave 20150121-195114-2272962752-37116-8641-S1
(fedora-19)
I0121 19:51:14.872689 8672 slave.cpp:2890] Monitoring executor '0' of
framework '20150121-195114-2272962752-37116-8641-0000' in container
'64af806e-92c2-48d7-bc61-7a330b8577c7'
I0121 19:51:14.874202 8672 slave.cpp:2890] Monitoring executor '1' of
framework '20150121-195114-2272962752-37116-8641-0000' in container
'701bd453-89fe-4fec-986d-6398e70a52ad'
I0121 19:51:14.878371 8670 hierarchical_allocator_process.hpp:610] Updated
allocation of framework 20150121-195114-2272962752-37116-8641-0000 on slave
20150121-195114-2272962752-37116-8641-S1 from cpus(*):4; mem(*):10496;
disk(*):752; ports(*):[31000-32000] to cpus(*):4; mem(*):10496; disk(*):752;
ports(*):[31000-32000]
I0121 19:51:14.878459 8673 master.cpp:2897] Launching task 2 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 with
resources cpus(*):1; mem(*):128 on slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:14.879776 8675 slave.cpp:1130] Got assigned task 2 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.881176 8675 slave.cpp:1245] Launching task 2 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.891171 8675 slave.cpp:3921] Launching executor 2 of framework
20150121-195114-2272962752-37116-8641-0000 in work directory
'/tmp/mesos-QmI8OW/2/slaves/20150121-195114-2272962752-37116-8641-S1/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/2/runs/fcae907c-c188-4d90-95e7-52fc0df4ba12'
I0121 19:51:14.892745 8673 master.hpp:766] Adding task 3 with resources
cpus(*):1; mem(*):128 on slave 20150121-195114-2272962752-37116-8641-S1
(fedora-19)
I0121 19:51:14.901448 8675 slave.cpp:1368] Queuing task '2' for executor 2 of
framework '20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.903540 8669 containerizer.cpp:445] Starting container
'fcae907c-c188-4d90-95e7-52fc0df4ba12' for executor '2' of framework
'20150121-195114-2272962752-37116-8641-0000'
I0121 19:51:14.903993 8678 hierarchical_allocator_process.hpp:610] Updated
allocation of framework 20150121-195114-2272962752-37116-8641-0000 on slave
20150121-195114-2272962752-37116-8641-S1 from cpus(*):4; mem(*):10496;
disk(*):752; ports(*):[31000-32000] to cpus(*):4; mem(*):10496; disk(*):752;
ports(*):[31000-32000]
W0121 19:51:14.915360 8674 containerizer.cpp:296] CommandInfo.grace_period
flag is not set, using default value: 3secs
I0121 19:51:14.904042 8673 master.cpp:2897] Launching task 3 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 with
resources cpus(*):1; mem(*):128 on slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:14.918277 8670 hierarchical_allocator_process.hpp:653] Recovered
mem(*):9984; disk(*):752; ports(*):[31000-32000] (total allocatable:
mem(*):9984; disk(*):752; ports(*):[31000-32000]) on slave
20150121-195114-2272962752-37116-8641-S1 from framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.919327 8670 hierarchical_allocator_process.hpp:689] Framework
20150121-195114-2272962752-37116-8641-0000 filtered slave
20150121-195114-2272962752-37116-8641-S1 for 5secs
I0121 19:51:14.919853 8675 slave.cpp:566] Successfully attached file
'/tmp/mesos-QmI8OW/2/slaves/20150121-195114-2272962752-37116-8641-S1/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/2/runs/fcae907c-c188-4d90-95e7-52fc0df4ba12'
I0121 19:51:14.920125 8675 slave.cpp:1130] Got assigned task 3 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.922003 8675 slave.cpp:1245] Launching task 3 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:14.966212 8674 launcher.cpp:137] Forked child with pid '8767' for
container 'fcae907c-c188-4d90-95e7-52fc0df4ba12'
I0121 19:51:14.992738 8675 slave.cpp:3921] Launching executor 3 of framework
20150121-195114-2272962752-37116-8641-0000 in work directory
'/tmp/mesos-QmI8OW/2/slaves/20150121-195114-2272962752-37116-8641-S1/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/3/runs/ae57fe49-c49d-4ed6-a092-b405d77dec86'
I0121 19:51:15.006045 8672 containerizer.cpp:445] Starting container
'ae57fe49-c49d-4ed6-a092-b405d77dec86' for executor '3' of framework
'20150121-195114-2272962752-37116-8641-0000'
W0121 19:51:15.023995 8672 containerizer.cpp:296] CommandInfo.grace_period
flag is not set, using default value: 3secs
I0121 19:51:15.043795 8675 slave.cpp:1368] Queuing task '3' for executor 3 of
framework '20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.046706 8675 slave.cpp:2890] Monitoring executor '2' of
framework '20150121-195114-2272962752-37116-8641-0000' in container
'fcae907c-c188-4d90-95e7-52fc0df4ba12'
I0121 19:51:15.047899 8675 slave.cpp:566] Successfully attached file
'/tmp/mesos-QmI8OW/2/slaves/20150121-195114-2272962752-37116-8641-S1/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/3/runs/ae57fe49-c49d-4ed6-a092-b405d77dec86'
I0121 19:51:15.070725 8672 launcher.cpp:137] Forked child with pid '8789' for
container 'ae57fe49-c49d-4ed6-a092-b405d77dec86'
I0121 19:51:15.102144 8678 slave.cpp:1075] Will retry registration in
1.629780053secs if necessary
I0121 19:51:15.105643 8670 master.cpp:3276] Registering slave at
slave(1)@192.168.122.135:37116 (fedora-19) with id
20150121-195114-2272962752-37116-8641-S2
I0121 19:51:15.108727 8670 registrar.cpp:445] Applied 1 operations in
176835ns; attempting to update the 'registry'
I0121 19:51:15.132966 8674 log.cpp:684] Attempting to append 628 bytes to the
log
I0121 19:51:15.133617 8678 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 7
I0121 19:51:15.139910 8678 replica.cpp:511] Replica received write request for
position 7
I0121 19:51:15.142585 8678 leveldb.cpp:343] Persisting action (647 bytes) to
leveldb took 114745ns
I0121 19:51:15.142763 8678 replica.cpp:679] Persisted action at 7
I0121 19:51:15.146975 8674 replica.cpp:658] Replica received learned notice
for position 7
I0121 19:51:15.148669 8674 leveldb.cpp:343] Persisting action (649 bytes) to
leveldb took 128247ns
I0121 19:51:15.150676 8674 replica.cpp:679] Persisted action at 7
I0121 19:51:15.151079 8674 replica.cpp:664] Replica learned APPEND action at
position 7
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0121 19:51:15.156864 8691 process.cpp:958] libprocess is initialized on
192.168.122.135:42130 for 8 cpus
I0121 19:51:15.184538 8691 logging.cpp:177] Logging to STDERR
I0121 19:51:15.192140 8673 slave.cpp:2890] Monitoring executor '3' of
framework '20150121-195114-2272962752-37116-8641-0000' in container
'ae57fe49-c49d-4ed6-a092-b405d77dec86'
I0121 19:51:15.202606 8673 registrar.cpp:490] Successfully updated the
'registry' in 91.131136ms
I0121 19:51:15.203997 8673 master.cpp:3330] Registered slave
20150121-195114-2272962752-37116-8641-S2 at slave(1)@192.168.122.135:37116
(fedora-19) with cpus(*):2; mem(*):10240; disk(*):752; ports(*):[31000-32000]
I0121 19:51:15.205441 8691 exec.cpp:147] Version: 0.22.0
I0121 19:51:15.208714 8669 hierarchical_allocator_process.hpp:453] Added slave
20150121-195114-2272962752-37116-8641-S2 (fedora-19) with cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000] (and cpus(*):2; mem(*):10240;
disk(*):752; ports(*):[31000-32000] available)
I0121 19:51:15.211026 8669 hierarchical_allocator_process.hpp:764] Performed
allocation for slave 20150121-195114-2272962752-37116-8641-S2 in 600717ns
I0121 19:51:15.208834 8675 slave.cpp:781] Registered with master
[email protected]:37116; given slave ID
20150121-195114-2272962752-37116-8641-S2
I0121 19:51:15.214854 8671 status_update_manager.cpp:178] Resuming sending
status updates
I0121 19:51:15.218658 8675 slave.cpp:797] Checkpointing SlaveInfo to
'/tmp/mesos-QmI8OW/0/meta/slaves/20150121-195114-2272962752-37116-8641-S2/slave.info'
I0121 19:51:15.219745 8675 slave.cpp:2588] Received ping from
slave-observer(3)@192.168.122.135:37116
I0121 19:51:15.221559 8673 master.cpp:4072] Sending 1 offers to framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116
I0121 19:51:15.228996 8794 exec.cpp:197] Executor started at:
executor(1)@192.168.122.135:42130 with pid 8691
I0121 19:51:15.227635 8670 log.cpp:703] Attempting to truncate the log to 7
I0121 19:51:15.233371 8669 hierarchical_allocator_process.hpp:903] Filtered
mem(*):9984; disk(*):752; ports(*):[31000-32000] on slave
20150121-195114-2272962752-37116-8641-S1 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.246156 8669 hierarchical_allocator_process.hpp:903] Filtered
mem(*):9984; disk(*):752; ports(*):[31000-32000] on slave
20150121-195114-2272962752-37116-8641-S0 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.246398 8669 hierarchical_allocator_process.hpp:839] No
resources available to allocate!
Received offer 20150121-195114-2272962752-37116-8641-O2 with cpus(*):2;
mem(*):10240; disk(*):752; ports(*):[31000-32000]
Launching task 4 using offer 20150121-195114-2272962752-37116-8641-O2
I0121 19:51:15.248265 8678 sched.cpp:605] Scheduler::resourceOffers took
1.322871ms
I0121 19:51:15.253217 8678 master.cpp:2541] Processing reply for offers: [
20150121-195114-2272962752-37116-8641-O2 ] on slave
20150121-195114-2272962752-37116-8641-S2 at slave(1)@192.168.122.135:37116
(fedora-19) for framework 20150121-195114-2272962752-37116-8641-0000 (No
Executor Framework (C++)) at
[email protected]:37116
I0121 19:51:15.259547 8671 slave.cpp:1912] Got registration for executor '0'
of framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:42130
I0121 19:51:15.268862 8671 slave.cpp:2031] Flushing queued task 0 for executor
'0' of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.277726 8791 exec.cpp:221] Executor registered on slave
20150121-195114-2272962752-37116-8641-S0
I0121 19:51:15.280460 8678 master.cpp:2647] Authorizing framework principal
'test-principal' to launch task 4 as user 'jenkins'
I0121 19:51:15.284914 8672 master.hpp:766] Adding task 4 with resources
cpus(*):1; mem(*):128 on slave 20150121-195114-2272962752-37116-8641-S2
(fedora-19)
Shutdown timeout is set to 3secsRegistered executor on fedora-19
II0121 19:51:15.287843 8672 master.cpp:2897] Launching task 4 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 with
resources cpus(*):1; mem(*):128 on slave
20150121-195114-2272962752-37116-8641-S2 at slave(1)@192.168.122.135:37116
(fedora-19)
0121 19:51:15.287569 8791 exec.cpp:233] Executor::registered took 862484ns
II0121 19:51:15.294335 8672 slave.cpp:1130] Got assigned task 4 for framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.297229 8672 slave.cpp:1245] Launching task 4 for framework
20150121-195114-2272962752-37116-8641-0000
0121 19:51:15.293241 8797 exec.cpp:308] Executor asked to run task '0'
IStarting task 0
0121 19:51:15.297765 8797 exec.cpp:317] Executor::launchTask took 107558ns
sh -c 'echo hello'
hello
Forked command at 8877
I0121 19:51:15.301460 8669 hierarchical_allocator_process.hpp:746] Performed
allocation for 3 slaves in 68.692042ms
I0121 19:51:15.301617 8674 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 8
I0121 19:51:15.328037 8669 hierarchical_allocator_process.hpp:610] Updated
allocation of framework 20150121-195114-2272962752-37116-8641-0000 on slave
20150121-195114-2272962752-37116-8641-S2 from cpus(*):6; mem(*):10752;
disk(*):752; ports(*):[31000-32000] to cpus(*):6; mem(*):10752; disk(*):752;
ports(*):[31000-32000]
I0121 19:51:15.325326 8794 exec.cpp:540] Executor sending status update
TASK_RUNNING (UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.330809 8669 hierarchical_allocator_process.hpp:653] Recovered
cpus(*):1; mem(*):10112; disk(*):752; ports(*):[31000-32000] (total
allocatable: cpus(*):1; mem(*):10112; disk(*):752; ports(*):[31000-32000]) on
slave 20150121-195114-2272962752-37116-8641-S2 from framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.334456 8669 hierarchical_allocator_process.hpp:689] Framework
20150121-195114-2272962752-37116-8641-0000 filtered slave
20150121-195114-2272962752-37116-8641-S2 for 5secs
I0121 19:51:15.330586 8674 replica.cpp:511] Replica received write request for
position 8
I0121 19:51:15.335093 8674 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 93577ns
I0121 19:51:15.336494 8674 replica.cpp:679] Persisted action at 8
I0121 19:51:15.365191 8678 slave.cpp:2265] Handling status update TASK_RUNNING
(UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:42130
I0121 19:51:15.365591 8675 replica.cpp:658] Replica received learned notice
for position 8
I0121 19:51:15.367508 8675 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 85815ns
I0121 19:51:15.367710 8675 leveldb.cpp:401] Deleting ~2 keys from leveldb took
42654ns
I0121 19:51:15.367961 8675 replica.cpp:679] Persisted action at 8
I0121 19:51:15.368125 8675 replica.cpp:664] Replica learned TRUNCATE action at
position 8
I0121 19:51:15.374074 8678 status_update_manager.cpp:317] Received status
update TASK_RUNNING (UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.375895 8678 status_update_manager.cpp:494] Creating
StatusUpdate stream for task 0 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.377903 8678 status_update_manager.cpp:371] Forwarding update
TASK_RUNNING (UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:15.385704 8674 slave.cpp:2508] Forwarding the update TASK_RUNNING
(UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:15.392774 8674 master.cpp:3653] Forwarding status update
TASK_RUNNING (UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.393259 8674 master.cpp:3625] Status update TASK_RUNNING (UUID:
6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
Task 0 is in state TASK_RUNNING
I0121 19:51:15.393862 8674 master.cpp:4935] Updating the latest state of task
0 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_RUNNING
I0121 19:51:15.393738 8675 sched.cpp:696] Scheduler::statusUpdate took 145514ns
I0121 19:51:15.394508 8675 master.cpp:3126] Forwarding status update
acknowledgement 6c159eb3-c838-4e14-a6c3-f96b4b438588 for task 0 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
I0121 19:51:15.402521 8673 slave.cpp:2435] Status update manager successfully
handled status update TASK_RUNNING (UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588)
for task 0 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.402775 8673 slave.cpp:2441] Sending acknowledgement for status
update TASK_RUNNING (UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:42130
I0121 19:51:15.405280 8800 exec.cpp:354] Executor received status update
acknowledgement 6c159eb3-c838-4e14-a6c3-f96b4b438588 for task 0 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.411936 8678 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.414719 8669 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
6c159eb3-c838-4e14-a6c3-f96b4b438588) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.416793 8672 slave.cpp:3921] Launching executor 4 of framework
20150121-195114-2272962752-37116-8641-0000 in work directory
'/tmp/mesos-QmI8OW/0/slaves/20150121-195114-2272962752-37116-8641-S2/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/4/runs/6e422756-1323-4955-8b29-b74ad6a70f8a'
I0121 19:51:15.425458 8673 containerizer.cpp:445] Starting container
'6e422756-1323-4955-8b29-b74ad6a70f8a' for executor '4' of framework
'20150121-195114-2272962752-37116-8641-0000'
W0121 19:51:15.436545 8674 containerizer.cpp:296] CommandInfo.grace_period
flag is not set, using default value: 3secs
I0121 19:51:15.445781 8672 slave.cpp:1368] Queuing task '4' for executor 4 of
framework '20150121-195114-2272962752-37116-8641-0000
Command exited with status 0 (pid: 8877)
I0121 19:51:15.456938 8798 exec.cpp:540] Executor sending status update
TASK_FINISHED (UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0121 19:51:15.474784 8672 slave.cpp:566] Successfully attached file
'/tmp/mesos-QmI8OW/0/slaves/20150121-195114-2272962752-37116-8641-S2/frameworks/20150121-195114-2272962752-37116-8641-0000/executors/4/runs/6e422756-1323-4955-8b29-b74ad6a70f8a'
I0121 19:51:15.465473 8726 process.cpp:958] libprocess is initialized on
192.168.122.135:52868 for 8 cpus
I0121 19:51:15.488029 8673 slave.cpp:2265] Handling status update
TASK_FINISHED (UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:42130
I0121 19:51:15.489670 8673 slave.cpp:4229] Terminating task 0
I0121 19:51:15.500972 8673 status_update_manager.cpp:317] Received status
update TASK_FINISHED (UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.502712 8726 logging.cpp:177] Logging to STDERR
I0121 19:51:15.503408 8673 status_update_manager.cpp:371] Forwarding update
TASK_FINISHED (UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:15.503252 8674 launcher.cpp:137] Forked child with pid '8892' for
container '6e422756-1323-4955-8b29-b74ad6a70f8a'
I0121 19:51:15.515324 8671 slave.cpp:2508] Forwarding the update TASK_FINISHED
(UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:15.517704 8671 master.cpp:3653] Forwarding status update
TASK_FINISHED (UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.521610 8671 master.cpp:3625] Status update TASK_FINISHED (UUID:
1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
Task 0 is in state TASK_FINISHED
I0121 19:51:15.522312 8671 master.cpp:4935] Updating the latest state of task
0 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_FINISHED
I0121 19:51:15.522883 8671 hierarchical_allocator_process.hpp:653] Recovered
cpus(*):1; mem(*):128 (total allocatable: mem(*):10112; disk(*):752;
ports(*):[31000-32000]; cpus(*):1) on slave
20150121-195114-2272962752-37116-8641-S0 from framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.522238 8669 sched.cpp:696] Scheduler::statusUpdate took 294116ns
I0121 19:51:15.524179 8669 master.cpp:4994] Removing task 0 with resources
cpus(*):1; mem(*):128 of framework 20150121-195114-2272962752-37116-8641-0000
on slave 20150121-195114-2272962752-37116-8641-S0 at
slave(2)@192.168.122.135:37116 (fedora-19)
I0121 19:51:15.527992 8669 master.cpp:3126] Forwarding status update
acknowledgement 1c1ec1df-f8da-4cf7-b80e-f3014400a338 for task 0 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
II0121 19:51:15.539926 8673 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0
of framework 20150121-195114-2272962752-37116-8641-0000
0121 19:51:15.539443 8726 exec.cpp:147] Version: 0.22.0
I0121 19:51:15.542232 8678 slave.cpp:2435] Status update manager successfully
handled status update TASK_FINISHED (UUID:
1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.543570 8678 slave.cpp:2441] Sending acknowledgement for status
update TASK_FINISHED (UUID: 1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:42130
I0121 19:51:15.547049 8790 exec.cpp:354] Executor received status update
acknowledgement 1c1ec1df-f8da-4cf7-b80e-f3014400a338 for task 0 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.556150 8673 status_update_manager.cpp:525] Cleaning up status
update stream for task 0 of framework 20150121-195114-2272962752-37116-8641-0000
II0121 19:51:15.563487 8672 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
1c1ec1df-f8da-4cf7-b80e-f3014400a338) for task 0 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.563988 8672 slave.cpp:4268] Completing task 0
I0121 19:51:15.564900 8669 slave.cpp:2890] Monitoring executor '4' of
framework '20150121-195114-2272962752-37116-8641-0000' in container
'6e422756-1323-4955-8b29-b74ad6a70f8a'
0121 19:51:15.560830 8883 exec.cpp:197] Executor started at:
executor(1)@192.168.122.135:52868 with pid 8726
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0121 19:51:15.580338 8669 slave.cpp:1912] Got registration for executor '1'
of framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:52868
I0121 19:51:15.578806 8767 process.cpp:958] libprocess is initialized on
192.168.122.135:60774 for 8 cpus
I0121 19:51:15.592075 8669 slave.cpp:2031] Flushing queued task 1 for executor
'1' of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.598752 8881 exec.cpp:221] Executor registered on slave
20150121-195114-2272962752-37116-8641-S0
I0121 19:51:15.616471 8767 logging.cpp:177] Logging to STDERR
Shutdown timeout is set to 3secsRegistered executor on fedora-19
I0121 19:51:15.625520 8881 exec.cpp:233] Executor::registered took 21.19897ms
II0121 19:51:15.633946 8767 exec.cpp:147] Version: 0.22.0
0121 19:51:15.627087 8881 exec.cpp:308] Executor asked to run task '1'
IStarting task 1
I0121 19:51:15.659446 8921 exec.cpp:197] Executor started at:
executor(1)@192.168.122.135:60774 with pid 8767
0121 19:51:15.645635 8881 exec.cpp:317] Executor::launchTask took 1.650497ms
sh -c 'echo hello'
Forked command at 8928
hello
I0121 19:51:15.687127 8673 slave.cpp:1912] Got registration for executor '2'
of framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:60774
I0121 19:51:15.693169 8878 exec.cpp:540] Executor sending status update
TASK_RUNNING (UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.703030 8919 exec.cpp:221] Executor registered on slave
20150121-195114-2272962752-37116-8641-S1
Shutdown timeout is set to 3secsRegistered executor on fedora-19
I0121 19:51:15.706172 8670 slave.cpp:2265] Handling status update TASK_RUNNING
(UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:52868
I0121 19:51:15.706544 8919 exec.cpp:233] Executor::registered took 1.412548ms
I0121 19:51:15.707336 8670 status_update_manager.cpp:317] Received status
update TASK_RUNNING (UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.708449 8670 status_update_manager.cpp:494] Creating
StatusUpdate stream for task 1 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.709188 8670 status_update_manager.cpp:371] Forwarding update
TASK_RUNNING (UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:15.712764 8670 slave.cpp:2508] Forwarding the update TASK_RUNNING
(UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:15.712414 8673 slave.cpp:2031] Flushing queued task 2 for executor
'2' of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.719471 8670 slave.cpp:2435] Status update manager successfully
handled status update TASK_RUNNING (UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc)
for task 1 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.719950 8670 slave.cpp:2441] Sending acknowledgement for status
update TASK_RUNNING (UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:52868
I0121 19:51:15.719910 8673 master.cpp:3653] Forwarding status update
TASK_RUNNING (UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000
Task 1 is in state TASK_RUNNING
I0121 19:51:15.721751 8671 sched.cpp:696] Scheduler::statusUpdate took 148516ns
I0121 19:51:15.722120 8673 master.cpp:3625] Status update TASK_RUNNING (UUID:
28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
II0121 19:51:15.724227 8673 master.cpp:4935] Updating the latest state of task
1 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_RUNNING
0121 19:51:15.724020 8921 exec.cpp:308] Executor asked to run task '2'
II0121 19:51:15.727632 8673 master.cpp:3126] Forwarding status update
acknowledgement 28224a63-9cd1-43ec-9b0f-c99d68e676cc for task 1 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
Starting task 2
0121 19:51:15.727367 8921 exec.cpp:317] Executor::launchTask took 108263ns
I0121 19:51:15.735651 8669 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.736243 8669 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
28224a63-9cd1-43ec-9b0f-c99d68e676cc) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.737500 8880 exec.cpp:354] Executor received status update
acknowledgement 28224a63-9cd1-43ec-9b0f-c99d68e676cc for task 1 of framework
20150121-195114-2272962752-37116-8641-0000
sh -c 'echo hello'
hello
Forked command at 8929
I0121 19:51:15.760294 8920 exec.cpp:540] Executor sending status update
TASK_RUNNING (UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.767074 8625 exec.cpp:96] Committing suicide by killing the
process group
I0121 19:51:15.775118 8672 slave.cpp:2265] Handling status update TASK_RUNNING
(UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:60774
I0121 19:51:15.777261 8672 status_update_manager.cpp:317] Received status
update TASK_RUNNING (UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.780460 8672 status_update_manager.cpp:494] Creating
StatusUpdate stream for task 2 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.781100 8672 status_update_manager.cpp:371] Forwarding update
TASK_RUNNING (UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
Command exited with status 0 (pid: 8928)
I0121 19:51:15.788465 8882 exec.cpp:540] Executor sending status update
TASK_FINISHED (UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.792198 8671 slave.cpp:2265] Handling status update
TASK_FINISHED (UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:52868
I0121 19:51:15.792871 8671 slave.cpp:4229] Terminating task 1
I0121 19:51:15.798055 8669 slave.cpp:2508] Forwarding the update TASK_RUNNING
(UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:15.798751 8669 master.cpp:3653] Forwarding status update
TASK_RUNNING (UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000
Task 2 is in state TASK_RUNNING
I0121 19:51:15.799463 8675 sched.cpp:696] Scheduler::statusUpdate took 185570ns
I0121 19:51:15.800037 8669 master.cpp:3625] Status update TASK_RUNNING (UUID:
844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:15.801010 8669 master.cpp:4935] Updating the latest state of task
2 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_RUNNING
I0121 19:51:15.801589 8669 master.cpp:3126] Forwarding status update
acknowledgement 844d7781-ebdd-468c-924e-0f6b461e67f5 for task 2 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0121 19:51:15.802927 8672 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.801750 8789 process.cpp:958] libprocess is initialized on
192.168.122.135:46322 for 8 cpus
I0121 19:51:15.806177 8609 exec.cpp:96] Committing suicide by killing the
process group
I0121 19:51:15.809157 8675 slave.cpp:2435] Status update manager successfully
handled status update TASK_RUNNING (UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5)
for task 2 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.809360 8675 slave.cpp:2441] Sending acknowledgement for status
update TASK_RUNNING (UUID: 844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:60774
I0121 19:51:15.814692 8922 exec.cpp:354] Executor received status update
acknowledgement 844d7781-ebdd-468c-924e-0f6b461e67f5 for task 2 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.815981 8673 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
844d7781-ebdd-468c-924e-0f6b461e67f5) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.817116 8671 status_update_manager.cpp:317] Received status
update TASK_FINISHED (UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.822491 8671 status_update_manager.cpp:371] Forwarding update
TASK_FINISHED (UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:15.823073 8674 slave.cpp:2508] Forwarding the update TASK_FINISHED
(UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:15.823724 8674 master.cpp:3653] Forwarding status update
TASK_FINISHED (UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.825177 8789 logging.cpp:177] Logging to STDERR
I0121 19:51:15.825781 8674 master.cpp:3625] Status update TASK_FINISHED (UUID:
38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
Task 1 is in state TASK_FINISHED
I0121 19:51:15.826706 8674 master.cpp:4935] Updating the latest state of task
1 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_FINISHED
I0121 19:51:15.826637 8678 sched.cpp:696] Scheduler::statusUpdate took 360792ns
I0121 19:51:15.829615 8670 master.cpp:4994] Removing task 1 with resources
cpus(*):1; mem(*):128 of framework 20150121-195114-2272962752-37116-8641-0000
on slave 20150121-195114-2272962752-37116-8641-S0 at
slave(2)@192.168.122.135:37116 (fedora-19)
I0121 19:51:15.831714 8670 master.cpp:3126] Forwarding status update
acknowledgement 38e2afc6-d5b8-4e75-a7c4-c4728becece5 for task 1 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S0 at slave(2)@192.168.122.135:37116
(fedora-19)
I0121 19:51:15.832365 8674 hierarchical_allocator_process.hpp:653] Recovered
cpus(*):1; mem(*):128 (total allocatable: mem(*):10240; disk(*):752;
ports(*):[31000-32000]; cpus(*):2) on slave
20150121-195114-2272962752-37116-8641-S0 from framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.835090 8678 slave.cpp:2435] Status update manager successfully
handled status update TASK_FINISHED (UUID:
38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.835273 8678 slave.cpp:2441] Sending acknowledgement for status
update TASK_FINISHED (UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:52868
I0121 19:51:15.838420 8879 exec.cpp:354] Executor received status update
acknowledgement 38e2afc6-d5b8-4e75-a7c4-c4728becece5 for task 1 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.839193 8671 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.839606 8671 status_update_manager.cpp:525] Cleaning up status
update stream for task 1 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.845685 8670 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
38e2afc6-d5b8-4e75-a7c4-c4728becece5) for task 1 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.846319 8670 slave.cpp:4268] Completing task 1
I0121 19:51:15.849195 8631 exec.cpp:96] Committing suicide by killing the
process group
I0121 19:51:15.852751 8789 exec.cpp:147] Version: 0.22.0
Command exited with status 0 (pid: 8929)
I0121 19:51:15.874649 8917 exec.cpp:540] Executor sending status update
TASK_FINISHED (UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.878484 8933 exec.cpp:197] Executor started at:
executor(1)@192.168.122.135:46322 with pid 8789
I0121 19:51:15.883720 8669 slave.cpp:2265] Handling status update
TASK_FINISHED (UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:60774
I0121 19:51:15.884933 8669 slave.cpp:4229] Terminating task 2
I0121 19:51:15.884791 8673 monitor.cpp:142] Failed to collect resource usage
for container '64af806e-92c2-48d7-bc61-7a330b8577c7' for executor '0' of
framework '20150121-195114-2272962752-37116-8641-0000': Unknown container:
64af806e-92c2-48d7-bc61-7a330b8577c7
I0121 19:51:15.891576 8673 monitor.cpp:142] Failed to collect resource usage
for container '701bd453-89fe-4fec-986d-6398e70a52ad' for executor '1' of
framework '20150121-195114-2272962752-37116-8641-0000': Unknown container:
701bd453-89fe-4fec-986d-6398e70a52ad
I0121 19:51:15.895797 8669 status_update_manager.cpp:317] Received status
update TASK_FINISHED (UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.896503 8669 status_update_manager.cpp:371] Forwarding update
TASK_FINISHED (UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:15.896919 8672 slave.cpp:1912] Got registration for executor '3'
of framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:46322
I0121 19:51:15.899721 8672 slave.cpp:2031] Flushing queued task 3 for executor
'3' of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.900166 8672 slave.cpp:2508] Forwarding the update TASK_FINISHED
(UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:15.900677 8678 master.cpp:3653] Forwarding status update
TASK_FINISHED (UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.904541 8678 master.cpp:3625] Status update TASK_FINISHED (UUID:
dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
Task 2 is in state TASK_FINISHED
I0121 19:51:15.905072 8678 master.cpp:4935] Updating the latest state of task
2 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_FINISHED
I0121 19:51:15.905578 8678 hierarchical_allocator_process.hpp:653] Recovered
cpus(*):1; mem(*):128 (total allocatable: mem(*):10112; disk(*):752;
ports(*):[31000-32000]; cpus(*):1) on slave
20150121-195114-2272962752-37116-8641-S1 from framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.905014 8670 sched.cpp:696] Scheduler::statusUpdate took 142477ns
I0121 19:51:15.906141 8670 master.cpp:4994] Removing task 2 with resources
cpus(*):1; mem(*):128 of framework 20150121-195114-2272962752-37116-8641-0000
on slave 20150121-195114-2272962752-37116-8641-S1 at
slave(3)@192.168.122.135:37116 (fedora-19)
I0121 19:51:15.906501 8670 master.cpp:3126] Forwarding status update
acknowledgement dbb7234f-06d1-4d45-a903-e30f497817a6 for task 2 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:15.906779 8672 slave.cpp:2435] Status update manager successfully
handled status update TASK_FINISHED (UUID:
dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.906947 8672 slave.cpp:2441] Sending acknowledgement for status
update TASK_FINISHED (UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:60774
I0121 19:51:15.909281 8669 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.909592 8669 status_update_manager.cpp:525] Cleaning up status
update stream for task 2 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.910976 8922 exec.cpp:354] Executor received status update
acknowledgement dbb7234f-06d1-4d45-a903-e30f497817a6 for task 2 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.915912 8678 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
dbb7234f-06d1-4d45-a903-e30f497817a6) for task 2 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.916147 8678 slave.cpp:4268] Completing task 2
I0121 19:51:15.918617 8934 exec.cpp:221] Executor registered on slave
20150121-195114-2272962752-37116-8641-S1
Shutdown timeout is set to 3secsRegistered executor on fedora-19
I0121 19:51:15.921136 8934 exec.cpp:233] Executor::registered took 543944ns
I0121 19:51:15.933137 8935 exec.cpp:308] Executor asked to run task '3'
IStarting task 3
0121 19:51:15.933619 8935 exec.cpp:317] Executor::launchTask took 138857ns
sh -c 'echo hello'
hello
Forked command at 8952
I0121 19:51:15.952551 8933 exec.cpp:540] Executor sending status update
TASK_RUNNING (UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.965998 8674 slave.cpp:2265] Handling status update TASK_RUNNING
(UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:46322
I0121 19:51:15.968754 8674 status_update_manager.cpp:317] Received status
update TASK_RUNNING (UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.968966 8674 status_update_manager.cpp:494] Creating
StatusUpdate stream for task 3 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.969549 8674 status_update_manager.cpp:371] Forwarding update
TASK_RUNNING (UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:15.970166 8673 slave.cpp:2508] Forwarding the update TASK_RUNNING
(UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:15.970618 8673 slave.cpp:2435] Status update manager successfully
handled status update TASK_RUNNING (UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94)
for task 3 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.971207 8673 slave.cpp:2441] Sending acknowledgement for status
update TASK_RUNNING (UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:46322
I0121 19:51:15.971149 8675 master.cpp:3653] Forwarding status update
TASK_RUNNING (UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000
Task 3 is in state TASK_RUNNING
I0121 19:51:15.974524 8675 master.cpp:3625] Status update TASK_RUNNING (UUID:
f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:15.975195 8675 master.cpp:4935] Updating the latest state of task
3 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_RUNNING
I0121 19:51:15.974970 8671 sched.cpp:696] Scheduler::statusUpdate took 166775ns
I0121 19:51:15.976141 8671 master.cpp:3126] Forwarding status update
acknowledgement f25fc596-98f1-40e6-a8d0-dbf0ea423f94 for task 3 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:15.976812 8673 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.981055 8936 exec.cpp:354] Executor received status update
acknowledgement f25fc596-98f1-40e6-a8d0-dbf0ea423f94 for task 3 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:15.985622 8678 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
f25fc596-98f1-40e6-a8d0-dbf0ea423f94) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000
WARNING: Logging before InitGoogleLogging() is written to STDERR
I0121 19:51:16.009034 8892 process.cpp:958] libprocess is initialized on
192.168.122.135:43211 for 8 cpus
I0121 19:51:16.030426 8892 logging.cpp:177] Logging to STDERR
I0121 19:51:16.036309 8892 exec.cpp:147] Version: 0.22.0
II0121 19:51:16.050998 8669 monitor.cpp:142] Failed to collect resource usage
for container 'fcae907c-c188-4d90-95e7-52fc0df4ba12' for executor '2' of
framework '20150121-195114-2272962752-37116-8641-0000': Unknown container:
fcae907c-c188-4d90-95e7-52fc0df4ba12
0121 19:51:16.050240 8955 exec.cpp:197] Executor started at:
executor(1)@192.168.122.135:43211 with pid 8892
I0121 19:51:16.059176 8670 slave.cpp:1912] Got registration for executor '4'
of framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:43211
I0121 19:51:16.062557 8670 slave.cpp:2031] Flushing queued task 4 for executor
'4' of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.064218 8960 exec.cpp:221] Executor registered on slave
20150121-195114-2272962752-37116-8641-S2
Command exited with status 0 (pid: 8952)
IShutdown timeout is set to 3secsRegistered executor on fedora-19
0121 19:51:16.069164 8933 exec.cpp:540] Executor sending status update
TASK_FINISHED (UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.073053 8960 exec.cpp:233] Executor::registered took 2.579896ms
I0121 19:51:16.077533 8671 slave.cpp:2265] Handling status update
TASK_FINISHED (UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:46322
I0121 19:51:16.077980 8671 slave.cpp:4229] Terminating task 3
I0121 19:51:16.080574 8674 status_update_manager.cpp:317] Received status
update TASK_FINISHED (UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.081528 8674 status_update_manager.cpp:371] Forwarding update
TASK_FINISHED (UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:16.082164 8671 slave.cpp:2508] Forwarding the update TASK_FINISHED
(UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:16.083096 8673 master.cpp:3653] Forwarding status update
TASK_FINISHED (UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000
ITask 3 is in state TASK_FINISHED
0121 19:51:16.083693 8954 exec.cpp:308] Executor asked to run task '4'
II0121 19:51:16.084959 8669 sched.cpp:696] Scheduler::statusUpdate took
784173ns
Starting task 4
0121 19:51:16.084789 8954 exec.cpp:317] Executor::launchTask took 265689ns
I0121 19:51:16.085443 8673 master.cpp:3625] Status update TASK_FINISHED (UUID:
324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:16.086848 8673 master.cpp:4935] Updating the latest state of task
3 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_FINISHED
I0121 19:51:16.087541 8675 hierarchical_allocator_process.hpp:653] Recovered
cpus(*):1; mem(*):128 (total allocatable: mem(*):10240; disk(*):752;
ports(*):[31000-32000]; cpus(*):2) on slave
20150121-195114-2272962752-37116-8641-S1 from framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.087774 8673 master.cpp:4994] Removing task 3 with resources
cpus(*):1; mem(*):128 of framework 20150121-195114-2272962752-37116-8641-0000
on slave 20150121-195114-2272962752-37116-8641-S1 at
slave(3)@192.168.122.135:37116 (fedora-19)
I0121 19:51:16.089057 8673 master.cpp:3126] Forwarding status update
acknowledgement 324efd6a-b197-4db4-b6fd-885091f6952a for task 3 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S1 at slave(3)@192.168.122.135:37116
(fedora-19)
I0121 19:51:16.089495 8671 slave.cpp:2435] Status update manager successfully
handled status update TASK_FINISHED (UUID:
324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.089670 8671 slave.cpp:2441] Sending acknowledgement for status
update TASK_FINISHED (UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:46322
sh -c 'echo hello'
hello
I0121 19:51:16.096036 8936 exec.cpp:354] Executor received status update
acknowledgement 324efd6a-b197-4db4-b6fd-885091f6952a for task 3 of framework
20150121-195114-2272962752-37116-8641-0000
Forked command at 8962
I0121 19:51:16.104780 8672 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 324efd6a-b197-4db4-b6fd-885091f6952a) for task 3
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.105304 8672 status_update_manager.cpp:525] Cleaning up status
update stream for task 3 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.105839 8671 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
324efd6a-b197-4db4-b6fd-885091f6952a) for task 3 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.106465 8671 slave.cpp:4268] Completing task 3
I0121 19:51:16.109747 8957 exec.cpp:540] Executor sending status update
TASK_RUNNING (UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.111857 8674 slave.cpp:2265] Handling status update TASK_RUNNING
(UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4 of framework
20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:43211
I0121 19:51:16.112332 8674 status_update_manager.cpp:317] Received status
update TASK_RUNNING (UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.112646 8674 status_update_manager.cpp:494] Creating
StatusUpdate stream for task 4 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.113229 8674 status_update_manager.cpp:371] Forwarding update
TASK_RUNNING (UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:16.113739 8669 slave.cpp:2508] Forwarding the update TASK_RUNNING
(UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:16.114297 8669 master.cpp:3653] Forwarding status update
TASK_RUNNING (UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.114758 8669 master.cpp:3625] Status update TASK_RUNNING (UUID:
4962e477-9998-4309-a7b6-fa056e976267) for task 4 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S2 at slave(1)@192.168.122.135:37116
(fedora-19)
Task 4 is in state TASK_RUNNING
I0121 19:51:16.115306 8675 sched.cpp:696] Scheduler::statusUpdate took 137857ns
I0121 19:51:16.115417 8669 master.cpp:4935] Updating the latest state of task
4 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_RUNNING
I0121 19:51:16.116190 8669 master.cpp:3126] Forwarding status update
acknowledgement 4962e477-9998-4309-a7b6-fa056e976267 for task 4 of framework
20150121-195114-2272962752-37116-8641-0000 (No Executor Framework (C++)) at
[email protected]:37116 to slave
20150121-195114-2272962752-37116-8641-S2 at slave(1)@192.168.122.135:37116
(fedora-19)
I0121 19:51:16.116956 8678 slave.cpp:2435] Status update manager successfully
handled status update TASK_RUNNING (UUID: 4962e477-9998-4309-a7b6-fa056e976267)
for task 4 of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.117140 8678 slave.cpp:2441] Sending acknowledgement for status
update TASK_RUNNING (UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:43211
I0121 19:51:16.118958 8960 exec.cpp:354] Executor received status update
acknowledgement 4962e477-9998-4309-a7b6-fa056e976267 for task 4 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.122521 8674 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 4962e477-9998-4309-a7b6-fa056e976267) for task 4
of framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.123201 8675 slave.cpp:1852] Status update manager successfully
handled status update acknowledgement (UUID:
4962e477-9998-4309-a7b6-fa056e976267) for task 4 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.198173 8670 monitor.cpp:142] Failed to collect resource usage
for container 'ae57fe49-c49d-4ed6-a092-b405d77dec86' for executor '3' of
framework '20150121-195114-2272962752-37116-8641-0000': Unknown container:
ae57fe49-c49d-4ed6-a092-b405d77dec86
Command exited with status 0 (pid: 8962)
I0121 19:51:16.214493 8960 exec.cpp:540] Executor sending status update
TASK_FINISHED (UUID: 064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.217262 8669 slave.cpp:2265] Handling status update
TASK_FINISHED (UUID: 064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000 from
executor(1)@192.168.122.135:43211
I0121 19:51:16.217751 8669 slave.cpp:4229] Terminating task 4
I0121 19:51:16.219632 8669 status_update_manager.cpp:317] Received status
update TASK_FINISHED (UUID: 064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.220095 8669 status_update_manager.cpp:371] Forwarding update
TASK_FINISHED (UUID: 064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000 to the slave
I0121 19:51:16.220582 8671 slave.cpp:2508] Forwarding the update TASK_FINISHED
(UUID: 064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of framework
20150121-195114-2272962752-37116-8641-0000 to [email protected]:37116
I0121 19:51:16.221087 8671 master.cpp:3653] Forwarding status update
TASK_FINISHED (UUID: 064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.221573 8671 master.cpp:3625] Status update TASK_FINISHED (UUID:
064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of framework
20150121-195114-2272962752-37116-8641-0000 from slave
20150121-195114-2272962752-37116-8641-S2 at slave(1)@192.168.122.135:37116
(fedora-19)
Task 4 is in state TASK_FINISHED
I0121 19:51:16.222146 8673 sched.cpp:1471] Asked to stop the driver
I0121 19:51:16.222626 8673 sched.cpp:696] Scheduler::statusUpdate took 630157ns
I0121 19:51:16.222782 8673 sched.cpp:702] Not sending status update
acknowledgment message because the driver is not running!
I0121 19:51:16.223067 8673 sched.cpp:808] Stopping framework
'20150121-195114-2272962752-37116-8641-0000'
I0121 19:51:16.223500 8641 sched.cpp:1471] Asked to stop the driver
I0121 19:51:16.223664 8641 sched.cpp:1474] Ignoring stop because the status of
the driver is DRIVER_STOPPED
I0121 19:51:16.225774 8671 master.cpp:4935] Updating the latest state of task
4 of framework 20150121-195114-2272962752-37116-8641-0000 to TASK_FINISHED
I0121 19:51:16.226161 8671 master.cpp:654] Master terminating
I0121 19:51:16.226511 8675 hierarchical_allocator_process.hpp:653] Recovered
cpus(*):1; mem(*):128 (total allocatable: cpus(*):2; mem(*):10240; disk(*):752;
ports(*):[31000-32000]) on slave 20150121-195114-2272962752-37116-8641-S2 from
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.227663 8671 master.cpp:4994] Removing task 4 with resources
cpus(*):1; mem(*):128 of framework 20150121-195114-2272962752-37116-8641-0000
on slave 20150121-195114-2272962752-37116-8641-S2 at
slave(1)@192.168.122.135:37116 (fedora-19)
I0121 19:51:16.237928 8670 slave.cpp:2435] Status update manager successfully
handled status update TASK_FINISHED (UUID:
064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.238118 8670 slave.cpp:2441] Sending acknowledgement for status
update TASK_FINISHED (UUID: 064b8bdf-5cfc-4aef-849a-7400c2acf7b1) for task 4 of
framework 20150121-195114-2272962752-37116-8641-0000 to
executor(1)@192.168.122.135:43211
I0121 19:51:16.240422 8958 exec.cpp:354] Executor received status update
acknowledgement 064b8bdf-5cfc-4aef-849a-7400c2acf7b1 for task 4 of framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.248719 8678 slave.cpp:2673] [email protected]:37116 exited
W0121 19:51:16.248889 8678 slave.cpp:2676] Master disconnected! Waiting for a
new master to be elected
I0121 19:51:16.249514 8674 slave.cpp:2673] [email protected]:37116 exited
W0121 19:51:16.249716 8674 slave.cpp:2676] Master disconnected! Waiting for a
new master to be elected
I0121 19:51:16.250072 8678 slave.cpp:2673] [email protected]:37116 exited
W0121 19:51:16.250278 8678 slave.cpp:2676] Master disconnected! Waiting for a
new master to be elected
I0121 19:51:16.273524 8678 slave.cpp:495] Slave terminating
I0121 19:51:16.274646 8678 slave.cpp:1585] Asked to shut down framework
20150121-195114-2272962752-37116-8641-0000 by @0.0.0.0:0
I0121 19:51:16.274823 8678 slave.cpp:1610] Shutting down framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.275374 8678 slave.cpp:3198] Shutting down executor '4' of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.277443 8960 exec.cpp:394] Executor asked to shutdown
I0121 19:51:16.277590 8960 exec.cpp:409] Executor::shutdown took 12268ns
I0121 19:51:16.277714 8960 exec.cpp:90] Scheduling shutdown of the executor
I0121 19:51:16.314046 8641 slave.cpp:495] Slave terminating
I0121 19:51:16.314812 8641 slave.cpp:1585] Asked to shut down framework
20150121-195114-2272962752-37116-8641-0000 by @0.0.0.0:0
I0121 19:51:16.314996 8641 slave.cpp:1610] Shutting down framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.315238 8641 slave.cpp:3198] Shutting down executor '1' of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.318058 8878 exec.cpp:394] Executor asked to shutdown
I0121 19:51:16.318353 8885 exec.cpp:90] Scheduling shutdown of the executor
I0121 19:51:16.318523 8878 exec.cpp:409] Executor::shutdown took 19555ns
I0121 19:51:16.322510 8641 slave.cpp:3198] Shutting down executor '0' of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.324558 8793 exec.cpp:394] Executor asked to shutdown
I0121 19:51:16.324728 8793 exec.cpp:409] Executor::shutdown took 19856ns
I0121 19:51:16.324857 8793 exec.cpp:90] Scheduling shutdown of the executor
I0121 19:51:16.353931 8675 slave.cpp:495] Slave terminating
I0121 19:51:16.354256 8675 slave.cpp:1585] Asked to shut down framework
20150121-195114-2272962752-37116-8641-0000 by @0.0.0.0:0
I0121 19:51:16.354459 8675 slave.cpp:1610] Shutting down framework
20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.354734 8675 slave.cpp:3198] Shutting down executor '3' of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.355984 8675 slave.cpp:3198] Shutting down executor '2' of
framework 20150121-195114-2272962752-37116-8641-0000
I0121 19:51:16.356978 8930 exec.cpp:394] Executor asked to shutdown
I0121 19:51:16.357130 8930 exec.cpp:409] Executor::shutdown took 22156ns
I0121 19:51:16.357261 8930 exec.cpp:90] Scheduling shutdown of the executor
I0121 19:51:16.363234 8918 exec.cpp:394] Executor asked to shutdown
I0121 19:51:16.363363 8918 exec.cpp:409] Executor::shutdown took 11926ns
I0121 19:51:16.363522 8918 exec.cpp:90] Scheduling shutdown of the executor
Shutting down
Sending SIGTERM to process tree at pid 8877
Killing the following process trees:
[
]
Shutting down
Sending SIGTERM to process tree at pid 8928
Killing the following process trees:
[
]
Shutting down
Sending SIGTERM to process tree at pid 8929
Killing the following process trees:
[
]
Shutting down
Sending SIGTERM to process tree at pid 8952
I0121 19:51:17.131989 8960 exec.cpp:455] Ignoring exited event because the
driver is aborted!
tests/script.cpp:83: Failure
Failed
no_executor_framework_test.sh terminated with signal Segmentation fault
[ FAILED ] ExamplesTest.NoExecutorFramework (5228 ms)
{noformat}
> ExamplesTest.NoExecutorFramework terminates with segmentation fault
> -------------------------------------------------------------------
>
> Key: MESOS-2179
> URL: https://issues.apache.org/jira/browse/MESOS-2179
> Project: Mesos
> Issue Type: Bug
> Components: test
> Affects Versions: 0.22.0
> Environment: Centos7 inside Docker
> Mesos master commit: 49d4553a0645624179f17ed6da8d2443e88998bf
> Reporter: Cody Maloney
> Priority: Minor
> Labels: flaky
>
> {code}
> [ RUN ] ExamplesTest.NoExecutorFramework
> ../../src/tests/script.cpp:83: Failure
> Failed
> no_executor_framework_test.sh terminated with signal Segmentation fault
> [ FAILED ] ExamplesTest.NoExecutorFramework (2543 ms)
> {code}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
