Console Output

Skipping 6,582 KB.. Full Log
2017-12-21T01:45:12.958579631Z I1221 01:45:12.954539       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/info as src/.git/info
2017-12-21T01:45:12.958583012Z I1221 01:45:12.954622       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/info/exclude as src/.git/info/exclude
2017-12-21T01:45:12.958586211Z I1221 01:45:12.954713       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs as src/.git/logs
2017-12-21T01:45:12.95870553Z I1221 01:45:12.954804       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs/HEAD as src/.git/logs/HEAD
2017-12-21T01:45:12.958711192Z I1221 01:45:12.954868       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs/refs as src/.git/logs/refs
2017-12-21T01:45:12.95871465Z I1221 01:45:12.954968       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs/refs/heads as src/.git/logs/refs/heads
2017-12-21T01:45:12.958718228Z I1221 01:45:12.955057       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs/refs/heads/master as src/.git/logs/refs/heads/master
2017-12-21T01:45:12.958723448Z I1221 01:45:12.955164       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs/refs/remotes as src/.git/logs/refs/remotes
2017-12-21T01:45:12.958727184Z I1221 01:45:12.955252       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs/refs/remotes/origin as src/.git/logs/refs/remotes/origin
2017-12-21T01:45:12.958730666Z I1221 01:45:12.955341       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/logs/refs/remotes/origin/HEAD as src/.git/logs/refs/remotes/origin/HEAD
2017-12-21T01:45:12.958734277Z I1221 01:45:12.955433       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects as src/.git/objects
2017-12-21T01:45:12.958737632Z I1221 01:45:12.955530       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/02 as src/.git/objects/02
2017-12-21T01:45:12.958741225Z I1221 01:45:12.955620       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/02/2d87e4160c00274b63cdad7c238b5c6a299265 as src/.git/objects/02/2d87e4160c00274b63cdad7c238b5c6a299265
2017-12-21T01:45:12.958748188Z I1221 01:45:12.955721       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/03 as src/.git/objects/03
2017-12-21T01:45:12.958751717Z I1221 01:45:12.955814       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/03/ea8db67ae0f2628e70b93d0ecb7ff6cb8839cc as src/.git/objects/03/ea8db67ae0f2628e70b93d0ecb7ff6cb8839cc
2017-12-21T01:45:12.958755804Z I1221 01:45:12.955923       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/07 as src/.git/objects/07
2017-12-21T01:45:12.958789371Z I1221 01:45:12.956021       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/07/ab3a68fa5e6a12d284cae2c05b7ca20b0182d8 as src/.git/objects/07/ab3a68fa5e6a12d284cae2c05b7ca20b0182d8
2017-12-21T01:45:12.958795475Z I1221 01:45:12.956133       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/0a as src/.git/objects/0a
2017-12-21T01:45:12.958804911Z I1221 01:45:12.956223       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/0a/5a0d2c143de3244295b7750eacdfff7b94d546 as src/.git/objects/0a/5a0d2c143de3244295b7750eacdfff7b94d546
2017-12-21T01:45:12.958809252Z I1221 01:45:12.956327       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/1d as src/.git/objects/1d
2017-12-21T01:45:12.95881273Z I1221 01:45:12.956419       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/1d/9d458b3d24b93d018be09d7aabc98752b52962 as src/.git/objects/1d/9d458b3d24b93d018be09d7aabc98752b52962
2017-12-21T01:45:12.958817461Z I1221 01:45:12.956512       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/2b as src/.git/objects/2b
2017-12-21T01:45:12.961477439Z I1221 01:45:12.956611       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/2b/b6c76c29870c9b4a9cff52cfc41f7e6bf44329 as src/.git/objects/2b/b6c76c29870c9b4a9cff52cfc41f7e6bf44329
2017-12-21T01:45:12.961487743Z I1221 01:45:12.956704       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/31 as src/.git/objects/31
2017-12-21T01:45:12.961491474Z I1221 01:45:12.956793       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/31/056df85336b9e9ffafad75681f817d9f33c7dd as src/.git/objects/31/056df85336b9e9ffafad75681f817d9f33c7dd
2017-12-21T01:45:12.961495053Z I1221 01:45:12.956889       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/42 as src/.git/objects/42
2017-12-21T01:45:12.961498425Z I1221 01:45:12.957519       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/42/f8df3b2ad306607f2d58038e302c02c35b0473 as src/.git/objects/42/f8df3b2ad306607f2d58038e302c02c35b0473
2017-12-21T01:45:12.961501922Z I1221 01:45:12.957679       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/57 as src/.git/objects/57
2017-12-21T01:45:12.961505185Z I1221 01:45:12.957800       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/57/0bd16c41745891a5aabc60399d1a743c231236 as src/.git/objects/57/0bd16c41745891a5aabc60399d1a743c231236
2017-12-21T01:45:12.961508633Z I1221 01:45:12.957905       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/60 as src/.git/objects/60
2017-12-21T01:45:12.96151203Z I1221 01:45:12.958005       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/60/b59ea27b319761b90e63c29adbf184a388aa79 as src/.git/objects/60/b59ea27b319761b90e63c29adbf184a388aa79
2017-12-21T01:45:12.961515531Z I1221 01:45:12.958111       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/6d as src/.git/objects/6d
2017-12-21T01:45:12.961519023Z I1221 01:45:12.958206       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/6d/98b321f0f4d9d69aee86cb71247bdf78a18613 as src/.git/objects/6d/98b321f0f4d9d69aee86cb71247bdf78a18613
2017-12-21T01:45:12.961522585Z I1221 01:45:12.958298       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/8b as src/.git/objects/8b
2017-12-21T01:45:12.9615274Z I1221 01:45:12.958397       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/8b/f7b88b3c0258ec9fb5ec4ca820a2f052dd48f3 as src/.git/objects/8b/f7b88b3c0258ec9fb5ec4ca820a2f052dd48f3
2017-12-21T01:45:12.961531151Z I1221 01:45:12.958930       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/8f as src/.git/objects/8f
2017-12-21T01:45:12.961534396Z I1221 01:45:12.959043       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/8f/1b5fb76498255f4d2fc2308209116c562324dc as src/.git/objects/8f/1b5fb76498255f4d2fc2308209116c562324dc
2017-12-21T01:45:12.96154651Z I1221 01:45:12.959187       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/9c as src/.git/objects/9c
2017-12-21T01:45:12.961550373Z I1221 01:45:12.959291       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/9c/4622537bf2249a4d04f95349bb4f6f983c274d as src/.git/objects/9c/4622537bf2249a4d04f95349bb4f6f983c274d
2017-12-21T01:45:12.961553822Z I1221 01:45:12.959383       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/9d as src/.git/objects/9d
2017-12-21T01:45:12.961556876Z I1221 01:45:12.959477       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/9d/920bc2a425ce2ef02156fce1d79b4d4f091245 as src/.git/objects/9d/920bc2a425ce2ef02156fce1d79b4d4f091245
2017-12-21T01:45:12.961560499Z I1221 01:45:12.959570       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/a0 as src/.git/objects/a0
2017-12-21T01:45:12.961563839Z I1221 01:45:12.959669       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/a0/96530bebdd5ceb03c15bff5e3961826ca88984 as src/.git/objects/a0/96530bebdd5ceb03c15bff5e3961826ca88984
2017-12-21T01:45:12.961567349Z I1221 01:45:12.959758       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/a4 as src/.git/objects/a4
2017-12-21T01:45:12.961570536Z I1221 01:45:12.959846       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/a4/a44a9f5fcde990895bcfd1240021c683c9ddca as src/.git/objects/a4/a44a9f5fcde990895bcfd1240021c683c9ddca
2017-12-21T01:45:12.961574171Z I1221 01:45:12.959949       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/af as src/.git/objects/af
2017-12-21T01:45:12.961577267Z I1221 01:45:12.960046       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/af/f947776b1769b6e667d154122d645f7b150a83 as src/.git/objects/af/f947776b1769b6e667d154122d645f7b150a83
2017-12-21T01:45:12.961581031Z I1221 01:45:12.960165       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/b3 as src/.git/objects/b3
2017-12-21T01:45:12.961584999Z I1221 01:45:12.960256       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/b3/5478f5ae420bdf313be0050f405a8957b97153 as src/.git/objects/b3/5478f5ae420bdf313be0050f405a8957b97153
2017-12-21T01:45:12.96158872Z I1221 01:45:12.960351       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/bc as src/.git/objects/bc
2017-12-21T01:45:12.961592139Z I1221 01:45:12.960452       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/bc/0cb8f548e62100af9f815e72b1dafe9ba1974d as src/.git/objects/bc/0cb8f548e62100af9f815e72b1dafe9ba1974d
2017-12-21T01:45:12.96159595Z I1221 01:45:12.960553       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/bc/1356d49e0dc5f1688c6d91dd0bfca270b1d2dc as src/.git/objects/bc/1356d49e0dc5f1688c6d91dd0bfca270b1d2dc
2017-12-21T01:45:12.961599642Z I1221 01:45:12.960638       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/cd as src/.git/objects/cd
2017-12-21T01:45:12.961602996Z I1221 01:45:12.960733       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/cd/b36b569837bce573dc3a1d8a951add228feeeb as src/.git/objects/cd/b36b569837bce573dc3a1d8a951add228feeeb
2017-12-21T01:45:12.96160642Z I1221 01:45:12.960800       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/db as src/.git/objects/db
2017-12-21T01:45:12.961614524Z I1221 01:45:12.960922       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/db/84f883f29fb7c98b4309874a07ab0a91789f44 as src/.git/objects/db/84f883f29fb7c98b4309874a07ab0a91789f44
2017-12-21T01:45:12.961619091Z I1221 01:45:12.960991       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/df as src/.git/objects/df
2017-12-21T01:45:12.961622123Z I1221 01:45:12.961099       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/df/6078b30a7a52303ee9e0ebe7cfba0c79178347 as src/.git/objects/df/6078b30a7a52303ee9e0ebe7cfba0c79178347
2017-12-21T01:45:12.961625462Z I1221 01:45:12.961170       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/f0 as src/.git/objects/f0
2017-12-21T01:45:12.96162855Z I1221 01:45:12.961292       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/f0/3b50e4f9ec339f7a75ec5fd4f3af255e3e74ec as src/.git/objects/f0/3b50e4f9ec339f7a75ec5fd4f3af255e3e74ec
2017-12-21T01:45:12.961631924Z I1221 01:45:12.961361       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/f8 as src/.git/objects/f8
2017-12-21T01:45:12.970340147Z I1221 01:45:12.965813       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/f8/25659db8127d255def0af624073151662b09c3 as src/.git/objects/f8/25659db8127d255def0af624073151662b09c3
2017-12-21T01:45:12.970362327Z I1221 01:45:12.965918       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/info as src/.git/objects/info
2017-12-21T01:45:12.970367383Z I1221 01:45:12.966006       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/objects/pack as src/.git/objects/pack
2017-12-21T01:45:12.970371167Z I1221 01:45:12.966107       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/packed-refs as src/.git/packed-refs
2017-12-21T01:45:12.97037488Z I1221 01:45:12.966201       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/refs as src/.git/refs
2017-12-21T01:45:12.970378565Z I1221 01:45:12.966290       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/refs/heads as src/.git/refs/heads
2017-12-21T01:45:12.970382131Z I1221 01:45:12.966380       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/refs/heads/master as src/.git/refs/heads/master
2017-12-21T01:45:12.970385614Z I1221 01:45:12.966450       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/refs/remotes as src/.git/refs/remotes
2017-12-21T01:45:12.970389031Z I1221 01:45:12.966551       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/refs/remotes/origin as src/.git/refs/remotes/origin
2017-12-21T01:45:12.970405025Z I1221 01:45:12.966642       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/refs/remotes/origin/HEAD as src/.git/refs/remotes/origin/HEAD
2017-12-21T01:45:12.970410628Z I1221 01:45:12.966710       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/refs/tags as src/.git/refs/tags
2017-12-21T01:45:12.970414558Z I1221 01:45:12.966816       1 tar.go:296] Adding to tar: /tmp/upload/src/.git/shallow as src/.git/shallow
2017-12-21T01:45:12.970418198Z I1221 01:45:12.966885       1 tar.go:296] Adding to tar: /tmp/upload/src/.gitignore as src/.gitignore
2017-12-21T01:45:12.970421562Z I1221 01:45:12.966997       1 tar.go:296] Adding to tar: /tmp/upload/src/.s2i as src/.s2i
2017-12-21T01:45:12.970424732Z I1221 01:45:12.967092       1 tar.go:296] Adding to tar: /tmp/upload/src/.s2i/bin as src/.s2i/bin
2017-12-21T01:45:12.970435687Z I1221 01:45:12.967160       1 tar.go:296] Adding to tar: /tmp/upload/src/.s2i/bin/README as src/.s2i/bin/README
2017-12-21T01:45:12.970439558Z I1221 01:45:12.967240       1 tar.go:296] Adding to tar: /tmp/upload/src/.s2i/environment as src/.s2i/environment
2017-12-21T01:45:12.970444754Z I1221 01:45:12.967314       1 tar.go:296] Adding to tar: /tmp/upload/src/Dockerfile as src/Dockerfile
2017-12-21T01:45:12.97044852Z I1221 01:45:12.967409       1 tar.go:296] Adding to tar: /tmp/upload/src/Gemfile as src/Gemfile
2017-12-21T01:45:12.970451712Z I1221 01:45:12.967506       1 tar.go:296] Adding to tar: /tmp/upload/src/Gemfile.lock as src/Gemfile.lock
2017-12-21T01:45:12.970454812Z I1221 01:45:12.967602       1 tar.go:296] Adding to tar: /tmp/upload/src/README.md as src/README.md
2017-12-21T01:45:12.970458074Z I1221 01:45:12.967689       1 tar.go:296] Adding to tar: /tmp/upload/src/Rakefile as src/Rakefile
2017-12-21T01:45:12.970461181Z I1221 01:45:12.967765       1 tar.go:296] Adding to tar: /tmp/upload/src/app.rb as src/app.rb
2017-12-21T01:45:12.970464523Z I1221 01:45:12.967842       1 tar.go:296] Adding to tar: /tmp/upload/src/config as src/config
2017-12-21T01:45:12.970467649Z I1221 01:45:12.967936       1 tar.go:296] Adding to tar: /tmp/upload/src/config/database.rb as src/config/database.rb
2017-12-21T01:45:12.970470943Z I1221 01:45:12.968024       1 tar.go:296] Adding to tar: /tmp/upload/src/config/database.yml as src/config/database.yml
2017-12-21T01:45:12.970474429Z I1221 01:45:12.968108       1 tar.go:296] Adding to tar: /tmp/upload/src/config.ru as src/config.ru
2017-12-21T01:45:12.970477791Z I1221 01:45:12.968171       1 tar.go:296] Adding to tar: /tmp/upload/src/db as src/db
2017-12-21T01:45:12.970481129Z I1221 01:45:12.968265       1 tar.go:296] Adding to tar: /tmp/upload/src/db/migrate as src/db/migrate
2017-12-21T01:45:12.970484494Z I1221 01:45:12.968328       1 tar.go:296] Adding to tar: /tmp/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
2017-12-21T01:45:12.970488107Z I1221 01:45:12.968406       1 tar.go:296] Adding to tar: /tmp/upload/src/models.rb as src/models.rb
2017-12-21T01:45:12.970491358Z I1221 01:45:12.968498       1 tar.go:296] Adding to tar: /tmp/upload/src/run.sh as src/run.sh
2017-12-21T01:45:12.970494772Z I1221 01:45:12.968560       1 tar.go:296] Adding to tar: /tmp/upload/src/test as src/test
2017-12-21T01:45:12.970498791Z I1221 01:45:12.968659       1 tar.go:296] Adding to tar: /tmp/upload/src/test/sample_test.rb as src/test/sample_test.rb
2017-12-21T01:45:12.970502652Z I1221 01:45:12.968715       1 tar.go:296] Adding to tar: /tmp/upload/src/views as src/views
2017-12-21T01:45:12.970506182Z I1221 01:45:12.968781       1 tar.go:296] Adding to tar: /tmp/upload/src/views/main.erb as src/views/main.erb
2017-12-21T01:45:12.98365649Z I1221 01:45:12.982114       1 sti.go:681] ---> Installing application source ...
2017-12-21T01:45:12.986869948Z I1221 01:45:12.985208       1 sti.go:681] ---> Building your Ruby application from source ...
2017-12-21T01:45:12.986907088Z I1221 01:45:12.985224       1 sti.go:681] ---> Running 'bundle install --retry 2 --deployment --without development:test' ...
2017-12-21T01:45:17.453354611Z I1221 01:45:17.441157       1 sti.go:681] Fetching gem metadata from https://rubygems.org/..........
2017-12-21T01:45:17.851994711Z I1221 01:45:17.841353       1 sti.go:681] Installing rake 10.3.2
2017-12-21T01:45:18.037534841Z I1221 01:45:18.033427       1 sti.go:681] Installing i18n 0.6.11
2017-12-21T01:45:20.148748188Z I1221 01:45:20.146596       1 sti.go:681] Installing json 1.8.6
2017-12-21T01:45:20.290543415Z I1221 01:45:20.268628       1 sti.go:681] Installing minitest 5.4.2
2017-12-21T01:45:20.476243837Z I1221 01:45:20.475182       1 sti.go:681] Installing thread_safe 0.3.4
2017-12-21T01:45:20.68992319Z I1221 01:45:20.689575       1 sti.go:681] Installing tzinfo 1.2.2
2017-12-21T01:45:20.962573058Z I1221 01:45:20.962477       1 sti.go:681] Installing activesupport 4.1.7
2017-12-21T01:45:21.04424465Z I1221 01:45:21.042254       1 sti.go:681] Installing builder 3.2.2
2017-12-21T01:45:21.205505249Z I1221 01:45:21.187262       1 sti.go:681] Installing activemodel 4.1.7
2017-12-21T01:45:21.376190469Z I1221 01:45:21.375995       1 sti.go:681] Installing arel 5.0.1.20140414130214
2017-12-21T01:45:21.693349414Z I1221 01:45:21.691832       1 sti.go:681] Installing activerecord 4.1.7
2017-12-21T01:45:25.610265559Z I1221 01:45:25.609973       1 sti.go:681] Installing mysql2 0.3.16
2017-12-21T01:45:25.860411502Z I1221 01:45:25.860062       1 sti.go:681] Installing rack 1.5.2
2017-12-21T01:45:25.990057978Z I1221 01:45:25.988762       1 sti.go:681] Installing rack-protection 1.5.3
2017-12-21T01:45:26.174128118Z I1221 01:45:26.165252       1 sti.go:681] Installing tilt 1.4.1
2017-12-21T01:45:26.372281066Z I1221 01:45:26.370239       1 sti.go:681] Installing sinatra 1.4.5
2017-12-21T01:45:26.452607545Z I1221 01:45:26.452335       1 sti.go:681] Installing sinatra-activerecord 2.0.3
2017-12-21T01:45:26.452620411Z I1221 01:45:26.452362       1 sti.go:681] Using bundler 1.7.8
2017-12-21T01:45:26.452815454Z I1221 01:45:26.452674       1 sti.go:681] Your bundle is complete!
2017-12-21T01:45:26.453807692Z I1221 01:45:26.453569       1 sti.go:681] Gems in the groups development and test were not installed.
2017-12-21T01:45:26.453819492Z I1221 01:45:26.453594       1 sti.go:681] It was installed into ./bundle
2017-12-21T01:45:26.479152709Z I1221 01:45:26.475653       1 sti.go:681] ---> Cleaning up unused ruby gems ...
2017-12-21T01:45:28.914168978Z I1221 01:45:28.894208       1 docker.go:1055] Waiting for container "813fb273e49a4355629b598ffdc25233c33b113372a7c9546d1a4f2fcc83015d" to stop ...
2017-12-21T01:45:28.932850518Z I1221 01:45:28.930368       1 docker.go:1078] Invoking PostExecute function
2017-12-21T01:45:28.932878723Z I1221 01:45:28.930392       1 postexecutorstep.go:67] Skipping step: store previous image
2017-12-21T01:45:28.932884479Z I1221 01:45:28.930399       1 postexecutorstep.go:116] Executing step: commit image
2017-12-21T01:45:28.952653474Z I1221 01:45:28.933865       1 postexecutorstep.go:521] Checking for new Labels to apply... 
2017-12-21T01:45:28.952680812Z I1221 01:45:28.933885       1 postexecutorstep.go:529] Creating the download path '/tmp/metadata'
2017-12-21T01:45:28.952686031Z I1221 01:45:28.934046       1 postexecutorstep.go:463] Downloading file "/tmp/.s2i/image_metadata.json"
2017-12-21T01:45:28.96871966Z I1221 01:45:28.967677       1 postexecutorstep.go:537] unable to download and extract 'image_metadata.json' ... continuing
2017-12-21T01:45:28.975553593Z I1221 01:45:28.975322       1 docker.go:1112] Committing container with dockerOpts: {Reference:docker.io/extended-test-build-no-outputname-kxlwx-6pjnp/test-sti-1:86ee27f1 Comment: Author: Changes:[] Pause:false Config:0xc4208de6c0}, config: {Hostname: Domainname: User:1001 AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production OPENSHIFT_BUILD_NAME=test-sti-1 OPENSHIFT_BUILD_NAMESPACE=extended-test-build-no-outputname-kxlwx-6pjnp OPENSHIFT_BUILD_SOURCE=https://github.com/openshift/ruby-hello-world OPENSHIFT_BUILD_COMMIT=022d87e4160c00274b63cdad7c238b5c6a299265 BUILD_LOGLEVEL=5] Cmd:[/usr/libexec/s2i/run] ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[container-entrypoint] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[com.redhat.component:rh-ruby22-docker io.openshift.s2i.scripts-url:image:///usr/libexec/s2i build-date:20171128 description:Ruby 2.2 available as docker container is a base platform for building and running various Ruby 2.2 applications and frameworks. Ruby is the interpreted scripting language for quick and easy object-oriented programming. It has many features to process text files and to do system management tasks (as in Perl). It is simple, straight-forward, and extensible. maintainer:SoftwareCollections.org <sclorg@redhat.com> io.openshift.tags:builder,ruby,ruby22 version:2.2 vendor:CentOS io.openshift.builder-version:"d7fd5c1" io.openshift.expose-services:8080:http name:centos/ruby-22-centos7 io.openshift.build.commit.message:Merge pull request #58 from junaruga/feature/fix-for-ruby24 io.openshift.build.commit.id:022d87e4160c00274b63cdad7c238b5c6a299265 usage:s2i build https://github.com/sclorg/s2i-ruby-container.git --context-dir=2.4/test/puma-test-app/ centos/ruby-22-centos7 ruby-sample-app summary:Platform for building and running Ruby 2.2 applications io.openshift.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.build.image:centos/ruby-22-centos7 io.s2i.scripts-url:image:///usr/libexec/s2i release:1 io.k8s.display-name:docker.io/extended-test-build-no-outputname-kxlwx-6pjnp/test-sti-1:86ee27f1 license:GPLv2 io.openshift.build.commit.ref:master io.openshift.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.build.commit.date:Fri Mar 3 15:29:12 2017 -0500 io.k8s.description:Ruby 2.2 available as docker container is a base platform for building and running various Ruby 2.2 applications and frameworks. Ruby is the interpreted scripting language for quick and easy object-oriented programming. It has many features to process text files and to do system management tasks (as in Perl). It is simple, straight-forward, and extensible.] StopSignal:}
2017-12-21T01:45:29.787907933Z I1221 01:45:29.786545       1 postexecutorstep.go:391] Executing step: report success
2017-12-21T01:45:29.787931288Z I1221 01:45:29.786575       1 postexecutorstep.go:396] Successfully built docker.io/extended-test-build-no-outputname-kxlwx-6pjnp/test-sti-1:86ee27f1
2017-12-21T01:45:29.787936561Z I1221 01:45:29.786584       1 postexecutorstep.go:92] Skipping step: remove previous image
2017-12-21T01:45:29.787953668Z I1221 01:45:29.786624       1 docker.go:991] Removing container "813fb273e49a4355629b598ffdc25233c33b113372a7c9546d1a4f2fcc83015d" ...
2017-12-21T01:45:29.890928697Z I1221 01:45:29.890209       1 docker.go:1001] Removed container "813fb273e49a4355629b598ffdc25233c33b113372a7c9546d1a4f2fcc83015d"
2017-12-21T01:45:29.890955121Z I1221 01:45:29.890327       1 cleanup.go:31] Temporary directory "/tmp" will be saved, not deleted
2017-12-21T01:45:30.074922714Z Build complete, no image push requested
[AfterEach] 
  /go/src/github.com/openshift/origin/test/extended/builds/no_outputname.go:22
[AfterEach] [Feature:Builds][Conformance] build without output image
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:45:31.863: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-build-no-outputname-kxlwx-6pjnp" for this suite.
Dec 21 01:45:39.951: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:45:41.689: INFO: namespace: extended-test-build-no-outputname-kxlwx-6pjnp, resource: bindings, ignored listing per whitelist
Dec 21 01:45:41.708: INFO: namespace extended-test-build-no-outputname-kxlwx-6pjnp deletion completed in 9.81208439s


• [SLOW TEST:37.427 seconds]
[Feature:Builds][Conformance] build without output image
/go/src/github.com/openshift/origin/test/extended/builds/no_outputname.go:12
  
  /go/src/github.com/openshift/origin/test/extended/builds/no_outputname.go:20
    building from templates
    /go/src/github.com/openshift/origin/test/extended/builds/no_outputname.go:29
      should create an image from a S2i template without an output image reference defined [Suite:openshift/conformance/parallel]
      /go/src/github.com/openshift/origin/test/extended/builds/no_outputname.go:47
------------------------------
Dec 21 01:45:41.710: INFO: Running AfterSuite actions on all node


[sig-storage] Downward API volume 
  should provide node allocatable (memory) as default memory limit if the limit is not set  [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Downward API volume
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:45:23.500: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:45:23.657: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [sig-storage] Downward API volume
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:38
[It] should provide node allocatable (memory) as default memory limit if the limit is not set  [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648
STEP: Creating a pod to test downward API volume plugin
Dec 21 01:45:23.947: INFO: Waiting up to 5m0s for pod "downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40" in namespace "e2e-tests-downward-api-d54st" to be "success or failure"
Dec 21 01:45:23.968: INFO: Pod "downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40": Phase="Pending", Reason="", readiness=false. Elapsed: 21.456123ms
Dec 21 01:45:25.986: INFO: Pod "downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40": Phase="Pending", Reason="", readiness=false. Elapsed: 2.039260189s
Dec 21 01:45:28.010: INFO: Pod "downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40": Phase="Pending", Reason="", readiness=false. Elapsed: 4.063663122s
Dec 21 01:45:30.063: INFO: Pod "downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40": Phase="Pending", Reason="", readiness=false. Elapsed: 6.116192045s
Dec 21 01:45:32.080: INFO: Pod "downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40": Phase="Succeeded", Reason="", readiness=false. Elapsed: 8.133418961s
STEP: Saw pod success
Dec 21 01:45:32.080: INFO: Pod "downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40" satisfied condition "success or failure"
Dec 21 01:45:32.097: INFO: Trying to get logs from node ci-primg625-ig-n-th01 pod downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40 container client-container: <nil>
STEP: delete the pod
Dec 21 01:45:32.157: INFO: Waiting for pod downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40 to disappear
Dec 21 01:45:32.172: INFO: Pod downwardapi-volume-9c844f2e-e5f0-11e7-b48a-0eadb8886b40 no longer exists
[AfterEach] [sig-storage] Downward API volume
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:45:32.172: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-downward-api-d54st" for this suite.
Dec 21 01:45:40.272: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:45:41.926: INFO: namespace: e2e-tests-downward-api-d54st, resource: bindings, ignored listing per whitelist
Dec 21 01:45:41.995: INFO: namespace e2e-tests-downward-api-d54st deletion completed in 9.792437985s


• [SLOW TEST:18.496 seconds]
[sig-storage] Downward API volume
/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/downwardapi_volume.go:33
  should provide node allocatable (memory) as default memory limit if the limit is not set  [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648
------------------------------
Dec 21 01:45:41.996: INFO: Running AfterSuite actions on all node


[sig-storage] Projected 
  optional updates should be reflected in volume [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:44:02.829: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:44:02.950: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:858
[It] optional updates should be reflected in volume [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648
Dec 21 01:44:03.206: INFO: Couldn't get node TTL annotation (using default value of 0): No TTL annotation found on the node
STEP: Creating configMap with name cm-test-opt-del-6c6cadce-e5f0-11e7-ba04-0eadb8886b40
STEP: Creating configMap with name cm-test-opt-upd-6c6cae09-e5f0-11e7-ba04-0eadb8886b40
STEP: Creating the pod
STEP: Deleting configmap cm-test-opt-del-6c6cadce-e5f0-11e7-ba04-0eadb8886b40
STEP: Updating configmap cm-test-opt-upd-6c6cae09-e5f0-11e7-ba04-0eadb8886b40
STEP: Creating configMap with name cm-test-opt-create-6c6cae1e-e5f0-11e7-ba04-0eadb8886b40
STEP: waiting to observe update in volume
[AfterEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:45:17.314: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-projected-n86p4" for this suite.
Dec 21 01:45:41.476: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:45:43.026: INFO: namespace: e2e-tests-projected-n86p4, resource: bindings, ignored listing per whitelist
Dec 21 01:45:43.093: INFO: namespace e2e-tests-projected-n86p4 deletion completed in 25.737509365s


• [SLOW TEST:100.265 seconds]
[sig-storage] Projected
/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:34
  optional updates should be reflected in volume [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648
------------------------------
Dec 21 01:45:43.095: INFO: Running AfterSuite actions on all node


[Feature:DeploymentConfig] deploymentconfigs when tagging images [Conformance] 
  should successfully tag the deployed image [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:414

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:45:17.282: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:45:18.325: INFO: configPath is now "/tmp/extended-test-cli-deployment-6wtq5-6hpbm-user.kubeconfig"
Dec 21 01:45:18.325: INFO: The user is now "extended-test-cli-deployment-6wtq5-6hpbm-user"
Dec 21 01:45:18.325: INFO: Creating project "extended-test-cli-deployment-6wtq5-6hpbm"
Dec 21 01:45:18.693: INFO: Waiting on permissions in project "extended-test-cli-deployment-6wtq5-6hpbm" ...
STEP: Waiting for a default service account to be provisioned in namespace
[JustBeforeEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:43
[It] should successfully tag the deployed image [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:414
STEP: creating the deployment config fixture
Dec 21 01:45:18.803: INFO: Running 'oc create --config=/tmp/extended-test-cli-deployment-6wtq5-6hpbm-user.kubeconfig --namespace=extended-test-cli-deployment-6wtq5-6hpbm -f /tmp/fixture-testdata-dir237035411/test/extended/testdata/deployments/tag-images-deployment.yaml -o name'
STEP: verifying the deployment is marked complete
Dec 21 01:45:37.023: INFO: Latest rollout of dc/tag-images (rc/tag-images-1) is complete.
STEP: verifying the deployer service account can update imagestreamtags and user can get them
STEP: verifying the post deployment action happened: tag is set
[AfterEach] when tagging images [Conformance]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:410
[AfterEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:62
[AfterEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:45:39.086: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-cli-deployment-6wtq5-6hpbm" for this suite.
Dec 21 01:45:47.180: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:45:47.785: INFO: namespace: extended-test-cli-deployment-6wtq5-6hpbm, resource: bindings, ignored listing per whitelist
Dec 21 01:45:48.669: INFO: namespace extended-test-cli-deployment-6wtq5-6hpbm deletion completed in 9.55051518s


• [SLOW TEST:31.387 seconds]
[Feature:DeploymentConfig] deploymentconfigs
/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:37
  when tagging images [Conformance]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:409
    should successfully tag the deployed image [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:414
------------------------------
Dec 21 01:45:48.670: INFO: Running AfterSuite actions on all node


[k8s.io] InitContainer 
  should not start app containers if init containers fail on a RestartAlways pod [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/init_container.go:166

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [k8s.io] InitContainer
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:43:34.350: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:43:34.473: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [k8s.io] InitContainer
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/init_container.go:40
[It] should not start app containers if init containers fail on a RestartAlways pod [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/init_container.go:166
STEP: creating the pod
Dec 21 01:43:34.714: INFO: PodSpec: initContainers in spec.initContainers
Dec 21 01:44:54.804: INFO: init container has failed twice: &v1.Pod{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"pod-init-5b6e451e-e5f0-11e7-91be-0eadb8886b40", GenerateName:"", Namespace:"e2e-tests-init-container-6jg8p", SelfLink:"/api/v1/namespaces/e2e-tests-init-container-6jg8p/pods/pod-init-5b6e451e-e5f0-11e7-91be-0eadb8886b40", UID:"5b76f559-e5f0-11e7-9bf7-42010a8e0005", ResourceVersion:"19987", Generation:0, CreationTimestamp:v1.Time{Time:time.Time{wall:0x0, ext:63649417414, loc:(*time.Location)(0x6930d80)}}, DeletionTimestamp:(*v1.Time)(nil), DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string{"name":"foo", "time":"695457027"}, Annotations:map[string]string{"openshift.io/scc":"anyuid"}, OwnerReferences:[]v1.OwnerReference(nil), Initializers:(*v1.Initializers)(nil), Finalizers:[]string(nil), ClusterName:""}, Spec:v1.PodSpec{Volumes:[]v1.Volume{v1.Volume{Name:"default-token-b6gns", VolumeSource:v1.VolumeSource{HostPath:(*v1.HostPathVolumeSource)(nil), EmptyDir:(*v1.EmptyDirVolumeSource)(nil), GCEPersistentDisk:(*v1.GCEPersistentDiskVolumeSource)(nil), AWSElasticBlockStore:(*v1.AWSElasticBlockStoreVolumeSource)(nil), GitRepo:(*v1.GitRepoVolumeSource)(nil), Secret:(*v1.SecretVolumeSource)(0xc42185ff40), NFS:(*v1.NFSVolumeSource)(nil), ISCSI:(*v1.ISCSIVolumeSource)(nil), Glusterfs:(*v1.GlusterfsVolumeSource)(nil), PersistentVolumeClaim:(*v1.PersistentVolumeClaimVolumeSource)(nil), RBD:(*v1.RBDVolumeSource)(nil), FlexVolume:(*v1.FlexVolumeSource)(nil), Cinder:(*v1.CinderVolumeSource)(nil), CephFS:(*v1.CephFSVolumeSource)(nil), Flocker:(*v1.FlockerVolumeSource)(nil), DownwardAPI:(*v1.DownwardAPIVolumeSource)(nil), FC:(*v1.FCVolumeSource)(nil), AzureFile:(*v1.AzureFileVolumeSource)(nil), ConfigMap:(*v1.ConfigMapVolumeSource)(nil), VsphereVolume:(*v1.VsphereVirtualDiskVolumeSource)(nil), Quobyte:(*v1.QuobyteVolumeSource)(nil), AzureDisk:(*v1.AzureDiskVolumeSource)(nil), PhotonPersistentDisk:(*v1.PhotonPersistentDiskVolumeSource)(nil), Projected:(*v1.ProjectedVolumeSource)(nil), PortworxVolume:(*v1.PortworxVolumeSource)(nil), ScaleIO:(*v1.ScaleIOVolumeSource)(nil), StorageOS:(*v1.StorageOSVolumeSource)(nil)}}}, InitContainers:[]v1.Container{v1.Container{Name:"init1", Image:"busybox", Command:[]string{"/bin/false"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount{v1.VolumeMount{Name:"default-token-b6gns", ReadOnly:true, MountPath:"/var/run/secrets/kubernetes.io/serviceaccount", SubPath:"", MountPropagation:(*v1.MountPropagationMode)(nil)}}, VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"Always", SecurityContext:(*v1.SecurityContext)(0xc42119c2c0), Stdin:false, StdinOnce:false, TTY:false}, v1.Container{Name:"init2", Image:"busybox", Command:[]string{"/bin/true"}, Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList(nil), Requests:v1.ResourceList(nil)}, VolumeMounts:[]v1.VolumeMount{v1.VolumeMount{Name:"default-token-b6gns", ReadOnly:true, MountPath:"/var/run/secrets/kubernetes.io/serviceaccount", SubPath:"", MountPropagation:(*v1.MountPropagationMode)(nil)}}, VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"Always", SecurityContext:(*v1.SecurityContext)(0xc42119c440), Stdin:false, StdinOnce:false, TTY:false}}, Containers:[]v1.Container{v1.Container{Name:"run1", Image:"gcr.io/google_containers/pause-amd64:3.0", Command:[]string(nil), Args:[]string(nil), WorkingDir:"", Ports:[]v1.ContainerPort(nil), EnvFrom:[]v1.EnvFromSource(nil), Env:[]v1.EnvVar(nil), Resources:v1.ResourceRequirements{Limits:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:31457280, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"31457280", Format:"DecimalSI"}}, Requests:v1.ResourceList{"cpu":resource.Quantity{i:resource.int64Amount{value:100, scale:-3}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"100m", Format:"DecimalSI"}, "memory":resource.Quantity{i:resource.int64Amount{value:31457280, scale:0}, d:resource.infDecAmount{Dec:(*inf.Dec)(nil)}, s:"31457280", Format:"DecimalSI"}}}, VolumeMounts:[]v1.VolumeMount{v1.VolumeMount{Name:"default-token-b6gns", ReadOnly:true, MountPath:"/var/run/secrets/kubernetes.io/serviceaccount", SubPath:"", MountPropagation:(*v1.MountPropagationMode)(nil)}}, VolumeDevices:[]v1.VolumeDevice(nil), LivenessProbe:(*v1.Probe)(nil), ReadinessProbe:(*v1.Probe)(nil), Lifecycle:(*v1.Lifecycle)(nil), TerminationMessagePath:"/dev/termination-log", TerminationMessagePolicy:"File", ImagePullPolicy:"IfNotPresent", SecurityContext:(*v1.SecurityContext)(0xc42119c0c0), Stdin:false, StdinOnce:false, TTY:false}}, RestartPolicy:"Always", TerminationGracePeriodSeconds:(*int64)(0xc42171c6f0), ActiveDeadlineSeconds:(*int64)(nil), DNSPolicy:"ClusterFirst", NodeSelector:map[string]string{"role":"app"}, ServiceAccountName:"default", DeprecatedServiceAccount:"default", AutomountServiceAccountToken:(*bool)(nil), NodeName:"ci-primg625-ig-n-th01", HostNetwork:false, HostPID:false, HostIPC:false, SecurityContext:(*v1.PodSecurityContext)(0xc42119c200), ImagePullSecrets:[]v1.LocalObjectReference{v1.LocalObjectReference{Name:"default-dockercfg-fslwb"}}, Hostname:"", Subdomain:"", Affinity:(*v1.Affinity)(nil), SchedulerName:"default-scheduler", Tolerations:[]v1.Toleration(nil), HostAliases:[]v1.HostAlias(nil), PriorityClassName:"", Priority:(*int32)(nil), DNSConfig:(*v1.PodDNSConfig)(nil)}, Status:v1.PodStatus{Phase:"Pending", Conditions:[]v1.PodCondition{v1.PodCondition{Type:"Initialized", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63649417414, loc:(*time.Location)(0x6930d80)}}, Reason:"ContainersNotInitialized", Message:"containers with incomplete status: [init1 init2]"}, v1.PodCondition{Type:"Ready", Status:"False", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63649417414, loc:(*time.Location)(0x6930d80)}}, Reason:"ContainersNotReady", Message:"containers with unready status: [run1]"}, v1.PodCondition{Type:"PodScheduled", Status:"True", LastProbeTime:v1.Time{Time:time.Time{wall:0x0, ext:0, loc:(*time.Location)(nil)}}, LastTransitionTime:v1.Time{Time:time.Time{wall:0x0, ext:63649417414, loc:(*time.Location)(0x6930d80)}}, Reason:"", Message:""}}, Message:"", Reason:"", HostIP:"10.142.0.2", PodIP:"172.16.0.78", StartTime:(*v1.Time)(0xc420a99780), InitContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"init1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(0xc420a997c0), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(0xc4203e3ce0)}, Ready:false, RestartCount:3, Image:"docker.io/busybox:latest", ImageID:"docker-pullable://docker.io/busybox@sha256:91ef6c1c52b166be02645b8efee30d1ee65362024f7da41c404681561734c465", ContainerID:"docker://556d0d498de9a123293ad8a97b871c1fe58248049a21951bb51a7c9a93fa534c"}, v1.ContainerStatus{Name:"init2", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc420a997e0), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"busybox", ImageID:"", ContainerID:""}}, ContainerStatuses:[]v1.ContainerStatus{v1.ContainerStatus{Name:"run1", State:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(0xc420a997a0), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, LastTerminationState:v1.ContainerState{Waiting:(*v1.ContainerStateWaiting)(nil), Running:(*v1.ContainerStateRunning)(nil), Terminated:(*v1.ContainerStateTerminated)(nil)}, Ready:false, RestartCount:0, Image:"gcr.io/google_containers/pause-amd64:3.0", ImageID:"", ContainerID:""}}, QOSClass:"Guaranteed"}}
[AfterEach] [k8s.io] InitContainer
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:44:54.805: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-init-container-6jg8p" for this suite.
Dec 21 01:45:54.886: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:45:56.255: INFO: namespace: e2e-tests-init-container-6jg8p, resource: bindings, ignored listing per whitelist
Dec 21 01:45:56.352: INFO: namespace e2e-tests-init-container-6jg8p deletion completed in 1m1.517104472s


• [SLOW TEST:142.002 seconds]
[k8s.io] InitContainer
/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:643
  should not start app containers if init containers fail on a RestartAlways pod [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/init_container.go:166
------------------------------
Dec 21 01:45:56.354: INFO: Running AfterSuite actions on all node


[Feature:Builds][pullsecret][Conformance] docker build using a pull secret  Building from a template 
  should create a docker build that pulls using a secret run it [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:40

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Feature:Builds][pullsecret][Conformance] docker build using a pull secret
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:45:09.663: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:45:09.890: INFO: configPath is now "/tmp/extended-test-docker-build-pullsecret-g2tlm-nvrbq-user.kubeconfig"
Dec 21 01:45:09.890: INFO: The user is now "extended-test-docker-build-pullsecret-g2tlm-nvrbq-user"
Dec 21 01:45:09.890: INFO: Creating project "extended-test-docker-build-pullsecret-g2tlm-nvrbq"
Dec 21 01:45:10.025: INFO: Waiting on permissions in project "extended-test-docker-build-pullsecret-g2tlm-nvrbq" ...
STEP: Waiting for a default service account to be provisioned in namespace
[JustBeforeEach] 
  /go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:26
STEP: waiting for builder service account
[It] should create a docker build that pulls using a secret run it [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:40
STEP: calling oc create -f "/tmp/fixture-testdata-dir267030604/test/extended/testdata/builds/test-docker-build-pullsecret.json"
Dec 21 01:45:10.244: INFO: Running 'oc create --config=/tmp/extended-test-docker-build-pullsecret-g2tlm-nvrbq-user.kubeconfig --namespace=extended-test-docker-build-pullsecret-g2tlm-nvrbq -f /tmp/fixture-testdata-dir267030604/test/extended/testdata/builds/test-docker-build-pullsecret.json'
imagestream "image1" created
buildconfig "docker-build" created
buildconfig "docker-build-pull" created
STEP: starting a build
Dec 21 01:45:10.571: INFO: Running 'oc start-build --config=/tmp/extended-test-docker-build-pullsecret-g2tlm-nvrbq-user.kubeconfig --namespace=extended-test-docker-build-pullsecret-g2tlm-nvrbq docker-build -o=name'
Dec 21 01:45:11.044: INFO: 

start-build output with args [docker-build -o=name]:
Error><nil>
StdOut>
build/docker-build-1
StdErr>



Dec 21 01:45:11.045: INFO: Waiting for docker-build-1 to complete

Dec 21 01:45:47.133: INFO: Done waiting for docker-build-1: util.BuildResult{BuildPath:"build/docker-build-1", BuildName:"docker-build-1", StartBuildStdErr:"", StartBuildStdOut:"build/docker-build-1", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc42183c600), BuildAttempt:true, BuildSuccess:true, BuildFailure:false, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc420e73260)}
 with error: <nil>

STEP: starting a second build that pulls the image from the first build
Dec 21 01:45:47.133: INFO: Running 'oc start-build --config=/tmp/extended-test-docker-build-pullsecret-g2tlm-nvrbq-user.kubeconfig --namespace=extended-test-docker-build-pullsecret-g2tlm-nvrbq docker-build-pull -o=name'
Dec 21 01:45:47.438: INFO: 

start-build output with args [docker-build-pull -o=name]:
Error><nil>
StdOut>
build/docker-build-pull-1
StdErr>



Dec 21 01:45:47.440: INFO: Waiting for docker-build-pull-1 to complete

Dec 21 01:45:53.473: INFO: Done waiting for docker-build-pull-1: util.BuildResult{BuildPath:"build/docker-build-pull-1", BuildName:"docker-build-pull-1", StartBuildStdErr:"", StartBuildStdOut:"build/docker-build-pull-1", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc4211b3800), BuildAttempt:true, BuildSuccess:true, BuildFailure:false, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc420e73260)}
 with error: <nil>

[AfterEach] 
  /go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:32
[AfterEach] [Feature:Builds][pullsecret][Conformance] docker build using a pull secret
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:45:53.473: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-docker-build-pullsecret-g2tlm-nvrbq" for this suite.
Dec 21 01:45:59.554: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:46:00.731: INFO: namespace: extended-test-docker-build-pullsecret-g2tlm-nvrbq, resource: bindings, ignored listing per whitelist
Dec 21 01:46:01.078: INFO: namespace extended-test-docker-build-pullsecret-g2tlm-nvrbq deletion completed in 7.573739157s


• [SLOW TEST:51.415 seconds]
[Feature:Builds][pullsecret][Conformance] docker build using a pull secret
/go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:12
  
  /go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:24
    Building from a template
    /go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:39
      should create a docker build that pulls using a secret run it [Suite:openshift/conformance/parallel]
      /go/src/github.com/openshift/origin/test/extended/builds/docker_pullsecret.go:40
------------------------------
Dec 21 01:46:01.079: INFO: Running AfterSuite actions on all node


[k8s.io] Pods 
  should support remote command execution over websockets [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/pods.go:470

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [k8s.io] Pods
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:45:03.444: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:45:03.618: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [k8s.io] Pods
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/pods.go:127
[It] should support remote command execution over websockets [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/pods.go:470
Dec 21 01:45:03.832: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: creating the pod
STEP: submitting the pod to kubernetes
[AfterEach] [k8s.io] Pods
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:45:18.488: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-pods-hmfb7" for this suite.
Dec 21 01:46:04.804: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:46:06.201: INFO: namespace: e2e-tests-pods-hmfb7, resource: bindings, ignored listing per whitelist
Dec 21 01:46:06.262: INFO: namespace e2e-tests-pods-hmfb7 deletion completed in 47.682964353s


• [SLOW TEST:62.819 seconds]
[k8s.io] Pods
/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:643
  should support remote command execution over websockets [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/pods.go:470
------------------------------
Dec 21 01:46:06.263: INFO: Running AfterSuite actions on all node


[Feature:DeploymentConfig] deploymentconfigs keep the deployer pod invariant valid [Conformance] 
  should deal with cancellation after deployer pod succeeded [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:1292

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:44:47.312: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:44:47.619: INFO: configPath is now "/tmp/extended-test-cli-deployment-gf4zh-8l9s8-user.kubeconfig"
Dec 21 01:44:47.619: INFO: The user is now "extended-test-cli-deployment-gf4zh-8l9s8-user"
Dec 21 01:44:47.619: INFO: Creating project "extended-test-cli-deployment-gf4zh-8l9s8"
Dec 21 01:44:47.804: INFO: Waiting on permissions in project "extended-test-cli-deployment-gf4zh-8l9s8" ...
STEP: Waiting for a default service account to be provisioned in namespace
[JustBeforeEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:43
[It] should deal with cancellation after deployer pod succeeded [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:1292
STEP: creating DC
STEP: waiting for RC to be created
STEP: waiting for deployer to be completed
STEP: canceling the deployment
STEP: redeploying immediately by config change
[AfterEach] keep the deployer pod invariant valid [Conformance]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:1158
[AfterEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:62
[AfterEach] [Feature:DeploymentConfig] deploymentconfigs
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:45:14.874: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-cli-deployment-gf4zh-8l9s8" for this suite.
Dec 21 01:46:05.046: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:46:06.259: INFO: namespace: extended-test-cli-deployment-gf4zh-8l9s8, resource: bindings, ignored listing per whitelist
Dec 21 01:46:06.525: INFO: namespace extended-test-cli-deployment-gf4zh-8l9s8 deletion completed in 51.608498312s


• [SLOW TEST:79.214 seconds]
[Feature:DeploymentConfig] deploymentconfigs
/go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:37
  keep the deployer pod invariant valid [Conformance]
  /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:1155
    should deal with cancellation after deployer pod succeeded [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/test/extended/deployments/deployments.go:1292
------------------------------
[Feature:Prometheus][Feature:Builds] Prometheus when installed to the cluster 
  should start and expose a secured proxy and verify build metrics [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/prometheus/prometheus_builds.go:36

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Feature:Prometheus][Feature:Builds] Prometheus
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:45:10.415: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:45:10.675: INFO: configPath is now "/tmp/extended-test-prometheus-sqbmr-6bpkt-user.kubeconfig"
Dec 21 01:45:10.675: INFO: The user is now "extended-test-prometheus-sqbmr-6bpkt-user"
Dec 21 01:45:10.675: INFO: Creating project "extended-test-prometheus-sqbmr-6bpkt"
Dec 21 01:45:10.918: INFO: Waiting on permissions in project "extended-test-prometheus-sqbmr-6bpkt" ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [Feature:Prometheus][Feature:Builds] Prometheus
  /go/src/github.com/openshift/origin/test/extended/prometheus/prometheus_builds.go:31
[It] should start and expose a secured proxy and verify build metrics [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/prometheus/prometheus_builds.go:36
Dec 21 01:45:11.180: INFO: Creating new exec pod
STEP: verifying the oauth-proxy reports a 403 on the root URL
Dec 21 01:45:15.307: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -k -s -o /dev/null -w '%{http_code}' "https://prometheus.kube-system.svc:443"'
Dec 21 01:45:16.303: INFO: stderr: ""
STEP: verifying a service account token is able to authenticate
Dec 21 01:45:16.303: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -k -s -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' -o /dev/null -w '%{http_code}' "https://prometheus.kube-system.svc:443/graph"'
Dec 21 01:45:17.042: INFO: stderr: ""
STEP: waiting for builder service account
STEP: calling oc new-app  /tmp/fixture-testdata-dir706685474/examples/jenkins/application-template.json 
Dec 21 01:45:17.164: INFO: Running 'oc new-app --config=/tmp/extended-test-prometheus-sqbmr-6bpkt-user.kubeconfig --namespace=extended-test-prometheus-sqbmr-6bpkt /tmp/fixture-testdata-dir706685474/examples/jenkins/application-template.json'
--> Deploying template "extended-test-prometheus-sqbmr-6bpkt/nodejs-helloworld-sample" for "/tmp/fixture-testdata-dir706685474/examples/jenkins/application-template.json" to project extended-test-prometheus-sqbmr-6bpkt

     nodejs-helloworld-sample
     ---------
     This example shows how to create a simple nodejs application in openshift origin v3

     * With parameters:
        * Memory Limit=512Mi
        * Namespace=openshift
        * Administrator Username=admin86F # generated
        * Administrator Password=BnbOReuF # generated

--> Creating resources ...
    service "frontend-prod" created
    route "frontend" created
    deploymentconfig "frontend-prod" created
    service "frontend" created
    imagestream "origin-nodejs-sample" created
    imagestream "origin-nodejs-sample2" created
    imagestream "origin-nodejs-sample3" created
    imagestream "nodejs-010-centos7" created
    buildconfig "frontend" created
    deploymentconfig "frontend" created
--> Success
    Access your application via route 'frontend-extended-test-prometheus-sqbmr-6bpkt.apps.primg625.origin-ci-int-gce.dev.rhcloud.com' 
    Use 'oc start-build frontend' to start a build.
    Run 'oc status' to view your app.
STEP: wait on imagestreams used by build
Dec 21 01:45:18.298: INFO: Running scan #0 

Dec 21 01:45:18.298: INFO: Checking language ruby 

Dec 21 01:45:18.389: INFO: Checking tag latest 

Dec 21 01:45:18.389: INFO: Checking tag 2.0 

Dec 21 01:45:18.389: INFO: Checking tag 2.2 

Dec 21 01:45:18.389: INFO: Checking tag 2.3 

Dec 21 01:45:18.389: INFO: Checking tag 2.4 

Dec 21 01:45:18.389: INFO: Checking language nodejs 

Dec 21 01:45:18.443: INFO: Checking tag 4 

Dec 21 01:45:18.443: INFO: Checking tag 6 

Dec 21 01:45:18.443: INFO: Checking tag latest 

Dec 21 01:45:18.443: INFO: Checking tag 0.10 

Dec 21 01:45:18.443: INFO: Checking language perl 

Dec 21 01:45:18.464: INFO: Checking tag 5.16 

Dec 21 01:45:18.464: INFO: Checking tag 5.20 

Dec 21 01:45:18.464: INFO: Checking tag 5.24 

Dec 21 01:45:18.464: INFO: Checking tag latest 

Dec 21 01:45:18.464: INFO: Checking language php 

Dec 21 01:45:18.521: INFO: Checking tag 5.5 

Dec 21 01:45:18.521: INFO: Checking tag 5.6 

Dec 21 01:45:18.521: INFO: Checking tag 7.0 

Dec 21 01:45:18.521: INFO: Checking tag latest 

Dec 21 01:45:18.521: INFO: Checking language python 

Dec 21 01:45:18.611: INFO: Checking tag 3.5 

Dec 21 01:45:18.611: INFO: Checking tag latest 

Dec 21 01:45:18.611: INFO: Checking tag 2.7 

Dec 21 01:45:18.611: INFO: Checking tag 3.3 

Dec 21 01:45:18.611: INFO: Checking tag 3.4 

Dec 21 01:45:18.611: INFO: Checking language wildfly 

Dec 21 01:45:18.654: INFO: Checking tag 9.0 

Dec 21 01:45:18.655: INFO: Checking tag latest 

Dec 21 01:45:18.655: INFO: Checking tag 10.0 

Dec 21 01:45:18.655: INFO: Checking tag 10.1 

Dec 21 01:45:18.655: INFO: Checking tag 8.1 

Dec 21 01:45:18.655: INFO: Checking language mysql 

Dec 21 01:45:18.680: INFO: Checking tag 5.5 

Dec 21 01:45:18.680: INFO: Checking tag 5.6 

Dec 21 01:45:18.680: INFO: Checking tag 5.7 

Dec 21 01:45:18.680: INFO: Checking tag latest 

Dec 21 01:45:18.680: INFO: Checking language postgresql 

Dec 21 01:45:18.800: INFO: Checking tag 9.2 

Dec 21 01:45:18.800: INFO: Checking tag 9.4 

Dec 21 01:45:18.800: INFO: Checking tag 9.5 

Dec 21 01:45:18.800: INFO: Checking tag latest 

Dec 21 01:45:18.800: INFO: Checking language mongodb 

Dec 21 01:45:18.845: INFO: Checking tag 2.4 

Dec 21 01:45:18.845: INFO: Checking tag 2.6 

Dec 21 01:45:18.845: INFO: Checking tag 3.2 

Dec 21 01:45:18.845: INFO: Checking tag latest 

Dec 21 01:45:18.845: INFO: Checking language jenkins 

Dec 21 01:45:18.900: INFO: Checking tag 1 

Dec 21 01:45:18.900: INFO: Checking tag 2 

Dec 21 01:45:18.900: INFO: Checking tag latest 

Dec 21 01:45:18.900: INFO: Success! 

STEP: explicitly set up image stream tag, avoid timing window
Dec 21 01:45:18.900: INFO: Running 'oc tag --config=/tmp/cluster-admin.kubeconfig --namespace=extended-test-prometheus-sqbmr-6bpkt openshift/nodejs:latest extended-test-prometheus-sqbmr-6bpkt/nodejs-010-centos7:latest'
Tag nodejs-010-centos7:latest set to openshift/nodejs@sha256:ac68512255a6233acf34bad52bdf7d2e731e5d98d82e516931360ebc4fb1e686.
STEP: start build
Dec 21 01:45:19.641: INFO: Running 'oc start-build --config=/tmp/extended-test-prometheus-sqbmr-6bpkt-user.kubeconfig --namespace=extended-test-prometheus-sqbmr-6bpkt frontend -o=name'
Dec 21 01:45:20.230: INFO: 

start-build output with args [frontend -o=name]:
Error><nil>
StdOut>
build/frontend-1
StdErr>



STEP: verifying a service account token is able to query active build metrics from the Prometheus API
STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:20.230: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:21.105: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820721.076,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:22.105: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:22.982: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820722.949,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:23.982: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:24.838: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820724.811,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:25.839: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:26.491: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820726.46,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:27.491: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:28.095: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820728.068,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:29.096: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:29.763: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820729.738,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:30.764: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:31.434: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820731.401,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:32.434: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:33.061: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820733.036,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:34.062: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:34.727: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820734.701,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:35.727: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:36.420: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820736.389,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:37.420: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:38.257: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820738.206,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:39.257: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:39.908: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820739.881,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:40.908: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:41.563: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820741.533,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:42.563: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:43.354: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820743.327,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:44.354: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:45.675: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820745.407,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:46.675: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:47.732: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820747.631,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:48.732: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:49.848: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820749.366,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:50.849: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:52.149: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820751.891,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:53.149: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:54.102: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820754.074,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:55.103: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:56.123: INFO: stderr: ""
query openshift_build_active_time_seconds for tests []prometheus.metricTest{prometheus.metricTest{labels:map[string]string{"name":"frontend-1"}, greaterThanEqual:true, value:0, success:false}} had results {"status":"success","data":{"resultType":"vector","result":[{"metric":{"__name__":"openshift_build_active_time_seconds","instance":"10.142.0.5:8444","job":"kubernetes-controllers","name":"cakephp-mysql-example-1","namespace":"extended-test-templates-nh27j-xsw2l","phase":"Running"},"value":[1513820755.933,"1513820679"]}]}}STEP: perform prometheus metric query openshift_build_active_time_seconds
Dec 21 01:45:57.124: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_active_time_seconds"'
Dec 21 01:45:58.847: INFO: stderr: ""
STEP: verifying build completed successfully
Dec 21 01:45:58.848: INFO: Waiting for frontend-1 to complete

Dec 21 01:46:19.888: INFO: Done waiting for frontend-1: util.BuildResult{BuildPath:"build/frontend-1", BuildName:"frontend-1", StartBuildStdErr:"", StartBuildStdOut:"build/frontend-1", StartBuildErr:error(nil), BuildConfigName:"", Build:(*build.Build)(0xc4216f8300), BuildAttempt:true, BuildSuccess:true, BuildFailure:false, BuildCancelled:false, BuildTimeout:false, LogDumper:(util.LogDumperFunc)(nil), Oc:(*util.CLI)(0xc4217928c0)}
 with error: <nil>

STEP: verifying a service account token is able to query terminal build metrics from the Prometheus API
STEP: perform prometheus metric query openshift_build_total
Dec 21 01:46:19.889: INFO: Running '/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64/kubectl --server=https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443 --kubeconfig=/tmp/cluster-admin.kubeconfig exec --namespace=extended-test-prometheus-sqbmr-6bpkt execpodxtlrr -- /bin/sh -c curl -s -k -H 'Authorization: Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJpc3MiOiJrdWJlcm5ldGVzL3NlcnZpY2VhY2NvdW50Iiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9uYW1lc3BhY2UiOiJrdWJlLXN5c3RlbSIsImt1YmVybmV0ZXMuaW8vc2VydmljZWFjY291bnQvc2VjcmV0Lm5hbWUiOiJwcm9tZXRoZXVzLXRva2VuLWQ3d3NqIiwia3ViZXJuZXRlcy5pby9zZXJ2aWNlYWNjb3VudC9zZXJ2aWNlLWFjY291bnQubmFtZSI6InByb21ldGhldXMiLCJrdWJlcm5ldGVzLmlvL3NlcnZpY2VhY2NvdW50L3NlcnZpY2UtYWNjb3VudC51aWQiOiIzZDMwZDE4NC1lNWYwLTExZTctOWJmNy00MjAxMGE4ZTAwMDUiLCJzdWIiOiJzeXN0ZW06c2VydmljZWFjY291bnQ6a3ViZS1zeXN0ZW06cHJvbWV0aGV1cyJ9.J6UQBfKs5-nxKjM34B3mgk42V1zG8bzeg-1l_tz0RjhL_9hu7MFf7T24xj4k8zn5Rzm7zT24KtTsdhY73eFjVqvEqHt4wpuywEi9MDfKzEgRc7eg5oHtbSUsp6CzvU19LKTUOaU3yc8SC3-Xs3gxj5A7L23O-MLyCpTIK-D_4LLOw2z3yd2-zyOhmviWMpptpVB5FfvwrYqSCe2gDMvXoq2L-ENVeQ7QY2p86mcfkpeWKGQxmHHb8ZXb9q1p2MZKzcAI0SN5s2PxyOJIMr-h9o2Nf6xaPrD-FZjjM7DyhohgTCphOPzwT_yFNbOK3p8ZKIMa5RBAtmL9Zdkp1VJWTg' "https://prometheus.kube-system.svc:443/api/v1/query?query=openshift_build_total"'
Dec 21 01:46:20.554: INFO: stderr: ""
[AfterEach] [Feature:Prometheus][Feature:Builds] Prometheus
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:46:20.575: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-prometheus-sqbmr-6bpkt" for this suite.
Dec 21 01:46:30.655: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:46:31.293: INFO: namespace: extended-test-prometheus-sqbmr-6bpkt, resource: bindings, ignored listing per whitelist
Dec 21 01:46:32.119: INFO: namespace extended-test-prometheus-sqbmr-6bpkt deletion completed in 11.513688153s


• [SLOW TEST:81.704 seconds]
[Feature:Prometheus][Feature:Builds] Prometheus
/go/src/github.com/openshift/origin/test/extended/prometheus/prometheus_builds.go:25
  when installed to the cluster
  /go/src/github.com/openshift/origin/test/extended/prometheus/prometheus_builds.go:35
    should start and expose a secured proxy and verify build metrics [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/test/extended/prometheus/prometheus_builds.go:36
------------------------------
Dec 21 01:46:32.120: INFO: Running AfterSuite actions on all node


[sig-storage] Projected 
  should update labels on modification [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:45:00.141: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:45:00.321: INFO: About to run a Kube e2e test, ensuring namespace is privileged
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:858
[It] should update labels on modification [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648
STEP: Creating the pod
Dec 21 01:45:07.436: INFO: Successfully updated pod "labelsupdate8eb9b7c8-e5f0-11e7-a230-0eadb8886b40"
[AfterEach] [sig-storage] Projected
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:46:13.238: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "e2e-tests-projected-w5g7h" for this suite.
Dec 21 01:46:35.319: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:46:36.122: INFO: namespace: e2e-tests-projected-w5g7h, resource: bindings, ignored listing per whitelist
Dec 21 01:46:36.767: INFO: namespace e2e-tests-projected-w5g7h deletion completed in 23.499358254s


• [SLOW TEST:96.626 seconds]
[sig-storage] Projected
/go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/common/projected.go:34
  should update labels on modification [Conformance] [Suite:openshift/conformance/parallel] [Suite:k8s]
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:648
------------------------------
Dec 21 01:46:36.768: INFO: Running AfterSuite actions on all node


[Conformance][templates] templateinstance readiness test  
  should report ready soon after all annotated objects are ready [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/templates/templateinstance_readiness.go:119

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] [Conformance][templates] templateinstance readiness test
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:44:36.787: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:44:37.116: INFO: configPath is now "/tmp/extended-test-templates-nh27j-xsw2l-user.kubeconfig"
Dec 21 01:44:37.116: INFO: The user is now "extended-test-templates-nh27j-xsw2l-user"
Dec 21 01:44:37.116: INFO: Creating project "extended-test-templates-nh27j-xsw2l"
Dec 21 01:44:37.430: INFO: Waiting on permissions in project "extended-test-templates-nh27j-xsw2l" ...
STEP: Waiting for a default service account to be provisioned in namespace
[BeforeEach] 
  /go/src/github.com/openshift/origin/test/extended/templates/templateinstance_readiness.go:101
Dec 21 01:44:37.908: INFO: Running 'oc create --config=/tmp/extended-test-templates-nh27j-xsw2l-user.kubeconfig --namespace=extended-test-templates-nh27j-xsw2l -f /tmp/fixture-testdata-dir359334644/examples/quickstarts/cakephp-mysql.json'
template "cakephp-mysql-example" created
[It] should report ready soon after all annotated objects are ready [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/templates/templateinstance_readiness.go:119
STEP: instantiating the templateinstance
STEP: waiting for build and dc to settle
STEP: waiting for the templateinstance to indicate ready
[AfterEach] 
  /go/src/github.com/openshift/origin/test/extended/templates/templateinstance_readiness.go:112
[AfterEach] [Conformance][templates] templateinstance readiness test
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:46:15.431: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-templates-nh27j-xsw2l" for this suite.
Dec 21 01:46:37.510: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:46:38.700: INFO: namespace: extended-test-templates-nh27j-xsw2l, resource: bindings, ignored listing per whitelist
Dec 21 01:46:39.107: INFO: namespace extended-test-templates-nh27j-xsw2l deletion completed in 23.645721522s


• [SLOW TEST:122.320 seconds]
[Conformance][templates] templateinstance readiness test
/go/src/github.com/openshift/origin/test/extended/templates/templateinstance_readiness.go:24
  
  /go/src/github.com/openshift/origin/test/extended/templates/templateinstance_readiness.go:100
    should report ready soon after all annotated objects are ready [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/test/extended/templates/templateinstance_readiness.go:119
------------------------------
Dec 21 01:46:39.108: INFO: Running AfterSuite actions on all node


[Area:Networking] multicast when using a plugin that does not isolate namespaces by default 
  should block multicast traffic [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/networking/multicast.go:25

[BeforeEach] [Top Level]
  /go/src/github.com/openshift/origin/test/extended/util/test.go:53
[BeforeEach] when using a plugin that does not isolate namespaces by default
  /go/src/github.com/openshift/origin/test/extended/networking/util.go:369
[BeforeEach] when using a plugin that does not isolate namespaces by default
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:134
STEP: Creating a kubernetes client
Dec 21 01:42:06.385: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
STEP: Building a namespace api object
Dec 21 01:42:06.593: INFO: configPath is now "/tmp/extended-test-multicast-vck4n-84tjh-user.kubeconfig"
Dec 21 01:42:06.593: INFO: The user is now "extended-test-multicast-vck4n-84tjh-user"
Dec 21 01:42:06.593: INFO: Creating project "extended-test-multicast-vck4n-84tjh"
Dec 21 01:42:06.752: INFO: Waiting on permissions in project "extended-test-multicast-vck4n-84tjh" ...
STEP: Waiting for a default service account to be provisioned in namespace
[It] should block multicast traffic [Suite:openshift/conformance/parallel]
  /go/src/github.com/openshift/origin/test/extended/networking/multicast.go:25
Dec 21 01:42:06.857: INFO: Waiting up to 5m0s for pod multicast-0                                             status to be running
Dec 21 01:42:06.874: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (17.33303ms elapsed)
Dec 21 01:42:11.955: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (5.098145557s elapsed)
Dec 21 01:42:16.972: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (10.1151107s elapsed)
Dec 21 01:42:22.033: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (15.176633711s elapsed)
Dec 21 01:42:27.049: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (20.19242425s elapsed)
Dec 21 01:42:32.074: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (25.217833288s elapsed)
Dec 21 01:42:37.090: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (30.233776192s elapsed)
Dec 21 01:42:42.108: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (35.250946128s elapsed)
Dec 21 01:42:47.142: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (40.285304337s elapsed)
Dec 21 01:42:52.160: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (45.30389105s elapsed)
Dec 21 01:42:57.181: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (50.324243491s elapsed)
Dec 21 01:43:02.200: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (55.343552526s elapsed)
Dec 21 01:43:07.216: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m0.359923186s elapsed)
Dec 21 01:43:12.235: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m5.378426424s elapsed)
Dec 21 01:43:17.250: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m10.39382039s elapsed)
Dec 21 01:43:22.266: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m15.409907219s elapsed)
Dec 21 01:43:27.285: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m20.42807367s elapsed)
Dec 21 01:43:32.302: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m25.445779829s elapsed)
Dec 21 01:43:37.319: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m30.462908453s elapsed)
Dec 21 01:43:42.335: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m35.47866628s elapsed)
Dec 21 01:43:47.351: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m40.49416059s elapsed)
Dec 21 01:43:52.366: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m45.509158676s elapsed)
Dec 21 01:43:57.396: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m50.539408815s elapsed)
Dec 21 01:44:02.433: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (1m55.576129483s elapsed)
Dec 21 01:44:07.504: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m0.647066656s elapsed)
Dec 21 01:44:12.526: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m5.669342176s elapsed)
Dec 21 01:44:17.551: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m10.69402894s elapsed)
Dec 21 01:44:22.566: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m15.709045418s elapsed)
Dec 21 01:44:27.587: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m20.730065034s elapsed)
Dec 21 01:44:32.635: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m25.77849477s elapsed)
Dec 21 01:44:37.684: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m30.827044388s elapsed)
Dec 21 01:44:42.700: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m35.843218896s elapsed)
Dec 21 01:44:47.731: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m40.874921309s elapsed)
Dec 21 01:44:52.758: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m45.901464605s elapsed)
Dec 21 01:44:57.784: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m50.927869181s elapsed)
Dec 21 01:45:02.822: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (2m55.965124593s elapsed)
Dec 21 01:45:07.838: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m0.981885896s elapsed)
Dec 21 01:45:12.862: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m6.00495719s elapsed)
Dec 21 01:45:17.924: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m11.067475402s elapsed)
Dec 21 01:45:22.944: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m16.087366785s elapsed)
Dec 21 01:45:27.961: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m21.104428788s elapsed)
Dec 21 01:45:32.986: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m26.129068518s elapsed)
Dec 21 01:45:38.019: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m31.1624331s elapsed)
Dec 21 01:45:43.046: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m36.188988071s elapsed)
Dec 21 01:45:48.063: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m41.206362796s elapsed)
Dec 21 01:45:53.079: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m46.222364952s elapsed)
Dec 21 01:45:58.097: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m51.240105857s elapsed)
Dec 21 01:46:03.112: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (3m56.25522899s elapsed)
Dec 21 01:46:08.128: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m1.271443199s elapsed)
Dec 21 01:46:13.155: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m6.298919778s elapsed)
Dec 21 01:46:18.171: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m11.314512691s elapsed)
Dec 21 01:46:23.187: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m16.330523754s elapsed)
Dec 21 01:46:28.203: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m21.346616811s elapsed)
Dec 21 01:46:33.219: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m26.362162252s elapsed)
Dec 21 01:46:38.235: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m31.378163867s elapsed)
Dec 21 01:46:43.251: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m36.394260797s elapsed)
Dec 21 01:46:48.267: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m41.410331612s elapsed)
Dec 21 01:46:53.283: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m46.425975306s elapsed)
Dec 21 01:46:58.298: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m51.441270136s elapsed)
Dec 21 01:47:03.313: INFO: Waiting for pod multicast-0                                             in namespace 'extended-test-multicast-vck4n-84tjh' status to be 'running'(found phase: "Failed", readiness: false) (4m56.456840432s elapsed)
[AfterEach] when using a plugin that does not isolate namespaces by default
  /go/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/test/e2e/framework/framework.go:135
Dec 21 01:47:08.314: INFO: Waiting up to 3m0s for all (but 0) nodes to be ready
STEP: Destroying namespace "extended-test-multicast-vck4n-84tjh" for this suite.
Dec 21 01:47:14.392: INFO: Waiting up to 30s for server preferred namespaced resources to be successfully discovered
Dec 21 01:47:15.294: INFO: namespace: extended-test-multicast-vck4n-84tjh, resource: bindings, ignored listing per whitelist
Dec 21 01:47:15.799: INFO: namespace extended-test-multicast-vck4n-84tjh deletion completed in 7.454232603s


• [SLOW TEST:309.414 seconds]
[Area:Networking] multicast
/go/src/github.com/openshift/origin/test/extended/networking/multicast.go:20
  when using a plugin that does not isolate namespaces by default
  /go/src/github.com/openshift/origin/test/extended/networking/util.go:368
    should block multicast traffic [Suite:openshift/conformance/parallel]
    /go/src/github.com/openshift/origin/test/extended/networking/multicast.go:25
------------------------------
Dec 21 01:47:15.801: INFO: Running AfterSuite actions on all node


Dec 21 01:46:06.526: INFO: Running AfterSuite actions on all node
Dec 21 01:47:15.844: INFO: Running AfterSuite actions on node 1


Ran 194 of 440 Specs in 434.742 seconds
SUCCESS! -- 194 Passed | 0 Failed | 0 Pending | 246 Skipped 

Ginkgo ran 1 suite in 7m19.125018209s
Test Suite Passed
[INFO] Running serial tests
I1221 01:47:16.153993    8996 test.go:94] Extended test version v3.9.0-alpha.0+31367ee-263
I1221 01:47:17.830641    9947 test.go:94] Extended test version v3.9.0-alpha.0+31367ee-263
Running Suite: Extended
=======================
Random Seed: 1513820837 - Will randomize all specs
Will run 0 of 440 specs

Dec 21 01:47:17.959: INFO: Fetching cloud provider for "gce"

I1221 01:47:17.959684    9947 gce.go:805] Using DefaultTokenSource &oauth2.reuseTokenSource{new:jwt.jwtSource{ctx:(*context.emptyCtx)(0xc420016180), conf:(*jwt.Config)(0xc42036a600)}, mu:sync.Mutex{state:0, sema:0x0}, t:(*oauth2.Token)(nil)}
I1221 01:47:18.057450    9947 gce.go:805] Using DefaultTokenSource &oauth2.reuseTokenSource{new:jwt.jwtSource{ctx:(*context.emptyCtx)(0xc420016180), conf:(*jwt.Config)(0xc4202b8000)}, mu:sync.Mutex{state:0, sema:0x0}, t:(*oauth2.Token)(nil)}
I1221 01:47:18.112887    9947 gce.go:805] Using DefaultTokenSource &oauth2.reuseTokenSource{new:jwt.jwtSource{ctx:(*context.emptyCtx)(0xc420016180), conf:(*jwt.Config)(0xc42036a000)}, mu:sync.Mutex{state:0, sema:0x0}, t:(*oauth2.Token)(nil)}
W1221 01:47:18.173566    9947 gce.go:430] No network name or URL specified.
Dec 21 01:47:18.173: INFO: lookupDiskImageSources: gcloud error with [[]string{"instance-groups", "list-instances", "", "--format=get(instance)"}]; err:exec: "gcloud": executable file not found in $PATH
Dec 21 01:47:18.173: INFO:  > 
Dec 21 01:47:18.173: INFO: Cluster image sources lookup failed: exec: "gcloud": executable file not found in $PATH

Dec 21 01:47:18.173: INFO: >>> kubeConfig: /tmp/cluster-admin.kubeconfig
Dec 21 01:47:18.175: INFO: Waiting up to 4h0m0s for all (but 0) nodes to be schedulable
Dec 21 01:47:18.254: INFO: Waiting up to 10m0s for all pods (need at least 0) in namespace 'kube-system' to be running and ready
Dec 21 01:47:18.302: INFO: 1 / 1 pods in namespace 'kube-system' are running and ready (0 seconds elapsed)
Dec 21 01:47:18.302: INFO: expected 0 pod replicas in namespace 'kube-system', 0 are Running and Ready.
Dec 21 01:47:18.318: INFO: Waiting for pods to enter Success, but no pods in "kube-system" match label map[name:e2e-image-puller]
Dec 21 01:47:18.318: INFO: Dumping network health container logs from all nodes...
Dec 21 01:47:18.334: INFO: e2e test version: v1.9.0-beta1
Dec 21 01:47:18.348: INFO: kube-apiserver version: v1.9.0-beta1
SSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSSDec 21 01:47:18.350: INFO: Running AfterSuite actions on all node
Dec 21 01:47:18.350: INFO: Running AfterSuite actions on node 1

Ran 0 of 440 Specs in 0.391 seconds
SUCCESS! -- 0 Passed | 0 Failed | 0 Pending | 440 Skipped Dec 21 01:47:18.352: INFO: Dumping logs locally to: /data/src/github.com/openshift/origin/_output/scripts/conformance/artifacts/junit
Checking for custom logdump instances, if any
Sourcing kube-util.sh
Detecting project
/data/src/github.com/openshift/origin/vendor/k8s.io/kubernetes/cluster/log-dump/../../cluster/../cluster/gce/util.sh: line 147: gcloud: command not found
Dec 21 01:47:18.388: INFO: Error running cluster/log-dump/log-dump.sh: exit status 127
PASS

Ginkgo ran 1 suite in 835.194324ms
Test Suite Passed
[INFO] [CLEANUP] Beginning cleanup routines...
[INFO] [CLEANUP] Dumping cluster events to _output/scripts/conformance/artifacts/events.txt
Logged into "https://internal-api.primg625.origin-ci-int-gce.dev.rhcloud.com:8443" as "system:admin" using existing credentials.

You have access to the following projects and can switch between them with 'oc project <projectname>':

  * default
    kube-public
    kube-system
    logging
    management-infra
    openshift
    openshift-infra
    openshift-node

Using project "default".
[INFO] [CLEANUP] Dumping container logs to _output/scripts/conformance/logs/containers
[INFO] [CLEANUP] Truncating log files over 200M
[INFO] [CLEANUP] Stopping docker containers
[INFO] [CLEANUP] Removing docker containers
[INFO] [CLEANUP] Killing child processes
[INFO] test/extended/conformance.sh exited with code 0 after 00h 07m 53s

real	7m52.642s
user	2m31.863s
sys	0m20.317s
+ [[ branch_success == \b\r\a\n\c\h\_\s\u\c\c\e\s\s ]]
+ [[ '' != 1 ]]
+ [[ 1 == 1 ]]
+ to=docker.io/openshift/origin-gce:latest
+ sudo docker tag openshift/origin-gce:latest docker.io/openshift/origin-gce:latest
+ sudo docker push docker.io/openshift/origin-gce:latest
The push refers to a repository [docker.io/openshift/origin-gce]
8a01d39f35c4: Preparing
08cc6f582ed0: Preparing
5f3b952bfeee: Preparing
34e0c805337c: Preparing
bba07d132cf6: Preparing
d1be66a59bc5: Preparing
d1be66a59bc5: Waiting
34e0c805337c: Layer already exists
bba07d132cf6: Layer already exists
d1be66a59bc5: Layer already exists
8a01d39f35c4: Pushed
08cc6f582ed0: Pushed
5f3b952bfeee: Pushed
latest: digest: sha256:b427c00c5d5c12682199914e34a679a278abacc083d50121b64cc77c5249b575 size: 1581
+ exit 0
+ gather
+ set +e
++ pwd
+ export PATH=/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64:/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/_output/local/bin/linux/amd64:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/home/origin/.local/bin:/home/origin/bin
+ PATH=/data/src/github.com/openshift/origin/_output/local/bin/linux/amd64:/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/_output/local/bin/linux/amd64:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/home/origin/.local/bin:/home/origin/bin
+ oc get nodes --template '{{ range .items }}{{ .metadata.name }}{{ "\n" }}{{ end }}'
+ xargs -L 1 -I X bash -c 'oc get --raw /api/v1/nodes/X/proxy/metrics > /tmp/artifacts/X.metrics' ''
+ oc get --raw /metrics
+ set -e
[PostBuildScript] - Execution post build scripts.
[workspace] $ /bin/bash /tmp/jenkins8678943173046078475.sh
~/jobs/zz_origin_gce_image/workspace ~/jobs/zz_origin_gce_image/workspace
Activated service account credentials for: [jenkins-ci-provisioner@openshift-gce-devel.iam.gserviceaccount.com]

PLAY [Terminate running cluster and remove all supporting resources in GCE] ****

TASK [Gathering Facts] *********************************************************
Thursday 21 December 2017  01:48:27 +0000 (0:00:00.057)       0:00:00.057 ***** 
ok: [localhost]

TASK [include_role] ************************************************************
Thursday 21 December 2017  01:48:31 +0000 (0:00:04.427)       0:00:04.484 ***** 

TASK [openshift_gcp : Templatize DNS script] ***********************************
Thursday 21 December 2017  01:48:31 +0000 (0:00:00.086)       0:00:04.570 ***** 
changed: [localhost]

TASK [openshift_gcp : Templatize provision script] *****************************
Thursday 21 December 2017  01:48:32 +0000 (0:00:00.503)       0:00:05.074 ***** 
changed: [localhost]

TASK [openshift_gcp : Templatize de-provision script] **************************
Thursday 21 December 2017  01:48:32 +0000 (0:00:00.301)       0:00:05.375 ***** 
changed: [localhost]

TASK [openshift_gcp : Provision GCP DNS domain] ********************************
Thursday 21 December 2017  01:48:32 +0000 (0:00:00.276)       0:00:05.652 ***** 
skipping: [localhost]

TASK [openshift_gcp : Ensure that DNS resolves to the hosted zone] *************
Thursday 21 December 2017  01:48:32 +0000 (0:00:00.022)       0:00:05.675 ***** 
skipping: [localhost]

TASK [openshift_gcp : Provision GCP resources] *********************************
Thursday 21 December 2017  01:48:32 +0000 (0:00:00.020)       0:00:05.695 ***** 
skipping: [localhost]

TASK [openshift_gcp : De-provision GCP resources] ******************************
Thursday 21 December 2017  01:48:32 +0000 (0:00:00.021)       0:00:05.717 ***** 
changed: [localhost]

PLAY RECAP *********************************************************************
localhost                  : ok=5    changed=4    unreachable=0    failed=0   

Thursday 21 December 2017  01:55:06 +0000 (0:06:33.448)       0:06:39.165 ***** 
=============================================================================== 
openshift_gcp : De-provision GCP resources ---------------------------- 393.45s
Gathering Facts --------------------------------------------------------- 4.43s
openshift_gcp : Templatize DNS script ----------------------------------- 0.50s
openshift_gcp : Templatize provision script ----------------------------- 0.30s
openshift_gcp : Templatize de-provision script -------------------------- 0.28s
include_role ------------------------------------------------------------ 0.09s
openshift_gcp : Provision GCP DNS domain -------------------------------- 0.02s
openshift_gcp : Provision GCP resources --------------------------------- 0.02s
openshift_gcp : Ensure that DNS resolves to the hosted zone ------------- 0.02s
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/junit
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/ci-primg625-ig-m-vjdc.metrics
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/ci-primg625-ig-n-ct01.metrics
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/ci-primg625-ig-n-dvmw.metrics
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/ci-primg625-ig-n-th01.metrics
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/master.metrics
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/openshift
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/openshift/conformance
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/openshift/conformance/volumes
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/openshift/shell
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/openshift/shell/volumes
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_01.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_02.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_03.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_04.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_05.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_06.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_07.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_08.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_09.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_10.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_11.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_12.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_13.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_14.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_15.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_16.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_17.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_18.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_19.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_20.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_21.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_22.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_23.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_24.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_parallel_25.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/junit/conformance_serial_01.xml
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/artifacts/events.txt
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/logs
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/logs/containers
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/logs/scripts.log
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/conformance/openshift.local.home
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/shell
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/shell/artifacts
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/shell/logs
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/shell/logs/scripts.log
/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/artifacts/scripts/shell/openshift.local.home

PLAYBOOK: main.yml *************************************************************
4 plays in /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml

PLAY [ensure we have the parameters necessary to deprovision virtual hosts] ****

TASK [ensure all required variables are set] ***********************************
task path: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:9
skipping: [localhost] => (item=origin_ci_inventory_dir)  => {
    "changed": false, 
    "generated_timestamp": "2017-12-20 20:55:08.952069", 
    "item": "origin_ci_inventory_dir", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}
skipping: [localhost] => (item=origin_ci_aws_region)  => {
    "changed": false, 
    "generated_timestamp": "2017-12-20 20:55:08.956466", 
    "item": "origin_ci_aws_region", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}

PLAY [deprovision virtual hosts in EC2] ****************************************

TASK [Gathering Facts] *********************************************************
ok: [localhost]

TASK [deprovision a virtual EC2 host] ******************************************
task path: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:28
included: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml for localhost

TASK [update the SSH configuration to remove AWS EC2 specifics] ****************
task path: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:2
ok: [localhost] => {
    "changed": false, 
    "generated_timestamp": "2017-12-20 20:55:09.779093", 
    "msg": ""
}

TASK [rename EC2 instance for termination reaper] ******************************
task path: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:8
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-12-20 20:55:10.333150", 
    "msg": "Tags {'Name': 'oct-terminate'} created for resource i-01c740846ab2a4f04."
}

TASK [tear down the EC2 instance] **********************************************
task path: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:15
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-12-20 20:55:11.327364", 
    "instance_ids": [
        "i-01c740846ab2a4f04"
    ], 
    "instances": [
        {
            "ami_launch_index": "0", 
            "architecture": "x86_64", 
            "block_device_mapping": {
                "/dev/sda1": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0035817b3e18cc9ba"
                }, 
                "/dev/sdb": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0875bcd7a0b29baf1"
                }
            }, 
            "dns_name": "ec2-52-55-194-134.compute-1.amazonaws.com", 
            "ebs_optimized": false, 
            "groups": {
                "sg-7e73221a": "default"
            }, 
            "hypervisor": "xen", 
            "id": "i-01c740846ab2a4f04", 
            "image_id": "ami-259cef5f", 
            "instance_type": "m4.xlarge", 
            "kernel": null, 
            "key_name": "libra", 
            "launch_time": "2017-12-21T01:06:16.000Z", 
            "placement": "us-east-1d", 
            "private_dns_name": "ip-172-18-5-27.ec2.internal", 
            "private_ip": "172.18.5.27", 
            "public_dns_name": "ec2-52-55-194-134.compute-1.amazonaws.com", 
            "public_ip": "52.55.194.134", 
            "ramdisk": null, 
            "region": "us-east-1", 
            "root_device_name": "/dev/sda1", 
            "root_device_type": "ebs", 
            "state": "running", 
            "state_code": 16, 
            "tags": {
                "Name": "oct-terminate", 
                "openshift_etcd": "", 
                "openshift_master": "", 
                "openshift_node": ""
            }, 
            "tenancy": "default", 
            "virtualization_type": "hvm"
        }
    ], 
    "tagged_instances": []
}

TASK [remove the serialized host variables] ************************************
task path: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:22
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-12-20 20:55:11.576883", 
    "path": "/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/.config/origin-ci-tool/inventory/host_vars/172.18.5.27.yml", 
    "state": "absent"
}

PLAY [deprovision virtual hosts locally manged by Vagrant] *********************

PLAY [clean up local configuration for deprovisioned instances] ****************

TASK [remove inventory configuration directory] ********************************
task path: /var/lib/jenkins/origin-ci-tool/608476c10a39b9649a000dd17605607455939e65/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:61
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2017-12-20 20:55:11.743686", 
    "path": "/var/lib/jenkins/jobs/zz_origin_gce_image/workspace/.config/origin-ci-tool/inventory", 
    "state": "absent"
}

PLAY RECAP *********************************************************************
localhost                  : ok=7    changed=4    unreachable=0    failed=0   

~/jobs/zz_origin_gce_image/workspace
Recording test results
Archiving artifacts
Finished: SUCCESS