[MESOS-5008] ProvisionerDockerPullerTest.ROOT_LocalPullerSimpleCommand can lead to segfault. - ASF JIRA

XML

Word

Printable

JSON

Details

Type: Bug
Status: Resolved
Priority: Major
Resolution: Fixed
Affects Version/s: 1.0.0
Fix Version/s: None
Component/s: containerization
Labels:
- flaky-test

Description

[19:58:51] :	 [Step 11/11] [ RUN      ] ProvisionerDockerPullerTest.ROOT_LocalPullerSimpleCommand
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.850121  2767 cluster.cpp:139] Creating default 'local' authorizer
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.856395  2767 leveldb.cpp:174] Opened db in 6.060593ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.858052  2767 leveldb.cpp:181] Compacted db in 1.632877ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.858093  2767 leveldb.cpp:196] Created db iterator in 15978ns
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.858106  2767 leveldb.cpp:202] Seeked to beginning of db in 1501ns
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.858114  2767 leveldb.cpp:271] Iterated through 0 keys in the db in 403ns
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.858146  2767 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.858558  2788 recover.cpp:447] Starting replica recovery
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.858798  2788 recover.cpp:473] Replica is in EMPTY status
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.859633  2784 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15242)@172.30.2.225:33126
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.860041  2788 recover.cpp:193] Received a recover response from a replica in EMPTY status
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.860553  2783 recover.cpp:564] Updating replica status to STARTING
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.861685  2784 master.cpp:376] Master f874517a-7b69-44e3-a0c9-d093bebfe0dc (ip-172-30-2-225.mesosphere.io) started on 172.30.2.225:33126
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.861702  2784 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="true" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/RaTZmW/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/RaTZmW/master" --zk_session_timeout="10secs"
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.861923  2784 master.cpp:423] Master only allowing authenticated frameworks to register
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.861933  2784 master.cpp:428] Master only allowing authenticated slaves to register
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.861939  2784 credentials.hpp:35] Loading credentials for authentication from '/tmp/RaTZmW/credentials'
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862218  2784 master.cpp:468] Using default 'crammd5' authenticator
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862380  2784 master.cpp:537] Using default 'basic' HTTP authenticator
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862478  2781 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 1.644385ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862506  2781 replica.cpp:320] Persisted replica status to STARTING
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862524  2784 master.cpp:571] Authorization enabled
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862689  2781 hierarchical.cpp:144] Initialized hierarchical allocator process
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862704  2787 recover.cpp:473] Replica is in STARTING status
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.862728  2782 whitelist_watcher.cpp:77] No whitelist given
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.863550  2786 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15244)@172.30.2.225:33126
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.863860  2787 recover.cpp:193] Received a recover response from a replica in STARTING status
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.864265  2781 recover.cpp:564] Updating replica status to VOTING
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.864765  2787 master.cpp:1806] The newly elected leader is master@172.30.2.225:33126 with id f874517a-7b69-44e3-a0c9-d093bebfe0dc
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.864789  2787 master.cpp:1819] Elected as the leading master!
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.864802  2787 master.cpp:1508] Recovering from registrar
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.864907  2785 registrar.cpp:307] Recovering registrar
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.865766  2786 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 1.333552ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.865787  2786 replica.cpp:320] Persisted replica status to VOTING
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.865881  2781 recover.cpp:578] Successfully joined the Paxos group
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.866052  2781 recover.cpp:462] Recover process terminated
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.866407  2783 log.cpp:659] Attempting to start the writer
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.867410  2781 replica.cpp:493] Replica received implicit promise request from (15245)@172.30.2.225:33126 with proposal 1
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.868778  2781 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 1.343239ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.868801  2781 replica.cpp:342] Persisted promised to 1
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.869365  2785 coordinator.cpp:238] Coordinator attempting to fill missing positions
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.870409  2784 replica.cpp:388] Replica received explicit promise request from (15246)@172.30.2.225:33126 for position 0 with proposal 2
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.871788  2784 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 1.346579ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.871811  2784 replica.cpp:712] Persisted action at 0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.872735  2786 replica.cpp:537] Replica received write request for position 0 from (15247)@172.30.2.225:33126
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.872781  2786 leveldb.cpp:436] Reading position from leveldb took 20995ns
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.874184  2786 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 1.373888ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.874208  2786 replica.cpp:712] Persisted action at 0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.874728  2785 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.876116  2785 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.363635ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.876138  2785 replica.cpp:712] Persisted action at 0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.876152  2785 replica.cpp:697] Replica learned NOP action at position 0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.876626  2787 log.cpp:675] Writer started with ending position 0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.877534  2781 leveldb.cpp:436] Reading position from leveldb took 24119ns
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.878324  2784 registrar.cpp:340] Successfully fetched the registry (0B) in 13376us
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.878409  2784 registrar.cpp:439] Applied 1 operations in 21321ns; attempting to update the 'registry'
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.878988  2787 log.cpp:683] Attempting to append 210 bytes to the log
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.879086  2784 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.879740  2783 replica.cpp:537] Replica received write request for position 1 from (15248)@172.30.2.225:33126
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.881371  2783 leveldb.cpp:341] Persisting action (229 bytes) to leveldb took 1.600751ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.881395  2783 replica.cpp:712] Persisted action at 1
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.881918  2783 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.883472  2783 leveldb.cpp:341] Persisting action (231 bytes) to leveldb took 1.531355ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.883496  2783 replica.cpp:712] Persisted action at 1
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.883510  2783 replica.cpp:697] Replica learned APPEND action at position 1
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.884271  2788 registrar.cpp:484] Successfully updated the 'registry' in 5.810944ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.884402  2788 registrar.cpp:370] Successfully recovered registrar
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.884465  2782 log.cpp:702] Attempting to truncate the log to 1
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.884579  2783 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.884851  2784 master.cpp:1616] Recovered 0 slaves from the Registry (171B) ; allowing 10mins for slaves to re-register
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.884882  2786 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.885421  2782 replica.cpp:537] Replica received write request for position 2 from (15249)@172.30.2.225:33126
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.886895  2782 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.446625ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.886919  2782 replica.cpp:712] Persisted action at 2
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.887403  2786 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.888756  2786 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.324677ms
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.888808  2786 leveldb.cpp:399] Deleting ~1 keys from leveldb took 27978ns
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.888823  2786 replica.cpp:712] Persisted action at 2
[19:58:51]W:	 [Step 11/11] I0322 19:58:51.888839  2786 replica.cpp:697] Replica learned TRUNCATE action at position 2
[19:58:52]W:	 [Step 11/11] I0322 19:58:52.863459  2787 hierarchical.cpp:1457] No resources available to allocate!
[19:58:52]W:	 [Step 11/11] I0322 19:58:52.863514  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 163278ns
[19:58:53]W:	 [Step 11/11] I0322 19:58:53.864653  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:58:53]W:	 [Step 11/11] I0322 19:58:53.864702  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 137816ns
[19:58:54]W:	 [Step 11/11] I0322 19:58:54.865679  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:58:54]W:	 [Step 11/11] I0322 19:58:54.865727  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 158278ns
[19:58:55]W:	 [Step 11/11] I0322 19:58:55.866746  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:58:55]W:	 [Step 11/11] I0322 19:58:55.866794  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 118186ns
[19:58:56]W:	 [Step 11/11] I0322 19:58:56.867504  2782 hierarchical.cpp:1457] No resources available to allocate!
[19:58:56]W:	 [Step 11/11] I0322 19:58:56.867552  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 138557ns
[19:58:57]W:	 [Step 11/11] I0322 19:58:57.868799  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:58:57]W:	 [Step 11/11] I0322 19:58:57.868849  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 144555ns
[19:58:58]W:	 [Step 11/11] I0322 19:58:58.869858  2784 hierarchical.cpp:1457] No resources available to allocate!
[19:58:58]W:	 [Step 11/11] I0322 19:58:58.869909  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 141696ns
[19:58:59]W:	 [Step 11/11] I0322 19:58:59.870204  2784 hierarchical.cpp:1457] No resources available to allocate!
[19:58:59]W:	 [Step 11/11] I0322 19:58:59.870261  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123811ns
[19:59:00]W:	 [Step 11/11] I0322 19:59:00.871028  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:59:00]W:	 [Step 11/11] I0322 19:59:00.871075  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 126558ns
[19:59:01]W:	 [Step 11/11] I0322 19:59:01.871633  2782 hierarchical.cpp:1457] No resources available to allocate!
[19:59:01]W:	 [Step 11/11] I0322 19:59:01.871680  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 127677ns
[19:59:02]W:	 [Step 11/11] I0322 19:59:02.872849  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:02]W:	 [Step 11/11] I0322 19:59:02.872902  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 161808ns
[19:59:03]W:	 [Step 11/11] I0322 19:59:03.873381  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:03]W:	 [Step 11/11] I0322 19:59:03.873414  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 114848ns
[19:59:04]W:	 [Step 11/11] I0322 19:59:04.874038  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:04]W:	 [Step 11/11] I0322 19:59:04.874099  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 152944ns
[19:59:05]W:	 [Step 11/11] I0322 19:59:05.874698  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:05]W:	 [Step 11/11] I0322 19:59:05.874748  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 134310ns
[19:59:06]W:	 [Step 11/11] I0322 19:59:06.875221  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:06]W:	 [Step 11/11] I0322 19:59:06.875286  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 147159ns
[19:59:07]W:	 [Step 11/11] I0322 19:59:07.875797  2782 hierarchical.cpp:1457] No resources available to allocate!
[19:59:07]W:	 [Step 11/11] I0322 19:59:07.875844  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 134424ns
[19:59:08]W:	 [Step 11/11] I0322 19:59:08.876493  2784 hierarchical.cpp:1457] No resources available to allocate!
[19:59:08]W:	 [Step 11/11] I0322 19:59:08.876541  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 147355ns
[19:59:09]W:	 [Step 11/11] I0322 19:59:09.877219  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:09]W:	 [Step 11/11] I0322 19:59:09.877249  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 90328ns
[19:59:10]W:	 [Step 11/11] I0322 19:59:10.877900  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:10]W:	 [Step 11/11] I0322 19:59:10.877950  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121094ns
[19:59:11]W:	 [Step 11/11] I0322 19:59:11.878623  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:11]W:	 [Step 11/11] I0322 19:59:11.878670  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 120283ns
[19:59:12]W:	 [Step 11/11] I0322 19:59:12.879181  2787 hierarchical.cpp:1457] No resources available to allocate!
[19:59:12]W:	 [Step 11/11] I0322 19:59:12.879245  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 167338ns
[19:59:13]W:	 [Step 11/11] I0322 19:59:13.880336  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:13]W:	 [Step 11/11] I0322 19:59:13.880384  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 126235ns
[19:59:14]W:	 [Step 11/11] I0322 19:59:14.880826  2787 hierarchical.cpp:1457] No resources available to allocate!
[19:59:14]W:	 [Step 11/11] I0322 19:59:14.880872  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 114124ns
[19:59:15]W:	 [Step 11/11] I0322 19:59:15.881377  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:15]W:	 [Step 11/11] I0322 19:59:15.881423  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121409ns
[19:59:16]W:	 [Step 11/11] I0322 19:59:16.882678  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:16]W:	 [Step 11/11] I0322 19:59:16.882728  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123011ns
[19:59:17]W:	 [Step 11/11] I0322 19:59:17.883411  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:17]W:	 [Step 11/11] I0322 19:59:17.883456  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 114739ns
[19:59:18]W:	 [Step 11/11] I0322 19:59:18.884146  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:59:18]W:	 [Step 11/11] I0322 19:59:18.884194  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 145815ns
[19:59:19]W:	 [Step 11/11] I0322 19:59:19.884563  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:19]W:	 [Step 11/11] I0322 19:59:19.884601  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 89341ns
[19:59:20]W:	 [Step 11/11] I0322 19:59:20.885823  2784 hierarchical.cpp:1457] No resources available to allocate!
[19:59:20]W:	 [Step 11/11] I0322 19:59:20.885866  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 111882ns
[19:59:21]W:	 [Step 11/11] I0322 19:59:21.886473  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:21]W:	 [Step 11/11] I0322 19:59:21.886520  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 108596ns
[19:59:22]W:	 [Step 11/11] I0322 19:59:22.887181  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:22]W:	 [Step 11/11] I0322 19:59:22.887217  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 81211ns
[19:59:23]W:	 [Step 11/11] I0322 19:59:23.887881  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:23]W:	 [Step 11/11] I0322 19:59:23.887925  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 113041ns
[19:59:24]W:	 [Step 11/11] I0322 19:59:24.888442  2787 hierarchical.cpp:1457] No resources available to allocate!
[19:59:24]W:	 [Step 11/11] I0322 19:59:24.888487  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 111021ns
[19:59:25]W:	 [Step 11/11] I0322 19:59:25.889019  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:25]W:	 [Step 11/11] I0322 19:59:25.889065  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 119138ns
[19:59:26]W:	 [Step 11/11] I0322 19:59:26.889520  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:26]W:	 [Step 11/11] I0322 19:59:26.889561  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 100246ns
[19:59:27]W:	 [Step 11/11] I0322 19:59:27.890631  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:27]W:	 [Step 11/11] I0322 19:59:27.890677  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 131507ns
[19:59:28]W:	 [Step 11/11] I0322 19:59:28.891499  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:28]W:	 [Step 11/11] I0322 19:59:28.891546  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 116712ns
[19:59:29]W:	 [Step 11/11] I0322 19:59:29.892084  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:59:29]W:	 [Step 11/11] I0322 19:59:29.892122  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 95442ns
[19:59:30]W:	 [Step 11/11] I0322 19:59:30.893273  2787 hierarchical.cpp:1457] No resources available to allocate!
[19:59:30]W:	 [Step 11/11] I0322 19:59:30.893322  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 135457ns
[19:59:31]W:	 [Step 11/11] I0322 19:59:31.894413  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:31]W:	 [Step 11/11] I0322 19:59:31.894462  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 127605ns
[19:59:32]W:	 [Step 11/11] I0322 19:59:32.895412  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:59:32]W:	 [Step 11/11] I0322 19:59:32.895452  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 100140ns
[19:59:33]W:	 [Step 11/11] I0322 19:59:33.895978  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:33]W:	 [Step 11/11] I0322 19:59:33.896026  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 122533ns
[19:59:34]W:	 [Step 11/11] I0322 19:59:34.897312  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:34]W:	 [Step 11/11] I0322 19:59:34.897367  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123119ns
[19:59:35]W:	 [Step 11/11] I0322 19:59:35.898039  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:35]W:	 [Step 11/11] I0322 19:59:35.898090  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121914ns
[19:59:36]W:	 [Step 11/11] I0322 19:59:36.898586  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:59:36]W:	 [Step 11/11] I0322 19:59:36.898632  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 117986ns
[19:59:37]W:	 [Step 11/11] I0322 19:59:37.899231  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:37]W:	 [Step 11/11] I0322 19:59:37.899293  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 140875ns
[19:59:38]W:	 [Step 11/11] I0322 19:59:38.899708  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:38]W:	 [Step 11/11] I0322 19:59:38.899740  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 72487ns
[19:59:39]W:	 [Step 11/11] I0322 19:59:39.900449  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:39]W:	 [Step 11/11] I0322 19:59:39.900496  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 122990ns
[19:59:40]W:	 [Step 11/11] I0322 19:59:40.900884  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:40]W:	 [Step 11/11] I0322 19:59:40.900933  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 109313ns
[19:59:41]W:	 [Step 11/11] I0322 19:59:41.901378  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:41]W:	 [Step 11/11] I0322 19:59:41.901414  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 92022ns
[19:59:42]W:	 [Step 11/11] I0322 19:59:42.902048  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:42]W:	 [Step 11/11] I0322 19:59:42.902096  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121779ns
[19:59:43]W:	 [Step 11/11] I0322 19:59:43.902655  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:59:43]W:	 [Step 11/11] I0322 19:59:43.902701  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 118852ns
[19:59:44]W:	 [Step 11/11] I0322 19:59:44.903089  2785 hierarchical.cpp:1457] No resources available to allocate!
[19:59:44]W:	 [Step 11/11] I0322 19:59:44.903111  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 52436ns
[19:59:45]W:	 [Step 11/11] I0322 19:59:45.903758  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:45]W:	 [Step 11/11] I0322 19:59:45.903798  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 96195ns
[19:59:46]W:	 [Step 11/11] I0322 19:59:46.904268  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:46]W:	 [Step 11/11] I0322 19:59:46.904311  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 104504ns
[19:59:47]W:	 [Step 11/11] I0322 19:59:47.905170  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:47]W:	 [Step 11/11] I0322 19:59:47.905216  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 116392ns
[19:59:48]W:	 [Step 11/11] I0322 19:59:48.906414  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:48]W:	 [Step 11/11] I0322 19:59:48.906461  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121081ns
[19:59:49]W:	 [Step 11/11] I0322 19:59:49.907204  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:49]W:	 [Step 11/11] I0322 19:59:49.907245  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 98866ns
[19:59:50]W:	 [Step 11/11] I0322 19:59:50.907735  2782 hierarchical.cpp:1457] No resources available to allocate!
[19:59:50]W:	 [Step 11/11] I0322 19:59:50.907783  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 124681ns
[19:59:51]W:	 [Step 11/11] I0322 19:59:51.908295  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:51]W:	 [Step 11/11] I0322 19:59:51.908341  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 111929ns
[19:59:52]W:	 [Step 11/11] I0322 19:59:52.908776  2787 hierarchical.cpp:1457] No resources available to allocate!
[19:59:52]W:	 [Step 11/11] I0322 19:59:52.908814  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 94408ns
[19:59:53]W:	 [Step 11/11] I0322 19:59:53.909277  2787 hierarchical.cpp:1457] No resources available to allocate!
[19:59:53]W:	 [Step 11/11] I0322 19:59:53.909319  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 101173ns
[19:59:54]W:	 [Step 11/11] I0322 19:59:54.909783  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:54]W:	 [Step 11/11] I0322 19:59:54.909826  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 100899ns
[19:59:55]W:	 [Step 11/11] I0322 19:59:55.910339  2783 hierarchical.cpp:1457] No resources available to allocate!
[19:59:55]W:	 [Step 11/11] I0322 19:59:55.910384  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 112172ns
[19:59:56]W:	 [Step 11/11] I0322 19:59:56.910887  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:56]W:	 [Step 11/11] I0322 19:59:56.910929  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 98568ns
[19:59:57]W:	 [Step 11/11] I0322 19:59:57.911479  2786 hierarchical.cpp:1457] No resources available to allocate!
[19:59:57]W:	 [Step 11/11] I0322 19:59:57.911525  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 112905ns
[19:59:58]W:	 [Step 11/11] I0322 19:59:58.912099  2788 hierarchical.cpp:1457] No resources available to allocate!
[19:59:58]W:	 [Step 11/11] I0322 19:59:58.912144  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 114823ns
[19:59:59]W:	 [Step 11/11] I0322 19:59:59.912693  2781 hierarchical.cpp:1457] No resources available to allocate!
[19:59:59]W:	 [Step 11/11] I0322 19:59:59.912740  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123449ns
[20:00:00]W:	 [Step 11/11] I0322 20:00:00.913215  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:00]W:	 [Step 11/11] I0322 20:00:00.913275  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 125409ns
[20:00:01]W:	 [Step 11/11] I0322 20:00:01.914398  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:01]W:	 [Step 11/11] I0322 20:00:01.914449  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123844ns
[20:00:02]W:	 [Step 11/11] I0322 20:00:02.914793  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:00:02]W:	 [Step 11/11] I0322 20:00:02.914841  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121138ns
[20:00:03]W:	 [Step 11/11] I0322 20:00:03.915388  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:03]W:	 [Step 11/11] I0322 20:00:03.915417  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 66571ns
[20:00:04]W:	 [Step 11/11] I0322 20:00:04.915879  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:04]W:	 [Step 11/11] I0322 20:00:04.915915  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 86969ns
[20:00:05]W:	 [Step 11/11] I0322 20:00:05.916411  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:05]W:	 [Step 11/11] I0322 20:00:05.916452  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 97306ns
[20:00:06]W:	 [Step 11/11] I0322 20:00:06.917824  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:06]W:	 [Step 11/11] I0322 20:00:06.917873  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121968ns
[20:00:07]W:	 [Step 11/11] I0322 20:00:07.918473  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:07]W:	 [Step 11/11] I0322 20:00:07.918522  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 126144ns
[20:00:08]W:	 [Step 11/11] I0322 20:00:08.918980  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:08]W:	 [Step 11/11] I0322 20:00:08.919018  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 88778ns
[20:00:09]W:	 [Step 11/11] I0322 20:00:09.919556  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:00:09]W:	 [Step 11/11] I0322 20:00:09.919603  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 116555ns
[20:00:10]W:	 [Step 11/11] I0322 20:00:10.920080  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:10]W:	 [Step 11/11] I0322 20:00:10.920121  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 104443ns
[20:00:11]W:	 [Step 11/11] I0322 20:00:11.920625  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:11]W:	 [Step 11/11] I0322 20:00:11.920670  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 108204ns
[20:00:12]W:	 [Step 11/11] I0322 20:00:12.921661  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:12]W:	 [Step 11/11] I0322 20:00:12.921710  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 132809ns
[20:00:13]W:	 [Step 11/11] I0322 20:00:13.922555  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:00:13]W:	 [Step 11/11] I0322 20:00:13.922605  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123090ns
[20:00:14]W:	 [Step 11/11] I0322 20:00:14.923146  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:14]W:	 [Step 11/11] I0322 20:00:14.923194  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121895ns
[20:00:15]W:	 [Step 11/11] I0322 20:00:15.923939  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:15]W:	 [Step 11/11] I0322 20:00:15.923987  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123225ns
[20:00:16]W:	 [Step 11/11] I0322 20:00:16.924515  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:16]W:	 [Step 11/11] I0322 20:00:16.924558  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 109028ns
[20:00:17]W:	 [Step 11/11] I0322 20:00:17.925086  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:17]W:	 [Step 11/11] I0322 20:00:17.925133  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 119148ns
[20:00:18]W:	 [Step 11/11] I0322 20:00:18.925716  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:18]W:	 [Step 11/11] I0322 20:00:18.925766  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123144ns
[20:00:19]W:	 [Step 11/11] I0322 20:00:19.926331  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:19]W:	 [Step 11/11] I0322 20:00:19.926379  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 124530ns
[20:00:20]W:	 [Step 11/11] I0322 20:00:20.927175  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:20]W:	 [Step 11/11] I0322 20:00:20.927220  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 118232ns
[20:00:21]W:	 [Step 11/11] I0322 20:00:21.927902  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:00:21]W:	 [Step 11/11] I0322 20:00:21.927952  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 125945ns
[20:00:22]W:	 [Step 11/11] I0322 20:00:22.928534  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:00:22]W:	 [Step 11/11] I0322 20:00:22.928586  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 124079ns
[20:00:23]W:	 [Step 11/11] I0322 20:00:23.928995  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:00:23]W:	 [Step 11/11] I0322 20:00:23.929036  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 106485ns
[20:00:24]W:	 [Step 11/11] I0322 20:00:24.929539  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:24]W:	 [Step 11/11] I0322 20:00:24.929586  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 119721ns
[20:00:25]W:	 [Step 11/11] I0322 20:00:25.929960  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:25]W:	 [Step 11/11] I0322 20:00:25.930007  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 114555ns
[20:00:26]W:	 [Step 11/11] I0322 20:00:26.930924  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:00:26]W:	 [Step 11/11] I0322 20:00:26.930973  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 125435ns
[20:00:27]W:	 [Step 11/11] I0322 20:00:27.931761  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:00:27]W:	 [Step 11/11] I0322 20:00:27.931807  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 119598ns
[20:00:28]W:	 [Step 11/11] I0322 20:00:28.932253  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:28]W:	 [Step 11/11] I0322 20:00:28.932313  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 131899ns
[20:00:29]W:	 [Step 11/11] I0322 20:00:29.932718  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:29]W:	 [Step 11/11] I0322 20:00:29.932762  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 109902ns
[20:00:30]W:	 [Step 11/11] I0322 20:00:30.933121  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:30]W:	 [Step 11/11] I0322 20:00:30.933169  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123123ns
[20:00:31]W:	 [Step 11/11] I0322 20:00:31.933693  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:31]W:	 [Step 11/11] I0322 20:00:31.933724  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 68933ns
[20:00:32]W:	 [Step 11/11] I0322 20:00:32.934062  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:00:32]W:	 [Step 11/11] I0322 20:00:32.934109  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 122722ns
[20:00:33]W:	 [Step 11/11] I0322 20:00:33.935338  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:33]W:	 [Step 11/11] I0322 20:00:33.935380  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 108868ns
[20:00:34]W:	 [Step 11/11] I0322 20:00:34.935900  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:00:34]W:	 [Step 11/11] I0322 20:00:34.935950  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 122693ns
[20:00:35]W:	 [Step 11/11] I0322 20:00:35.936364  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:35]W:	 [Step 11/11] I0322 20:00:35.936406  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 99877ns
[20:00:36]W:	 [Step 11/11] I0322 20:00:36.936897  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:36]W:	 [Step 11/11] I0322 20:00:36.936944  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 116599ns
[20:00:37]W:	 [Step 11/11] I0322 20:00:37.937278  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:37]W:	 [Step 11/11] I0322 20:00:37.937341  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 138516ns
[20:00:38]W:	 [Step 11/11] I0322 20:00:38.937870  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:38]W:	 [Step 11/11] I0322 20:00:38.937917  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 114952ns
[20:00:39]W:	 [Step 11/11] I0322 20:00:39.938390  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:00:39]W:	 [Step 11/11] I0322 20:00:39.938436  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 119035ns
[20:00:40]W:	 [Step 11/11] I0322 20:00:40.939692  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:40]W:	 [Step 11/11] I0322 20:00:40.939740  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 124050ns
[20:00:41]W:	 [Step 11/11] I0322 20:00:41.940508  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:00:41]W:	 [Step 11/11] I0322 20:00:41.940557  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 131337ns
[20:00:42]W:	 [Step 11/11] I0322 20:00:42.940973  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:42]W:	 [Step 11/11] I0322 20:00:42.941022  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121767ns
[20:00:43]W:	 [Step 11/11] I0322 20:00:43.941489  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:43]W:	 [Step 11/11] I0322 20:00:43.941532  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 104017ns
[20:00:44]W:	 [Step 11/11] I0322 20:00:44.942003  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:00:44]W:	 [Step 11/11] I0322 20:00:44.942040  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 89926ns
[20:00:45]W:	 [Step 11/11] I0322 20:00:45.942581  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:45]W:	 [Step 11/11] I0322 20:00:45.942630  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 122128ns
[20:00:46]W:	 [Step 11/11] I0322 20:00:46.943253  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:46]W:	 [Step 11/11] I0322 20:00:46.943317  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 140484ns
[20:00:47]W:	 [Step 11/11] I0322 20:00:47.943882  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:47]W:	 [Step 11/11] I0322 20:00:47.943930  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 128878ns
[20:00:48]W:	 [Step 11/11] I0322 20:00:48.944567  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:00:48]W:	 [Step 11/11] I0322 20:00:48.944617  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 128791ns
[20:00:49]W:	 [Step 11/11] I0322 20:00:49.945063  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:00:49]W:	 [Step 11/11] I0322 20:00:49.945117  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 137240ns
[20:00:50]W:	 [Step 11/11] I0322 20:00:50.945577  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:50]W:	 [Step 11/11] I0322 20:00:50.945626  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 129833ns
[20:00:51]W:	 [Step 11/11] I0322 20:00:51.946058  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:51]W:	 [Step 11/11] I0322 20:00:51.946100  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 89332ns
[20:00:52]W:	 [Step 11/11] I0322 20:00:52.946601  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:00:52]W:	 [Step 11/11] I0322 20:00:52.946651  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121766ns
[20:00:53]W:	 [Step 11/11] I0322 20:00:53.947185  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:00:53]W:	 [Step 11/11] I0322 20:00:53.947245  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 133861ns
[20:00:54]W:	 [Step 11/11] I0322 20:00:54.947670  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:00:54]W:	 [Step 11/11] I0322 20:00:54.947710  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 102236ns
[20:00:55]W:	 [Step 11/11] I0322 20:00:55.948137  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:55]W:	 [Step 11/11] I0322 20:00:55.948174  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 86636ns
[20:00:56]W:	 [Step 11/11] I0322 20:00:56.948719  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:56]W:	 [Step 11/11] I0322 20:00:56.948753  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 70687ns
[20:00:57]W:	 [Step 11/11] I0322 20:00:57.949280  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:00:57]W:	 [Step 11/11] I0322 20:00:57.949343  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 131120ns
[20:00:58]W:	 [Step 11/11] I0322 20:00:58.949852  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:58]W:	 [Step 11/11] I0322 20:00:58.949899  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 120411ns
[20:00:59]W:	 [Step 11/11] I0322 20:00:59.950465  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:00:59]W:	 [Step 11/11] I0322 20:00:59.950515  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 127774ns
[20:01:00]W:	 [Step 11/11] I0322 20:01:00.951196  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:01:00]W:	 [Step 11/11] I0322 20:01:00.951259  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 147939ns
[20:01:01]W:	 [Step 11/11] I0322 20:01:01.952463  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:01:01]W:	 [Step 11/11] I0322 20:01:01.952512  2781 hierarchical.cpp:1134] Performed allocation for 0 slaves in 124992ns
[20:01:02]W:	 [Step 11/11] I0322 20:01:02.953112  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:01:02]W:	 [Step 11/11] I0322 20:01:02.953160  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 122440ns
[20:01:03]W:	 [Step 11/11] I0322 20:01:03.953703  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:03]W:	 [Step 11/11] I0322 20:01:03.953752  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 122521ns
[20:01:04]W:	 [Step 11/11] I0322 20:01:04.954205  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:04]W:	 [Step 11/11] I0322 20:01:04.954237  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 82836ns
[20:01:05]W:	 [Step 11/11] I0322 20:01:05.954936  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:05]W:	 [Step 11/11] I0322 20:01:05.954982  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121399ns
[20:01:06]W:	 [Step 11/11] I0322 20:01:06.955508  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:06]W:	 [Step 11/11] I0322 20:01:06.955549  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 100615ns
[20:01:07]W:	 [Step 11/11] I0322 20:01:07.956116  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:07]W:	 [Step 11/11] I0322 20:01:07.956166  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123637ns
[20:01:08]W:	 [Step 11/11] I0322 20:01:08.957268  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:08]W:	 [Step 11/11] I0322 20:01:08.957334  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 141138ns
[20:01:09]W:	 [Step 11/11] I0322 20:01:09.958022  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:09]W:	 [Step 11/11] I0322 20:01:09.958070  2785 hierarchical.cpp:1134] Performed allocation for 0 slaves in 123666ns
[20:01:10]W:	 [Step 11/11] I0322 20:01:10.958569  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:10]W:	 [Step 11/11] I0322 20:01:10.958609  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 98211ns
[20:01:11]W:	 [Step 11/11] I0322 20:01:11.958930  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:11]W:	 [Step 11/11] I0322 20:01:11.958966  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 89197ns
[20:01:12]W:	 [Step 11/11] I0322 20:01:12.959444  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:12]W:	 [Step 11/11] I0322 20:01:12.959491  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 117044ns
[20:01:13]W:	 [Step 11/11] I0322 20:01:13.960140  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:01:13]W:	 [Step 11/11] I0322 20:01:13.960187  2782 hierarchical.cpp:1134] Performed allocation for 0 slaves in 121424ns
[20:01:14]W:	 [Step 11/11] I0322 20:01:14.960878  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:14]W:	 [Step 11/11] I0322 20:01:14.960928  2788 hierarchical.cpp:1134] Performed allocation for 0 slaves in 124089ns
[20:01:15]W:	 [Step 11/11] I0322 20:01:15.961694  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:15]W:	 [Step 11/11] I0322 20:01:15.961745  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 134336ns
[20:01:16]W:	 [Step 11/11] I0322 20:01:16.962349  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:16]W:	 [Step 11/11] I0322 20:01:16.962402  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 134445ns
[20:01:17]W:	 [Step 11/11] I0322 20:01:17.963125  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:17]W:	 [Step 11/11] I0322 20:01:17.963173  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 131345ns
[20:01:18]W:	 [Step 11/11] I0322 20:01:18.964474  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:18]W:	 [Step 11/11] I0322 20:01:18.964529  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 138170ns
[20:01:19]W:	 [Step 11/11] I0322 20:01:19.965958  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:19]W:	 [Step 11/11] I0322 20:01:19.966007  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 132485ns
[20:01:20]W:	 [Step 11/11] I0322 20:01:20.967330  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:01:20]W:	 [Step 11/11] I0322 20:01:20.967388  2783 hierarchical.cpp:1134] Performed allocation for 0 slaves in 164489ns
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.623937  2767 containerizer.cpp:149] Using isolation: docker/runtime,filesystem/linux
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.631418  2767 linux_launcher.cpp:101] Using /sys/fs/cgroup/freezer as the freezer hierarchy for the Linux launcher
[20:01:21]W:	 [Step 11/11] E0322 20:01:21.636220  2767 shell.hpp:106] Command 'hadoop version 2>&1' failed; this is the output:
[20:01:21]W:	 [Step 11/11] sh: hadoop: command not found
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.636255  2767 fetcher.cpp:59] Skipping URI fetcher plugin 'hadoop' as it could not be created: Failed to create HDFS client: Failed to execute 'hadoop version 2>&1'; the command was either not found or exited with a non-zero exit status: 127
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.636375  2767 local_puller.cpp:90] Creating local puller with docker registry '/tmp/RaTZmW/archives'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.638038  2767 linux.cpp:81] Making '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX' a shared mount
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.646831  2788 slave.cpp:193] Slave started on 432)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.646857  2788 slave.cpp:194] Flags at startup: --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticatee="crammd5" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="/tmp/RaTZmW/archives" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/RaTZmW/store" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --image_providers="docker" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="docker/runtime,filesystem/linux" --launcher_dir="/mnt/teamcity/work/4240ba9ddd0997c3/build/src" --logbufsecs="0" --logging_level="INFO" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX"
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.647238  2788 credentials.hpp:83] Loading credential for authentication from '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/credential'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.647455  2788 slave.cpp:324] Slave using credential for: test-principal
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.647615  2788 resources.cpp:572] Parsing resources as JSON failed: cpus:2;mem:1024;disk:1024;ports:[31000-32000]
[20:01:21]W:	 [Step 11/11] Trying semicolon-delimited string format instead
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.647980  2788 slave.cpp:464] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.648038  2788 slave.cpp:472] Slave attributes: [  ]
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.648048  2788 slave.cpp:477] Slave hostname: ip-172-30-2-225.mesosphere.io
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.648581  2767 sched.cpp:222] Version: 0.29.0
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649108  2781 state.cpp:58] Recovering state from '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/meta'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649164  2784 sched.cpp:326] New master detected at master@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649277  2784 sched.cpp:382] Authenticating with master master@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649299  2784 sched.cpp:389] Using default CRAM-MD5 authenticatee
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649360  2782 status_update_manager.cpp:200] Recovering status update manager
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649591  2782 containerizer.cpp:407] Recovering containerizer
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649626  2783 authenticatee.cpp:121] Creating new client SASL connection
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.649906  2784 master.cpp:5659] Authenticating scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650007  2787 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(890)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650277  2785 authenticator.cpp:98] Creating new server SASL connection
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650573  2781 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650605  2781 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650707  2781 authenticator.cpp:203] Received SASL authentication start
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650749  2781 authenticator.cpp:325] Authentication requires more steps
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650837  2781 authenticatee.cpp:258] Received SASL authentication step
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650969  2781 authenticator.cpp:231] Received SASL authentication step
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.650996  2781 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: 'ip-172-30-2-225.mesosphere.io' server FQDN: 'ip-172-30-2-225.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651005  2781 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651054  2781 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651085  2781 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: 'ip-172-30-2-225.mesosphere.io' server FQDN: 'ip-172-30-2-225.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651095  2781 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651100  2781 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651111  2781 authenticator.cpp:317] Authentication success
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651197  2784 authenticatee.cpp:298] Authentication success
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651235  2783 master.cpp:5689] Successfully authenticated principal 'test-principal' at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651348  2788 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(890)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651499  2785 sched.cpp:471] Successfully authenticated with master master@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651523  2785 sched.cpp:776] Sending SUBSCRIBE call to master@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651625  2785 sched.cpp:809] Will retry registration in 813.035573ms if necessary
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651716  2784 master.cpp:2326] Received SUBSCRIBE call for framework 'default' at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651764  2784 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.651986  2788 master.cpp:2397] Subscribing framework default with checkpointing disabled and capabilities [  ]
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.652346  2784 hierarchical.cpp:265] Added framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.652415  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.652448  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.652467  2787 sched.cpp:703] Framework registered with f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.652470  2784 hierarchical.cpp:1134] Performed allocation for 0 slaves in 105801ns
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.652503  2787 sched.cpp:717] Scheduler::registered took 16738ns
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.652881  2781 metadata_manager.cpp:192] No images to load from disk. Docker provisioner image storage path '/tmp/RaTZmW/store/storedImages' does not exist
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.653259  2784 provisioner.cpp:245] Provisioner recovery complete
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.653596  2781 slave.cpp:4565] Finished recovery
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.653971  2781 slave.cpp:4737] Querying resource estimator for oversubscribable resources
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654146  2781 slave.cpp:796] New master detected at master@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654158  2783 status_update_manager.cpp:174] Pausing sending status updates
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654212  2781 slave.cpp:859] Authenticating with master master@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654242  2781 slave.cpp:864] Using default CRAM-MD5 authenticatee
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654351  2781 slave.cpp:832] Detecting new master
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654386  2788 authenticatee.cpp:121] Creating new client SASL connection
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654477  2781 slave.cpp:4751] Received oversubscribable resources  from the resource estimator
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654655  2786 master.cpp:5659] Authenticating slave(432)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654742  2787 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(891)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.654948  2782 authenticator.cpp:98] Creating new server SASL connection
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655203  2783 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655223  2783 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655315  2783 authenticator.cpp:203] Received SASL authentication start
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655347  2783 authenticator.cpp:325] Authentication requires more steps
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655426  2783 authenticatee.cpp:258] Received SASL authentication step
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655531  2781 authenticator.cpp:231] Received SASL authentication step
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655552  2781 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: 'ip-172-30-2-225.mesosphere.io' server FQDN: 'ip-172-30-2-225.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655560  2781 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655582  2781 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655602  2781 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: 'ip-172-30-2-225.mesosphere.io' server FQDN: 'ip-172-30-2-225.mesosphere.io' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655611  2781 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655616  2781 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655627  2781 authenticator.cpp:317] Authentication success
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655709  2785 authenticatee.cpp:298] Authentication success
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655760  2784 master.cpp:5689] Successfully authenticated principal 'test-principal' at slave(432)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.655815  2783 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(891)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.656024  2786 slave.cpp:927] Successfully authenticated with master master@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.656142  2786 slave.cpp:1321] Will retry registration in 7.172411ms if necessary
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.656322  2788 master.cpp:4370] Registering slave at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) with id f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.656759  2782 registrar.cpp:439] Applied 1 operations in 70254ns; attempting to update the 'registry'
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.657534  2788 log.cpp:683] Attempting to append 396 bytes to the log
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.657639  2781 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.658263  2788 replica.cpp:537] Replica received write request for position 3 from (15270)@172.30.2.225:33126
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.664609  2787 slave.cpp:1321] Will retry registration in 36.166838ms if necessary
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.664696  2786 master.cpp:4358] Ignoring register slave message from slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) as admission is already in progress
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.701930  2785 slave.cpp:1321] Will retry registration in 59.698875ms if necessary
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.702057  2784 master.cpp:4358] Ignoring register slave message from slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) as admission is already in progress
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.762325  2781 slave.cpp:1321] Will retry registration in 142.720607ms if necessary
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.762413  2785 master.cpp:4358] Ignoring register slave message from slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) as admission is already in progress
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.906000  2787 slave.cpp:1321] Will retry registration in 104.301144ms if necessary
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.906108  2783 master.cpp:4358] Ignoring register slave message from slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) as admission is already in progress
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.968173  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.968199  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:21]W:	 [Step 11/11] I0322 20:01:21.968214  2786 hierarchical.cpp:1134] Performed allocation for 0 slaves in 82151ns
[20:01:22]W:	 [Step 11/11] I0322 20:01:22.011605  2782 slave.cpp:1321] Will retry registration in 332.799266ms if necessary
[20:01:22]W:	 [Step 11/11] I0322 20:01:22.011711  2786 master.cpp:4358] Ignoring register slave message from slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) as admission is already in progress
[20:01:22]W:	 [Step 11/11] I0322 20:01:22.345715  2781 slave.cpp:1321] Will retry registration in 1.252306982secs if necessary
[20:01:22]W:	 [Step 11/11] I0322 20:01:22.345827  2783 master.cpp:4358] Ignoring register slave message from slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) as admission is already in progress
[20:01:22]W:	 [Step 11/11] I0322 20:01:22.968796  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:22]W:	 [Step 11/11] I0322 20:01:22.968830  2787 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:22]W:	 [Step 11/11] I0322 20:01:22.968849  2787 hierarchical.cpp:1134] Performed allocation for 0 slaves in 104374ns
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.312163  2788 leveldb.cpp:341] Persisting action (415 bytes) to leveldb took 1.65386842secs
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.312192  2788 replica.cpp:712] Persisted action at 3
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.312896  2785 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.314190  2785 leveldb.cpp:341] Persisting action (417 bytes) to leveldb took 1.263841ms
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.314214  2785 replica.cpp:712] Persisted action at 3
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.314231  2785 replica.cpp:697] Replica learned APPEND action at position 3
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.315577  2785 registrar.cpp:484] Successfully updated the 'registry' in 1.658745088secs
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.315776  2788 log.cpp:702] Attempting to truncate the log to 3
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.315902  2783 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316319  2782 slave.cpp:3482] Received ping from slave-observer(403)@172.30.2.225:33126
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316436  2787 master.cpp:4438] Registered slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316500  2782 slave.cpp:971] Registered with master master@172.30.2.225:33126; given slave ID f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316526  2782 fetcher.cpp:81] Clearing fetcher cache
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316623  2781 hierarchical.cpp:473] Added slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 (ip-172-30-2-225.mesosphere.io) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316676  2783 status_update_manager.cpp:181] Resuming sending status updates
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316674  2784 replica.cpp:537] Replica received write request for position 4 from (15271)@172.30.2.225:33126
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.316864  2782 slave.cpp:994] Checkpointing SlaveInfo to '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/meta/slaves/f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0/slave.info'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317080  2782 slave.cpp:1030] Forwarding total oversubscribed resources 
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317215  2782 master.cpp:4782] Received update of slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) with total oversubscribed resources 
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317373  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317410  2781 hierarchical.cpp:1154] Performed allocation for slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 in 751349ns
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317448  2784 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 719464ns
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317473  2784 replica.cpp:712] Persisted action at 4
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317693  2787 master.cpp:5488] Sending 1 offers to framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317728  2781 hierarchical.cpp:531] Slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 (ip-172-30-2-225.mesosphere.io) updated with oversubscribed resources  (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000])
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317867  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317911  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.317941  2781 hierarchical.cpp:1154] Performed allocation for slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 in 180579ns
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.318068  2788 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.318192  2787 sched.cpp:873] Scheduler::resourceOffers took 179434ns
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.318532  2767 resources.cpp:572] Parsing resources as JSON failed: cpus:1;mem:128
[20:01:23]W:	 [Step 11/11] Trying semicolon-delimited string format instead
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.318853  2788 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 758381ns
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.318927  2788 leveldb.cpp:399] Deleting ~2 keys from leveldb took 50515ns
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.318944  2788 replica.cpp:712] Persisted action at 4
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.318964  2788 replica.cpp:697] Replica learned TRUNCATE action at position 4
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.319846  2787 master.cpp:3268] Processing ACCEPT call for offers: [ f874517a-7b69-44e3-a0c9-d093bebfe0dc-O0 ] on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.319885  2787 master.cpp:2871] Authorizing framework principal 'test-principal' to launch task f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 as user 'root'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.320989  2787 master.hpp:177] Adding task f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 with resources cpus(*):1; mem(*):128 on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 (ip-172-30-2-225.mesosphere.io)
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.321075  2787 master.cpp:3753] Launching task f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126 with resources cpus(*):1; mem(*):128 on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io)
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.321357  2786 slave.cpp:1361] Got assigned task f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.321537  2786 resources.cpp:572] Parsing resources as JSON failed: cpus:0.1;mem:32
[20:01:23]W:	 [Step 11/11] Trying semicolon-delimited string format instead
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.321636  2784 hierarchical.cpp:890] Recovered cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):1; mem(*):128) on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 from framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.321678  2784 hierarchical.cpp:927] Framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 filtered slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 for 5secs
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.321979  2786 slave.cpp:1480] Launching task f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.322052  2786 resources.cpp:572] Parsing resources as JSON failed: cpus:0.1;mem:32
[20:01:23]W:	 [Step 11/11] Trying semicolon-delimited string format instead
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.322607  2786 paths.cpp:528] Trying to chown '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/slaves/f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0/frameworks/f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000/executors/f29d5aa6-79ed-4f3f-8b3a-8a730878dca3/runs/600e8a39-3084-48a2-9462-3d641d225ef9' to user 'root'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.327443  2786 slave.cpp:5367] Launching executor f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 with resources cpus(*):0.1; mem(*):32 in work directory '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/slaves/f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0/frameworks/f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000/executors/f29d5aa6-79ed-4f3f-8b3a-8a730878dca3/runs/600e8a39-3084-48a2-9462-3d641d225ef9'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.327899  2786 slave.cpp:1698] Queuing task 'f29d5aa6-79ed-4f3f-8b3a-8a730878dca3' for executor 'f29d5aa6-79ed-4f3f-8b3a-8a730878dca3' of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.327903  2785 containerizer.cpp:666] Starting container '600e8a39-3084-48a2-9462-3d641d225ef9' for executor 'f29d5aa6-79ed-4f3f-8b3a-8a730878dca3' of framework 'f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.327998  2786 slave.cpp:749] Successfully attached file '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/slaves/f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0/frameworks/f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000/executors/f29d5aa6-79ed-4f3f-8b3a-8a730878dca3/runs/600e8a39-3084-48a2-9462-3d641d225ef9'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.328500  2783 metadata_manager.cpp:159] Looking for image 'alpine'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.328891  2787 local_puller.cpp:142] Untarring image 'alpine' from '/tmp/RaTZmW/archives/alpine.tar' to '/tmp/RaTZmW/store/staging/Fry21a'
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.970053  2785 hierarchical.cpp:1643] Filtered offer with cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.970130  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.970167  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:23]W:	 [Step 11/11] I0322 20:01:23.970193  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 754419ns
[20:01:24]W:	 [Step 11/11] I0322 20:01:24.311338  2781 local_puller.cpp:162] The repositories JSON file for image 'alpine' is '{"alpine":{"latest":"815b809d588c80fd6ddf4d6ac244ad1c01ae4cbe0f91cc7480e306671ee9c346"}}'
[20:01:24]W:	 [Step 11/11] I0322 20:01:24.311869  2781 local_puller.cpp:282] Extracting layer tar ball '/tmp/RaTZmW/store/staging/Fry21a/815b809d588c80fd6ddf4d6ac244ad1c01ae4cbe0f91cc7480e306671ee9c346/layer.tar to rootfs '/tmp/RaTZmW/store/staging/Fry21a/815b809d588c80fd6ddf4d6ac244ad1c01ae4cbe0f91cc7480e306671ee9c346/rootfs'
[20:01:24]W:	 [Step 11/11] I0322 20:01:24.971063  2782 hierarchical.cpp:1643] Filtered offer with cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:24]W:	 [Step 11/11] I0322 20:01:24.971138  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:01:24]W:	 [Step 11/11] I0322 20:01:24.971174  2782 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:24]W:	 [Step 11/11] I0322 20:01:24.971201  2782 hierarchical.cpp:1134] Performed allocation for 1 slaves in 747682ns
[20:01:25]W:	 [Step 11/11] I0322 20:01:25.972501  2788 hierarchical.cpp:1643] Filtered offer with cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:25]W:	 [Step 11/11] I0322 20:01:25.972553  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:25]W:	 [Step 11/11] I0322 20:01:25.972584  2788 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:25]W:	 [Step 11/11] I0322 20:01:25.972609  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 630877ns
[20:01:26]W:	 [Step 11/11] I0322 20:01:26.973353  2788 hierarchical.cpp:1643] Filtered offer with cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:26]W:	 [Step 11/11] I0322 20:01:26.973394  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:26]W:	 [Step 11/11] I0322 20:01:26.973423  2788 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:26]W:	 [Step 11/11] I0322 20:01:26.973443  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 487937ns
[20:01:27]W:	 [Step 11/11] I0322 20:01:27.975111  2786 hierarchical.cpp:1643] Filtered offer with cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 for framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:01:27]W:	 [Step 11/11] I0322 20:01:27.975180  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:27]W:	 [Step 11/11] I0322 20:01:27.975216  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:27]W:	 [Step 11/11] I0322 20:01:27.975262  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 697249ns
[20:01:28]W:	 [Step 11/11] I0322 20:01:28.977082  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:28]W:	 [Step 11/11] I0322 20:01:28.977144  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 817952ns
[20:01:28]W:	 [Step 11/11] I0322 20:01:28.977447  2781 master.cpp:5488] Sending 1 offers to framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:01:28]W:	 [Step 11/11] I0322 20:01:28.977829  2782 sched.cpp:873] Scheduler::resourceOffers took 21518ns
[20:01:29]W:	 [Step 11/11] I0322 20:01:29.977952  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:29]W:	 [Step 11/11] I0322 20:01:29.977998  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:29]W:	 [Step 11/11] I0322 20:01:29.978025  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 225736ns
[20:01:30]W:	 [Step 11/11] I0322 20:01:30.978551  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:30]W:	 [Step 11/11] I0322 20:01:30.978613  2788 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:30]W:	 [Step 11/11] I0322 20:01:30.978641  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 278722ns
[20:01:31]W:	 [Step 11/11] I0322 20:01:31.979945  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:31]W:	 [Step 11/11] I0322 20:01:31.979985  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:31]W:	 [Step 11/11] I0322 20:01:31.980007  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 176071ns
[20:01:32]W:	 [Step 11/11] I0322 20:01:32.980522  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:32]W:	 [Step 11/11] I0322 20:01:32.980561  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:32]W:	 [Step 11/11] I0322 20:01:32.980582  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 180537ns
[20:01:33]W:	 [Step 11/11] I0322 20:01:33.981019  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:33]W:	 [Step 11/11] I0322 20:01:33.981079  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:33]W:	 [Step 11/11] I0322 20:01:33.981106  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 278662ns
[20:01:34]W:	 [Step 11/11] I0322 20:01:34.982493  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:34]W:	 [Step 11/11] I0322 20:01:34.982563  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:34]W:	 [Step 11/11] I0322 20:01:34.982594  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 286790ns
[20:01:35]W:	 [Step 11/11] I0322 20:01:35.983806  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:01:35]W:	 [Step 11/11] I0322 20:01:35.983856  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:35]W:	 [Step 11/11] I0322 20:01:35.983880  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 208372ns
[20:01:36]W:	 [Step 11/11] I0322 20:01:36.654964  2785 slave.cpp:4737] Querying resource estimator for oversubscribable resources
[20:01:36]W:	 [Step 11/11] I0322 20:01:36.655201  2785 slave.cpp:4751] Received oversubscribable resources  from the resource estimator
[20:01:36]W:	 [Step 11/11] I0322 20:01:36.984473  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:36]W:	 [Step 11/11] I0322 20:01:36.984524  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:36]W:	 [Step 11/11] I0322 20:01:36.984549  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 195258ns
[20:01:37]W:	 [Step 11/11] I0322 20:01:37.984958  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:01:37]W:	 [Step 11/11] I0322 20:01:37.985015  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:37]W:	 [Step 11/11] I0322 20:01:37.985043  2781 hierarchical.cpp:1134] Performed allocation for 1 slaves in 245096ns
[20:01:38]W:	 [Step 11/11] I0322 20:01:38.317147  2787 slave.cpp:3482] Received ping from slave-observer(403)@172.30.2.225:33126
[20:01:38]W:	 [Step 11/11] I0322 20:01:38.985663  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:38]W:	 [Step 11/11] I0322 20:01:38.985713  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:38]W:	 [Step 11/11] I0322 20:01:38.985741  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 261505ns
[20:01:39]W:	 [Step 11/11] I0322 20:01:39.986101  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:39]W:	 [Step 11/11] I0322 20:01:39.986143  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:39]W:	 [Step 11/11] I0322 20:01:39.986166  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 183386ns
[20:01:40]W:	 [Step 11/11] I0322 20:01:40.987126  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:01:40]W:	 [Step 11/11] I0322 20:01:40.987190  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:40]W:	 [Step 11/11] I0322 20:01:40.987217  2781 hierarchical.cpp:1134] Performed allocation for 1 slaves in 283397ns
[20:01:41]W:	 [Step 11/11] I0322 20:01:41.987789  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:41]W:	 [Step 11/11] I0322 20:01:41.987853  2787 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:41]W:	 [Step 11/11] I0322 20:01:41.987882  2787 hierarchical.cpp:1134] Performed allocation for 1 slaves in 281919ns
[20:01:42]W:	 [Step 11/11] I0322 20:01:42.989017  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:42]W:	 [Step 11/11] I0322 20:01:42.989080  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:42]W:	 [Step 11/11] I0322 20:01:42.989109  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 278722ns
[20:01:43]W:	 [Step 11/11] I0322 20:01:43.989807  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:01:43]W:	 [Step 11/11] I0322 20:01:43.989871  2783 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:43]W:	 [Step 11/11] I0322 20:01:43.989900  2783 hierarchical.cpp:1134] Performed allocation for 1 slaves in 306369ns
[20:01:44]W:	 [Step 11/11] I0322 20:01:44.990582  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:01:44]W:	 [Step 11/11] I0322 20:01:44.990646  2783 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:44]W:	 [Step 11/11] I0322 20:01:44.990675  2783 hierarchical.cpp:1134] Performed allocation for 1 slaves in 302429ns
[20:01:45]W:	 [Step 11/11] I0322 20:01:45.991631  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:45]W:	 [Step 11/11] I0322 20:01:45.991693  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:45]W:	 [Step 11/11] I0322 20:01:45.991722  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 288702ns
[20:01:46]W:	 [Step 11/11] I0322 20:01:46.993053  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:46]W:	 [Step 11/11] I0322 20:01:46.993115  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:46]W:	 [Step 11/11] I0322 20:01:46.993144  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 274361ns
[20:01:47]W:	 [Step 11/11] I0322 20:01:47.993543  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:47]W:	 [Step 11/11] I0322 20:01:47.993604  2787 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:47]W:	 [Step 11/11] I0322 20:01:47.993633  2787 hierarchical.cpp:1134] Performed allocation for 1 slaves in 274875ns
[20:01:48]W:	 [Step 11/11] I0322 20:01:48.994153  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:48]W:	 [Step 11/11] I0322 20:01:48.994215  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:48]W:	 [Step 11/11] I0322 20:01:48.994263  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 302656ns
[20:01:49]W:	 [Step 11/11] I0322 20:01:49.995546  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:01:49]W:	 [Step 11/11] I0322 20:01:49.995586  2787 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:49]W:	 [Step 11/11] I0322 20:01:49.995609  2787 hierarchical.cpp:1134] Performed allocation for 1 slaves in 181062ns
[20:01:50]W:	 [Step 11/11] I0322 20:01:50.996134  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:50]W:	 [Step 11/11] I0322 20:01:50.996196  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:50]W:	 [Step 11/11] I0322 20:01:50.996243  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 273109ns
[20:01:51]W:	 [Step 11/11] I0322 20:01:51.656224  2785 slave.cpp:4737] Querying resource estimator for oversubscribable resources
[20:01:51]W:	 [Step 11/11] I0322 20:01:51.656463  2785 slave.cpp:4751] Received oversubscribable resources  from the resource estimator
[20:01:51]W:	 [Step 11/11] I0322 20:01:51.997524  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:51]W:	 [Step 11/11] I0322 20:01:51.997571  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:51]W:	 [Step 11/11] I0322 20:01:51.997599  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 248805ns
[20:01:52]W:	 [Step 11/11] I0322 20:01:52.997988  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:01:52]W:	 [Step 11/11] I0322 20:01:52.998029  2782 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:52]W:	 [Step 11/11] I0322 20:01:52.998051  2782 hierarchical.cpp:1134] Performed allocation for 1 slaves in 184352ns
[20:01:53]W:	 [Step 11/11] I0322 20:01:53.317322  2782 slave.cpp:3482] Received ping from slave-observer(403)@172.30.2.225:33126
[20:01:53]W:	 [Step 11/11] I0322 20:01:53.998625  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:01:55]W:	 [Step 11/11] I0322 20:01:53.998687  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:55]W:	 [Step 11/11] I0322 20:01:53.998715  2781 hierarchical.cpp:1134] Performed allocation for 1 slaves in 281045ns
[20:01:55]W:	 [Step 11/11] I0322 20:01:54.999131  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:55]W:	 [Step 11/11] I0322 20:01:54.999184  2788 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:55]W:	 [Step 11/11] I0322 20:01:54.999209  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 237690ns
[20:01:56]W:	 [Step 11/11] I0322 20:01:56.000118  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:01:56]W:	 [Step 11/11] I0322 20:01:56.000181  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:56]W:	 [Step 11/11] I0322 20:01:56.000210  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 286275ns
[20:01:57]W:	 [Step 11/11] I0322 20:01:57.001453  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:01:57]W:	 [Step 11/11] I0322 20:01:57.001516  2788 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:57]W:	 [Step 11/11] I0322 20:01:57.001544  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 280489ns
[20:01:58]W:	 [Step 11/11] I0322 20:01:58.002853  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:01:58]W:	 [Step 11/11] I0322 20:01:58.002915  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:01:58]W:	 [Step 11/11] I0322 20:01:58.002944  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 283368ns
[20:01:58]W:	 [Step 11/11] I0322 20:01:58.903749  2784 metadata_manager.cpp:148] Successfully cached image 'alpine'
[20:01:58]W:	 [Step 11/11] I0322 20:01:58.904800  2783 provisioner.cpp:285] Provisioning image rootfs '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/provisioner/containers/600e8a39-3084-48a2-9462-3d641d225ef9/backends/copy/rootfses/cb54bc92-53e7-4f06-ae8b-d420c2cd8d75' for container 600e8a39-3084-48a2-9462-3d641d225ef9
[20:01:58]W:	 [Step 11/11] I0322 20:01:58.905506  2782 copy.cpp:127] Copying layer path '/tmp/RaTZmW/store/layers/815b809d588c80fd6ddf4d6ac244ad1c01ae4cbe0f91cc7480e306671ee9c346/rootfs' to rootfs '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/provisioner/containers/600e8a39-3084-48a2-9462-3d641d225ef9/backends/copy/rootfses/cb54bc92-53e7-4f06-ae8b-d420c2cd8d75'
[20:01:59]W:	 [Step 11/11] I0322 20:01:59.003618  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:02:00]W:	 [Step 11/11] I0322 20:01:59.003684  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:00]W:	 [Step 11/11] I0322 20:01:59.003712  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 320493ns
[20:02:01]W:	 [Step 11/11] I0322 20:02:00.004235  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:02:01]W:	 [Step 11/11] I0322 20:02:00.004300  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:01]W:	 [Step 11/11] I0322 20:02:00.004329  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 337343ns
[20:02:01]W:	 [Step 11/11] I0322 20:02:01.005720  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:01]W:	 [Step 11/11] I0322 20:02:01.005782  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:01]W:	 [Step 11/11] I0322 20:02:01.005810  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 286854ns
[20:02:02]W:	 [Step 11/11] I0322 20:02:02.006980  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:02:02]W:	 [Step 11/11] I0322 20:02:02.007045  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:02]W:	 [Step 11/11] I0322 20:02:02.007074  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 283972ns
[20:02:03]W:	 [Step 11/11] I0322 20:02:03.008024  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:02:03]W:	 [Step 11/11] I0322 20:02:03.008088  2782 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:03]W:	 [Step 11/11] I0322 20:02:03.008118  2782 hierarchical.cpp:1134] Performed allocation for 1 slaves in 291579ns
[20:02:04]W:	 [Step 11/11] I0322 20:02:04.009317  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:05]W:	 [Step 11/11] I0322 20:02:04.009366  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:05]W:	 [Step 11/11] I0322 20:02:04.009392  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 224770ns
[20:02:05]W:	 [Step 11/11] I0322 20:02:05.009968  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:02:05]W:	 [Step 11/11] I0322 20:02:05.010026  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:05]W:	 [Step 11/11] I0322 20:02:05.010053  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 263611ns
[20:02:06]W:	 [Step 11/11] I0322 20:02:06.010699  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:02:06]W:	 [Step 11/11] I0322 20:02:06.010761  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:06]W:	 [Step 11/11] I0322 20:02:06.010790  2781 hierarchical.cpp:1134] Performed allocation for 1 slaves in 314179ns
[20:02:06]W:	 [Step 11/11] I0322 20:02:06.656930  2784 slave.cpp:4737] Querying resource estimator for oversubscribable resources
[20:02:08]W:	 [Step 11/11] I0322 20:02:06.657186  2784 slave.cpp:4751] Received oversubscribable resources  from the resource estimator
[20:02:08]W:	 [Step 11/11] I0322 20:02:07.011523  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:02:08]W:	 [Step 11/11] I0322 20:02:07.011569  2788 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:08]W:	 [Step 11/11] I0322 20:02:07.011596  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 264861ns
[20:02:08]W:	 [Step 11/11] I0322 20:02:08.012979  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:02:08]W:	 [Step 11/11] I0322 20:02:08.013034  2787 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:08]W:	 [Step 11/11] I0322 20:02:08.013063  2787 hierarchical.cpp:1134] Performed allocation for 1 slaves in 267635ns
[20:02:08]W:	 [Step 11/11] I0322 20:02:08.317991  2782 slave.cpp:3482] Received ping from slave-observer(403)@172.30.2.225:33126
[20:02:09]W:	 [Step 11/11] I0322 20:02:09.013885  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:02:09]W:	 [Step 11/11] I0322 20:02:09.013947  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:09]W:	 [Step 11/11] I0322 20:02:09.013977  2781 hierarchical.cpp:1134] Performed allocation for 1 slaves in 276697ns
[20:02:10]W:	 [Step 11/11] I0322 20:02:10.014341  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:02:10]W:	 [Step 11/11] I0322 20:02:10.014385  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:10]W:	 [Step 11/11] I0322 20:02:10.014408  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 192251ns
[20:02:11]W:	 [Step 11/11] I0322 20:02:11.015781  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:11]W:	 [Step 11/11] I0322 20:02:11.015835  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:13]W:	 [Step 11/11] I0322 20:02:11.015861  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 243612ns
[20:02:15]W:	 [Step 11/11] I0322 20:02:12.016235  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:02:15]W:	 [Step 11/11] I0322 20:02:12.016279  2788 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:15]W:	 [Step 11/11] I0322 20:02:12.016302  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 209025ns
[20:02:15]W:	 [Step 11/11] I0322 20:02:13.016672  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:02:15]W:	 [Step 11/11] I0322 20:02:13.016721  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:15]W:	 [Step 11/11] I0322 20:02:13.016743  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 189423ns
[20:02:15]W:	 [Step 11/11] I0322 20:02:14.017967  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:02:16]W:	 [Step 11/11] I0322 20:02:14.018013  2783 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:16]W:	 [Step 11/11] I0322 20:02:14.018038  2783 hierarchical.cpp:1134] Performed allocation for 1 slaves in 219765ns
[20:02:16]W:	 [Step 11/11] I0322 20:02:15.018350  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:16]W:	 [Step 11/11] I0322 20:02:15.018386  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:16]W:	 [Step 11/11] I0322 20:02:15.018409  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 158713ns
[20:02:16]W:	 [Step 11/11] I0322 20:02:16.018893  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:02:16]W:	 [Step 11/11] I0322 20:02:16.018940  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:16]W:	 [Step 11/11] I0322 20:02:16.018965  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 194046ns
[20:02:17]W:	 [Step 11/11] I0322 20:02:17.019784  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:02:17]W:	 [Step 11/11] I0322 20:02:17.019860  2783 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:17]W:	 [Step 11/11] I0322 20:02:17.019891  2783 hierarchical.cpp:1134] Performed allocation for 1 slaves in 299261ns
[20:02:18]W:	 [Step 11/11] I0322 20:02:18.020658  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:02:18]W:	 [Step 11/11] I0322 20:02:18.020720  2784 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:18]W:	 [Step 11/11] I0322 20:02:18.020748  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 278501ns
[20:02:19]W:	 [Step 11/11] I0322 20:02:19.021201  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:02:19]W:	 [Step 11/11] I0322 20:02:19.021289  2783 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:19]W:	 [Step 11/11] I0322 20:02:19.021319  2783 hierarchical.cpp:1134] Performed allocation for 1 slaves in 312173ns
[20:02:20]W:	 [Step 11/11] I0322 20:02:20.022007  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:20]W:	 [Step 11/11] I0322 20:02:20.022068  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:20]W:	 [Step 11/11] I0322 20:02:20.022095  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 283710ns
[20:02:21]W:	 [Step 11/11] I0322 20:02:21.023164  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:02:21]W:	 [Step 11/11] I0322 20:02:21.023241  2781 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:21]W:	 [Step 11/11] I0322 20:02:21.023272  2781 hierarchical.cpp:1134] Performed allocation for 1 slaves in 316886ns
[20:02:21]W:	 [Step 11/11] I0322 20:02:21.648494  2781 slave.cpp:4374] Current disk usage 37.34%. Max allowed age: 3.686225156966157days
[20:02:22]W:	 [Step 11/11] I0322 20:02:21.657596  2787 slave.cpp:4737] Querying resource estimator for oversubscribable resources
[20:02:22]W:	 [Step 11/11] I0322 20:02:21.657841  2781 slave.cpp:4751] Received oversubscribable resources  from the resource estimator
[20:02:22]W:	 [Step 11/11] I0322 20:02:22.023886  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:02:22]W:	 [Step 11/11] I0322 20:02:22.023929  2786 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:22]W:	 [Step 11/11] I0322 20:02:22.023957  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 230797ns
[20:02:23]W:	 [Step 11/11] I0322 20:02:23.025318  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:23] :	 [Step 11/11] ../../src/tests/containerizer/provisioner_docker_tests.cpp:386: Failure
[20:02:23] :	 [Step 11/11] Failed to wait 1mins for statusRunning
[20:02:23]W:	 [Step 11/11] I0322 20:02:23.025375  2785 hierarchical.cpp:1552] No inverse offers to send out!
[20:02:23]W:	 [Step 11/11] I0322 20:02:23.025401  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 270193ns
[20:02:23] :	 [Step 11/11] ../../src/tests/containerizer/provisioner_docker_tests.cpp:380: Failure
[20:02:23]W:	 [Step 11/11] I0322 20:02:23.318375  2783 slave.cpp:3482] Received ping from slave-observer(403)@172.30.2.225:33126
[20:02:23] :	 [Step 11/11] Actual function call count doesn't match EXPECT_CALL(sched, statusUpdate(&driver, _))...
[20:02:23] :	 [Step 11/11]          Expected: to be called twice
[20:02:23]W:	 [Step 11/11] I0322 20:02:23.320112  2783 master.cpp:1251] Framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126 disconnected
[20:02:23] :	 [Step 11/11]            Actual: never called - unsatisfied and active
[20:02:23]W:	 [Step 11/11] I0322 20:02:23.320139  2783 master.cpp:2622] Disconnecting framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:02:23]W:	 [Step 11/11] I0322 20:02:23.320168  2783 master.cpp:2646] Deactivating framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.320322  2782 hierarchical.cpp:375] Deactivated framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:02:25]W:	 [Step 11/11] W0322 20:02:23.320734  2783 master.hpp:1818] Master attempted to send message to disconnected framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.320829  2783 master.cpp:1275] Giving framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126 0ns to failover
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.320834  2785 hierarchical.cpp:890] Recovered cpus(*):1; mem(*):896; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):1; mem(*):128) on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 from framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321068  2783 master.cpp:5340] Framework failover timeout, removing framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321097  2783 master.cpp:6073] Removing framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (default) at scheduler-921164b1-8d07-450a-9ace-1ad1aa71b61a@172.30.2.225:33126
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321209  2786 containerizer.cpp:1402] Destroying container '600e8a39-3084-48a2-9462-3d641d225ef9'
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321267  2786 containerizer.cpp:1405] Waiting for the isolators to complete preparing before destroying the container
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321279  2781 slave.cpp:2079] Asked to shut down framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 by master@172.30.2.225:33126
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321300  2783 master.cpp:6588] Updating the state of task f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321322  2781 slave.cpp:2104] Shutting down framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321403  2781 slave.cpp:4198] Shutting down executor 'f29d5aa6-79ed-4f3f-8b3a-8a730878dca3' of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:02:25]W:	 [Step 11/11] W0322 20:02:23.321427  2781 slave.hpp:665] Unable to send event to executor 'f29d5aa6-79ed-4f3f-8b3a-8a730878dca3' of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000: unknown connection type
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321686  2785 hierarchical.cpp:890] Recovered cpus(*):1; mem(*):128 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 from framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321681  2783 master.cpp:6654] Removing task f29d5aa6-79ed-4f3f-8b3a-8a730878dca3 with resources cpus(*):1; mem(*):128 of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 on slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io)
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.321908  2782 linux.cpp:755] Ignoring cleanup request for unknown container: 600e8a39-3084-48a2-9462-3d641d225ef9
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.322289  2786 hierarchical.cpp:326] Removed framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.322334  2788 provisioner.cpp:330] Destroying container rootfs at '/tmp/ProvisionerDockerPullerTest_ROOT_LocalPullerSimpleCommand_7FS2pX/provisioner/containers/600e8a39-3084-48a2-9462-3d641d225ef9/backends/copy/rootfses/cb54bc92-53e7-4f06-ae8b-d420c2cd8d75' for container 600e8a39-3084-48a2-9462-3d641d225ef9
[20:02:25]W:	 [Step 11/11] I0322 20:02:23.328294  2787 slave.cpp:4293] Ignoring registration timeout for executor 'f29d5aa6-79ed-4f3f-8b3a-8a730878dca3' because the  framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 is terminating
[20:02:25]W:	 [Step 11/11] I0322 20:02:24.025982  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:02:25]W:	 [Step 11/11] I0322 20:02:24.026015  2787 hierarchical.cpp:1134] Performed allocation for 1 slaves in 171900ns
[20:02:25]W:	 [Step 11/11] I0322 20:02:25.026459  2784 hierarchical.cpp:1457] No resources available to allocate!
[20:02:25]W:	 [Step 11/11] I0322 20:02:25.026490  2784 hierarchical.cpp:1134] Performed allocation for 1 slaves in 140645ns
[20:02:26]W:	 [Step 11/11] I0322 20:02:26.027189  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:26]W:	 [Step 11/11] I0322 20:02:26.027253  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 261767ns
[20:02:27]W:	 [Step 11/11] I0322 20:02:27.027788  2782 hierarchical.cpp:1457] No resources available to allocate!
[20:02:27]W:	 [Step 11/11] I0322 20:02:27.027840  2782 hierarchical.cpp:1134] Performed allocation for 1 slaves in 246512ns
[20:02:28]W:	 [Step 11/11] I0322 20:02:28.028439  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:02:28]W:	 [Step 11/11] I0322 20:02:28.028488  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 231801ns
[20:02:28]W:	 [Step 11/11] I0322 20:02:28.322428  2781 slave.cpp:4263] Killing executor 'f29d5aa6-79ed-4f3f-8b3a-8a730878dca3' of framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000
[20:02:29]W:	 [Step 11/11] I0322 20:02:29.029230  2786 hierarchical.cpp:1457] No resources available to allocate!
[20:02:29]W:	 [Step 11/11] I0322 20:02:29.029283  2786 hierarchical.cpp:1134] Performed allocation for 1 slaves in 244095ns
[20:02:30]W:	 [Step 11/11] I0322 20:02:30.030783  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:02:30]W:	 [Step 11/11] I0322 20:02:30.030835  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 232829ns
[20:02:31]W:	 [Step 11/11] I0322 20:02:31.031309  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:31]W:	 [Step 11/11] I0322 20:02:31.031366  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 236200ns
[20:02:32]W:	 [Step 11/11] I0322 20:02:32.031832  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:02:32]W:	 [Step 11/11] I0322 20:02:32.031877  2783 hierarchical.cpp:1134] Performed allocation for 1 slaves in 201259ns
[20:02:33]W:	 [Step 11/11] I0322 20:02:33.032277  2783 hierarchical.cpp:1457] No resources available to allocate!
[20:02:33]W:	 [Step 11/11] I0322 20:02:33.032327  2783 hierarchical.cpp:1134] Performed allocation for 1 slaves in 160674ns
[20:02:34]W:	 [Step 11/11] I0322 20:02:34.032739  2785 hierarchical.cpp:1457] No resources available to allocate!
[20:02:34]W:	 [Step 11/11] I0322 20:02:34.032790  2785 hierarchical.cpp:1134] Performed allocation for 1 slaves in 217801ns
[20:02:35]W:	 [Step 11/11] I0322 20:02:35.034044  2787 hierarchical.cpp:1457] No resources available to allocate!
[20:02:35]W:	 [Step 11/11] I0322 20:02:35.034080  2787 hierarchical.cpp:1134] Performed allocation for 1 slaves in 151339ns
[20:02:36]W:	 [Step 11/11] I0322 20:02:36.034745  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:02:36]W:	 [Step 11/11] I0322 20:02:36.034795  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 212042ns
[20:02:36]W:	 [Step 11/11] I0322 20:02:36.658752  2782 slave.cpp:4737] Querying resource estimator for oversubscribable resources
[20:02:36]W:	 [Step 11/11] I0322 20:02:36.659003  2782 slave.cpp:4751] Received oversubscribable resources  from the resource estimator
[20:02:37]W:	 [Step 11/11] I0322 20:02:37.035296  2788 hierarchical.cpp:1457] No resources available to allocate!
[20:02:37]W:	 [Step 11/11] I0322 20:02:37.035327  2788 hierarchical.cpp:1134] Performed allocation for 1 slaves in 181743ns
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.035951  2781 hierarchical.cpp:1457] No resources available to allocate!
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.036003  2781 hierarchical.cpp:1134] Performed allocation for 1 slaves in 224865ns
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.318956  2782 slave.cpp:3482] Received ping from slave-observer(403)@172.30.2.225:33126
[20:02:38] :	 [Step 11/11] ../../src/tests/cluster.cpp:452: Failure
[20:02:38] :	 [Step 11/11] Failed to wait 15secs for wait
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.322357  2787 slave.cpp:668] Slave terminating
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.322433  2787 slave.cpp:2079] Asked to shut down framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 by @0.0.0.0:0
[20:02:38]W:	 [Step 11/11] W0322 20:02:38.322465  2787 slave.cpp:2100] Ignoring shutdown framework f874517a-7b69-44e3-a0c9-d093bebfe0dc-0000 because it is terminating
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.322660  2785 master.cpp:1212] Slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io) disconnected
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.322684  2785 master.cpp:2681] Disconnecting slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io)
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.322737  2785 master.cpp:2700] Deactivating slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 at slave(432)@172.30.2.225:33126 (ip-172-30-2-225.mesosphere.io)
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.322846  2788 hierarchical.cpp:560] Slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0 deactivated
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.327556  2781 master.cpp:1065] Master terminating
[20:02:38]W:	 [Step 11/11] I0322 20:02:38.328100  2786 hierarchical.cpp:505] Removed slave f874517a-7b69-44e3-a0c9-d093bebfe0dc-S0
[20:02:38]W:	 [Step 11/11] *** Aborted at 1458676958 (unix time) try "date -d @1458676958" if you are using GNU date ***
[20:02:38]W:	 [Step 11/11] PC: @     0x7f232d1ca2a6 (unknown)
[20:02:38]W:	 [Step 11/11] *** SIGSEGV (@0xb) received by PID 2767 (TID 0x7f2324058700) from PID 11; stack trace: ***
[20:02:38]W:	 [Step 11/11]     @     0x7f22fb765d1b (unknown)
[20:02:38]W:	 [Step 11/11]     @     0x7f22fb76a8e1 (unknown)
[20:02:38]W:	 [Step 11/11]     @     0x7f22fb75ebc8 (unknown)
[20:02:38]W:	 [Step 11/11]     @     0x7f232d890100 (unknown)
[20:02:38]W:	 [Step 11/11]     @     0x7f232d1ca2a6 (unknown)
[20:02:39]W:	 [Step 11/11]     @     0x7f23319c7a7b process::Process<>::self()
[20:02:39]W:	 [Step 11/11]     @     0x7f23319b3643 _ZZN5mesos8internal5slave25MesosContainerizerProcess7_launchERKNS_11ContainerIDERK6OptionINS_8TaskInfoEERKNS_12ExecutorInfoERKSsRKS6_ISsERKNS_7SlaveIDERKN7process3PIDINS1_5SlaveEEEbRKS6_INS1_13ProvisionInfoEEENKUlvE0_clEv
[20:02:39]W:	 [Step 11/11]     @     0x7f23319be5a0 _ZNSt17_Function_handlerIFN7process6FutureIbEEvEZN5mesos8internal5slave25MesosContainerizerProcess7_launchERKNS4_11ContainerIDERK6OptionINS4_8TaskInfoEERKNS4_12ExecutorInfoERKSsRKSB_ISsERKNS4_7SlaveIDERKNS0_3PIDINS6_5SlaveEEEbRKSB_INS6_13ProvisionInfoEEEUlvE0_E9_M_invokeERKSt9_Any_data
[20:02:39]W:	 [Step 11/11]     @     0x7f23315e04cf std::function<>::operator()()
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a2f9ab _ZNSt5_BindIFSt8functionIFN7process6FutureIbEEvEEvEE6__callIS3_IRKSt4listI7NothingSaISA_EEEIEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a24c59 _ZNSt5_BindIFSt8functionIFN7process6FutureIbEEvEEvEEclIJRKSt4listI7NothingSaISA_EEES3_EET0_DpOT_
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a143e6 std::_Function_handler<>::_M_invoke()
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a35bdd std::function<>::operator()()
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a2fa3f process::internal::thenf<>()
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a396c5 _ZNSt5_BindIFPFvRKSt8functionIFN7process6FutureIbEERKSt4listI7NothingSaIS5_EEEERKSt10shared_ptrINS1_7PromiseIbEEERKNS2_IS7_EEESB_SH_St12_PlaceholderILi1EEEE6__callIvISM_EILm0ELm1ELm2EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a3818d std::_Bind<>::operator()<>()
[20:02:39]W:	 [Step 11/11]     @     0x7f2331a35e74 std::_Function_handler<>::_M_invoke()
[20:02:39]W:	 [Step 11/11]     @           0xc9b3d1 std::function<>::operator()()
[20:02:39]W:	 [Step 11/11]     @     0x7f233186f507 _ZZNK7process6FutureISt4listI7NothingSaIS2_EEE5onAnyIRSt8functionIFvRKS5_EEvEES9_OT_NS5_6PreferEENUlS9_E_clES9_
[20:02:39]W:	 [Step 11/11]     @     0x7f233187d688 _ZNSt17_Function_handlerIFvRKN7process6FutureISt4listI7NothingSaIS3_EEEEEZNKS6_5onAnyIRSt8functionIS9_EvEES8_OT_NS6_6PreferEEUlS8_E_E9_M_invokeERKSt9_Any_dataS8_
[20:02:39]W:	 [Step 11/11]     @           0xc9b3d1 std::function<>::operator()()
[20:02:39]W:	 [Step 11/11]     @           0xc9764f _ZN7process8internal3runISt8functionIFvRKNS_6FutureISt4listI7NothingSaIS5_EEEEEEJRS8_EEEvRKSt6vectorIT_SaISF_EEDpOT0_
[20:02:39]W:	 [Step 11/11]     @           0xc926eb process::Future<>::set()
[20:02:39]W:	 [Step 11/11]     @           0xca70d4 process::Promise<>::set()
[20:02:39]W:	 [Step 11/11]     @           0xca58fc process::internal::CollectProcess<>::waited()
[20:02:39]W:	 [Step 11/11]     @           0xca8621 _ZZN7process8dispatchINS_8internal14CollectProcessI7NothingEERKNS_6FutureIS3_EES6_EEvRKNS_3PIDIT_EEMSA_FvT0_ET1_ENKUlPNS_11ProcessBaseEE_clESJ_
[20:02:39]W:	 [Step 11/11]     @           0xcaa1cc _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchINS0_8internal14CollectProcessI7NothingEERKNS0_6FutureIS7_EESA_EEvRKNS0_3PIDIT_EEMSE_FvT0_ET1_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
[20:02:39]W:	 [Step 11/11]     @     0x7f23321d850f std::function<>::operator()()
[20:02:39]W:	 [Step 11/11]     @     0x7f23321be1b1 process::ProcessBase::visit()
[20:02:39]W:	 [Step 11/11]     @     0x7f23321c3f12 process::DispatchEvent::visit()
[20:02:39]W:	 [Step 11/11]     @           0x9a0cd0 process::ProcessBase::serve()
[20:02:39]W:	 [Step 11/11]     @     0x7f23321ba4e7 process::ProcessManager::resume()

Attachments

Issue Links

relates to

MESOS-5205 ProvisionerDockerPullerTest.ROOT_LocalPullerSimpleCommand is flaky

Resolved

Activity

People

Assignee:: Unassigned

Reporter:: Jie Yu

Votes:: 0 Vote for this issue

Watchers:: 2 Start watching this issue

Dates

Created:: 22/Mar/16 20:35

Updated:: 08/Feb/18 18:01

Resolved:: 08/Feb/18 18:01