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 2016/03/17 17:31:53 UTC
Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1826
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1826/changes>
Changes:
[yujie.jay] Handled chunked responses in docker URI fetcher.
------------------------------------------
[...truncated 119714 lines...]
I0317 16:31:53.308459 32091 slave.cpp:477] Slave hostname: 76df5a57a9ca
I0317 16:31:53.308542 32087 slave.cpp:859] Authenticating with master master@172.17.0.2:43540
I0317 16:31:53.308567 32087 slave.cpp:864] Using default CRAM-MD5 authenticatee
I0317 16:31:53.309041 32082 authenticatee.cpp:121] Creating new client SASL connection
I0317 16:31:53.309082 32087 slave.cpp:832] Detecting new master
I0317 16:31:53.309103 32095 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 53.756668ms
I0317 16:31:53.309146 32095 replica.cpp:712] Persisted action at 4
I0317 16:31:53.309453 32095 master.cpp:5659] Authenticating slave(293)@172.17.0.2:43540
I0317 16:31:53.309581 32087 slave.cpp:4751] Received oversubscribable resources from the resource estimator
I0317 16:31:53.309686 32096 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(621)@172.17.0.2:43540
I0317 16:31:53.309962 32094 authenticator.cpp:98] Creating new server SASL connection
I0317 16:31:53.310263 32086 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0317 16:31:53.310310 32086 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0317 16:31:53.310451 32095 authenticator.cpp:203] Received SASL authentication start
I0317 16:31:53.310506 32063 containerizer.cpp:149] Using isolation: posix/cpu,posix/mem,filesystem/posix
I0317 16:31:53.310529 32095 authenticator.cpp:325] Authentication requires more steps
I0317 16:31:53.310740 32085 authenticatee.cpp:258] Received SASL authentication step
I0317 16:31:53.310814 32095 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0
I0317 16:31:53.310948 32085 authenticator.cpp:231] Received SASL authentication step
I0317 16:31:53.310983 32085 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0317 16:31:53.310998 32085 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
I0317 16:31:53.311058 32085 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0317 16:31:53.311086 32085 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0317 16:31:53.311099 32085 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.311107 32085 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.311127 32085 authenticator.cpp:317] Authentication success
I0317 16:31:53.311287 32089 authenticatee.cpp:298] Authentication success
I0317 16:31:53.311357 32085 master.cpp:5689] Successfully authenticated principal 'test-principal' at slave(293)@172.17.0.2:43540
I0317 16:31:53.311504 32092 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(621)@172.17.0.2:43540
I0317 16:31:53.312186 32091 state.cpp:58] Recovering state from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_RHO3py/meta'
I0317 16:31:53.312511 32091 status_update_manager.cpp:200] Recovering status update manager
I0317 16:31:53.312705 32089 slave.cpp:927] Successfully authenticated with master master@172.17.0.2:43540
I0317 16:31:53.312767 32086 containerizer.cpp:407] Recovering containerizer
I0317 16:31:53.312860 32089 slave.cpp:1321] Will retry registration in 8.956678ms if necessary
I0317 16:31:53.313186 32089 master.cpp:4370] Registering slave at slave(293)@172.17.0.2:43540 (76df5a57a9ca) with id 0196163d-91f7-4337-9dd7-9fef49e8cd75-S7
W0317 16:31:53.313962 32063 backend.cpp:66] Failed to create 'bind' backend: BindBackend requires root privileges
I0317 16:31:53.316969 32083 provisioner.cpp:245] Provisioner recovery complete
I0317 16:31:53.317064 32090 slave.cpp:193] Slave started on 295)@172.17.0.2:43540
I0317 16:31:53.317153 32090 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/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --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/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/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="posix/cpu,posix/mem" --launcher_dir="/mesos/mesos-0.29.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="10ms" --resources="cpus:2;mem:1024" --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/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1"
I0317 16:31:53.317610 32090 credentials.hpp:83] Loading credential for authentication from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/credential'
I0317 16:31:53.317811 32090 slave.cpp:324] Slave using credential for: test-principal
I0317 16:31:53.317821 32094 slave.cpp:4565] Finished recovery
I0317 16:31:53.317975 32090 resources.cpp:572] Parsing resources as JSON failed: cpus:2;mem:1024
Trying semicolon-delimited string format instead
I0317 16:31:53.318377 32094 slave.cpp:4737] Querying resource estimator for oversubscribable resources
I0317 16:31:53.318447 32090 slave.cpp:464] Slave resources: cpus(*):2; mem(*):1024; disk(*):3.70122e+06; ports(*):[31000-32000]
I0317 16:31:53.318521 32090 slave.cpp:472] Slave attributes: [ ]
I0317 16:31:53.318534 32090 slave.cpp:477] Slave hostname: 76df5a57a9ca
I0317 16:31:53.318650 32084 slave.cpp:796] New master detected at master@172.17.0.2:43540
I0317 16:31:53.318743 32084 slave.cpp:859] Authenticating with master master@172.17.0.2:43540
I0317 16:31:53.318769 32084 slave.cpp:864] Using default CRAM-MD5 authenticatee
I0317 16:31:53.318907 32084 slave.cpp:832] Detecting new master
I0317 16:31:53.319026 32084 slave.cpp:4751] Received oversubscribable resources from the resource estimator
I0317 16:31:53.318663 32094 status_update_manager.cpp:174] Pausing sending status updates
I0317 16:31:53.319288 32096 authenticatee.cpp:121] Creating new client SASL connection
I0317 16:31:53.319649 32063 sched.cpp:222] Version: 0.29.0
I0317 16:31:53.319975 32088 state.cpp:58] Recovering state from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/meta'
I0317 16:31:53.320080 32089 master.cpp:5659] Authenticating slave(294)@172.17.0.2:43540
I0317 16:31:53.320312 32091 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(622)@172.17.0.2:43540
I0317 16:31:53.320755 32096 status_update_manager.cpp:200] Recovering status update manager
I0317 16:31:53.320801 32086 sched.cpp:326] New master detected at master@172.17.0.2:43540
I0317 16:31:53.320883 32086 sched.cpp:382] Authenticating with master master@172.17.0.2:43540
I0317 16:31:53.320905 32086 sched.cpp:389] Using default CRAM-MD5 authenticatee
I0317 16:31:53.320953 32096 containerizer.cpp:407] Recovering containerizer
I0317 16:31:53.321233 32086 authenticatee.cpp:121] Creating new client SASL connection
I0317 16:31:53.321844 32093 master.cpp:5659] Authenticating scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
I0317 16:31:53.321966 32083 authenticator.cpp:98] Creating new server SASL connection
I0317 16:31:53.322016 32091 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(623)@172.17.0.2:43540
I0317 16:31:53.322273 32083 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0317 16:31:53.322300 32091 authenticator.cpp:98] Creating new server SASL connection
I0317 16:31:53.322314 32083 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0317 16:31:53.322499 32083 slave.cpp:1321] Will retry registration in 441332ns if necessary
I0317 16:31:53.322540 32091 authenticator.cpp:203] Received SASL authentication start
I0317 16:31:53.322610 32091 authenticator.cpp:325] Authentication requires more steps
I0317 16:31:53.322630 32086 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0317 16:31:53.322661 32086 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0317 16:31:53.322871 32091 provisioner.cpp:245] Provisioner recovery complete
I0317 16:31:53.322888 32081 authenticator.cpp:203] Received SASL authentication start
I0317 16:31:53.322954 32081 authenticator.cpp:325] Authentication requires more steps
I0317 16:31:53.323045 32081 authenticatee.cpp:258] Received SASL authentication step
I0317 16:31:53.323165 32084 authenticator.cpp:231] Received SASL authentication step
I0317 16:31:53.323202 32084 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0317 16:31:53.323217 32084 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
I0317 16:31:53.323279 32084 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0317 16:31:53.323289 32086 slave.cpp:4565] Finished recovery
I0317 16:31:53.323312 32084 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0317 16:31:53.323324 32084 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.323333 32084 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.323353 32084 authenticator.cpp:317] Authentication success
I0317 16:31:53.323518 32084 authenticatee.cpp:298] Authentication success
I0317 16:31:53.323623 32084 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(623)@172.17.0.2:43540
I0317 16:31:53.323947 32093 master.cpp:4358] Ignoring register slave message from slave(293)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.323961 32086 slave.cpp:4737] Querying resource estimator for oversubscribable resources
I0317 16:31:53.324038 32096 sched.cpp:471] Successfully authenticated with master master@172.17.0.2:43540
I0317 16:31:53.324064 32096 sched.cpp:776] Sending SUBSCRIBE call to master@172.17.0.2:43540
I0317 16:31:53.324170 32096 sched.cpp:809] Will retry registration in 500.472097ms if necessary
I0317 16:31:53.324188 32091 slave.cpp:1321] Will retry registration in 44.448753ms if necessary
I0317 16:31:53.324327 32083 authenticatee.cpp:258] Received SASL authentication step
I0317 16:31:53.324407 32093 master.cpp:5689] Successfully authenticated principal 'test-principal' at scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
I0317 16:31:53.324447 32083 authenticator.cpp:231] Received SASL authentication step
I0317 16:31:53.324481 32083 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0317 16:31:53.324497 32083 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
I0317 16:31:53.324537 32083 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0317 16:31:53.324559 32093 master.cpp:2326] Received SUBSCRIBE call for framework 'default' at scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
I0317 16:31:53.324580 32093 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0317 16:31:53.324573 32083 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0317 16:31:53.324610 32083 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.324621 32083 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.324640 32083 authenticator.cpp:317] Authentication success
I0317 16:31:53.324836 32093 master.cpp:4358] Ignoring register slave message from slave(293)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.324906 32093 master.cpp:5689] Successfully authenticated principal 'test-principal' at slave(294)@172.17.0.2:43540
I0317 16:31:53.324982 32093 master.cpp:2397] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0317 16:31:53.325026 32083 authenticatee.cpp:298] Authentication success
I0317 16:31:53.324930 32085 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(622)@172.17.0.2:43540
I0317 16:31:53.325211 32083 slave.cpp:1321] Will retry registration in 106.056181ms if necessary
I0317 16:31:53.325479 32085 hierarchical.cpp:265] Added framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
I0317 16:31:53.325683 32093 master.cpp:4358] Ignoring register slave message from slave(289)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.325736 32083 sched.cpp:703] Framework registered with 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
I0317 16:31:53.325778 32083 sched.cpp:717] Scheduler::registered took 28022ns
I0317 16:31:53.325789 32081 slave.cpp:796] New master detected at master@172.17.0.2:43540
I0317 16:31:53.325819 32083 status_update_manager.cpp:174] Pausing sending status updates
I0317 16:31:53.325901 32089 slave.cpp:927] Successfully authenticated with master master@172.17.0.2:43540
I0317 16:31:53.325935 32081 slave.cpp:859] Authenticating with master master@172.17.0.2:43540
I0317 16:31:53.325958 32081 slave.cpp:864] Using default CRAM-MD5 authenticatee
I0317 16:31:53.326087 32089 slave.cpp:1321] Will retry registration in 8.262313ms if necessary
I0317 16:31:53.326138 32081 slave.cpp:832] Detecting new master
I0317 16:31:53.326251 32083 authenticatee.cpp:121] Creating new client SASL connection
I0317 16:31:53.326310 32085 hierarchical.cpp:1548] No inverse offers to send out!
I0317 16:31:53.326351 32085 hierarchical.cpp:1130] Performed allocation for 1 slaves in 857164ns
I0317 16:31:53.326419 32089 master.cpp:4370] Registering slave at slave(294)@172.17.0.2:43540 (76df5a57a9ca) with id 0196163d-91f7-4337-9dd7-9fef49e8cd75-S8
I0317 16:31:53.326822 32081 slave.cpp:4751] Received oversubscribable resources from the resource estimator
I0317 16:31:53.327114 32089 master.cpp:5488] Sending 1 offers to framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 (default) at scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
I0317 16:31:53.327725 32089 master.cpp:5659] Authenticating slave(295)@172.17.0.2:43540
I0317 16:31:53.327816 32094 sched.cpp:873] Scheduler::resourceOffers took 318203ns
I0317 16:31:53.328331 32063 sched.cpp:1903] Asked to stop the driver
I0317 16:31:53.328474 32089 sched.cpp:1143] Stopping framework '0196163d-91f7-4337-9dd7-9fef49e8cd75-0000'
I0317 16:31:53.328629 32083 slave.cpp:1321] Will retry registration in 13.899081ms if necessary
I0317 16:31:53.328737 32092 master.cpp:6061] Processing TEARDOWN call for framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 (default) at scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
I0317 16:31:53.328770 32092 master.cpp:6073] Removing framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 (default) at scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
I0317 16:31:53.328919 32083 hierarchical.cpp:375] Deactivated framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
I0317 16:31:53.328997 32083 slave.cpp:2079] Asked to shut down framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 by master@172.17.0.2:43540
W0317 16:31:53.329027 32083 slave.cpp:2094] Cannot shut down unknown framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
I0317 16:31:53.329593 32083 hierarchical.cpp:890] Recovered cpus(*):2; mem(*):1024; disk(*):3.70122e+06; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: ) on slave 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 from framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
I0317 16:31:53.329941 32092 master.cpp:4358] Ignoring register slave message from slave(290)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.330127 32084 hierarchical.cpp:326] Removed framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
I0317 16:31:53.331360 32094 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(624)@172.17.0.2:43540
I0317 16:31:53.331728 32088 slave.cpp:1321] Will retry registration in 151.78908ms if necessary
I0317 16:31:53.331933 32088 master.cpp:4358] Ignoring register slave message from slave(291)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.332417 32092 authenticator.cpp:98] Creating new server SASL connection
I0317 16:31:53.332729 32092 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0317 16:31:53.332765 32092 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0317 16:31:53.332847 32092 authenticator.cpp:203] Received SASL authentication start
I0317 16:31:53.332921 32092 authenticator.cpp:325] Authentication requires more steps
I0317 16:31:53.333004 32092 authenticatee.cpp:258] Received SASL authentication step
I0317 16:31:53.333106 32092 authenticator.cpp:231] Received SASL authentication step
I0317 16:31:53.333137 32092 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0317 16:31:53.333149 32092 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
I0317 16:31:53.333194 32092 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0317 16:31:53.333241 32092 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: '76df5a57a9ca' server FQDN: '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0317 16:31:53.333266 32092 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.333279 32092 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0317 16:31:53.333302 32092 authenticator.cpp:317] Authentication success
I0317 16:31:53.333482 32092 authenticatee.cpp:298] Authentication success
I0317 16:31:53.333575 32092 master.cpp:5689] Successfully authenticated principal 'test-principal' at slave(295)@172.17.0.2:43540
I0317 16:31:53.333667 32092 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(624)@172.17.0.2:43540
I0317 16:31:53.333971 32092 slave.cpp:927] Successfully authenticated with master master@172.17.0.2:43540
I0317 16:31:53.334074 32092 slave.cpp:1321] Will retry registration in 13.498871ms if necessary
I0317 16:31:53.334384 32092 master.cpp:4370] Registering slave at slave(295)@172.17.0.2:43540 (76df5a57a9ca) with id 0196163d-91f7-4337-9dd7-9fef49e8cd75-S9
I0317 16:31:53.334939 32090 slave.cpp:1321] Will retry registration in 5.494497ms if necessary
I0317 16:31:53.335175 32090 master.cpp:4358] Ignoring register slave message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.335952 32086 slave.cpp:668] Slave terminating
I0317 16:31:53.336267 32086 master.cpp:1212] Slave 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 at slave(286)@172.17.0.2:43540 (76df5a57a9ca) disconnected
I0317 16:31:53.336303 32086 master.cpp:2681] Disconnecting slave 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 at slave(286)@172.17.0.2:43540 (76df5a57a9ca)
I0317 16:31:53.336365 32086 master.cpp:2700] Deactivating slave 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 at slave(286)@172.17.0.2:43540 (76df5a57a9ca)
I0317 16:31:53.340410 32086 hierarchical.cpp:560] Slave 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 deactivated
I0317 16:31:53.340770 32063 slave.cpp:668] Slave terminating
I0317 16:31:53.343119 32095 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.291311ms
I0317 16:31:53.343245 32095 leveldb.cpp:399] Deleting ~2 keys from leveldb took 91711ns
I0317 16:31:53.343247 32086 slave.cpp:1321] Will retry registration in 337182ns if necessary
I0317 16:31:53.343281 32095 replica.cpp:712] Persisted action at 4
I0317 16:31:53.343315 32095 replica.cpp:697] Replica learned TRUNCATE action at position 4
I0317 16:31:53.343489 32082 master.cpp:4358] Ignoring register slave message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.344532 32086 slave.cpp:1321] Will retry registration in 55.726101ms if necessary
I0317 16:31:53.344867 32091 master.cpp:4358] Ignoring register slave message from slave(290)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.345196 32086 slave.cpp:668] Slave terminating
I0317 16:31:53.345613 32086 slave.cpp:1321] Will retry registration in 25.184692ms if necessary
I0317 16:31:53.345805 32086 master.cpp:4358] Ignoring register slave message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.348780 32092 slave.cpp:1321] Will retry registration in 34.731801ms if necessary
I0317 16:31:53.349040 32086 master.cpp:4358] Ignoring register slave message from slave(295)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.349493 32082 log.cpp:683] Attempting to append 1003 bytes to the log
I0317 16:31:53.349710 32096 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5
I0317 16:31:53.351014 32063 slave.cpp:668] Slave terminating
I0317 16:31:53.351953 32096 replica.cpp:537] Replica received write request for position 5 from (9622)@172.17.0.2:43540
I0317 16:31:53.356771 32063 slave.cpp:668] Slave terminating
I0317 16:31:53.361335 32063 slave.cpp:668] Slave terminating
I0317 16:31:53.368293 32094 slave.cpp:668] Slave terminating
I0317 16:31:53.371337 32085 slave.cpp:1321] Will retry registration in 155.489751ms if necessary
I0317 16:31:53.371691 32086 slave.cpp:1321] Will retry registration in 245.502849ms if necessary
I0317 16:31:53.373518 32087 master.cpp:4358] Ignoring register slave message from slave(293)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.373689 32087 master.cpp:4358] Ignoring register slave message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.374321 32063 slave.cpp:668] Slave terminating
I0317 16:31:53.385280 32096 leveldb.cpp:341] Persisting action (1022 bytes) to leveldb took 33.297688ms
I0317 16:31:53.385376 32096 replica.cpp:712] Persisted action at 5
I0317 16:31:53.389591 32093 slave.cpp:1321] Will retry registration in 54.982679ms if necessary
I0317 16:31:53.389953 32093 master.cpp:4358] Ignoring register slave message from slave(295)@172.17.0.2:43540 (76df5a57a9ca) as admission is already in progress
I0317 16:31:53.391055 32083 replica.cpp:691] Replica received learned notice for position 5 from @0.0.0.0:0
I0317 16:31:53.393159 32063 slave.cpp:668] Slave terminating
I0317 16:31:53.405388 32087 slave.cpp:668] Slave terminating
I0317 16:31:53.411324 32083 leveldb.cpp:341] Persisting action (1024 bytes) to leveldb took 20.262946ms
I0317 16:31:53.411413 32083 replica.cpp:712] Persisted action at 5
I0317 16:31:53.411450 32083 replica.cpp:697] Replica learned APPEND action at position 5
I0317 16:31:53.421093 32063 master.cpp:1065] Master terminating
I0317 16:31:53.421715 32091 hierarchical.cpp:505] Removed slave 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0
I0317 16:31:53.426599 32083 registrar.cpp:484] Successfully updated the 'registry' in 171.705856ms
I0317 16:31:53.427611 32083 registrar.cpp:439] Applied 5 operations in 243279ns; attempting to update the 'registry'
*** Aborted at 1458232313 (unix time) try "date -d @1458232313" if you are using GNU date ***
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 32063 (TID 0x2ac839b6e700) from PID 0; stack trace: ***
@ 0x2ac861f83ff7 (unknown)
@ 0x2ac861f88159 (unknown)
@ 0x2ac8317cb340 (unknown)
make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[4]: *** [check-local] Segmentation fault
make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[2]: *** [check] Error 2
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1458229922-17682
Untagged: mesos-1458229922-17682:latest
Deleted: 7b1725d71bd85088d72498fabaa68d8f797b262a5aa5ec4a727e3b1e9a1deddf
Deleted: 85b9280e12adf2da920145f06a86d98e6d58df787f79d876377efebe26930fb5
Deleted: 6ed0cd7b7179bb548f1b17acd65f4fe004d9b618a21cbf461d8cdde3e0befcca
Deleted: ba29a21dd8b56db500a62446adc2eb128395490aab03d10f9fcaf69fa1121064
Deleted: fa4c4b289ff8000ff0bdc04f4014c382090b2ba0c87b6da1618a5a5e03ea303f
Deleted: c090f18ab3e8867063d97870c74352bd3c43c53e4fcb53d0bc68af020a6ccbbc
Deleted: 389ff416fcd5614176701aa3f255e38a2b5a2e1f19b94961d59ed99f6ddb6b0f
Deleted: 937cbebaa858ceba207af77ef445557905ac3f203cec6c82c6c059bcd230cf11
Deleted: b6cf9aca2f441408037c70022ed67c5acdd8545904dc18ae8c4a0aa517f0ad7c
Deleted: e2e9d28f81294dacc395c9a8253799474e1410774ce4ce94d5c4b7ffac01334a
Deleted: d3d1a9b5d17a595f23b6de7f91c56ce065e6c42d1e7775600b75d0a5764c0668
Deleted: 0b5e89656454575ff409269c4653eedf185cc7f352bc7c2e45d388138dab8cc5
Deleted: 3878480379a32185c8b963d6dfda1dad01cd2c14249f14bac24233914024a6b0
Deleted: 75ff92d040f53af7695fd1b77e14d332180c5e262085dda77c3842e777af5fba
Deleted: e5abc259bf6ad865d2e9d38975016b6eb8c7bac879427542802e4089b5193b68
Deleted: 1a857c368e1373d119d3588b08cc052fd8f46926ee2bcb4c256922c6523b3266
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1826
Posted by Greg Mann <gr...@mesosphere.io>.
This looks like a new one. Ticket here:
https://issues.apache.org/jira/browse/MESOS-4968
On Thu, Mar 17, 2016 at 9:31 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1826/changes
> >
>
> Changes:
>
> [yujie.jay] Handled chunked responses in docker URI fetcher.
>
> ------------------------------------------
> [...truncated 119714 lines...]
> I0317 16:31:53.308459 32091 slave.cpp:477] Slave hostname: 76df5a57a9ca
> I0317 16:31:53.308542 32087 slave.cpp:859] Authenticating with master
> master@172.17.0.2:43540
> I0317 16:31:53.308567 32087 slave.cpp:864] Using default CRAM-MD5
> authenticatee
> I0317 16:31:53.309041 32082 authenticatee.cpp:121] Creating new client
> SASL connection
> I0317 16:31:53.309082 32087 slave.cpp:832] Detecting new master
> I0317 16:31:53.309103 32095 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 53.756668ms
> I0317 16:31:53.309146 32095 replica.cpp:712] Persisted action at 4
> I0317 16:31:53.309453 32095 master.cpp:5659] Authenticating slave(293)@
> 172.17.0.2:43540
> I0317 16:31:53.309581 32087 slave.cpp:4751] Received oversubscribable
> resources from the resource estimator
> I0317 16:31:53.309686 32096 authenticator.cpp:413] Starting authentication
> session for crammd5_authenticatee(621)@172.17.0.2:43540
> I0317 16:31:53.309962 32094 authenticator.cpp:98] Creating new server SASL
> connection
> I0317 16:31:53.310263 32086 authenticatee.cpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I0317 16:31:53.310310 32086 authenticatee.cpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I0317 16:31:53.310451 32095 authenticator.cpp:203] Received SASL
> authentication start
> I0317 16:31:53.310506 32063 containerizer.cpp:149] Using isolation:
> posix/cpu,posix/mem,filesystem/posix
> I0317 16:31:53.310529 32095 authenticator.cpp:325] Authentication requires
> more steps
> I0317 16:31:53.310740 32085 authenticatee.cpp:258] Received SASL
> authentication step
> I0317 16:31:53.310814 32095 replica.cpp:691] Replica received learned
> notice for position 4 from @0.0.0.0:0
> I0317 16:31:53.310948 32085 authenticator.cpp:231] Received SASL
> authentication step
> I0317 16:31:53.310983 32085 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0317 16:31:53.310998 32085 auxprop.cpp:179] Looking up auxiliary property
> '*userPassword'
> I0317 16:31:53.311058 32085 auxprop.cpp:179] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I0317 16:31:53.311086 32085 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0317 16:31:53.311099 32085 auxprop.cpp:129] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.311107 32085 auxprop.cpp:129] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.311127 32085 authenticator.cpp:317] Authentication success
> I0317 16:31:53.311287 32089 authenticatee.cpp:298] Authentication success
> I0317 16:31:53.311357 32085 master.cpp:5689] Successfully authenticated
> principal 'test-principal' at slave(293)@172.17.0.2:43540
> I0317 16:31:53.311504 32092 authenticator.cpp:431] Authentication session
> cleanup for crammd5_authenticatee(621)@172.17.0.2:43540
> I0317 16:31:53.312186 32091 state.cpp:58] Recovering state from
> '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_RHO3py/meta'
> I0317 16:31:53.312511 32091 status_update_manager.cpp:200] Recovering
> status update manager
> I0317 16:31:53.312705 32089 slave.cpp:927] Successfully authenticated with
> master master@172.17.0.2:43540
> I0317 16:31:53.312767 32086 containerizer.cpp:407] Recovering containerizer
> I0317 16:31:53.312860 32089 slave.cpp:1321] Will retry registration in
> 8.956678ms if necessary
> I0317 16:31:53.313186 32089 master.cpp:4370] Registering slave at
> slave(293)@172.17.0.2:43540 (76df5a57a9ca) with id
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S7
> W0317 16:31:53.313962 32063 backend.cpp:66] Failed to create 'bind'
> backend: BindBackend requires root privileges
> I0317 16:31:53.316969 32083 provisioner.cpp:245] Provisioner recovery
> complete
> I0317 16:31:53.317064 32090 slave.cpp:193] Slave started on 295)@
> 172.17.0.2:43540
> I0317 16:31:53.317153 32090 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/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/credential"
> --default_role="*" --disk_watch_interval="1mins" --docker="docker"
> --docker_kill_orphans="true" --docker_registry="
> https://registry-1.docker.io" --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/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/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="posix/cpu,posix/mem"
> --launcher_dir="/mesos/mesos-0.29.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="10ms"
> --resources="cpus:2;mem:1024" --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/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1"
> I0317 16:31:53.317610 32090 credentials.hpp:83] Loading credential for
> authentication from
> '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/credential'
> I0317 16:31:53.317811 32090 slave.cpp:324] Slave using credential for:
> test-principal
> I0317 16:31:53.317821 32094 slave.cpp:4565] Finished recovery
> I0317 16:31:53.317975 32090 resources.cpp:572] Parsing resources as JSON
> failed: cpus:2;mem:1024
> Trying semicolon-delimited string format instead
> I0317 16:31:53.318377 32094 slave.cpp:4737] Querying resource estimator
> for oversubscribable resources
> I0317 16:31:53.318447 32090 slave.cpp:464] Slave resources: cpus(*):2;
> mem(*):1024; disk(*):3.70122e+06; ports(*):[31000-32000]
> I0317 16:31:53.318521 32090 slave.cpp:472] Slave attributes: [ ]
> I0317 16:31:53.318534 32090 slave.cpp:477] Slave hostname: 76df5a57a9ca
> I0317 16:31:53.318650 32084 slave.cpp:796] New master detected at
> master@172.17.0.2:43540
> I0317 16:31:53.318743 32084 slave.cpp:859] Authenticating with master
> master@172.17.0.2:43540
> I0317 16:31:53.318769 32084 slave.cpp:864] Using default CRAM-MD5
> authenticatee
> I0317 16:31:53.318907 32084 slave.cpp:832] Detecting new master
> I0317 16:31:53.319026 32084 slave.cpp:4751] Received oversubscribable
> resources from the resource estimator
> I0317 16:31:53.318663 32094 status_update_manager.cpp:174] Pausing sending
> status updates
> I0317 16:31:53.319288 32096 authenticatee.cpp:121] Creating new client
> SASL connection
> I0317 16:31:53.319649 32063 sched.cpp:222] Version: 0.29.0
> I0317 16:31:53.319975 32088 state.cpp:58] Recovering state from
> '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_ZYxgO1/meta'
> I0317 16:31:53.320080 32089 master.cpp:5659] Authenticating slave(294)@
> 172.17.0.2:43540
> I0317 16:31:53.320312 32091 authenticator.cpp:413] Starting authentication
> session for crammd5_authenticatee(622)@172.17.0.2:43540
> I0317 16:31:53.320755 32096 status_update_manager.cpp:200] Recovering
> status update manager
> I0317 16:31:53.320801 32086 sched.cpp:326] New master detected at
> master@172.17.0.2:43540
> I0317 16:31:53.320883 32086 sched.cpp:382] Authenticating with master
> master@172.17.0.2:43540
> I0317 16:31:53.320905 32086 sched.cpp:389] Using default CRAM-MD5
> authenticatee
> I0317 16:31:53.320953 32096 containerizer.cpp:407] Recovering containerizer
> I0317 16:31:53.321233 32086 authenticatee.cpp:121] Creating new client
> SASL connection
> I0317 16:31:53.321844 32093 master.cpp:5659] Authenticating
> scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
> I0317 16:31:53.321966 32083 authenticator.cpp:98] Creating new server SASL
> connection
> I0317 16:31:53.322016 32091 authenticator.cpp:413] Starting authentication
> session for crammd5_authenticatee(623)@172.17.0.2:43540
> I0317 16:31:53.322273 32083 authenticatee.cpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I0317 16:31:53.322300 32091 authenticator.cpp:98] Creating new server SASL
> connection
> I0317 16:31:53.322314 32083 authenticatee.cpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I0317 16:31:53.322499 32083 slave.cpp:1321] Will retry registration in
> 441332ns if necessary
> I0317 16:31:53.322540 32091 authenticator.cpp:203] Received SASL
> authentication start
> I0317 16:31:53.322610 32091 authenticator.cpp:325] Authentication requires
> more steps
> I0317 16:31:53.322630 32086 authenticatee.cpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I0317 16:31:53.322661 32086 authenticatee.cpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I0317 16:31:53.322871 32091 provisioner.cpp:245] Provisioner recovery
> complete
> I0317 16:31:53.322888 32081 authenticator.cpp:203] Received SASL
> authentication start
> I0317 16:31:53.322954 32081 authenticator.cpp:325] Authentication requires
> more steps
> I0317 16:31:53.323045 32081 authenticatee.cpp:258] Received SASL
> authentication step
> I0317 16:31:53.323165 32084 authenticator.cpp:231] Received SASL
> authentication step
> I0317 16:31:53.323202 32084 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0317 16:31:53.323217 32084 auxprop.cpp:179] Looking up auxiliary property
> '*userPassword'
> I0317 16:31:53.323279 32084 auxprop.cpp:179] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I0317 16:31:53.323289 32086 slave.cpp:4565] Finished recovery
> I0317 16:31:53.323312 32084 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0317 16:31:53.323324 32084 auxprop.cpp:129] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.323333 32084 auxprop.cpp:129] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.323353 32084 authenticator.cpp:317] Authentication success
> I0317 16:31:53.323518 32084 authenticatee.cpp:298] Authentication success
> I0317 16:31:53.323623 32084 authenticator.cpp:431] Authentication session
> cleanup for crammd5_authenticatee(623)@172.17.0.2:43540
> I0317 16:31:53.323947 32093 master.cpp:4358] Ignoring register slave
> message from slave(293)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.323961 32086 slave.cpp:4737] Querying resource estimator
> for oversubscribable resources
> I0317 16:31:53.324038 32096 sched.cpp:471] Successfully authenticated with
> master master@172.17.0.2:43540
> I0317 16:31:53.324064 32096 sched.cpp:776] Sending SUBSCRIBE call to
> master@172.17.0.2:43540
> I0317 16:31:53.324170 32096 sched.cpp:809] Will retry registration in
> 500.472097ms if necessary
> I0317 16:31:53.324188 32091 slave.cpp:1321] Will retry registration in
> 44.448753ms if necessary
> I0317 16:31:53.324327 32083 authenticatee.cpp:258] Received SASL
> authentication step
> I0317 16:31:53.324407 32093 master.cpp:5689] Successfully authenticated
> principal 'test-principal' at
> scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
> I0317 16:31:53.324447 32083 authenticator.cpp:231] Received SASL
> authentication step
> I0317 16:31:53.324481 32083 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0317 16:31:53.324497 32083 auxprop.cpp:179] Looking up auxiliary property
> '*userPassword'
> I0317 16:31:53.324537 32083 auxprop.cpp:179] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I0317 16:31:53.324559 32093 master.cpp:2326] Received SUBSCRIBE call for
> framework 'default' at
> scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
> I0317 16:31:53.324580 32093 master.cpp:1845] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0317 16:31:53.324573 32083 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0317 16:31:53.324610 32083 auxprop.cpp:129] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.324621 32083 auxprop.cpp:129] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.324640 32083 authenticator.cpp:317] Authentication success
> I0317 16:31:53.324836 32093 master.cpp:4358] Ignoring register slave
> message from slave(293)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.324906 32093 master.cpp:5689] Successfully authenticated
> principal 'test-principal' at slave(294)@172.17.0.2:43540
> I0317 16:31:53.324982 32093 master.cpp:2397] Subscribing framework default
> with checkpointing disabled and capabilities [ ]
> I0317 16:31:53.325026 32083 authenticatee.cpp:298] Authentication success
> I0317 16:31:53.324930 32085 authenticator.cpp:431] Authentication session
> cleanup for crammd5_authenticatee(622)@172.17.0.2:43540
> I0317 16:31:53.325211 32083 slave.cpp:1321] Will retry registration in
> 106.056181ms if necessary
> I0317 16:31:53.325479 32085 hierarchical.cpp:265] Added framework
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
> I0317 16:31:53.325683 32093 master.cpp:4358] Ignoring register slave
> message from slave(289)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.325736 32083 sched.cpp:703] Framework registered with
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
> I0317 16:31:53.325778 32083 sched.cpp:717] Scheduler::registered took
> 28022ns
> I0317 16:31:53.325789 32081 slave.cpp:796] New master detected at
> master@172.17.0.2:43540
> I0317 16:31:53.325819 32083 status_update_manager.cpp:174] Pausing sending
> status updates
> I0317 16:31:53.325901 32089 slave.cpp:927] Successfully authenticated with
> master master@172.17.0.2:43540
> I0317 16:31:53.325935 32081 slave.cpp:859] Authenticating with master
> master@172.17.0.2:43540
> I0317 16:31:53.325958 32081 slave.cpp:864] Using default CRAM-MD5
> authenticatee
> I0317 16:31:53.326087 32089 slave.cpp:1321] Will retry registration in
> 8.262313ms if necessary
> I0317 16:31:53.326138 32081 slave.cpp:832] Detecting new master
> I0317 16:31:53.326251 32083 authenticatee.cpp:121] Creating new client
> SASL connection
> I0317 16:31:53.326310 32085 hierarchical.cpp:1548] No inverse offers to
> send out!
> I0317 16:31:53.326351 32085 hierarchical.cpp:1130] Performed allocation
> for 1 slaves in 857164ns
> I0317 16:31:53.326419 32089 master.cpp:4370] Registering slave at
> slave(294)@172.17.0.2:43540 (76df5a57a9ca) with id
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S8
> I0317 16:31:53.326822 32081 slave.cpp:4751] Received oversubscribable
> resources from the resource estimator
> I0317 16:31:53.327114 32089 master.cpp:5488] Sending 1 offers to framework
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 (default) at
> scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
> I0317 16:31:53.327725 32089 master.cpp:5659] Authenticating slave(295)@
> 172.17.0.2:43540
> I0317 16:31:53.327816 32094 sched.cpp:873] Scheduler::resourceOffers took
> 318203ns
> I0317 16:31:53.328331 32063 sched.cpp:1903] Asked to stop the driver
> I0317 16:31:53.328474 32089 sched.cpp:1143] Stopping framework
> '0196163d-91f7-4337-9dd7-9fef49e8cd75-0000'
> I0317 16:31:53.328629 32083 slave.cpp:1321] Will retry registration in
> 13.899081ms if necessary
> I0317 16:31:53.328737 32092 master.cpp:6061] Processing TEARDOWN call for
> framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 (default) at
> scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
> I0317 16:31:53.328770 32092 master.cpp:6073] Removing framework
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 (default) at
> scheduler-b6838900-3b2b-49c2-abcb-b00ea1c734f4@172.17.0.2:43540
> I0317 16:31:53.328919 32083 hierarchical.cpp:375] Deactivated framework
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
> I0317 16:31:53.328997 32083 slave.cpp:2079] Asked to shut down framework
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000 by master@172.17.0.2:43540
> W0317 16:31:53.329027 32083 slave.cpp:2094] Cannot shut down unknown
> framework 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
> I0317 16:31:53.329593 32083 hierarchical.cpp:890] Recovered cpus(*):2;
> mem(*):1024; disk(*):3.70122e+06; ports(*):[31000-32000] (total: cpus(*):2;
> mem(*):1024; disk(*):3.70122e+06; ports(*):[31000-32000], allocated: ) on
> slave 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 from framework
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
> I0317 16:31:53.329941 32092 master.cpp:4358] Ignoring register slave
> message from slave(290)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.330127 32084 hierarchical.cpp:326] Removed framework
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-0000
> I0317 16:31:53.331360 32094 authenticator.cpp:413] Starting authentication
> session for crammd5_authenticatee(624)@172.17.0.2:43540
> I0317 16:31:53.331728 32088 slave.cpp:1321] Will retry registration in
> 151.78908ms if necessary
> I0317 16:31:53.331933 32088 master.cpp:4358] Ignoring register slave
> message from slave(291)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.332417 32092 authenticator.cpp:98] Creating new server SASL
> connection
> I0317 16:31:53.332729 32092 authenticatee.cpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I0317 16:31:53.332765 32092 authenticatee.cpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I0317 16:31:53.332847 32092 authenticator.cpp:203] Received SASL
> authentication start
> I0317 16:31:53.332921 32092 authenticator.cpp:325] Authentication requires
> more steps
> I0317 16:31:53.333004 32092 authenticatee.cpp:258] Received SASL
> authentication step
> I0317 16:31:53.333106 32092 authenticator.cpp:231] Received SASL
> authentication step
> I0317 16:31:53.333137 32092 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0317 16:31:53.333149 32092 auxprop.cpp:179] Looking up auxiliary property
> '*userPassword'
> I0317 16:31:53.333194 32092 auxprop.cpp:179] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I0317 16:31:53.333241 32092 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: '76df5a57a9ca' server FQDN:
> '76df5a57a9ca' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0317 16:31:53.333266 32092 auxprop.cpp:129] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.333279 32092 auxprop.cpp:129] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0317 16:31:53.333302 32092 authenticator.cpp:317] Authentication success
> I0317 16:31:53.333482 32092 authenticatee.cpp:298] Authentication success
> I0317 16:31:53.333575 32092 master.cpp:5689] Successfully authenticated
> principal 'test-principal' at slave(295)@172.17.0.2:43540
> I0317 16:31:53.333667 32092 authenticator.cpp:431] Authentication session
> cleanup for crammd5_authenticatee(624)@172.17.0.2:43540
> I0317 16:31:53.333971 32092 slave.cpp:927] Successfully authenticated with
> master master@172.17.0.2:43540
> I0317 16:31:53.334074 32092 slave.cpp:1321] Will retry registration in
> 13.498871ms if necessary
> I0317 16:31:53.334384 32092 master.cpp:4370] Registering slave at
> slave(295)@172.17.0.2:43540 (76df5a57a9ca) with id
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S9
> I0317 16:31:53.334939 32090 slave.cpp:1321] Will retry registration in
> 5.494497ms if necessary
> I0317 16:31:53.335175 32090 master.cpp:4358] Ignoring register slave
> message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.335952 32086 slave.cpp:668] Slave terminating
> I0317 16:31:53.336267 32086 master.cpp:1212] Slave
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 at slave(286)@172.17.0.2:43540
> (76df5a57a9ca) disconnected
> I0317 16:31:53.336303 32086 master.cpp:2681] Disconnecting slave
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 at slave(286)@172.17.0.2:43540
> (76df5a57a9ca)
> I0317 16:31:53.336365 32086 master.cpp:2700] Deactivating slave
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 at slave(286)@172.17.0.2:43540
> (76df5a57a9ca)
> I0317 16:31:53.340410 32086 hierarchical.cpp:560] Slave
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0 deactivated
> I0317 16:31:53.340770 32063 slave.cpp:668] Slave terminating
> I0317 16:31:53.343119 32095 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 32.291311ms
> I0317 16:31:53.343245 32095 leveldb.cpp:399] Deleting ~2 keys from leveldb
> took 91711ns
> I0317 16:31:53.343247 32086 slave.cpp:1321] Will retry registration in
> 337182ns if necessary
> I0317 16:31:53.343281 32095 replica.cpp:712] Persisted action at 4
> I0317 16:31:53.343315 32095 replica.cpp:697] Replica learned TRUNCATE
> action at position 4
> I0317 16:31:53.343489 32082 master.cpp:4358] Ignoring register slave
> message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.344532 32086 slave.cpp:1321] Will retry registration in
> 55.726101ms if necessary
> I0317 16:31:53.344867 32091 master.cpp:4358] Ignoring register slave
> message from slave(290)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.345196 32086 slave.cpp:668] Slave terminating
> I0317 16:31:53.345613 32086 slave.cpp:1321] Will retry registration in
> 25.184692ms if necessary
> I0317 16:31:53.345805 32086 master.cpp:4358] Ignoring register slave
> message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.348780 32092 slave.cpp:1321] Will retry registration in
> 34.731801ms if necessary
> I0317 16:31:53.349040 32086 master.cpp:4358] Ignoring register slave
> message from slave(295)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.349493 32082 log.cpp:683] Attempting to append 1003 bytes
> to the log
> I0317 16:31:53.349710 32096 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 5
> I0317 16:31:53.351014 32063 slave.cpp:668] Slave terminating
> I0317 16:31:53.351953 32096 replica.cpp:537] Replica received write
> request for position 5 from (9622)@172.17.0.2:43540
> I0317 16:31:53.356771 32063 slave.cpp:668] Slave terminating
> I0317 16:31:53.361335 32063 slave.cpp:668] Slave terminating
> I0317 16:31:53.368293 32094 slave.cpp:668] Slave terminating
> I0317 16:31:53.371337 32085 slave.cpp:1321] Will retry registration in
> 155.489751ms if necessary
> I0317 16:31:53.371691 32086 slave.cpp:1321] Will retry registration in
> 245.502849ms if necessary
> I0317 16:31:53.373518 32087 master.cpp:4358] Ignoring register slave
> message from slave(293)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.373689 32087 master.cpp:4358] Ignoring register slave
> message from slave(294)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.374321 32063 slave.cpp:668] Slave terminating
> I0317 16:31:53.385280 32096 leveldb.cpp:341] Persisting action (1022
> bytes) to leveldb took 33.297688ms
> I0317 16:31:53.385376 32096 replica.cpp:712] Persisted action at 5
> I0317 16:31:53.389591 32093 slave.cpp:1321] Will retry registration in
> 54.982679ms if necessary
> I0317 16:31:53.389953 32093 master.cpp:4358] Ignoring register slave
> message from slave(295)@172.17.0.2:43540 (76df5a57a9ca) as admission is
> already in progress
> I0317 16:31:53.391055 32083 replica.cpp:691] Replica received learned
> notice for position 5 from @0.0.0.0:0
> I0317 16:31:53.393159 32063 slave.cpp:668] Slave terminating
> I0317 16:31:53.405388 32087 slave.cpp:668] Slave terminating
> I0317 16:31:53.411324 32083 leveldb.cpp:341] Persisting action (1024
> bytes) to leveldb took 20.262946ms
> I0317 16:31:53.411413 32083 replica.cpp:712] Persisted action at 5
> I0317 16:31:53.411450 32083 replica.cpp:697] Replica learned APPEND action
> at position 5
> I0317 16:31:53.421093 32063 master.cpp:1065] Master terminating
> I0317 16:31:53.421715 32091 hierarchical.cpp:505] Removed slave
> 0196163d-91f7-4337-9dd7-9fef49e8cd75-S0
> I0317 16:31:53.426599 32083 registrar.cpp:484] Successfully updated the
> 'registry' in 171.705856ms
> I0317 16:31:53.427611 32083 registrar.cpp:439] Applied 5 operations in
> 243279ns; attempting to update the 'registry'
> *** Aborted at 1458232313 (unix time) try "date -d @1458232313" if you are
> using GNU date ***
> PC: @ 0x0 (unknown)
> *** SIGSEGV (@0x0) received by PID 32063 (TID 0x2ac839b6e700) from PID 0;
> stack trace: ***
> @ 0x2ac861f83ff7 (unknown)
> @ 0x2ac861f88159 (unknown)
> @ 0x2ac8317cb340 (unknown)
> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[4]: *** [check-local] Segmentation fault
> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
> make[2]: *** [check] Error 2
> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
> make[1]: *** [check-recursive] Error 1
> make: *** [distcheck] Error 1
> + docker rmi mesos-1458229922-17682
> Untagged: mesos-1458229922-17682:latest
> Deleted: 7b1725d71bd85088d72498fabaa68d8f797b262a5aa5ec4a727e3b1e9a1deddf
> Deleted: 85b9280e12adf2da920145f06a86d98e6d58df787f79d876377efebe26930fb5
> Deleted: 6ed0cd7b7179bb548f1b17acd65f4fe004d9b618a21cbf461d8cdde3e0befcca
> Deleted: ba29a21dd8b56db500a62446adc2eb128395490aab03d10f9fcaf69fa1121064
> Deleted: fa4c4b289ff8000ff0bdc04f4014c382090b2ba0c87b6da1618a5a5e03ea303f
> Deleted: c090f18ab3e8867063d97870c74352bd3c43c53e4fcb53d0bc68af020a6ccbbc
> Deleted: 389ff416fcd5614176701aa3f255e38a2b5a2e1f19b94961d59ed99f6ddb6b0f
> Deleted: 937cbebaa858ceba207af77ef445557905ac3f203cec6c82c6c059bcd230cf11
> Deleted: b6cf9aca2f441408037c70022ed67c5acdd8545904dc18ae8c4a0aa517f0ad7c
> Deleted: e2e9d28f81294dacc395c9a8253799474e1410774ce4ce94d5c4b7ffac01334a
> Deleted: d3d1a9b5d17a595f23b6de7f91c56ce065e6c42d1e7775600b75d0a5764c0668
> Deleted: 0b5e89656454575ff409269c4653eedf185cc7f352bc7c2e45d388138dab8cc5
> Deleted: 3878480379a32185c8b963d6dfda1dad01cd2c14249f14bac24233914024a6b0
> Deleted: 75ff92d040f53af7695fd1b77e14d332180c5e262085dda77c3842e777af5fba
> Deleted: e5abc259bf6ad865d2e9d38975016b6eb8c7bac879427542802e4089b5193b68
> Deleted: 1a857c368e1373d119d3588b08cc052fd8f46926ee2bcb4c256922c6523b3266
> Build step 'Execute shell' marked build as failure
>
Jenkins build is back to normal : Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1827
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1827/changes>