You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/12/01 21:26:57 UTC
Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1301
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1301/changes>
Changes:
[yujie.jay] Added hex number support to numify().
[yujie.jay] Added stdout/tests/numify_tests.cpp into Makefile.am.
[yujie.jay] Added --egress_flow_classifier_parent flag.
------------------------------------------
[...truncated 29126 lines...]
I1201 20:26:54.344596 30582 resources.cpp:478] Parsing resources as JSON failed: cpus:2;mem:10240
Trying semicolon-delimited string format instead
I1201 20:26:54.345010 30582 slave.cpp:390] Slave resources: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1201 20:26:54.345135 30582 slave.cpp:398] Slave attributes: [ ]
I1201 20:26:54.345160 30582 slave.cpp:403] Slave hostname: b4d97f439470
I1201 20:26:54.345167 30582 slave.cpp:408] Slave checkpoint: true
I1201 20:26:54.345217 30567 containerizer.cpp:141] Using isolation: filesystem/posix,posix/cpu,posix/mem
W1201 20:26:54.345466 30567 backend.cpp:48] Failed to create 'bind' backend: BindBackend requires root privileges
I1201 20:26:54.346271 30592 state.cpp:52] Recovering state from '/tmp/mesos-ybXGT3/0/meta'
I1201 20:26:54.346479 30582 status_update_manager.cpp:200] Recovering status update manager
I1201 20:26:54.346675 30590 containerizer.cpp:383] Recovering containerizer
I1201 20:26:54.347076 30580 slave.cpp:189] Slave started on 2)@172.17.25.124:37666
I1201 20:26:54.347283 30593 slave.cpp:4258] Finished recovery
I1201 20:26:54.347482 30585 master.cpp:1637] The newly elected leader is master@172.17.25.124:37666 with id 6166a26c-9d72-41fe-82f4-26b8f83cd643
I1201 20:26:54.347509 30585 master.cpp:1650] Elected as the leading master!
I1201 20:26:54.347523 30585 master.cpp:1395] Recovering from registrar
I1201 20:26:54.347105 30580 slave.cpp:190] Flags at startup: --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" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_auth_server="auth.docker.io" --docker_auth_server_port="443" --docker_kill_orphans="true" --docker_local_archives_dir="/tmp/mesos/images/docker" --docker_puller="local" --docker_puller_timeout="60" --docker_registry="registry-1.docker.io" --docker_registry_port="443" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/mesos/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="filesystem/posix,posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-0.27.0/_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="1secs" --resources="cpus:2;mem:10240" --revocable_cpu_low_priority="true" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/mesos-ybXGT3/1"
I1201 20:26:54.347654 30591 registrar.cpp:307] Recovering registrar
I1201 20:26:54.347760 30593 slave.cpp:4430] Querying resource estimator for oversubscribable resources
I1201 20:26:54.347872 30584 status_update_manager.cpp:174] Pausing sending status updates
I1201 20:26:54.347887 30593 slave.cpp:727] New master detected at master@172.17.25.124:37666
I1201 20:26:54.348047 30567 containerizer.cpp:141] Using isolation: filesystem/posix,posix/cpu,posix/mem
I1201 20:26:54.348067 30580 resources.cpp:478] Parsing resources as JSON failed: cpus:2;mem:10240
Trying semicolon-delimited string format instead
I1201 20:26:54.348073 30593 slave.cpp:752] No credentials provided. Attempting to register without authentication
I1201 20:26:54.348116 30593 slave.cpp:763] Detecting new master
I1201 20:26:54.348179 30593 slave.cpp:4444] Received oversubscribable resources from the resource estimator
W1201 20:26:54.348395 30567 backend.cpp:48] Failed to create 'bind' backend: BindBackend requires root privileges
I1201 20:26:54.348383 30580 slave.cpp:390] Slave resources: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1201 20:26:54.348417 30580 slave.cpp:398] Slave attributes: [ ]
I1201 20:26:54.348423 30580 slave.cpp:403] Slave hostname: b4d97f439470
I1201 20:26:54.348428 30580 slave.cpp:408] Slave checkpoint: true
I1201 20:26:54.348808 30587 state.cpp:52] Recovering state from '/tmp/mesos-ybXGT3/1/meta'
I1201 20:26:54.348925 30580 status_update_manager.cpp:200] Recovering status update manager
I1201 20:26:54.349030 30585 containerizer.cpp:383] Recovering containerizer
I1201 20:26:54.349505 30594 slave.cpp:4258] Finished recovery
I1201 20:26:54.349794 30592 slave.cpp:189] Slave started on 3)@172.17.25.124:37666
I1201 20:26:54.349879 30594 slave.cpp:4430] Querying resource estimator for oversubscribable resources
I1201 20:26:54.349967 30594 slave.cpp:4444] Received oversubscribable resources from the resource estimator
I1201 20:26:54.350036 30581 status_update_manager.cpp:174] Pausing sending status updates
I1201 20:26:54.350041 30594 slave.cpp:727] New master detected at master@172.17.25.124:37666
I1201 20:26:54.350075 30594 slave.cpp:752] No credentials provided. Attempting to register without authentication
I1201 20:26:54.350117 30594 slave.cpp:763] Detecting new master
I1201 20:26:54.349820 30592 slave.cpp:190] Flags at startup: --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" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_auth_server="auth.docker.io" --docker_auth_server_port="443" --docker_kill_orphans="true" --docker_local_archives_dir="/tmp/mesos/images/docker" --docker_puller="local" --docker_puller_timeout="60" --docker_registry="registry-1.docker.io" --docker_registry_port="443" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/mesos/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="filesystem/posix,posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-0.27.0/_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="1secs" --resources="cpus:2;mem:10240" --revocable_cpu_low_priority="true" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/mesos-ybXGT3/2"
I1201 20:26:54.350313 30567 sched.cpp:164] Version: 0.27.0
I1201 20:26:54.350477 30592 resources.cpp:478] Parsing resources as JSON failed: cpus:2;mem:10240
Trying semicolon-delimited string format instead
I1201 20:26:54.350522 30594 sched.cpp:262] New master detected at master@172.17.25.124:37666
I1201 20:26:54.350575 30594 sched.cpp:318] Authenticating with master master@172.17.25.124:37666
I1201 20:26:54.350592 30594 sched.cpp:325] Using default CRAM-MD5 authenticatee
I1201 20:26:54.350733 30592 slave.cpp:390] Slave resources: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1201 20:26:54.350775 30592 slave.cpp:398] Slave attributes: [ ]
I1201 20:26:54.350785 30592 slave.cpp:403] Slave hostname: b4d97f439470
I1201 20:26:54.350790 30592 slave.cpp:408] Slave checkpoint: true
I1201 20:26:54.350803 30591 authenticatee.cpp:97] Initializing client SASL
I1201 20:26:54.350980 30591 authenticatee.cpp:121] Creating new client SASL connection
I1201 20:26:54.351099 30594 state.cpp:52] Recovering state from '/tmp/mesos-ybXGT3/2/meta'
I1201 20:26:54.351198 30587 master.cpp:1222] Dropping 'mesos.internal.AuthenticateMessage' message since not recovered yet
I1201 20:26:54.351358 30591 status_update_manager.cpp:200] Recovering status update manager
I1201 20:26:54.351465 30591 containerizer.cpp:383] Recovering containerizer
I1201 20:26:54.351958 30594 slave.cpp:4258] Finished recovery
I1201 20:26:54.378700 30594 slave.cpp:4430] Querying resource estimator for oversubscribable resources
I1201 20:26:54.378823 30584 slave.cpp:4444] Received oversubscribable resources from the resource estimator
I1201 20:26:54.378978 30584 slave.cpp:727] New master detected at master@172.17.25.124:37666
I1201 20:26:54.378983 30590 status_update_manager.cpp:174] Pausing sending status updates
I1201 20:26:54.379078 30584 slave.cpp:752] No credentials provided. Attempting to register without authentication
I1201 20:26:54.379117 30584 slave.cpp:763] Detecting new master
I1201 20:26:54.386754 30583 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 47.033245ms
I1201 20:26:54.386781 30583 replica.cpp:321] Persisted replica status to STARTING
I1201 20:26:54.386878 30583 recover.cpp:473] Replica is in STARTING status
I1201 20:26:54.387356 30583 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (38)@172.17.25.124:37666
I1201 20:26:54.387454 30583 recover.cpp:193] Received a recover response from a replica in STARTING status
I1201 20:26:54.387652 30584 recover.cpp:564] Updating replica status to VOTING
I1201 20:26:54.429482 30580 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 41.777418ms
I1201 20:26:54.429520 30580 replica.cpp:321] Persisted replica status to VOTING
I1201 20:26:54.429587 30580 recover.cpp:578] Successfully joined the Paxos group
I1201 20:26:54.429709 30580 recover.cpp:462] Recover process terminated
I1201 20:26:54.429963 30583 log.cpp:659] Attempting to start the writer
I1201 20:26:54.431110 30593 replica.cpp:494] Replica received implicit promise request from (39)@172.17.25.124:37666 with proposal 1
I1201 20:26:54.462868 30593 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.731802ms
I1201 20:26:54.462914 30593 replica.cpp:343] Persisted promised to 1
I1201 20:26:54.463438 30581 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1201 20:26:54.464364 30590 replica.cpp:389] Replica received explicit promise request from (40)@172.17.25.124:37666 for position 0 with proposal 2
I1201 20:26:54.494639 30593 slave.cpp:1252] Will retry registration in 1.731069628secs if necessary
I1201 20:26:54.494639 30586 master.cpp:1222] Dropping 'mesos.internal.RegisterSlaveMessage' message since not recovered yet
I1201 20:26:54.496261 30590 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 31.871898ms
I1201 20:26:54.496292 30590 replica.cpp:713] Persisted action at 0
I1201 20:26:54.497171 30591 replica.cpp:538] Replica received write request for position 0 from (41)@172.17.25.124:37666
I1201 20:26:54.497277 30591 leveldb.cpp:436] Reading position from leveldb took 31212ns
I1201 20:26:54.529742 30591 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 32.445407ms
I1201 20:26:54.529783 30591 replica.cpp:713] Persisted action at 0
I1201 20:26:54.530133 30588 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1201 20:26:54.563211 30588 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 33.002525ms
I1201 20:26:54.563247 30588 replica.cpp:713] Persisted action at 0
I1201 20:26:54.563261 30588 replica.cpp:698] Replica learned NOP action at position 0
I1201 20:26:54.563575 30580 log.cpp:675] Writer started with ending position 0
I1201 20:26:54.564352 30581 leveldb.cpp:436] Reading position from leveldb took 41704ns
I1201 20:26:54.567111 30582 registrar.cpp:340] Successfully fetched the registry (0B) in 219.427072ms
I1201 20:26:54.567240 30582 registrar.cpp:439] Applied 1 operations in 39688ns; attempting to update the 'registry'
I1201 20:26:54.569589 30589 log.cpp:683] Attempting to append 178 bytes to the log
I1201 20:26:54.569774 30585 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1201 20:26:54.570188 30587 replica.cpp:538] Replica received write request for position 1 from (42)@172.17.25.124:37666
I1201 20:26:54.604960 30587 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 34.75429ms
I1201 20:26:54.604991 30587 replica.cpp:713] Persisted action at 1
I1201 20:26:54.605309 30590 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1201 20:26:54.638453 30590 leveldb.cpp:341] Persisting action (199 bytes) to leveldb took 33.073373ms
I1201 20:26:54.638490 30590 replica.cpp:713] Persisted action at 1
I1201 20:26:54.638505 30590 replica.cpp:698] Replica learned APPEND action at position 1
I1201 20:26:54.639137 30589 registrar.cpp:484] Successfully updated the 'registry' in 71.85408ms
I1201 20:26:54.639217 30589 registrar.cpp:370] Successfully recovered registrar
I1201 20:26:54.639286 30591 log.cpp:702] Attempting to truncate the log to 1
I1201 20:26:54.639416 30592 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1201 20:26:54.639457 30593 hierarchical.cpp:174] Allocator recovery is not supported yet
I1201 20:26:54.639446 30590 master.cpp:1447] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1201 20:26:54.640044 30579 replica.cpp:538] Replica received write request for position 2 from (43)@172.17.25.124:37666
I1201 20:26:54.644824 30588 slave.cpp:1252] Will retry registration in 225.822419ms if necessary
I1201 20:26:54.644965 30594 master.cpp:3890] Registering slave at slave(2)@172.17.25.124:37666 (b4d97f439470) with id 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0
I1201 20:26:54.645179 30583 registrar.cpp:439] Applied 1 operations in 53151ns; attempting to update the 'registry'
I1201 20:26:54.674031 30579 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 33.960563ms
I1201 20:26:54.674067 30579 replica.cpp:713] Persisted action at 2
I1201 20:26:54.674520 30583 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1201 20:26:54.699123 30583 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 24.441612ms
I1201 20:26:54.699183 30583 leveldb.cpp:399] Deleting ~1 keys from leveldb took 37067ns
I1201 20:26:54.699198 30583 replica.cpp:713] Persisted action at 2
I1201 20:26:54.699211 30583 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1201 20:26:54.699755 30592 log.cpp:683] Attempting to append 347 bytes to the log
I1201 20:26:54.699820 30590 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 3
I1201 20:26:54.700369 30580 replica.cpp:538] Replica received write request for position 3 from (44)@172.17.25.124:37666
I1201 20:26:54.724331 30580 leveldb.cpp:341] Persisting action (366 bytes) to leveldb took 23.944502ms
I1201 20:26:54.724364 30580 replica.cpp:713] Persisted action at 3
I1201 20:26:54.724836 30589 replica.cpp:692] Replica received learned notice for position 3 from @0.0.0.0:0
I1201 20:26:54.749481 30589 leveldb.cpp:341] Persisting action (368 bytes) to leveldb took 24.625163ms
I1201 20:26:54.749524 30589 replica.cpp:713] Persisted action at 3
I1201 20:26:54.749539 30589 replica.cpp:698] Replica learned APPEND action at position 3
I1201 20:26:54.750270 30580 registrar.cpp:484] Successfully updated the 'registry' in 105.042176ms
I1201 20:26:54.750421 30587 log.cpp:702] Attempting to truncate the log to 3
I1201 20:26:54.750567 30580 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4
I1201 20:26:54.750715 30590 slave.cpp:3197] Received ping from slave-observer(1)@172.17.25.124:37666
I1201 20:26:54.750825 30591 master.cpp:3958] Registered slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0 at slave(2)@172.17.25.124:37666 (b4d97f439470) with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1201 20:26:54.750856 30589 slave.cpp:902] Registered with master master@172.17.25.124:37666; given slave ID 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0
I1201 20:26:54.750923 30592 hierarchical.cpp:380] Added slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0 (b4d97f439470) with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (allocated: )
I1201 20:26:54.751037 30589 fetcher.cpp:79] Clearing fetcher cache
I1201 20:26:54.751147 30592 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:54.751152 30590 status_update_manager.cpp:181] Resuming sending status updates
I1201 20:26:54.751242 30592 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:54.751256 30592 hierarchical.cpp:973] Performed allocation for slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0 in 216897ns
I1201 20:26:54.751319 30579 replica.cpp:538] Replica received write request for position 4 from (45)@172.17.25.124:37666
I1201 20:26:54.751368 30589 slave.cpp:925] Checkpointing SlaveInfo to '/tmp/mesos-ybXGT3/1/meta/slaves/6166a26c-9d72-41fe-82f4-26b8f83cd643-S0/slave.info'
I1201 20:26:54.751647 30589 slave.cpp:961] Forwarding total oversubscribed resources
I1201 20:26:54.751747 30594 master.cpp:4300] Received update of slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0 at slave(2)@172.17.25.124:37666 (b4d97f439470) with total oversubscribed resources
I1201 20:26:54.751950 30589 hierarchical.cpp:434] Slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0 (b4d97f439470) updated with oversubscribed resources (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: )
I1201 20:26:54.752034 30589 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:54.752059 30589 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:54.752068 30589 hierarchical.cpp:973] Performed allocation for slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S0 in 88817ns
I1201 20:26:54.774878 30579 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.52993ms
I1201 20:26:54.774914 30579 replica.cpp:713] Persisted action at 4
I1201 20:26:54.775209 30593 replica.cpp:692] Replica received learned notice for position 4 from @0.0.0.0:0
I1201 20:26:54.799957 30593 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 24.699262ms
I1201 20:26:54.800020 30593 leveldb.cpp:399] Deleting ~2 keys from leveldb took 42156ns
I1201 20:26:54.800042 30593 replica.cpp:713] Persisted action at 4
I1201 20:26:54.800063 30593 replica.cpp:698] Replica learned TRUNCATE action at position 4
I1201 20:26:55.122862 30582 slave.cpp:1252] Will retry registration in 529.105097ms if necessary
I1201 20:26:55.122966 30588 master.cpp:3890] Registering slave at slave(1)@172.17.25.124:37666 (b4d97f439470) with id 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1
I1201 20:26:55.123164 30581 registrar.cpp:439] Applied 1 operations in 40306ns; attempting to update the 'registry'
I1201 20:26:55.125459 30585 log.cpp:683] Attempting to append 513 bytes to the log
I1201 20:26:55.125572 30591 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5
I1201 20:26:55.126154 30592 replica.cpp:538] Replica received write request for position 5 from (46)@172.17.25.124:37666
I1201 20:26:55.150137 30592 leveldb.cpp:341] Persisting action (532 bytes) to leveldb took 23.958869ms
I1201 20:26:55.150169 30592 replica.cpp:713] Persisted action at 5
I1201 20:26:55.150676 30586 replica.cpp:692] Replica received learned notice for position 5 from @0.0.0.0:0
I1201 20:26:55.175266 30586 leveldb.cpp:341] Persisting action (534 bytes) to leveldb took 24.567492ms
I1201 20:26:55.175297 30586 replica.cpp:713] Persisted action at 5
I1201 20:26:55.175310 30586 replica.cpp:698] Replica learned APPEND action at position 5
I1201 20:26:55.176175 30588 registrar.cpp:484] Successfully updated the 'registry' in 52.96512ms
I1201 20:26:55.176360 30590 log.cpp:702] Attempting to truncate the log to 5
I1201 20:26:55.176502 30584 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6
I1201 20:26:55.176661 30592 slave.cpp:3197] Received ping from slave-observer(2)@172.17.25.124:37666
I1201 20:26:55.176753 30583 master.cpp:3958] Registered slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1 at slave(1)@172.17.25.124:37666 (b4d97f439470) with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1201 20:26:55.176832 30590 hierarchical.cpp:380] Added slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1 (b4d97f439470) with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (allocated: )
I1201 20:26:55.176946 30590 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:55.176961 30592 slave.cpp:902] Registered with master master@172.17.25.124:37666; given slave ID 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1
I1201 20:26:55.177047 30585 replica.cpp:538] Replica received write request for position 6 from (47)@172.17.25.124:37666
I1201 20:26:55.177060 30590 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:55.177132 30592 fetcher.cpp:79] Clearing fetcher cache
I1201 20:26:55.177253 30590 hierarchical.cpp:973] Performed allocation for slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1 in 394941ns
I1201 20:26:55.177391 30588 status_update_manager.cpp:181] Resuming sending status updates
I1201 20:26:55.177593 30592 slave.cpp:925] Checkpointing SlaveInfo to '/tmp/mesos-ybXGT3/0/meta/slaves/6166a26c-9d72-41fe-82f4-26b8f83cd643-S1/slave.info'
I1201 20:26:55.177974 30592 slave.cpp:961] Forwarding total oversubscribed resources
I1201 20:26:55.178056 30592 master.cpp:4300] Received update of slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1 at slave(1)@172.17.25.124:37666 (b4d97f439470) with total oversubscribed resources
I1201 20:26:55.178338 30587 hierarchical.cpp:434] Slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1 (b4d97f439470) updated with oversubscribed resources (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: )
I1201 20:26:55.178434 30587 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:55.178452 30587 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:55.178462 30587 hierarchical.cpp:973] Performed allocation for slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S1 in 91575ns
I1201 20:26:55.200675 30585 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.446983ms
I1201 20:26:55.200707 30585 replica.cpp:713] Persisted action at 6
I1201 20:26:55.201074 30586 replica.cpp:692] Replica received learned notice for position 6 from @0.0.0.0:0
I1201 20:26:55.230164 30586 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 29.073895ms
I1201 20:26:55.230243 30586 leveldb.cpp:399] Deleting ~2 keys from leveldb took 56081ns
I1201 20:26:55.230273 30586 replica.cpp:713] Persisted action at 6
I1201 20:26:55.230294 30586 replica.cpp:698] Replica learned TRUNCATE action at position 6
I1201 20:26:55.343325 30582 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:55.343364 30582 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:55.343385 30582 hierarchical.cpp:957] Performed allocation for 2 slaves in 179020ns
I1201 20:26:56.226503 30587 slave.cpp:1252] Will retry registration in 2.719153465secs if necessary
I1201 20:26:56.226647 30583 master.cpp:3890] Registering slave at slave(3)@172.17.25.124:37666 (b4d97f439470) with id 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2
I1201 20:26:56.226856 30590 registrar.cpp:439] Applied 1 operations in 75453ns; attempting to update the 'registry'
I1201 20:26:56.229226 30588 log.cpp:683] Attempting to append 679 bytes to the log
I1201 20:26:56.229288 30583 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7
I1201 20:26:56.229871 30585 replica.cpp:538] Replica received write request for position 7 from (48)@172.17.25.124:37666
I1201 20:26:56.263767 30585 leveldb.cpp:341] Persisting action (698 bytes) to leveldb took 33.877362ms
I1201 20:26:56.263797 30585 replica.cpp:713] Persisted action at 7
I1201 20:26:56.264354 30590 replica.cpp:692] Replica received learned notice for position 7 from @0.0.0.0:0
I1201 20:26:56.297381 30590 leveldb.cpp:341] Persisting action (700 bytes) to leveldb took 33.006806ms
I1201 20:26:56.297418 30590 replica.cpp:713] Persisted action at 7
I1201 20:26:56.297433 30590 replica.cpp:698] Replica learned APPEND action at position 7
I1201 20:26:56.298377 30589 registrar.cpp:484] Successfully updated the 'registry' in 71.476992ms
I1201 20:26:56.298579 30586 log.cpp:702] Attempting to truncate the log to 7
I1201 20:26:56.298735 30591 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8
I1201 20:26:56.299036 30591 slave.cpp:3197] Received ping from slave-observer(3)@172.17.25.124:37666
I1201 20:26:56.299201 30581 replica.cpp:538] Replica received write request for position 8 from (49)@172.17.25.124:37666
I1201 20:26:56.299185 30594 master.cpp:3958] Registered slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2 at slave(3)@172.17.25.124:37666 (b4d97f439470) with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000]
I1201 20:26:56.299268 30589 hierarchical.cpp:380] Added slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2 (b4d97f439470) with cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000] (allocated: )
I1201 20:26:56.299201 30586 slave.cpp:902] Registered with master master@172.17.25.124:37666; given slave ID 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2
I1201 20:26:56.299330 30586 fetcher.cpp:79] Clearing fetcher cache
I1201 20:26:56.299381 30589 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:56.299406 30585 status_update_manager.cpp:181] Resuming sending status updates
I1201 20:26:56.299407 30589 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:56.299438 30589 hierarchical.cpp:973] Performed allocation for slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2 in 137246ns
I1201 20:26:56.299690 30586 slave.cpp:925] Checkpointing SlaveInfo to '/tmp/mesos-ybXGT3/2/meta/slaves/6166a26c-9d72-41fe-82f4-26b8f83cd643-S2/slave.info'
I1201 20:26:56.300017 30586 slave.cpp:961] Forwarding total oversubscribed resources
I1201 20:26:56.300089 30586 master.cpp:4300] Received update of slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2 at slave(3)@172.17.25.124:37666 (b4d97f439470) with total oversubscribed resources
I1201 20:26:56.300340 30586 hierarchical.cpp:434] Slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2 (b4d97f439470) updated with oversubscribed resources (total: cpus(*):2; mem(*):10240; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: )
I1201 20:26:56.300467 30586 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:56.300498 30586 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:56.300514 30586 hierarchical.cpp:973] Performed allocation for slave 6166a26c-9d72-41fe-82f4-26b8f83cd643-S2 in 143271ns
I1201 20:26:56.338001 30581 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.779462ms
I1201 20:26:56.338038 30581 replica.cpp:713] Persisted action at 8
I1201 20:26:56.338456 30579 replica.cpp:692] Replica received learned notice for position 8 from @0.0.0.0:0
I1201 20:26:56.343799 30587 hierarchical.cpp:1062] No resources available to allocate!
I1201 20:26:56.343827 30587 hierarchical.cpp:1155] No inverse offers to send out!
I1201 20:26:56.343837 30587 hierarchical.cpp:957] Performed allocation for 3 slaves in 154409ns
I1201 20:26:56.364632 30579 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 26.165386ms
I1201 20:26:56.364703 30579 leveldb.cpp:399] Deleting ~2 keys from leveldb took 44631ns
I1201 20:26:56.364719 30579 replica.cpp:713] Persisted action at 8
I1201 20:26:56.364733 30579 replica.cpp:698] Replica learned TRUNCATE action at position 8
+ docker rmi mesos-1448999867-21602
dial unix /var/run/docker.sock: no such file or directory. Are you trying to connect to a TLS-enabled daemon without TLS?
Error: failed to remove images: [mesos-1448999867-21602]
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1302
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1302/changes>