You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@mesos.apache.org by "Vinod Kone (JIRA)" <ji...@apache.org> on 2015/04/22 00:25:58 UTC
[jira] [Resolved] (MESOS-1075) ExamplesTest.TestFramework runs
forever
[ https://issues.apache.org/jira/browse/MESOS-1075?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Vinod Kone resolved MESOS-1075.
-------------------------------
Resolution: Fixed
Assignee: Vinod Kone
haven't seen this in a while. i think this was fixed with the update to test frameworks to fail fast.
> ExamplesTest.TestFramework runs forever
> ---------------------------------------
>
> Key: MESOS-1075
> URL: https://issues.apache.org/jira/browse/MESOS-1075
> Project: Mesos
> Issue Type: Bug
> Components: test
> Affects Versions: 0.19.0
> Environment: Ubuntu 12.04
> Reporter: Vinod Kone
> Assignee: Vinod Kone
>
> {code}
> [ RUN ] ExamplesTest.TestFramework
> Using temporary directory '/tmp/ExamplesTest_TestFramework_Inty2n'
> + grep MESOS_SOURCE_DIR
> + env
> + test 0 '!=' 0
> + grep MESOS_BUILD_DIR
> + env
> + test 0 '!=' 0
> + source /var/jenkins/workspace/mesos-ubuntu-12.04-gcc/support/atexit.sh
> ++ declare -a __atexit_cmds
> ++ mktemp -d -t mesos-XXXXXX
> + MESOS_WORK_DIR=/tmp/mesos-RoIrFs
> + atexit 'rm -rf /tmp/mesos-RoIrFs'
> + local length=0
> + __atexit_cmds[${length}]='rm -rf /tmp/mesos-RoIrFs'
> + [[ 0 -eq 0 ]]
> + trap __atexit EXIT
> + export MESOS_WORK_DIR=/tmp/mesos-RoIrFs
> + MESOS_WORK_DIR=/tmp/mesos-RoIrFs
> + export MESOS_NUM_SLAVES=3
> + MESOS_NUM_SLAVES=3
> + exec /var/jenkins/workspace/mesos-ubuntu-12.04-gcc/src/test-framework --master=local
> Enabling authentication for the framework
> WARNING: Logging before InitGoogleLogging() is written to STDERR
> I0307 13:43:29.858752 21918 process.cpp:1589] libprocess is initialized on 127.0.1.1:60532 for 8 cpus
> I0307 13:43:29.859704 21918 logging.cpp:140] Logging to STDERR
> I0307 13:43:29.860990 21918 master.cpp:254] Master ID: 2014-03-07-13:43:29-16842879-60532-21918 Hostname: precise
> I0307 13:43:29.861851 21946 master.cpp:264] Master started on 127.0.1.1:60532
> I0307 13:43:29.862236 21946 master.cpp:267] Master only allowing authenticated frameworks to register!
> I0307 13:43:29.863009 21948 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : master@127.0.1.1:60532
> I0307 13:43:29.864270 21948 master.cpp:98] No whitelist given. Advertising offers for all slaves
> I0307 13:43:29.865993 21918 containerizer.cpp:180] Using isolation: posix/cpu,posix/mem
> I0307 13:43:29.867660 21948 master.cpp:773] The newly elected leader is master@127.0.1.1:60532 with id 2014-03-07-13:43:29-16842879-60532-21918
> I0307 13:43:29.868123 21948 master.cpp:783] Elected as the leading master!
> I0307 13:43:29.873780 21918 containerizer.cpp:180] Using isolation: posix/cpu,posix/mem
> I0307 13:43:29.874433 21943 slave.cpp:120] Slave started on 1)@127.0.1.1:60532
> I0307 13:43:29.875149 21943 slave.cpp:130] Slave resources: cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000]
> I0307 13:43:29.875658 21943 slave.cpp:158] Slave hostname: precise
> I0307 13:43:29.876027 21943 slave.cpp:159] Slave checkpoint: true
> I0307 13:43:29.877121 21949 state.cpp:33] Recovering state from '/tmp/mesos-RoIrFs/0/meta'
> I0307 13:43:29.882982 21943 status_update_manager.cpp:193] Recovering status update manager
> I0307 13:43:29.883061 21943 mesos_containerizer.cpp:137] Recovering containerizer
> I0307 13:43:29.884135 21918 containerizer.cpp:180] Using isolation: posix/cpu,posix/mem
> I0307 13:43:29.884325 21945 slave.cpp:120] Slave started on 2)@127.0.1.1:60532
> I0307 13:43:29.885432 21945 slave.cpp:130] Slave resources: cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000]
> I0307 13:43:29.885501 21945 slave.cpp:158] Slave hostname: precise
> I0307 13:43:29.885514 21945 slave.cpp:159] Slave checkpoint: true
> I0307 13:43:29.885781 21945 state.cpp:33] Recovering state from '/tmp/mesos-RoIrFs/1/meta'
> I0307 13:43:29.885843 21945 status_update_manager.cpp:193] Recovering status update manager
> I0307 13:43:29.885881 21945 mesos_containerizer.cpp:137] Recovering containerizer
> I0307 13:43:29.886020 21945 slave.cpp:2707] Finished recovery
> I0307 13:43:29.886360 21945 slave.cpp:405] New master detected at master@127.0.1.1:60532
> I0307 13:43:29.886471 21945 slave.cpp:430] Detecting new master
> I0307 13:43:29.886499 21945 status_update_manager.cpp:167] New master detected at master@127.0.1.1:60532
> I0307 13:43:29.886579 21945 master.cpp:1885] Attempting to register slave on precise at slave(2)@127.0.1.1:60532
> I0307 13:43:29.886598 21945 master.cpp:2869] Adding slave 2014-03-07-13:43:29-16842879-60532-21918-0 at precise with cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000]
> I0307 13:43:29.886729 21945 slave.cpp:448] Registered with master master@127.0.1.1:60532; given slave ID 2014-03-07-13:43:29-16842879-60532-21918-0
> I0307 13:43:29.886889 21945 slave.cpp:461] Checkpointing SlaveInfo to '/tmp/mesos-RoIrFs/1/meta/slaves/2014-03-07-13:43:29-16842879-60532-21918-0/slave.info'
> I0307 13:43:29.887059 21945 hierarchical_allocator_process.hpp:445] Added slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) with cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] (and cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] available)
> I0307 13:43:29.887152 21945 hierarchical_allocator_process.hpp:708] Performed allocation for slave 2014-03-07-13:43:29-16842879-60532-21918-0 in 21606ns
> I0307 13:43:29.885253 21943 slave.cpp:2707] Finished recovery
> I0307 13:43:29.888034 21943 slave.cpp:405] New master detected at master@127.0.1.1:60532
> I0307 13:43:29.888087 21943 slave.cpp:430] Detecting new master
> I0307 13:43:29.888128 21943 status_update_manager.cpp:167] New master detected at master@127.0.1.1:60532
> I0307 13:43:29.888164 21943 master.cpp:1885] Attempting to register slave on precise at slave(1)@127.0.1.1:60532
> I0307 13:43:29.888173 21943 master.cpp:2869] Adding slave 2014-03-07-13:43:29-16842879-60532-21918-1 at precise with cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000]
> I0307 13:43:29.888263 21943 slave.cpp:448] Registered with master master@127.0.1.1:60532; given slave ID 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:29.888394 21943 slave.cpp:461] Checkpointing SlaveInfo to '/tmp/mesos-RoIrFs/0/meta/slaves/2014-03-07-13:43:29-16842879-60532-21918-1/slave.info'
> I0307 13:43:29.889194 21942 hierarchical_allocator_process.hpp:445] Added slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) with cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] (and cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] available)
> I0307 13:43:29.889243 21942 hierarchical_allocator_process.hpp:708] Performed allocation for slave 2014-03-07-13:43:29-16842879-60532-21918-1 in 7055ns
> I0307 13:43:29.890279 21942 slave.cpp:120] Slave started on 3)@127.0.1.1:60532
> I0307 13:43:29.890405 21942 slave.cpp:130] Slave resources: cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000]
> I0307 13:43:29.890466 21942 slave.cpp:158] Slave hostname: precise
> I0307 13:43:29.890476 21942 slave.cpp:159] Slave checkpoint: true
> I0307 13:43:29.890719 21942 state.cpp:33] Recovering state from '/tmp/mesos-RoIrFs/2/meta'
> I0307 13:43:29.890782 21942 status_update_manager.cpp:193] Recovering status update manager
> I0307 13:43:29.890817 21942 mesos_containerizer.cpp:137] Recovering containerizer
> I0307 13:43:29.890959 21942 slave.cpp:2707] Finished recovery
> I0307 13:43:29.891193 21942 slave.cpp:405] New master detected at master@127.0.1.1:60532
> I0307 13:43:29.891245 21942 slave.cpp:430] Detecting new master
> I0307 13:43:29.891290 21942 status_update_manager.cpp:167] New master detected at master@127.0.1.1:60532
> I0307 13:43:29.891327 21942 master.cpp:1885] Attempting to register slave on precise at slave(3)@127.0.1.1:60532
> I0307 13:43:29.891337 21942 master.cpp:2869] Adding slave 2014-03-07-13:43:29-16842879-60532-21918-2 at precise with cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000]
> I0307 13:43:29.891414 21942 slave.cpp:448] Registered with master master@127.0.1.1:60532; given slave ID 2014-03-07-13:43:29-16842879-60532-21918-2
> I0307 13:43:29.891528 21942 slave.cpp:461] Checkpointing SlaveInfo to '/tmp/mesos-RoIrFs/2/meta/slaves/2014-03-07-13:43:29-16842879-60532-21918-2/slave.info'
> I0307 13:43:29.891640 21942 hierarchical_allocator_process.hpp:445] Added slave 2014-03-07-13:43:29-16842879-60532-21918-2 (precise) with cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] (and cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] available)
> I0307 13:43:29.891676 21942 hierarchical_allocator_process.hpp:708] Performed allocation for slave 2014-03-07-13:43:29-16842879-60532-21918-2 in 5333ns
> I0307 13:43:29.893339 21918 sched.cpp:121] Version: 0.19.0
> I0307 13:43:29.893944 21944 sched.cpp:217] New master detected at master@127.0.1.1:60532
> I0307 13:43:29.893967 21944 sched.cpp:267] Authenticating with master master@127.0.1.1:60532
> I0307 13:43:29.894124 21944 authenticatee.hpp:104] Initializing client SASL
> I0307 13:43:29.895776 21944 authenticatee.hpp:128] Creating new client SASL connection
> I0307 13:43:29.896527 21944 master.cpp:2382] Authenticating framework at scheduler(1)@127.0.1.1:60532
> I0307 13:43:29.897199 21944 authenticator.hpp:91] Initializing server SASL
> I0307 13:43:29.897725 21944 auxprop.cpp:45] Initialized in-memory auxiliary property plugin
> I0307 13:43:29.898135 21944 authenticator.hpp:148] Creating new server SASL connection
> I0307 13:43:29.898660 21948 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
> I0307 13:43:29.899024 21948 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
> I0307 13:43:29.899485 21944 authenticator.hpp:254] Received SASL authentication start
> I0307 13:43:29.899917 21944 authenticator.hpp:342] Authentication requires more steps
> I0307 13:43:29.900362 21944 authenticatee.hpp:265] Received SASL authentication step
> I0307 13:43:29.900874 21944 authenticator.hpp:282] Received SASL authentication step
> I0307 13:43:29.901331 21944 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'precise' server FQDN: 'precise' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0307 13:43:29.901705 21944 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
> I0307 13:43:29.902138 21944 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
> I0307 13:43:29.902156 21944 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'precise' server FQDN: 'precise' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0307 13:43:29.902163 21944 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0307 13:43:29.902168 21944 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0307 13:43:29.902179 21944 authenticator.hpp:334] Authentication success
> I0307 13:43:29.902644 21949 authenticatee.hpp:305] Authentication success
> I0307 13:43:29.903141 21945 master.cpp:2422] Successfully authenticated framework at scheduler(1)@127.0.1.1:60532
> I0307 13:43:29.903643 21946 sched.cpp:341] Successfully authenticated with master master@127.0.1.1:60532
> I0307 13:43:29.903666 21946 sched.cpp:460] Sending registration request to master@127.0.1.1:60532
> I0307 13:43:29.904175 21945 master.cpp:831] Received registration request from scheduler(1)@127.0.1.1:60532
> I0307 13:43:29.904220 21945 master.cpp:849] Registering framework 2014-03-07-13:43:29-16842879-60532-21918-0000 at scheduler(1)@127.0.1.1:60532
> I0307 13:43:29.904340 21945 sched.cpp:391] Framework registered with 2014-03-07-13:43:29-16842879-60532-21918-0000
> Registered!
> I0307 13:43:29.904361 21945 sched.cpp:405] Scheduler::registered took 6600ns
> I0307 13:43:29.904398 21945 hierarchical_allocator_process.hpp:332] Added framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.904459 21945 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.904536 21945 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.904589 21945 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.904769 21945 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 348591ns
> I0307 13:43:29.904839 21945 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-0 with resources cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:29.904906 21945 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-1 with resources cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 (precise)
> I0307 13:43:29.904960 21945 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-2 with resources cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:29.904992 21945 master.cpp:2337] Sending 3 offers to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> .Starting task 0 on precise
> Starting task 1 on precise
> Starting task 2 on precise
> I0307 13:43:29.906167 21945 sched.cpp:528] Scheduler::resourceOffers took 971985ns
> I0307 13:43:29.906800 21948 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-0 with resources cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:29.906965 21948 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-0 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.907119 21948 master.hpp:428] Adding task 0 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:29.907150 21948 master.cpp:2506] Launching task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:29.907315 21948 slave.cpp:666] Got assigned task 0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.907594 21948 slave.cpp:775] Launching task 0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.908691 21946 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):947; disk(*):25116; ports(*):[31000-32000] unused on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:29.908762 21946 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-1 for 5secs
> I0307 13:43:29.909729 21948 slave.cpp:885] Queuing task '0' for executor default of framework '2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.909881 21948 mesos_containerizer.cpp:351] Starting container 'bbe08660-8c2e-455e-8993-2ed9b3fd91ab' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000'
> I0307 13:43:29.910065 21943 slave.cpp:366] Successfully attached file '/tmp/mesos-RoIrFs/0/slaves/2014-03-07-13:43:29-16842879-60532-21918-1/frameworks/2014-03-07-13:43:29-16842879-60532-21918-0000/executors/default/runs/bbe08660-8c2e-455e-8993-2ed9b3fd91ab'
> I0307 13:43:29.910893 21948 mesos_containerizer.cpp:492] Fetching URIs for container 'bbe08660-8c2e-455e-8993-2ed9b3fd91ab' using command '/usr/bin/env MESOS_EXECUTOR_URIS= MESOS_WORK_DIRECTORY=/tmp/mesos-RoIrFs/0/slaves/2014-03-07-13:43:29-16842879-60532-21918-1/frameworks/2014-03-07-13:43:29-16842879-60532-21918-0000/executors/default/runs/bbe08660-8c2e-455e-8993-2ed9b3fd91ab MESOS_USER=jenkins MESOS_FRAMEWORKS_HOME= HADOOP_HOME= /var/jenkins/workspace/mesos-ubuntu-12.04-gcc/src/mesos-fetcher'
> I0307 13:43:29.912992 21942 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-1 with resources cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 (precise)
> I0307 13:43:29.913063 21942 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-1 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.913136 21942 master.hpp:428] Adding task 1 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-2 (precise)
> I0307 13:43:29.913164 21942 master.cpp:2506] Launching task 1 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-2 (precise)
> I0307 13:43:29.913321 21942 slave.cpp:666] Got assigned task 1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.913513 21942 slave.cpp:775] Launching task 1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.914716 21942 slave.cpp:885] Queuing task '1' for executor default of framework '2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.914752 21942 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):947; disk(*):25116; ports(*):[31000-32000] unused on slave 2014-03-07-13:43:29-16842879-60532-21918-2
> I0307 13:43:29.914819 21942 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-2 for 5secs
> I0307 13:43:29.914916 21942 mesos_containerizer.cpp:351] Starting container 'b56d41b5-4b0f-4032-bee3-b22b7a99c943' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000'
> I0307 13:43:29.915176 21942 slave.cpp:366] Successfully attached file '/tmp/mesos-RoIrFs/2/slaves/2014-03-07-13:43:29-16842879-60532-21918-2/frameworks/2014-03-07-13:43:29-16842879-60532-21918-0000/executors/default/runs/b56d41b5-4b0f-4032-bee3-b22b7a99c943'
> I0307 13:43:29.915318 21942 mesos_containerizer.cpp:492] Fetching URIs for container 'b56d41b5-4b0f-4032-bee3-b22b7a99c943' using command '/usr/bin/env MESOS_EXECUTOR_URIS= MESOS_WORK_DIRECTORY=/tmp/mesos-RoIrFs/2/slaves/2014-03-07-13:43:29-16842879-60532-21918-2/frameworks/2014-03-07-13:43:29-16842879-60532-21918-0000/executors/default/runs/b56d41b5-4b0f-4032-bee3-b22b7a99c943 MESOS_USER=jenkins MESOS_FRAMEWORKS_HOME= HADOOP_HOME= /var/jenkins/workspace/mesos-ubuntu-12.04-gcc/src/mesos-fetcher'
> I0307 13:43:29.917520 21946 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-2 with resources cpus(*):1; mem(*):979; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:29.917615 21946 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-2 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.917683 21946 master.hpp:428] Adding task 2 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:29.917708 21946 master.cpp:2506] Launching task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:29.917836 21946 slave.cpp:666] Got assigned task 2 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.918081 21946 slave.cpp:775] Launching task 2 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.919262 21946 slave.cpp:885] Queuing task '2' for executor default of framework '2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:29.919316 21946 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):947; disk(*):25116; ports(*):[31000-32000] unused on slave 2014-03-07-13:43:29-16842879-60532-21918-0
> I0307 13:43:29.919378 21946 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-0 for 5secs
> I0307 13:43:29.919481 21946 mesos_containerizer.cpp:351] Starting container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000'
> I0307 13:43:29.919738 21946 slave.cpp:366] Successfully attached file '/tmp/mesos-RoIrFs/1/slaves/2014-03-07-13:43:29-16842879-60532-21918-0/frameworks/2014-03-07-13:43:29-16842879-60532-21918-0000/executors/default/runs/4fb9d3c7-83f9-4941-9222-4bf57e5e7240'
> I0307 13:43:29.919855 21946 mesos_containerizer.cpp:492] Fetching URIs for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' using command '/usr/bin/env MESOS_EXECUTOR_URIS= MESOS_WORK_DIRECTORY=/tmp/mesos-RoIrFs/1/slaves/2014-03-07-13:43:29-16842879-60532-21918-0/frameworks/2014-03-07-13:43:29-16842879-60532-21918-0000/executors/default/runs/4fb9d3c7-83f9-4941-9222-4bf57e5e7240 MESOS_USER=jenkins MESOS_FRAMEWORKS_HOME= HADOOP_HOME= /var/jenkins/workspace/mesos-ubuntu-12.04-gcc/src/mesos-fetcher'
> I0307 13:43:30.864485 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:30.864572 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:30.864661 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:30.864686 21949 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 320092ns
> I0307 13:43:31.865893 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:31.865972 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:31.866003 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:31.866019 21949 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 222503ns
> I0307 13:43:31.914862 21949 launcher.cpp:116] Forked child with pid '22021' for container 'bbe08660-8c2e-455e-8993-2ed9b3fd91ab'
> I0307 13:43:31.915599 21949 launcher.cpp:116] Forked child with pid '22022' for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240'
> I0307 13:43:31.916789 21945 slave.cpp:2026] Monitoring executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000' in container 'bbe08660-8c2e-455e-8993-2ed9b3fd91ab'
> I0307 13:43:31.916847 21945 slave.cpp:2026] Monitoring executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000' in container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240'
> WARNING: Logging before InitGoogleLogging() is written to STDERR
> I0307 13:43:32.542305 22028 process.cpp:1589] libprocess is initialized on 127.0.1.1:60804 for 8 cpus
> I0307 13:43:32.543751 22028 exec.cpp:131] Version: 0.19.0
> WARNING: Logging before InitGoogleLogging() is written to STDERR
> I0307 13:43:32.544981 22025 process.cpp:1589] libprocess is initialized on 127.0.1.1:35335 for 8 cpus
> II0307 13:43:32.547199 22025 exec.cpp:131] Version: 0.19.0
> 0307 13:43:32.546375 22081 exec.cpp:181] Executor started at: executor(1)@127.0.1.1:60804 with pid 22028
> I0307 13:43:32.549568 21945 slave.cpp:1357] Got registration for executor 'default' of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.549877 21945 slave.cpp:1475] Flushing queued task 0 for executor 'default' of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.550649 22082 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.550911 22079 exec.cpp:205] Executor registered on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:32.551892 22072 exec.cpp:181] Executor started at: executor(1)@127.0.1.1:35335 with pid 22025
> Registered executor on precise
> I0307 13:43:32.555806 22079 exec.cpp:217] Executor::registered took 122426ns
> I0307 13:43:32.556275 22079 exec.cpp:292] Executor asked to run task '0'
> Starting task 0
> Finishing task 0
> I0307 13:43:32.556756 22079 exec.cpp:301] Executor::launchTask took 297690ns
> I0307 13:43:32.557715 22079 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.558166 21945 slave.cpp:1684] Handling status update TASK_RUNNING (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:60804
> I0307 13:43:32.558290 21945 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.558310 21945 status_update_manager.cpp:499] Creating StatusUpdate stream for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.558390 21945 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.558557 21945 master.cpp:2069] Status update TASK_RUNNING (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(1)@127.0.1.1:60532
> I0307 13:43:32.558588 21945 slave.cpp:1800] Status update manager successfully handled status update TASK_RUNNING (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.558596 21945 slave.cpp:1806] Sending acknowledgement for status update TASK_RUNNING (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:60804
> Task 0 is in state 1
> I0307 13:43:32.558789 21945 sched.cpp:619] Scheduler::statusUpdate took 13576ns
> I0307 13:43:32.558867 21945 status_update_manager.cpp:398] Received status update acknowledgement (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.558912 21945 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: aae75d09-07b0-49f7-bcb2-a129f758f92c) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.559406 22082 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.560451 22079 exec.cpp:524] Executor sending status update TASK_FINISHED (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.560958 21944 slave.cpp:1684] Handling status update TASK_FINISHED (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:60804
> I0307 13:43:32.560999 21944 slave.cpp:3179] Terminating task 0
> I0307 13:43:32.561136 21944 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.561157 21944 status_update_manager.cpp:373] Forwarding status update TASK_FINISHED (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.561350 21944 master.cpp:2069] Status update TASK_FINISHED (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(1)@127.0.1.1:60532
> I0307 13:43:32.561416 21944 master.hpp:446] Removing task 0 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:32.561475 21944 slave.cpp:1800] Status update manager successfully handled status update TASK_FINISHED (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.561486 21944 slave.cpp:1806] Sending acknowledgement for status update TASK_FINISHED (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:60804
> Task 0 is in state 2
> I0307 13:43:32.561664 21944 sched.cpp:619] Scheduler::statusUpdate took 11043ns
> I0307 13:43:32.561750 21944 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):32 (total allocatable: mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1) on slave 2014-03-07-13:43:29-16842879-60532-21918-1 from framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.561800 21944 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.561821 21944 status_update_manager.cpp:530] Cleaning up status update stream for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.561873 21944 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: 48a701a7-f8bc-464d-8240-8999ddd67e3a) for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.561888 21944 slave.cpp:3202] Completing task 0
> I0307 13:43:32.562728 22082 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.563266 22079 exec.cpp:338] Executor received status update acknowledgement aae75d09-07b0-49f7-bcb2-a129f758f92c for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.563504 22079 exec.cpp:338] Executor received status update acknowledgement 48a701a7-f8bc-464d-8240-8999ddd67e3a for task 0 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.564061 21943 slave.cpp:1357] Got registration for executor 'default' of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.585032 22070 exec.cpp:205] Executor registered on slave 2014-03-07-13:43:29-16842879-60532-21918-0
> Registered executor on precise
> I0307 13:43:32.585832 22070 exec.cpp:217] Executor::registered took 23184ns
> I0307 13:43:32.586570 22073 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.587048 21943 slave.cpp:1475] Flushing queued task 2 for executor 'default' of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.588516 22070 exec.cpp:292] Executor asked to run task '2'
> Starting task 2
> Finishing task 2
> I0307 13:43:32.588567 22070 exec.cpp:301] Executor::launchTask took 30854ns
> I0307 13:43:32.589480 22070 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.590699 22070 exec.cpp:524] Executor sending status update TASK_FINISHED (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.591698 22073 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.592548 21944 slave.cpp:1684] Handling status update TASK_RUNNING (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:35335
> I0307 13:43:32.592712 21944 slave.cpp:1684] Handling status update TASK_FINISHED (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:35335
> I0307 13:43:32.592735 21944 slave.cpp:3179] Terminating task 2
> I0307 13:43:32.592803 21944 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.592814 21944 status_update_manager.cpp:499] Creating StatusUpdate stream for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.592867 21944 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.593142 21945 master.cpp:2069] Status update TASK_RUNNING (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(2)@127.0.1.1:60532
> Task 2 is in state 1
> I0307 13:43:32.593278 21945 sched.cpp:619] Scheduler::statusUpdate took 10871ns
> I0307 13:43:32.594247 21944 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.594310 21944 status_update_manager.cpp:398] Received status update acknowledgement (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.594346 21944 status_update_manager.cpp:373] Forwarding status update TASK_FINISHED (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.594414 21944 slave.cpp:1800] Status update manager successfully handled status update TASK_RUNNING (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.594426 21944 slave.cpp:1806] Sending acknowledgement for status update TASK_RUNNING (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:35335
> I0307 13:43:32.595336 21945 master.cpp:2069] Status update TASK_FINISHED (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(2)@127.0.1.1:60532
> I0307 13:43:32.595397 21945 master.hpp:446] Removing task 2 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> Task 2 is in state 2
> I0307 13:43:32.595482 21945 sched.cpp:619] Scheduler::statusUpdate took 5433ns
> I0307 13:43:32.595552 21945 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):32 (total allocatable: mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1) on slave 2014-03-07-13:43:29-16842879-60532-21918-0 from framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.596169 21944 slave.cpp:1800] Status update manager successfully handled status update TASK_FINISHED (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.596222 21944 slave.cpp:1806] Sending acknowledgement for status update TASK_FINISHED (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:35335
> I0307 13:43:32.596271 21944 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: e99b686e-d584-4bb2-86c4-0fc17e575d34) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.596552 22069 exec.cpp:338] Executor received status update acknowledgement e99b686e-d584-4bb2-86c4-0fc17e575d34 for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.596786 22069 exec.cpp:338] Executor received status update acknowledgement 6677c7e0-5e4b-4ab8-a992-fb2908e732fa for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.596902 22073 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.597738 21944 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.597805 21944 status_update_manager.cpp:530] Cleaning up status update stream for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.597864 21944 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: 6677c7e0-5e4b-4ab8-a992-fb2908e732fa) for task 2 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.597887 21944 slave.cpp:3202] Completing task 2
> I0307 13:43:32.866572 21949 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.866673 21949 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.866744 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.866835 21949 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 363901ns
> I0307 13:43:32.866897 21949 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-3 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:32.866965 21949 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-4 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:32.866997 21949 master.cpp:2337] Sending 2 offers to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> .Starting task 3 on precise
> Starting task 4 on precise
> I0307 13:43:32.867373 21949 sched.cpp:528] Scheduler::resourceOffers took 170984ns
> I0307 13:43:32.867522 21949 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-3 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:32.867568 21949 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-3 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.867648 21949 master.hpp:428] Adding task 3 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:32.867673 21949 master.cpp:2506] Launching task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:32.867799 21949 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-4 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:32.867842 21949 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-4 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.867890 21949 master.hpp:428] Adding task 4 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:32.867913 21949 master.cpp:2506] Launching task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:32.868003 21949 slave.cpp:666] Got assigned task 3 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.868083 21949 slave.cpp:775] Launching task 3 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.868126 21949 slave.cpp:907] Sending task '3' to executor 'default' of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.868376 21947 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):947; disk(*):25116; ports(*):[31000-32000] unused on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:32.868451 21947 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-1 for 5secs
> I0307 13:43:32.868486 21947 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):947; disk(*):25116; ports(*):[31000-32000] unused on slave 2014-03-07-13:43:29-16842879-60532-21918-0
> I0307 13:43:32.868525 21947 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-0 for 5secs
> I0307 13:43:32.868561 21947 slave.cpp:666] Got assigned task 4 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.868706 21947 slave.cpp:775] Launching task 4 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.868752 21947 slave.cpp:907] Sending task '4' to executor 'default' of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.873594 22075 exec.cpp:292] Executor asked to run task '3'
> Starting task 3
> Finishing task 3
> I0307 13:43:32.873649 22075 exec.cpp:301] Executor::launchTask took 26368ns
> I0307 13:43:32.874452 22075 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.875371 22075 exec.cpp:524] Executor sending status update TASK_FINISHED (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.876338 22082 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.877425 22069 exec.cpp:292] Executor asked to run task '4'
> Starting task 4
> Finishing task 4
> I0307 13:43:32.877475 22069 exec.cpp:301] Executor::launchTask took 24567ns
> I0307 13:43:32.878286 22069 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.879092 22069 exec.cpp:524] Executor sending status update TASK_FINISHED (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.879320 21944 slave.cpp:1684] Handling status update TASK_RUNNING (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:60804
> I0307 13:43:32.879422 21944 slave.cpp:1684] Handling status update TASK_FINISHED (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:60804
> I0307 13:43:32.879441 21944 slave.cpp:3179] Terminating task 3
> I0307 13:43:32.879508 21944 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.879521 21944 status_update_manager.cpp:499] Creating StatusUpdate stream for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.879565 21944 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.879645 21944 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.879811 21944 master.cpp:2069] Status update TASK_RUNNING (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(1)@127.0.1.1:60532
> I0307 13:43:32.879835 21944 slave.cpp:1800] Status update manager successfully handled status update TASK_RUNNING (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.879843 21944 slave.cpp:1806] Sending acknowledgement for status update TASK_RUNNING (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:60804
> Task 3 is in state 1
> I0307 13:43:32.884765 21943 sched.cpp:619] Scheduler::statusUpdate took 15205ns
> I0307 13:43:32.885383 22073 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.885918 21944 slave.cpp:1800] Status update manager successfully handled status update TASK_FINISHED (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.886288 21944 slave.cpp:1806] Sending acknowledgement for status update TASK_FINISHED (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:60804
> I0307 13:43:32.886813 21943 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.886847 21943 status_update_manager.cpp:373] Forwarding status update TASK_FINISHED (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.886955 21943 master.cpp:2069] Status update TASK_FINISHED (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(1)@127.0.1.1:60532
> I0307 13:43:32.886999 21943 master.hpp:446] Removing task 3 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:32.887051 21943 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: 3eb68821-6112-452b-afa3-0a2e0f3c7254) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> Task 3 is in state 2
> I0307 13:43:32.887100 21943 sched.cpp:619] Scheduler::statusUpdate took 5133ns
> I0307 13:43:32.887171 21943 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):32 (total allocatable: mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1) on slave 2014-03-07-13:43:29-16842879-60532-21918-1 from framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.887217 21943 status_update_manager.cpp:398] Received status update acknowledgement (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.887233 21943 status_update_manager.cpp:530] Cleaning up status update stream for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.887333 21943 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: c96f1964-5f12-442d-a3e7-9f8ca2310236) for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.887351 21943 slave.cpp:3202] Completing task 3
> I0307 13:43:32.887562 22080 exec.cpp:338] Executor received status update acknowledgement 3eb68821-6112-452b-afa3-0a2e0f3c7254 for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.887847 22078 exec.cpp:338] Executor received status update acknowledgement c96f1964-5f12-442d-a3e7-9f8ca2310236 for task 3 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.887933 22082 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.888696 21943 slave.cpp:1684] Handling status update TASK_RUNNING (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:35335
> I0307 13:43:32.888788 21943 slave.cpp:1684] Handling status update TASK_FINISHED (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from executor(1)@127.0.1.1:35335
> I0307 13:43:32.888806 21943 slave.cpp:3179] Terminating task 4
> I0307 13:43:32.888875 21943 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.888887 21943 status_update_manager.cpp:499] Creating StatusUpdate stream for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.888936 21943 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.889005 21943 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.889160 21943 master.cpp:2069] Status update TASK_RUNNING (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(2)@127.0.1.1:60532
> I0307 13:43:32.889183 21943 slave.cpp:1800] Status update manager successfully handled status update TASK_RUNNING (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.889191 21943 slave.cpp:1806] Sending acknowledgement for status update TASK_RUNNING (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:35335
> I0307 13:43:32.890157 22068 exec.cpp:338] Executor received status update acknowledgement 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> Task 4 is in state 1
> I0307 13:43:32.890708 21948 sched.cpp:619] Scheduler::statusUpdate took 10505ns
> I0307 13:43:32.890795 22073 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.891230 21943 slave.cpp:1800] Status update manager successfully handled status update TASK_FINISHED (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.891265 21943 slave.cpp:1806] Sending acknowledgement for status update TASK_FINISHED (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to executor(1)@127.0.1.1:35335
> I0307 13:43:32.891953 22067 exec.cpp:338] Executor received status update acknowledgement 9e9a0554-ac84-4026-8915-18b99e8b30bd for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.892429 22073 process.cpp:1008] Socket closed while receiving
> I0307 13:43:32.892992 21947 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.893026 21947 status_update_manager.cpp:373] Forwarding status update TASK_FINISHED (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 to master@127.0.1.1:60532
> I0307 13:43:32.893194 21947 master.cpp:2069] Status update TASK_FINISHED (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000 from slave(2)@127.0.1.1:60532
> I0307 13:43:32.893240 21947 master.hpp:446] Removing task 4 with resources cpus(*):1; mem(*):32 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> Task 4 is in state 2
> I0307 13:43:32.893322 21947 sched.cpp:619] Scheduler::statusUpdate took 5940ns
> I0307 13:43:32.893393 21947 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):32 (total allocatable: mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1) on slave 2014-03-07-13:43:29-16842879-60532-21918-0 from framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.894081 21948 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.894109 21948 status_update_manager.cpp:530] Cleaning up status update stream for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.894587 21943 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: 58eaf1b0-2eb7-44ab-a3b8-21d31e293d1f) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.894618 21943 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: 9e9a0554-ac84-4026-8915-18b99e8b30bd) for task 4 of framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:32.894626 21943 slave.cpp:3202] Completing task 4
> I0307 13:43:32.919563 21946 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:33.867382 21949 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:33.867522 21949 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:33.867585 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:33.867672 21949 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 466940ns
> I0307 13:43:33.867761 21949 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-5 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:33.867831 21949 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-6 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:33.867884 21949 master.cpp:2337] Sending 2 offers to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> .I0307 13:43:33.868115 21949 sched.cpp:528] Scheduler::resourceOffers took 41845ns
> I0307 13:43:33.868234 21949 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-5 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:33.868279 21949 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-5 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:33.868371 21949 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-6 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:33.868412 21949 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-6 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:33.868463 21949 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:33.868530 21949 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-1 for 5secs
> I0307 13:43:33.868580 21949 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-0
> I0307 13:43:33.868688 21949 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-0 for 5secs
> I0307 13:43:33.919862 21946 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:34.865213 21943 master.cpp:98] No whitelist given. Advertising offers for all slaves
> I0307 13:43:34.868551 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:34.868664 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:34.868705 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):947; disk(*):25116; ports(*):[31000-32000] on slave 2014-03-07-13:43:29-16842879-60532-21918-2 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:34.868722 21943 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 237076ns
> I0307 13:43:34.920094 21943 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:35.869546 21946 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:35.869648 21946 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:35.869688 21946 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 260094ns
> I0307 13:43:35.921162 21946 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:36.870599 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:36.870681 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:36.870714 21949 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 197379ns
> I0307 13:43:36.922159 21949 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:37.870842 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:37.870923 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:37.870957 21943 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 191347ns
> I0307 13:43:37.923425 21943 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:38.872197 21946 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:38.872334 21946 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:38.872534 21946 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 475304ns
> I0307 13:43:38.872709 21946 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-7 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:38.872831 21946 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-8 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:38.872897 21946 master.cpp:2337] Sending 2 offers to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> .I0307 13:43:38.873129 21946 sched.cpp:528] Scheduler::resourceOffers took 43914ns
> I0307 13:43:38.873270 21946 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-7 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:38.873343 21946 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-7 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:38.873468 21946 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-8 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:38.873536 21946 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-8 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:38.873612 21946 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:38.873721 21946 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-1 for 5secs
> I0307 13:43:38.873777 21946 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-0
> I0307 13:43:38.873858 21946 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-0 for 5secs
> I0307 13:43:38.923643 21944 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:39.865833 21949 master.cpp:98] No whitelist given. Advertising offers for all slaves
> I0307 13:43:39.873127 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:39.873195 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:39.873229 21949 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 180488ns
> I0307 13:43:39.924733 21949 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:40.874783 21947 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:40.874892 21947 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:40.874953 21947 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 320296ns
> I0307 13:43:40.925325 21947 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:41.875764 21944 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:41.875848 21944 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:41.875882 21944 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 249962ns
> I0307 13:43:41.926408 21944 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:42.876796 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:42.876896 21949 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:42.876943 21949 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 376512ns
> I0307 13:43:42.926779 21949 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:43.877285 21943 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:43.877383 21943 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:43.877501 21943 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 283042ns
> I0307 13:43:43.877564 21943 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-9 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:43.877631 21943 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-10 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:43.877665 21943 master.cpp:2337] Sending 2 offers to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> .I0307 13:43:43.877828 21943 sched.cpp:528] Scheduler::resourceOffers took 28367ns
> I0307 13:43:43.877919 21943 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-9 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:43.877964 21943 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-9 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:43.878051 21943 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-10 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:43.878093 21943 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-10 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:43.878137 21943 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:43.878200 21943 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-1 for 5secs
> I0307 13:43:43.878229 21943 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-0
> I0307 13:43:43.878273 21943 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-0 for 5secs
> I0307 13:43:43.928088 21948 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:44.866242 21943 master.cpp:98] No whitelist given. Advertising offers for all slaves
> I0307 13:43:44.878597 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:44.878664 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:44.878695 21943 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 167045ns
> I0307 13:43:44.929072 21943 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:45.879477 21948 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:45.879564 21948 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:45.879598 21948 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 282321ns
> I0307 13:43:45.930009 21948 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:46.880770 21946 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:46.880851 21946 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:46.880885 21946 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 206439ns
> I0307 13:43:46.931316 21946 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:47.881608 21948 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:47.881692 21948 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:47.881727 21948 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 250382ns
> I0307 13:43:47.932119 21948 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:48.882923 21947 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:48.883025 21947 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:48.883146 21947 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 449763ns
> I0307 13:43:48.883219 21947 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-11 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:48.883303 21947 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-12 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:48.883342 21947 master.cpp:2337] Sending 2 offers to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> .I0307 13:43:48.883527 21947 sched.cpp:528] Scheduler::resourceOffers took 30408ns
> I0307 13:43:48.883637 21947 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-11 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:48.883684 21947 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-11 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:48.883806 21947 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-12 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:48.883879 21947 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-12 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:48.884068 21947 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:48.884186 21947 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-1 for 5secs
> I0307 13:43:48.884240 21947 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-0
> I0307 13:43:48.884321 21947 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-0 for 5secs
> I0307 13:43:48.933380 21944 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:49.866598 21943 master.cpp:98] No whitelist given. Advertising offers for all slaves
> I0307 13:43:49.883929 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:49.884001 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:49.884034 21943 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 181971ns
> I0307 13:43:49.934356 21943 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:50.884732 21944 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:50.884819 21944 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:50.884853 21944 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 313649ns
> I0307 13:43:50.935344 21944 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:51.885814 21947 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:51.885896 21947 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:51.885931 21947 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 195986ns
> I0307 13:43:51.936517 21947 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:52.887006 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:52.887089 21943 hierarchical_allocator_process.hpp:835] Filtered mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:52.887123 21943 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 239097ns
> I0307 13:43:52.937705 21943 monitor.cpp:140] Failed to collect resource usage for container '4fb9d3c7-83f9-4941-9222-4bf57e5e7240' for executor 'default' of framework '2014-03-07-13:43:29-16842879-60532-21918-0000': Unknown container: 4fb9d3c7-83f9-4941-9222-4bf57e5e7240
> I0307 13:43:53.887375 21944 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:53.887516 21944 hierarchical_allocator_process.hpp:752] Offering mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:53.887658 21944 hierarchical_allocator_process.hpp:688] Performed allocation for 3 slaves in 439604ns
> I0307 13:43:53.887723 21944 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-13 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:53.887800 21944 master.hpp:456] Adding offer 2014-03-07-13:43:29-16842879-60532-21918-14 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:53.887888 21944 master.cpp:2337] Sending 2 offers to framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> .I0307 13:43:53.888113 21944 sched.cpp:528] Scheduler::resourceOffers took 42710ns
> I0307 13:43:53.888211 21944 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-13 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise)
> I0307 13:43:53.888257 21944 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-13 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-1 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:53.888332 21944 master.hpp:466] Removing offer 2014-03-07-13:43:29-16842879-60532-21918-14 with resources mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise)
> I0307 13:43:53.888371 21944 master.cpp:1611] Processing reply for offers: [ 2014-03-07-13:43:29-16842879-60532-21918-14 ] on slave 2014-03-07-13:43:29-16842879-60532-21918-0 (precise) for framework 2014-03-07-13:43:29-16842879-60532-21918-0000
> I0307 13:43:53.888424 21944 hierarchical_allocator_process.hpp:547] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 left mem(*):979; disk(*):25116; ports(*):[31000-32000]; cpus(*):1 unused on slave 2014-03-07-13:43:29-16842879-60532-21918-1
> I0307 13:43:53.888514 21944 hierarchical_allocator_process.hpp:590] Framework 2014-03-07-13:43:29-16842879-60532-21918-0000 filtered slave 2014-03-07-13:43:29-16842879-60532-21918-1 for 5secs
> {code}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)