[2018-10-16T00:49:17.714+0000] Created project testproj [2018-10-16T00:49:18.035+0000] Running test/mux.sh:361: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s... [2018-10-16T00:49:18.035+0000] SUCCESS after 0.290s: test/mux.sh:361: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s [2018-10-16T00:49:20.882+0000] added es message 7c89e74b9bd64a19a408a3fcf053e553 [2018-10-16T00:49:20.885+0000] added es-ops message 52cb6cf1630a4332ac3a4c4d14ce9482 [2018-10-16T00:49:29.186+0000] good - wait_for_fluentd_to_catch_up: found 1 record .operations.* for 'GET /7c89e74b9bd64a19a408a3fcf053e553 404 ' [2018-10-16T00:49:29.387+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:49:34.523+0000] configmap/logging-fluentd replaced [2018-10-16T00:49:34.955+0000] configmap/logging-fluentd patched [2018-10-16T00:49:35.179+0000] configmap/logging-fluentd patched [2018-10-16T00:49:35.871+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:49:41.158+0000] logging-fluentd-8kj4z 1/1 Running 0 5s [2018-10-16T00:49:41.821+0000] logging-fluentd-8kj4z 1/1 Running 0 5s [2018-10-16T00:49:42.020+0000] logging-fluentd-8kj4z 1/1 Running 0 6s [2018-10-16T00:49:42.297+0000] Cursors: [2018-10-16T00:49:42.305+0000] before s=e88eb39dfa5f41cba87692648482f60b;i=6e42;b=cf184a0ccd2a421693c146586789e8b9;m=ef4dc943;t=5784dedbc6984;x=a1e8a96efa40dd48 [2018-10-16T00:49:42.322+0000] record s=baf31c3443a346adb109a7a9a922f8c3;i=6e52;b=cf184a0ccd2a421693c146586789e8b9;m=ef6d7ef2;t=5784deddc1f33;x=aca89069419ae014 [2018-10-16T00:49:42.334+0000] after s=e88eb39dfa5f41cba87692648482f60b;i=6e48;b=cf184a0ccd2a421693c146586789e8b9;m=ef5d4db3;t=5784dedcbedf3;x=b71ba56b615678bd [2018-10-16T00:49:42.343+0000] logging-fluentd-8kj4z 1/1 Running 0 6s [2018-10-16T00:49:42.579+0000] Running test/mux.sh:212: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s... [2018-10-16T00:49:42.579+0000] SUCCESS after 0.248s: test/mux.sh:212: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s [2018-10-16T00:49:42.873+0000] start Tue Oct 16 00:49:42 UTC 2018 1539650982 [2018-10-16T00:49:52.810+0000] Running test/mux.sh:257: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s... [2018-10-16T00:49:52.810+0000] SUCCESS after 0.251s: test/mux.sh:257: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s [2018-10-16T00:49:53.102+0000] start Tue Oct 16 00:49:53 UTC 2018 1539650993 [2018-10-16T00:49:53.750+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:49:58.620+0000] configmap/logging-fluentd replaced [2018-10-16T00:49:59.130+0000] configmap/logging-fluentd patched [2018-10-16T00:49:59.828+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:50:04.950+0000] logging-fluentd-w4gjv 1/1 Running 0 5s [2018-10-16T00:50:05.584+0000] logging-fluentd-w4gjv 1/1 Running 0 5s [2018-10-16T00:50:05.819+0000] logging-fluentd-w4gjv 1/1 Running 0 6s [2018-10-16T00:50:06.030+0000] Cursors: [2018-10-16T00:50:06.031+0000] before s=e88eb39dfa5f41cba87692648482f60b;i=6f3d;b=cf184a0ccd2a421693c146586789e8b9;m=f0b58553;t=5784def242593;x=8fba90e4e0dd084c [2018-10-16T00:50:06.032+0000] record s=baf31c3443a346adb109a7a9a922f8c3;i=6f50;b=cf184a0ccd2a421693c146586789e8b9;m=f0d8b7fd;t=5784def47583e;x=52514d9564a08c5d [2018-10-16T00:50:06.034+0000] after s=e88eb39dfa5f41cba87692648482f60b;i=6f43;b=cf184a0ccd2a421693c146586789e8b9;m=f0c62e5c;t=5784def34ce9c;x=cd9c01f02a59ebd6 [2018-10-16T00:50:06.038+0000] logging-fluentd-w4gjv 1/1 Running 0 6s [2018-10-16T00:50:06.283+0000] start Tue Oct 16 00:50:06 UTC 2018 1539651006 [2018-10-16T00:50:18.201+0000] Running test/mux.sh:257: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s... [2018-10-16T00:50:18.201+0000] SUCCESS after 0.249s: test/mux.sh:257: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s [2018-10-16T00:50:18.493+0000] start Tue Oct 16 00:50:18 UTC 2018 1539651018 [2018-10-16T00:50:19.148+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:50:30.019+0000] configmap/logging-fluentd replaced [2018-10-16T00:50:30.583+0000] configmap/logging-fluentd patched [2018-10-16T00:50:31.397+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:50:37.067+0000] logging-fluentd-vz82n 1/1 Running 0 6s [2018-10-16T00:50:37.802+0000] logging-fluentd-vz82n 1/1 Running 0 7s [2018-10-16T00:50:38.043+0000] logging-fluentd-vz82n 1/1 Running 0 7s [2018-10-16T00:50:38.246+0000] Cursors: [2018-10-16T00:50:38.247+0000] before s=e88eb39dfa5f41cba87692648482f60b;i=704d;b=cf184a0ccd2a421693c146586789e8b9;m=f2a29764;t=5784df11137a5;x=9523f3b724e6ee3e [2018-10-16T00:50:38.248+0000] record s=baf31c3443a346adb109a7a9a922f8c3;i=705c;b=cf184a0ccd2a421693c146586789e8b9;m=f2c46aa1;t=5784df1330ae1;x=d6a006dc3e2567f5 [2018-10-16T00:50:38.250+0000] after s=e88eb39dfa5f41cba87692648482f60b;i=7052;b=cf184a0ccd2a421693c146586789e8b9;m=f2b00da7;t=5784df11eade8;x=41d6a52ea169d7f1 [2018-10-16T00:50:38.253+0000] logging-fluentd-vz82n 1/1 Running 0 7s [2018-10-16T00:50:38.431+0000] Running test/mux.sh:212: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s... [2018-10-16T00:50:38.431+0000] SUCCESS after 0.273s: test/mux.sh:212: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s [2018-10-16T00:50:38.747+0000] start Tue Oct 16 00:50:38 UTC 2018 1539651038 [2018-10-16T00:50:47.910+0000] Running test/mux.sh:257: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s... [2018-10-16T00:50:47.910+0000] SUCCESS after 0.278s: test/mux.sh:257: executing 'oc get project testproj' expecting success; re-trying every 0.2s until completion or 60.000s [2018-10-16T00:50:48.280+0000] start Tue Oct 16 00:50:48 UTC 2018 1539651048 [2018-10-16T00:50:49.362+0000] replication controller "logging-mux-1" successfully rolled out [2018-10-16T00:50:50.790+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:50:51.238+0000] configmap "logging-fluentd" deleted [2018-10-16T00:50:51.238+0000] configmap/logging-fluentd replaced [2018-10-16T00:50:51.444+0000] daemonset.extensions "logging-fluentd" deleted [2018-10-16T00:50:51.444+0000] daemonset.extensions/logging-fluentd replaced [2018-10-16T00:50:53.975+0000] node/ip-172-18-7-24.ec2.internal labeled [2018-10-16T00:50:57.419+0000] project.project.openshift.io "testproj" deleted [2018-10-16T00:51:04.079+0000] Running test/mux.sh:340: executing 'oc get project testproj' expecting failure; re-trying every 0.2s until completion or 60.000s... [2018-10-16T00:51:04.079+0000] SUCCESS after 0.210s: test/mux.sh:340: executing 'oc get project testproj' expecting failure; re-trying every 0.2s until completion or 60.000s