See <https://builds.apache.org/job/mesos-reviewbot/15671/changes>
Changes: [vinodkone] Added utils method to check if `ExecutorInfo` different. [vinodkone] Filled missing executor info in tasks when `LAUNCH_GROUP`. [vinodkone] Exposed the executor's type in the endpoints. [vinodkone] Fixed the wrong sandbox directory of the tasks in Web UI. [vinodkone] Moved the `decimalFloat` filter to app.js for consistency. ------------------------------------------ [...truncated 27954 lines...] I1014 20:13:07.918138 31418 authenticatee.cpp:121] Creating new client SASL connection I1014 20:13:07.919251 31425 master.cpp:6663] Authenticating scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 I1014 20:13:07.919778 31418 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(2)@172.17.0.2:44518 I1014 20:13:07.921108 31425 authenticator.cpp:98] Creating new server SASL connection I1014 20:13:07.921798 31420 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1014 20:13:07.921891 31420 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1014 20:13:07.922262 31420 authenticator.cpp:204] Received SASL authentication start I1014 20:13:07.922463 31420 authenticator.cpp:326] Authentication requires more steps I1014 20:13:07.922777 31420 authenticatee.cpp:259] Received SASL authentication step I1014 20:13:07.923033 31420 authenticator.cpp:232] Received SASL authentication step I1014 20:13:07.923125 31420 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6b3d34307def' server FQDN: '6b3d34307def' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1014 20:13:07.923177 31420 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1014 20:13:07.923426 31420 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1014 20:13:07.923521 31420 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '6b3d34307def' server FQDN: '6b3d34307def' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1014 20:13:07.923563 31420 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1014 20:13:07.923591 31420 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1014 20:13:07.923630 31420 authenticator.cpp:318] Authentication success I1014 20:13:07.924413 31420 authenticatee.cpp:299] Authentication success I1014 20:13:07.924679 31420 master.cpp:6693] Successfully authenticated principal 'test-principal' at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 I1014 20:13:07.925015 31423 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(2)@172.17.0.2:44518 I1014 20:13:07.925750 31421 sched.cpp:502] Successfully authenticated with master master@172.17.0.2:44518 I1014 20:13:07.925845 31421 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:44518 I1014 20:13:07.926209 31421 sched.cpp:853] Will retry registration in 1.976701234secs if necessary I1014 20:13:07.926964 31425 master.cpp:2587] Received SUBSCRIBE call for framework 'Test Framework (Java)' at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 I1014 20:13:07.927309 31425 master.cpp:2049] Authorizing framework principal 'test-principal' to receive offers for role '*' I1014 20:13:07.929759 31425 master.cpp:2663] Subscribing framework Test Framework (Java) with checkpointing enabled and capabilities [ ] I1014 20:13:07.932500 31420 sched.cpp:743] Framework registered with bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:07.934727 31419 hierarchical.cpp:275] Added framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:07.941439 31419 hierarchical.cpp:1789] No inverse offers to send out! I1014 20:13:07.941746 31419 hierarchical.cpp:1286] Performed allocation for 3 agents in 6.93437ms I1014 20:13:07.944602 31423 master.cpp:6492] Sending 3 offers to framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 Registered! ID = bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:07.959259 31420 sched.cpp:757] Scheduler::registered took 26.635678ms Received offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O0 with cpus: 2.0 and mem: 10240.0 Launching task 0 using offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O0 Launching task 1 using offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O0 Received offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O1 with cpus: 2.0 and mem: 10240.0 Launching task 2 using offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O1 Launching task 3 using offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O1 Received offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O2 with cpus: 2.0 and mem: 10240.0 Launching task 4 using offer bcef9e32-6588-4518-ba00-0c0470a83c9f-O2 I1014 20:13:08.194571 31420 sched.cpp:917] Scheduler::resourceOffers took 233.461831ms I1014 20:13:08.200425 31423 master.cpp:3538] Processing ACCEPT call for offers: [ bcef9e32-6588-4518-ba00-0c0470a83c9f-O0 ] on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 at slave(3)@172.17.0.2:44518 (6b3d34307def) for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 I1014 20:13:08.200901 31423 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task 0 I1014 20:13:08.201958 31423 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task 1 I1014 20:13:08.207465 31423 master.cpp:3538] Processing ACCEPT call for offers: [ bcef9e32-6588-4518-ba00-0c0470a83c9f-O1 ] on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 at slave(2)@172.17.0.2:44518 (6b3d34307def) for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 I1014 20:13:08.207764 31423 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task 2 I1014 20:13:08.208467 31423 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task 3 I1014 20:13:08.211447 31423 master.cpp:3538] Processing ACCEPT call for offers: [ bcef9e32-6588-4518-ba00-0c0470a83c9f-O2 ] on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 at slave(1)@172.17.0.2:44518 (6b3d34307def) for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 I1014 20:13:08.213115 31423 master.cpp:3143] Authorizing framework principal 'test-principal' to launch task 4 W1014 20:13:08.217895 31423 validation.cpp:920] Executor 'default' for task '0' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1014 20:13:08.218013 31423 validation.cpp:932] Executor 'default' for task '0' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1014 20:13:08.219537 31423 master.cpp:8245] Adding task 0 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 (6b3d34307def) I1014 20:13:08.220001 31423 master.cpp:4176] Launching task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 at slave(3)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:08.221328 31422 slave.cpp:1539] Got assigned task '0' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.222096 31422 slave.cpp:6093] Checkpointing FrameworkInfo to '/tmp/mesos-ZQnce4/2/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/framework.info' W1014 20:13:08.222918 31423 validation.cpp:920] Executor 'default' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1014 20:13:08.222998 31423 validation.cpp:932] Executor 'default' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1014 20:13:08.223198 31422 slave.cpp:6104] Checkpointing framework pid 'scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518' to '/tmp/mesos-ZQnce4/2/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/framework.pid' I1014 20:13:08.223870 31423 master.cpp:8245] Adding task 1 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 (6b3d34307def) I1014 20:13:08.224298 31423 master.cpp:4176] Launching task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 at slave(3)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:08.226066 31422 slave.cpp:1539] Got assigned task '1' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.227190 31422 slave.cpp:1701] Launching task '0' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 W1014 20:13:08.227658 31423 validation.cpp:920] Executor 'default' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1014 20:13:08.227828 31423 validation.cpp:932] Executor 'default' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1014 20:13:08.228688 31425 hierarchical.cpp:1018] Recovered ports(*):[31000-32000]; mem(*):9984; disk(*):196368 (total: cpus(*):2; mem(*):10240; disk(*):196368; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):256) on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 from framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.228695 31423 master.cpp:8245] Adding task 2 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 (6b3d34307def) I1014 20:13:08.228888 31425 hierarchical.cpp:1055] Framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 filtered agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 for 1secs I1014 20:13:08.229146 31422 paths.cpp:536] Trying to chown '/tmp/mesos-ZQnce4/2/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726' to user 'mesos' I1014 20:13:08.229126 31423 master.cpp:4176] Launching task 2 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 at slave(2)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:08.234429 31425 slave.cpp:1539] Got assigned task '2' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 W1014 20:13:08.234961 31423 validation.cpp:920] Executor 'default' for task '3' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1014 20:13:08.235054 31423 validation.cpp:932] Executor 'default' for task '3' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1014 20:13:08.235589 31425 slave.cpp:6093] Checkpointing FrameworkInfo to '/tmp/mesos-ZQnce4/1/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/framework.info' I1014 20:13:08.235939 31423 master.cpp:8245] Adding task 3 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 (6b3d34307def) I1014 20:13:08.236338 31423 master.cpp:4176] Launching task 3 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 at slave(2)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:08.237167 31425 slave.cpp:6104] Checkpointing framework pid 'scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518' to '/tmp/mesos-ZQnce4/1/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/framework.pid' W1014 20:13:08.239625 31423 validation.cpp:920] Executor 'default' for task '4' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1014 20:13:08.239797 31423 validation.cpp:932] Executor 'default' for task '4' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1014 20:13:08.240602 31423 master.cpp:8245] Adding task 4 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 (6b3d34307def) I1014 20:13:08.240932 31423 master.cpp:4176] Launching task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 with resources cpus(*):1; mem(*):128 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 at slave(1)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:08.242493 31424 slave.cpp:1539] Got assigned task '4' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.242825 31420 hierarchical.cpp:1018] Recovered ports(*):[31000-32000]; mem(*):9984; disk(*):196368 (total: cpus(*):2; mem(*):10240; disk(*):196368; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):256) on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 from framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.242991 31420 hierarchical.cpp:1055] Framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 filtered agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 for 1secs I1014 20:13:08.243621 31424 slave.cpp:6093] Checkpointing FrameworkInfo to '/tmp/mesos-ZQnce4/0/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/framework.info' I1014 20:13:08.245265 31424 slave.cpp:6104] Checkpointing framework pid 'scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518' to '/tmp/mesos-ZQnce4/0/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/framework.pid' I1014 20:13:08.248124 31425 slave.cpp:1539] Got assigned task '3' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.248272 31420 hierarchical.cpp:1018] Recovered cpus(*):1; mem(*):10112; disk(*):196368; ports(*):[31000-32000] (total: cpus(*):2; mem(*):10240; disk(*):196368; ports(*):[31000-32000], allocated: cpus(*):1; mem(*):128) on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 from framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.248337 31424 slave.cpp:1701] Launching task '4' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.248423 31420 hierarchical.cpp:1055] Framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 filtered agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 for 1secs I1014 20:13:08.249608 31425 slave.cpp:1701] Launching task '2' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.252192 31422 slave.cpp:6572] Checkpointing ExecutorInfo to '/tmp/mesos-ZQnce4/2/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/executor.info' I1014 20:13:08.253211 31424 paths.cpp:536] Trying to chown '/tmp/mesos-ZQnce4/0/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/b0612706-bb63-4053-80a1-0f38082b2d96' to user 'mesos' I1014 20:13:08.256539 31422 slave.cpp:6179] Launching executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 with resources {} in work directory '/tmp/mesos-ZQnce4/2/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726' I1014 20:13:08.261414 31419 containerizer.cpp:938] Starting container 861e05a2-25ff-4333-935e-0d9a7fe47726 for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.262344 31425 paths.cpp:536] Trying to chown '/tmp/mesos-ZQnce4/1/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d' to user 'mesos' I1014 20:13:08.265887 31424 slave.cpp:6572] Checkpointing ExecutorInfo to '/tmp/mesos-ZQnce4/0/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/executor.info' I1014 20:13:08.268296 31422 slave.cpp:6595] Checkpointing TaskInfo to '/tmp/mesos-ZQnce4/2/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726/tasks/0/task.info' I1014 20:13:08.268349 31424 slave.cpp:6179] Launching executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 with resources {} in work directory '/tmp/mesos-ZQnce4/0/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/b0612706-bb63-4053-80a1-0f38082b2d96' I1014 20:13:08.270478 31418 containerizer.cpp:938] Starting container b0612706-bb63-4053-80a1-0f38082b2d96 for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.270828 31424 slave.cpp:6595] Checkpointing TaskInfo to '/tmp/mesos-ZQnce4/0/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/b0612706-bb63-4053-80a1-0f38082b2d96/tasks/4/task.info' I1014 20:13:08.272614 31424 slave.cpp:1987] Queued task '4' for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.272843 31424 slave.cpp:868] Successfully attached file '/tmp/mesos-ZQnce4/0/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/b0612706-bb63-4053-80a1-0f38082b2d96' I1014 20:13:08.276408 31425 slave.cpp:6572] Checkpointing ExecutorInfo to '/tmp/mesos-ZQnce4/1/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/executor.info' I1014 20:13:08.276712 31422 slave.cpp:1987] Queued task '0' for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.276948 31422 slave.cpp:1701] Launching task '1' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.277386 31422 slave.cpp:6595] Checkpointing TaskInfo to '/tmp/mesos-ZQnce4/2/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726/tasks/1/task.info' I1014 20:13:08.278522 31422 slave.cpp:1987] Queued task '1' for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.278671 31422 slave.cpp:868] Successfully attached file '/tmp/mesos-ZQnce4/2/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726' I1014 20:13:08.284019 31425 slave.cpp:6179] Launching executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 with resources {} in work directory '/tmp/mesos-ZQnce4/1/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d' I1014 20:13:08.285418 31424 containerizer.cpp:938] Starting container 32e0a924-5ce7-4a16-ad53-85e3e0135d3d for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.286005 31425 slave.cpp:6595] Checkpointing TaskInfo to '/tmp/mesos-ZQnce4/1/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d/tasks/2/task.info' I1014 20:13:08.287413 31425 slave.cpp:1987] Queued task '2' for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.287022 31423 containerizer.cpp:1452] Launching 'mesos-containerizer' with flags '--command="{"shell":true,"value":"\/mesos\/mesos-1.1.0\/_build\/src\/examples\/java\/test-executor"}" --environment="{"LIBPROCESS_PORT":"0","MESOS_AGENT_ENDPOINT":"172.17.0.2:44518","MESOS_CHECKPOINT":"1","MESOS_DIRECTORY":"\/tmp\/mesos-ZQnce4\/0\/slaves\/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1\/frameworks\/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000\/executors\/default\/runs\/b0612706-bb63-4053-80a1-0f38082b2d96","MESOS_EXECUTOR_ID":"default","MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD":"5secs","MESOS_FRAMEWORK_ID":"bcef9e32-6588-4518-ba00-0c0470a83c9f-0000","MESOS_HTTP_COMMAND_EXECUTOR":"0","MESOS_RECOVERY_TIMEOUT":"15mins","MESOS_SANDBOX":"\/tmp\/mesos-ZQnce4\/0\/slaves\/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1\/frameworks\/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000\/executors\/default\/runs\/b0612706-bb63-4053-80a1-0f38082b2d96","MESOS_SLAVE_ID":"bcef9e32-6588-4518-ba00-0c0470a83c9f-S1","MESOS_SLAVE_PID":"slave(1)@172.17.0.2:44518","MESOS_SUBSCRIPTION_BACKOFF_MAX":"2secs","PATH":"\/usr\/local\/sbin:\/usr\/local\/bin:\/usr\/sbin:\/usr\/bin:\/sbin:\/bin"}" --help="false" --pipe_read="19" --pipe_write="22" --pre_exec_commands="[]" --runtime_directory="/tmp/mesos-lH8obZ/containers/b0612706-bb63-4053-80a1-0f38082b2d96" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/mesos-ZQnce4/0/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/b0612706-bb63-4053-80a1-0f38082b2d96"' I1014 20:13:08.288015 31425 slave.cpp:1701] Launching task '3' for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.288765 31425 slave.cpp:6595] Checkpointing TaskInfo to '/tmp/mesos-ZQnce4/1/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d/tasks/3/task.info' I1014 20:13:08.293195 31420 containerizer.cpp:1452] Launching 'mesos-containerizer' with flags '--command="{"shell":true,"value":"\/mesos\/mesos-1.1.0\/_build\/src\/examples\/java\/test-executor"}" --environment="{"LIBPROCESS_PORT":"0","MESOS_AGENT_ENDPOINT":"172.17.0.2:44518","MESOS_CHECKPOINT":"1","MESOS_DIRECTORY":"\/tmp\/mesos-ZQnce4\/2\/slaves\/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0\/frameworks\/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000\/executors\/default\/runs\/861e05a2-25ff-4333-935e-0d9a7fe47726","MESOS_EXECUTOR_ID":"default","MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD":"5secs","MESOS_FRAMEWORK_ID":"bcef9e32-6588-4518-ba00-0c0470a83c9f-0000","MESOS_HTTP_COMMAND_EXECUTOR":"0","MESOS_RECOVERY_TIMEOUT":"15mins","MESOS_SANDBOX":"\/tmp\/mesos-ZQnce4\/2\/slaves\/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0\/frameworks\/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000\/executors\/default\/runs\/861e05a2-25ff-4333-935e-0d9a7fe47726","MESOS_SLAVE_ID":"bcef9e32-6588-4518-ba00-0c0470a83c9f-S0","MESOS_SLAVE_PID":"slave(3)@172.17.0.2:44518","MESOS_SUBSCRIPTION_BACKOFF_MAX":"2secs","PATH":"\/usr\/local\/sbin:\/usr\/local\/bin:\/usr\/sbin:\/usr\/bin:\/sbin:\/bin"}" --help="false" --pipe_read="26" --pipe_write="27" --pre_exec_commands="[]" --runtime_directory="/tmp/mesos-lH8obZ/containers/861e05a2-25ff-4333-935e-0d9a7fe47726" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/mesos-ZQnce4/2/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726"' I1014 20:13:08.295028 31425 slave.cpp:1987] Queued task '3' for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.304561 31420 launcher.cpp:127] Forked child with pid '31435' for container '861e05a2-25ff-4333-935e-0d9a7fe47726' I1014 20:13:08.307114 31425 slave.cpp:868] Successfully attached file '/tmp/mesos-ZQnce4/1/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d' I1014 20:13:08.307600 31420 containerizer.cpp:1489] Checkpointing container's forked pid 31435 to '/tmp/mesos-ZQnce4/2/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726/pids/forked.pid' I1014 20:13:08.305544 31423 launcher.cpp:127] Forked child with pid '31434' for container 'b0612706-bb63-4053-80a1-0f38082b2d96' I1014 20:13:08.309550 31423 containerizer.cpp:1489] Checkpointing container's forked pid 31434 to '/tmp/mesos-ZQnce4/0/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/b0612706-bb63-4053-80a1-0f38082b2d96/pids/forked.pid' I1014 20:13:08.325842 31422 containerizer.cpp:1452] Launching 'mesos-containerizer' with flags '--command="{"shell":true,"value":"\/mesos\/mesos-1.1.0\/_build\/src\/examples\/java\/test-executor"}" --environment="{"LIBPROCESS_PORT":"0","MESOS_AGENT_ENDPOINT":"172.17.0.2:44518","MESOS_CHECKPOINT":"1","MESOS_DIRECTORY":"\/tmp\/mesos-ZQnce4\/1\/slaves\/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2\/frameworks\/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000\/executors\/default\/runs\/32e0a924-5ce7-4a16-ad53-85e3e0135d3d","MESOS_EXECUTOR_ID":"default","MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD":"5secs","MESOS_FRAMEWORK_ID":"bcef9e32-6588-4518-ba00-0c0470a83c9f-0000","MESOS_HTTP_COMMAND_EXECUTOR":"0","MESOS_RECOVERY_TIMEOUT":"15mins","MESOS_SANDBOX":"\/tmp\/mesos-ZQnce4\/1\/slaves\/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2\/frameworks\/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000\/executors\/default\/runs\/32e0a924-5ce7-4a16-ad53-85e3e0135d3d","MESOS_SLAVE_ID":"bcef9e32-6588-4518-ba00-0c0470a83c9f-S2","MESOS_SLAVE_PID":"slave(2)@172.17.0.2:44518","MESOS_SUBSCRIPTION_BACKOFF_MAX":"2secs","PATH":"\/usr\/local\/sbin:\/usr\/local\/bin:\/usr\/sbin:\/usr\/bin:\/sbin:\/bin"}" --help="false" --pipe_read="23" --pipe_write="24" --pre_exec_commands="[]" --runtime_directory="/tmp/mesos-lH8obZ/containers/32e0a924-5ce7-4a16-ad53-85e3e0135d3d" --unshare_namespace_mnt="false" --user="mesos" --working_directory="/tmp/mesos-ZQnce4/1/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d"' I1014 20:13:08.336190 31422 launcher.cpp:127] Forked child with pid '31441' for container '32e0a924-5ce7-4a16-ad53-85e3e0135d3d' I1014 20:13:08.343333 31422 containerizer.cpp:1489] Checkpointing container's forked pid 31441 to '/tmp/mesos-ZQnce4/1/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d/pids/forked.pid' I1014 20:13:08.650027 31424 hierarchical.cpp:1880] Filtered offer with ports(*):[31000-32000]; mem(*):9984; disk(*):196368 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.651850 31424 hierarchical.cpp:1880] Filtered offer with cpus(*):1; mem(*):10112; disk(*):196368; ports(*):[31000-32000] on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.653257 31424 hierarchical.cpp:1880] Filtered offer with ports(*):[31000-32000]; mem(*):9984; disk(*):196368 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 for framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:08.653370 31424 hierarchical.cpp:1694] No allocations performed I1014 20:13:08.654680 31424 hierarchical.cpp:1789] No inverse offers to send out! I1014 20:13:08.655405 31424 hierarchical.cpp:1286] Performed allocation for 3 agents in 7.066219ms I1014 20:13:09.005870 31482 exec.cpp:162] Version: 1.1.0 I1014 20:13:09.020959 31425 slave.cpp:3231] Got registration for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:34194 I1014 20:13:09.023051 31425 slave.cpp:3317] Checkpointing executor pid 'executor(1)@172.17.0.2:34194' to '/tmp/mesos-ZQnce4/0/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S1/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/b0612706-bb63-4053-80a1-0f38082b2d96/pids/libprocess.pid' I1014 20:13:09.034654 31421 slave.cpp:2191] Sending queued task '4' to executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 at executor(1)@172.17.0.2:34194 I1014 20:13:09.038964 31480 exec.cpp:162] Version: 1.1.0 I1014 20:13:09.039593 31524 exec.cpp:237] Executor registered on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 I1014 20:13:09.073042 31419 slave.cpp:3231] Got registration for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:53110 I1014 20:13:09.074198 31419 slave.cpp:3317] Checkpointing executor pid 'executor(1)@172.17.0.2:53110' to '/tmp/mesos-ZQnce4/2/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S0/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/861e05a2-25ff-4333-935e-0d9a7fe47726/pids/libprocess.pid' I1014 20:13:09.083042 31423 slave.cpp:2191] Sending queued task '0' to executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 at executor(1)@172.17.0.2:53110 I1014 20:13:09.083573 31535 exec.cpp:237] Executor registered on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 I1014 20:13:09.088433 31423 slave.cpp:2191] Sending queued task '1' to executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 at executor(1)@172.17.0.2:53110 I1014 20:13:09.142307 31492 exec.cpp:162] Version: 1.1.0 I1014 20:13:09.176944 31419 slave.cpp:3231] Got registration for executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:44304 I1014 20:13:09.178589 31419 slave.cpp:3317] Checkpointing executor pid 'executor(1)@172.17.0.2:44304' to '/tmp/mesos-ZQnce4/1/meta/slaves/bcef9e32-6588-4518-ba00-0c0470a83c9f-S2/frameworks/bcef9e32-6588-4518-ba00-0c0470a83c9f-0000/executors/default/runs/32e0a924-5ce7-4a16-ad53-85e3e0135d3d/pids/libprocess.pid' I1014 20:13:09.195515 31541 exec.cpp:237] Executor registered on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S2 Registered executor on 6b3d34307def I1014 20:13:09.198254 31422 slave.cpp:2191] Sending queued task '2' to executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 at executor(1)@172.17.0.2:44304 I1014 20:13:09.200126 31422 slave.cpp:2191] Sending queued task '3' to executor 'default' of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 at executor(1)@172.17.0.2:44304 Running task 4 I1014 20:13:09.229703 31419 slave.cpp:3634] Handling status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:34194 I1014 20:13:09.236588 31419 slave.cpp:3634] Handling status update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:34194 I1014 20:13:09.243520 31425 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.244817 31425 status_update_manager.cpp:500] Creating StatusUpdate stream for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.246436 31425 status_update_manager.cpp:832] Checkpointing UPDATE for status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.247802 31425 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to the agent I1014 20:13:09.248273 31420 slave.cpp:4051] Forwarding the update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to master@172.17.0.2:44518 I1014 20:13:09.248471 31420 slave.cpp:3945] Status update manager successfully handled status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.248533 31420 slave.cpp:3961] Sending acknowledgement for status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to executor(1)@172.17.0.2:34194 I1014 20:13:09.248623 31425 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.248765 31425 status_update_manager.cpp:832] Checkpointing UPDATE for status update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.249083 31420 master.cpp:5701] Status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 at slave(1)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:09.250452 31420 master.cpp:5763] Forwarding status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.250733 31420 master.cpp:7623] Updating the state of task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING) I1014 20:13:09.251013 31420 slave.cpp:3945] Status update manager successfully handled status update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.251060 31420 slave.cpp:3961] Sending acknowledgement for status update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to executor(1)@172.17.0.2:34194 I1014 20:13:09.251682 31420 hierarchical.cpp:1018] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):196368; ports(*):[31000-32000], allocated: {}) on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 from framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 Registered executor on 6b3d34307def Running task 1 Running task 0 I1014 20:13:09.336665 31420 slave.cpp:3634] Handling status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:53110 I1014 20:13:09.338513 31420 slave.cpp:3634] Handling status update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:53110 I1014 20:13:09.339932 31420 slave.cpp:3634] Handling status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:53110 I1014 20:13:09.341433 31420 slave.cpp:3634] Handling status update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from executor(1)@172.17.0.2:53110 I1014 20:13:09.343746 31424 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.343917 31424 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.344944 31424 status_update_manager.cpp:832] Checkpointing UPDATE for status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.345458 31424 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to the agent I1014 20:13:09.346035 31424 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.346110 31424 status_update_manager.cpp:500] Creating StatusUpdate stream for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.346813 31424 status_update_manager.cpp:832] Checkpointing UPDATE for status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.346833 31420 slave.cpp:4051] Forwarding the update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to master@172.17.0.2:44518 I1014 20:13:09.347359 31424 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to the agent I1014 20:13:09.347407 31420 slave.cpp:3945] Status update manager successfully handled status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.347612 31420 slave.cpp:3961] Sending acknowledgement for status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to executor(1)@172.17.0.2:53110 I1014 20:13:09.348126 31421 master.cpp:5701] Status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 at slave(3)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:09.350023 31421 master.cpp:5763] Forwarding status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.350049 31420 slave.cpp:4051] Forwarding the update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to master@172.17.0.2:44518 I1014 20:13:09.350389 31421 master.cpp:7623] Updating the state of task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING) I1014 20:13:09.352203 31423 hierarchical.cpp:1018] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):196368; ports(*):[31000-32000], allocated: cpus(*):1; mem(*):128) on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 from framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.352432 31421 master.cpp:5701] Status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 at slave(3)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:09.352630 31421 master.cpp:5763] Forwarding status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.352948 31421 master.cpp:7623] Updating the state of task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING) I1014 20:13:09.353646 31423 hierarchical.cpp:1018] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):10240; disk(*):196368; ports(*):[31000-32000], allocated: {}) on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 from framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.353819 31424 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.354012 31424 status_update_manager.cpp:832] Checkpointing UPDATE for status update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.353688 31420 slave.cpp:3945] Status update manager successfully handled status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.354445 31420 slave.cpp:3961] Sending acknowledgement for status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to executor(1)@172.17.0.2:53110 I1014 20:13:09.354590 31424 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.355494 31424 status_update_manager.cpp:832] Checkpointing UPDATE for status update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.354743 31420 slave.cpp:3945] Status update manager successfully handled status update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.356101 31420 slave.cpp:3961] Sending acknowledgement for status update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to executor(1)@172.17.0.2:53110 I1014 20:13:09.357131 31420 slave.cpp:3945] Status update manager successfully handled status update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.357199 31420 slave.cpp:3961] Sending acknowledgement for status update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to executor(1)@172.17.0.2:53110 Registered executor on 6b3d34307def Status update: task 4 is in state TASK_RUNNING I1014 20:13:09.426534 31425 sched.cpp:1025] Scheduler::statusUpdate took 175.334938ms I1014 20:13:09.427512 31418 master.cpp:4813] Processing ACKNOWLEDGE call fd30b1c8-a356-4e8c-9abf-af62026c3612 for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 Status update: task 1 is in state TASK_RUNNING I1014 20:13:09.428386 31425 sched.cpp:1025] Scheduler::statusUpdate took 861094ns I1014 20:13:09.428624 31418 status_update_manager.cpp:395] Received status update acknowledgement (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.428853 31418 status_update_manager.cpp:832] Checkpointing ACK for status update TASK_RUNNING (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.429119 31418 status_update_manager.cpp:377] Forwarding update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to the agent I1014 20:13:09.429625 31418 slave.cpp:4051] Forwarding the update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to master@172.17.0.2:44518 I1014 20:13:09.429832 31418 slave.cpp:2958] Status update manager successfully handled status update acknowledgement (UUID: fd30b1c8-a356-4e8c-9abf-af62026c3612) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.430035 31419 master.cpp:5701] Status update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S1 at slave(1)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:09.430174 31419 master.cpp:5763] Forwarding status update TASK_FINISHED (UUID: 3609e40d-0003-4265-9288-000a33efce9e) for task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.430363 31419 master.cpp:7623] Updating the state of task 4 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED) I1014 20:13:09.434604 31422 master.cpp:4813] Processing ACKNOWLEDGE call c91c1c05-5d40-46a3-9dee-507ee2b062f5 for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 I1014 20:13:09.435014 31422 status_update_manager.cpp:395] Received status update acknowledgement (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.435312 31422 status_update_manager.cpp:832] Checkpointing ACK for status update TASK_RUNNING (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.435689 31422 status_update_manager.cpp:377] Forwarding update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to the agent Status update: task 0 is in state TASK_RUNNING I1014 20:13:09.436313 31425 sched.cpp:1025] Scheduler::statusUpdate took 1.723721ms I1014 20:13:09.436319 31422 slave.cpp:4051] Forwarding the update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to master@172.17.0.2:44518 I1014 20:13:09.436679 31422 slave.cpp:2958] Status update manager successfully handled status update acknowledgement (UUID: c91c1c05-5d40-46a3-9dee-507ee2b062f5) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.437012 31422 master.cpp:5701] Status update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 at slave(3)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:09.437069 31422 master.cpp:5763] Forwarding status update TASK_FINISHED (UUID: 1abd2813-eab5-425c-9953-bb635cf5bd0d) for task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 Running task 3 I1014 20:13:09.437520 31422 master.cpp:7623] Updating the state of task 1 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED) I1014 20:13:09.437762 31422 master.cpp:4813] Processing ACKNOWLEDGE call 97aeccea-00de-46c1-beab-087e76da3659 for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (Test Framework (Java)) at scheduler-d74fcab5-3bdb-467b-9e52-1c218f7caaf5@172.17.0.2:44518 on agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 I1014 20:13:09.438084 31422 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.438226 31422 status_update_manager.cpp:832] Checkpointing ACK for status update TASK_RUNNING (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.438591 31422 status_update_manager.cpp:377] Forwarding update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to the agent I1014 20:13:09.438921 31422 slave.cpp:4051] Forwarding the update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 to master@172.17.0.2:44518 I1014 20:13:09.439313 31422 slave.cpp:2958] Status update manager successfully handled status update acknowledgement (UUID: 97aeccea-00de-46c1-beab-087e76da3659) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.439607 31422 master.cpp:5701] Status update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 from agent bcef9e32-6588-4518-ba00-0c0470a83c9f-S0 at slave(3)@172.17.0.2:44518 (6b3d34307def) I1014 20:13:09.439682 31422 master.cpp:5763] Forwarding status update TASK_FINISHED (UUID: b5d2b54f-17d8-4e60-a4bd-0e6f373d384b) for task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 I1014 20:13:09.440063 31422 master.cpp:7623] Updating the state of task 0 of framework bcef9e32-6588-4518-ba00-0c0470a83c9f-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED) Running task 2 Status update: task 4 is in state TASK_FINISHED Finished tasks: 1 I1014 20:13:09.442315 31425 sched.cpp:1025] Scheduler::statusUpdate took 5.15117ms Status update: task 1 is in state TASK_FINISHED <I1014 20:13Error deleting container: Error response from daemon: Conflict, You cannot remove a running container. Stop the container before attempting removal or use -f Unrecognized input header + docker rmi mesos-1476473236-29646 Failed to remove image (mesos-1476473236-29646): Error response from daemon: conflict: unable to remove repository reference "mesos-1476473236-29646" (must force) - container 6b3d34307def is using its referenced image 71196004c247 Build step 'Execute shell' marked build as failure