1. 2017-07-19T14:57:12.221 INFO:teuthology.orchestra.run.target149202163224.stderr:+ ceph -s
  2. 2017-07-19T14:57:12.457 INFO:teuthology.orchestra.run.target149202163224.stdout: cluster:
  3. 2017-07-19T14:57:12.457 INFO:teuthology.orchestra.run.target149202163224.stdout: id: dcbe392b-f978-3ad6-bc76-75fb9f299839
  4. 2017-07-19T14:57:12.458 INFO:teuthology.orchestra.run.target149202163224.stdout: health: HEALTH_OK
  5. 2017-07-19T14:57:12.458 INFO:teuthology.orchestra.run.target149202163224.stdout:
  6. 2017-07-19T14:57:12.458 INFO:teuthology.orchestra.run.target149202163224.stdout: services:
  7. 2017-07-19T14:57:12.458 INFO:teuthology.orchestra.run.target149202163224.stdout: mon: 1 daemons, quorum target149202163224
  8. 2017-07-19T14:57:12.458 INFO:teuthology.orchestra.run.target149202163224.stdout: mgr: target149202163224(active)
  9. 2017-07-19T14:57:12.458 INFO:teuthology.orchestra.run.target149202163224.stdout: mds: 0/0/1 up
  10. 2017-07-19T14:57:12.458 INFO:teuthology.orchestra.run.target149202163224.stdout: osd: 3 osds: 3 up, 3 in
  11. 2017-07-19T14:57:12.459 INFO:teuthology.orchestra.run.target149202163224.stdout:
  12. 2017-07-19T14:57:12.459 INFO:teuthology.orchestra.run.target149202163224.stdout: data:
  13. 2017-07-19T14:57:12.459 INFO:teuthology.orchestra.run.target149202163224.stdout: pools: 2 pools, 256 pgs
  14. 2017-07-19T14:57:12.459 INFO:teuthology.orchestra.run.target149202163224.stdout: objects: 0 objects, 0 bytes
  15. 2017-07-19T14:57:12.459 INFO:teuthology.orchestra.run.target149202163224.stdout: usage: 3165 MB used, 27251 MB / 30416 MB avail
  16. 2017-07-19T14:57:12.459 INFO:teuthology.orchestra.run.target149202163224.stdout: pgs: 17.578% pgs unknown
  17. 2017-07-19T14:57:12.460 INFO:teuthology.orchestra.run.target149202163224.stdout: 211 active+clean
  18. 2017-07-19T14:57:12.460 INFO:teuthology.orchestra.run.target149202163224.stdout: 45 unknown
  19. 2017-07-19T14:57:12.460 INFO:teuthology.orchestra.run.target149202163224.stdout:
  20. 2017-07-19T14:57:12.472 INFO:teuthology.orchestra.run.target149202163224.stderr:+ ceph_health_test
  21. 2017-07-19T14:57:12.473 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local LOGFILE=/tmp/ceph_health_test.log
  22. 2017-07-19T14:57:12.473 INFO:teuthology.orchestra.run.target149202163224.stderr:+ echo 'Waiting up to 15 minutes for HEALTH_OK...'
  23. 2017-07-19T14:57:12.473 INFO:teuthology.orchestra.run.target149202163224.stderr:+ salt -C I@roles:master wait.until status=HEALTH_OK timeout=900 check=1
  24. 2017-07-19T14:57:12.473 INFO:teuthology.orchestra.run.target149202163224.stderr:+ tee /tmp/ceph_health_test.log
  25. 2017-07-19T14:57:12.474 INFO:teuthology.orchestra.run.target149202163224.stdout:Waiting up to 15 minutes for HEALTH_OK...
  26. 2017-07-19T14:57:12.764 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Configuration file path: /etc/salt/master
  27. 2017-07-19T14:57:12.764 INFO:teuthology.orchestra.run.target149202163224.stderr:[WARNING ] Insecure logging configuration detected! Sensitive data may be logged.
  28. 2017-07-19T14:57:12.765 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master
  29. 2017-07-19T14:57:12.766 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/eauth.conf'
  30. 2017-07-19T14:57:12.766 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/eauth.conf
  31. 2017-07-19T14:57:12.767 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/modules.conf'
  32. 2017-07-19T14:57:12.768 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/modules.conf
  33. 2017-07-19T14:57:12.770 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/output.conf'
  34. 2017-07-19T14:57:12.770 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/output.conf
  35. 2017-07-19T14:57:12.770 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/reactor.conf'
  36. 2017-07-19T14:57:12.771 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/reactor.conf
  37. 2017-07-19T14:57:12.773 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/salt-api.conf'
  38. 2017-07-19T14:57:12.773 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/salt-api.conf
  39. 2017-07-19T14:57:12.774 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/sharedsecret.conf'
  40. 2017-07-19T14:57:12.774 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/sharedsecret.conf
  41. 2017-07-19T14:57:12.775 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Using cached minion ID from /etc/salt/minion_id: target149202163224.teuthology
  42. 2017-07-19T14:57:12.777 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Missing configuration file: /root/.saltrc
  43. 2017-07-19T14:57:12.778 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PUB socket URI: /var/run/salt/master/master_event_pub.ipc
  44. 2017-07-19T14:57:12.778 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PULL socket URI: /var/run/salt/master/master_event_pull.ipc
  45. 2017-07-19T14:57:12.850 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new AsyncZeroMQReqChannel for ('/etc/salt/pki/master', 'target149202163224.teuthology_master', 'tcp://127.0.0.1:4506', 'clear')
  46. 2017-07-19T14:57:12.851 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new IPCClient for path: /var/run/salt/master/master_event_pub.ipc
  47. 2017-07-19T14:57:12.874 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded local_cache.get_load
  48. 2017-07-19T14:57:12.874 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading minion list from /var/cache/salt/master/jobs/a9/144722dc8243c52ad07c6dbc8f5b1cb824476d237452e9755d13dc140f9c8a/.minions.p
  49. 2017-07-19T14:57:12.874 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] get_iter_returns for jid 20170719145712852381 sent to set(['target149202163224.teuthology']) will timeout at 14:57:17.870626
  50. 2017-07-19T14:57:12.953 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145712852381 return from target149202163224.teuthology
  51. 2017-07-19T14:57:12.953 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded nested.output
  52. 2017-07-19T14:57:13.054 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145712852381 found all minions set(['target149202163224.teuthology'])
  53. 2017-07-19T14:57:13.085 INFO:teuthology.orchestra.run.target149202163224.stdout:target149202163224.teuthology:
  54. 2017-07-19T14:57:13.085 INFO:teuthology.orchestra.run.target149202163224.stdout: None
  55. 2017-07-19T14:57:13.087 INFO:teuthology.orchestra.run.target149202163224.stderr:+ grep -q 'Timeout expired' /tmp/ceph_health_test.log
  56. 2017-07-19T14:57:13.088 INFO:teuthology.orchestra.run.target149202163224.stderr:+ cephfs_mount_and_sanity_test
  57. 2017-07-19T14:57:13.089 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local TESTSCRIPT=/tmp/cephfs_test.sh
  58. 2017-07-19T14:57:13.089 INFO:teuthology.orchestra.run.target149202163224.stderr:++ _client_node
  59. 2017-07-19T14:57:13.090 INFO:teuthology.orchestra.run.target149202163224.stderr:++ salt --no-color -C 'not I@roles:storage' test.ping
  60. 2017-07-19T14:57:13.090 INFO:teuthology.orchestra.run.target149202163224.stderr:++ grep -o -P '^\S+(?=:)'
  61. 2017-07-19T14:57:13.091 INFO:teuthology.orchestra.run.target149202163224.stderr:++ head -1
  62. 2017-07-19T14:57:13.305 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Configuration file path: /etc/salt/master
  63. 2017-07-19T14:57:13.306 INFO:teuthology.orchestra.run.target149202163224.stderr:[WARNING ] Insecure logging configuration detected! Sensitive data may be logged.
  64. 2017-07-19T14:57:13.306 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master
  65. 2017-07-19T14:57:13.307 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/eauth.conf'
  66. 2017-07-19T14:57:13.307 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/eauth.conf
  67. 2017-07-19T14:57:13.308 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/modules.conf'
  68. 2017-07-19T14:57:13.308 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/modules.conf
  69. 2017-07-19T14:57:13.309 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/output.conf'
  70. 2017-07-19T14:57:13.309 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/output.conf
  71. 2017-07-19T14:57:13.310 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/reactor.conf'
  72. 2017-07-19T14:57:13.310 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/reactor.conf
  73. 2017-07-19T14:57:13.311 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/salt-api.conf'
  74. 2017-07-19T14:57:13.312 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/salt-api.conf
  75. 2017-07-19T14:57:13.312 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/sharedsecret.conf'
  76. 2017-07-19T14:57:13.313 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/sharedsecret.conf
  77. 2017-07-19T14:57:13.313 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Using cached minion ID from /etc/salt/minion_id: target149202163224.teuthology
  78. 2017-07-19T14:57:13.314 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Missing configuration file: /root/.saltrc
  79. 2017-07-19T14:57:13.315 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PUB socket URI: /var/run/salt/master/master_event_pub.ipc
  80. 2017-07-19T14:57:13.315 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PULL socket URI: /var/run/salt/master/master_event_pull.ipc
  81. 2017-07-19T14:57:13.380 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new AsyncZeroMQReqChannel for ('/etc/salt/pki/master', 'target149202163224.teuthology_master', 'tcp://127.0.0.1:4506', 'clear')
  82. 2017-07-19T14:57:13.380 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new IPCClient for path: /var/run/salt/master/master_event_pub.ipc
  83. 2017-07-19T14:57:13.403 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded local_cache.get_load
  84. 2017-07-19T14:57:13.404 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading minion list from /var/cache/salt/master/jobs/dc/080c4de644943d8f85d74cad516f5ccb2f81159faa48455cfd2b5a23668f13/.minions.p
  85. 2017-07-19T14:57:13.404 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] get_iter_returns for jid 20170719145713383935 sent to set(['target149202163255.teuthology']) will timeout at 14:57:18.401950
  86. 2017-07-19T14:57:13.484 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145713383935 return from target149202163255.teuthology
  87. 2017-07-19T14:57:13.485 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded nested.output
  88. 2017-07-19T14:57:13.587 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145713383935 found all minions set(['target149202163255.teuthology'])
  89. 2017-07-19T14:57:13.614 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local CLIENTNODE=target149202163255.teuthology
  90. 2017-07-19T14:57:13.615 INFO:teuthology.orchestra.run.target149202163224.stderr:+ cat
  91. 2017-07-19T14:57:13.615 INFO:teuthology.orchestra.run.target149202163224.stderr:+ _run_test_script_on_node /tmp/cephfs_test.sh target149202163255.teuthology
  92. 2017-07-19T14:57:13.615 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local TESTSCRIPT=/tmp/cephfs_test.sh
  93. 2017-07-19T14:57:13.615 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local TESTNODE=target149202163255.teuthology
  94. 2017-07-19T14:57:13.616 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local ASUSER=
  95. 2017-07-19T14:57:13.616 INFO:teuthology.orchestra.run.target149202163224.stderr:+ salt-cp target149202163255.teuthology /tmp/cephfs_test.sh /tmp/cephfs_test.sh
  96. 2017-07-19T14:57:13.861 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Configuration file path: /etc/salt/master
  97. 2017-07-19T14:57:13.862 INFO:teuthology.orchestra.run.target149202163224.stderr:[WARNING ] Insecure logging configuration detected! Sensitive data may be logged.
  98. 2017-07-19T14:57:13.862 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master
  99. 2017-07-19T14:57:13.862 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/eauth.conf'
  100. 2017-07-19T14:57:13.863 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/eauth.conf
  101. 2017-07-19T14:57:13.864 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/modules.conf'
  102. 2017-07-19T14:57:13.864 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/modules.conf
  103. 2017-07-19T14:57:13.865 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/output.conf'
  104. 2017-07-19T14:57:13.865 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/output.conf
  105. 2017-07-19T14:57:13.866 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/reactor.conf'
  106. 2017-07-19T14:57:13.866 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/reactor.conf
  107. 2017-07-19T14:57:13.868 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/salt-api.conf'
  108. 2017-07-19T14:57:13.868 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/salt-api.conf
  109. 2017-07-19T14:57:13.869 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/sharedsecret.conf'
  110. 2017-07-19T14:57:13.869 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/sharedsecret.conf
  111. 2017-07-19T14:57:13.870 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Using cached minion ID from /etc/salt/minion_id: target149202163224.teuthology
  112. 2017-07-19T14:57:13.871 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Missing configuration file: /root/.saltrc
  113. 2017-07-19T14:57:13.871 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PUB socket URI: /var/run/salt/master/master_event_pub.ipc
  114. 2017-07-19T14:57:13.872 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PULL socket URI: /var/run/salt/master/master_event_pull.ipc
  115. 2017-07-19T14:57:13.942 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new AsyncZeroMQReqChannel for ('/etc/salt/pki/master', 'target149202163224.teuthology_master', 'tcp://127.0.0.1:4506', 'clear')
  116. 2017-07-19T14:57:13.942 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new IPCClient for path: /var/run/salt/master/master_event_pub.ipc
  117. 2017-07-19T14:57:13.963 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded local_cache.get_load
  118. 2017-07-19T14:57:13.964 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading minion list from /var/cache/salt/master/jobs/70/e95c3e310b180e375a25c6533e1c6a3e7551db55c961c00f6175dc686286c1/.minions.p
  119. 2017-07-19T14:57:13.964 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] get_iter_returns for jid 20170719145713944245 sent to set(['target149202163255.teuthology']) will timeout at 14:57:18.961356
  120. 2017-07-19T14:57:14.004 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145713944245 return from target149202163255.teuthology
  121. 2017-07-19T14:57:14.105 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145713944245 found all minions set(['target149202163255.teuthology'])
  122. 2017-07-19T14:57:14.107 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded nested.output
  123. 2017-07-19T14:57:14.136 INFO:teuthology.orchestra.run.target149202163224.stdout:target149202163255.teuthology:
  124. 2017-07-19T14:57:14.137 INFO:teuthology.orchestra.run.target149202163224.stdout: ----------
  125. 2017-07-19T14:57:14.137 INFO:teuthology.orchestra.run.target149202163224.stdout: /tmp/cephfs_test.sh:
  126. 2017-07-19T14:57:14.137 INFO:teuthology.orchestra.run.target149202163224.stdout: True
  127. 2017-07-19T14:57:14.139 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local LOGFILE=/tmp/test_script.log
  128. 2017-07-19T14:57:14.139 INFO:teuthology.orchestra.run.target149202163224.stderr:+ '[' -z '' -o x = xroot ']'
  129. 2017-07-19T14:57:14.139 INFO:teuthology.orchestra.run.target149202163224.stderr:+ salt target149202163255.teuthology cmd.run 'sh /tmp/cephfs_test.sh'
  130. 2017-07-19T14:57:14.139 INFO:teuthology.orchestra.run.target149202163224.stderr:+ tee /tmp/test_script.log
  131. 2017-07-19T14:57:14.353 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Configuration file path: /etc/salt/master
  132. 2017-07-19T14:57:14.353 INFO:teuthology.orchestra.run.target149202163224.stderr:[WARNING ] Insecure logging configuration detected! Sensitive data may be logged.
  133. 2017-07-19T14:57:14.353 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master
  134. 2017-07-19T14:57:14.354 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/eauth.conf'
  135. 2017-07-19T14:57:14.354 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/eauth.conf
  136. 2017-07-19T14:57:14.355 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/modules.conf'
  137. 2017-07-19T14:57:14.356 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/modules.conf
  138. 2017-07-19T14:57:14.357 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/output.conf'
  139. 2017-07-19T14:57:14.357 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/output.conf
  140. 2017-07-19T14:57:14.358 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/reactor.conf'
  141. 2017-07-19T14:57:14.358 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/reactor.conf
  142. 2017-07-19T14:57:14.360 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/salt-api.conf'
  143. 2017-07-19T14:57:14.360 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/salt-api.conf
  144. 2017-07-19T14:57:14.361 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Including configuration from '/etc/salt/master.d/sharedsecret.conf'
  145. 2017-07-19T14:57:14.361 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading configuration from /etc/salt/master.d/sharedsecret.conf
  146. 2017-07-19T14:57:14.362 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Using cached minion ID from /etc/salt/minion_id: target149202163224.teuthology
  147. 2017-07-19T14:57:14.363 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Missing configuration file: /root/.saltrc
  148. 2017-07-19T14:57:14.364 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PUB socket URI: /var/run/salt/master/master_event_pub.ipc
  149. 2017-07-19T14:57:14.364 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] MasterEvent PULL socket URI: /var/run/salt/master/master_event_pull.ipc
  150. 2017-07-19T14:57:14.431 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new AsyncZeroMQReqChannel for ('/etc/salt/pki/master', 'target149202163224.teuthology_master', 'tcp://127.0.0.1:4506', 'clear')
  151. 2017-07-19T14:57:14.431 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Initializing new IPCClient for path: /var/run/salt/master/master_event_pub.ipc
  152. 2017-07-19T14:57:14.445 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded local_cache.get_load
  153. 2017-07-19T14:57:14.446 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] Reading minion list from /var/cache/salt/master/jobs/25/45ce6b6baebf16b9e5e469e9f114b23b9b3204ef3fd34ca298d618d803b76b/.minions.p
  154. 2017-07-19T14:57:14.446 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] get_iter_returns for jid 20170719145714432337 sent to set(['target149202163255.teuthology']) will timeout at 14:57:19.444285
  155. 2017-07-19T14:57:15.387 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145714432337 return from target149202163255.teuthology
  156. 2017-07-19T14:57:15.390 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] LazyLoaded nested.output
  157. 2017-07-19T14:57:15.493 INFO:teuthology.orchestra.run.target149202163224.stderr:[DEBUG ] jid 20170719145714432337 found all minions set(['target149202163255.teuthology'])
  158. 2017-07-19T14:57:15.493 INFO:teuthology.orchestra.run.target149202163224.stderr:ERROR: Minions returned with non-zero exit code
  159. 2017-07-19T14:57:15.494 INFO:teuthology.orchestra.run.target149202163224.stdout:target149202163255.teuthology:
  160. 2017-07-19T14:57:15.494 INFO:teuthology.orchestra.run.target149202163224.stdout: + trap 'echo "Result: NOT_OK"' ERR
  161. 2017-07-19T14:57:15.494 INFO:teuthology.orchestra.run.target149202163224.stdout: ++ whoami
  162. 2017-07-19T14:57:15.494 INFO:teuthology.orchestra.run.target149202163224.stdout: ++ hostname --fqdn
  163. 2017-07-19T14:57:15.494 INFO:teuthology.orchestra.run.target149202163224.stdout: + echo 'cephfs mount test script running as root on target149202163255.teuthology'
  164. 2017-07-19T14:57:15.495 INFO:teuthology.orchestra.run.target149202163224.stdout: cephfs mount test script running as root on target149202163255.teuthology
  165. 2017-07-19T14:57:15.495 INFO:teuthology.orchestra.run.target149202163224.stdout: ++ ceph-conf --lookup mon_initial_members
  166. 2017-07-19T14:57:15.495 INFO:teuthology.orchestra.run.target149202163224.stdout: ++ tr -d '[:space:]'
  167. 2017-07-19T14:57:15.495 INFO:teuthology.orchestra.run.target149202163224.stdout: + TESTMONS=target149202163224
  168. 2017-07-19T14:57:15.495 INFO:teuthology.orchestra.run.target149202163224.stdout: ++ grep 'key =' /etc/ceph/ceph.client.admin.keyring
  169. 2017-07-19T14:57:15.495 INFO:teuthology.orchestra.run.target149202163224.stdout: ++ awk '{print $NF}'
  170. 2017-07-19T14:57:15.495 INFO:teuthology.orchestra.run.target149202163224.stdout: + TESTSECR=AQCscm9ZAAAAABAAiA7AMJTXg4tzX+9p84GUHg==
  171. 2017-07-19T14:57:15.496 INFO:teuthology.orchestra.run.target149202163224.stdout: + echo 'MONs: target149202163224'
  172. 2017-07-19T14:57:15.496 INFO:teuthology.orchestra.run.target149202163224.stdout: MONs: target149202163224
  173. 2017-07-19T14:57:15.496 INFO:teuthology.orchestra.run.target149202163224.stdout: + echo 'admin secret: AQCscm9ZAAAAABAAiA7AMJTXg4tzX+9p84GUHg=='
  174. 2017-07-19T14:57:15.496 INFO:teuthology.orchestra.run.target149202163224.stdout: admin secret: AQCscm9ZAAAAABAAiA7AMJTXg4tzX+9p84GUHg==
  175. 2017-07-19T14:57:15.496 INFO:teuthology.orchestra.run.target149202163224.stdout: + test -d /mnt
  176. 2017-07-19T14:57:15.496 INFO:teuthology.orchestra.run.target149202163224.stdout: + mount -t ceph target149202163224:/ /mnt -o name=admin,secret=AQCscm9ZAAAAABAAiA7AMJTXg4tzX+9p84GUHg==
  177. 2017-07-19T14:57:15.496 INFO:teuthology.orchestra.run.target149202163224.stdout: mount error 2 = No such file or directory
  178. 2017-07-19T14:57:15.497 INFO:teuthology.orchestra.run.target149202163224.stdout: ++ echo 'Result: NOT_OK'
  179. 2017-07-19T14:57:15.497 INFO:teuthology.orchestra.run.target149202163224.stdout: Result: NOT_OK
  180. 2017-07-19T14:57:15.532 INFO:teuthology.orchestra.run.target149202163224.stderr:++ grep -o -P '(?<=Result: )(OK|NOT_OK)$' /tmp/test_script.log
  181. 2017-07-19T14:57:15.532 INFO:teuthology.orchestra.run.target149202163224.stderr:++ head -1
  182. 2017-07-19T14:57:15.533 INFO:teuthology.orchestra.run.target149202163224.stderr:+ local RESULT=NOT_OK
  183. 2017-07-19T14:57:15.533 INFO:teuthology.orchestra.run.target149202163224.stderr:+ test xNOT_OK = xOK