Yan Xu created MESOS-928:
----------------------------
Summary: AllocatorZooKeeperTest/0.SlaveReregistersFirst is flaky
Key: MESOS-928
URL: https://issues.apache.org/jira/browse/MESOS-928
Project: Mesos
Issue Type: Bug
Components: test
Reporter: Yan Xu
Fix For: 0.17.0
https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1575/consoleFull
I0117 22:45:27.010951 5602 sched.cpp:261] Authenticating with master master@67.195.138.8:58517
GMOCK WARNING:
Uninteresting mock function call - taking default action specified at:
./tests/mesos.hpp:407:
Function call: slaveAdded(@0x2b5164037140 201401172245-143311683-58517-5141-0, @0x2b51640370d0 hostname: "minerva.apache.org"
webui_hostname: "minerva.apache.org"
resources {
name: "cpus"
type: SCALAR
scalar {
value: 2
}
role: "*"
}
resources {
name: "mem"
type: SCALAR
scalar {
value: 1024
}
role: "*"
}
resources {
name: "disk"
type: SCALAR
scalar {
value: 23038
}
role: "*"
}
resources {
name: "ports"
type: RANGES
ranges {
range {
begin: 31000
end: 32000
}
}
role: "*"
}
id {
value: "201401172245-143311683-58517-5141-0"
}
checkpoint: false
port: 58517
, @0x2b51640370a0 { (201401172245-143311683-58517-5141-0000, { cpus(*):1, mem(*):500 }) })
Stack trace:
I0117 22:45:27.010987 5602 sched.cpp:230] Detecting new master
I0117 22:45:27.011076 5601 hierarchical_allocator_process.hpp:445] Added slave 201401172245-143311683-58517-5141-0 (minerva.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available)
I0117 22:45:27.011111 5601 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201401172245-143311683-58517-5141-0 in 4802ns
I0117 22:45:27.011157 5603 authenticatee.hpp:124] Creating new client SASL connection
I0117 22:45:27.011283 5602 master.cpp:1836] Authenticating framework at scheduler(132)@67.195.138.8:58517
I0117 22:45:27.011338 5603 authenticator.hpp:140] Creating new server SASL connection
I0117 22:45:27.011437 5602 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5
I0117 22:45:27.011454 5602 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I0117 22:45:27.011486 5602 authenticator.hpp:243] Received SASL authentication start
I0117 22:45:27.011534 5602 authenticator.hpp:325] Authentication requires more steps
I0117 22:45:27.011564 5602 authenticatee.hpp:258] Received SASL authentication step
I0117 22:45:27.011606 5602 authenticator.hpp:271] Received SASL authentication step
I0117 22:45:27.011620 5602 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0117 22:45:27.011631 5602 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0117 22:45:27.011643 5602 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0117 22:45:27.011653 5602 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0117 22:45:27.011659 5602 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0117 22:45:27.011664 5602 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0117 22:45:27.011675 5602 authenticator.hpp:317] Authentication success
I0117 22:45:27.011710 5603 authenticatee.hpp:298] Authentication success
I0117 22:45:27.011803 5603 sched.cpp:335] Successfully authenticated with master master@67.195.138.8:58517
I0117 22:45:27.011803 5602 master.cpp:1876] Successfully authenticated framework at scheduler(132)@67.195.138.8:58517
2014-01-17 22:45:27,438:5141(0x2b5274803700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:48985] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0117 22:45:27.980269 5603 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:27.999601 5604 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:28.006669 5599 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 20519ns
I0117 22:45:28.981165 5601 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:29.000334 5599 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:29.007385 5603 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 22274ns
I0117 22:45:29.981925 5601 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:30.001018 5604 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:30.008090 5603 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 24251ns
2014-01-17 22:45:30,774:5141(0x2b5274803700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:48985] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0117 22:45:30.982780 5602 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:31.002218 5604 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:31.009212 5604 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 23330ns
I0117 22:45:31.983933 5601 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:32.003159 5600 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:32.006222 5600 master.cpp:85] No whitelist given. Advertising offers for all slaves
I0117 22:45:32.009299 5600 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 19118ns
I0117 22:45:32.984730 5600 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:33.004012 5600 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:33.010018 5599 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 24777ns
I0117 22:45:33.985347 5603 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:34.004447 5603 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:34.010575 5606 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 27435ns
2014-01-17 22:45:34,111:5141(0x2b5274803700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:48985] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0117 22:45:34.985859 5602 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:35.005079 5599 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:35.011052 5599 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 22058ns
I0117 22:45:35.986865 5600 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:36.006248 5606 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:36.011289 5606 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 11259ns
I0117 22:45:36.987643 5606 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:37.007050 5599 master.cpp:85] No whitelist given. Advertising offers for all slaves
I0117 22:45:37.007182 5599 monitor.cpp:193] Publishing resource usage for executor 'default' of framework '201401172245-143311683-58517-5141-0000'
I0117 22:45:37.012279 5601 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 44495ns
tests/allocator_zookeeper_tests.cpp:290: Failure
Failed to wait 10secs for slaveAdded
tests/allocator_zookeeper_tests.cpp:284: Failure
Actual function call count doesn't match EXPECT_CALL(allocator2, slaveAdded(_, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
--
This message was sent by Atlassian JIRA
(v6.1.5#6160)