[ https://issues.apache.org/jira/browse/MESOS-988?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Artem Harutyunyan updated MESOS-988: ------------------------------------ Story Points: 3 > ExamplesTest.PythonFramework is flaky > ------------------------------------- > > Key: MESOS-988 > URL: https://issues.apache.org/jira/browse/MESOS-988 > Project: Mesos > Issue Type: Bug > Components: test > Affects Versions: 0.18.0 > Reporter: Vinod Kone > Assignee: Greg Mann > Labels: flaky, mesosphere > > Looks like a SEGFAULT during shutdown. > {noformat} > [ RUN ] ExamplesTest.PythonFramework > Using temporary directory '/tmp/ExamplesTest_PythonFramework_RZ4yaf' > WARNING: Logging before InitGoogleLogging() is written to STDERR > I0211 21:14:47.861803 21045 process.cpp:1591] libprocess is initialized on > 67.195.138.9:53443 for 8 cpus > I0211 21:14:47.861884 21045 logging.cpp:140] Logging to STDERR > I0211 21:14:47.862761 21045 master.cpp:240] Master ID: > 2014-02-11-21:14:47-160088899-53443-21045 Hostname: vesta.apache.org > I0211 21:14:47.862897 21054 master.cpp:322] Master started on > 67.195.138.9:53443 > I0211 21:14:47.862908 21054 master.cpp:325] Master only allowing > authenticated frameworks to register! > I0211 21:14:47.864362 21053 master.cpp:86] No whitelist given. Advertising > offers for all slaves > I0211 21:14:47.864506 21055 slave.cpp:112] Slave started on > 1)@67.195.138.9:53443 > I0211 21:14:47.864522 21059 slave.cpp:112] Slave started on > 2)@67.195.138.9:53443 > I0211 21:14:47.864749 21055 slave.cpp:212] Slave resources: cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] > I0211 21:14:47.864778 21059 slave.cpp:212] Slave resources: cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] > I0211 21:14:47.864819 21055 slave.cpp:240] Slave hostname: vesta.apache.org > I0211 21:14:47.864827 21055 slave.cpp:241] Slave checkpoint: true > I0211 21:14:47.864850 21059 slave.cpp:240] Slave hostname: vesta.apache.org > I0211 21:14:47.864858 21059 slave.cpp:241] Slave checkpoint: true > I0211 21:14:47.865329 21055 master.cpp:760] The newly elected leader is > master@67.195.138.9:53443 with id 2014-02-11-21:14:47-160088899-53443-21045 > I0211 21:14:47.865350 21055 master.cpp:770] Elected as the leading master! > I0211 21:14:47.865399 21055 state.cpp:33] Recovering state from > '/tmp/mesos-Z8v6cu/1/meta' > I0211 21:14:47.865407 21059 state.cpp:33] Recovering state from > '/tmp/mesos-Z8v6cu/0/meta' > I0211 21:14:47.865502 21052 hierarchical_allocator_process.hpp:302] > Initializing hierarchical allocator process with master : > master@67.195.138.9:53443 > I0211 21:14:47.865540 21054 status_update_manager.cpp:188] Recovering status > update manager > I0211 21:14:47.865619 21053 process_isolator.cpp:319] Recovering isolator > I0211 21:14:47.865674 21057 status_update_manager.cpp:188] Recovering status > update manager > I0211 21:14:47.865699 21059 slave.cpp:2760] Finished recovery > I0211 21:14:47.865733 21053 process_isolator.cpp:319] Recovering isolator > I0211 21:14:47.865789 21053 slave.cpp:2760] Finished recovery > I0211 21:14:47.865921 21059 slave.cpp:508] New master detected at > master@67.195.138.9:53443 > I0211 21:14:47.865958 21053 status_update_manager.cpp:162] New master > detected at master@67.195.138.9:53443 > I0211 21:14:47.865978 21059 slave.cpp:533] Detecting new master > I0211 21:14:47.866019 21053 slave.cpp:508] New master detected at > master@67.195.138.9:53443 > I0211 21:14:47.866063 21053 slave.cpp:533] Detecting new master > I0211 21:14:47.866070 21055 status_update_manager.cpp:162] New master > detected at master@67.195.138.9:53443 > I0211 21:14:47.866077 21059 master.cpp:1840] Attempting to register slave on > vesta.apache.org at slave(2)@67.195.138.9:53443 > I0211 21:14:47.866092 21059 master.cpp:2810] Adding slave > 2014-02-11-21:14:47-160088899-53443-21045-0 at vesta.apache.org with > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] > I0211 21:14:47.866216 21059 master.cpp:1840] Attempting to register slave on > vesta.apache.org at slave(1)@67.195.138.9:53443 > I0211 21:14:47.866225 21053 slave.cpp:551] Registered with master > master@67.195.138.9:53443; given slave ID > 2014-02-11-21:14:47-160088899-53443-21045-0 > I0211 21:14:47.866228 21059 master.cpp:2810] Adding slave > 2014-02-11-21:14:47-160088899-53443-21045-1 at vesta.apache.org with > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] > I0211 21:14:47.866278 21055 hierarchical_allocator_process.hpp:445] Added > slave 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) with > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] (and > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] available) > I0211 21:14:47.866297 21059 slave.cpp:551] Registered with master > master@67.195.138.9:53443; given slave ID > 2014-02-11-21:14:47-160088899-53443-21045-1 > I0211 21:14:47.866327 21055 hierarchical_allocator_process.hpp:708] Performed > allocation for slave 2014-02-11-21:14:47-160088899-53443-21045-0 in 11us > I0211 21:14:47.866330 21053 slave.cpp:564] Checkpointing SlaveInfo to > '/tmp/mesos-Z8v6cu/1/meta/slaves/2014-02-11-21:14:47-160088899-53443-21045-0/slave.info' > I0211 21:14:47.866400 21059 slave.cpp:564] Checkpointing SlaveInfo to > '/tmp/mesos-Z8v6cu/0/meta/slaves/2014-02-11-21:14:47-160088899-53443-21045-1/slave.info' > I0211 21:14:47.866399 21055 hierarchical_allocator_process.hpp:445] Added > slave 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) with > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] (and > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] available) > I0211 21:14:47.866423 21055 hierarchical_allocator_process.hpp:708] Performed > allocation for slave 2014-02-11-21:14:47-160088899-53443-21045-1 in 2505ns > I0211 21:14:47.866636 21059 slave.cpp:112] Slave started on > 3)@67.195.138.9:53443 > I0211 21:14:47.866727 21059 slave.cpp:212] Slave resources: cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] > I0211 21:14:47.866766 21059 slave.cpp:240] Slave hostname: vesta.apache.org > I0211 21:14:47.866772 21059 slave.cpp:241] Slave checkpoint: true > I0211 21:14:47.867300 21052 state.cpp:33] Recovering state from > '/tmp/mesos-Z8v6cu/2/meta' > I0211 21:14:47.867368 21052 status_update_manager.cpp:188] Recovering status > update manager > I0211 21:14:47.867419 21055 process_isolator.cpp:319] Recovering isolator > I0211 21:14:47.867544 21052 slave.cpp:2760] Finished recovery > I0211 21:14:47.867729 21052 slave.cpp:508] New master detected at > master@67.195.138.9:53443 > I0211 21:14:47.867770 21054 status_update_manager.cpp:162] New master > detected at master@67.195.138.9:53443 > I0211 21:14:47.867777 21052 slave.cpp:533] Detecting new master > I0211 21:14:47.867815 21055 master.cpp:1840] Attempting to register slave on > vesta.apache.org at slave(3)@67.195.138.9:53443 > I0211 21:14:47.867827 21055 master.cpp:2810] Adding slave > 2014-02-11-21:14:47-160088899-53443-21045-2 at vesta.apache.org with > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] > I0211 21:14:47.867885 21052 slave.cpp:551] Registered with master > master@67.195.138.9:53443; given slave ID > 2014-02-11-21:14:47-160088899-53443-21045-2 > I0211 21:14:47.867961 21055 hierarchical_allocator_process.hpp:445] Added > slave 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) with > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] (and > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] available) > I0211 21:14:47.867985 21052 slave.cpp:564] Checkpointing SlaveInfo to > '/tmp/mesos-Z8v6cu/2/meta/slaves/2014-02-11-21:14:47-160088899-53443-21045-2/slave.info' > I0211 21:14:47.867987 21055 hierarchical_allocator_process.hpp:708] Performed > allocation for slave 2014-02-11-21:14:47-160088899-53443-21045-2 in 3308ns > I0211 21:14:47.868468 21045 sched.cpp:121] Version: 0.18.0 > I0211 21:14:47.868633 21055 sched.cpp:217] New master detected at > master@67.195.138.9:53443 > I0211 21:14:47.868651 21055 sched.cpp:268] Authenticating with master > master@67.195.138.9:53443 > I0211 21:14:47.868696 21055 sched.cpp:237] Detecting new master > I0211 21:14:47.868708 21054 authenticatee.hpp:100] Initializing client SASL > I0211 21:14:47.869549 21054 authenticatee.hpp:124] Creating new client SASL > connection > I0211 21:14:47.869633 21055 master.cpp:2323] Authenticating framework at > scheduler(1)@67.195.138.9:53443 > I0211 21:14:47.869818 21059 authenticator.hpp:83] Initializing server SASL > I0211 21:14:47.870029 21059 auxprop.cpp:45] Initialized in-memory auxiliary > property plugin > I0211 21:14:47.870040 21059 authenticator.hpp:140] Creating new server SASL > connection > I0211 21:14:47.870144 21057 authenticatee.hpp:212] Received SASL > authentication mechanisms: CRAM-MD5 > I0211 21:14:47.870174 21057 authenticatee.hpp:238] Attempting to authenticate > with mechanism 'CRAM-MD5' > I0211 21:14:47.870203 21057 authenticator.hpp:243] Received SASL > authentication start > I0211 21:14:47.870256 21057 authenticator.hpp:325] Authentication requires > more steps > I0211 21:14:47.870282 21057 authenticatee.hpp:258] Received SASL > authentication step > I0211 21:14:47.870348 21057 authenticator.hpp:271] Received SASL > authentication step > I0211 21:14:47.870376 21057 auxprop.cpp:81] Request to lookup properties for > user: 'test-principal' realm: 'vesta.apache.org' server FQDN: > 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0211 21:14:47.870384 21057 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0211 21:14:47.870396 21057 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0211 21:14:47.870405 21057 auxprop.cpp:81] Request to lookup properties for > user: 'test-principal' realm: 'vesta.apache.org' server FQDN: > 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0211 21:14:47.870411 21057 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0211 21:14:47.870415 21057 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0211 21:14:47.870425 21057 authenticator.hpp:317] Authentication success > I0211 21:14:47.870445 21057 master.cpp:2363] Successfully authenticated > framework at scheduler(1)@67.195.138.9:53443 > I0211 21:14:47.870448 21055 authenticatee.hpp:298] Authentication success > I0211 21:14:47.870492 21055 sched.cpp:342] Successfully authenticated with > master master@67.195.138.9:53443 > I0211 21:14:47.870538 21057 master.cpp:818] Received registration request > from scheduler(1)@67.195.138.9:53443 > I0211 21:14:47.870590 21057 master.cpp:836] Registering framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 at > scheduler(1)@67.195.138.9:53443 > I0211 21:14:47.870661 21055 sched.cpp:391] Framework registered with > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.870661 21057 hierarchical_allocator_process.hpp:332] Added > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.870707 21057 hierarchical_allocator_process.hpp:752] Offering > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.870798 21057 hierarchical_allocator_process.hpp:752] Offering > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.870869 21057 hierarchical_allocator_process.hpp:752] Offering > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.870894 21055 sched.cpp:405] Scheduler::registered took 222149ns > I0211 21:14:47.871038 21057 hierarchical_allocator_process.hpp:688] Performed > allocation for 3 slaves in 351098ns > I0211 21:14:47.871106 21058 master.hpp:439] Adding offer > 2014-02-11-21:14:47-160088899-53443-21045-0 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) > I0211 21:14:47.871215 21058 master.hpp:439] Adding offer > 2014-02-11-21:14:47-160088899-53443-21045-1 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) > I0211 21:14:47.871296 21058 master.hpp:439] Adding offer > 2014-02-11-21:14:47-160088899-53443-21045-2 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) > I0211 21:14:47.871333 21058 master.cpp:2278] Sending 3 offers to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.873667 21055 sched.cpp:525] Scheduler::resourceOffers took > 2.150843ms > I0211 21:14:47.873884 21053 master.hpp:449] Removing offer > 2014-02-11-21:14:47-160088899-53443-21045-0 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) > I0211 21:14:47.873934 21053 master.cpp:1574] Processing reply for offers: [ > 2014-02-11-21:14:47-160088899-53443-21045-0 ] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.874035 21053 master.hpp:411] Adding task 0 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-2 > (vesta.apache.org) > I0211 21:14:47.874059 21053 master.cpp:2447] Launching task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 with resources cpus(*):1; > mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-2 > (vesta.apache.org) > I0211 21:14:47.874150 21059 slave.cpp:736] Got assigned task 0 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.874200 21058 hierarchical_allocator_process.hpp:547] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 left cpus(*):7; mem(*):6929; > disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 > I0211 21:14:47.874250 21053 master.hpp:449] Removing offer > 2014-02-11-21:14:47-160088899-53443-21045-1 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) > I0211 21:14:47.874307 21053 master.cpp:1574] Processing reply for offers: [ > 2014-02-11-21:14:47-160088899-53443-21045-1 ] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.874322 21058 hierarchical_allocator_process.hpp:590] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 filtered slave > 2014-02-11-21:14:47-160088899-53443-21045-2 for 5secs > I0211 21:14:47.874354 21059 slave.cpp:845] Launching task 0 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.874404 21053 master.hpp:411] Adding task 1 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-1 > (vesta.apache.org) > I0211 21:14:47.874428 21053 master.cpp:2447] Launching task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 with resources cpus(*):1; > mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-1 > (vesta.apache.org) > I0211 21:14:47.874479 21058 slave.cpp:736] Got assigned task 1 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.874586 21053 master.hpp:449] Removing offer > 2014-02-11-21:14:47-160088899-53443-21045-2 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) > I0211 21:14:47.874646 21053 master.cpp:1574] Processing reply for offers: [ > 2014-02-11-21:14:47-160088899-53443-21045-2 ] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.874690 21058 slave.cpp:845] Launching task 1 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.874694 21053 master.hpp:411] Adding task 2 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-0 > (vesta.apache.org) > I0211 21:14:47.874716 21053 master.cpp:2447] Launching task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 with resources cpus(*):1; > mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-0 > (vesta.apache.org) > I0211 21:14:47.874820 21053 hierarchical_allocator_process.hpp:547] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 left cpus(*):7; mem(*):6929; > disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 > I0211 21:14:47.874892 21053 hierarchical_allocator_process.hpp:590] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 filtered slave > 2014-02-11-21:14:47-160088899-53443-21045-1 for 5secs > I0211 21:14:47.874922 21053 hierarchical_allocator_process.hpp:547] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 left cpus(*):7; mem(*):6929; > disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 > I0211 21:14:47.874980 21053 hierarchical_allocator_process.hpp:590] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 filtered slave > 2014-02-11-21:14:47-160088899-53443-21045-0 for 5secs > I0211 21:14:47.875012 21053 slave.cpp:736] Got assigned task 2 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.875151 21053 slave.cpp:845] Launching task 2 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.875527 21059 slave.cpp:955] Queuing task '0' for executor > default of framework '2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.875608 21059 process_isolator.cpp:102] Launching default > (/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src/examples/python/test-executor) > in > /tmp/mesos-Z8v6cu/2/slaves/2014-02-11-21:14:47-160088899-53443-21045-2/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/02cdf8bd-0757-4a40-8e77-af60bb202d71 > with resources cpus(*):1; mem(*):32' for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.876787 21054 slave.cpp:469] Successfully attached file > '/tmp/mesos-Z8v6cu/2/slaves/2014-02-11-21:14:47-160088899-53443-21045-2/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/02cdf8bd-0757-4a40-8e77-af60bb202d71' > I0211 21:14:47.876852 21059 process_isolator.cpp:165] Forked executor at 21061 > I0211 21:14:47.876940 21058 slave.cpp:955] Queuing task '1' for executor > default of framework '2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.877095 21057 process_isolator.cpp:102] Launching default > (/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src/examples/python/test-executor) > in > /tmp/mesos-Z8v6cu/0/slaves/2014-02-11-21:14:47-160088899-53443-21045-1/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/568b657d-839d-483f-aff1-4872fbfc27dc > with resources cpus(*):1; mem(*):32' for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.877102 21052 slave.cpp:469] Successfully attached file > '/tmp/mesos-Z8v6cu/0/slaves/2014-02-11-21:14:47-160088899-53443-21045-1/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/568b657d-839d-483f-aff1-4872fbfc27dc' > I0211 21:14:47.878783 21057 process_isolator.cpp:165] Forked executor at 21062 > I0211 21:14:47.879032 21053 slave.cpp:955] Queuing task '2' for executor > default of framework '2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.879192 21054 slave.cpp:2098] Monitoring executor default of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 forked at pid 21062 > I0211 21:14:47.879192 21058 slave.cpp:469] Successfully attached file > '/tmp/mesos-Z8v6cu/1/slaves/2014-02-11-21:14:47-160088899-53443-21045-0/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/a7c4170a-f40b-4493-81b3-0ea8c70e3977' > I0211 21:14:47.879166 21052 process_isolator.cpp:102] Launching default > (/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/src/examples/python/test-executor) > in > /tmp/mesos-Z8v6cu/1/slaves/2014-02-11-21:14:47-160088899-53443-21045-0/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/a7c4170a-f40b-4493-81b3-0ea8c70e3977 > with resources cpus(*):1; mem(*):32' for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:47.880775 21057 slave.cpp:2098] Monitoring executor default of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 forked at pid 21061 > I0211 21:14:47.880959 21052 process_isolator.cpp:165] Forked executor at 21064 > E0211 21:14:47.881386 21054 slave.cpp:2124] Failed to watch executor default > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000: Already watched > I0211 21:14:47.881474 21055 slave.cpp:2098] Monitoring executor default of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 forked at pid 21064 > E0211 21:14:47.881516 21055 slave.cpp:2124] Failed to watch executor default > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000: Already watched > Fetching resources into > '/tmp/mesos-Z8v6cu/2/slaves/2014-02-11-21:14:47-160088899-53443-21045-2/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/02cdf8bd-0757-4a40-8e77-af60bb202d71' > Fetching resources into > '/tmp/mesos-Z8v6cu/0/slaves/2014-02-11-21:14:47-160088899-53443-21045-1/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/568b657d-839d-483f-aff1-4872fbfc27dc' > Fetching resources into > '/tmp/mesos-Z8v6cu/1/slaves/2014-02-11-21:14:47-160088899-53443-21045-0/frameworks/2014-02-11-21:14:47-160088899-53443-21045-0000/executors/default/runs/a7c4170a-f40b-4493-81b3-0ea8c70e3977' > WARNING: Logging before InitGoogleLogging() is written to STDERR > I0211 21:14:48.154657 21117 process.cpp:1591] libprocess is initialized on > 67.195.138.9:60148 for 8 cpus > I0211 21:14:48.155632 21117 exec.cpp:131] Version: 0.18.0 > WARNING: Logging before InitGoogleLogging() is written to STDERR > I0211 21:14:48.156184 21116 process.cpp:1591] libprocess is initialized on > 67.195.138.9:55901 for 8 cpus > I0211 21:14:48.157078 21119 exec.cpp:181] Executor started at: > executor(1)@67.195.138.9:60148 with pid 21117 > I0211 21:14:48.157146 21116 exec.cpp:131] Version: 0.18.0 > I0211 21:14:48.157536 21052 slave.cpp:1431] Got registration for executor > 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.157784 21052 slave.cpp:1552] Flushing queued task 2 for > executor 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.158042 21126 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.158088 21124 exec.cpp:205] Executor registered on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 > WARNING: Logging before InitGoogleLogging() is written to STDERR > I0211 21:14:48.158324 21113 process.cpp:1591] libprocess is initialized on > 67.195.138.9:43514 for 8 cpus > I0211 21:14:48.158526 21128 exec.cpp:181] Executor started at: > executor(1)@67.195.138.9:55901 with pid 21116 > I0211 21:14:48.158803 21055 slave.cpp:1431] Got registration for executor > 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.159018 21055 slave.cpp:1552] Flushing queued task 1 for > executor 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.159241 21133 exec.cpp:205] Executor registered on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 > I0211 21:14:48.159246 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.159283 21113 exec.cpp:131] Version: 0.18.0 > I0211 21:14:48.159543 21124 exec.cpp:217] Executor::registered took 575493ns > I0211 21:14:48.159593 21124 exec.cpp:292] Executor asked to run task '2' > Starting executor > Running task 2 > I0211 21:14:48.160181 21124 exec.cpp:301] Executor::launchTask took 569794ns > I0211 21:14:48.160450 21133 exec.cpp:217] Executor::registered took 454612ns > I0211 21:14:48.160522 21133 exec.cpp:292] Executor asked to run task '1' > Sending status update... > I0211 21:14:48.160640 21137 exec.cpp:181] Executor started at: > executor(1)@67.195.138.9:43514 with pid 21113 > Sent status update > I0211 21:14:48.160894 21052 slave.cpp:1431] Got registration for executor > 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > Starting executor > Running task 1 > I0211 21:14:48.161001 21133 exec.cpp:301] Executor::launchTask took 466392ns > I0211 21:14:48.161068 21052 slave.cpp:1552] Flushing queued task 0 for > executor 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.161222 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.161273 21137 exec.cpp:205] Executor registered on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 > ISending status update... > 0211 21:14:48.161321 21144 process.cpp:1010] Socket closed while receiving > Sent status update > I0211 21:14:48.161535 21125 exec.cpp:524] Executor sending status update > TASK_RUNNING (UUID: bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task 2 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.161744 21058 slave.cpp:1765] Handling status update > TASK_RUNNING (UUID: bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task 2 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:60148 > I0211 21:14:48.161859 21058 status_update_manager.cpp:314] Received status > update TASK_RUNNING (UUID: bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task 2 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.161874 21058 status_update_manager.cpp:493] Creating > StatusUpdate stream for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.161938 21058 status_update_manager.cpp:367] Forwarding status > update TASK_RUNNING (UUID: bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task 2 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.162057 21058 master.cpp:2026] Status update TASK_RUNNING > (UUID: bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(2)@67.195.138.9:53443 > I0211 21:14:48.162080 21058 slave.cpp:1884] Status update manager > successfully handled status update TASK_RUNNING (UUID: > bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.162088 21058 slave.cpp:1890] Sending acknowledgement for > status update TASK_RUNNING (UUID: bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for > task 2 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:60148 > I0211 21:14:48.162555 21058 sched.cpp:616] Scheduler::statusUpdate took > 351553ns > I0211 21:14:48.162623 21058 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task > 2 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.162669 21058 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > bd0018b7-0742-42bc-a0a0-1d90f87e7d3b) for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.162766 21126 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.163368 21131 exec.cpp:524] Executor sending status update > TASK_RUNNING (UUID: e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task 1 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.163434 21125 exec.cpp:524] Executor sending status update > TASK_FINISHED (UUID: 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task 2 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.163486 21125 exec.cpp:338] Executor received status update > acknowledgement bd0018b7-0742-42bc-a0a0-1d90f87e7d3b for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.163565 21058 slave.cpp:1765] Handling status update > TASK_FINISHED (UUID: 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task 2 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:60148 > I0211 21:14:48.163583 21058 slave.cpp:3214] Terminating task 2 > I0211 21:14:48.163662 21058 slave.cpp:1765] Handling status update > TASK_RUNNING (UUID: e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task 1 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:55901 > I0211 21:14:48.163676 21137 exec.cpp:217] Executor::registered took 548316ns > II0211 21:14:48.163739 21058 status_update_manager.cpp:314] Received status > update TASK_FINISHED (UUID: 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task 2 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > 0211 21:14:48.163740 21137 exec.cpp:292] Executor asked to run task '0' > I0211 21:14:48.163756 21058 status_update_manager.cpp:367] Forwarding status > update TASK_FINISHED (UUID: 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task 2 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.163813 21058 status_update_manager.cpp:314] Received status > update TASK_RUNNING (UUID: e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task 1 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.163825 21058 status_update_manager.cpp:493] Creating > StatusUpdate stream for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.163868 21058 status_update_manager.cpp:367] Forwarding status > update TASK_RUNNING (UUID: e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task 1 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.163954 21058 master.cpp:2026] Status update TASK_FINISHED > (UUID: 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(2)@67.195.138.9:53443 > I0211 21:14:48.163998 21058 master.hpp:429] Removing task 2 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-0 > (vesta.apache.org) > I0211 21:14:48.164083 21058 master.cpp:2026] Status update TASK_RUNNING > (UUID: e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(1)@67.195.138.9:53443 > I0211 21:14:48.164103 21058 slave.cpp:1884] Status update manager > successfully handled status update TASK_FINISHED (UUID: > 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164113 21058 slave.cpp:1890] Sending acknowledgement for > status update TASK_FINISHED (UUID: 1d57909c-8b68-45f9-9785-c4b6ad29e664) for > task 2 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:60148 > I0211 21:14:48.164181 21058 slave.cpp:1884] Status update manager > successfully handled status update TASK_RUNNING (UUID: > e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > II0211 21:14:48.164193 21058 slave.cpp:1890] Sending acknowledgement for > status update TASK_RUNNING (UUID: e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for > task 1 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:55901 > 0211 21:14:48.164191 21131 exec.cpp:524] Executor sending status update > TASK_FINISHED (UUID: 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task 1 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > Starting executor > Running task 0 > I0211 21:14:48.164299 21052 hierarchical_allocator_process.hpp:637] Recovered > cpus(*):1; mem(*):32 (total allocatable: cpus(*):8; mem(*):6961; > disk(*):1.38501e+06; ports(*):[31000-32000]) on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 from framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164361 21058 slave.cpp:1765] Handling status update > TASK_FINISHED (UUID: 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task 1 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:55901 > I0211 21:14:48.164392 21058 slave.cpp:3214] Terminating task 1 > I0211 21:14:48.164505 21052 status_update_manager.cpp:314] Received status > update TASK_FINISHED (UUID: 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task 1 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164512 21057 sched.cpp:616] Scheduler::statusUpdate took > 238156ns > I0211 21:14:48.164559 21052 slave.cpp:1884] Status update manager > successfully handled status update TASK_FINISHED (UUID: > 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > II0211 21:14:48.164572 21052 slave.cpp:1890] Sending acknowledgement for > status update TASK_FINISHED (UUID: 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for > task 1 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:55901 > 0211 21:14:48.164571 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.164600 21130 exec.cpp:338] Executor received status update > acknowledgement e2254a60-ebc8-4553-9ed8-e44cc4d84eb8 for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164635 21057 sched.cpp:616] Scheduler::statusUpdate took > 76837ns > Sending status update... > I0211 21:14:48.164715 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.164726 21057 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task > 1 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164728 21130 exec.cpp:338] Executor received status update > acknowledgement 37fd5f35-c3b3-4c16-b836-3cab90ed6874 for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164749 21057 status_update_manager.cpp:367] Forwarding status > update TASK_FINISHED (UUID: 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task 1 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > Sent status update > I0211 21:14:48.164818 21057 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > e2254a60-ebc8-4553-9ed8-e44cc4d84eb8) for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164842 21053 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task > 2 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164829 21137 exec.cpp:301] Executor::launchTask took 1.068244ms > I0211 21:14:48.164872 21053 status_update_manager.cpp:524] Cleaning up status > update stream for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164911 21126 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.164952 21122 exec.cpp:338] Executor received status update > acknowledgement 1d57909c-8b68-45f9-9785-c4b6ad29e664 for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.164995 21126 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.165006 21122 exec.cpp:359] Executor received framework message > I0211 21:14:48.165004 21058 master.cpp:2026] Status update TASK_FINISHED > (UUID: 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(1)@67.195.138.9:53443 > I0211 21:14:48.165043 21057 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > 1d57909c-8b68-45f9-9785-c4b6ad29e664) for task 2 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.165052 21122 exec.cpp:368] Executor::frameworkMessage took > 34533ns > I0211 21:14:48.165058 21057 slave.cpp:3237] Completing task 2 > I0211 21:14:48.165057 21058 master.hpp:429] Removing task 1 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-1 > (vesta.apache.org) > I0211 21:14:48.165175 21053 sched.cpp:616] Scheduler::statusUpdate took > 162784ns > I0211 21:14:48.165220 21058 slave.cpp:1943] Sending message for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 to > scheduler(1)@67.195.138.9:53443 > I0211 21:14:48.165211 21055 hierarchical_allocator_process.hpp:637] Recovered > cpus(*):1; mem(*):32 (total allocatable: cpus(*):8; mem(*):6961; > disk(*):1.38501e+06; ports(*):[31000-32000]) on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 from framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.165316 21057 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task > 1 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.165386 21057 status_update_manager.cpp:524] Cleaning up status > update stream for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.165427 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.165451 21055 sched.cpp:701] Scheduler::frameworkMessage took > 170832ns > I0211 21:14:48.165462 21053 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > 37fd5f35-c3b3-4c16-b836-3cab90ed6874) for task 1 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.165468 21132 exec.cpp:359] Executor received framework message > I0211 21:14:48.165475 21053 slave.cpp:3237] Completing task 1 > I0211 21:14:48.165493 21132 exec.cpp:368] Executor::frameworkMessage took > 11572ns > I0211 21:14:48.165654 21057 slave.cpp:1943] Sending message for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 to > scheduler(1)@67.195.138.9:53443 > I0211 21:14:48.165786 21057 sched.cpp:701] Scheduler::frameworkMessage took > 96059ns > I0211 21:14:48.165777 21137 exec.cpp:524] Executor sending status update > TASK_RUNNING (UUID: f955480b-856b-4f79-8d92-63edea7ad97d) for task 0 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.165974 21055 slave.cpp:1765] Handling status update > TASK_RUNNING (UUID: f955480b-856b-4f79-8d92-63edea7ad97d) for task 0 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:43514 > I0211 21:14:48.166085 21053 status_update_manager.cpp:314] Received status > update TASK_RUNNING (UUID: f955480b-856b-4f79-8d92-63edea7ad97d) for task 0 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166113 21053 status_update_manager.cpp:493] Creating > StatusUpdate stream for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166160 21053 status_update_manager.cpp:367] Forwarding status > update TASK_RUNNING (UUID: f955480b-856b-4f79-8d92-63edea7ad97d) for task 0 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.166240 21052 master.cpp:2026] Status update TASK_RUNNING > (UUID: f955480b-856b-4f79-8d92-63edea7ad97d) for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(3)@67.195.138.9:53443 > I0211 21:14:48.166244 21055 slave.cpp:1884] Status update manager > successfully handled status update TASK_RUNNING (UUID: > f955480b-856b-4f79-8d92-63edea7ad97d) for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166278 21055 slave.cpp:1890] Sending acknowledgement for > status update TASK_RUNNING (UUID: f955480b-856b-4f79-8d92-63edea7ad97d) for > task 0 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:43514 > I0211 21:14:48.166385 21058 sched.cpp:616] Scheduler::statusUpdate took > 88496ns > I0211 21:14:48.166489 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.166631 21052 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: f955480b-856b-4f79-8d92-63edea7ad97d) for task > 0 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166653 21137 exec.cpp:524] Executor sending status update > TASK_FINISHED (UUID: 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task 0 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166679 21052 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > f955480b-856b-4f79-8d92-63edea7ad97d) for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166699 21137 exec.cpp:338] Executor received status update > acknowledgement f955480b-856b-4f79-8d92-63edea7ad97d for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166836 21059 slave.cpp:1765] Handling status update > TASK_FINISHED (UUID: 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task 0 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:43514 > I0211 21:14:48.166858 21059 slave.cpp:3214] Terminating task 0 > I0211 21:14:48.166960 21054 status_update_manager.cpp:314] Received status > update TASK_FINISHED (UUID: 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task 0 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.166988 21054 status_update_manager.cpp:367] Forwarding status > update TASK_FINISHED (UUID: 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task 0 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.167079 21055 slave.cpp:1884] Status update manager > successfully handled status update TASK_FINISHED (UUID: > 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.167095 21055 slave.cpp:1890] Sending acknowledgement for > status update TASK_FINISHED (UUID: 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for > task 0 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:43514 > I0211 21:14:48.167100 21059 master.cpp:2026] Status update TASK_FINISHED > (UUID: 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(3)@67.195.138.9:53443 > I0211 21:14:48.167147 21059 master.hpp:429] Removing task 0 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-2 > (vesta.apache.org) > I0211 21:14:48.167260 21055 hierarchical_allocator_process.hpp:637] Recovered > cpus(*):1; mem(*):32 (total allocatable: cpus(*):8; mem(*):6961; > disk(*):1.38501e+06; ports(*):[31000-32000]) on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 from framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.167284 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.167284 21140 exec.cpp:338] Executor received status update > acknowledgement 9af4cdff-74ae-40ab-9788-0d5f8b7435ec for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.167326 21054 sched.cpp:616] Scheduler::statusUpdate took > 160237ns > I0211 21:14:48.167469 21057 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task > 0 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.167495 21057 status_update_manager.cpp:524] Cleaning up status > update stream for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.167501 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.167520 21141 exec.cpp:359] Executor received framework message > I0211 21:14:48.167543 21054 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > 9af4cdff-74ae-40ab-9788-0d5f8b7435ec) for task 0 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.167563 21054 slave.cpp:3237] Completing task 0 > I0211 21:14:48.167563 21141 exec.cpp:368] Executor::frameworkMessage took > 29844ns > I0211 21:14:48.167691 21057 slave.cpp:1943] Sending message for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 to > scheduler(1)@67.195.138.9:53443 > I0211 21:14:48.167773 21052 sched.cpp:701] Scheduler::frameworkMessage took > 46462ns > I0211 21:14:48.866621 21057 hierarchical_allocator_process.hpp:752] Offering > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.866730 21057 hierarchical_allocator_process.hpp:752] Offering > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.866799 21057 hierarchical_allocator_process.hpp:752] Offering > cpus(*):8; mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.866981 21057 hierarchical_allocator_process.hpp:688] Performed > allocation for 3 slaves in 433438ns > I0211 21:14:48.867055 21059 master.hpp:439] Adding offer > 2014-02-11-21:14:47-160088899-53443-21045-3 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) > I0211 21:14:48.867164 21059 master.hpp:439] Adding offer > 2014-02-11-21:14:47-160088899-53443-21045-4 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) > I0211 21:14:48.867241 21059 master.hpp:439] Adding offer > 2014-02-11-21:14:47-160088899-53443-21045-5 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) > I0211 21:14:48.867285 21059 master.cpp:2278] Sending 3 offers to framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.869622 21053 sched.cpp:525] Scheduler::resourceOffers took > 2.155683ms > I0211 21:14:48.869803 21059 master.hpp:449] Removing offer > 2014-02-11-21:14:47-160088899-53443-21045-3 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) > I0211 21:14:48.869858 21059 master.cpp:1574] Processing reply for offers: [ > 2014-02-11-21:14:47-160088899-53443-21045-3 ] on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.869946 21059 master.hpp:411] Adding task 3 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-2 > (vesta.apache.org) > I0211 21:14:48.869969 21059 master.cpp:2447] Launching task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 with resources cpus(*):1; > mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-2 > (vesta.apache.org) > I0211 21:14:48.870033 21053 slave.cpp:736] Got assigned task 3 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870142 21059 master.hpp:449] Removing offer > 2014-02-11-21:14:47-160088899-53443-21045-4 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) > I0211 21:14:48.870169 21058 hierarchical_allocator_process.hpp:547] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 left cpus(*):7; mem(*):6929; > disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 > I0211 21:14:48.870193 21059 master.cpp:1574] Processing reply for offers: [ > 2014-02-11-21:14:47-160088899-53443-21045-4 ] on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870250 21059 master.hpp:411] Adding task 4 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-1 > (vesta.apache.org) > I0211 21:14:48.870275 21059 master.cpp:2447] Launching task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 with resources cpus(*):1; > mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-1 > (vesta.apache.org) > I0211 21:14:48.870281 21058 hierarchical_allocator_process.hpp:590] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 filtered slave > 2014-02-11-21:14:47-160088899-53443-21045-2 for 5secs > I0211 21:14:48.870331 21058 slave.cpp:736] Got assigned task 4 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870414 21058 slave.cpp:845] Launching task 4 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870406 21059 master.hpp:449] Removing offer > 2014-02-11-21:14:47-160088899-53443-21045-5 with resources cpus(*):8; > mem(*):6961; disk(*):1.38501e+06; ports(*):[31000-32000] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) > I0211 21:14:48.870468 21058 slave.cpp:980] Sending task '4' to executor > 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870475 21059 master.cpp:1574] Processing reply for offers: [ > 2014-02-11-21:14:47-160088899-53443-21045-5 ] on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870528 21059 hierarchical_allocator_process.hpp:547] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 left cpus(*):7; mem(*):6929; > disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 > I0211 21:14:48.870601 21059 hierarchical_allocator_process.hpp:590] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 filtered slave > 2014-02-11-21:14:47-160088899-53443-21045-1 for 5secs > I0211 21:14:48.870632 21053 slave.cpp:845] Launching task 3 for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870656 21059 hierarchical_allocator_process.hpp:547] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 left cpus(*):8; mem(*):6961; > disk(*):1.38501e+06; ports(*):[31000-32000] unused on slave > 2014-02-11-21:14:47-160088899-53443-21045-0 > I0211 21:14:48.870666 21053 slave.cpp:980] Sending task '3' to executor > 'default' of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.870735 21059 hierarchical_allocator_process.hpp:590] Framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 filtered slave > 2014-02-11-21:14:47-160088899-53443-21045-0 for 5secs > I0211 21:14:48.870842 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.870910 21133 exec.cpp:292] Executor asked to run task '4' > I0211 21:14:48.870980 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.871078 21136 exec.cpp:292] Executor asked to run task '3' > Running task 4 > I0211 21:14:48.871618 21133 exec.cpp:301] Executor::launchTask took 669868ns > Sending status update... > Running task 3 > Sent status update > Sending status update... > Sent status update > I0211 21:14:48.872700 21134 exec.cpp:524] Executor sending status update > TASK_RUNNING (UUID: 1402086c-13c6-4892-a87a-603864039b45) for task 4 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.872844 21136 exec.cpp:301] Executor::launchTask took 1.735607ms > I0211 21:14:48.872951 21057 slave.cpp:1765] Handling status update > TASK_RUNNING (UUID: 1402086c-13c6-4892-a87a-603864039b45) for task 4 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:55901 > I0211 21:14:48.873046 21055 status_update_manager.cpp:314] Received status > update TASK_RUNNING (UUID: 1402086c-13c6-4892-a87a-603864039b45) for task 4 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.873066 21055 status_update_manager.cpp:493] Creating > StatusUpdate stream for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.873123 21055 status_update_manager.cpp:367] Forwarding status > update TASK_RUNNING (UUID: 1402086c-13c6-4892-a87a-603864039b45) for task 4 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.873214 21057 master.cpp:2026] Status update TASK_RUNNING > (UUID: 1402086c-13c6-4892-a87a-603864039b45) for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(1)@67.195.138.9:53443 > I0211 21:14:48.873245 21058 slave.cpp:1884] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 1402086c-13c6-4892-a87a-603864039b45) for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.873268 21058 slave.cpp:1890] Sending acknowledgement for > status update TASK_RUNNING (UUID: 1402086c-13c6-4892-a87a-603864039b45) for > task 4 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:55901 > I0211 21:14:48.873344 21055 sched.cpp:616] Scheduler::statusUpdate took > 109430ns > I0211 21:14:48.873440 21055 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: 1402086c-13c6-4892-a87a-603864039b45) for task > 4 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.873472 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.873497 21057 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > 1402086c-13c6-4892-a87a-603864039b45) for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874042 21134 exec.cpp:524] Executor sending status update > TASK_FINISHED (UUID: 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task 4 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874084 21134 exec.cpp:338] Executor received status update > acknowledgement 1402086c-13c6-4892-a87a-603864039b45 for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874217 21055 slave.cpp:1765] Handling status update > TASK_FINISHED (UUID: 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task 4 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:55901 > I0211 21:14:48.874234 21055 slave.cpp:3214] Terminating task 4 > I0211 21:14:48.874250 21136 exec.cpp:524] Executor sending status update > TASK_RUNNING (UUID: 14454a53-c4e5-49bd-be22-cc119dbf206e) for task 3 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874305 21055 status_update_manager.cpp:314] Received status > update TASK_FINISHED (UUID: 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task 4 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874326 21055 status_update_manager.cpp:367] Forwarding status > update TASK_FINISHED (UUID: 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task 4 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.874400 21055 slave.cpp:1765] Handling status update > TASK_RUNNING (UUID: 14454a53-c4e5-49bd-be22-cc119dbf206e) for task 3 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:43514 > I0211 21:14:48.874440 21052 master.cpp:2026] Status update TASK_FINISHED > (UUID: 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(1)@67.195.138.9:53443 > I0211 21:14:48.874461 21055 slave.cpp:1884] Status update manager > successfully handled status update TASK_FINISHED (UUID: > 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874471 21055 slave.cpp:1890] Sending acknowledgement for > status update TASK_FINISHED (UUID: 9ef8e17e-8569-41fc-93f2-df09a42bf876) for > task 4 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:55901 > I0211 21:14:48.874487 21052 master.hpp:429] Removing task 4 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-1 > (vesta.apache.org) > I0211 21:14:48.874555 21052 status_update_manager.cpp:314] Received status > update TASK_RUNNING (UUID: 14454a53-c4e5-49bd-be22-cc119dbf206e) for task 3 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874575 21052 status_update_manager.cpp:493] Creating > StatusUpdate stream for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874647 21052 status_update_manager.cpp:367] Forwarding status > update TASK_RUNNING (UUID: 14454a53-c4e5-49bd-be22-cc119dbf206e) for task 3 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.874707 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.874711 21055 sched.cpp:616] Scheduler::statusUpdate took > 141827ns > I0211 21:14:48.874744 21132 exec.cpp:338] Executor received status update > acknowledgement 9ef8e17e-8569-41fc-93f2-df09a42bf876 for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874634 21054 hierarchical_allocator_process.hpp:637] Recovered > cpus(*):1; mem(*):32 (total allocatable: cpus(*):8; mem(*):6961; > disk(*):1.38501e+06; ports(*):[31000-32000]) on slave > 2014-02-11-21:14:47-160088899-53443-21045-1 from framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874775 21054 slave.cpp:1884] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 14454a53-c4e5-49bd-be22-cc119dbf206e) for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.874785 21054 slave.cpp:1890] Sending acknowledgement for > status update TASK_RUNNING (UUID: 14454a53-c4e5-49bd-be22-cc119dbf206e) for > task 3 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:43514 > I0211 21:14:48.874802 21052 master.cpp:2026] Status update TASK_RUNNING > (UUID: 14454a53-c4e5-49bd-be22-cc119dbf206e) for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(3)@67.195.138.9:53443 > I0211 21:14:48.874904 21055 sched.cpp:616] Scheduler::statusUpdate took > 76541ns > I0211 21:14:48.874948 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.874986 21055 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: 14454a53-c4e5-49bd-be22-cc119dbf206e) for task > 3 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875021 21058 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task > 4 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875051 21055 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > 14454a53-c4e5-49bd-be22-cc119dbf206e) for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875064 21058 status_update_manager.cpp:524] Cleaning up status > update stream for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875071 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.875093 21131 exec.cpp:359] Executor received framework message > II0211 21:14:48.875123 21131 exec.cpp:368] Executor::frameworkMessage took > 17599ns > 0211 21:14:48.875120 21136 exec.cpp:524] Executor sending status update > TASK_FINISHED (UUID: 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task 3 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875174 21136 exec.cpp:338] Executor received status update > acknowledgement 14454a53-c4e5-49bd-be22-cc119dbf206e for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875203 21059 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > 9ef8e17e-8569-41fc-93f2-df09a42bf876) for task 4 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875219 21059 slave.cpp:3237] Completing task 4 > I0211 21:14:48.875272 21059 slave.cpp:1943] Sending message for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 to > scheduler(1)@67.195.138.9:53443 > I0211 21:14:48.875335 21058 slave.cpp:1765] Handling status update > TASK_FINISHED (UUID: 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task 3 of > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 from > executor(1)@67.195.138.9:43514 > I0211 21:14:48.875358 21058 slave.cpp:3214] Terminating task 3 > I0211 21:14:48.875360 21059 sched.cpp:701] Scheduler::frameworkMessage took > 53004ns > I0211 21:14:48.875466 21059 status_update_manager.cpp:314] Received status > update TASK_FINISHED (UUID: 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task 3 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875488 21059 status_update_manager.cpp:367] Forwarding status > update TASK_FINISHED (UUID: 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task 3 > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > master@67.195.138.9:53443 > I0211 21:14:48.875579 21057 slave.cpp:1884] Status update manager > successfully handled status update TASK_FINISHED (UUID: > 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875582 21058 master.cpp:2026] Status update TASK_FINISHED > (UUID: 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 from > slave(3)@67.195.138.9:53443 > I0211 21:14:48.875604 21057 slave.cpp:1890] Sending acknowledgement for > status update TASK_FINISHED (UUID: 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for > task 3 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 to > executor(1)@67.195.138.9:43514 > I0211 21:14:48.875639 21058 master.hpp:429] Removing task 3 with resources > cpus(*):1; mem(*):32 on slave 2014-02-11-21:14:47-160088899-53443-21045-2 > (vesta.apache.org) > I0211 21:14:48.875778 21055 sched.cpp:616] Scheduler::statusUpdate took > 143427ns > I0211 21:14:48.875794 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.875833 21058 hierarchical_allocator_process.hpp:637] Recovered > cpus(*):1; mem(*):32 (total allocatable: cpus(*):8; mem(*):6961; > disk(*):1.38501e+06; ports(*):[31000-32000]) on slave > 2014-02-11-21:14:47-160088899-53443-21045-2 from framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875932 21138 exec.cpp:338] Executor received status update > acknowledgement 292fb4b8-d187-497c-8d7f-b8e6f3bba219 for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.875988 21055 status_update_manager.cpp:392] Received status > update acknowledgement (UUID: 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task > 3 of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876013 21055 status_update_manager.cpp:524] Cleaning up status > update stream for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876006 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.876032 21137 exec.cpp:359] Executor received framework message > I0211 21:14:48.876083 21137 exec.cpp:368] Executor::frameworkMessage took > 36509ns > I0211 21:14:48.876106 21058 slave.cpp:1371] Status update manager > successfully handled status update acknowledgement (UUID: > 292fb4b8-d187-497c-8d7f-b8e6f3bba219) for task 3 of framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876121 21058 slave.cpp:3237] Completing task 3 > I0211 21:14:48.876209 21055 slave.cpp:1943] Sending message for framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 to > scheduler(1)@67.195.138.9:53443 > I0211 21:14:48.876293 21055 sched.cpp:701] Scheduler::frameworkMessage took > 59391ns > I0211 21:14:48.876307 21055 sched.cpp:727] Stopping framework > '2014-02-11-21:14:47-160088899-53443-21045-0000' > I0211 21:14:48.876369 21052 master.cpp:1024] Asked to unregister framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876387 21052 master.cpp:2682] Removing framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876422 21055 hierarchical_allocator_process.hpp:408] > Deactivated framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876449 21055 slave.cpp:1142] Asked to shut down framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 by master@67.195.138.9:53443 > I0211 21:14:48.876461 21055 slave.cpp:1167] Shutting down framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > Enabling authentication for the framework > Registered with framework ID 2014-02-11-21:14:47-160088899-53443-21045-0000 > Got 3 resource offers > Got resource offer 2014-02-11-21:14:47-160088899-53443-21045-0 > Accepting offer on vesta.apache.org to start task 0 > Got resource offer 2014-02-11-21:14:47-160088899-53443-21045-1 > Accepting offer on vesta.apache.org to start task 1 > Got resource offer 2014-02-11-21:14:47-160088899-53443-21045-2 > Accepting offer on vesta.apache.org to start task 2 > Task 2 is in state 1 > Task 2 is in state 2 > Task 1 is in state 1 > Task 1 is in state 2 > Received message: 'data with a \x00 byte' > Received message: 'data with a \x00 byte' > Task 0 is in state 1 > Task 0 is in state 2 > Received message: 'data with a \x00 byte' > Got 3 resource offers > Got resource offer 2014-02-11-21:14:47-160088899-53443-21045-3 > Accepting offer on vesta.apache.org to start task 3 > Got resource offer 2014-02-11-21:14:47-160088899-53443-21045-4 > Accepting offer on vesta.apache.org to start task 4 > Got resource offer 2014-02-11-21:14:47-160088899-53443-21045-5 > Task 4 is in state 1 > Task 4 is in state 2 > Task 3 is in state 1 > Received message: 'data with a \x00 byte' > Task 3 is in state 2 > All tasks done, waiting for final framework message > Received message: 'data with a \x00 byte' > All tasks done, and all messages received, exiting > I0211 21:14:48.876477 21055 slave.cpp:2431] Shutting down executor 'default' > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876525 21052 slave.cpp:1142] Asked to shut down framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 by master@67.195.138.9:53443 > I0211 21:14:48.876545 21052 slave.cpp:1167] Shutting down framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876555 21052 slave.cpp:2431] Shutting down executor 'default' > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876582 21055 slave.cpp:1142] Asked to shut down framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 by master@67.195.138.9:53443 > I0211 21:14:48.876597 21055 slave.cpp:1167] Shutting down framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876606 21055 slave.cpp:2431] Shutting down executor 'default' > of framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876662 21053 hierarchical_allocator_process.hpp:363] Removed > framework 2014-02-11-21:14:47-160088899-53443-21045-0000 > I0211 21:14:48.876766 21133 exec.cpp:378] Executor asked to shutdown > I0211 21:14:48.876785 21135 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.876814 21133 exec.cpp:393] Executor::shutdown took 7179ns > I0211 21:14:48.876843 21133 exec.cpp:77] Scheduling shutdown of the executor > I0211 21:14:48.876899 21144 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.876956 21138 exec.cpp:378] Executor asked to shutdown > I0211 21:14:48.876988 21126 process.cpp:1010] Socket closed while receiving > I0211 21:14:48.877019 21138 exec.cpp:393] Executor::shutdown took 22029ns > I0211 21:14:48.877027 21136 exec.cpp:77] Scheduling shutdown of the executor > I0211 21:14:48.877110 21122 exec.cpp:378] Executor asked to shutdown > I0211 21:14:48.877168 21122 exec.cpp:393] Executor::shutdown took 15665ns > I0211 21:14:48.877185 21123 exec.cpp:77] Scheduling shutdown of the executor > I0211 21:14:48.881350 21045 master.cpp:587] Master terminating > I0211 21:14:48.881434 21045 master.cpp:247] Shutting down master > I0211 21:14:48.881440 21058 slave.cpp:1965] master@67.195.138.9:53443 exited > W0211 21:14:48.881453 21058 slave.cpp:1968] Master disconnected! Waiting for > a new master to be elected > I0211 21:14:48.881456 21045 master.cpp:290] Removing slave > 2014-02-11-21:14:47-160088899-53443-21045-2 (vesta.apache.org) > I0211 21:14:48.881464 21052 slave.cpp:1965] master@67.195.138.9:53443 exited > W0211 21:14:48.881475 21052 slave.cpp:1968] Master disconnected! Waiting for > a new master to be elected > I0211 21:14:48.881438 21053 slave.cpp:1965] master@67.195.138.9:53443 exited > W0211 21:14:48.881515 21053 slave.cpp:1968] Master disconnected! Waiting for > a new master to be elected > I0211 21:14:48.881549 21045 master.cpp:290] Removing slave > 2014-02-11-21:14:47-160088899-53443-21045-1 (vesta.apache.org) > I0211 21:14:48.881618 21045 master.cpp:290] Removing slave > 2014-02-11-21:14:47-160088899-53443-21045-0 (vesta.apache.org) > I0211 21:14:48.882072 21045 slave.cpp:394] Slave terminating > I0211 21:14:48.882113 21045 slave.cpp:1142] Asked to shut down framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 by @0.0.0.0:0 > W0211 21:14:48.882135 21045 slave.cpp:1163] Ignoring shutdown framework > 2014-02-11-21:14:47-160088899-53443-21045-0000 because it is terminating > I0211 21:14:49.300734 21126 process.cpp:1010] Socket closed while receiving > I0211 21:14:49.300804 21121 exec.cpp:439] Ignoring exited event because the > driver is aborted! > II0211 21:14:49.300813 21135 process.cpp:1010] Socket closed while receiving > 0211 21:14:49.300820 21144 process.cpp:1010] Socket closed while receiving > II0211 21:14:49.300904 21138 exec.cpp:439] Ignoring exited event because the > driver is aborted! > 0211 21:14:49.300907 21133 exec.cpp:439] Ignoring exited event because the > driver is aborted! > tests/script.cpp:81: Failure > Failed > python_framework_test.sh terminated with signal 'Segmentation fault' > [ FAILED ] ExamplesTest.PythonFramework (2484 ms) > {noformat} -- This message was sent by Atlassian JIRA (v6.3.4#6332)