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/01/29 20:28:04 UTC
Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,docker||Hadoop #1585
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/1585/changes>
Changes:
[yujie.jay] Fixed some typos.
------------------------------------------
[...truncated 69008 lines...]
I0129 19:28:06.946007 30527 master.cpp:5521] Authenticating slave(117)@172.17.0.10:40784
I0129 19:28:06.946188 30527 authenticator.cpp:413] Starting authentication session for crammd5_authenticatee(300)@172.17.0.10:40784
I0129 19:28:06.946534 30527 authenticator.cpp:98] Creating new server SASL connection
I0129 19:28:06.946750 30527 authenticatee.cpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0129 19:28:06.946789 30527 authenticatee.cpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0129 19:28:06.946884 30527 authenticator.cpp:203] Received SASL authentication start
I0129 19:28:06.946949 30527 authenticator.cpp:325] Authentication requires more steps
I0129 19:28:06.947026 30527 authenticatee.cpp:258] Received SASL authentication step
I0129 19:28:06.947115 30527 authenticator.cpp:231] Received SASL authentication step
I0129 19:28:06.947145 30527 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: 'cde29ecb09da' server FQDN: 'cde29ecb09da' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0129 19:28:06.947159 30527 auxprop.cpp:179] Looking up auxiliary property '*userPassword'
I0129 19:28:06.947209 30527 auxprop.cpp:179] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0129 19:28:06.947242 30527 auxprop.cpp:107] Request to lookup properties for user: 'test-principal' realm: 'cde29ecb09da' server FQDN: 'cde29ecb09da' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0129 19:28:06.947255 30527 auxprop.cpp:129] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0129 19:28:06.947264 30527 auxprop.cpp:129] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0129 19:28:06.947283 30527 authenticator.cpp:317] Authentication success
I0129 19:28:06.947470 30532 authenticatee.cpp:298] Authentication success
I0129 19:28:06.947553 30532 master.cpp:5551] Successfully authenticated principal 'test-principal' at slave(117)@172.17.0.10:40784
I0129 19:28:06.947630 30532 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(300)@172.17.0.10:40784
I0129 19:28:06.947942 30529 slave.cpp:4681] Received oversubscribable resources from the resource estimator
I0129 19:28:06.948119 30529 slave.cpp:926] Successfully authenticated with master master@172.17.0.10:40784
I0129 19:28:06.948253 30529 slave.cpp:1320] Will retry registration in 6.419811ms if necessary
I0129 19:28:06.948772 30535 master.cpp:4235] Registering slave at slave(117)@172.17.0.10:40784 (maintenance-host-2) with id 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
I0129 19:28:06.949611 30535 registrar.cpp:439] Applied 1 operations in 244518ns; attempting to update the 'registry'
I0129 19:28:06.951304 30522 log.cpp:683] Attempting to append 518 bytes to the log
I0129 19:28:06.951570 30522 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5
I0129 19:28:06.952713 30522 replica.cpp:537] Replica received write request for position 5 from (4488)@172.17.0.10:40784
I0129 19:28:06.953480 30522 leveldb.cpp:341] Persisting action (537 bytes) to leveldb took 727902ns
I0129 19:28:06.953516 30522 replica.cpp:712] Persisted action at 5
I0129 19:28:06.954491 30535 replica.cpp:691] Replica received learned notice for position 5 from @0.0.0.0:0
I0129 19:28:06.954879 30535 leveldb.cpp:341] Persisting action (539 bytes) to leveldb took 368075ns
I0129 19:28:06.954908 30535 replica.cpp:712] Persisted action at 5
I0129 19:28:06.954984 30535 replica.cpp:697] Replica learned APPEND action at position 5
I0129 19:28:06.956744 30527 slave.cpp:1320] Will retry registration in 6.376082ms if necessary
I0129 19:28:06.957098 30527 master.cpp:4223] Ignoring register slave message from slave(117)@172.17.0.10:40784 (maintenance-host-2) as admission is already in progress
I0129 19:28:06.958451 30525 log.cpp:702] Attempting to truncate the log to 5
I0129 19:28:06.958741 30525 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6
I0129 19:28:06.959534 30522 registrar.cpp:484] Successfully updated the 'registry' in 8.739072ms
I0129 19:28:06.960038 30522 replica.cpp:537] Replica received write request for position 6 from (4489)@172.17.0.10:40784
I0129 19:28:06.960626 30522 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 557176ns
I0129 19:28:06.960659 30522 replica.cpp:712] Persisted action at 6
I0129 19:28:06.960691 30530 master.cpp:4303] Registered slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0129 19:28:06.961015 30530 slave.cpp:970] Registered with master master@172.17.0.10:40784; given slave ID 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
I0129 19:28:06.961041 30530 fetcher.cpp:81] Clearing fetcher cache
I0129 19:28:06.961077 30522 hierarchical.cpp:473] Added slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
I0129 19:28:06.961272 30522 hierarchical.cpp:1403] No resources available to allocate!
I0129 19:28:06.961307 30522 hierarchical.cpp:1116] Performed allocation for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 184508ns
I0129 19:28:06.961450 30530 slave.cpp:993] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/meta/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/slave.info'
I0129 19:28:06.961720 30522 status_update_manager.cpp:181] Resuming sending status updates
I0129 19:28:06.961895 30530 slave.cpp:1029] Forwarding total oversubscribed resources
I0129 19:28:06.962009 30530 slave.cpp:3435] Received ping from slave-observer(119)@172.17.0.10:40784
I0129 19:28:06.962524 30530 master.cpp:4644] Received update of slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784 (maintenance-host-2) with total oversubscribed resources
I0129 19:28:06.962838 30522 replica.cpp:691] Replica received learned notice for position 6 from @0.0.0.0:0
I0129 19:28:06.963052 30530 hierarchical.cpp:531] Slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: )
I0129 19:28:06.963225 30530 hierarchical.cpp:1403] No resources available to allocate!
I0129 19:28:06.963256 30530 hierarchical.cpp:1116] Performed allocation for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 164384ns
I0129 19:28:06.963572 30522 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 711367ns
I0129 19:28:06.963652 30522 leveldb.cpp:399] Deleting ~2 keys from leveldb took 60271ns
I0129 19:28:06.963681 30522 replica.cpp:712] Persisted action at 6
I0129 19:28:06.963714 30522 replica.cpp:697] Replica learned TRUNCATE action at position 6
I0129 19:28:06.966958 30522 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
I0129 19:28:06.967597 30525 http.cpp:503] HTTP POST for /master/maintenance/schedule from 172.17.0.10:52641
I0129 19:28:06.968852 30525 registrar.cpp:439] Applied 1 operations in 257453ns; attempting to update the 'registry'
I0129 19:28:06.970000 30521 log.cpp:683] Attempting to append 741 bytes to the log
I0129 19:28:06.970311 30522 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7
I0129 19:28:06.971256 30524 replica.cpp:537] Replica received write request for position 7 from (4492)@172.17.0.10:40784
I0129 19:28:06.971992 30524 leveldb.cpp:341] Persisting action (760 bytes) to leveldb took 705079ns
I0129 19:28:06.972024 30524 replica.cpp:712] Persisted action at 7
I0129 19:28:06.973152 30533 replica.cpp:691] Replica received learned notice for position 7 from @0.0.0.0:0
I0129 19:28:06.973717 30533 leveldb.cpp:341] Persisting action (762 bytes) to leveldb took 525639ns
I0129 19:28:06.973750 30533 replica.cpp:712] Persisted action at 7
I0129 19:28:06.973779 30533 replica.cpp:697] Replica learned APPEND action at position 7
I0129 19:28:06.976619 30529 registrar.cpp:484] Successfully updated the 'registry' in 7.67488ms
I0129 19:28:06.976933 30533 log.cpp:702] Attempting to truncate the log to 7
I0129 19:28:06.977150 30533 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8
I0129 19:28:06.977303 30529 master.cpp:4700] Updating unavailability of slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784 (maintenance-host-2), starting at 2407.25945761074weeks
I0129 19:28:06.977622 30529 master.cpp:4700] Updating unavailability of slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784 (maintenance-host), starting at 2407.25945761074weeks
I0129 19:28:06.977768 30529 master.cpp:4700] Updating unavailability of slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784 (maintenance-host), starting at 2407.25945761074weeks
I0129 19:28:06.977795 30521 hierarchical.cpp:1403] No resources available to allocate!
I0129 19:28:06.977828 30521 hierarchical.cpp:1116] Performed allocation for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 189380ns
I0129 19:28:06.977900 30529 master.cpp:4700] Updating unavailability of slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784 (maintenance-host-2), starting at 2407.25945761074weeks
I0129 19:28:06.977974 30521 hierarchical.cpp:1403] No resources available to allocate!
I0129 19:28:06.978005 30521 hierarchical.cpp:1116] Performed allocation for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 in 113520ns
I0129 19:28:06.978152 30521 hierarchical.cpp:1403] No resources available to allocate!
I0129 19:28:06.978180 30521 hierarchical.cpp:1116] Performed allocation for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 in 110659ns
I0129 19:28:06.978325 30521 hierarchical.cpp:1403] No resources available to allocate!
I0129 19:28:06.978354 30521 hierarchical.cpp:1116] Performed allocation for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 111175ns
I0129 19:28:06.979660 30524 replica.cpp:537] Replica received write request for position 8 from (4493)@172.17.0.10:40784
I0129 19:28:06.980316 30524 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 602412ns
I0129 19:28:06.980348 30524 replica.cpp:712] Persisted action at 8
I0129 19:28:06.980533 30503 scheduler.cpp:154] Version: 0.28.0
I0129 19:28:06.981590 30535 replica.cpp:691] Replica received learned notice for position 8 from @0.0.0.0:0
I0129 19:28:06.981799 30523 scheduler.cpp:236] New master detected at master@172.17.0.10:40784
I0129 19:28:06.982188 30535 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 578470ns
I0129 19:28:06.982257 30535 leveldb.cpp:399] Deleting ~2 keys from leveldb took 49715ns
I0129 19:28:06.982285 30535 replica.cpp:712] Persisted action at 8
I0129 19:28:06.982314 30535 replica.cpp:697] Replica learned TRUNCATE action at position 8
I0129 19:28:06.991621 30523 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.10:40784
I0129 19:28:06.994441 30530 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0129 19:28:06.995003 30533 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.10:52642
I0129 19:28:06.995275 30533 master.cpp:1972] Received subscription request for HTTP framework 'default'
I0129 19:28:06.995301 30533 master.cpp:1749] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0129 19:28:06.995650 30533 master.cpp:2063] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0129 19:28:06.996767 30530 master.hpp:1658] Sending heartbeat to 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:06.997012 30533 hierarchical.cpp:265] Added framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:06.998599 30522 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.10:40784
I0129 19:28:06.999290 30533 hierarchical.cpp:1096] Performed allocation for 2 slaves in 2.246931ms
I0129 19:28:06.999588 30522 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.10:40784
I0129 19:28:06.999832 30531 master.cpp:5350] Sending 2 offers to framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
I0129 19:28:07.000118 30524 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0129 19:28:07.000816 30531 master.cpp:5440] Sending 2 inverse offers to framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
I0129 19:28:07.003466 30527 scheduler.cpp:457] Enqueuing event OFFERS received from master@172.17.0.10:40784
I0129 19:28:07.004482 30526 scheduler.cpp:457] Enqueuing event OFFERS received from master@172.17.0.10:40784
I0129 19:28:07.015707 30532 hierarchical.cpp:1403] No resources available to allocate!
I0129 19:28:07.016024 30532 hierarchical.cpp:1096] Performed allocation for 2 slaves in 573546ns
I0129 19:28:07.024850 30532 scheduler.cpp:298] Sending ACCEPT call to master@172.17.0.10:40784
I0129 19:28:07.032543 30521 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0129 19:28:07.033124 30531 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.10:52643
I0129 19:28:07.034265 30531 master.cpp:3136] Processing ACCEPT call for offers: [ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O0 ] on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784 (maintenance-host) for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
I0129 19:28:07.034318 30531 master.cpp:2823] Authorizing framework principal 'test-principal' to launch task 285183f9-94b0-42b1-a9ac-760013050fa9 as user 'mesos'
I0129 19:28:07.038235 30532 scheduler.cpp:298] Sending ACCEPT call to master@172.17.0.10:40784
W0129 19:28:07.038614 30531 validation.cpp:404] Executor executor-1 for task 285183f9-94b0-42b1-a9ac-760013050fa9 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0129 19:28:07.038687 30531 validation.cpp:416] Executor executor-1 for task 285183f9-94b0-42b1-a9ac-760013050fa9 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0129 19:28:07.039165 30531 master.hpp:176] Adding task 285183f9-94b0-42b1-a9ac-760013050fa9 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 (maintenance-host)
I0129 19:28:07.039428 30531 master.cpp:3621] Launching task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784 (maintenance-host)
I0129 19:28:07.039952 30531 slave.cpp:1360] Got assigned task 285183f9-94b0-42b1-a9ac-760013050fa9 for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.040659 30531 slave.cpp:1479] Launching task 285183f9-94b0-42b1-a9ac-760013050fa9 for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.040920 30523 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0129 19:28:07.041457 30531 paths.cpp:474] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_zf5ZwD/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S0/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-1/runs/209bc342-2d14-4752-95f9-21b8a235f108' to user 'mesos'
I0129 19:28:07.041544 30536 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.10:52644
I0129 19:28:07.045539 30536 master.cpp:3136] Processing ACCEPT call for offers: [ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O1 ] on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784 (maintenance-host-2) for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
I0129 19:28:07.045624 30536 master.cpp:2823] Authorizing framework principal 'test-principal' to launch task cc072a08-3c25-49f0-b049-9cd2e6707c04 as user 'mesos'
W0129 19:28:07.047829 30536 validation.cpp:404] Executor executor-2 for task cc072a08-3c25-49f0-b049-9cd2e6707c04 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0129 19:28:07.047888 30536 validation.cpp:416] Executor executor-2 for task cc072a08-3c25-49f0-b049-9cd2e6707c04 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0129 19:28:07.048319 30536 master.hpp:176] Adding task cc072a08-3c25-49f0-b049-9cd2e6707c04 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 (maintenance-host-2)
I0129 19:28:07.049053 30536 master.cpp:3621] Launching task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784 (maintenance-host-2)
I0129 19:28:07.050323 30536 slave.cpp:1360] Got assigned task cc072a08-3c25-49f0-b049-9cd2e6707c04 for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.051344 30536 slave.cpp:1479] Launching task cc072a08-3c25-49f0-b049-9cd2e6707c04 for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.051946 30531 slave.cpp:5281] Launching executor executor-1 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_zf5ZwD/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S0/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-1/runs/209bc342-2d14-4752-95f9-21b8a235f108'
I0129 19:28:07.053107 30536 paths.cpp:474] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-2/runs/3546fcc8-d4a6-4b66-84e3-cb5032edaa49' to user 'mesos'
*** Aborted at 1454095687 (unix time) try "date -d @1454095687" if you are using GNU date ***
I0129 19:28:07.060842 30531 exec.cpp:134] Version: 0.28.0
PC: @ 0x2b2faec908dd (unknown)
I0129 19:28:07.061427 30523 exec.cpp:184] Executor started at: executor(48)@172.17.0.10:40784 with pid 30503
I0129 19:28:07.061749 30531 slave.cpp:1697] Queuing task '285183f9-94b0-42b1-a9ac-760013050fa9' for executor 'executor-1' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.061928 30531 slave.cpp:748] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_zf5ZwD/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S0/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-1/runs/209bc342-2d14-4752-95f9-21b8a235f108'
I0129 19:28:07.062142 30531 slave.cpp:2642] Got registration for executor 'executor-1' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from executor(48)@172.17.0.10:40784
I0129 19:28:07.063915 30535 exec.cpp:208] Executor registered on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0
I0129 19:28:07.064000 30535 exec.cpp:220] Executor::registered took 54144ns
I0129 19:28:07.064177 30531 slave.cpp:1862] Sending queued task '285183f9-94b0-42b1-a9ac-760013050fa9' to executor 'executor-1' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 at executor(48)@172.17.0.10:40784
I0129 19:28:07.064534 30531 exec.cpp:295] Executor asked to run task '285183f9-94b0-42b1-a9ac-760013050fa9'
I0129 19:28:07.064632 30531 exec.cpp:304] Executor::launchTask took 82499ns
I0129 19:28:07.064724 30531 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.064982 30531 slave.cpp:3001] Handling status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from executor(48)@172.17.0.10:40784
I0129 19:28:07.065373 30535 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.065460 30535 status_update_manager.cpp:497] Creating StatusUpdate stream for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.066195 30535 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to the slave
I0129 19:28:07.066848 30535 slave.cpp:3353] Forwarding the update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to master@172.17.0.10:40784
I0129 19:28:07.067059 30535 slave.cpp:3247] Status update manager successfully handled status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.067106 30535 slave.cpp:3263] Sending acknowledgement for status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to executor(48)@172.17.0.10:40784
I0129 19:28:07.067440 30535 master.cpp:4789] Status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784 (maintenance-host)
I0129 19:28:07.067498 30535 master.cpp:4837] Forwarding status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.067569 30534 exec.cpp:341] Executor received status update acknowledgement 3d6be9d7-cd05-4803-99ca-139286d024a6 for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.067960 30535 master.cpp:6445] Updating the state of task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0129 19:28:07.070008 30531 scheduler.cpp:457] Enqueuing event UPDATE received from master@172.17.0.10:40784
I0129 19:28:07.073709 30536 slave.cpp:5281] Launching executor executor-2 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-2/runs/3546fcc8-d4a6-4b66-84e3-cb5032edaa49'
I0129 19:28:07.077035 30536 exec.cpp:134] Version: 0.28.0
I0129 19:28:07.077448 30531 exec.cpp:184] Executor started at: executor(49)@172.17.0.10:40784 with pid 30503
I0129 19:28:07.078758 30536 slave.cpp:1697] Queuing task 'cc072a08-3c25-49f0-b049-9cd2e6707c04' for executor 'executor-2' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.078948 30536 slave.cpp:748] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-2/runs/3546fcc8-d4a6-4b66-84e3-cb5032edaa49'
I0129 19:28:07.079035 30536 slave.cpp:2642] Got registration for executor 'executor-2' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from executor(49)@172.17.0.10:40784
I0129 19:28:07.079576 30523 exec.cpp:208] Executor registered on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
I0129 19:28:07.079627 30523 exec.cpp:220] Executor::registered took 32882ns
I0129 19:28:07.081107 30536 slave.cpp:1862] Sending queued task 'cc072a08-3c25-49f0-b049-9cd2e6707c04' to executor 'executor-2' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 at executor(49)@172.17.0.10:40784
I0129 19:28:07.081478 30534 exec.cpp:295] Executor asked to run task 'cc072a08-3c25-49f0-b049-9cd2e6707c04'
I0129 19:28:07.081573 30534 exec.cpp:304] Executor::launchTask took 79174ns
I0129 19:28:07.081671 30534 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.081949 30534 slave.cpp:3001] Handling status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from executor(49)@172.17.0.10:40784
I0129 19:28:07.082386 30534 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.082449 30534 status_update_manager.cpp:497] Creating StatusUpdate stream for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.082908 30534 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to the slave
I0129 19:28:07.083353 30534 slave.cpp:3353] Forwarding the update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to master@172.17.0.10:40784
I0129 19:28:07.083573 30534 slave.cpp:3247] Status update manager successfully handled status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.083622 30534 slave.cpp:3263] Sending acknowledgement for status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to executor(49)@172.17.0.10:40784
*** SIGSEGV (@0x51) received by PID 30503 (TID 0x2b307fe5f700) from PID 81; stack trace: ***
I0129 19:28:07.083884 30534 master.cpp:4789] Status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784 (maintenance-host-2)
I0129 19:28:07.083933 30534 master.cpp:4837] Forwarding status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.084341 30534 master.cpp:6445] Updating the state of task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0129 19:28:07.084470 30534 exec.cpp:341] Executor received status update acknowledgement 7be263f7-843d-40ec-ac41-eef09944bdab for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
@ 0x2b2fbb1e50b7 os::Linux::chained_handler()
I0129 19:28:07.086391 30528 scheduler.cpp:457] Enqueuing event UPDATE received from master@172.17.0.10:40784
@ 0x2b2fbb1e9219 JVM_handle_linux_signal
@ 0x2b2faea47340 (unknown)
@ 0x2b2faec908dd (unknown)
@ 0x2b2faec86681 (unknown)
@ 0x2b2faecdd98e (unknown)
@ 0x2b2faecdd8cf (unknown)
I0129 19:28:07.099602 30528 scheduler.cpp:298] Sending ACKNOWLEDGE call to master@172.17.0.10:40784
@ 0x2b2facc1b0d0 handle_socket_error_msg
I0129 19:28:07.103446 30523 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0129 19:28:07.104017 30523 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.10:52646
I0129 19:28:07.104205 30523 master.cpp:3947] Processing ACKNOWLEDGE call 3d6be9d7-cd05-4803-99ca-139286d024a6 for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0
I0129 19:28:07.104629 30524 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.104986 30523 slave.cpp:2411] Status update manager successfully handled status update acknowledgement (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.111610 30524 scheduler.cpp:298] Sending ACKNOWLEDGE call to master@172.17.0.10:40784
I0129 19:28:07.115684 30534 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0129 19:28:07.116317 30534 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.10:52647
I0129 19:28:07.116556 30534 master.cpp:3947] Processing ACKNOWLEDGE call 7be263f7-843d-40ec-ac41-eef09944bdab for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) on slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
I0129 19:28:07.117308 30534 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.117715 30534 slave.cpp:2411] Status update manager successfully handled status update acknowledgement (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
I0129 19:28:07.120380 30534 scheduler.cpp:298] Sending DECLINE call to master@172.17.0.10:40784
I0129 19:28:07.124797 30527 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0129 19:28:07.125454 30527 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.10:52648
I0129 19:28:07.125619 30527 master.cpp:3673] Processing DECLINE call for offers: [ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O3 ] for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
I0129 19:28:07.126106 30527 hierarchical.cpp:799] Framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 filtered inverse offers from slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 for 101secs
@ 0x2b2facc1bf6b zookeeper_process
I0129 19:28:07.131680 30535 scheduler.cpp:298] Sending ACCEPT call to master@172.17.0.10:40784
I0129 19:28:07.135752 30524 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0129 19:28:07.136337 30524 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.10:52649
W0129 19:28:07.136922 30524 master.cpp:3099] ACCEPT call used invalid offers '[ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O2 ]': Offer 856fb6b3-b596-4de1-bb80-8f3f835ae401-O2 is no longer valid
@ 0x2b2facc2209b do_io
@ 0x2b2faea3f182 start_thread
@ 0x2b2faed4f47d (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1454093743-28431
Untagged: mesos-1454093743-28431:latest
Deleted: 6de59b0c2227923361dcc4d94f701af0b4400c9044eb08e69f0063e1ac9670b0
Deleted: 814c845cb6a7a55b3ad89b8f0e2978d487bda5d6aa7b81ce5ffabea03ea5085c
Deleted: bb09c811f6fbd51fd1bf83d6a74536b4f139a12c85555dec2021b4519beb8e1b
Deleted: ab610143091db1a255a5424e34db29e4f5e039f40c1fbc0dbddf48a4ba9f7647
Deleted: 66f0ea79cb73c72b8bf38144591e79f120e78a8ca7fc392ff1365c4104da304f
Deleted: 5f11f902196edfbaf411d59c207f8f9b234fdd2d3c40405fb4aa45ed31c4969e
Deleted: c4ff5ea5115b9131c6a4fbb57c1d5b771c3a2732439002f7d23d4a2cd2ded15c
Deleted: cdf04b3ddf2841e09980a897de36e492f3a62bf83ad4a143649806353135f233
Deleted: 5469ac0054400dccb6df93ae57abe92fabaa9164a44e391fca15310fe6a1624a
Deleted: c01fb532107e8939af91b6de234f17b8e856a8777c98915499b3bf8cfe20a9f8
Deleted: 725c4b60180f7268aeaaab0fdf4fa6172649fddecc5424a20378c11a6d74c61a
Deleted: ad0bc615de4c918338e0dc11a0c12d7cef1b7af9a7df7c165f4bfececa09e02d
Deleted: 222afef45a828b03a5612fb8ef24e78e82fb82e11a92ae4be68202e0a5a38ef1
Deleted: 6cb378c5ea66d7fa4206800409c06677a8e6384b33f07896790f930c5241cb7b
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 #1586
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/1586/changes>
Re: Build failed in Jenkins: Mesos » clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,docker||Hadoop #1585
Posted by Joseph Wu <jo...@mesosphere.io>.
Looks like this is the same problem as
https://issues.apache.org/jira/browse/MESOS-4554
On Fri, Jan 29, 2016 at 11:28 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/1585/changes
> >
>
> Changes:
>
> [yujie.jay] Fixed some typos.
>
> ------------------------------------------
> [...truncated 69008 lines...]
> I0129 19:28:06.946007 30527 master.cpp:5521] Authenticating slave(117)@
> 172.17.0.10:40784
> I0129 19:28:06.946188 30527 authenticator.cpp:413] Starting authentication
> session for crammd5_authenticatee(300)@172.17.0.10:40784
> I0129 19:28:06.946534 30527 authenticator.cpp:98] Creating new server SASL
> connection
> I0129 19:28:06.946750 30527 authenticatee.cpp:212] Received SASL
> authentication mechanisms: CRAM-MD5
> I0129 19:28:06.946789 30527 authenticatee.cpp:238] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I0129 19:28:06.946884 30527 authenticator.cpp:203] Received SASL
> authentication start
> I0129 19:28:06.946949 30527 authenticator.cpp:325] Authentication requires
> more steps
> I0129 19:28:06.947026 30527 authenticatee.cpp:258] Received SASL
> authentication step
> I0129 19:28:06.947115 30527 authenticator.cpp:231] Received SASL
> authentication step
> I0129 19:28:06.947145 30527 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: 'cde29ecb09da' server FQDN:
> 'cde29ecb09da' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I0129 19:28:06.947159 30527 auxprop.cpp:179] Looking up auxiliary property
> '*userPassword'
> I0129 19:28:06.947209 30527 auxprop.cpp:179] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I0129 19:28:06.947242 30527 auxprop.cpp:107] Request to lookup properties
> for user: 'test-principal' realm: 'cde29ecb09da' server FQDN:
> 'cde29ecb09da' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I0129 19:28:06.947255 30527 auxprop.cpp:129] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I0129 19:28:06.947264 30527 auxprop.cpp:129] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I0129 19:28:06.947283 30527 authenticator.cpp:317] Authentication success
> I0129 19:28:06.947470 30532 authenticatee.cpp:298] Authentication success
> I0129 19:28:06.947553 30532 master.cpp:5551] Successfully authenticated
> principal 'test-principal' at slave(117)@172.17.0.10:40784
> I0129 19:28:06.947630 30532 authenticator.cpp:431] Authentication session
> cleanup for crammd5_authenticatee(300)@172.17.0.10:40784
> I0129 19:28:06.947942 30529 slave.cpp:4681] Received oversubscribable
> resources from the resource estimator
> I0129 19:28:06.948119 30529 slave.cpp:926] Successfully authenticated with
> master master@172.17.0.10:40784
> I0129 19:28:06.948253 30529 slave.cpp:1320] Will retry registration in
> 6.419811ms if necessary
> I0129 19:28:06.948772 30535 master.cpp:4235] Registering slave at
> slave(117)@172.17.0.10:40784 (maintenance-host-2) with id
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
> I0129 19:28:06.949611 30535 registrar.cpp:439] Applied 1 operations in
> 244518ns; attempting to update the 'registry'
> I0129 19:28:06.951304 30522 log.cpp:683] Attempting to append 518 bytes to
> the log
> I0129 19:28:06.951570 30522 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 5
> I0129 19:28:06.952713 30522 replica.cpp:537] Replica received write
> request for position 5 from (4488)@172.17.0.10:40784
> I0129 19:28:06.953480 30522 leveldb.cpp:341] Persisting action (537 bytes)
> to leveldb took 727902ns
> I0129 19:28:06.953516 30522 replica.cpp:712] Persisted action at 5
> I0129 19:28:06.954491 30535 replica.cpp:691] Replica received learned
> notice for position 5 from @0.0.0.0:0
> I0129 19:28:06.954879 30535 leveldb.cpp:341] Persisting action (539 bytes)
> to leveldb took 368075ns
> I0129 19:28:06.954908 30535 replica.cpp:712] Persisted action at 5
> I0129 19:28:06.954984 30535 replica.cpp:697] Replica learned APPEND action
> at position 5
> I0129 19:28:06.956744 30527 slave.cpp:1320] Will retry registration in
> 6.376082ms if necessary
> I0129 19:28:06.957098 30527 master.cpp:4223] Ignoring register slave
> message from slave(117)@172.17.0.10:40784 (maintenance-host-2) as
> admission is already in progress
> I0129 19:28:06.958451 30525 log.cpp:702] Attempting to truncate the log to
> 5
> I0129 19:28:06.958741 30525 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 6
> I0129 19:28:06.959534 30522 registrar.cpp:484] Successfully updated the
> 'registry' in 8.739072ms
> I0129 19:28:06.960038 30522 replica.cpp:537] Replica received write
> request for position 6 from (4489)@172.17.0.10:40784
> I0129 19:28:06.960626 30522 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 557176ns
> I0129 19:28:06.960659 30522 replica.cpp:712] Persisted action at 6
> I0129 19:28:06.960691 30530 master.cpp:4303] Registered slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784
> (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I0129 19:28:06.961015 30530 slave.cpp:970] Registered with master
> master@172.17.0.10:40784; given slave ID
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
> I0129 19:28:06.961041 30530 fetcher.cpp:81] Clearing fetcher cache
> I0129 19:28:06.961077 30522 hierarchical.cpp:473] Added slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 (maintenance-host-2) with
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
> I0129 19:28:06.961272 30522 hierarchical.cpp:1403] No resources available
> to allocate!
> I0129 19:28:06.961307 30522 hierarchical.cpp:1116] Performed allocation
> for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 184508ns
> I0129 19:28:06.961450 30530 slave.cpp:993] Checkpointing SlaveInfo to
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/meta/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/
> slave.info'
> I0129 19:28:06.961720 30522 status_update_manager.cpp:181] Resuming
> sending status updates
> I0129 19:28:06.961895 30530 slave.cpp:1029] Forwarding total
> oversubscribed resources
> I0129 19:28:06.962009 30530 slave.cpp:3435] Received ping from
> slave-observer(119)@172.17.0.10:40784
> I0129 19:28:06.962524 30530 master.cpp:4644] Received update of slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784
> (maintenance-host-2) with total oversubscribed resources
> I0129 19:28:06.962838 30522 replica.cpp:691] Replica received learned
> notice for position 6 from @0.0.0.0:0
> I0129 19:28:06.963052 30530 hierarchical.cpp:531] Slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 (maintenance-host-2) updated with
> oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000], allocated: )
> I0129 19:28:06.963225 30530 hierarchical.cpp:1403] No resources available
> to allocate!
> I0129 19:28:06.963256 30530 hierarchical.cpp:1116] Performed allocation
> for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 164384ns
> I0129 19:28:06.963572 30522 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 711367ns
> I0129 19:28:06.963652 30522 leveldb.cpp:399] Deleting ~2 keys from leveldb
> took 60271ns
> I0129 19:28:06.963681 30522 replica.cpp:712] Persisted action at 6
> I0129 19:28:06.963714 30522 replica.cpp:697] Replica learned TRUNCATE
> action at position 6
> I0129 19:28:06.966958 30522 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/maintenance/schedule'
> I0129 19:28:06.967597 30525 http.cpp:503] HTTP POST for
> /master/maintenance/schedule from 172.17.0.10:52641
> I0129 19:28:06.968852 30525 registrar.cpp:439] Applied 1 operations in
> 257453ns; attempting to update the 'registry'
> I0129 19:28:06.970000 30521 log.cpp:683] Attempting to append 741 bytes to
> the log
> I0129 19:28:06.970311 30522 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 7
> I0129 19:28:06.971256 30524 replica.cpp:537] Replica received write
> request for position 7 from (4492)@172.17.0.10:40784
> I0129 19:28:06.971992 30524 leveldb.cpp:341] Persisting action (760 bytes)
> to leveldb took 705079ns
> I0129 19:28:06.972024 30524 replica.cpp:712] Persisted action at 7
> I0129 19:28:06.973152 30533 replica.cpp:691] Replica received learned
> notice for position 7 from @0.0.0.0:0
> I0129 19:28:06.973717 30533 leveldb.cpp:341] Persisting action (762 bytes)
> to leveldb took 525639ns
> I0129 19:28:06.973750 30533 replica.cpp:712] Persisted action at 7
> I0129 19:28:06.973779 30533 replica.cpp:697] Replica learned APPEND action
> at position 7
> I0129 19:28:06.976619 30529 registrar.cpp:484] Successfully updated the
> 'registry' in 7.67488ms
> I0129 19:28:06.976933 30533 log.cpp:702] Attempting to truncate the log to
> 7
> I0129 19:28:06.977150 30533 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 8
> I0129 19:28:06.977303 30529 master.cpp:4700] Updating unavailability of
> slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@
> 172.17.0.10:40784 (maintenance-host-2), starting at 2407.25945761074weeks
> I0129 19:28:06.977622 30529 master.cpp:4700] Updating unavailability of
> slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@
> 172.17.0.10:40784 (maintenance-host), starting at 2407.25945761074weeks
> I0129 19:28:06.977768 30529 master.cpp:4700] Updating unavailability of
> slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@
> 172.17.0.10:40784 (maintenance-host), starting at 2407.25945761074weeks
> I0129 19:28:06.977795 30521 hierarchical.cpp:1403] No resources available
> to allocate!
> I0129 19:28:06.977828 30521 hierarchical.cpp:1116] Performed allocation
> for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 189380ns
> I0129 19:28:06.977900 30529 master.cpp:4700] Updating unavailability of
> slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@
> 172.17.0.10:40784 (maintenance-host-2), starting at 2407.25945761074weeks
> I0129 19:28:06.977974 30521 hierarchical.cpp:1403] No resources available
> to allocate!
> I0129 19:28:06.978005 30521 hierarchical.cpp:1116] Performed allocation
> for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 in 113520ns
> I0129 19:28:06.978152 30521 hierarchical.cpp:1403] No resources available
> to allocate!
> I0129 19:28:06.978180 30521 hierarchical.cpp:1116] Performed allocation
> for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 in 110659ns
> I0129 19:28:06.978325 30521 hierarchical.cpp:1403] No resources available
> to allocate!
> I0129 19:28:06.978354 30521 hierarchical.cpp:1116] Performed allocation
> for slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 in 111175ns
> I0129 19:28:06.979660 30524 replica.cpp:537] Replica received write
> request for position 8 from (4493)@172.17.0.10:40784
> I0129 19:28:06.980316 30524 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 602412ns
> I0129 19:28:06.980348 30524 replica.cpp:712] Persisted action at 8
> I0129 19:28:06.980533 30503 scheduler.cpp:154] Version: 0.28.0
> I0129 19:28:06.981590 30535 replica.cpp:691] Replica received learned
> notice for position 8 from @0.0.0.0:0
> I0129 19:28:06.981799 30523 scheduler.cpp:236] New master detected at
> master@172.17.0.10:40784
> I0129 19:28:06.982188 30535 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 578470ns
> I0129 19:28:06.982257 30535 leveldb.cpp:399] Deleting ~2 keys from leveldb
> took 49715ns
> I0129 19:28:06.982285 30535 replica.cpp:712] Persisted action at 8
> I0129 19:28:06.982314 30535 replica.cpp:697] Replica learned TRUNCATE
> action at position 8
> I0129 19:28:06.991621 30523 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.10:40784
> I0129 19:28:06.994441 30530 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0129 19:28:06.995003 30533 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.10:52642
> I0129 19:28:06.995275 30533 master.cpp:1972] Received subscription request
> for HTTP framework 'default'
> I0129 19:28:06.995301 30533 master.cpp:1749] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0129 19:28:06.995650 30533 master.cpp:2063] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0129 19:28:06.996767 30530 master.hpp:1658] Sending heartbeat to
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:06.997012 30533 hierarchical.cpp:265] Added framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:06.998599 30522 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.10:40784
> I0129 19:28:06.999290 30533 hierarchical.cpp:1096] Performed allocation
> for 2 slaves in 2.246931ms
> I0129 19:28:06.999588 30522 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.10:40784
> I0129 19:28:06.999832 30531 master.cpp:5350] Sending 2 offers to framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
> I0129 19:28:07.000118 30524 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0129 19:28:07.000816 30531 master.cpp:5440] Sending 2 inverse offers to
> framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
> I0129 19:28:07.003466 30527 scheduler.cpp:457] Enqueuing event OFFERS
> received from master@172.17.0.10:40784
> I0129 19:28:07.004482 30526 scheduler.cpp:457] Enqueuing event OFFERS
> received from master@172.17.0.10:40784
> I0129 19:28:07.015707 30532 hierarchical.cpp:1403] No resources available
> to allocate!
> I0129 19:28:07.016024 30532 hierarchical.cpp:1096] Performed allocation
> for 2 slaves in 573546ns
> I0129 19:28:07.024850 30532 scheduler.cpp:298] Sending ACCEPT call to
> master@172.17.0.10:40784
> I0129 19:28:07.032543 30521 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0129 19:28:07.033124 30531 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.10:52643
> I0129 19:28:07.034265 30531 master.cpp:3136] Processing ACCEPT call for
> offers: [ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O0 ] on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784
> (maintenance-host) for framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> (default)
> I0129 19:28:07.034318 30531 master.cpp:2823] Authorizing framework
> principal 'test-principal' to launch task
> 285183f9-94b0-42b1-a9ac-760013050fa9 as user 'mesos'
> I0129 19:28:07.038235 30532 scheduler.cpp:298] Sending ACCEPT call to
> master@172.17.0.10:40784
> W0129 19:28:07.038614 30531 validation.cpp:404] Executor executor-1 for
> task 285183f9-94b0-42b1-a9ac-760013050fa9 uses less CPUs (None) than the
> minimum required (0.01). Please update your executor, as this will be
> mandatory in future releases.
> W0129 19:28:07.038687 30531 validation.cpp:416] Executor executor-1 for
> task 285183f9-94b0-42b1-a9ac-760013050fa9 uses less memory (None) than the
> minimum required (32MB). Please update your executor, as this will be
> mandatory in future releases.
> I0129 19:28:07.039165 30531 master.hpp:176] Adding task
> 285183f9-94b0-42b1-a9ac-760013050fa9 with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 (maintenance-host)
> I0129 19:28:07.039428 30531 master.cpp:3621] Launching task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) with resources
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784
> (maintenance-host)
> I0129 19:28:07.039952 30531 slave.cpp:1360] Got assigned task
> 285183f9-94b0-42b1-a9ac-760013050fa9 for framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.040659 30531 slave.cpp:1479] Launching task
> 285183f9-94b0-42b1-a9ac-760013050fa9 for framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.040920 30523 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0129 19:28:07.041457 30531 paths.cpp:474] Trying to chown
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_zf5ZwD/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S0/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-1/runs/209bc342-2d14-4752-95f9-21b8a235f108'
> to user 'mesos'
> I0129 19:28:07.041544 30536 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.10:52644
> I0129 19:28:07.045539 30536 master.cpp:3136] Processing ACCEPT call for
> offers: [ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O1 ] on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784
> (maintenance-host-2) for framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
> I0129 19:28:07.045624 30536 master.cpp:2823] Authorizing framework
> principal 'test-principal' to launch task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 as user 'mesos'
> W0129 19:28:07.047829 30536 validation.cpp:404] Executor executor-2 for
> task cc072a08-3c25-49f0-b049-9cd2e6707c04 uses less CPUs (None) than the
> minimum required (0.01). Please update your executor, as this will be
> mandatory in future releases.
> W0129 19:28:07.047888 30536 validation.cpp:416] Executor executor-2 for
> task cc072a08-3c25-49f0-b049-9cd2e6707c04 uses less memory (None) than the
> minimum required (32MB). Please update your executor, as this will be
> mandatory in future releases.
> I0129 19:28:07.048319 30536 master.hpp:176] Adding task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 (maintenance-host-2)
> I0129 19:28:07.049053 30536 master.cpp:3621] Launching task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) with resources
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784
> (maintenance-host-2)
> I0129 19:28:07.050323 30536 slave.cpp:1360] Got assigned task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 for framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.051344 30536 slave.cpp:1479] Launching task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 for framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.051946 30531 slave.cpp:5281] Launching executor executor-1
> of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 with resources in
> work directory
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_zf5ZwD/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S0/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-1/runs/209bc342-2d14-4752-95f9-21b8a235f108'
> I0129 19:28:07.053107 30536 paths.cpp:474] Trying to chown
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-2/runs/3546fcc8-d4a6-4b66-84e3-cb5032edaa49'
> to user 'mesos'
> *** Aborted at 1454095687 (unix time) try "date -d @1454095687" if you are
> using GNU date ***
> I0129 19:28:07.060842 30531 exec.cpp:134] Version: 0.28.0
> PC: @ 0x2b2faec908dd (unknown)
> I0129 19:28:07.061427 30523 exec.cpp:184] Executor started at:
> executor(48)@172.17.0.10:40784 with pid 30503
> I0129 19:28:07.061749 30531 slave.cpp:1697] Queuing task
> '285183f9-94b0-42b1-a9ac-760013050fa9' for executor 'executor-1' of
> framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.061928 30531 slave.cpp:748] Successfully attached file
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_zf5ZwD/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S0/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-1/runs/209bc342-2d14-4752-95f9-21b8a235f108'
> I0129 19:28:07.062142 30531 slave.cpp:2642] Got registration for executor
> 'executor-1' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from
> executor(48)@172.17.0.10:40784
> I0129 19:28:07.063915 30535 exec.cpp:208] Executor registered on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0
> I0129 19:28:07.064000 30535 exec.cpp:220] Executor::registered took 54144ns
> I0129 19:28:07.064177 30531 slave.cpp:1862] Sending queued task
> '285183f9-94b0-42b1-a9ac-760013050fa9' to executor 'executor-1' of
> framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 at executor(48)@
> 172.17.0.10:40784
> I0129 19:28:07.064534 30531 exec.cpp:295] Executor asked to run task
> '285183f9-94b0-42b1-a9ac-760013050fa9'
> I0129 19:28:07.064632 30531 exec.cpp:304] Executor::launchTask took 82499ns
> I0129 19:28:07.064724 30531 exec.cpp:517] Executor sending status update
> TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.064982 30531 slave.cpp:3001] Handling status update
> TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from executor(48)@
> 172.17.0.10:40784
> I0129 19:28:07.065373 30535 status_update_manager.cpp:320] Received status
> update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.065460 30535 status_update_manager.cpp:497] Creating
> StatusUpdate stream for task 285183f9-94b0-42b1-a9ac-760013050fa9 of
> framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.066195 30535 status_update_manager.cpp:374] Forwarding
> update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to the slave
> I0129 19:28:07.066848 30535 slave.cpp:3353] Forwarding the update
> TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to master@172.17.0.10:40784
> I0129 19:28:07.067059 30535 slave.cpp:3247] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.067106 30535 slave.cpp:3263] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for
> task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to executor(48)@
> 172.17.0.10:40784
> I0129 19:28:07.067440 30535 master.cpp:4789] Status update TASK_RUNNING
> (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 at slave(116)@172.17.0.10:40784
> (maintenance-host)
> I0129 19:28:07.067498 30535 master.cpp:4837] Forwarding status update
> TASK_RUNNING (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.067569 30534 exec.cpp:341] Executor received status update
> acknowledgement 3d6be9d7-cd05-4803-99ca-139286d024a6 for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.067960 30535 master.cpp:6445] Updating the state of task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (latest state: TASK_RUNNING,
> status update state: TASK_RUNNING)
> I0129 19:28:07.070008 30531 scheduler.cpp:457] Enqueuing event UPDATE
> received from master@172.17.0.10:40784
> I0129 19:28:07.073709 30536 slave.cpp:5281] Launching executor executor-2
> of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 with resources in
> work directory
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-2/runs/3546fcc8-d4a6-4b66-84e3-cb5032edaa49'
> I0129 19:28:07.077035 30536 exec.cpp:134] Version: 0.28.0
> I0129 19:28:07.077448 30531 exec.cpp:184] Executor started at:
> executor(49)@172.17.0.10:40784 with pid 30503
> I0129 19:28:07.078758 30536 slave.cpp:1697] Queuing task
> 'cc072a08-3c25-49f0-b049-9cd2e6707c04' for executor 'executor-2' of
> framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.078948 30536 slave.cpp:748] Successfully attached file
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_LqUaUx/slaves/856fb6b3-b596-4de1-bb80-8f3f835ae401-S1/frameworks/856fb6b3-b596-4de1-bb80-8f3f835ae401-0000/executors/executor-2/runs/3546fcc8-d4a6-4b66-84e3-cb5032edaa49'
> I0129 19:28:07.079035 30536 slave.cpp:2642] Got registration for executor
> 'executor-2' of framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from
> executor(49)@172.17.0.10:40784
> I0129 19:28:07.079576 30523 exec.cpp:208] Executor registered on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
> I0129 19:28:07.079627 30523 exec.cpp:220] Executor::registered took 32882ns
> I0129 19:28:07.081107 30536 slave.cpp:1862] Sending queued task
> 'cc072a08-3c25-49f0-b049-9cd2e6707c04' to executor 'executor-2' of
> framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 at executor(49)@
> 172.17.0.10:40784
> I0129 19:28:07.081478 30534 exec.cpp:295] Executor asked to run task
> 'cc072a08-3c25-49f0-b049-9cd2e6707c04'
> I0129 19:28:07.081573 30534 exec.cpp:304] Executor::launchTask took 79174ns
> I0129 19:28:07.081671 30534 exec.cpp:517] Executor sending status update
> TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.081949 30534 slave.cpp:3001] Handling status update
> TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from executor(49)@
> 172.17.0.10:40784
> I0129 19:28:07.082386 30534 status_update_manager.cpp:320] Received status
> update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.082449 30534 status_update_manager.cpp:497] Creating
> StatusUpdate stream for task cc072a08-3c25-49f0-b049-9cd2e6707c04 of
> framework 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.082908 30534 status_update_manager.cpp:374] Forwarding
> update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to the slave
> I0129 19:28:07.083353 30534 slave.cpp:3353] Forwarding the update
> TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to master@172.17.0.10:40784
> I0129 19:28:07.083573 30534 slave.cpp:3247] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.083622 30534 slave.cpp:3263] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for
> task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 to executor(49)@
> 172.17.0.10:40784
> *** SIGSEGV (@0x51) received by PID 30503 (TID 0x2b307fe5f700) from PID
> 81; stack trace: ***
> I0129 19:28:07.083884 30534 master.cpp:4789] Status update TASK_RUNNING
> (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 from slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1 at slave(117)@172.17.0.10:40784
> (maintenance-host-2)
> I0129 19:28:07.083933 30534 master.cpp:4837] Forwarding status update
> TASK_RUNNING (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.084341 30534 master.cpp:6445] Updating the state of task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (latest state: TASK_RUNNING,
> status update state: TASK_RUNNING)
> I0129 19:28:07.084470 30534 exec.cpp:341] Executor received status update
> acknowledgement 7be263f7-843d-40ec-ac41-eef09944bdab for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> @ 0x2b2fbb1e50b7 os::Linux::chained_handler()
> I0129 19:28:07.086391 30528 scheduler.cpp:457] Enqueuing event UPDATE
> received from master@172.17.0.10:40784
> @ 0x2b2fbb1e9219 JVM_handle_linux_signal
> @ 0x2b2faea47340 (unknown)
> @ 0x2b2faec908dd (unknown)
> @ 0x2b2faec86681 (unknown)
> @ 0x2b2faecdd98e (unknown)
> @ 0x2b2faecdd8cf (unknown)
> I0129 19:28:07.099602 30528 scheduler.cpp:298] Sending ACKNOWLEDGE call to
> master@172.17.0.10:40784
> @ 0x2b2facc1b0d0 handle_socket_error_msg
> I0129 19:28:07.103446 30523 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0129 19:28:07.104017 30523 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.10:52646
> I0129 19:28:07.104205 30523 master.cpp:3947] Processing ACKNOWLEDGE call
> 3d6be9d7-cd05-4803-99ca-139286d024a6 for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0
> I0129 19:28:07.104629 30524 status_update_manager.cpp:392] Received status
> update acknowledgement (UUID: 3d6be9d7-cd05-4803-99ca-139286d024a6) for
> task 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.104986 30523 slave.cpp:2411] Status update manager
> successfully handled status update acknowledgement (UUID:
> 3d6be9d7-cd05-4803-99ca-139286d024a6) for task
> 285183f9-94b0-42b1-a9ac-760013050fa9 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.111610 30524 scheduler.cpp:298] Sending ACKNOWLEDGE call to
> master@172.17.0.10:40784
> I0129 19:28:07.115684 30534 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0129 19:28:07.116317 30534 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.10:52647
> I0129 19:28:07.116556 30534 master.cpp:3947] Processing ACKNOWLEDGE call
> 7be263f7-843d-40ec-ac41-eef09944bdab for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default) on slave
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-S1
> I0129 19:28:07.117308 30534 status_update_manager.cpp:392] Received status
> update acknowledgement (UUID: 7be263f7-843d-40ec-ac41-eef09944bdab) for
> task cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.117715 30534 slave.cpp:2411] Status update manager
> successfully handled status update acknowledgement (UUID:
> 7be263f7-843d-40ec-ac41-eef09944bdab) for task
> cc072a08-3c25-49f0-b049-9cd2e6707c04 of framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000
> I0129 19:28:07.120380 30534 scheduler.cpp:298] Sending DECLINE call to
> master@172.17.0.10:40784
> I0129 19:28:07.124797 30527 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0129 19:28:07.125454 30527 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.10:52648
> I0129 19:28:07.125619 30527 master.cpp:3673] Processing DECLINE call for
> offers: [ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O3 ] for framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 (default)
> I0129 19:28:07.126106 30527 hierarchical.cpp:799] Framework
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-0000 filtered inverse offers from
> slave 856fb6b3-b596-4de1-bb80-8f3f835ae401-S0 for 101secs
> @ 0x2b2facc1bf6b zookeeper_process
> I0129 19:28:07.131680 30535 scheduler.cpp:298] Sending ACCEPT call to
> master@172.17.0.10:40784
> I0129 19:28:07.135752 30524 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0129 19:28:07.136337 30524 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.10:52649
> W0129 19:28:07.136922 30524 master.cpp:3099] ACCEPT call used invalid
> offers '[ 856fb6b3-b596-4de1-bb80-8f3f835ae401-O2 ]': Offer
> 856fb6b3-b596-4de1-bb80-8f3f835ae401-O2 is no longer valid
> @ 0x2b2facc2209b do_io
> @ 0x2b2faea3f182 start_thread
> @ 0x2b2faed4f47d (unknown)
> make[4]: *** [check-local] Segmentation fault
> make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[2]: *** [check] Error 2
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1454093743-28431
> Untagged: mesos-1454093743-28431:latest
> Deleted: 6de59b0c2227923361dcc4d94f701af0b4400c9044eb08e69f0063e1ac9670b0
> Deleted: 814c845cb6a7a55b3ad89b8f0e2978d487bda5d6aa7b81ce5ffabea03ea5085c
> Deleted: bb09c811f6fbd51fd1bf83d6a74536b4f139a12c85555dec2021b4519beb8e1b
> Deleted: ab610143091db1a255a5424e34db29e4f5e039f40c1fbc0dbddf48a4ba9f7647
> Deleted: 66f0ea79cb73c72b8bf38144591e79f120e78a8ca7fc392ff1365c4104da304f
> Deleted: 5f11f902196edfbaf411d59c207f8f9b234fdd2d3c40405fb4aa45ed31c4969e
> Deleted: c4ff5ea5115b9131c6a4fbb57c1d5b771c3a2732439002f7d23d4a2cd2ded15c
> Deleted: cdf04b3ddf2841e09980a897de36e492f3a62bf83ad4a143649806353135f233
> Deleted: 5469ac0054400dccb6df93ae57abe92fabaa9164a44e391fca15310fe6a1624a
> Deleted: c01fb532107e8939af91b6de234f17b8e856a8777c98915499b3bf8cfe20a9f8
> Deleted: 725c4b60180f7268aeaaab0fdf4fa6172649fddecc5424a20378c11a6d74c61a
> Deleted: ad0bc615de4c918338e0dc11a0c12d7cef1b7af9a7df7c165f4bfececa09e02d
> Deleted: 222afef45a828b03a5612fb8ef24e78e82fb82e11a92ae4be68202e0a5a38ef1
> Deleted: 6cb378c5ea66d7fa4206800409c06677a8e6384b33f07896790f930c5241cb7b
> Build step 'Execute shell' marked build as failure
>