See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2368/changes>
Changes: [yujie.jay] Replaced PKG_CHECK_MODULES with AC_CHECKs in configure.ac. [yujie.jay] Replaced PKG_CHECK_MODULES with AC_CHECKs in libprocess configure.ac. ------------------------------------------ [...truncated 23561 lines...] I0827 20:36:23.616559 18037 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0) I0827 20:36:23.616574 18037 group.cpp:385] Trying to create path '/znode/log_replicas' in ZooKeeper I0827 20:36:23.616781 18034 group.cpp:313] Group process (group(4)@67.195.81.186:49273) connected to ZooKeeper I0827 20:36:23.616821 18034 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0) I0827 20:36:23.616833 18034 group.cpp:385] Trying to create path '/znode' in ZooKeeper 2014-08-27 20:36:23,617:18008(0x2aca63df6700):ZOO_INFO@check_events@1750: session establishment complete on server [127.0.0.1:56082], sessionId=0x148192eff150003, negotiated timeout=10000 I0827 20:36:23.617689 18031 group.cpp:313] Group process (group(2)@67.195.81.186:49273) connected to ZooKeeper I0827 20:36:23.617713 18031 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (1, 0, 0) I0827 20:36:23.617725 18031 group.cpp:385] Trying to create path '/znode/log_replicas' in ZooKeeper I0827 20:36:23.638625 18029 contender.cpp:247] New candidate (id='0') has entered the contest for leadership I0827 20:36:23.641109 18034 group.cpp:714] Found non-sequence node 'log_replicas' at '/znode' in ZooKeeper I0827 20:36:23.641260 18035 detector.cpp:138] Detected a new leader: (id='0') I0827 20:36:23.641393 18026 group.cpp:714] Found non-sequence node 'log_replicas' at '/znode' in ZooKeeper I0827 20:36:23.641561 18022 group.cpp:658] Trying to get '/znode/info_0000000000' in ZooKeeper I0827 20:36:23.645103 18035 detector.cpp:426] A new leading master (UPID=master@67.195.81.186:49273) is detected I0827 20:36:23.645179 18024 master.cpp:1205] The newly elected leader is master@67.195.81.186:49273 with id 20140827-203623-3125920579-49273-18008 I0827 20:36:23.645206 18024 master.cpp:1218] Elected as the leading master! I0827 20:36:23.645217 18024 master.cpp:1036] Recovering from registrar I0827 20:36:23.645447 18030 registrar.cpp:313] Recovering registrar I0827 20:36:23.645555 18029 network.hpp:423] ZooKeeper group memberships changed I0827 20:36:23.645757 18030 group.cpp:658] Trying to get '/znode/log_replicas/0000000000' in ZooKeeper I0827 20:36:23.645807 18036 log.cpp:656] Attempting to start the writer I0827 20:36:23.646709 18027 replica.cpp:474] Replica received implicit promise request with proposal 1 I0827 20:36:23.647060 18028 network.hpp:461] ZooKeeper group PIDs: { log-replica(134)@67.195.81.186:49273 } I0827 20:36:23.647233 18027 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 499696ns I0827 20:36:23.647254 18027 replica.cpp:342] Persisted promised to 1 I0827 20:36:23.647662 18026 coordinator.cpp:230] Coordinator attemping to fill missing position I0827 20:36:23.648377 18027 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0827 20:36:23.648510 18027 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 108878ns I0827 20:36:23.648530 18027 replica.cpp:676] Persisted action at 0 I0827 20:36:23.649034 18033 replica.cpp:508] Replica received write request for position 0 I0827 20:36:23.649071 18033 leveldb.cpp:438] Reading position from leveldb took 16241ns I0827 20:36:23.649427 18033 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 334420ns I0827 20:36:23.649446 18033 replica.cpp:676] Persisted action at 0 I0827 20:36:23.649771 18036 replica.cpp:655] Replica received learned notice for position 0 I0827 20:36:23.649911 18036 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 117325ns I0827 20:36:23.649940 18036 replica.cpp:676] Persisted action at 0 I0827 20:36:23.649955 18036 replica.cpp:661] Replica learned NOP action at position 0 I0827 20:36:23.650184 18036 log.cpp:672] Writer started with ending position 0 I0827 20:36:23.650632 18029 leveldb.cpp:438] Reading position from leveldb took 11101ns I0827 20:36:23.652402 18022 registrar.cpp:346] Successfully fetched the registry (0B) I0827 20:36:23.652446 18022 registrar.cpp:422] Attempting to update the 'registry' I0827 20:36:23.655201 18036 log.cpp:680] Attempting to append 140 bytes to the log I0827 20:36:23.655402 18026 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I0827 20:36:23.655912 18033 replica.cpp:508] Replica received write request for position 1 I0827 20:36:23.656443 18033 leveldb.cpp:343] Persisting action (159 bytes) to leveldb took 492030ns I0827 20:36:23.656501 18033 replica.cpp:676] Persisted action at 1 I0827 20:36:23.657109 18026 replica.cpp:655] Replica received learned notice for position 1 I0827 20:36:23.657611 18026 leveldb.cpp:343] Persisting action (161 bytes) to leveldb took 439685ns I0827 20:36:23.657670 18026 replica.cpp:676] Persisted action at 1 I0827 20:36:23.657682 18026 replica.cpp:661] Replica learned APPEND action at position 1 I0827 20:36:23.657999 18029 registrar.cpp:479] Successfully updated 'registry' I0827 20:36:23.658115 18037 log.cpp:699] Attempting to truncate the log to 1 I0827 20:36:23.658145 18029 registrar.cpp:372] Successfully recovered registrar I0827 20:36:23.658180 18030 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I0827 20:36:23.658332 18034 master.cpp:1063] Recovered 0 slaves from the Registry (102B) ; allowing 10mins for slaves to re-register I0827 20:36:23.658800 18029 replica.cpp:508] Replica received write request for position 2 I0827 20:36:23.658982 18029 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 116369ns I0827 20:36:23.659036 18029 replica.cpp:676] Persisted action at 2 I0827 20:36:23.659327 18031 replica.cpp:655] Replica received learned notice for position 2 I0827 20:36:23.659459 18031 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 111713ns I0827 20:36:23.659488 18031 leveldb.cpp:401] Deleting ~1 keys from leveldb took 12530ns I0827 20:36:23.659498 18031 replica.cpp:676] Persisted action at 2 I0827 20:36:23.659507 18031 replica.cpp:661] Replica learned TRUNCATE action at position 2 2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5 2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@716: Client environment:host.name=penates.apache.org 2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@723: Client environment:os.name=Linux 2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@724: Client environment:os.arch=3.13.0-24-generic 2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@725: Client environment:os.version=#47-Ubuntu SMP Fri May 2 23:30:00 UTC 2014 2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@log_env@733: Client environment:user.name=(null) 2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins 2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@log_env@753: Client environment:user.dir=/tmp/RegistrarZooKeeperTest_TaskRunning_WhHZri 2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=127.0.0.1:56082 sessionTimeout=10000 watcher=0x2ac6abf9f920 sessionId=0 sessionPasswd=<null> context=0x2ac6f4040ec0 flags=0 2014-08-27 20:36:23,670:18008(0x2aca643f9700):ZOO_INFO@check_events@1703: initiated connection to server [127.0.0.1:56082] I0827 20:36:23.670694 18028 slave.cpp:167] Slave started on 83)@67.195.81.186:49273 I0827 20:36:23.670725 18028 credentials.hpp:84] Loading credential for authentication from '/tmp/RegistrarZooKeeperTest_TaskRunning_6FF4jK/credential' I0827 20:36:23.670846 18028 slave.cpp:274] Slave using credential for: test-principal I0827 20:36:23.671012 18028 slave.cpp:287] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0827 20:36:23.671129 18028 slave.cpp:315] Slave hostname: penates.apache.org I0827 20:36:23.671150 18028 slave.cpp:316] Slave checkpoint: false I0827 20:36:23.671577 18032 state.cpp:33] Recovering state from '/tmp/RegistrarZooKeeperTest_TaskRunning_6FF4jK/meta' I0827 20:36:23.671757 18023 status_update_manager.cpp:193] Recovering status update manager I0827 20:36:23.671907 18030 slave.cpp:3204] Finished recovery 2014-08-27 20:36:23,672:18008(0x2aca643f9700):ZOO_INFO@check_events@1750: session establishment complete on server [127.0.0.1:56082], sessionId=0x148192eff150004, negotiated timeout=10000 I0827 20:36:23.672554 18030 group.cpp:313] Group process (group(5)@67.195.81.186:49273) connected to ZooKeeper I0827 20:36:23.672585 18030 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0) I0827 20:36:23.672598 18030 group.cpp:385] Trying to create path '/znode' in ZooKeeper I0827 20:36:23.674561 18030 group.cpp:714] Found non-sequence node 'log_replicas' at '/znode' in ZooKeeper I0827 20:36:23.674681 18022 detector.cpp:138] Detected a new leader: (id='0') I0827 20:36:23.674929 18022 group.cpp:658] Trying to get '/znode/info_0000000000' in ZooKeeper I0827 20:36:23.675407 18008 sched.cpp:137] Version: 0.21.0 I0827 20:36:23.675626 18025 sched.cpp:233] New master detected at master@67.195.81.186:49273 I0827 20:36:23.675662 18025 sched.cpp:283] Authenticating with master master@67.195.81.186:49273 I0827 20:36:23.675747 18035 authenticatee.hpp:128] Creating new client SASL connection I0827 20:36:23.675856 18035 master.cpp:3637] Authenticating scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273 I0827 20:36:23.675967 18026 detector.cpp:426] A new leading master (UPID=master@67.195.81.186:49273) is detected I0827 20:36:23.675981 18023 authenticator.hpp:156] Creating new server SASL connection I0827 20:36:23.676049 18026 slave.cpp:598] New master detected at master@67.195.81.186:49273 I0827 20:36:23.676064 18023 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0827 20:36:23.676090 18023 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0827 20:36:23.676128 18026 slave.cpp:672] Authenticating with master master@67.195.81.186:49273 I0827 20:36:23.676197 18024 status_update_manager.cpp:167] New master detected at master@67.195.81.186:49273 I0827 20:36:23.676213 18035 authenticator.hpp:262] Received SASL authentication start I0827 20:36:23.676285 18035 authenticator.hpp:384] Authentication requires more steps I0827 20:36:23.676373 18023 authenticatee.hpp:265] Received SASL authentication step I0827 20:36:23.676400 18026 slave.cpp:645] Detecting new master I0827 20:36:23.676448 18023 authenticator.hpp:290] Received SASL authentication step I0827 20:36:23.676446 18028 authenticatee.hpp:128] Creating new client SASL connection I0827 20:36:23.676486 18023 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0827 20:36:23.676504 18023 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0827 20:36:23.676527 18023 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0827 20:36:23.676548 18023 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0827 20:36:23.676565 18023 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0827 20:36:23.676579 18023 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0827 20:36:23.676584 18033 master.cpp:3637] Authenticating slave(83)@67.195.81.186:49273 I0827 20:36:23.676606 18023 authenticator.hpp:376] Authentication success I0827 20:36:23.676667 18036 authenticatee.hpp:305] Authentication success I0827 20:36:23.676969 18025 authenticator.hpp:156] Creating new server SASL connection I0827 20:36:23.677011 18033 master.cpp:3677] Successfully authenticated principal 'test-principal' at scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273 I0827 20:36:23.677021 18027 sched.cpp:357] Successfully authenticated with master master@67.195.81.186:49273 I0827 20:36:23.677048 18027 sched.cpp:476] Sending registration request to master@67.195.81.186:49273 I0827 20:36:23.677075 18022 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0827 20:36:23.677106 18022 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0827 20:36:23.677136 18033 master.cpp:1324] Received registration request from scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273 I0827 20:36:23.677150 18022 authenticator.hpp:262] Received SASL authentication start I0827 20:36:23.677168 18033 master.cpp:1284] Authorizing framework principal 'test-principal' to receive offers for role '*' I0827 20:36:23.677192 18022 authenticator.hpp:384] Authentication requires more steps I0827 20:36:23.677285 18022 authenticatee.hpp:265] Received SASL authentication step I0827 20:36:23.677350 18032 authenticator.hpp:290] Received SASL authentication step I0827 20:36:23.677347 18023 master.cpp:1383] Registering framework 20140827-203623-3125920579-49273-18008-0000 at scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273 I0827 20:36:23.677382 18032 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0827 20:36:23.677402 18032 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0827 20:36:23.677420 18032 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0827 20:36:23.677435 18032 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0827 20:36:23.677445 18032 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0827 20:36:23.677448 18022 sched.cpp:407] Framework registered with 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.677454 18032 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0827 20:36:23.677481 18032 authenticator.hpp:376] Authentication success I0827 20:36:23.677490 18022 sched.cpp:421] Scheduler::registered took 21164ns I0827 20:36:23.677500 18034 hierarchical_allocator_process.hpp:329] Added framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.677525 18034 hierarchical_allocator_process.hpp:691] No resources available to allocate! I0827 20:36:23.677538 18034 hierarchical_allocator_process.hpp:653] Performed allocation for 0 slaves in 16302ns I0827 20:36:23.677553 18036 authenticatee.hpp:305] Authentication success I0827 20:36:23.677574 18030 master.cpp:3677] Successfully authenticated principal 'test-principal' at slave(83)@67.195.81.186:49273 I0827 20:36:23.677685 18036 slave.cpp:729] Successfully authenticated with master master@67.195.81.186:49273 I0827 20:36:23.677745 18036 slave.cpp:980] Will retry registration in 19.175231ms if necessary I0827 20:36:23.677798 18032 master.cpp:2836] Registering slave at slave(83)@67.195.81.186:49273 (penates.apache.org) with id 20140827-203623-3125920579-49273-18008-0 I0827 20:36:23.677937 18028 registrar.cpp:422] Attempting to update the 'registry' I0827 20:36:23.679662 18030 log.cpp:680] Attempting to append 337 bytes to the log I0827 20:36:23.679740 18022 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I0827 20:36:23.680212 18026 replica.cpp:508] Replica received write request for position 3 I0827 20:36:23.680418 18026 leveldb.cpp:343] Persisting action (356 bytes) to leveldb took 174539ns I0827 20:36:23.680444 18026 replica.cpp:676] Persisted action at 3 I0827 20:36:23.680752 18032 replica.cpp:655] Replica received learned notice for position 3 I0827 20:36:23.681164 18032 leveldb.cpp:343] Persisting action (358 bytes) to leveldb took 385849ns I0827 20:36:23.681187 18032 replica.cpp:676] Persisted action at 3 I0827 20:36:23.681200 18032 replica.cpp:661] Replica learned APPEND action at position 3 I0827 20:36:23.681562 18037 registrar.cpp:479] Successfully updated 'registry' I0827 20:36:23.681694 18033 log.cpp:699] Attempting to truncate the log to 3 I0827 20:36:23.681710 18022 master.cpp:2876] Registered slave 20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273 (penates.apache.org) I0827 20:36:23.681732 18022 master.cpp:4110] Adding slave 20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0827 20:36:23.681771 18031 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I0827 20:36:23.681834 18025 slave.cpp:763] Registered with master master@67.195.81.186:49273; given slave ID 20140827-203623-3125920579-49273-18008-0 I0827 20:36:23.681970 18029 slave.cpp:2333] Received ping from slave-observer(68)@67.195.81.186:49273 I0827 20:36:23.682057 18030 hierarchical_allocator_process.hpp:442] Added slave 20140827-203623-3125920579-49273-18008-0 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I0827 20:36:23.682168 18030 hierarchical_allocator_process.hpp:728] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140827-203623-3125920579-49273-18008-0 to framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.682314 18030 hierarchical_allocator_process.hpp:673] Performed allocation for slave 20140827-203623-3125920579-49273-18008-0 in 190586ns I0827 20:36:23.682317 18033 replica.cpp:508] Replica received write request for position 4 I0827 20:36:23.682433 18022 master.hpp:857] Adding offer 20140827-203623-3125920579-49273-18008-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140827-203623-3125920579-49273-18008-0 (penates.apache.org) I0827 20:36:23.682464 18033 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 113256ns I0827 20:36:23.682483 18033 replica.cpp:676] Persisted action at 4 I0827 20:36:23.682507 18022 master.cpp:3584] Sending 1 offers to framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.682688 18025 sched.cpp:544] Scheduler::resourceOffers took 38346ns I0827 20:36:23.682838 18037 replica.cpp:655] Replica received learned notice for position 4 I0827 20:36:23.682996 18037 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 129181ns I0827 20:36:23.683121 18037 leveldb.cpp:401] Deleting ~2 keys from leveldb took 61473ns I0827 20:36:23.683146 18037 replica.cpp:676] Persisted action at 4 I0827 20:36:23.683166 18037 replica.cpp:661] Replica learned TRUNCATE action at position 4 I0827 20:36:23.685097 18034 master.hpp:867] Removing offer 20140827-203623-3125920579-49273-18008-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140827-203623-3125920579-49273-18008-0 (penates.apache.org) I0827 20:36:23.685485 18034 master.cpp:2194] Processing reply for offers: [ 20140827-203623-3125920579-49273-18008-0 ] on slave 20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273 (penates.apache.org) for framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.685600 18034 master.cpp:2277] Authorizing framework principal 'test-principal' to launch task 9a5937dd-1dbc-4120-becc-f635cc6656f6 as user 'jenkins' I0827 20:36:23.686669 18034 master.hpp:829] Adding task 9a5937dd-1dbc-4120-becc-f635cc6656f6 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140827-203623-3125920579-49273-18008-0 (penates.apache.org) I0827 20:36:23.686758 18034 master.cpp:2343] Launching task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273 (penates.apache.org) I0827 20:36:23.687325 18029 slave.cpp:1011] Got assigned task 9a5937dd-1dbc-4120-becc-f635cc6656f6 for framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.687593 18029 slave.cpp:1121] Launching task 9a5937dd-1dbc-4120-becc-f635cc6656f6 for framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.691444 18029 exec.cpp:132] Version: 0.21.0 I0827 20:36:23.691726 18030 exec.cpp:182] Executor started at: executor(17)@67.195.81.186:49273 with pid 18008 I0827 20:36:23.691815 18029 slave.cpp:1231] Queuing task '9a5937dd-1dbc-4120-becc-f635cc6656f6' for executor default of framework '20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.691869 18029 slave.cpp:552] Successfully attached file '/tmp/RegistrarZooKeeperTest_TaskRunning_6FF4jK/slaves/20140827-203623-3125920579-49273-18008-0/frameworks/20140827-203623-3125920579-49273-18008-0000/executors/default/runs/2846316d-065d-478c-8bb1-5ad649bc0629' I0827 20:36:23.691915 18029 slave.cpp:2544] Monitoring executor 'default' of framework '20140827-203623-3125920579-49273-18008-0000' in container '2846316d-065d-478c-8bb1-5ad649bc0629' I0827 20:36:23.692044 18029 slave.cpp:1742] Got registration for executor 'default' of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.692399 18029 slave.cpp:1861] Flushing queued task 9a5937dd-1dbc-4120-becc-f635cc6656f6 for executor 'default' of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.692492 18036 exec.cpp:206] Executor registered on slave 20140827-203623-3125920579-49273-18008-0 I0827 20:36:23.694097 18036 exec.cpp:218] Executor::registered took 22140ns I0827 20:36:23.694246 18036 exec.cpp:293] Executor asked to run task '9a5937dd-1dbc-4120-becc-f635cc6656f6' I0827 20:36:23.694299 18036 exec.cpp:302] Executor::launchTask took 33286ns I0827 20:36:23.695788 18036 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.695935 18036 slave.cpp:2096] Handling status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 from executor(17)@67.195.81.186:49273 I0827 20:36:23.696074 18030 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.696099 18030 status_update_manager.cpp:499] Creating StatusUpdate stream for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.696372 18030 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 to master@67.195.81.186:49273 I0827 20:36:23.696583 18030 slave.cpp:2254] Status update manager successfully handled status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.696609 18036 master.cpp:3205] Forwarding status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.696650 18030 slave.cpp:2260] Sending acknowledgement for status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 to executor(17)@67.195.81.186:49273 I0827 20:36:23.696755 18036 master.cpp:3171] Status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 from slave 20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273 (penates.apache.org) I0827 20:36:23.697017 18033 exec.cpp:339] Executor received status update acknowledgement a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.697070 18024 sched.cpp:635] Scheduler::statusUpdate took 63398ns I0827 20:36:23.697154 18024 master.cpp:2686] Forwarding status update acknowledgement a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 to slave 20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273 (penates.apache.org) I0827 20:36:23.697516 18028 status_update_manager.cpp:398] Received status update acknowledgement (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.697532 18008 master.cpp:643] Master terminating I0827 20:36:23.697633 18032 sched.cpp:745] Stopping framework '20140827-203623-3125920579-49273-18008-0000' I0827 20:36:23.697635 18028 slave.cpp:1682] Status update manager successfully handled status update acknowledgement (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.697685 18008 master.hpp:847] Removing task 9a5937dd-1dbc-4120-becc-f635cc6656f6 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140827-203623-3125920579-49273-18008-0 (penates.apache.org) W0827 20:36:23.697896 18008 master.cpp:4403] Removing task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework 20140827-203623-3125920579-49273-18008-0000 and slave 20140827-203623-3125920579-49273-18008-0 in non-terminal state TASK_RUNNING I0827 20:36:23.698776 18036 slave.cpp:2365] master@67.195.81.186:49273 exited W0827 20:36:23.698802 18036 slave.cpp:2368] Master disconnected! Waiting for a new master to be elected I0827 20:36:23.698945 18035 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20140827-203623-3125920579-49273-18008-0 from framework 20140827-203623-3125920579-49273-18008-0000 I0827 20:36:23.699013 18035 hierarchical_allocator_process.hpp:594] Framework 20140827-203623-3125920579-49273-18008-0000 filtered slave 20140827-203623-3125920579-49273-18008-0 for 5secs 2014-08-27 20:36:23,702:18008(0x2ac6b031d700):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x148192eff150003 to [127.0.0.1:56082] 2014-08-27 20:36:23,703:18008(0x2ac6afd1a700):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x148192eff150001 to [127.0.0.1:56082] I0827 20:36:23.705186 18008 contender.cpp:186] Now cancelling the membership: 0 I0827 20:36:23.705526 18027 group.cpp:619] Trying to remove '/znode/info_0000000000' in ZooKeeper W0827 20:36:23.705526 20319 glog.hpp:59] RAW: Received signal SIGPIPE; escalating to SIGABRT *** Aborted at 1409171783 (unix time) try "date -d @1409171783" if you are using GNU date *** PC: @ 0x2ac6add1f20b raise *** SIGABRT (@0x95c00004658) received by PID 18008 (TID 0x2ac6c34f1700) from PID 18008; stack trace: *** @ 0x2ac6add1f340 (unknown) @ 0x2ac6add1f20b raise @ 0x2ac6abd06287 internal::handler() @ 0x2ac6b28b885a call_chained_handler() @ 0x2ac6b28b5deb os::Linux::chained_handler() @ 0x2ac6b28b99fe JVM_handle_linux_signal @ 0x2ac6b28b5cfe signalHandler() @ 0x2ac6add1f340 (unknown) @ 0x2ac6add1e35d __libc_write @ 0x2ac6c30eb4b0 Java_sun_nio_ch_FileDispatcher_write0 @ 0x2ac6b8010c48 (unknown) make[3]: *** [check-local] Aborted make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'> make: *** [check-recursive] Error 1 Test finished Exit status: 2 Build step 'Execute shell' marked build as failure