[
https://issues.apache.org/jira/browse/MESOS-830?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14172612#comment-14172612
]
Alexander Rukletsov commented on MESOS-830:
-------------------------------------------
[ RUN ] ExamplesTest.JavaFramework
Using temporary directory '/tmp/ExamplesTest_JavaFramework_qJnXgB'
Enabling authentication for the framework
I1015 16:30:22.277333 280764416 leveldb.cpp:176] Opened db in 2162us
I1015 16:30:22.277742 280764416 leveldb.cpp:183] Compacted db in 391us
I1015 16:30:22.277783 280764416 leveldb.cpp:198] Created db iterator in 15us
I1015 16:30:22.277808 280764416 leveldb.cpp:204] Seeked to beginning of db in
17us
I1015 16:30:22.277823 280764416 leveldb.cpp:273] Iterated through 0 keys in the
db in 12us
I1015 16:30:22.277849 280764416 replica.cpp:741] Replica recovered with log
positions 0 -> 0 with 1 holes and 0 unlearned
I1015 16:30:22.278256 793427968 recover.cpp:425] Starting replica recovery
I1015 16:30:22.278350 793427968 recover.cpp:451] Replica is in EMPTY status
I1015 16:30:22.278827 791818240 replica.cpp:638] Replica in EMPTY status
received a broadcasted recover request
I1015 16:30:22.278897 793427968 recover.cpp:188] Received a recover response
from a replica in EMPTY status
I1015 16:30:22.279062 793964544 recover.cpp:542] Updating replica status to
STARTING
I1015 16:30:22.279276 792354816 master.cpp:312] Master
20141015-163022-26053898-61054-26738 (10.141.141.1) started on
10.141.141.1:61054
I1015 16:30:22.279325 792354816 master.cpp:358] Master only allowing
authenticated frameworks to register
I1015 16:30:22.279333 792354816 master.cpp:365] Master allowing unauthenticated
slaves to register
I1015 16:30:22.279341 792354816 credentials.hpp:36] Loading credentials for
authentication from '/tmp/ExamplesTest_JavaFramework_qJnXgB/credentials'
I1015 16:30:22.279333 791281664 leveldb.cpp:306] Persisting metadata (8 bytes)
to leveldb took 214us
I1015 16:30:22.279356 791281664 replica.cpp:320] Persisted replica status to
STARTING
W1015 16:30:22.279388 792354816 credentials.hpp:51] Permissions on credentials
file '/tmp/ExamplesTest_JavaFramework_qJnXgB/credentials' are too open. It is
recommended that your credentials file is NOT accessible by others.
I1015 16:30:22.279394 793964544 recover.cpp:451] Replica is in STARTING status
I1015 16:30:22.279422 792354816 master.cpp:392] Authorization enabled
I1015 16:30:22.279675 280764416 containerizer.cpp:89] Using isolation:
posix/cpu,posix/mem
I1015 16:30:22.279726 793427968 replica.cpp:638] Replica in STARTING status
received a broadcasted recover request
I1015 16:30:22.279810 790208512 recover.cpp:188] Received a recover response
from a replica in STARTING status
I1015 16:30:22.279940 793964544 recover.cpp:542] Updating replica status to
VOTING
I1015 16:30:22.280112 791281664 leveldb.cpp:306] Persisting metadata (8 bytes)
to leveldb took 115us
I1015 16:30:22.280133 791281664 replica.cpp:320] Persisted replica status to
VOTING
I1015 16:30:22.280145 793964544 slave.cpp:169] Slave started on
1)@10.141.141.1:61054
I1015 16:30:22.280171 793427968 recover.cpp:556] Successfully joined the Paxos
group
I1015 16:30:22.280227 793427968 recover.cpp:440] Recover process terminated
I1015 16:30:22.280302 793964544 slave.cpp:289] Slave resources: cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000]
I1015 16:30:22.280405 792354816 master.cpp:1242] The newly elected leader is
[email protected]:61054 with id 20141015-163022-26053898-61054-26738
I1015 16:30:22.280423 792354816 master.cpp:1255] Elected as the leading master!
I1015 16:30:22.280434 792354816 master.cpp:1073] Recovering from registrar
I1015 16:30:22.280478 790208512 registrar.cpp:313] Recovering registrar
I1015 16:30:22.280477 280764416 containerizer.cpp:89] Using isolation:
posix/cpu,posix/mem
I1015 16:30:22.280521 793964544 slave.cpp:318] Slave hostname: 10.141.141.1
I1015 16:30:22.280529 793964544 slave.cpp:319] Slave checkpoint: true
I1015 16:30:22.280633 793427968 log.cpp:656] Attempting to start the writer
I1015 16:30:22.280802 792354816 slave.cpp:169] Slave started on
2)@10.141.141.1:61054
I1015 16:30:22.280841 792891392 state.cpp:33] Recovering state from
'/var/folders/h3/8j18s1cx2bn78ms99d3lz4jh0000gn/T/mesos-XXXXXX.TWviyPgj/0/meta'
I1015 16:30:22.280932 792354816 slave.cpp:289] Slave resources: cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000]
I1015 16:30:22.280943 791818240 status_update_manager.cpp:193] Recovering
status update manager
I1015 16:30:22.281029 790208512 containerizer.cpp:252] Recovering containerizer
I1015 16:30:22.281132 791818240 replica.cpp:474] Replica received implicit
promise request with proposal 1
I1015 16:30:22.281138 792354816 slave.cpp:318] Slave hostname: 10.141.141.1
I1015 16:30:22.281148 280764416 containerizer.cpp:89] Using isolation:
posix/cpu,posix/mem
I1015 16:30:22.281158 792354816 slave.cpp:319] Slave checkpoint: true
I1015 16:30:22.281249 791818240 leveldb.cpp:306] Persisting metadata (8 bytes)
to leveldb took 102us
I1015 16:30:22.281265 791818240 replica.cpp:342] Persisted promised to 1
I1015 16:30:22.281325 792891392 slave.cpp:3355] Finished recovery
I1015 16:30:22.281502 791281664 coordinator.cpp:230] Coordinator attemping to
fill missing position
I1015 16:30:22.281574 793427968 state.cpp:33] Recovering state from
'/var/folders/h3/8j18s1cx2bn78ms99d3lz4jh0000gn/T/mesos-XXXXXX.TWviyPgj/1/meta'
I1015 16:30:22.281610 791818240 slave.cpp:169] Slave started on
3)@10.141.141.1:61054
I1015 16:30:22.281627 792891392 slave.cpp:600] New master detected at
[email protected]:61054
I1015 16:30:22.281677 790745088 status_update_manager.cpp:193] Recovering
status update manager
I1015 16:30:22.281677 792891392 slave.cpp:636] No credentials provided.
Attempting to register without authentication
I1015 16:30:22.281692 792354816 status_update_manager.cpp:167] New master
detected at [email protected]:61054
I1015 16:30:22.281707 792891392 slave.cpp:647] Detecting new master
I1015 16:30:22.281741 791818240 slave.cpp:289] Slave resources: cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000]
I1015 16:30:22.281765 792354816 containerizer.cpp:252] Recovering containerizer
I1015 16:30:22.281926 791818240 slave.cpp:318] Slave hostname: 10.141.141.1
I1015 16:30:22.281935 791818240 slave.cpp:319] Slave checkpoint: true
I1015 16:30:22.282034 792891392 slave.cpp:3355] Finished recovery
I1015 16:30:22.282068 790745088 replica.cpp:375] Replica received explicit
promise request for position 0 with proposal 2
I1015 16:30:22.282196 790745088 leveldb.cpp:343] Persisting action (8 bytes) to
leveldb took 122us
I1015 16:30:22.282219 790745088 replica.cpp:676] Persisted action at 0
I1015 16:30:22.282225 790208512 state.cpp:33] Recovering state from
'/var/folders/h3/8j18s1cx2bn78ms99d3lz4jh0000gn/T/mesos-XXXXXX.TWviyPgj/2/meta'
I1015 16:30:22.282343 791818240 status_update_manager.cpp:193] Recovering
status update manager
I1015 16:30:22.282366 793964544 slave.cpp:600] New master detected at
[email protected]:61054
I1015 16:30:22.282444 793964544 slave.cpp:636] No credentials provided.
Attempting to register without authentication
I1015 16:30:22.282452 791818240 status_update_manager.cpp:167] New master
detected at [email protected]:61054
I1015 16:30:22.282467 793964544 slave.cpp:647] Detecting new master
I1015 16:30:22.282470 792891392 containerizer.cpp:252] Recovering containerizer
I1015 16:30:22.282649 791281664 replica.cpp:508] Replica received write request
for position 0
I1015 16:30:22.282677 791281664 leveldb.cpp:438] Reading position from leveldb
took 15us
I1015 16:30:22.282727 793427968 slave.cpp:3355] Finished recovery
I1015 16:30:22.282798 791281664 leveldb.cpp:343] Persisting action (14 bytes)
to leveldb took 115us
I1015 16:30:22.282822 791281664 replica.cpp:676] Persisted action at 0
I1015 16:30:22.283046 793427968 replica.cpp:655] Replica received learned
notice for position 0
I1015 16:30:22.283058 792891392 slave.cpp:600] New master detected at
[email protected]:61054
I1015 16:30:22.283108 792891392 slave.cpp:636] No credentials provided.
Attempting to register without authentication
I1015 16:30:22.283120 792891392 slave.cpp:647] Detecting new master
I1015 16:30:22.283113 791818240 status_update_manager.cpp:167] New master
detected at [email protected]:61054
I1015 16:30:22.283148 793427968 leveldb.cpp:343] Persisting action (16 bytes)
to leveldb took 99us
I1015 16:30:22.283167 793427968 replica.cpp:676] Persisted action at 0
I1015 16:30:22.283176 793427968 replica.cpp:661] Replica learned NOP action at
position 0
I1015 16:30:22.283360 790745088 log.cpp:672] Writer started with ending
position 0
I1015 16:30:22.283639 793427968 leveldb.cpp:438] Reading position from leveldb
took 18us
I1015 16:30:22.289927 280764416 sched.cpp:137] Version: 0.21.0
I1015 16:30:22.290091 792354816 sched.cpp:233] New master detected at
[email protected]:61054
I1015 16:30:22.290112 792354816 sched.cpp:283] Authenticating with master
[email protected]:61054
I1015 16:30:22.290180 790745088 authenticatee.hpp:109] Initializing client SASL
I1015 16:30:22.290675 793964544 registrar.cpp:346] Successfully fetched the
registry (0B) in 10.181888ms
I1015 16:30:22.290717 793964544 registrar.cpp:445] Applied 1 operations in
10us; attempting to update the 'registry'
I1015 16:30:22.295140 790208512 log.cpp:680] Attempting to append 128 bytes to
the log
I1015 16:30:22.295203 792354816 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 1
I1015 16:30:22.295459 791281664 replica.cpp:508] Replica received write request
for position 1
I1015 16:30:22.295694 791281664 leveldb.cpp:343] Persisting action (147 bytes)
to leveldb took 221us
I1015 16:30:22.295721 791281664 replica.cpp:676] Persisted action at 1
I1015 16:30:22.295930 790208512 replica.cpp:655] Replica received learned
notice for position 1
I1015 16:30:22.296144 790208512 leveldb.cpp:343] Persisting action (149 bytes)
to leveldb took 180us
I1015 16:30:22.296169 790208512 replica.cpp:676] Persisted action at 1
I1015 16:30:22.296177 790208512 replica.cpp:661] Replica learned APPEND action
at position 1
I1015 16:30:22.296322 792891392 registrar.cpp:490] Successfully updated the
'registry' in 5.588992ms
I1015 16:30:22.296361 792891392 registrar.cpp:376] Successfully recovered
registrar
I1015 16:30:22.296392 791818240 log.cpp:699] Attempting to truncate the log to 1
I1015 16:30:22.296412 793964544 master.cpp:1100] Recovered 0 slaves from the
Registry (92B) ; allowing 10mins for slaves to re-register
I1015 16:30:22.296429 792891392 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 2
I1015 16:30:22.296646 790745088 authenticatee.hpp:133] Creating new client SASL
connection
I1015 16:30:22.296675 791818240 replica.cpp:508] Replica received write request
for position 2
I1015 16:30:22.296736 793964544 master.cpp:3787] Authenticating
[email protected]:61054
I1015 16:30:22.296779 792891392 authenticator.hpp:99] Initializing server SASL
I1015 16:30:22.296797 791818240 leveldb.cpp:343] Persisting action (16 bytes)
to leveldb took 114us
I1015 16:30:22.296815 791818240 replica.cpp:676] Persisted action at 2
I1015 16:30:22.296965 790208512 replica.cpp:655] Replica received learned
notice for position 2
I1015 16:30:22.297070 790208512 leveldb.cpp:343] Persisting action (18 bytes)
to leveldb took 97us
I1015 16:30:22.297096 790208512 leveldb.cpp:401] Deleting ~1 keys from leveldb
took 14us
I1015 16:30:22.297109 790208512 replica.cpp:676] Persisted action at 2
I1015 16:30:22.297116 790208512 replica.cpp:661] Replica learned TRUNCATE
action at position 2
I1015 16:30:22.299142 792891392 authenticator.hpp:161] Creating new server SASL
connection
I1015 16:30:22.299185 790208512 authenticatee.hpp:224] Received SASL
authentication mechanisms: CRAM-MD5
I1015 16:30:22.299208 790208512 authenticatee.hpp:250] Attempting to
authenticate with mechanism 'CRAM-MD5'
I1015 16:30:22.299232 790208512 authenticator.hpp:267] Received SASL
authentication start
I1015 16:30:22.299285 790208512 authenticator.hpp:389] Authentication requires
more steps
I1015 16:30:22.299309 790208512 authenticatee.hpp:270] Received SASL
authentication step
I1015 16:30:22.299352 791818240 authenticator.hpp:295] Received SASL
authentication step
I1015 16:30:22.299376 791818240 authenticator.hpp:381] Authentication success
I1015 16:30:22.299407 792354816 authenticatee.hpp:310] Authentication success
I1015 16:30:22.299432 792891392 master.cpp:3827] Successfully authenticated
principal 'test-principal' at
[email protected]:61054
I1015 16:30:22.299513 790208512 sched.cpp:357] Successfully authenticated with
master [email protected]:61054
I1015 16:30:22.299553 793427968 master.cpp:1362] Received registration request
for framework 'Test Framework (Java)' at
[email protected]:61054
I1015 16:30:22.299573 793427968 master.cpp:1321] Authorizing framework
principal 'test-principal' to receive offers for role '*'
I1015 16:30:22.299664 793427968 master.cpp:1426] Registering framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054
I1015 16:30:22.299720 790745088 hierarchical_allocator_process.hpp:329] Added
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.299788 791818240 sched.cpp:407] Framework registered with
20141015-163022-26053898-61054-26738-0000
Registered! ID = 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.677068 790208512 master.cpp:2968] Registering slave at
slave(2)@10.141.141.1:61054 (10.141.141.1) with id
20141015-163022-26053898-61054-26738-0
I1015 16:30:22.677155 792891392 registrar.cpp:445] Applied 1 operations in
22us; attempting to update the 'registry'
I1015 16:30:22.681386 791281664 log.cpp:680] Attempting to append 299 bytes to
the log
I1015 16:30:22.681440 791818240 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 3
I1015 16:30:22.681715 792354816 replica.cpp:508] Replica received write request
for position 3
I1015 16:30:22.682379 792354816 leveldb.cpp:343] Persisting action (318 bytes)
to leveldb took 666us
I1015 16:30:22.682409 792354816 replica.cpp:676] Persisted action at 3
I1015 16:30:22.682633 793964544 replica.cpp:655] Replica received learned
notice for position 3
I1015 16:30:22.682752 793964544 leveldb.cpp:343] Persisting action (320 bytes)
to leveldb took 123us
I1015 16:30:22.682781 793964544 replica.cpp:676] Persisted action at 3
I1015 16:30:22.682797 793964544 replica.cpp:661] Replica learned APPEND action
at position 3
I1015 16:30:22.682978 791281664 registrar.cpp:490] Successfully updated the
'registry' in 5.80096ms
I1015 16:30:22.683071 792354816 log.cpp:699] Attempting to truncate the log to 3
I1015 16:30:22.683106 792891392 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 4
I1015 16:30:22.683153 790745088 master.cpp:3022] Registered slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1) with cpus(*):2; mem(*):10240; disk(*):470842;
ports(*):[31000-32000]
I1015 16:30:22.683174 793427968 slave.cpp:765] Registered with master
[email protected]:61054; given slave ID 20141015-163022-26053898-61054-26738-0
I1015 16:30:22.683195 792354816 hierarchical_allocator_process.hpp:442] Added
slave 20141015-163022-26053898-61054-26738-0 (10.141.141.1) with cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000] (and cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000] available)
I1015 16:30:22.683353 790208512 replica.cpp:508] Replica received write request
for position 4
I1015 16:30:22.683429 791281664 master.cpp:3729] Sending 1 offers to framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054
I1015 16:30:22.683471 790208512 leveldb.cpp:343] Persisting action (16 bytes)
to leveldb took 122us
I1015 16:30:22.683500 790208512 replica.cpp:676] Persisted action at 4
I1015 16:30:22.683662 791818240 replica.cpp:655] Replica received learned
notice for position 4
I1015 16:30:22.683779 791818240 leveldb.cpp:343] Persisting action (18 bytes)
to leveldb took 112us
I1015 16:30:22.683831 791818240 leveldb.cpp:401] Deleting ~2 keys from leveldb
took 17us
I1015 16:30:22.683846 791818240 replica.cpp:676] Persisted action at 4
I1015 16:30:22.683854 791818240 replica.cpp:661] Replica learned TRUNCATE
action at position 4
Received offer 20141015-163022-26053898-61054-26738-0 with cpus: 2.0 and mem:
10240.0
Launching task 0 using offer 20141015-163022-26053898-61054-26738-0
Launching task 1 using offer 20141015-163022-26053898-61054-26738-0
I1015 16:30:22.704804 792891392 master.cpp:2315] Processing reply for offers: [
20141015-163022-26053898-61054-26738-0 ] on slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1) for framework 20141015-163022-26053898-61054-26738-0000 (Test
Framework (Java)) at
[email protected]:61054
W1015 16:30:22.704854 792891392 master.cpp:1969] 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.
W1015 16:30:22.704874 792891392 master.cpp:1980] 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.
I1015 16:30:22.704885 792891392 master.cpp:2397] Authorizing framework
principal 'test-principal' to launch task 0 as user 'alex'
W1015 16:30:22.704943 792891392 master.cpp:1969] Executor default for task 1
uses less CPUs (None) than the minimum required (0.01). Please update your
executor, as this will be mandatory in future releases.
W1015 16:30:22.704957 792891392 master.cpp:1980] Executor default for task 1
uses less memory (None) than the minimum required (32MB). Please update your
executor, as this will be mandatory in future releases.
I1015 16:30:22.704973 792891392 master.cpp:2397] Authorizing framework
principal 'test-principal' to launch task 1 as user 'alex'
I1015 16:30:22.705209 792891392 master.hpp:869] Adding task 0 with resources
cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-0
(10.141.141.1)
I1015 16:30:22.705229 792891392 master.cpp:2463] Launching task 0 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 with
resources cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-0
at slave(2)@10.141.141.1:61054 (10.141.141.1)
I1015 16:30:22.705291 792891392 master.hpp:869] Adding task 1 with resources
cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-0
(10.141.141.1)
I1015 16:30:22.705292 790208512 slave.cpp:1079] Got assigned task 0 for
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.705303 792891392 master.cpp:2463] Launching task 1 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 with
resources cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-0
at slave(2)@10.141.141.1:61054 (10.141.141.1)
I1015 16:30:22.705421 791818240 hierarchical_allocator_process.hpp:563]
Recovered mem(*):9984; disk(*):470842; ports(*):[31000-32000] (total
allocatable: mem(*):9984; disk(*):470842; ports(*):[31000-32000]) on slave
20141015-163022-26053898-61054-26738-0 from framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.705514 790208512 slave.cpp:1079] Got assigned task 1 for
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.705633 790208512 slave.cpp:1192] Launching task 0 for framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.710464 790208512 slave.cpp:3759] Launching executor default of
framework 20141015-163022-26053898-61054-26738-0000 in work directory
'/var/folders/h3/8j18s1cx2bn78ms99d3lz4jh0000gn/T/mesos-XXXXXX.TWviyPgj/1/slaves/20141015-163022-26053898-61054-26738-0/frameworks/20141015-163022-26053898-61054-26738-0000/executors/default/runs/44d10b88-ea25-4fc5-8469-bb459e536116'
I1015 16:30:22.710577 790745088 containerizer.cpp:394] Starting container
'44d10b88-ea25-4fc5-8469-bb459e536116' for executor 'default' of framework
'20141015-163022-26053898-61054-26738-0000'
I1015 16:30:22.710585 790208512 slave.cpp:1306] Queuing task '0' for executor
default of framework '20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.710615 790208512 slave.cpp:1192] Launching task 1 for framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.710633 790208512 slave.cpp:1306] Queuing task '1' for executor
default of framework '20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.712183 790745088 launcher.cpp:137] Forked child with pid '26773'
for container '44d10b88-ea25-4fc5-8469-bb459e536116'
I1015 16:30:22.712730 790745088 containerizer.cpp:510] Fetching URIs for
container '44d10b88-ea25-4fc5-8469-bb459e536116' using command
'/Users/alex/Projects/mesos/build/default/src/mesos-fetcher'
I1015 16:30:22.819401 791281664 slave.cpp:2695] Monitoring executor 'default'
of framework '20141015-163022-26053898-61054-26738-0000' in container
'44d10b88-ea25-4fc5-8469-bb459e536116'
I1015 16:30:22.937247 282861568 exec.cpp:146] Version: 0.21.0
I1015 16:30:22.938525 792354816 slave.cpp:1817] Got registration for executor
'default' of framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61055
I1015 16:30:22.938689 792354816 slave.cpp:1936] Flushing queued task 0 for
executor 'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.938726 792354816 slave.cpp:1936] Flushing queued task 1 for
executor 'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.938899 793595904 exec.cpp:220] Executor registered on slave
20141015-163022-26053898-61054-26738-0
Registered executor on 10.141.141.1
Running task 0
Running task 1
I1015 16:30:22.991192 791818240 slave.cpp:2170] Handling status update
TASK_RUNNING (UUID: 7cf3fc5d-4365-4910-8e03-338a3ad50826) for task 0 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61055
I1015 16:30:22.991318 793427968 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 7cf3fc5d-4365-4910-8e03-338a3ad50826) for task 0 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.991448 793427968 status_update_manager.cpp:373] Forwarding
status update TASK_RUNNING (UUID: 7cf3fc5d-4365-4910-8e03-338a3ad50826) for
task 0 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:22.991534 793964544 master.cpp:3352] Forwarding status update
TASK_RUNNING (UUID: 7cf3fc5d-4365-4910-8e03-338a3ad50826) for task 0 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.991554 792354816 slave.cpp:2333] Sending acknowledgement for
status update TASK_RUNNING (UUID: 7cf3fc5d-4365-4910-8e03-338a3ad50826) for
task 0 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61055
I1015 16:30:22.991588 793964544 master.cpp:3324] Status update TASK_RUNNING
(UUID: 7cf3fc5d-4365-4910-8e03-338a3ad50826) for task 0 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:22.995391 793427968 slave.cpp:2170] Handling status update
TASK_RUNNING (UUID: e9b2519e-47f6-47f9-b463-f563bf6d385a) for task 1 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61055
I1015 16:30:22.995445 791818240 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: e9b2519e-47f6-47f9-b463-f563bf6d385a) for task 1 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.995546 791818240 status_update_manager.cpp:373] Forwarding
status update TASK_RUNNING (UUID: e9b2519e-47f6-47f9-b463-f563bf6d385a) for
task 1 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:22.995622 793964544 master.cpp:3352] Forwarding status update
TASK_RUNNING (UUID: e9b2519e-47f6-47f9-b463-f563bf6d385a) for task 1 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:22.995645 793427968 slave.cpp:2333] Sending acknowledgement for
status update TASK_RUNNING (UUID: e9b2519e-47f6-47f9-b463-f563bf6d385a) for
task 1 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61055
I1015 16:30:22.995661 793964544 master.cpp:3324] Status update TASK_RUNNING
(UUID: e9b2519e-47f6-47f9-b463-f563bf6d385a) for task 1 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:22.999641 792354816 slave.cpp:2170] Handling status update
TASK_FINISHED (UUID: 58103509-643c-49ea-88e7-c4ada77704af) for task 0 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61055
I1015 16:30:22.999980 790208512 status_update_manager.cpp:320] Received status
update TASK_FINISHED (UUID: 58103509-643c-49ea-88e7-c4ada77704af) for task 0 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.000069 791281664 slave.cpp:2333] Sending acknowledgement for
status update TASK_FINISHED (UUID: 58103509-643c-49ea-88e7-c4ada77704af) for
task 0 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61055
I1015 16:30:23.003921 792354816 slave.cpp:2170] Handling status update
TASK_FINISHED (UUID: c9a3e405-09d2-452f-b9c9-6904b45d10ad) for task 1 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61055
I1015 16:30:23.004178 790745088 status_update_manager.cpp:320] Received status
update TASK_FINISHED (UUID: c9a3e405-09d2-452f-b9c9-6904b45d10ad) for task 1 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.004228 792354816 slave.cpp:2333] Sending acknowledgement for
status update TASK_FINISHED (UUID: c9a3e405-09d2-452f-b9c9-6904b45d10ad) for
task 1 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61055
Status update: task 0 is in state TASK_RUNNING
I1015 16:30:23.037717 793964544 master.cpp:2813] Forwarding status update
acknowledgement 7cf3fc5d-4365-4910-8e03-338a3ad50826 for task 0 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.037828 790208512 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 7cf3fc5d-4365-4910-8e03-338a3ad50826) for task 0
of framework 20141015-163022-26053898-61054-26738-0000
Status update: task 1 is in state TASK_RUNNING
I1015 16:30:23.037868 790208512 status_update_manager.cpp:373] Forwarding
status update TASK_FINISHED (UUID: 58103509-643c-49ea-88e7-c4ada77704af) for
task 0 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.037917 792354816 master.cpp:2813] Forwarding status update
acknowledgement e9b2519e-47f6-47f9-b463-f563bf6d385a for task 1 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.037981 792354816 master.cpp:3352] Forwarding status update
TASK_FINISHED (UUID: 58103509-643c-49ea-88e7-c4ada77704af) for task 0 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.037997 790745088 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: e9b2519e-47f6-47f9-b463-f563bf6d385a) for task 1
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.038028 790745088 status_update_manager.cpp:373] Forwarding
status update TASK_FINISHED (UUID: c9a3e405-09d2-452f-b9c9-6904b45d10ad) for
task 1 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.038041 792354816 master.cpp:3324] Status update TASK_FINISHED
(UUID: 58103509-643c-49ea-88e7-c4ada77704af) for task 0 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.038141 792354816 master.cpp:3352] Forwarding status update
TASK_FINISHED (UUID: c9a3e405-09d2-452f-b9c9-6904b45d10ad) for task 1 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.038147 792891392 hierarchical_allocator_process.hpp:563]
Recovered cpus(*):1; mem(*):128 (total allocatable: mem(*):10112;
disk(*):470842; ports(*):[31000-32000]; cpus(*):1) on slave
20141015-163022-26053898-61054-26738-0 from framework
20141015-163022-26053898-61054-26738-0000
Status update: task 0 is in state TASK_FINISHED
I1015 16:30:23.038179 792354816 master.cpp:3324] Status update TASK_FINISHED
(UUID: c9a3e405-09d2-452f-b9c9-6904b45d10ad) for task 1 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
Finished tasks: 1
I1015 16:30:23.038239 793964544 hierarchical_allocator_process.hpp:563]
Recovered cpus(*):1; mem(*):128 (total allocatable: mem(*):10240;
disk(*):470842; ports(*):[31000-32000]; cpus(*):2) on slave
20141015-163022-26053898-61054-26738-0 from framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.038275 790745088 master.cpp:4542] Removing task 0 with resources
cpus(*):1; mem(*):128 of framework 20141015-163022-26053898-61054-26738-0000 on
slave 20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
Status update: task 1 is in state TASK_FINISHEDI1015 16:30:23.038323 790745088
master.cpp:2813] Forwarding status update acknowledgement
58103509-643c-49ea-88e7-c4ada77704af for task 0 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
Finished tasks: 2
I1015 16:30:23.038401 791281664 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 58103509-643c-49ea-88e7-c4ada77704af) for task 0
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.038419 790208512 master.cpp:4542] Removing task 1 with resources
cpus(*):1; mem(*):128 of framework 20141015-163022-26053898-61054-26738-0000 on
slave 20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.038444 790208512 master.cpp:2813] Forwarding status update
acknowledgement c9a3e405-09d2-452f-b9c9-6904b45d10ad for task 1 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.038522 790745088 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: c9a3e405-09d2-452f-b9c9-6904b45d10ad) for task 1
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.067595 793964544 master.cpp:2968] Registering slave at
slave(3)@10.141.141.1:61054 (10.141.141.1) with id
20141015-163022-26053898-61054-26738-1
I1015 16:30:23.067772 790208512 registrar.cpp:445] Applied 1 operations in
67us; attempting to update the 'registry'
I1015 16:30:23.074892 791818240 log.cpp:680] Attempting to append 464 bytes to
the log
I1015 16:30:23.074937 790208512 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 5
I1015 16:30:23.075273 792354816 replica.cpp:508] Replica received write request
for position 5
I1015 16:30:23.075933 792354816 leveldb.cpp:343] Persisting action (483 bytes)
to leveldb took 654us
I1015 16:30:23.075964 792354816 replica.cpp:676] Persisted action at 5
I1015 16:30:23.076187 790208512 replica.cpp:655] Replica received learned
notice for position 5
I1015 16:30:23.076341 790208512 leveldb.cpp:343] Persisting action (485 bytes)
to leveldb took 144us
I1015 16:30:23.076360 790208512 replica.cpp:676] Persisted action at 5
I1015 16:30:23.076367 790208512 replica.cpp:661] Replica learned APPEND action
at position 5
I1015 16:30:23.076612 790745088 registrar.cpp:490] Successfully updated the
'registry' in 8.772864ms
I1015 16:30:23.076674 792354816 log.cpp:699] Attempting to truncate the log to 5
I1015 16:30:23.076722 793427968 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 6
I1015 16:30:23.076851 790208512 master.cpp:3022] Registered slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1) with cpus(*):2; mem(*):10240; disk(*):470842;
ports(*):[31000-32000]
I1015 16:30:23.076869 792354816 slave.cpp:765] Registered with master
[email protected]:61054; given slave ID 20141015-163022-26053898-61054-26738-1
I1015 16:30:23.076896 791281664 hierarchical_allocator_process.hpp:442] Added
slave 20141015-163022-26053898-61054-26738-1 (10.141.141.1) with cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000] (and cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000] available)
I1015 16:30:23.077024 790208512 replica.cpp:508] Replica received write request
for position 6
I1015 16:30:23.077148 793964544 master.cpp:3729] Sending 1 offers to framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054
I1015 16:30:23.077168 790208512 leveldb.cpp:343] Persisting action (16 bytes)
to leveldb took 132us
I1015 16:30:23.077193 790208512 replica.cpp:676] Persisted action at 6
I1015 16:30:23.077363 790745088 replica.cpp:655] Replica received learned
notice for position 6
I1015 16:30:23.077513 790745088 leveldb.cpp:343] Persisting action (18 bytes)
to leveldb took 144us
I1015 16:30:23.077554 790745088 leveldb.cpp:401] Deleting ~2 keys from leveldb
took 21us
I1015 16:30:23.077572 790745088 replica.cpp:676] Persisted action at 6
I1015 16:30:23.077579 790745088 replica.cpp:661] Replica learned TRUNCATE
action at position 6
Received offer 20141015-163022-26053898-61054-26738-1 with cpus: 2.0 and mem:
10240.0
Launching task 2 using offer 20141015-163022-26053898-61054-26738-1
Launching task 3 using offer 20141015-163022-26053898-61054-26738-1
I1015 16:30:23.078325 793964544 master.cpp:2315] Processing reply for offers: [
20141015-163022-26053898-61054-26738-1 ] on slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1) for framework 20141015-163022-26053898-61054-26738-0000 (Test
Framework (Java)) at
[email protected]:61054
W1015 16:30:23.078356 793964544 master.cpp:1969] Executor default for task 2
uses less CPUs (None) than the minimum required (0.01). Please update your
executor, as this will be mandatory in future releases.
W1015 16:30:23.078377 793964544 master.cpp:1980] Executor default for task 2
uses less memory (None) than the minimum required (32MB). Please update your
executor, as this will be mandatory in future releases.
I1015 16:30:23.078392 793964544 master.cpp:2397] Authorizing framework
principal 'test-principal' to launch task 2 as user 'alex'
W1015 16:30:23.078461 793964544 master.cpp:1969] Executor default for task 3
uses less CPUs (None) than the minimum required (0.01). Please update your
executor, as this will be mandatory in future releases.
W1015 16:30:23.078479 793964544 master.cpp:1980] Executor default for task 3
uses less memory (None) than the minimum required (32MB). Please update your
executor, as this will be mandatory in future releases.
I1015 16:30:23.078492 793964544 master.cpp:2397] Authorizing framework
principal 'test-principal' to launch task 3 as user 'alex'
I1015 16:30:23.078735 793964544 master.hpp:869] Adding task 2 with resources
cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-1
(10.141.141.1)
I1015 16:30:23.078757 793964544 master.cpp:2463] Launching task 2 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 with
resources cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-1
at slave(3)@10.141.141.1:61054 (10.141.141.1)
I1015 16:30:23.078809 793427968 slave.cpp:1079] Got assigned task 2 for
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.078817 793964544 master.hpp:869] Adding task 3 with resources
cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-1
(10.141.141.1)
I1015 16:30:23.078857 793964544 master.cpp:2463] Launching task 3 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 with
resources cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-1
at slave(3)@10.141.141.1:61054 (10.141.141.1)
I1015 16:30:23.079020 793427968 slave.cpp:1079] Got assigned task 3 for
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.079031 791818240 hierarchical_allocator_process.hpp:563]
Recovered mem(*):9984; disk(*):470842; ports(*):[31000-32000] (total
allocatable: mem(*):9984; disk(*):470842; ports(*):[31000-32000]) on slave
20141015-163022-26053898-61054-26738-1 from framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.079155 793427968 slave.cpp:1192] Launching task 2 for framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.083992 793427968 slave.cpp:3759] Launching executor default of
framework 20141015-163022-26053898-61054-26738-0000 in work directory
'/var/folders/h3/8j18s1cx2bn78ms99d3lz4jh0000gn/T/mesos-XXXXXX.TWviyPgj/2/slaves/20141015-163022-26053898-61054-26738-1/frameworks/20141015-163022-26053898-61054-26738-0000/executors/default/runs/3967922e-cc55-439c-bc1f-a06151699e7e'
I1015 16:30:23.084105 792354816 containerizer.cpp:394] Starting container
'3967922e-cc55-439c-bc1f-a06151699e7e' for executor 'default' of framework
'20141015-163022-26053898-61054-26738-0000'
I1015 16:30:23.084127 793427968 slave.cpp:1306] Queuing task '2' for executor
default of framework '20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.084159 793427968 slave.cpp:1192] Launching task 3 for framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.084175 793427968 slave.cpp:1306] Queuing task '3' for executor
default of framework '20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.085754 792354816 launcher.cpp:137] Forked child with pid '26832'
for container '3967922e-cc55-439c-bc1f-a06151699e7e'
I1015 16:30:23.086323 792354816 containerizer.cpp:510] Fetching URIs for
container '3967922e-cc55-439c-bc1f-a06151699e7e' using command
'/Users/alex/Projects/mesos/build/default/src/mesos-fetcher'
I1015 16:30:23.123929 791818240 master.cpp:2968] Registering slave at
slave(1)@10.141.141.1:61054 (10.141.141.1) with id
20141015-163022-26053898-61054-26738-2
I1015 16:30:23.124691 793964544 registrar.cpp:445] Applied 1 operations in
60us; attempting to update the 'registry'
I1015 16:30:23.129683 790208512 slave.cpp:2695] Monitoring executor 'default'
of framework '20141015-163022-26053898-61054-26738-0000' in container
'3967922e-cc55-439c-bc1f-a06151699e7e'
I1015 16:30:23.130925 793427968 log.cpp:680] Attempting to append 629 bytes to
the log
I1015 16:30:23.131001 791281664 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 7
I1015 16:30:23.131314 793427968 replica.cpp:508] Replica received write request
for position 7
I1015 16:30:23.131525 793427968 leveldb.cpp:343] Persisting action (648 bytes)
to leveldb took 187us
I1015 16:30:23.131551 793427968 replica.cpp:676] Persisted action at 7
I1015 16:30:23.131736 791281664 replica.cpp:655] Replica received learned
notice for position 7
I1015 16:30:23.131891 791281664 leveldb.cpp:343] Persisting action (650 bytes)
to leveldb took 141us
I1015 16:30:23.131914 791281664 replica.cpp:676] Persisted action at 7
I1015 16:30:23.131922 791281664 replica.cpp:661] Replica learned APPEND action
at position 7
I1015 16:30:23.132200 793964544 registrar.cpp:490] Successfully updated the
'registry' in 7.420928ms
I1015 16:30:23.132320 792354816 log.cpp:699] Attempting to truncate the log to 7
I1015 16:30:23.132372 793427968 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 8
I1015 16:30:23.132434 790208512 master.cpp:3022] Registered slave
20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1) with cpus(*):2; mem(*):10240; disk(*):470842;
ports(*):[31000-32000]
I1015 16:30:23.132460 791818240 slave.cpp:765] Registered with master
[email protected]:61054; given slave ID 20141015-163022-26053898-61054-26738-2
I1015 16:30:23.132490 791281664 hierarchical_allocator_process.hpp:442] Added
slave 20141015-163022-26053898-61054-26738-2 (10.141.141.1) with cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000] (and cpus(*):2;
mem(*):10240; disk(*):470842; ports(*):[31000-32000] available)
I1015 16:30:23.132604 790208512 replica.cpp:508] Replica received write request
for position 8
I1015 16:30:23.132699 792354816 master.cpp:3729] Sending 1 offers to framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054
I1015 16:30:23.132742 790208512 leveldb.cpp:343] Persisting action (16 bytes)
to leveldb took 133us
I1015 16:30:23.132767 790208512 replica.cpp:676] Persisted action at 8
I1015 16:30:23.132956 790208512 replica.cpp:655] Replica received learned
notice for position 8
I1015 16:30:23.133065 790208512 leveldb.cpp:343] Persisting action (18 bytes)
to leveldb took 106us
I1015 16:30:23.133098 790208512 leveldb.cpp:401] Deleting ~2 keys from leveldb
took 17us
I1015 16:30:23.133113 790208512 replica.cpp:676] Persisted action at 8
I1015 16:30:23.133121 790208512 replica.cpp:661] Replica learned TRUNCATE
action at position 8
Received offer 20141015-163022-26053898-61054-26738-2 with cpus: 2.0 and mem:
10240.0
Launching task 4 using offer 20141015-163022-26053898-61054-26738-2
I1015 16:30:23.133801 793964544 master.cpp:2315] Processing reply for offers: [
20141015-163022-26053898-61054-26738-2 ] on slave
20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1) for framework 20141015-163022-26053898-61054-26738-0000 (Test
Framework (Java)) at
[email protected]:61054
W1015 16:30:23.133824 793964544 master.cpp:1969] Executor default for task 4
uses less CPUs (None) than the minimum required (0.01). Please update your
executor, as this will be mandatory in future releases.
W1015 16:30:23.133836 793964544 master.cpp:1980] Executor default for task 4
uses less memory (None) than the minimum required (32MB). Please update your
executor, as this will be mandatory in future releases.
I1015 16:30:23.133844 793964544 master.cpp:2397] Authorizing framework
principal 'test-principal' to launch task 4 as user 'alex'
I1015 16:30:23.133985 793964544 master.hpp:869] Adding task 4 with resources
cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-2
(10.141.141.1)
I1015 16:30:23.134001 793964544 master.cpp:2463] Launching task 4 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 with
resources cpus(*):1; mem(*):128 on slave 20141015-163022-26053898-61054-26738-2
at slave(1)@10.141.141.1:61054 (10.141.141.1)
I1015 16:30:23.134052 790745088 slave.cpp:1079] Got assigned task 4 for
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.134181 792354816 hierarchical_allocator_process.hpp:563]
Recovered cpus(*):1; mem(*):10112; disk(*):470842; ports(*):[31000-32000]
(total allocatable: cpus(*):1; mem(*):10112; disk(*):470842;
ports(*):[31000-32000]) on slave 20141015-163022-26053898-61054-26738-2 from
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.134243 790745088 slave.cpp:1192] Launching task 4 for framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.139283 790745088 slave.cpp:3759] Launching executor default of
framework 20141015-163022-26053898-61054-26738-0000 in work directory
'/var/folders/h3/8j18s1cx2bn78ms99d3lz4jh0000gn/T/mesos-XXXXXX.TWviyPgj/0/slaves/20141015-163022-26053898-61054-26738-2/frameworks/20141015-163022-26053898-61054-26738-0000/executors/default/runs/f185c0d2-62da-44be-ba0b-abd85f187c8b'
I1015 16:30:23.139420 790745088 slave.cpp:1306] Queuing task '4' for executor
default of framework '20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.139425 791281664 containerizer.cpp:394] Starting container
'f185c0d2-62da-44be-ba0b-abd85f187c8b' for executor 'default' of framework
'20141015-163022-26053898-61054-26738-0000'
I1015 16:30:23.140909 791281664 launcher.cpp:137] Forked child with pid '26867'
for container 'f185c0d2-62da-44be-ba0b-abd85f187c8b'
I1015 16:30:23.141662 791281664 containerizer.cpp:510] Fetching URIs for
container 'f185c0d2-62da-44be-ba0b-abd85f187c8b' using command
'/Users/alex/Projects/mesos/build/default/src/mesos-fetcher'
I1015 16:30:23.234964 793964544 slave.cpp:2695] Monitoring executor 'default'
of framework '20141015-163022-26053898-61054-26738-0000' in container
'f185c0d2-62da-44be-ba0b-abd85f187c8b'
I1015 16:30:23.257539 150740992 exec.cpp:146] Version: 0.21.0
I1015 16:30:23.259127 793427968 slave.cpp:1817] Got registration for executor
'default' of framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61062
I1015 16:30:23.259337 793427968 slave.cpp:1936] Flushing queued task 2 for
executor 'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.259387 793427968 slave.cpp:1936] Flushing queued task 3 for
executor 'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.259528 664621056 exec.cpp:220] Executor registered on slave
20141015-163022-26053898-61054-26738-1
I1015 16:30:23.280979 793427968 master.cpp:3729] Sending 1 offers to framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054
Received offer 20141015-163022-26053898-61054-26738-3 with cpus: 2.0 and mem:
10240.0
I1015 16:30:23.281956 791818240 master.cpp:2315] Processing reply for offers: [
20141015-163022-26053898-61054-26738-3 ] on slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1) for framework 20141015-163022-26053898-61054-26738-0000 (Test
Framework (Java)) at
[email protected]:61054
I1015 16:30:23.282110 792354816 hierarchical_allocator_process.hpp:563]
Recovered mem(*):10240; disk(*):470842; ports(*):[31000-32000]; cpus(*):2
(total allocatable: mem(*):10240; disk(*):470842; ports(*):[31000-32000];
cpus(*):2) on slave 20141015-163022-26053898-61054-26738-0 from framework
20141015-163022-26053898-61054-26738-0000
Registered executor on 10.141.141.1
Running task 2
Running task 3
I1015 16:30:23.315791 791818240 slave.cpp:2170] Handling status update
TASK_RUNNING (UUID: 7f376dfa-6224-4fbb-ae7b-f557d097c16c) for task 3 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61062
I1015 16:30:23.315876 792354816 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 7f376dfa-6224-4fbb-ae7b-f557d097c16c) for task 3 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.315963 792354816 status_update_manager.cpp:373] Forwarding
status update TASK_RUNNING (UUID: 7f376dfa-6224-4fbb-ae7b-f557d097c16c) for
task 3 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.316040 793964544 master.cpp:3352] Forwarding status update
TASK_RUNNING (UUID: 7f376dfa-6224-4fbb-ae7b-f557d097c16c) for task 3 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.316048 793427968 slave.cpp:2333] Sending acknowledgement for
status update TASK_RUNNING (UUID: 7f376dfa-6224-4fbb-ae7b-f557d097c16c) for
task 3 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61062
I1015 16:30:23.316082 793964544 master.cpp:3324] Status update TASK_RUNNING
(UUID: 7f376dfa-6224-4fbb-ae7b-f557d097c16c) for task 3 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
Status update: task 3 is in state TASK_RUNNING
I1015 16:30:23.316443 792354816 master.cpp:2813] Forwarding status update
acknowledgement 7f376dfa-6224-4fbb-ae7b-f557d097c16c for task 3 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.316551 793964544 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 7f376dfa-6224-4fbb-ae7b-f557d097c16c) for task 3
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.320018 792354816 slave.cpp:2170] Handling status update
TASK_RUNNING (UUID: 26043b1d-3e45-4694-8717-48f738c0ac9d) for task 2 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61062
I1015 16:30:23.320070 791818240 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 26043b1d-3e45-4694-8717-48f738c0ac9d) for task 2 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.320143 791818240 status_update_manager.cpp:373] Forwarding
status update TASK_RUNNING (UUID: 26043b1d-3e45-4694-8717-48f738c0ac9d) for
task 2 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.320221 790745088 master.cpp:3352] Forwarding status update
TASK_RUNNING (UUID: 26043b1d-3e45-4694-8717-48f738c0ac9d) for task 2 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.320246 790208512 slave.cpp:2333] Sending acknowledgement for
status update TASK_RUNNING (UUID: 26043b1d-3e45-4694-8717-48f738c0ac9d) for
task 2 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61062
I1015 16:30:23.320274 790745088 master.cpp:3324] Status update TASK_RUNNING
(UUID: 26043b1d-3e45-4694-8717-48f738c0ac9d) for task 2 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
Status update: task 2 is in state TASK_RUNNING
I1015 16:30:23.320621 790745088 master.cpp:2813] Forwarding status update
acknowledgement 26043b1d-3e45-4694-8717-48f738c0ac9d for task 2 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.320727 793964544 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 26043b1d-3e45-4694-8717-48f738c0ac9d) for task 2
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.324249 790745088 slave.cpp:2170] Handling status update
TASK_FINISHED (UUID: 201a2a92-2c48-47e7-abc3-d9886f853290) for task 2 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61062
I1015 16:30:23.324501 792891392 status_update_manager.cpp:320] Received status
update TASK_FINISHED (UUID: 201a2a92-2c48-47e7-abc3-d9886f853290) for task 2 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.324550 792891392 status_update_manager.cpp:373] Forwarding
status update TASK_FINISHED (UUID: 201a2a92-2c48-47e7-abc3-d9886f853290) for
task 2 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.324607 790745088 master.cpp:3352] Forwarding status update
TASK_FINISHED (UUID: 201a2a92-2c48-47e7-abc3-d9886f853290) for task 2 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.324622 792891392 slave.cpp:2333] Sending acknowledgement for
status update TASK_FINISHED (UUID: 201a2a92-2c48-47e7-abc3-d9886f853290) for
task 2 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61062
I1015 16:30:23.324653 790745088 master.cpp:3324] Status update TASK_FINISHED
(UUID: 201a2a92-2c48-47e7-abc3-d9886f853290) for task 2 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.324779 792891392 hierarchical_allocator_process.hpp:563]
Recovered cpus(*):1; mem(*):128 (total allocatable: mem(*):10112;
disk(*):470842; ports(*):[31000-32000]; cpus(*):1) on slave
20141015-163022-26053898-61054-26738-1 from framework
20141015-163022-26053898-61054-26738-0000
Status update: task 2 is in state TASK_FINISHED
Finished tasks: 3
I1015 16:30:23.325112 791818240 master.cpp:4542] Removing task 2 with resources
cpus(*):1; mem(*):128 of framework 20141015-163022-26053898-61054-26738-0000 on
slave 20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.325161 791818240 master.cpp:2813] Forwarding status update
acknowledgement 201a2a92-2c48-47e7-abc3-d9886f853290 for task 2 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.325235 792891392 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 201a2a92-2c48-47e7-abc3-d9886f853290) for task 2
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.328444 790745088 slave.cpp:2170] Handling status update
TASK_FINISHED (UUID: 16de290d-7fbc-4591-9c73-fefa2fcf1fd5) for task 3 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61062
I1015 16:30:23.328781 791818240 status_update_manager.cpp:320] Received status
update TASK_FINISHED (UUID: 16de290d-7fbc-4591-9c73-fefa2fcf1fd5) for task 3 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.328809 791818240 status_update_manager.cpp:373] Forwarding
status update TASK_FINISHED (UUID: 16de290d-7fbc-4591-9c73-fefa2fcf1fd5) for
task 3 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.328868 790745088 master.cpp:3352] Forwarding status update
TASK_FINISHED (UUID: 16de290d-7fbc-4591-9c73-fefa2fcf1fd5) for task 3 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.328871 793427968 slave.cpp:2333] Sending acknowledgement for
status update TASK_FINISHED (UUID: 16de290d-7fbc-4591-9c73-fefa2fcf1fd5) for
task 3 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61062
I1015 16:30:23.328915 790745088 master.cpp:3324] Status update TASK_FINISHED
(UUID: 16de290d-7fbc-4591-9c73-fefa2fcf1fd5) for task 3 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
Status update: task 3 is in state TASK_FINISHED
I1015 16:30:23.329155 792354816 hierarchical_allocator_process.hpp:563]
Recovered cpus(*):1; mem(*):128 (total allocatable: mem(*):10240;
disk(*):470842; ports(*):[31000-32000]; cpus(*):2) on slave
20141015-163022-26053898-61054-26738-1 from framework
20141015-163022-26053898-61054-26738-0000
Finished tasks: 4
I1015 16:30:23.329262 790745088 master.cpp:4542] Removing task 3 with resources
cpus(*):1; mem(*):128 of framework 20141015-163022-26053898-61054-26738-0000 on
slave 20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.329318 790745088 master.cpp:2813] Forwarding status update
acknowledgement 16de290d-7fbc-4591-9c73-fefa2fcf1fd5 for task 3 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.329406 793427968 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 16de290d-7fbc-4591-9c73-fefa2fcf1fd5) for task 3
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.364958 169615360 exec.cpp:146] Version: 0.21.0
I1015 16:30:23.366132 791818240 slave.cpp:1817] Got registration for executor
'default' of framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61070
I1015 16:30:23.366294 791818240 slave.cpp:1936] Flushing queued task 4 for
executor 'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.366498 679518208 exec.cpp:220] Executor registered on slave
20141015-163022-26053898-61054-26738-2
Registered executor on 10.141.141.1
Running task 4
I1015 16:30:23.420583 791281664 slave.cpp:2170] Handling status update
TASK_RUNNING (UUID: 843064f8-f886-43ef-87e3-7ed244a5db64) for task 4 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61070
I1015 16:30:23.420678 793427968 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 843064f8-f886-43ef-87e3-7ed244a5db64) for task 4 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.420747 793427968 status_update_manager.cpp:373] Forwarding
status update TASK_RUNNING (UUID: 843064f8-f886-43ef-87e3-7ed244a5db64) for
task 4 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.420795 790745088 master.cpp:3352] Forwarding status update
TASK_RUNNING (UUID: 843064f8-f886-43ef-87e3-7ed244a5db64) for task 4 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.420821 793964544 slave.cpp:2333] Sending acknowledgement for
status update TASK_RUNNING (UUID: 843064f8-f886-43ef-87e3-7ed244a5db64) for
task 4 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61070
I1015 16:30:23.420847 790745088 master.cpp:3324] Status update TASK_RUNNING
(UUID: 843064f8-f886-43ef-87e3-7ed244a5db64) for task 4 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1)
Status update: task 4 is in state TASK_RUNNING
I1015 16:30:23.421138 790745088 master.cpp:2813] Forwarding status update
acknowledgement 843064f8-f886-43ef-87e3-7ed244a5db64 for task 4 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.421226 790208512 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 843064f8-f886-43ef-87e3-7ed244a5db64) for task 4
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.424799 790208512 slave.cpp:2170] Handling status update
TASK_FINISHED (UUID: dbe5f202-f34c-430a-8155-0459aae5ef04) for task 4 of
framework 20141015-163022-26053898-61054-26738-0000 from
executor(1)@10.141.141.1:61070
I1015 16:30:23.425129 790208512 status_update_manager.cpp:320] Received status
update TASK_FINISHED (UUID: dbe5f202-f34c-430a-8155-0459aae5ef04) for task 4 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425164 790208512 status_update_manager.cpp:373] Forwarding
status update TASK_FINISHED (UUID: dbe5f202-f34c-430a-8155-0459aae5ef04) for
task 4 of framework 20141015-163022-26053898-61054-26738-0000 to
[email protected]:61054
I1015 16:30:23.425223 793427968 master.cpp:3352] Forwarding status update
TASK_FINISHED (UUID: dbe5f202-f34c-430a-8155-0459aae5ef04) for task 4 of
framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425254 792891392 slave.cpp:2333] Sending acknowledgement for
status update TASK_FINISHED (UUID: dbe5f202-f34c-430a-8155-0459aae5ef04) for
task 4 of framework 20141015-163022-26053898-61054-26738-0000 to
executor(1)@10.141.141.1:61070
I1015 16:30:23.425279 793427968 master.cpp:3324] Status update TASK_FINISHED
(UUID: dbe5f202-f34c-430a-8155-0459aae5ef04) for task 4 of framework
20141015-163022-26053898-61054-26738-0000 from slave
20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.425369 793964544 hierarchical_allocator_process.hpp:563]
Recovered cpus(*):1; mem(*):128 (total allocatable: cpus(*):2; mem(*):10240;
disk(*):470842; ports(*):[31000-32000]) on slave
20141015-163022-26053898-61054-26738-2 from framework
20141015-163022-26053898-61054-26738-0000
Status update: task 4 is in state TASK_FINISHED
Finished tasks: 5
I1015 16:30:23.425684 791818240 sched.cpp:745] Stopping framework
'20141015-163022-26053898-61054-26738-0000'
I1015 16:30:23.425705 790208512 master.cpp:4542] Removing task 4 with resources
cpus(*):1; mem(*):128 of framework 20141015-163022-26053898-61054-26738-0000 on
slave 20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.425745 790208512 master.cpp:2813] Forwarding status update
acknowledgement dbe5f202-f34c-430a-8155-0459aae5ef04 for task 4 of framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054 to slave
20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.425801 790208512 master.cpp:1686] Asked to unregister framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425825 790208512 master.cpp:4128] Removing framework
20141015-163022-26053898-61054-26738-0000 (Test Framework (Java)) at
[email protected]:61054
I1015 16:30:23.425838 792891392 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: dbe5f202-f34c-430a-8155-0459aae5ef04) for task 4
of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425871 790745088 hierarchical_allocator_process.hpp:405]
Deactivated framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425900 791818240 slave.cpp:1490] Asked to shut down framework
20141015-163022-26053898-61054-26738-0000 by [email protected]:61054
I1015 16:30:23.425918 791818240 slave.cpp:1515] Shutting down framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425917 793427968 slave.cpp:1490] Asked to shut down framework
20141015-163022-26053898-61054-26738-0000 by [email protected]:61054
I1015 16:30:23.425935 791818240 slave.cpp:3035] Shutting down executor
'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425938 793427968 slave.cpp:1515] Shutting down framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425950 793427968 slave.cpp:3035] Shutting down executor
'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425953 792354816 slave.cpp:1490] Asked to shut down framework
20141015-163022-26053898-61054-26738-0000 by [email protected]:61054
I1015 16:30:23.425967 792354816 slave.cpp:1515] Shutting down framework
20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425977 792354816 slave.cpp:3035] Shutting down executor
'default' of framework 20141015-163022-26053898-61054-26738-0000
I1015 16:30:23.425981 790208512 master.cpp:4571] Removing executor 'default'
with resources of framework 20141015-163022-26053898-61054-26738-0000 on slave
20141015-163022-26053898-61054-26738-2 at slave(1)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.426038 790208512 master.cpp:4571] Removing executor 'default'
with resources of framework 20141015-163022-26053898-61054-26738-0000 on slave
20141015-163022-26053898-61054-26738-1 at slave(3)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.426103 790208512 master.cpp:4571] Removing executor 'default'
with resources of framework 20141015-163022-26053898-61054-26738-0000 on slave
20141015-163022-26053898-61054-26738-0 at slave(2)@10.141.141.1:61054
(10.141.141.1)
I1015 16:30:23.426162 680591360 exec.cpp:393] Executor asked to shutdown
I1015 16:30:23.426270 661401600 exec.cpp:393] Executor asked to shutdown
I1015 16:30:23.426301 795205632 exec.cpp:393] Executor asked to shutdown
I1015 16:30:23.426395 793964544 hierarchical_allocator_process.hpp:360] Removed
framework 20141015-163022-26053898-61054-26738-0000
../../../src/tests/script.cpp:83: Failure
Failed
java_framework_test.sh terminated with signal Abort trap: 6
*** Aborted at 1413383424 (unix time) try "date -d @1413383424" if you are
using GNU date ***
PC: @ 0x1050ec7a6 testing::UnitTest::AddTestPartResult()
*** SIGSEGV (@0x0) received by PID 21189 (TID 0x7fff7ac5c310) stack trace: ***
@ 0x7fff8eff65aa _sigtramp
@ 0x7fff8da55fa9 tiny_malloc_from_free_list
@ 0x1050ec2b6 testing::internal::AssertHelper::operator=()
@ 0x104f7011f mesos::internal::tests::execute()
@ 0x104bafea9 ExamplesTest_JavaFramework_Test::TestBody()
@ 0x10510b24c
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x1050f6bca testing::Test::Run()
@ 0x1050f7852 testing::TestInfo::Run()
@ 0x1050f7e90 testing::TestCase::Run()
@ 0x1050fd6e5 testing::internal::UnitTestImpl::RunAllTests()
@ 0x10510ba14
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x1050fd419 testing::UnitTest::Run()
@ 0x104d36a3b main
@ 0x7fff941605fd start
@ 0x5 (unknown)
zsh: segmentation fault GLOV_v=2 ./bin/mesos-tests.sh --gtest_repeat=-1
--gtest_break_on_failure
> ExamplesTest.JavaFramework is flaky
> -----------------------------------
>
> Key: MESOS-830
> URL: https://issues.apache.org/jira/browse/MESOS-830
> Project: Mesos
> Issue Type: Bug
> Components: test
> Reporter: Vinod Kone
>
> [ RUN ] ExamplesTest.JavaFramework
> Using temporary directory '/tmp/ExamplesTest_JavaFramework_wSc7u8'
> Enabling authentication for the framework
> I1120 15:13:39.820032 1681264640 master.cpp:285] Master started on
> 172.25.133.171:52576
> I1120 15:13:39.820180 1681264640 master.cpp:299] Master ID:
> 201311201513-2877626796-52576-3234
> I1120 15:13:39.820194 1681264640 master.cpp:302] Master only allowing
> authenticated frameworks to register!
> I1120 15:13:39.821197 1679654912 slave.cpp:112] Slave started on
> 1)@172.25.133.171:52576
> I1120 15:13:39.821795 1679654912 slave.cpp:212] Slave resources: cpus(*):4;
> mem(*):7168; disk(*):481998; ports(*):[31000-32000]
> I1120 15:13:39.822855 1682337792 slave.cpp:112] Slave started on
> 2)@172.25.133.171:52576
> I1120 15:13:39.823652 1682337792 slave.cpp:212] Slave resources: cpus(*):4;
> mem(*):7168; disk(*):481998; ports(*):[31000-32000]
> I1120 15:13:39.825330 1679118336 master.cpp:744] The newly elected leader is
> [email protected]:52576
> I1120 15:13:39.825445 1679118336 master.cpp:748] Elected as the leading
> master!
> I1120 15:13:39.825907 1681264640 state.cpp:33] Recovering state from
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/0/meta'
> I1120 15:13:39.826127 1681264640 status_update_manager.cpp:180] Recovering
> status update manager
> I1120 15:13:39.826331 1681801216 process_isolator.cpp:317] Recovering isolator
> I1120 15:13:39.826738 1682874368 slave.cpp:2743] Finished recovery
> I1120 15:13:39.827747 1682337792 state.cpp:33] Recovering state from
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/1/meta'
> I1120 15:13:39.827945 1680191488 slave.cpp:112] Slave started on
> 3)@172.25.133.171:52576
> I1120 15:13:39.828415 1682337792 status_update_manager.cpp:180] Recovering
> status update manager
> I1120 15:13:39.828608 1680728064 sched.cpp:260] Authenticating with master
> [email protected]:52576
> I1120 15:13:39.828606 1680191488 slave.cpp:212] Slave resources: cpus(*):4;
> mem(*):7168; disk(*):481998; ports(*):[31000-32000]
> I1120 15:13:39.828680 1682874368 slave.cpp:497] New master detected at
> [email protected]:52576
> I1120 15:13:39.828765 1682337792 process_isolator.cpp:317] Recovering isolator
> I1120 15:13:39.829828 1680728064 sched.cpp:229] Detecting new master
> I1120 15:13:39.830288 1679654912 authenticatee.hpp:100] Initializing client
> SASL
> I1120 15:13:39.831635 1680191488 state.cpp:33] Recovering state from
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/2/meta'
> I1120 15:13:39.831991 1679118336 status_update_manager.cpp:158] New master
> detected at [email protected]:52576
> I1120 15:13:39.832042 1682874368 slave.cpp:524] Detecting new master
> I1120 15:13:39.832314 1682337792 slave.cpp:2743] Finished recovery
> I1120 15:13:39.832309 1681264640 master.cpp:1266] Attempting to register
> slave on vkone.local at slave(1)@172.25.133.171:52576
> I1120 15:13:39.832929 1680728064 status_update_manager.cpp:180] Recovering
> status update manager
> I1120 15:13:39.833371 1681801216 slave.cpp:497] New master detected at
> [email protected]:52576
> I1120 15:13:39.833273 1681264640 master.cpp:2513] Adding slave
> 201311201513-2877626796-52576-3234-0 at vkone.local with cpus(*):4;
> mem(*):7168; disk(*):481998; ports(*):[31000-32000]
> I1120 15:13:39.833595 1680728064 process_isolator.cpp:317] Recovering isolator
> I1120 15:13:39.833859 1681801216 slave.cpp:524] Detecting new master
> I1120 15:13:39.833861 1682874368 status_update_manager.cpp:158] New master
> detected at [email protected]:52576
> I1120 15:13:39.834092 1680191488 slave.cpp:542] Registered with master
> [email protected]:52576; given slave ID
> 201311201513-2877626796-52576-3234-0
> I1120 15:13:39.834486 1681264640 master.cpp:1266] Attempting to register
> slave on vkone.local at slave(2)@172.25.133.171:52576
> I1120 15:13:39.834549 1681264640 master.cpp:2513] Adding slave
> 201311201513-2877626796-52576-3234-1 at vkone.local with cpus(*):4;
> mem(*):7168; disk(*):481998; ports(*):[31000-32000]
> I1120 15:13:39.834750 1680191488 slave.cpp:555] Checkpointing SlaveInfo to
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/0/meta/slaves/201311201513-2877626796-52576-3234-0/slave.info'
> I1120 15:13:39.834875 1682874368 hierarchical_allocator_process.hpp:445]
> Added slave 201311201513-2877626796-52576-3234-0 (vkone.local) with
> cpus(*):4; mem(*):7168; disk(*):481998; ports(*):[31000-32000] (and
> cpus(*):4; mem(*):7168; disk(*):481998; ports(*):[31000-32000] available)
> I1120 15:13:39.835155 1680728064 slave.cpp:542] Registered with master
> [email protected]:52576; given slave ID
> 201311201513-2877626796-52576-3234-1
> I1120 15:13:39.835458 1679118336 slave.cpp:2743] Finished recovery
> I1120 15:13:39.835739 1680728064 slave.cpp:555] Checkpointing SlaveInfo to
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/1/meta/slaves/201311201513-2877626796-52576-3234-1/slave.info'
> I1120 15:13:39.835922 1682874368 hierarchical_allocator_process.hpp:445]
> Added slave 201311201513-2877626796-52576-3234-1 (vkone.local) with
> cpus(*):4; mem(*):7168; disk(*):481998; ports(*):[31000-32000] (and
> cpus(*):4; mem(*):7168; disk(*):481998; ports(*):[31000-32000] available)
> I1120 15:13:39.836120 1681264640 slave.cpp:497] New master detected at
> [email protected]:52576
> I1120 15:13:39.836340 1679118336 status_update_manager.cpp:158] New master
> detected at [email protected]:52576
> I1120 15:13:39.836436 1681264640 slave.cpp:524] Detecting new master
> I1120 15:13:39.836629 1682874368 master.cpp:1266] Attempting to register
> slave on vkone.local at slave(3)@172.25.133.171:52576
> I1120 15:13:39.836653 1682874368 master.cpp:2513] Adding slave
> 201311201513-2877626796-52576-3234-2 at vkone.local with cpus(*):4;
> mem(*):7168; disk(*):481998; ports(*):[31000-32000]
> I1120 15:13:39.836804 1680728064 slave.cpp:542] Registered with master
> [email protected]:52576; given slave ID
> 201311201513-2877626796-52576-3234-2
> I1120 15:13:39.837190 1680728064 slave.cpp:555] Checkpointing SlaveInfo to
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/2/meta/slaves/201311201513-2877626796-52576-3234-2/slave.info'
> I1120 15:13:39.837569 1682874368 hierarchical_allocator_process.hpp:445]
> Added slave 201311201513-2877626796-52576-3234-2 (vkone.local) with
> cpus(*):4; mem(*):7168; disk(*):481998; ports(*):[31000-32000] (and
> cpus(*):4; mem(*):7168; disk(*):481998; ports(*):[31000-32000] available)
> I1120 15:13:39.852011 1679654912 authenticatee.hpp:124] Creating new client
> SASL connection
> I1120 15:13:39.852219 1680191488 master.cpp:1734] Authenticating framework at
> scheduler(1)@172.25.133.171:52576
> I1120 15:13:39.852577 1682337792 authenticator.hpp:83] Initializing server
> SASL
> I1120 15:13:39.856160 1682337792 authenticator.hpp:140] Creating new server
> SASL connection
> I1120 15:13:39.856334 1681264640 authenticatee.hpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I1120 15:13:39.856360 1681264640 authenticatee.hpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1120 15:13:39.856421 1681264640 authenticator.hpp:243] Received SASL
> authentication start
> I1120 15:13:39.856487 1681264640 authenticator.hpp:325] Authentication
> requires more steps
> I1120 15:13:39.856531 1681264640 authenticatee.hpp:258] Received SASL
> authentication step
> I1120 15:13:39.856576 1681264640 authenticator.hpp:271] Received SASL
> authentication step
> I1120 15:13:39.856643 1681264640 authenticator.hpp:317] Authentication success
> I1120 15:13:39.856724 1681264640 authenticatee.hpp:298] Authentication success
> I1120 15:13:39.856768 1681264640 master.cpp:1774] Successfully authenticated
> framework at scheduler(1)@172.25.133.171:52576
> I1120 15:13:39.857028 1681264640 sched.cpp:334] Successfully authenticated
> with master [email protected]:52576
> I1120 15:13:39.857139 1681264640 master.cpp:798] Received registration
> request from scheduler(1)@172.25.133.171:52576
> I1120 15:13:39.857306 1681264640 master.cpp:816] Registering framework
> 201311201513-2877626796-52576-3234-0000 at scheduler(1)@172.25.133.171:52576
> I1120 15:13:39.862296 1680191488 hierarchical_allocator_process.hpp:332]
> Added framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.863867 1680191488 master.cpp:1700] Sending 3 offers to
> framework 201311201513-2877626796-52576-3234-0000
> Registered! ID = 201311201513-2877626796-52576-3234-0000
> Launching task 0
> Launching task 1
> Launching task 2
> I1120 15:13:39.905390 1680191488 master.cpp:2026] Processing reply for offer
> 201311201513-2877626796-52576-3234-0 on slave
> 201311201513-2877626796-52576-3234-1 (vkone.local) for framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.905825 1680191488 master.hpp:400] Adding task 0 with resources
> cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-1
> (vkone.local)
> I1120 15:13:39.905886 1680191488 master.cpp:2150] Launching task 0 of
> framework 201311201513-2877626796-52576-3234-0000 with resources cpus(*):1;
> mem(*):128 on slave 201311201513-2877626796-52576-3234-1 (vkone.local)
> I1120 15:13:39.906422 1680191488 master.cpp:2026] Processing reply for offer
> 201311201513-2877626796-52576-3234-1 on slave
> 201311201513-2877626796-52576-3234-2 (vkone.local) for framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.906664 1680191488 master.hpp:400] Adding task 1 with resources
> cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-2
> (vkone.local)
> I1120 15:13:39.906721 1680191488 master.cpp:2150] Launching task 1 of
> framework 201311201513-2877626796-52576-3234-0000 with resources cpus(*):1;
> mem(*):128 on slave 201311201513-2877626796-52576-3234-2 (vkone.local)
> I1120 15:13:39.907171 1680191488 master.cpp:2026] Processing reply for offer
> 201311201513-2877626796-52576-3234-2 on slave
> 201311201513-2877626796-52576-3234-0 (vkone.local) for framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.907419 1680191488 master.hpp:400] Adding task 2 with resources
> cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-0
> (vkone.local)
> I1120 15:13:39.907480 1680191488 master.cpp:2150] Launching task 2 of
> framework 201311201513-2877626796-52576-3234-0000 with resources cpus(*):1;
> mem(*):128 on slave 201311201513-2877626796-52576-3234-0 (vkone.local)
> I1120 15:13:39.907938 1680191488 slave.cpp:722] Got assigned task 0 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.908473 1680191488 slave.cpp:833] Launching task 0 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.914427 1682874368 slave.cpp:722] Got assigned task 1 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.914594 1680728064 slave.cpp:722] Got assigned task 2 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.914844 1681801216 hierarchical_allocator_process.hpp:590]
> Framework 201311201513-2877626796-52576-3234-0000 filtered slave
> 201311201513-2877626796-52576-3234-1 for 1secs
> I1120 15:13:39.915292 1682874368 slave.cpp:833] Launching task 1 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.915424 1681801216 hierarchical_allocator_process.hpp:590]
> Framework 201311201513-2877626796-52576-3234-0000 filtered slave
> 201311201513-2877626796-52576-3234-2 for 1secs
> I1120 15:13:39.915685 1681801216 hierarchical_allocator_process.hpp:590]
> Framework 201311201513-2877626796-52576-3234-0000 filtered slave
> 201311201513-2877626796-52576-3234-0 for 1secs
> I1120 15:13:39.915828 1680728064 slave.cpp:833] Launching task 2 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.917840 1680191488 slave.cpp:943] Queuing task '0' for executor
> default of framework '201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.917935 1679118336 process_isolator.cpp:100] Launching default
> (/Users/vinod/workspace/apache/mesos/build/src/examples/java/test-executor)
> in
> /tmp/ExamplesTest_JavaFramework_wSc7u8/1/slaves/201311201513-2877626796-52576-3234-1/frameworks/201311201513-2877626796-52576-3234-0000/executors/default/runs/375b31a9-7093-4db1-964d-e6b425b1e4b4
> with resources ' for framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.922019 1679118336 process_isolator.cpp:163] Forked executor at
> 3268
> I1120 15:13:39.922703 1679118336 slave.cpp:2073] Monitoring executor default
> of framework 201311201513-2877626796-52576-3234-0000 forked at pid 3268
> I1120 15:13:39.929134 1682874368 slave.cpp:943] Queuing task '1' for executor
> default of framework '201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.929323 1682874368 process_isolator.cpp:100] Launching default
> (/Users/vinod/workspace/apache/mesos/build/src/examples/java/test-executor)
> in
> /tmp/ExamplesTest_JavaFramework_wSc7u8/2/slaves/201311201513-2877626796-52576-3234-2/frameworks/201311201513-2877626796-52576-3234-0000/executors/default/runs/2bd0e75d-a2b9-4ae6-be08-9782612309a5
> with resources ' for framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.931243 1682874368 process_isolator.cpp:163] Forked executor at
> 3269
> I1120 15:13:39.931612 1681801216 slave.cpp:2073] Monitoring executor default
> of framework 201311201513-2877626796-52576-3234-0000 forked at pid 3269
> E1120 15:13:39.931836 1681801216 slave.cpp:2099] Failed to watch executor
> default of framework 201311201513-2877626796-52576-3234-0000: Already watched
> I1120 15:13:39.936460 1680728064 slave.cpp:943] Queuing task '2' for executor
> default of framework '201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.936619 1681801216 process_isolator.cpp:100] Launching default
> (/Users/vinod/workspace/apache/mesos/build/src/examples/java/test-executor)
> in
> /tmp/ExamplesTest_JavaFramework_wSc7u8/0/slaves/201311201513-2877626796-52576-3234-0/frameworks/201311201513-2877626796-52576-3234-0000/executors/default/runs/16d600da-da86-4614-91cb-58a7b27ab534
> with resources ' for framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:39.941299 1681801216 process_isolator.cpp:163] Forked executor at
> 3270
> I1120 15:13:39.942179 1681801216 slave.cpp:2073] Monitoring executor default
> of framework 201311201513-2877626796-52576-3234-0000 forked at pid 3270
> E1120 15:13:39.942395 1681801216 slave.cpp:2099] Failed to watch executor
> default of framework 201311201513-2877626796-52576-3234-0000: Already watched
> Fetching resources into
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/2/slaves/201311201513-2877626796-52576-3234-2/frameworks/201311201513-2877626796-52576-3234-0000/executors/default/runs/2bd0e75d-a2b9-4ae6-be08-9782612309a5'
> Fetching resources into
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/1/slaves/201311201513-2877626796-52576-3234-1/frameworks/201311201513-2877626796-52576-3234-0000/executors/default/runs/375b31a9-7093-4db1-964d-e6b425b1e4b4'
> Fetching resources into
> '/tmp/ExamplesTest_JavaFramework_wSc7u8/0/slaves/201311201513-2877626796-52576-3234-0/frameworks/201311201513-2877626796-52576-3234-0000/executors/default/runs/16d600da-da86-4614-91cb-58a7b27ab534'
> I1120 15:13:40.372573 1681801216 slave.cpp:1406] Got registration for
> executor 'default' of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.373258 1681801216 slave.cpp:1527] Flushing queued task 1 for
> executor 'default' of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.388317 1681801216 slave.cpp:1406] Got registration for
> executor 'default' of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.388983 1681801216 slave.cpp:1527] Flushing queued task 0 for
> executor 'default' of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.398084 1679654912 slave.cpp:1406] Got registration for
> executor 'default' of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.399344 1679654912 slave.cpp:1527] Flushing queued task 2 for
> executor 'default' of framework 201311201513-2877626796-52576-3234-0000
> Registered executor on vkone.local
> I1120 15:13:40.491843 1679654912 slave.cpp:1740] Handling status update
> TASK_RUNNING (UUID: f04b1852-3669-444a-906f-3675f784c14f) for task 1 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52577
> I1120 15:13:40.492202 1679654912 status_update_manager.cpp:305] Received
> status update TASK_RUNNING (UUID: f04b1852-3669-444a-906f-3675f784c14f) for
> task 1 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.492424 1679654912 status_update_manager.cpp:356] Forwarding
> status update TASK_RUNNING (UUID: f04b1852-3669-444a-906f-3675f784c14f) for
> task 1 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> Registered executor on vkone.local
> I1120 15:13:40.492671 1682337792 master.cpp:1452] Status update TASK_RUNNING
> (UUID: f04b1852-3669-444a-906f-3675f784c14f) for task 1 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(3)@172.25.133.171:52576
> I1120 15:13:40.492735 1682337792 slave.cpp:1865] Sending acknowledgement for
> status update TASK_RUNNING (UUID: f04b1852-3669-444a-906f-3675f784c14f) for
> task 1 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52577
> Status update: task 1 is in state TASK_RUNNING
> I1120 15:13:40.502235 1679654912 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: f04b1852-3669-444a-906f-3675f784c14f)
> for task 1 of framework 201311201513-2877626796-52576-3234-0000
> Registered executor on vkone.local
> I1120 15:13:40.531292 1679654912 slave.cpp:1740] Handling status update
> TASK_RUNNING (UUID: c19b6a5a-19ce-4613-8a5a-08fe807ff27c) for task 2 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52579
> I1120 15:13:40.532091 1680728064 status_update_manager.cpp:305] Received
> status update TASK_RUNNING (UUID: c19b6a5a-19ce-4613-8a5a-08fe807ff27c) for
> task 2 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.532305 1680728064 status_update_manager.cpp:356] Forwarding
> status update TASK_RUNNING (UUID: c19b6a5a-19ce-4613-8a5a-08fe807ff27c) for
> task 2 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.532776 1682874368 slave.cpp:1865] Sending acknowledgement for
> status update TASK_RUNNING (UUID: c19b6a5a-19ce-4613-8a5a-08fe807ff27c) for
> task 2 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52579
> I1120 15:13:40.532951 1681801216 master.cpp:1452] Status update TASK_RUNNING
> (UUID: c19b6a5a-19ce-4613-8a5a-08fe807ff27c) for task 2 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(1)@172.25.133.171:52576
> Status update: task 2 is in state TASK_RUNNING
> I1120 15:13:40.538895 1682874368 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: c19b6a5a-19ce-4613-8a5a-08fe807ff27c)
> for task 2 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.541267 1682874368 slave.cpp:1740] Handling status update
> TASK_RUNNING (UUID: c218b0c3-d77c-4901-8570-391c330ba117) for task 0 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52578
> I1120 15:13:40.541555 1682874368 status_update_manager.cpp:305] Received
> status update TASK_RUNNING (UUID: c218b0c3-d77c-4901-8570-391c330ba117) for
> task 0 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.541725 1682874368 status_update_manager.cpp:356] Forwarding
> status update TASK_RUNNING (UUID: c218b0c3-d77c-4901-8570-391c330ba117) for
> task 0 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.542196 1682874368 master.cpp:1452] Status update TASK_RUNNING
> (UUID: c218b0c3-d77c-4901-8570-391c330ba117) for task 0 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(2)@172.25.133.171:52576
> I1120 15:13:40.542251 1682874368 slave.cpp:1865] Sending acknowledgement for
> status update TASK_RUNNING (UUID: c218b0c3-d77c-4901-8570-391c330ba117) for
> task 0 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52578
> Status update: task 0 is in state TASK_RUNNING
> I1120 15:13:40.545537 1682874368 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: c218b0c3-d77c-4901-8570-391c330ba117)
> for task 0 of framework 201311201513-2877626796-52576-3234-0000
> Running task value: "1"
> I1120 15:13:40.764219 1682337792 slave.cpp:1740] Handling status update
> TASK_FINISHED (UUID: 4a163594-146a-46f7-bd43-f906e76ad84c) for task 1 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52577
> I1120 15:13:40.764629 1682337792 status_update_manager.cpp:305] Received
> status update TASK_FINISHED (UUID: 4a163594-146a-46f7-bd43-f906e76ad84c) for
> task 1 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.764698 1682337792 status_update_manager.cpp:356] Forwarding
> status update TASK_FINISHED (UUID: 4a163594-146a-46f7-bd43-f906e76ad84c) for
> task 1 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.765043 1682337792 master.cpp:1452] Status update TASK_FINISHED
> (UUID: 4a163594-146a-46f7-bd43-f906e76ad84c) for task 1 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(3)@172.25.133.171:52576
> I1120 15:13:40.765192 1682337792 master.hpp:418] Removing task 1 with
> resources cpus(*):1; mem(*):128 on slave 2Status update: task 1 is in state
> TASK_FINISHED
> Finished tasks: 1
> 01311201513-2877626796-52576-3234-2 (vkone.local)
> I1120 15:13:40.765363 1679118336 slave.cpp:1865] Sending acknowledgement for
> status update TASK_FINISHED (UUID: 4a163594-146a-46f7-bd43-f906e76ad84c) for
> task 1 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52577
> I1120 15:13:40.772738 1682337792 hierarchical_allocator_process.hpp:637]
> Recovered cpus(*):1; mem(*):128 (total allocatable: cpus(*):4; mem(*):7168;
> disk(*):481998; ports(*):[31000-32000]) on slave
> 201311201513-2877626796-52576-3234-2 from framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.773190 1679118336 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: 4a163594-146a-46f7-bd43-f906e76ad84c)
> for task 1 of framework 201311201513-2877626796-52576-3234-0000
> Running task value: "0"
> Running task value: "2"
> I1120 15:13:40.790068 1679118336 slave.cpp:1740] Handling status update
> TASK_FINISHED (UUID: 13265a94-50f1-4bc4-b2e2-9f60a1bb4086) for task 0 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52578
> I1120 15:13:40.790411 1680728064 status_update_manager.cpp:305] Received
> status update TASK_FINISHED (UUID: 13265a94-50f1-4bc4-b2e2-9f60a1bb4086) for
> task 0 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.790493 1680728064 status_update_manager.cpp:356] Forwarding
> status update TASK_FINISHED (UUID: 13265a94-50f1-4bc4-b2e2-9f60a1bb4086) for
> task 0 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.790674 1679118336 master.cpp:1452] Status update TASK_FINISHED
> (UUID: 13265a94-50f1-4bc4-b2e2-9f60a1bb4086) for task 0 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(2)@172.25.133.171:52576
> I1120 15:13:40.790798 1679118336 master.hpp:418] Removing task 0 with
> resources cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-1
> (vkone.local)
> I1120 15:13:40.790928 1679118336 slave.cpp:1865] Sending acknowledgement for
> status update TASK_FINISHED (UUID: 13265a94-50f1-4bc4-b2e2-9f60a1bb4086) for
> task 0 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52578
> Status update: task 0 is in state TASK_FINISHED
> Finished tasks: 2
> I1120 15:13:40.791225 1680191488 hierarchical_allocator_process.hpp:637]
> Recovered cpus(*):1; mem(*):128 (total allocatable: cpus(*):4; mem(*):7168;
> disk(*):481998; ports(*):[31000-32000]) on slave
> 201311201513-2877626796-52576-3234-1 from framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.794234 1679118336 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: 13265a94-50f1-4bc4-b2e2-9f60a1bb4086)
> for task 0 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.795830 1681801216 slave.cpp:1740] Handling status update
> TASK_FINISHED (UUID: f6f28e88-5ea6-4519-ba92-65ead6236fff) for task 2 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52579
> I1120 15:13:40.796111 1679118336 status_update_manager.cpp:305] Received
> status update TASK_FINISHED (UUID: f6f28e88-5ea6-4519-ba92-65ead6236fff) for
> task 2 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.796182 1679118336 status_update_manager.cpp:356] Forwarding
> status update TASK_FINISHED (UUID: f6f28e88-5ea6-4519-ba92-65ead6236fff) for
> task 2 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.796352 1680728064 master.cpp:1452] Status update TASK_FINISHED
> (UUID: f6f28e88-5ea6-4519-ba92-65ead6236fff) for task 2 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(1)@172.25.133.171:52576
> I1120 15:13:40.796398 1679118336 slave.cpp:1865] Sending acknowledgement for
> status update TASK_FINISHED (UUID: f6f28e88-5ea6-4519-ba92-65ead6236fff) for
> task 2 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52579
> I1120 15:13:40.796466 1680728064 master.hpp:418] Removing task 2 with
> resources cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-0
> (vkone.local)
> I1120 15:13:40.796707 1679118336 hierarchical_allocator_process.hpp:637]
> Recovered cpus(*):1; mem(*):128 (total allocatable: cpus(*):4; mem(*):7168;
> disk(*):481998; ports(*):[31000-32000]) on slave
> 201311201513-2877626796-52576-3234-0 from framework
> 201311201513-2877626796-52576-3234-0000
> Status update: task 2 is in state TASK_FINISHED
> Finished tasks: 3
> I1120 15:13:40.797384 1680728064 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: f6f28e88-5ea6-4519-ba92-65ead6236fff)
> for task 2 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.824383 1681801216 master.cpp:1700] Sending 3 offers to
> framework 201311201513-2877626796-52576-3234-0000
> Launching task 3
> Launching task 4
> I1120 15:13:40.826971 1679118336 master.cpp:2026] Processing reply for offer
> 201311201513-2877626796-52576-3234-3 on slave
> 201311201513-2877626796-52576-3234-1 (vkone.local) for framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.827268 1679118336 master.hpp:400] Adding task 3 with resources
> cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-1
> (vkone.local)
> I1120 15:13:40.827348 1679118336 master.cpp:2150] Launching task 3 of
> framework 201311201513-2877626796-52576-3234-0000 with resources cpus(*):1;
> mem(*):128 on slave 201311201513-2877626796-52576-3234-1 (vkone.local)
> I1120 15:13:40.827487 1680728064 slave.cpp:722] Got assigned task 3 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.827857 1680728064 slave.cpp:833] Launching task 3 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.827913 1679118336 master.cpp:2026] Processing reply for offer
> 201311201513-2877626796-52576-3234-4 on slave
> 201311201513-2877626796-52576-3234-2 (vkone.local) for framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.827986 1680728064 slave.cpp:968] Sending task '3' to executor
> 'default' of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.828126 1679118336 master.hpp:400] Adding task 4 with resources
> cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-2
> (vkone.local)
> I1120 15:13:40.828187 1679118336 master.cpp:2150] Launching task 4 of
> framework 201311201513-2877626796-52576-3234-0000 with resources cpus(*):1;
> mem(*):128 on slave 201311201513-2877626796-52576-3234-2 (vkone.local)
> I1120 15:13:40.828632 1679118336 master.cpp:2026] Processing reply for offer
> 201311201513-2877626796-52576-3234-5 on slave
> 201311201513-2877626796-52576-3234-0 (vkone.local) for framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.828655 1680728064 hierarchical_allocator_process.hpp:590]
> Framework 201311201513-2877626796-52576-3234-0000 filtered slave
> 201311201513-2877626796-52576-3234-1 for 1secs
> I1120 15:13:40.829005 1679118336 slave.cpp:722] Got assigned task 4 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.829027 1680728064 hierarchical_allocator_process.hpp:590]
> Framework 201311201513-2877626796-52576-3234-0000 filtered slave
> 201311201513-2877626796-52576-3234-2 for 1secs
> I1120 15:13:40.829260 1679118336 slave.cpp:833] Launching task 4 for
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.829273 1680728064 hierarchical_allocator_process.hpp:590]
> Framework 201311201513-2877626796-52576-3234-0000 filtered slave
> 201311201513-2877626796-52576-3234-0 for 1secs
> I1120 15:13:40.829390 1679118336 slave.cpp:968] Sending task '4' to executor
> 'default' of framework 201311201513-2877626796-52576-3234-0000
> Running task value: "3"
> Running task value: "4"
> I1120 15:13:40.839279 1682337792 slave.cpp:1740] Handling status update
> TASK_RUNNING (UUID: a8d02ae6-3138-441c-a004-465d879b1277) for task 3 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52578
> I1120 15:13:40.839534 1679118336 status_update_manager.cpp:305] Received
> status update TASK_RUNNING (UUID: a8d02ae6-3138-441c-a004-465d879b1277) for
> task 3 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.839705 1679118336 status_update_manager.cpp:356] Forwarding
> status update TASK_RUNNING (UUID: a8d02ae6-3138-441c-a004-465d879b1277) for
> task 3 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.839944 1682337792 master.cpp:1452] Status update TASK_RUNNING
> (UUID: a8d02ae6-3138-441c-a004-465d879b1277) for task 3 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(2)@172.25.133.171:52576
> Status update: task 3 is in state TASK_RUNNING
> I1120 15:13:40.839947 1679118336 slave.cpp:1865] Sending acknowledgement for
> status update TASK_RUNNING (UUID: a8d02ae6-3138-441c-a004-465d879b1277) for
> task 3 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52578
> I1120 15:13:40.856334 1679118336 slave.cpp:1740] Handling status update
> TASK_FINISHED (UUID: 3fc45cb8-fd7f-4bed-a21d-76f234af6b36) for task 3 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52578
> I1120 15:13:40.856650 1679118336 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: a8d02ae6-3138-441c-a004-465d879b1277)
> for task 3 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.856818 1679118336 status_update_manager.cpp:305] Received
> status update TASK_FINISHED (UUID: 3fc45cb8-fd7f-4bed-a21d-76f234af6b36) for
> task 3 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.856875 1679118336 status_update_manager.cpp:356] Forwarding
> status update TASK_FINISHED (UUID: 3fc45cb8-fd7f-4bed-a21d-76f234af6b36) for
> task 3 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.857105 1679118336 slave.cpp:1740] Handling status update
> TASK_RUNNING (UUID: 4f97c8df-1cc0-4eeb-8469-9d72df09ec73) for task 4 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52577
> I1120 15:13:40.857369 1679118336 slave.cpp:1865] Sending acknowledgement for
> status update TASK_FINISHED (UUID: 3fc45cb8-fd7f-4bed-a21d-76f234af6b36) for
> task 3 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52578
> I1120 15:13:40.857498 1680728064 status_update_manager.cpp:305] Received
> status update TASK_RUNNING (UUID: 4f97c8df-1cc0-4eeb-8469-9d72df09ec73) for
> task 4 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.857518 1682337792 master.cpp:1452] Status update TASK_FINISHED
> (UUID: 3fc45cb8-fd7f-4bed-a21d-76f234af6b36) for task 3 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(2)@172.25.133.171:52576
> I1120 15:13:40.857635 1680728064 status_update_manager.cpp:356] Forwarding
> status update TASK_RUNNING (UUID: 4f97c8df-1cc0-4eeb-8469-9d72df09ec73) for
> task 4 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.857630 1682337792 master.hpp:418] Removing task 3 with
> resources cpus(*):1; mem(*):128 on slave 201311201513-2877626796-52576-3234-1
> (vkone.local)
> I1120 15:13:40.857843 1682337792 master.cpp:1452] Status update TASK_RUNNING
> (UUID: 4f97c8df-1cc0-4eeb-8469-9d72df09ec73) for task 4 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(3)@172.25.133.171:52576
> I1120 15:13:40.858043 1680728064 hierarchical_allocator_process.hpp:637]
> Recovered cpus(*):1; mem(*):128 (total allocatable: cpus(*):4; mem(*):7168;
> disk(*):481998; ports(*):[31000-32000]) on slave
> 201311201513-2877626796-52576-3234-1 from framework
> 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.858098 1680728064 slave.cpp:1865] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 4f97c8df-1cc0-4eeb-8469-9d72df09ec73) for
> task 4 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52577
> Status update: task 3 is in state TASK_FINISHED
> Finished tasks: 4
> Status update: task 4 is in state TASK_RUNNING
> I1120 15:13:40.858896 1682337792 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: 3fc45cb8-fd7f-4bed-a21d-76f234af6b36)
> for task 3 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.858957 1680728064 status_update_manager.cpp:380] Received
> status update acknowledgement (UUID: 4f97c8df-1cc0-4eeb-8469-9d72df09ec73)
> for task 4 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.859905 1679654912 slave.cpp:1740] Handling status update
> TASK_FINISHED (UUID: 876d7ddc-5d58-48df-a590-d82cf39d4978) for task 4 of
> framework 201311201513-2877626796-52576-3234-0000 from
> executor(1)@172.25.133.171:52577
> I1120 15:13:40.860174 1680728064 status_update_manager.cpp:305] Received
> status update TASK_FINISHED (UUID: 876d7ddc-5d58-48df-a590-d82cf39d4978) for
> task 4 of framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.860245 1680728064 status_update_manager.cpp:356] Forwarding
> status update TASK_FINISHED (UUID: 876d7ddc-5d58-48df-a590-d82cf39d4978) for
> task 4 of framework 201311201513-2877626796-52576-3234-0000 to
> [email protected]:52576
> I1120 15:13:40.860437 1679654912 master.cpp:1452] Status update TASK_FINISHED
> (UUID: 876d7ddc-5d58-48df-a590-d82cf39d4978) for task 4 of framework
> 201311201513-2877626796-52576-3234-0000 from slave(3)@172.25.133.171:52576
> I1120 15:13:40.860486 1680728064 slave.cpp:1865] Sending acknowledgement for
> status update TASK_FINISHED (UUID: 876d7ddc-5d58-48df-a590-d82cf39d4978) for
> task 4 of framework 201311201513-2877626796-52576-3234-0000 to
> executor(1)@172.25.133.171:52577
> I1120 15:13:40.860550 1679654912 master.hpp:418] Removing task 4 with
> resources cpus(*):1; mem(Status update: task 4 is in state TASK_FINISHED
> Finished tasks: 5
> *):128 on slave 201311201513-2877626796-52576-3234-2 (vkone.local)
> I1120 15:13:40.863689 1679654912 master.cpp:996] Asked to unregister
> framework 201311201513-2877626796-52576-3234-0000
> I1120 15:13:40.863750 1679654912 master.cpp:2385] Removing framework
> 201311201513-2877626796-52576-3234-0000
> ../../src/tests/script.cpp:81: Failure
> Failed
> java_framework_test.sh terminated with signal 'Abort trap: 6'
> [ FAILED ] ExamplesTest.JavaFramework (2688 ms)
> [----------] 1 test from ExamplesTest (2688 ms total)
> [----------] Global test environment tear-down
> [==========] 1 test from 1 test case ran. (2692 ms total)
> [ PASSED ] 0 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] ExamplesTest.JavaFramework
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
