SuccessConsole Output

Skipping 302 KB.. Full Log
I0620 00:59:57.429014   14975 tar.go:401] Creating directory /tmp/s2i632797381/upload/artifacts/views
I0620 00:59:57.429090   14975 tar.go:469] Creating /tmp/s2i632797381/upload/artifacts/views/main.erb
I0620 00:59:57.429198   14975 tar.go:479] Extracting/writing /tmp/s2i632797381/upload/artifacts/views/main.erb
I0620 00:59:57.429405   14975 tar.go:401] Creating directory /tmp/s2i632797381/upload/artifacts
I0620 00:59:57.429488   14975 tar.go:469] Creating /tmp/s2i632797381/upload/artifacts/save-artifacts-invoked
I0620 00:59:57.429648   14975 tar.go:479] Extracting/writing /tmp/s2i632797381/upload/artifacts/save-artifacts-invoked
I0620 00:59:57.429950   14975 tar.go:425] Done extracting tar stream
I0620 00:59:57.458712   14975 docker.go:1044] Waiting for container "a536ae0876693c32ec20f2630d7e48164c971616d8ab6d520e079c60c65d6c26" to stop ...
I0620 00:59:57.582897   14975 docker.go:980] Removing container "a536ae0876693c32ec20f2630d7e48164c971616d8ab6d520e079c60c65d6c26" ...
I0620 00:59:57.593104   14975 docker.go:990] Removed container "a536ae0876693c32ec20f2630d7e48164c971616d8ab6d520e079c60c65d6c26"
I0620 00:59:57.593457   14975 sti.go:227] Running "assemble" in "test/sti-incremental-app"
I0620 00:59:57.593515   14975 sti.go:585] Using image name sti_test/sti-fake
I0620 00:59:57.596191   14975 docker.go:490] Using locally available image "sti_test/sti-fake:latest"
I0620 00:59:57.596346   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 00:59:57.596728   14975 sti.go:703] starting the source uploading ...
I0620 00:59:57.596816   14975 tar.go:239] Adding "/tmp/s2i632797381/upload" to tar ...
I0620 00:59:57.597307   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts as artifacts
I0620 00:59:57.602089   14975 docker.go:793] Both scripts and untarred source will be placed in '/tmp'
I0620 00:59:57.602167   14975 docker.go:961] Setting "/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble" command for container ...
I0620 00:59:57.602601   14975 docker.go:970] Creating container with options {Name:"s2i_sti_test_sti_fake_974588f5" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:true StdinOnce:true Env:[RACK_ENV=production] Cmd:[/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble] Healthcheck:<nil> ArgsEscaped:false Image:sti_test/sti-fake:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 00:59:57.632797   14975 docker.go:1002] Attaching to container "66f5d96db1942b4179ab2a0a26508506f7aa5397b457fd181531bfa8de2b7330" ...
I0620 00:59:57.634251   14975 docker.go:1013] Starting container "66f5d96db1942b4179ab2a0a26508506f7aa5397b457fd181531bfa8de2b7330" ...
I0620 00:59:57.853301   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/.gitignore as artifacts/.gitignore
I0620 00:59:57.854042   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/.s2i as artifacts/.s2i
I0620 00:59:57.854656   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/.s2i/bin as artifacts/.s2i/bin
I0620 00:59:57.855238   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/.s2i/bin/README as artifacts/.s2i/bin/README
I0620 00:59:57.855857   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/.s2i/environment as artifacts/.s2i/environment
I0620 00:59:57.856509   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/.travis.yml as artifacts/.travis.yml
I0620 00:59:57.857184   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/Dockerfile as artifacts/Dockerfile
I0620 00:59:57.857718   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/Gemfile as artifacts/Gemfile
I0620 00:59:57.858308   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/Gemfile.lock as artifacts/Gemfile.lock
I0620 00:59:57.858890   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/README.md as artifacts/README.md
I0620 00:59:57.859395   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/Rakefile as artifacts/Rakefile
I0620 00:59:57.859873   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/app.rb as artifacts/app.rb
I0620 00:59:57.860404   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/config as artifacts/config
I0620 00:59:57.860848   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/config/database.rb as artifacts/config/database.rb
I0620 00:59:57.861382   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/config/database.yml as artifacts/config/database.yml
I0620 00:59:57.861864   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/config.ru as artifacts/config.ru
I0620 00:59:57.862379   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/db as artifacts/db
I0620 00:59:57.862855   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/db/migrate as artifacts/db/migrate
I0620 00:59:57.863377   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/db/migrate/20141102191902_create_key_pair.rb as artifacts/db/migrate/20141102191902_create_key_pair.rb
I0620 00:59:57.864033   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/models.rb as artifacts/models.rb
I0620 00:59:57.864505   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/run.sh as artifacts/run.sh
I0620 00:59:57.865071   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/save-artifacts-invoked as artifacts/save-artifacts-invoked
I0620 00:59:57.865647   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/test as artifacts/test
I0620 00:59:57.866168   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/test/sample_test.rb as artifacts/test/sample_test.rb
I0620 00:59:57.866761   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/views as artifacts/views
I0620 00:59:57.867345   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/artifacts/views/main.erb as artifacts/views/main.erb
I0620 00:59:57.868370   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/scripts as scripts
I0620 00:59:57.869320   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/scripts/assemble as scripts/assemble
I0620 00:59:57.869863   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/scripts/run as scripts/run
I0620 00:59:57.870394   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/scripts/save-artifacts as scripts/save-artifacts
I0620 00:59:57.870958   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src as src
I0620 00:59:57.875309   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/.gitignore as src/.gitignore
I0620 00:59:57.875974   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/.s2i as src/.s2i
I0620 00:59:57.876482   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/.s2i/bin as src/.s2i/bin
I0620 00:59:57.876918   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/.s2i/bin/README as src/.s2i/bin/README
I0620 00:59:57.877502   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/.s2i/environment as src/.s2i/environment
I0620 00:59:57.878075   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/.travis.yml as src/.travis.yml
I0620 00:59:57.878612   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/Dockerfile as src/Dockerfile
I0620 00:59:57.879196   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/Gemfile as src/Gemfile
I0620 00:59:57.879796   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/Gemfile.lock as src/Gemfile.lock
I0620 00:59:57.880531   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/README.md as src/README.md
I0620 00:59:57.881057   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/Rakefile as src/Rakefile
I0620 00:59:57.881626   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/app.rb as src/app.rb
I0620 00:59:57.882274   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/config as src/config
I0620 00:59:57.882837   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/config/database.rb as src/config/database.rb
I0620 00:59:57.883559   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/config/database.yml as src/config/database.yml
I0620 00:59:57.884239   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/config.ru as src/config.ru
I0620 00:59:57.884844   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/db as src/db
I0620 00:59:57.885409   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/db/migrate as src/db/migrate
I0620 00:59:57.885919   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
I0620 00:59:57.886422   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/models.rb as src/models.rb
I0620 00:59:57.886972   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/run.sh as src/run.sh
I0620 00:59:57.887516   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/test as src/test
I0620 00:59:57.888021   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/test/sample_test.rb as src/test/sample_test.rb
I0620 00:59:57.888526   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/views as src/views
I0620 00:59:57.889003   14975 tar.go:337] Adding to tar: /tmp/s2i632797381/upload/src/views/main.erb as src/views/main.erb
I0620 00:59:57.937527   14975 docker.go:1044] Waiting for container "66f5d96db1942b4179ab2a0a26508506f7aa5397b457fd181531bfa8de2b7330" to stop ...
I0620 00:59:58.149960   14975 docker.go:1069] Invoking PostExecute function
I0620 00:59:58.150031   14975 postexecutorstep.go:68] Skipping step: store previous image
I0620 00:59:58.150071   14975 postexecutorstep.go:117] Executing step: commit image
I0620 00:59:58.153278   14975 postexecutorstep.go:523] Checking for new Labels to apply... 
I0620 00:59:58.153351   14975 postexecutorstep.go:531] Creating the download path '/tmp/s2i632797381/metadata'
I0620 00:59:58.153623   14975 postexecutorstep.go:465] Downloading file "/tmp/.s2i/image_metadata.json"
I0620 00:59:58.196709   14975 tar.go:401] Creating directory /tmp/s2i632797381/metadata
I0620 00:59:58.196794   14975 tar.go:469] Creating /tmp/s2i632797381/metadata/image_metadata.json
I0620 00:59:58.196978   14975 tar.go:479] Extracting/writing /tmp/s2i632797381/metadata/image_metadata.json
I0620 00:59:58.197166   14975 tar.go:425] Done extracting tar stream
I0620 00:59:58.197338   14975 postexecutorstep.go:556] new Labels File contents : 
{
  "labels": [
    {"testLabel": "testLabel_value"}
  ]
}

I0620 00:59:58.202985   14975 docker.go:1103] Committing container with dockerOpts: {Reference:test/sti-incremental-app Comment: Author: Changes:[] Pause:false Config:0xc0005963c0}, config: {Hostname: Domainname: User: AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production] Cmd:[/tmp/scripts/run] Healthcheck:<nil> ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[io.k8s.display-name:test/sti-incremental-app io.openshift.s2i.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.s2i.build.commit.date:Thu Jan 17 17:21:03 2019 -0500 io.openshift.s2i.build.commit.id:787f1beae9956c959c6af62ee43bfdda73769cf7 io.openshift.s2i.build.commit.message:Merge pull request #78 from bparees/v22 io.openshift.s2i.build.commit.ref:master io.openshift.s2i.build.image:sti_test/sti-fake io.openshift.s2i.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.s2i.scripts-url:http://127.0.0.1:23456/.s2i/bin testLabel:testLabel_value] StopSignal: StopTimeout:<nil> Shell:[]}
I0620 00:59:58.266046   14975 postexecutorstep.go:393] Executing step: report success
I0620 00:59:58.266113   14975 postexecutorstep.go:398] Successfully built test/sti-incremental-app
I0620 00:59:58.266148   14975 postexecutorstep.go:93] Skipping step: remove previous image
I0620 00:59:58.266247   14975 docker.go:980] Removing container "66f5d96db1942b4179ab2a0a26508506f7aa5397b457fd181531bfa8de2b7330" ...
I0620 00:59:58.278650   14975 docker.go:990] Removed container "66f5d96db1942b4179ab2a0a26508506f7aa5397b457fd181531bfa8de2b7330"
I0620 00:59:58.278953   14975 cleanup.go:33] Removing temporary directory /tmp/s2i632797381
I0620 00:59:58.279028   14975 fs.go:314] Removing directory '/tmp/s2i632797381'
--- PASS: TestIncrementalBuildAndKeepPreviousImage (3.08s)
=== RUN   TestIncrementalBuildUser
I0620 00:59:58.958144   14975 docker.go:490] Using locally available image "sti_test/sti-fake:latest"
I0620 00:59:58.965412   14975 docker.go:490] Using locally available image "sti_test/sti-fake:latest"
I0620 00:59:58.965472   14975 docker.go:730] Image sha256:0b5af3e89d502196e1c457afba84ea7f0488b8b4875890b92723a7d31e2aaacf contains io.openshift.s2i.scripts-url set to "http://127.0.0.1:23456/.s2i/bin"
I0620 00:59:58.965522   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 00:59:58.965628   14975 sti.go:204] Preparing to build test/sti-incremental-app-user
I0620 00:59:58.966141   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 00:59:58.966199   14975 clone.go:40] Cloning sources into "/tmp/s2i480091967/upload/src"
I0620 00:59:59.206191   14975 clone.go:56] Checked out "HEAD"
I0620 00:59:59.238061   14975 clone.go:62] Updated submodules for "HEAD"
I0620 00:59:59.253865   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/assemble'
I0620 00:59:59.254004   14975 install.go:261] Using "assemble" installed from "http://127.0.0.1:23456/.s2i/bin/assemble"
I0620 00:59:59.256013   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/run'
I0620 00:59:59.256138   14975 install.go:261] Using "run" installed from "http://127.0.0.1:23456/.s2i/bin/run"
I0620 00:59:59.258644   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/save-artifacts'
I0620 00:59:59.258764   14975 install.go:261] Using "save-artifacts" installed from "http://127.0.0.1:23456/.s2i/bin/save-artifacts"
I0620 00:59:59.258843   14975 ignore.go:66] .s2iignore file does not exist
I0620 00:59:59.258887   14975 sti.go:213] Clean build will be performed
I0620 00:59:59.258955   14975 sti.go:216] Performing source build from https://github.com/openshift/ruby-hello-world
I0620 00:59:59.259022   14975 sti.go:227] Running "assemble" in "test/sti-incremental-app-user"
I0620 00:59:59.259060   14975 sti.go:585] Using image name sti_test/sti-fake
I0620 00:59:59.262502   14975 docker.go:490] Using locally available image "sti_test/sti-fake:latest"
I0620 00:59:59.262620   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 00:59:59.262799   14975 sti.go:703] starting the source uploading ...
I0620 00:59:59.262872   14975 tar.go:239] Adding "/tmp/s2i480091967/upload" to tar ...
I0620 00:59:59.263243   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/scripts as scripts
I0620 00:59:59.268078   14975 docker.go:793] Both scripts and untarred source will be placed in '/tmp'
I0620 00:59:59.268170   14975 docker.go:961] Setting "/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble" command for container ...
I0620 00:59:59.268494   14975 docker.go:970] Creating container with options {Name:"s2i_sti_test_sti_fake_d7b63c41" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:true StdinOnce:true Env:[RACK_ENV=production] Cmd:[/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble] Healthcheck:<nil> ArgsEscaped:false Image:sti_test/sti-fake:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 00:59:59.303866   14975 docker.go:1002] Attaching to container "cab0426fba377f596e83c493c8c0a806c35638721e075d0e39b49b37e2b50e17" ...
I0620 00:59:59.305138   14975 docker.go:1013] Starting container "cab0426fba377f596e83c493c8c0a806c35638721e075d0e39b49b37e2b50e17" ...
I0620 00:59:59.543399   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/scripts/assemble as scripts/assemble
I0620 00:59:59.544060   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/scripts/run as scripts/run
I0620 00:59:59.544638   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/scripts/save-artifacts as scripts/save-artifacts
I0620 00:59:59.545228   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src as src
I0620 00:59:59.549099   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/.gitignore as src/.gitignore
I0620 00:59:59.549641   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/.s2i as src/.s2i
I0620 00:59:59.550161   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/.s2i/bin as src/.s2i/bin
I0620 00:59:59.550618   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/.s2i/bin/README as src/.s2i/bin/README
I0620 00:59:59.551130   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/.s2i/environment as src/.s2i/environment
I0620 00:59:59.551616   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/.travis.yml as src/.travis.yml
I0620 00:59:59.552188   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/Dockerfile as src/Dockerfile
I0620 00:59:59.552875   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/Gemfile as src/Gemfile
I0620 00:59:59.553569   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/Gemfile.lock as src/Gemfile.lock
I0620 00:59:59.554287   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/README.md as src/README.md
I0620 00:59:59.554833   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/Rakefile as src/Rakefile
I0620 00:59:59.555409   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/app.rb as src/app.rb
I0620 00:59:59.556029   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/config as src/config
I0620 00:59:59.556550   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/config/database.rb as src/config/database.rb
I0620 00:59:59.557128   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/config/database.yml as src/config/database.yml
I0620 00:59:59.558053   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/config.ru as src/config.ru
I0620 00:59:59.558757   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/db as src/db
I0620 00:59:59.559793   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/db/migrate as src/db/migrate
I0620 00:59:59.560379   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
I0620 00:59:59.561050   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/models.rb as src/models.rb
I0620 00:59:59.561769   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/run.sh as src/run.sh
I0620 00:59:59.562479   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/test as src/test
I0620 00:59:59.563163   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/test/sample_test.rb as src/test/sample_test.rb
I0620 00:59:59.563799   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/views as src/views
I0620 00:59:59.564314   14975 tar.go:337] Adding to tar: /tmp/s2i480091967/upload/src/views/main.erb as src/views/main.erb
I0620 00:59:59.611430   14975 docker.go:1044] Waiting for container "cab0426fba377f596e83c493c8c0a806c35638721e075d0e39b49b37e2b50e17" to stop ...
I0620 00:59:59.715941   14975 docker.go:1069] Invoking PostExecute function
I0620 00:59:59.716003   14975 postexecutorstep.go:68] Skipping step: store previous image
I0620 00:59:59.716029   14975 postexecutorstep.go:117] Executing step: commit image
I0620 00:59:59.719041   14975 postexecutorstep.go:523] Checking for new Labels to apply... 
I0620 00:59:59.719113   14975 postexecutorstep.go:531] Creating the download path '/tmp/s2i480091967/metadata'
I0620 00:59:59.719313   14975 postexecutorstep.go:465] Downloading file "/tmp/.s2i/image_metadata.json"
I0620 00:59:59.758723   14975 tar.go:401] Creating directory /tmp/s2i480091967/metadata
I0620 00:59:59.758820   14975 tar.go:469] Creating /tmp/s2i480091967/metadata/image_metadata.json
I0620 00:59:59.759018   14975 tar.go:479] Extracting/writing /tmp/s2i480091967/metadata/image_metadata.json
I0620 00:59:59.759227   14975 tar.go:425] Done extracting tar stream
I0620 00:59:59.759393   14975 postexecutorstep.go:556] new Labels File contents : 
{
  "labels": [
    {"testLabel": "testLabel_value"}
  ]
}

I0620 00:59:59.764721   14975 docker.go:1103] Committing container with dockerOpts: {Reference:test/sti-incremental-app-user Comment: Author: Changes:[] Pause:false Config:0xc0002de640}, config: {Hostname: Domainname: User: AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production] Cmd:[/tmp/scripts/run] Healthcheck:<nil> ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[io.k8s.display-name:test/sti-incremental-app-user io.openshift.s2i.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.s2i.build.commit.date:Thu Jan 17 17:21:03 2019 -0500 io.openshift.s2i.build.commit.id:787f1beae9956c959c6af62ee43bfdda73769cf7 io.openshift.s2i.build.commit.message:Merge pull request #78 from bparees/v22 io.openshift.s2i.build.commit.ref:master io.openshift.s2i.build.image:sti_test/sti-fake io.openshift.s2i.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.s2i.scripts-url:http://127.0.0.1:23456/.s2i/bin testLabel:testLabel_value] StopSignal: StopTimeout:<nil> Shell:[]}
I0620 00:59:59.826750   14975 postexecutorstep.go:393] Executing step: report success
I0620 00:59:59.826808   14975 postexecutorstep.go:398] Successfully built test/sti-incremental-app-user
I0620 00:59:59.826843   14975 postexecutorstep.go:93] Skipping step: remove previous image
I0620 00:59:59.826970   14975 docker.go:980] Removing container "cab0426fba377f596e83c493c8c0a806c35638721e075d0e39b49b37e2b50e17" ...
I0620 00:59:59.837589   14975 docker.go:990] Removed container "cab0426fba377f596e83c493c8c0a806c35638721e075d0e39b49b37e2b50e17"
I0620 00:59:59.837894   14975 cleanup.go:33] Removing temporary directory /tmp/s2i480091967
I0620 00:59:59.837985   14975 fs.go:314] Removing directory '/tmp/s2i480091967'
I0620 00:59:59.843899   14975 docker.go:490] Using locally available image "sti_test/sti-fake:latest"
I0620 00:59:59.851352   14975 docker.go:490] Using locally available image "sti_test/sti-fake:latest"
I0620 00:59:59.851414   14975 docker.go:730] Image sha256:0b5af3e89d502196e1c457afba84ea7f0488b8b4875890b92723a7d31e2aaacf contains io.openshift.s2i.scripts-url set to "http://127.0.0.1:23456/.s2i/bin"
I0620 00:59:59.851472   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 00:59:59.851577   14975 sti.go:204] Preparing to build test/sti-incremental-app-user
I0620 00:59:59.852093   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 00:59:59.852147   14975 clone.go:40] Cloning sources into "/tmp/s2i434544073/upload/src"
I0620 01:00:00.078011   14975 clone.go:56] Checked out "HEAD"
I0620 01:00:00.109848   14975 clone.go:62] Updated submodules for "HEAD"
I0620 01:00:00.126805   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/assemble'
I0620 01:00:00.126971   14975 install.go:261] Using "assemble" installed from "http://127.0.0.1:23456/.s2i/bin/assemble"
I0620 01:00:00.129046   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/run'
I0620 01:00:00.129191   14975 install.go:261] Using "run" installed from "http://127.0.0.1:23456/.s2i/bin/run"
I0620 01:00:00.130905   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/save-artifacts'
I0620 01:00:00.131087   14975 install.go:261] Using "save-artifacts" installed from "http://127.0.0.1:23456/.s2i/bin/save-artifacts"
I0620 01:00:00.131177   14975 ignore.go:66] .s2iignore file does not exist
I0620 01:00:00.134553   14975 docker.go:490] Using locally available image "test/sti-incremental-app-user:latest"
I0620 01:00:00.137438   14975 sti.go:211] Existing image for tag test/sti-incremental-app-user detected for incremental build
I0620 01:00:00.137493   14975 sti.go:216] Performing source build from https://github.com/openshift/ruby-hello-world
I0620 01:00:00.137616   14975 sti.go:522] Saving build artifacts from image test/sti-incremental-app-user to path /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.140346   14975 sti.go:546] The assemble user is not set, defaulting to "" user
I0620 01:00:00.145910   14975 docker.go:793] Both scripts and untarred source will be placed in '/tmp'
I0620 01:00:00.146013   14975 docker.go:961] Setting "/tmp/scripts/save-artifacts" command for container ...
I0620 01:00:00.146283   14975 docker.go:970] Creating container with options {Name:"s2i_test_sti_incremental_app_user_dce102c3" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[] Cmd:[/tmp/scripts/save-artifacts] Healthcheck:<nil> ArgsEscaped:false Image:test/sti-incremental-app-user:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:00.195312   14975 docker.go:1002] Attaching to container "a80efa9d951e1d2807e59677f05c814515e3407252d0f827623956b22569c571" ...
I0620 01:00:00.196720   14975 docker.go:1013] Starting container "a80efa9d951e1d2807e59677f05c814515e3407252d0f827623956b22569c571" ...
I0620 01:00:00.463556   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.463752   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.463847   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/.gitignore
I0620 01:00:00.464373   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/.gitignore
I0620 01:00:00.464710   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts/.s2i
I0620 01:00:00.465053   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts/.s2i/bin
I0620 01:00:00.465288   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts/.s2i/bin
I0620 01:00:00.465381   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/.s2i/bin/README
I0620 01:00:00.465503   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/.s2i/bin/README
I0620 01:00:00.465775   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts/.s2i
I0620 01:00:00.465872   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/.s2i/environment
I0620 01:00:00.466015   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/.s2i/environment
I0620 01:00:00.466306   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.466427   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/.travis.yml
I0620 01:00:00.466568   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/.travis.yml
I0620 01:00:00.466877   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.466985   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/Dockerfile
I0620 01:00:00.467123   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/Dockerfile
I0620 01:00:00.467393   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.467464   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/Gemfile
I0620 01:00:00.467586   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/Gemfile
I0620 01:00:00.467857   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.467960   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/Gemfile.lock
I0620 01:00:00.468098   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/Gemfile.lock
I0620 01:00:00.468403   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.468476   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/README.md
I0620 01:00:00.468596   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/README.md
I0620 01:00:00.468863   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.468958   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/Rakefile
I0620 01:00:00.469095   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/Rakefile
I0620 01:00:00.469347   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.469430   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/app.rb
I0620 01:00:00.469545   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/app.rb
I0620 01:00:00.469827   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts/config
I0620 01:00:00.470064   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts/config
I0620 01:00:00.470137   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/config/database.rb
I0620 01:00:00.470246   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/config/database.rb
I0620 01:00:00.470518   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts/config
I0620 01:00:00.470591   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/config/database.yml
I0620 01:00:00.470710   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/config/database.yml
I0620 01:00:00.470922   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.471042   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/config.ru
I0620 01:00:00.471162   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/config.ru
I0620 01:00:00.471394   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts/db
I0620 01:00:00.471664   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts/db/migrate
I0620 01:00:00.471883   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts/db/migrate
I0620 01:00:00.471990   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:00.472106   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:00.472405   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.472482   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/models.rb
I0620 01:00:00.472599   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/models.rb
I0620 01:00:00.472868   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.472951   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/run.sh
I0620 01:00:00.473069   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/run.sh
I0620 01:00:00.473323   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts/test
I0620 01:00:00.473586   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts/test
I0620 01:00:00.473660   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/test/sample_test.rb
I0620 01:00:00.473787   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/test/sample_test.rb
I0620 01:00:00.474127   14975 tar.go:392] Creating directory /tmp/s2i434544073/upload/artifacts/views
I0620 01:00:00.474358   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts/views
I0620 01:00:00.474434   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/views/main.erb
I0620 01:00:00.474616   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/views/main.erb
I0620 01:00:00.474920   14975 tar.go:401] Creating directory /tmp/s2i434544073/upload/artifacts
I0620 01:00:00.475027   14975 tar.go:469] Creating /tmp/s2i434544073/upload/artifacts/save-artifacts-invoked
I0620 01:00:00.475200   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/upload/artifacts/save-artifacts-invoked
I0620 01:00:00.475467   14975 tar.go:425] Done extracting tar stream
I0620 01:00:00.505660   14975 docker.go:1044] Waiting for container "a80efa9d951e1d2807e59677f05c814515e3407252d0f827623956b22569c571" to stop ...
I0620 01:00:00.607349   14975 docker.go:980] Removing container "a80efa9d951e1d2807e59677f05c814515e3407252d0f827623956b22569c571" ...
I0620 01:00:00.618053   14975 docker.go:990] Removed container "a80efa9d951e1d2807e59677f05c814515e3407252d0f827623956b22569c571"
I0620 01:00:00.618363   14975 sti.go:227] Running "assemble" in "test/sti-incremental-app-user"
I0620 01:00:00.618417   14975 sti.go:585] Using image name sti_test/sti-fake
I0620 01:00:00.621718   14975 docker.go:490] Using locally available image "sti_test/sti-fake:latest"
I0620 01:00:00.621866   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 01:00:00.622509   14975 sti.go:703] starting the source uploading ...
I0620 01:00:00.622583   14975 tar.go:239] Adding "/tmp/s2i434544073/upload" to tar ...
I0620 01:00:00.623040   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts as artifacts
I0620 01:00:00.628100   14975 docker.go:793] Both scripts and untarred source will be placed in '/tmp'
I0620 01:00:00.628202   14975 docker.go:961] Setting "/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble" command for container ...
I0620 01:00:00.628578   14975 docker.go:970] Creating container with options {Name:"s2i_sti_test_sti_fake_4941bc53" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:true StdinOnce:true Env:[RACK_ENV=production] Cmd:[/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble] Healthcheck:<nil> ArgsEscaped:false Image:sti_test/sti-fake:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:00.658115   14975 docker.go:1002] Attaching to container "d2c49e84afbc1e1884e2d82e22926a4ff2b4d5ca039ae35106f57aa071d24410" ...
I0620 01:00:00.659551   14975 docker.go:1013] Starting container "d2c49e84afbc1e1884e2d82e22926a4ff2b4d5ca039ae35106f57aa071d24410" ...
I0620 01:00:00.967421   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/.gitignore as artifacts/.gitignore
I0620 01:00:00.968033   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/.s2i as artifacts/.s2i
I0620 01:00:00.968579   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/.s2i/bin as artifacts/.s2i/bin
I0620 01:00:00.969136   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/.s2i/bin/README as artifacts/.s2i/bin/README
I0620 01:00:00.969673   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/.s2i/environment as artifacts/.s2i/environment
I0620 01:00:00.970279   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/.travis.yml as artifacts/.travis.yml
I0620 01:00:00.970887   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/Dockerfile as artifacts/Dockerfile
I0620 01:00:00.971800   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/Gemfile as artifacts/Gemfile
I0620 01:00:00.972626   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/Gemfile.lock as artifacts/Gemfile.lock
I0620 01:00:00.973319   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/README.md as artifacts/README.md
I0620 01:00:00.973793   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/Rakefile as artifacts/Rakefile
I0620 01:00:00.974375   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/app.rb as artifacts/app.rb
I0620 01:00:00.974971   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/config as artifacts/config
I0620 01:00:00.976035   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/config/database.rb as artifacts/config/database.rb
I0620 01:00:00.976671   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/config/database.yml as artifacts/config/database.yml
I0620 01:00:00.977249   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/config.ru as artifacts/config.ru
I0620 01:00:00.977741   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/db as artifacts/db
I0620 01:00:00.978281   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/db/migrate as artifacts/db/migrate
I0620 01:00:00.978774   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/db/migrate/20141102191902_create_key_pair.rb as artifacts/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:00.979325   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/models.rb as artifacts/models.rb
I0620 01:00:00.980050   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/run.sh as artifacts/run.sh
I0620 01:00:00.980661   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/save-artifacts-invoked as artifacts/save-artifacts-invoked
I0620 01:00:00.981236   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/test as artifacts/test
I0620 01:00:00.981688   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/test/sample_test.rb as artifacts/test/sample_test.rb
I0620 01:00:00.982273   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/views as artifacts/views
I0620 01:00:00.982708   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/artifacts/views/main.erb as artifacts/views/main.erb
I0620 01:00:00.983240   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/scripts as scripts
I0620 01:00:00.983638   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/scripts/assemble as scripts/assemble
I0620 01:00:00.984168   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/scripts/run as scripts/run
I0620 01:00:00.984688   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/scripts/save-artifacts as scripts/save-artifacts
I0620 01:00:00.985338   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src as src
I0620 01:00:00.989806   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/.gitignore as src/.gitignore
I0620 01:00:00.990431   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/.s2i as src/.s2i
I0620 01:00:00.991105   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/.s2i/bin as src/.s2i/bin
I0620 01:00:00.991731   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/.s2i/bin/README as src/.s2i/bin/README
I0620 01:00:00.992486   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/.s2i/environment as src/.s2i/environment
I0620 01:00:00.993103   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/.travis.yml as src/.travis.yml
I0620 01:00:00.993654   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/Dockerfile as src/Dockerfile
I0620 01:00:00.994195   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/Gemfile as src/Gemfile
I0620 01:00:00.994688   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/Gemfile.lock as src/Gemfile.lock
I0620 01:00:00.995158   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/README.md as src/README.md
I0620 01:00:00.995609   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/Rakefile as src/Rakefile
I0620 01:00:00.996094   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/app.rb as src/app.rb
I0620 01:00:00.996616   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/config as src/config
I0620 01:00:00.997612   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/config/database.rb as src/config/database.rb
I0620 01:00:00.998409   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/config/database.yml as src/config/database.yml
I0620 01:00:00.999098   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/config.ru as src/config.ru
I0620 01:00:00.999969   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/db as src/db
I0620 01:00:01.000539   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/db/migrate as src/db/migrate
I0620 01:00:01.001023   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:01.001463   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/models.rb as src/models.rb
I0620 01:00:01.001899   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/run.sh as src/run.sh
I0620 01:00:01.002409   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/test as src/test
I0620 01:00:01.002839   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/test/sample_test.rb as src/test/sample_test.rb
I0620 01:00:01.003454   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/views as src/views
I0620 01:00:01.003918   14975 tar.go:337] Adding to tar: /tmp/s2i434544073/upload/src/views/main.erb as src/views/main.erb
I0620 01:00:01.052708   14975 docker.go:1044] Waiting for container "d2c49e84afbc1e1884e2d82e22926a4ff2b4d5ca039ae35106f57aa071d24410" to stop ...
I0620 01:00:01.150255   14975 docker.go:1069] Invoking PostExecute function
I0620 01:00:01.150337   14975 postexecutorstep.go:63] Executing step: store previous image
I0620 01:00:01.153588   14975 postexecutorstep.go:117] Executing step: commit image
I0620 01:00:01.156202   14975 postexecutorstep.go:523] Checking for new Labels to apply... 
I0620 01:00:01.156274   14975 postexecutorstep.go:531] Creating the download path '/tmp/s2i434544073/metadata'
I0620 01:00:01.156535   14975 postexecutorstep.go:465] Downloading file "/tmp/.s2i/image_metadata.json"
I0620 01:00:01.197685   14975 tar.go:401] Creating directory /tmp/s2i434544073/metadata
I0620 01:00:01.197787   14975 tar.go:469] Creating /tmp/s2i434544073/metadata/image_metadata.json
I0620 01:00:01.198014   14975 tar.go:479] Extracting/writing /tmp/s2i434544073/metadata/image_metadata.json
I0620 01:00:01.198237   14975 tar.go:425] Done extracting tar stream
I0620 01:00:01.198394   14975 postexecutorstep.go:556] new Labels File contents : 
{
  "labels": [
    {"testLabel": "testLabel_value"}
  ]
}

I0620 01:00:01.203654   14975 docker.go:1103] Committing container with dockerOpts: {Reference:test/sti-incremental-app-user Comment: Author: Changes:[] Pause:false Config:0xc000434500}, config: {Hostname: Domainname: User: AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production] Cmd:[/tmp/scripts/run] Healthcheck:<nil> ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[io.k8s.display-name:test/sti-incremental-app-user io.openshift.s2i.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.s2i.build.commit.date:Thu Jan 17 17:21:03 2019 -0500 io.openshift.s2i.build.commit.id:787f1beae9956c959c6af62ee43bfdda73769cf7 io.openshift.s2i.build.commit.message:Merge pull request #78 from bparees/v22 io.openshift.s2i.build.commit.ref:master io.openshift.s2i.build.image:sti_test/sti-fake io.openshift.s2i.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.s2i.scripts-url:http://127.0.0.1:23456/.s2i/bin testLabel:testLabel_value] StopSignal: StopTimeout:<nil> Shell:[]}
I0620 01:00:01.269124   14975 postexecutorstep.go:393] Executing step: report success
I0620 01:00:01.269179   14975 postexecutorstep.go:398] Successfully built test/sti-incremental-app-user
I0620 01:00:01.269219   14975 postexecutorstep.go:88] Executing step: remove previous image
I0620 01:00:01.269253   14975 postexecutorstep.go:102] Removing previously-tagged image sha256:535437123214d6b0ebeef4778ff7d52c7ab0e3889e114962688ea55305c91bae
I0620 01:00:01.273747   14975 docker.go:980] Removing container "d2c49e84afbc1e1884e2d82e22926a4ff2b4d5ca039ae35106f57aa071d24410" ...
I0620 01:00:01.286611   14975 docker.go:990] Removed container "d2c49e84afbc1e1884e2d82e22926a4ff2b4d5ca039ae35106f57aa071d24410"
I0620 01:00:01.286856   14975 cleanup.go:33] Removing temporary directory /tmp/s2i434544073
I0620 01:00:01.286900   14975 fs.go:314] Removing directory '/tmp/s2i434544073'
--- PASS: TestIncrementalBuildUser (2.88s)
=== RUN   TestIncrementalBuildScripts
I0620 01:00:01.845711   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts:latest"
I0620 01:00:01.853186   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts:latest"
I0620 01:00:01.853245   14975 docker.go:730] Image sha256:a391dc9e398154f4f97923ad754c422b80062022355c549f866a10276771ca3e contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:01.853310   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:01.853458   14975 sti.go:204] Preparing to build test/sti-incremental-app-scripts
I0620 01:00:01.854153   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 01:00:01.854210   14975 clone.go:40] Cloning sources into "/tmp/s2i368431843/upload/src"
I0620 01:00:03.867783   14975 clone.go:56] Checked out "HEAD"
I0620 01:00:03.902091   14975 clone.go:62] Updated submodules for "HEAD"
I0620 01:00:03.916398   14975 install.go:261] Using "assemble" installed from "image:///tmp/scripts//assemble"
I0620 01:00:03.916514   14975 install.go:261] Using "run" installed from "image:///tmp/scripts//run"
I0620 01:00:03.916611   14975 install.go:261] Using "save-artifacts" installed from "image:///tmp/scripts//save-artifacts"
I0620 01:00:03.916690   14975 ignore.go:66] .s2iignore file does not exist
I0620 01:00:03.916723   14975 sti.go:213] Clean build will be performed
I0620 01:00:03.916764   14975 sti.go:216] Performing source build from https://github.com/openshift/ruby-hello-world
I0620 01:00:03.916808   14975 sti.go:227] Running "assemble" in "test/sti-incremental-app-scripts"
I0620 01:00:03.916843   14975 sti.go:585] Using image name sti_test/sti-fake-scripts
I0620 01:00:03.920031   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts:latest"
I0620 01:00:03.920147   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 01:00:03.920349   14975 sti.go:703] starting the source uploading ...
I0620 01:00:03.920435   14975 tar.go:239] Adding "/tmp/s2i368431843/upload" to tar ...
I0620 01:00:03.920791   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/scripts as scripts
I0620 01:00:03.925672   14975 docker.go:730] Image sha256:a391dc9e398154f4f97923ad754c422b80062022355c549f866a10276771ca3e contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:03.925728   14975 docker.go:804] Base directory for S2I scripts is '/tmp/scripts/'. Untarring destination is '/tmp'.
I0620 01:00:03.925813   14975 docker.go:961] Setting "/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble" command for container ...
I0620 01:00:03.926180   14975 docker.go:970] Creating container with options {Name:"s2i_sti_test_sti_fake_scripts_c05d7a1f" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:true StdinOnce:true Env:[RACK_ENV=production] Cmd:[/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble] Healthcheck:<nil> ArgsEscaped:false Image:sti_test/sti-fake-scripts:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:03.959793   14975 docker.go:1002] Attaching to container "5699e64367850d7785ef18252aa84d701f096a7a090ee3599ea4ff265ab02f38" ...
I0620 01:00:03.961338   14975 docker.go:1013] Starting container "5699e64367850d7785ef18252aa84d701f096a7a090ee3599ea4ff265ab02f38" ...
I0620 01:00:04.239681   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src as src
I0620 01:00:04.244255   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/.gitignore as src/.gitignore
I0620 01:00:04.244918   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/.s2i as src/.s2i
I0620 01:00:04.245501   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/.s2i/bin as src/.s2i/bin
I0620 01:00:04.246027   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/.s2i/bin/README as src/.s2i/bin/README
I0620 01:00:04.246647   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/.s2i/environment as src/.s2i/environment
I0620 01:00:04.247378   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/.travis.yml as src/.travis.yml
I0620 01:00:04.247918   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/Dockerfile as src/Dockerfile
I0620 01:00:04.248515   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/Gemfile as src/Gemfile
I0620 01:00:04.249130   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/Gemfile.lock as src/Gemfile.lock
I0620 01:00:04.249614   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/README.md as src/README.md
I0620 01:00:04.250100   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/Rakefile as src/Rakefile
I0620 01:00:04.250652   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/app.rb as src/app.rb
I0620 01:00:04.252285   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/config as src/config
I0620 01:00:04.252862   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/config/database.rb as src/config/database.rb
I0620 01:00:04.254469   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/config/database.yml as src/config/database.yml
I0620 01:00:04.255053   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/config.ru as src/config.ru
I0620 01:00:04.255661   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/db as src/db
I0620 01:00:04.256285   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/db/migrate as src/db/migrate
I0620 01:00:04.256765   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:04.257292   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/models.rb as src/models.rb
I0620 01:00:04.257810   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/run.sh as src/run.sh
I0620 01:00:04.258336   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/test as src/test
I0620 01:00:04.258787   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/test/sample_test.rb as src/test/sample_test.rb
I0620 01:00:04.259295   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/views as src/views
I0620 01:00:04.259733   14975 tar.go:337] Adding to tar: /tmp/s2i368431843/upload/src/views/main.erb as src/views/main.erb
I0620 01:00:04.312594   14975 docker.go:1044] Waiting for container "5699e64367850d7785ef18252aa84d701f096a7a090ee3599ea4ff265ab02f38" to stop ...
I0620 01:00:04.414800   14975 docker.go:1069] Invoking PostExecute function
I0620 01:00:04.414874   14975 postexecutorstep.go:68] Skipping step: store previous image
I0620 01:00:04.414908   14975 postexecutorstep.go:117] Executing step: commit image
I0620 01:00:04.418163   14975 postexecutorstep.go:523] Checking for new Labels to apply... 
I0620 01:00:04.418237   14975 postexecutorstep.go:531] Creating the download path '/tmp/s2i368431843/metadata'
I0620 01:00:04.418505   14975 postexecutorstep.go:465] Downloading file "/tmp/.s2i/image_metadata.json"
I0620 01:00:04.450230   14975 tar.go:401] Creating directory /tmp/s2i368431843/metadata
I0620 01:00:04.450332   14975 tar.go:469] Creating /tmp/s2i368431843/metadata/image_metadata.json
I0620 01:00:04.450511   14975 tar.go:479] Extracting/writing /tmp/s2i368431843/metadata/image_metadata.json
I0620 01:00:04.450690   14975 tar.go:425] Done extracting tar stream
I0620 01:00:04.450822   14975 postexecutorstep.go:556] new Labels File contents : 
{
  "labels": [
    {"testLabel": "testLabel_value"}
  ]
}

I0620 01:00:04.456432   14975 docker.go:1103] Committing container with dockerOpts: {Reference:test/sti-incremental-app-scripts Comment: Author: Changes:[] Pause:false Config:0xc00025a640}, config: {Hostname: Domainname: User: AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production] Cmd:[/tmp/scripts//run] Healthcheck:<nil> ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[io.k8s.display-name:test/sti-incremental-app-scripts io.openshift.s2i.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.s2i.build.commit.date:Thu Jan 17 17:21:03 2019 -0500 io.openshift.s2i.build.commit.id:787f1beae9956c959c6af62ee43bfdda73769cf7 io.openshift.s2i.build.commit.message:Merge pull request #78 from bparees/v22 io.openshift.s2i.build.commit.ref:master io.openshift.s2i.build.image:sti_test/sti-fake-scripts io.openshift.s2i.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.s2i.scripts-url:image:///tmp/scripts/ testLabel:testLabel_value] StopSignal: StopTimeout:<nil> Shell:[]}
I0620 01:00:04.517072   14975 postexecutorstep.go:393] Executing step: report success
I0620 01:00:04.517139   14975 postexecutorstep.go:398] Successfully built test/sti-incremental-app-scripts
I0620 01:00:04.517171   14975 postexecutorstep.go:93] Skipping step: remove previous image
I0620 01:00:04.517266   14975 docker.go:980] Removing container "5699e64367850d7785ef18252aa84d701f096a7a090ee3599ea4ff265ab02f38" ...
I0620 01:00:04.528137   14975 docker.go:990] Removed container "5699e64367850d7785ef18252aa84d701f096a7a090ee3599ea4ff265ab02f38"
I0620 01:00:04.528402   14975 cleanup.go:33] Removing temporary directory /tmp/s2i368431843
I0620 01:00:04.528446   14975 fs.go:314] Removing directory '/tmp/s2i368431843'
I0620 01:00:04.538717   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts:latest"
I0620 01:00:04.546522   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts:latest"
I0620 01:00:04.546589   14975 docker.go:730] Image sha256:a391dc9e398154f4f97923ad754c422b80062022355c549f866a10276771ca3e contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:04.546645   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:04.546733   14975 sti.go:204] Preparing to build test/sti-incremental-app-scripts
I0620 01:00:04.547313   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 01:00:04.547367   14975 clone.go:40] Cloning sources into "/tmp/s2i469823757/upload/src"
I0620 01:00:06.671627   14975 clone.go:56] Checked out "HEAD"
I0620 01:00:06.703526   14975 clone.go:62] Updated submodules for "HEAD"
I0620 01:00:06.719177   14975 install.go:261] Using "assemble" installed from "image:///tmp/scripts//assemble"
I0620 01:00:06.719293   14975 install.go:261] Using "run" installed from "image:///tmp/scripts//run"
I0620 01:00:06.719392   14975 install.go:261] Using "save-artifacts" installed from "image:///tmp/scripts//save-artifacts"
I0620 01:00:06.719469   14975 ignore.go:66] .s2iignore file does not exist
I0620 01:00:06.723262   14975 docker.go:490] Using locally available image "test/sti-incremental-app-scripts:latest"
I0620 01:00:06.726298   14975 sti.go:211] Existing image for tag test/sti-incremental-app-scripts detected for incremental build
I0620 01:00:06.726356   14975 sti.go:216] Performing source build from https://github.com/openshift/ruby-hello-world
I0620 01:00:06.726500   14975 sti.go:522] Saving build artifacts from image test/sti-incremental-app-scripts to path /tmp/s2i469823757/upload/artifacts
I0620 01:00:06.729371   14975 sti.go:546] The assemble user is not set, defaulting to "" user
I0620 01:00:06.735181   14975 docker.go:730] Image sha256:9330b5a669d21f1a7414b9c72e38047c8a9029f11ffa973714afacf1602210df contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:06.735237   14975 docker.go:804] Base directory for S2I scripts is '/tmp/scripts/'. Untarring destination is '/tmp'.
I0620 01:00:06.735289   14975 docker.go:961] Setting "/tmp/scripts/save-artifacts" command for container ...
I0620 01:00:06.735621   14975 docker.go:970] Creating container with options {Name:"s2i_test_sti_incremental_app_scripts_84050362" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[] Cmd:[/tmp/scripts/save-artifacts] Healthcheck:<nil> ArgsEscaped:false Image:test/sti-incremental-app-scripts:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:06.767053   14975 docker.go:1002] Attaching to container "9ed6ce38bfc1933801c6ca4f394521050490a91d0bc7ae47ed712f34e88b4033" ...
I0620 01:00:06.768390   14975 docker.go:1013] Starting container "9ed6ce38bfc1933801c6ca4f394521050490a91d0bc7ae47ed712f34e88b4033" ...
I0620 01:00:07.021041   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.021239   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.021316   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/.gitignore
I0620 01:00:07.021482   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/.gitignore
I0620 01:00:07.021735   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts/.s2i
I0620 01:00:07.021985   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts/.s2i/bin
I0620 01:00:07.022209   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts/.s2i/bin
I0620 01:00:07.022277   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/.s2i/bin/README
I0620 01:00:07.022379   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/.s2i/bin/README
I0620 01:00:07.022582   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts/.s2i
I0620 01:00:07.022650   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/.s2i/environment
I0620 01:00:07.022747   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/.s2i/environment
I0620 01:00:07.022982   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.023075   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/.travis.yml
I0620 01:00:07.023212   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/.travis.yml
I0620 01:00:07.023479   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.023554   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/Dockerfile
I0620 01:00:07.023690   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/Dockerfile
I0620 01:00:07.023919   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.024018   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/Gemfile
I0620 01:00:07.024208   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/Gemfile
I0620 01:00:07.024495   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.024568   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/Gemfile.lock
I0620 01:00:07.024751   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/Gemfile.lock
I0620 01:00:07.025058   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.025161   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/README.md
I0620 01:00:07.025292   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/README.md
I0620 01:00:07.025523   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.025604   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/Rakefile
I0620 01:00:07.025702   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/Rakefile
I0620 01:00:07.025978   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.026042   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/app.rb
I0620 01:00:07.026151   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/app.rb
I0620 01:00:07.026367   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts/config
I0620 01:00:07.026571   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts/config
I0620 01:00:07.026632   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/config/database.rb
I0620 01:00:07.026730   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/config/database.rb
I0620 01:00:07.026987   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts/config
I0620 01:00:07.027075   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/config/database.yml
I0620 01:00:07.027185   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/config/database.yml
I0620 01:00:07.027457   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.027524   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/config.ru
I0620 01:00:07.027609   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/config.ru
I0620 01:00:07.027782   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts/db
I0620 01:00:07.027986   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts/db/migrate
I0620 01:00:07.028234   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts/db/migrate
I0620 01:00:07.028289   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:07.028375   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:07.028637   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.028717   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/models.rb
I0620 01:00:07.028956   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/models.rb
I0620 01:00:07.029203   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.029296   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/run.sh
I0620 01:00:07.029424   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/run.sh
I0620 01:00:07.029764   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts/test
I0620 01:00:07.030067   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts/test
I0620 01:00:07.030170   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/test/sample_test.rb
I0620 01:00:07.030294   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/test/sample_test.rb
I0620 01:00:07.030562   14975 tar.go:392] Creating directory /tmp/s2i469823757/upload/artifacts/views
I0620 01:00:07.030808   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts/views
I0620 01:00:07.030895   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/views/main.erb
I0620 01:00:07.031042   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/views/main.erb
I0620 01:00:07.031346   14975 tar.go:401] Creating directory /tmp/s2i469823757/upload/artifacts
I0620 01:00:07.031434   14975 tar.go:469] Creating /tmp/s2i469823757/upload/artifacts/save-artifacts-invoked
I0620 01:00:07.031590   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/upload/artifacts/save-artifacts-invoked
I0620 01:00:07.031798   14975 tar.go:425] Done extracting tar stream
I0620 01:00:07.057828   14975 docker.go:1044] Waiting for container "9ed6ce38bfc1933801c6ca4f394521050490a91d0bc7ae47ed712f34e88b4033" to stop ...
I0620 01:00:07.156878   14975 docker.go:980] Removing container "9ed6ce38bfc1933801c6ca4f394521050490a91d0bc7ae47ed712f34e88b4033" ...
I0620 01:00:07.173386   14975 docker.go:990] Removed container "9ed6ce38bfc1933801c6ca4f394521050490a91d0bc7ae47ed712f34e88b4033"
I0620 01:00:07.173690   14975 sti.go:227] Running "assemble" in "test/sti-incremental-app-scripts"
I0620 01:00:07.173763   14975 sti.go:585] Using image name sti_test/sti-fake-scripts
I0620 01:00:07.176454   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts:latest"
I0620 01:00:07.176572   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 01:00:07.176926   14975 sti.go:703] starting the source uploading ...
I0620 01:00:07.177047   14975 tar.go:239] Adding "/tmp/s2i469823757/upload" to tar ...
I0620 01:00:07.177657   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts as artifacts
I0620 01:00:07.182130   14975 docker.go:730] Image sha256:a391dc9e398154f4f97923ad754c422b80062022355c549f866a10276771ca3e contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:07.182183   14975 docker.go:804] Base directory for S2I scripts is '/tmp/scripts/'. Untarring destination is '/tmp'.
I0620 01:00:07.182235   14975 docker.go:961] Setting "/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble" command for container ...
I0620 01:00:07.182535   14975 docker.go:970] Creating container with options {Name:"s2i_sti_test_sti_fake_scripts_9c6e5e9a" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:true StdinOnce:true Env:[RACK_ENV=production] Cmd:[/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble] Healthcheck:<nil> ArgsEscaped:false Image:sti_test/sti-fake-scripts:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:07.216307   14975 docker.go:1002] Attaching to container "661794273aaaac41608a26d6c0cd1672071124e9789d834e2ab3577dc1728d17" ...
I0620 01:00:07.217605   14975 docker.go:1013] Starting container "661794273aaaac41608a26d6c0cd1672071124e9789d834e2ab3577dc1728d17" ...
I0620 01:00:07.461659   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/.gitignore as artifacts/.gitignore
I0620 01:00:07.462403   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/.s2i as artifacts/.s2i
I0620 01:00:07.462987   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/.s2i/bin as artifacts/.s2i/bin
I0620 01:00:07.463501   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/.s2i/bin/README as artifacts/.s2i/bin/README
I0620 01:00:07.464103   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/.s2i/environment as artifacts/.s2i/environment
I0620 01:00:07.464719   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/.travis.yml as artifacts/.travis.yml
I0620 01:00:07.465353   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/Dockerfile as artifacts/Dockerfile
I0620 01:00:07.465859   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/Gemfile as artifacts/Gemfile
I0620 01:00:07.466828   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/Gemfile.lock as artifacts/Gemfile.lock
I0620 01:00:07.467727   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/README.md as artifacts/README.md
I0620 01:00:07.468270   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/Rakefile as artifacts/Rakefile
I0620 01:00:07.469615   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/app.rb as artifacts/app.rb
I0620 01:00:07.470333   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/config as artifacts/config
I0620 01:00:07.470749   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/config/database.rb as artifacts/config/database.rb
I0620 01:00:07.471270   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/config/database.yml as artifacts/config/database.yml
I0620 01:00:07.471845   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/config.ru as artifacts/config.ru
I0620 01:00:07.472372   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/db as artifacts/db
I0620 01:00:07.472873   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/db/migrate as artifacts/db/migrate
I0620 01:00:07.473404   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/db/migrate/20141102191902_create_key_pair.rb as artifacts/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:07.473878   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/models.rb as artifacts/models.rb
I0620 01:00:07.474369   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/run.sh as artifacts/run.sh
I0620 01:00:07.474897   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/save-artifacts-invoked as artifacts/save-artifacts-invoked
I0620 01:00:07.475414   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/test as artifacts/test
I0620 01:00:07.475857   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/test/sample_test.rb as artifacts/test/sample_test.rb
I0620 01:00:07.476502   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/views as artifacts/views
I0620 01:00:07.477061   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/artifacts/views/main.erb as artifacts/views/main.erb
I0620 01:00:07.477745   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/scripts as scripts
I0620 01:00:07.478445   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src as src
I0620 01:00:07.482401   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/.gitignore as src/.gitignore
I0620 01:00:07.482986   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/.s2i as src/.s2i
I0620 01:00:07.483611   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/.s2i/bin as src/.s2i/bin
I0620 01:00:07.484141   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/.s2i/bin/README as src/.s2i/bin/README
I0620 01:00:07.484649   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/.s2i/environment as src/.s2i/environment
I0620 01:00:07.485242   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/.travis.yml as src/.travis.yml
I0620 01:00:07.486181   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/Dockerfile as src/Dockerfile
I0620 01:00:07.486826   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/Gemfile as src/Gemfile
I0620 01:00:07.487577   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/Gemfile.lock as src/Gemfile.lock
I0620 01:00:07.488546   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/README.md as src/README.md
I0620 01:00:07.489207   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/Rakefile as src/Rakefile
I0620 01:00:07.489686   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/app.rb as src/app.rb
I0620 01:00:07.490217   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/config as src/config
I0620 01:00:07.490662   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/config/database.rb as src/config/database.rb
I0620 01:00:07.491173   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/config/database.yml as src/config/database.yml
I0620 01:00:07.491647   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/config.ru as src/config.ru
I0620 01:00:07.492236   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/db as src/db
I0620 01:00:07.492775   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/db/migrate as src/db/migrate
I0620 01:00:07.493441   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:07.493983   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/models.rb as src/models.rb
I0620 01:00:07.494597   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/run.sh as src/run.sh
I0620 01:00:07.495251   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/test as src/test
I0620 01:00:07.495794   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/test/sample_test.rb as src/test/sample_test.rb
I0620 01:00:07.496459   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/views as src/views
I0620 01:00:07.497024   14975 tar.go:337] Adding to tar: /tmp/s2i469823757/upload/src/views/main.erb as src/views/main.erb
I0620 01:00:07.547366   14975 docker.go:1044] Waiting for container "661794273aaaac41608a26d6c0cd1672071124e9789d834e2ab3577dc1728d17" to stop ...
I0620 01:00:07.646905   14975 docker.go:1069] Invoking PostExecute function
I0620 01:00:07.646991   14975 postexecutorstep.go:63] Executing step: store previous image
I0620 01:00:07.650266   14975 postexecutorstep.go:117] Executing step: commit image
I0620 01:00:07.652880   14975 postexecutorstep.go:523] Checking for new Labels to apply... 
I0620 01:00:07.652966   14975 postexecutorstep.go:531] Creating the download path '/tmp/s2i469823757/metadata'
I0620 01:00:07.653225   14975 postexecutorstep.go:465] Downloading file "/tmp/.s2i/image_metadata.json"
I0620 01:00:07.702539   14975 tar.go:401] Creating directory /tmp/s2i469823757/metadata
I0620 01:00:07.702622   14975 tar.go:469] Creating /tmp/s2i469823757/metadata/image_metadata.json
I0620 01:00:07.702779   14975 tar.go:479] Extracting/writing /tmp/s2i469823757/metadata/image_metadata.json
I0620 01:00:07.703006   14975 tar.go:425] Done extracting tar stream
I0620 01:00:07.703185   14975 postexecutorstep.go:556] new Labels File contents : 
{
  "labels": [
    {"testLabel": "testLabel_value"}
  ]
}

I0620 01:00:07.708588   14975 docker.go:1103] Committing container with dockerOpts: {Reference:test/sti-incremental-app-scripts Comment: Author: Changes:[] Pause:false Config:0xc000484280}, config: {Hostname: Domainname: User: AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production] Cmd:[/tmp/scripts//run] Healthcheck:<nil> ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[io.k8s.display-name:test/sti-incremental-app-scripts io.openshift.s2i.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.s2i.build.commit.date:Thu Jan 17 17:21:03 2019 -0500 io.openshift.s2i.build.commit.id:787f1beae9956c959c6af62ee43bfdda73769cf7 io.openshift.s2i.build.commit.message:Merge pull request #78 from bparees/v22 io.openshift.s2i.build.commit.ref:master io.openshift.s2i.build.image:sti_test/sti-fake-scripts io.openshift.s2i.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.s2i.scripts-url:image:///tmp/scripts/ testLabel:testLabel_value] StopSignal: StopTimeout:<nil> Shell:[]}
I0620 01:00:07.775523   14975 postexecutorstep.go:393] Executing step: report success
I0620 01:00:07.775577   14975 postexecutorstep.go:398] Successfully built test/sti-incremental-app-scripts
I0620 01:00:07.775602   14975 postexecutorstep.go:88] Executing step: remove previous image
I0620 01:00:07.775640   14975 postexecutorstep.go:102] Removing previously-tagged image sha256:9330b5a669d21f1a7414b9c72e38047c8a9029f11ffa973714afacf1602210df
I0620 01:00:07.779987   14975 docker.go:980] Removing container "661794273aaaac41608a26d6c0cd1672071124e9789d834e2ab3577dc1728d17" ...
I0620 01:00:07.792335   14975 docker.go:990] Removed container "661794273aaaac41608a26d6c0cd1672071124e9789d834e2ab3577dc1728d17"
I0620 01:00:07.792651   14975 cleanup.go:33] Removing temporary directory /tmp/s2i469823757
I0620 01:00:07.792740   14975 fs.go:314] Removing directory '/tmp/s2i469823757'
--- PASS: TestIncrementalBuildScripts (6.53s)
=== RUN   TestIncrementalBuildScriptsNoSaveArtifacts
I0620 01:00:08.365771   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts-no-save-artifacts:latest"
I0620 01:00:08.378643   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts-no-save-artifacts:latest"
I0620 01:00:08.378704   14975 docker.go:730] Image sha256:5b9a6ddf6ec20b690e6128666e327bca2f016c967779f9f453504210b3911f69 contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:08.378804   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:08.378912   14975 sti.go:204] Preparing to build test/sti-incremental-app-scripts-no-save-artifacts
I0620 01:00:08.379473   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 01:00:08.379544   14975 clone.go:40] Cloning sources into "/tmp/s2i109605063/upload/src"
I0620 01:00:09.480695   14975 clone.go:56] Checked out "HEAD"
I0620 01:00:09.513896   14975 clone.go:62] Updated submodules for "HEAD"
I0620 01:00:09.528476   14975 install.go:261] Using "assemble" installed from "image:///tmp/scripts//assemble"
I0620 01:00:09.528598   14975 install.go:261] Using "run" installed from "image:///tmp/scripts//run"
I0620 01:00:09.528726   14975 install.go:261] Using "save-artifacts" installed from "image:///tmp/scripts//save-artifacts"
I0620 01:00:09.528800   14975 ignore.go:66] .s2iignore file does not exist
I0620 01:00:09.528848   14975 sti.go:213] Clean build will be performed
I0620 01:00:09.528905   14975 sti.go:216] Performing source build from https://github.com/openshift/ruby-hello-world
I0620 01:00:09.528989   14975 sti.go:227] Running "assemble" in "test/sti-incremental-app-scripts-no-save-artifacts"
I0620 01:00:09.529035   14975 sti.go:585] Using image name sti_test/sti-fake-scripts-no-save-artifacts
I0620 01:00:09.532361   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts-no-save-artifacts:latest"
I0620 01:00:09.532486   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 01:00:09.532853   14975 sti.go:703] starting the source uploading ...
I0620 01:00:09.532975   14975 tar.go:239] Adding "/tmp/s2i109605063/upload" to tar ...
I0620 01:00:09.533362   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/scripts as scripts
I0620 01:00:09.539647   14975 docker.go:730] Image sha256:5b9a6ddf6ec20b690e6128666e327bca2f016c967779f9f453504210b3911f69 contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:09.539695   14975 docker.go:804] Base directory for S2I scripts is '/tmp/scripts/'. Untarring destination is '/tmp'.
I0620 01:00:09.539748   14975 docker.go:961] Setting "/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble" command for container ...
I0620 01:00:09.540108   14975 docker.go:970] Creating container with options {Name:"s2i_sti_test_sti_fake_scripts_no_save_artifacts_5d269028" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:true StdinOnce:true Env:[RACK_ENV=production] Cmd:[/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble] Healthcheck:<nil> ArgsEscaped:false Image:sti_test/sti-fake-scripts-no-save-artifacts:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:09.579187   14975 docker.go:1002] Attaching to container "35e97d6979938b448417c1d68eb1047f3523c56f4c336644eb474f4fc1989320" ...
I0620 01:00:09.580632   14975 docker.go:1013] Starting container "35e97d6979938b448417c1d68eb1047f3523c56f4c336644eb474f4fc1989320" ...
I0620 01:00:09.839372   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src as src
I0620 01:00:09.844598   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/.gitignore as src/.gitignore
I0620 01:00:09.845334   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/.s2i as src/.s2i
I0620 01:00:09.845976   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/.s2i/bin as src/.s2i/bin
I0620 01:00:09.846862   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/.s2i/bin/README as src/.s2i/bin/README
I0620 01:00:09.847480   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/.s2i/environment as src/.s2i/environment
I0620 01:00:09.848057   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/.travis.yml as src/.travis.yml
I0620 01:00:09.848602   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/Dockerfile as src/Dockerfile
I0620 01:00:09.849212   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/Gemfile as src/Gemfile
I0620 01:00:09.849766   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/Gemfile.lock as src/Gemfile.lock
I0620 01:00:09.850327   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/README.md as src/README.md
I0620 01:00:09.850865   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/Rakefile as src/Rakefile
I0620 01:00:09.851393   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/app.rb as src/app.rb
I0620 01:00:09.852020   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/config as src/config
I0620 01:00:09.852491   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/config/database.rb as src/config/database.rb
I0620 01:00:09.852963   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/config/database.yml as src/config/database.yml
I0620 01:00:09.853398   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/config.ru as src/config.ru
I0620 01:00:09.853869   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/db as src/db
I0620 01:00:09.854378   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/db/migrate as src/db/migrate
I0620 01:00:09.854876   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:09.855513   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/models.rb as src/models.rb
I0620 01:00:09.856187   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/run.sh as src/run.sh
I0620 01:00:09.856724   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/test as src/test
I0620 01:00:09.857242   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/test/sample_test.rb as src/test/sample_test.rb
I0620 01:00:09.857758   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/views as src/views
I0620 01:00:09.858257   14975 tar.go:337] Adding to tar: /tmp/s2i109605063/upload/src/views/main.erb as src/views/main.erb
I0620 01:00:09.906320   14975 docker.go:1044] Waiting for container "35e97d6979938b448417c1d68eb1047f3523c56f4c336644eb474f4fc1989320" to stop ...
I0620 01:00:09.999977   14975 docker.go:1069] Invoking PostExecute function
I0620 01:00:10.000051   14975 postexecutorstep.go:68] Skipping step: store previous image
I0620 01:00:10.000085   14975 postexecutorstep.go:117] Executing step: commit image
I0620 01:00:10.003692   14975 postexecutorstep.go:523] Checking for new Labels to apply... 
I0620 01:00:10.003768   14975 postexecutorstep.go:531] Creating the download path '/tmp/s2i109605063/metadata'
I0620 01:00:10.004069   14975 postexecutorstep.go:465] Downloading file "/tmp/.s2i/image_metadata.json"
I0620 01:00:10.045776   14975 tar.go:401] Creating directory /tmp/s2i109605063/metadata
I0620 01:00:10.045868   14975 tar.go:469] Creating /tmp/s2i109605063/metadata/image_metadata.json
I0620 01:00:10.046099   14975 tar.go:479] Extracting/writing /tmp/s2i109605063/metadata/image_metadata.json
I0620 01:00:10.046286   14975 tar.go:425] Done extracting tar stream
I0620 01:00:10.046434   14975 postexecutorstep.go:556] new Labels File contents : 
{
  "labels": [
    {"testLabel": "testLabel_value"}
  ]
}

I0620 01:00:10.053290   14975 docker.go:1103] Committing container with dockerOpts: {Reference:test/sti-incremental-app-scripts-no-save-artifacts Comment: Author: Changes:[] Pause:false Config:0xc00034c3c0}, config: {Hostname: Domainname: User: AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production] Cmd:[/tmp/scripts//run] Healthcheck:<nil> ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[io.k8s.display-name:test/sti-incremental-app-scripts-no-save-artifacts io.openshift.s2i.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.s2i.build.commit.date:Thu Jan 17 17:21:03 2019 -0500 io.openshift.s2i.build.commit.id:787f1beae9956c959c6af62ee43bfdda73769cf7 io.openshift.s2i.build.commit.message:Merge pull request #78 from bparees/v22 io.openshift.s2i.build.commit.ref:master io.openshift.s2i.build.image:sti_test/sti-fake-scripts-no-save-artifacts io.openshift.s2i.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.s2i.scripts-url:image:///tmp/scripts/ testLabel:testLabel_value] StopSignal: StopTimeout:<nil> Shell:[]}
I0620 01:00:10.110746   14975 postexecutorstep.go:393] Executing step: report success
I0620 01:00:10.110799   14975 postexecutorstep.go:398] Successfully built test/sti-incremental-app-scripts-no-save-artifacts
I0620 01:00:10.110822   14975 postexecutorstep.go:93] Skipping step: remove previous image
I0620 01:00:10.110895   14975 docker.go:980] Removing container "35e97d6979938b448417c1d68eb1047f3523c56f4c336644eb474f4fc1989320" ...
I0620 01:00:10.125303   14975 docker.go:990] Removed container "35e97d6979938b448417c1d68eb1047f3523c56f4c336644eb474f4fc1989320"
I0620 01:00:10.125558   14975 cleanup.go:33] Removing temporary directory /tmp/s2i109605063
I0620 01:00:10.125646   14975 fs.go:314] Removing directory '/tmp/s2i109605063'
I0620 01:00:10.132891   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts-no-save-artifacts:latest"
I0620 01:00:10.141749   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts-no-save-artifacts:latest"
I0620 01:00:10.141806   14975 docker.go:730] Image sha256:5b9a6ddf6ec20b690e6128666e327bca2f016c967779f9f453504210b3911f69 contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:10.141858   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:10.142021   14975 sti.go:204] Preparing to build test/sti-incremental-app-scripts-no-save-artifacts
I0620 01:00:10.142548   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 01:00:10.142601   14975 clone.go:40] Cloning sources into "/tmp/s2i147538065/upload/src"
I0620 01:00:10.889595   14975 clone.go:56] Checked out "HEAD"
I0620 01:00:10.921475   14975 clone.go:62] Updated submodules for "HEAD"
I0620 01:00:10.936085   14975 install.go:261] Using "assemble" installed from "image:///tmp/scripts//assemble"
I0620 01:00:10.936216   14975 install.go:261] Using "run" installed from "image:///tmp/scripts//run"
I0620 01:00:10.936348   14975 install.go:261] Using "save-artifacts" installed from "image:///tmp/scripts//save-artifacts"
I0620 01:00:10.936422   14975 ignore.go:66] .s2iignore file does not exist
I0620 01:00:10.940163   14975 docker.go:490] Using locally available image "test/sti-incremental-app-scripts-no-save-artifacts:latest"
I0620 01:00:10.943786   14975 sti.go:211] Existing image for tag test/sti-incremental-app-scripts-no-save-artifacts detected for incremental build
I0620 01:00:10.943839   14975 sti.go:216] Performing source build from https://github.com/openshift/ruby-hello-world
I0620 01:00:10.944056   14975 sti.go:522] Saving build artifacts from image test/sti-incremental-app-scripts-no-save-artifacts to path /tmp/s2i147538065/upload/artifacts
I0620 01:00:10.947329   14975 sti.go:546] The assemble user is not set, defaulting to "" user
I0620 01:00:10.954166   14975 docker.go:730] Image sha256:5408bef7dd21cd3a69900463fcb5e71b67ec59312f179716100299a5cea57b83 contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:10.954224   14975 docker.go:804] Base directory for S2I scripts is '/tmp/scripts/'. Untarring destination is '/tmp'.
I0620 01:00:10.954288   14975 docker.go:961] Setting "/tmp/scripts/save-artifacts" command for container ...
I0620 01:00:10.954601   14975 docker.go:970] Creating container with options {Name:"s2i_test_sti_incremental_app_scripts_no_save_artifacts_9e53dcd6" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[] Cmd:[/tmp/scripts/save-artifacts] Healthcheck:<nil> ArgsEscaped:false Image:test/sti-incremental-app-scripts-no-save-artifacts:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:10.987507   14975 docker.go:1002] Attaching to container "67b30fa47e53453c817e182e2c51583f38532d3f9d77910897e26b02c0c23521" ...
I0620 01:00:10.989261   14975 docker.go:1013] Starting container "67b30fa47e53453c817e182e2c51583f38532d3f9d77910897e26b02c0c23521" ...
I0620 01:00:11.374919   14975 docker.go:980] Removing container "67b30fa47e53453c817e182e2c51583f38532d3f9d77910897e26b02c0c23521" ...
I0620 01:00:11.388256   14975 docker.go:990] Removed container "67b30fa47e53453c817e182e2c51583f38532d3f9d77910897e26b02c0c23521"
W0620 01:00:11.388635   14975 sti.go:219] Clean build will be performed because of error saving previous build artifacts
I0620 01:00:11.388693   14975 sti.go:220] error: Error response from daemon: oci runtime error: container_linux.go:247: starting container process caused "exec: \"/tmp/scripts/save-artifacts\": stat /tmp/scripts/save-artifacts: no such file or directory"
I0620 01:00:11.388816   14975 sti.go:227] Running "assemble" in "test/sti-incremental-app-scripts-no-save-artifacts"
I0620 01:00:11.388883   14975 sti.go:585] Using image name sti_test/sti-fake-scripts-no-save-artifacts
I0620 01:00:11.392466   14975 docker.go:490] Using locally available image "sti_test/sti-fake-scripts-no-save-artifacts:latest"
I0620 01:00:11.392603   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 01:00:11.393113   14975 sti.go:703] starting the source uploading ...
I0620 01:00:11.393171   14975 tar.go:239] Adding "/tmp/s2i147538065/upload" to tar ...
I0620 01:00:11.393578   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/artifacts as artifacts
I0620 01:00:11.399215   14975 docker.go:730] Image sha256:5b9a6ddf6ec20b690e6128666e327bca2f016c967779f9f453504210b3911f69 contains io.openshift.s2i.scripts-url set to "image:///tmp/scripts/"
I0620 01:00:11.399277   14975 docker.go:804] Base directory for S2I scripts is '/tmp/scripts/'. Untarring destination is '/tmp'.
I0620 01:00:11.399365   14975 docker.go:961] Setting "/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble" command for container ...
I0620 01:00:11.399717   14975 docker.go:970] Creating container with options {Name:"s2i_sti_test_sti_fake_scripts_no_save_artifacts_227ca6af" Config:{Hostname: Domainname: User: AttachStdin:false AttachStdout:true AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:true StdinOnce:true Env:[RACK_ENV=production] Cmd:[/bin/sh -c tar -C /tmp -xf - && /tmp/scripts/assemble] Healthcheck:<nil> ArgsEscaped:false Image:sti_test/sti-fake-scripts-no-save-artifacts:latest Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[] StopSignal: StopTimeout:<nil> Shell:[]} HostConfig:&{Binds:[] ContainerIDFile: LogConfig:{Type: Config:map[]} NetworkMode: PortBindings:map[] RestartPolicy:{Name: MaximumRetryCount:0} AutoRemove:false VolumeDriver: VolumesFrom:[] CapAdd:[] CapDrop:[] DNS:[] DNSOptions:[] DNSSearch:[] ExtraHosts:[] GroupAdd:[] IpcMode: Cgroup: Links:[] OomScoreAdj:0 PidMode: Privileged:false PublishAllPorts:false ReadonlyRootfs:false SecurityOpt:[] StorageOpt:map[] Tmpfs:map[] UTSMode: UsernsMode: ShmSize:67108864 Sysctls:map[] Runtime: ConsoleSize:[0 0] Isolation: Resources:{CPUShares:0 Memory:0 NanoCPUs:0 CgroupParent: BlkioWeight:0 BlkioWeightDevice:[] BlkioDeviceReadBps:[] BlkioDeviceWriteBps:[] BlkioDeviceReadIOps:[] BlkioDeviceWriteIOps:[] CPUPeriod:0 CPUQuota:0 CPURealtimePeriod:0 CPURealtimeRuntime:0 CpusetCpus: CpusetMems: Devices:[] DeviceCgroupRules:[] DiskQuota:0 KernelMemory:0 MemoryReservation:0 MemorySwap:0 MemorySwappiness:<nil> OomKillDisable:<nil> PidsLimit:0 Ulimits:[] CPUCount:0 CPUPercent:0 IOMaximumIOps:0 IOMaximumBandwidth:0} Mounts:[] Init:<nil>}} ...
I0620 01:00:11.430449   14975 docker.go:1002] Attaching to container "32a5aae7be7a8153490cfd4d6f4d552cdc18c9620981167f3acfcdd4fbf54401" ...
I0620 01:00:11.432243   14975 docker.go:1013] Starting container "32a5aae7be7a8153490cfd4d6f4d552cdc18c9620981167f3acfcdd4fbf54401" ...
I0620 01:00:11.699018   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/scripts as scripts
I0620 01:00:11.699524   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src as src
I0620 01:00:11.704904   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/.gitignore as src/.gitignore
I0620 01:00:11.705542   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/.s2i as src/.s2i
I0620 01:00:11.706045   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/.s2i/bin as src/.s2i/bin
I0620 01:00:11.706481   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/.s2i/bin/README as src/.s2i/bin/README
I0620 01:00:11.706972   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/.s2i/environment as src/.s2i/environment
I0620 01:00:11.707446   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/.travis.yml as src/.travis.yml
I0620 01:00:11.707881   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/Dockerfile as src/Dockerfile
I0620 01:00:11.708371   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/Gemfile as src/Gemfile
I0620 01:00:11.708841   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/Gemfile.lock as src/Gemfile.lock
I0620 01:00:11.709465   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/README.md as src/README.md
I0620 01:00:11.710066   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/Rakefile as src/Rakefile
I0620 01:00:11.710527   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/app.rb as src/app.rb
I0620 01:00:11.711075   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/config as src/config
I0620 01:00:11.711714   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/config/database.rb as src/config/database.rb
I0620 01:00:11.712407   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/config/database.yml as src/config/database.yml
I0620 01:00:11.713052   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/config.ru as src/config.ru
I0620 01:00:11.713758   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/db as src/db
I0620 01:00:11.714436   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/db/migrate as src/db/migrate
I0620 01:00:11.715136   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/db/migrate/20141102191902_create_key_pair.rb as src/db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:11.715678   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/models.rb as src/models.rb
I0620 01:00:11.716326   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/run.sh as src/run.sh
I0620 01:00:11.716952   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/test as src/test
I0620 01:00:11.717539   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/test/sample_test.rb as src/test/sample_test.rb
I0620 01:00:11.718143   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/views as src/views
I0620 01:00:11.718526   14975 tar.go:337] Adding to tar: /tmp/s2i147538065/upload/src/views/main.erb as src/views/main.erb
I0620 01:00:11.767903   14975 docker.go:1044] Waiting for container "32a5aae7be7a8153490cfd4d6f4d552cdc18c9620981167f3acfcdd4fbf54401" to stop ...
I0620 01:00:11.867613   14975 docker.go:1069] Invoking PostExecute function
I0620 01:00:11.867684   14975 postexecutorstep.go:63] Executing step: store previous image
I0620 01:00:11.872131   14975 postexecutorstep.go:117] Executing step: commit image
I0620 01:00:11.875673   14975 postexecutorstep.go:523] Checking for new Labels to apply... 
I0620 01:00:11.875742   14975 postexecutorstep.go:531] Creating the download path '/tmp/s2i147538065/metadata'
I0620 01:00:11.876041   14975 postexecutorstep.go:465] Downloading file "/tmp/.s2i/image_metadata.json"
I0620 01:00:11.915701   14975 tar.go:401] Creating directory /tmp/s2i147538065/metadata
I0620 01:00:11.915792   14975 tar.go:469] Creating /tmp/s2i147538065/metadata/image_metadata.json
I0620 01:00:11.915980   14975 tar.go:479] Extracting/writing /tmp/s2i147538065/metadata/image_metadata.json
I0620 01:00:11.916174   14975 tar.go:425] Done extracting tar stream
I0620 01:00:11.916329   14975 postexecutorstep.go:556] new Labels File contents : 
{
  "labels": [
    {"testLabel": "testLabel_value"}
  ]
}

I0620 01:00:11.922287   14975 docker.go:1103] Committing container with dockerOpts: {Reference:test/sti-incremental-app-scripts-no-save-artifacts Comment: Author: Changes:[] Pause:false Config:0xc000514500}, config: {Hostname: Domainname: User: AttachStdin:false AttachStdout:false AttachStderr:false ExposedPorts:map[] Tty:false OpenStdin:false StdinOnce:false Env:[RACK_ENV=production] Cmd:[/tmp/scripts//run] Healthcheck:<nil> ArgsEscaped:false Image: Volumes:map[] WorkingDir: Entrypoint:[] NetworkDisabled:false MacAddress: OnBuild:[] Labels:map[io.k8s.display-name:test/sti-incremental-app-scripts-no-save-artifacts io.openshift.s2i.build.commit.author:Ben Parees <bparees@users.noreply.github.com> io.openshift.s2i.build.commit.date:Thu Jan 17 17:21:03 2019 -0500 io.openshift.s2i.build.commit.id:787f1beae9956c959c6af62ee43bfdda73769cf7 io.openshift.s2i.build.commit.message:Merge pull request #78 from bparees/v22 io.openshift.s2i.build.commit.ref:master io.openshift.s2i.build.image:sti_test/sti-fake-scripts-no-save-artifacts io.openshift.s2i.build.source-location:https://github.com/openshift/ruby-hello-world io.openshift.s2i.scripts-url:image:///tmp/scripts/ testLabel:testLabel_value] StopSignal: StopTimeout:<nil> Shell:[]}
I0620 01:00:11.984467   14975 postexecutorstep.go:393] Executing step: report success
I0620 01:00:11.984524   14975 postexecutorstep.go:398] Successfully built test/sti-incremental-app-scripts-no-save-artifacts
I0620 01:00:11.984555   14975 postexecutorstep.go:88] Executing step: remove previous image
I0620 01:00:11.984593   14975 postexecutorstep.go:102] Removing previously-tagged image sha256:5408bef7dd21cd3a69900463fcb5e71b67ec59312f179716100299a5cea57b83
I0620 01:00:11.989294   14975 docker.go:980] Removing container "32a5aae7be7a8153490cfd4d6f4d552cdc18c9620981167f3acfcdd4fbf54401" ...
I0620 01:00:12.002801   14975 docker.go:990] Removed container "32a5aae7be7a8153490cfd4d6f4d552cdc18c9620981167f3acfcdd4fbf54401"
I0620 01:00:12.003188   14975 cleanup.go:33] Removing temporary directory /tmp/s2i147538065
I0620 01:00:12.003231   14975 fs.go:314] Removing directory '/tmp/s2i147538065'
--- PASS: TestIncrementalBuildScriptsNoSaveArtifacts (4.22s)
=== RUN   TestIncrementalBuildOnBuild
I0620 01:00:12.586861   14975 docker.go:490] Using locally available image "sti_test/sti-fake-onbuild:latest"
I0620 01:00:12.596349   14975 docker.go:490] Using locally available image "sti_test/sti-fake-onbuild:latest"
I0620 01:00:12.596406   14975 docker.go:730] Image sha256:7b5625c38357db901f87d18e9ca08afbb11bd58f764e663d9b09e827a4a48bbd contains io.openshift.s2i.scripts-url set to "http://127.0.0.1:23456/.s2i/bin"
I0620 01:00:12.596453   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:12.596549   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:12.596583   14975 onbuild.go:87] Preparing the source code for build
I0620 01:00:12.597102   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 01:00:12.597156   14975 clone.go:40] Cloning sources into "/tmp/s2i599983339/upload/src"
I0620 01:00:12.913160   14975 clone.go:56] Checked out "HEAD"
I0620 01:00:12.945252   14975 clone.go:62] Updated submodules for "HEAD"
I0620 01:00:12.961556   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/assemble'
I0620 01:00:12.961718   14975 install.go:261] Using "assemble" installed from "http://127.0.0.1:23456/.s2i/bin/assemble"
I0620 01:00:12.963471   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/run'
I0620 01:00:12.963581   14975 install.go:261] Using "run" installed from "http://127.0.0.1:23456/.s2i/bin/run"
I0620 01:00:12.963690   14975 ignore.go:66] .s2iignore file does not exist
I0620 01:00:12.963765   14975 onbuild.go:181] Found S2I 'run' script, copying to application source dir
I0620 01:00:12.964006   14975 fs.go:270] F "/tmp/s2i599983339/upload/scripts/run" -> "/tmp/s2i599983339/upload/src/run"
I0620 01:00:12.964178   14975 onbuild.go:185] Found S2I 'assemble' script, copying to application source dir
I0620 01:00:12.964417   14975 fs.go:270] F "/tmp/s2i599983339/upload/scripts/assemble" -> "/tmp/s2i599983339/upload/src/assemble"
I0620 01:00:12.964555   14975 onbuild.go:97] Creating application Dockerfile
I0620 01:00:12.965171   14975 entrypoint.go:33] Found valid ENTRYPOINT: run
I0620 01:00:12.965308   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 01:00:12.965504   14975 onbuild.go:106] Creating application source code image
I0620 01:00:12.965745   14975 onbuild.go:120] Building the application source
I0620 01:00:12.965898   14975 tar.go:239] Adding "/tmp/s2i599983339/upload/src" to tar ...
I0620 01:00:12.965923   14975 docker.go:1135] Building container using config: {Tags:[test/sti-incremental-app-onbuild] SuppressOutput:false RemoteContext: NoCache:true Remove:true ForceRemove:true PullParent:false Isolation: CPUSetCPUs: CPUSetMems: CPUShares:0 CPUQuota:0 CPUPeriod:0 Memory:0 MemorySwap:0 CgroupParent: NetworkMode: ShmSize:0 Dockerfile: Ulimits:[] BuildArgs:map[] AuthConfigs:map[] Context:<nil> Labels:map[] Squash:false CacheFrom:[] SecurityOpt:[] ExtraHosts:[] Target: SessionID:}
I0620 01:00:12.972096   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/.gitignore as .gitignore
I0620 01:00:12.972840   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/.s2i as .s2i
I0620 01:00:12.973521   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/.s2i/bin as .s2i/bin
I0620 01:00:12.974205   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/.s2i/bin/README as .s2i/bin/README
I0620 01:00:12.974958   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/.s2i/environment as .s2i/environment
I0620 01:00:12.975708   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/.travis.yml as .travis.yml
I0620 01:00:12.976460   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/Dockerfile as Dockerfile
I0620 01:00:12.977183   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/Gemfile as Gemfile
I0620 01:00:12.977875   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/Gemfile.lock as Gemfile.lock
I0620 01:00:12.978595   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/README.md as README.md
I0620 01:00:12.979260   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/Rakefile as Rakefile
I0620 01:00:12.979905   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/app.rb as app.rb
I0620 01:00:12.981359   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/assemble as assemble
I0620 01:00:12.982189   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/config as config
I0620 01:00:12.982861   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/config/database.rb as config/database.rb
I0620 01:00:12.983662   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/config/database.yml as config/database.yml
I0620 01:00:12.984317   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/config.ru as config.ru
I0620 01:00:12.984908   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/db as db
I0620 01:00:12.985465   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/db/migrate as db/migrate
I0620 01:00:12.985972   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/db/migrate/20141102191902_create_key_pair.rb as db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:12.986484   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/models.rb as models.rb
I0620 01:00:12.987043   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/run as run
I0620 01:00:12.987581   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/run.sh as run.sh
I0620 01:00:12.988136   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/test as test
I0620 01:00:12.988739   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/test/sample_test.rb as test/sample_test.rb
I0620 01:00:12.989425   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/views as views
I0620 01:00:12.989997   14975 tar.go:337] Adding to tar: /tmp/s2i599983339/upload/src/views/main.erb as views/main.erb
{"stream":"Step 1/4 : FROM sti_test/sti-fake-onbuild\n"}
{"stream":"\u001b[91m# Executing 2 build triggers...\n\u001b[0m"}
{"stream":"Step 1/1 : RUN touch /sti-fake/src/onbuild\n"}
{"stream":" ---\u003e Running in 3ee735febcb4\n"}
{}
{"stream":"\u001b[91m\u001b[0m"}
{"stream":"Step 1/1 : COPY . /tmp/src/\n"}
{"stream":" ---\u003e ba20eaff75cf\n"}
{"stream":"Removing intermediate container aeccbb8fd4a6\n"}
{"stream":"Removing intermediate container 3ee735febcb4\n"}
{"stream":"Step 2/4 : ENV RACK_ENV \"production\"\n"}
{"stream":" ---\u003e Running in c6b5890710fe\n"}
{"stream":" ---\u003e 4d3a1a37b433\n"}
{"stream":"Removing intermediate container c6b5890710fe\n"}
{"stream":"Step 3/4 : RUN sh assemble\n"}
{"stream":" ---\u003e Running in fa1caa8f2749\n"}
{}
{"stream":"\u001b[91m\u001b[0m"}
{"stream":" ---\u003e ca8a9815152e\n"}
{"stream":"Removing intermediate container fa1caa8f2749\n"}
{"stream":"Step 4/4 : ENTRYPOINT ./run\n"}
{"stream":" ---\u003e Running in 41e03c247a8e\n"}
{"stream":" ---\u003e ee767740e22b\n"}
{"stream":"Removing intermediate container 41e03c247a8e\n"}
{"stream":"Successfully built ee767740e22b\n"}
I0620 01:00:14.250200   14975 onbuild.go:129] Cleaning up temporary containers
I0620 01:00:14.250262   14975 cleanup.go:33] Removing temporary directory /tmp/s2i599983339
I0620 01:00:14.250305   14975 fs.go:314] Removing directory '/tmp/s2i599983339'
I0620 01:00:14.261610   14975 docker.go:490] Using locally available image "sti_test/sti-fake-onbuild:latest"
I0620 01:00:14.271004   14975 docker.go:490] Using locally available image "sti_test/sti-fake-onbuild:latest"
I0620 01:00:14.271076   14975 docker.go:730] Image sha256:7b5625c38357db901f87d18e9ca08afbb11bd58f764e663d9b09e827a4a48bbd contains io.openshift.s2i.scripts-url set to "http://127.0.0.1:23456/.s2i/bin"
I0620 01:00:14.271155   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:14.271280   14975 scm.go:20] DownloadForSource https://github.com/openshift/ruby-hello-world
I0620 01:00:14.271321   14975 onbuild.go:87] Preparing the source code for build
I0620 01:00:14.271845   14975 clone.go:36] Downloading "https://github.com/openshift/ruby-hello-world" ...
I0620 01:00:14.271896   14975 clone.go:40] Cloning sources into "/tmp/s2i384784718/upload/src"
I0620 01:00:14.522257   14975 clone.go:56] Checked out "HEAD"
I0620 01:00:14.554639   14975 clone.go:62] Updated submodules for "HEAD"
I0620 01:00:14.570724   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/assemble'
I0620 01:00:14.570836   14975 install.go:261] Using "assemble" installed from "http://127.0.0.1:23456/.s2i/bin/assemble"
I0620 01:00:14.572488   14975 download.go:77] Downloaded 'http://127.0.0.1:23456/.s2i/bin/run'
I0620 01:00:14.572595   14975 install.go:261] Using "run" installed from "http://127.0.0.1:23456/.s2i/bin/run"
I0620 01:00:14.572658   14975 ignore.go:66] .s2iignore file does not exist
I0620 01:00:14.572719   14975 onbuild.go:181] Found S2I 'run' script, copying to application source dir
I0620 01:00:14.572898   14975 fs.go:270] F "/tmp/s2i384784718/upload/scripts/run" -> "/tmp/s2i384784718/upload/src/run"
I0620 01:00:14.573043   14975 onbuild.go:185] Found S2I 'assemble' script, copying to application source dir
I0620 01:00:14.573242   14975 fs.go:270] F "/tmp/s2i384784718/upload/scripts/assemble" -> "/tmp/s2i384784718/upload/src/assemble"
I0620 01:00:14.573355   14975 onbuild.go:97] Creating application Dockerfile
I0620 01:00:14.573818   14975 entrypoint.go:33] Found valid ENTRYPOINT: run
I0620 01:00:14.573920   14975 environment.go:41] Setting 1 environment variables provided by environment file in sources
I0620 01:00:14.574120   14975 onbuild.go:106] Creating application source code image
I0620 01:00:14.574319   14975 onbuild.go:120] Building the application source
I0620 01:00:14.574441   14975 docker.go:1135] Building container using config: {Tags:[test/sti-incremental-app-onbuild] SuppressOutput:false RemoteContext: NoCache:true Remove:true ForceRemove:true PullParent:false Isolation: CPUSetCPUs: CPUSetMems: CPUShares:0 CPUQuota:0 CPUPeriod:0 Memory:0 MemorySwap:0 CgroupParent: NetworkMode: ShmSize:0 Dockerfile: Ulimits:[] BuildArgs:map[] AuthConfigs:map[] Context:<nil> Labels:map[] Squash:false CacheFrom:[] SecurityOpt:[] ExtraHosts:[] Target: SessionID:}
I0620 01:00:14.574509   14975 tar.go:239] Adding "/tmp/s2i384784718/upload/src" to tar ...
I0620 01:00:14.579599   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/.gitignore as .gitignore
I0620 01:00:14.580281   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/.s2i as .s2i
I0620 01:00:14.580823   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/.s2i/bin as .s2i/bin
I0620 01:00:14.581353   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/.s2i/bin/README as .s2i/bin/README
I0620 01:00:14.581982   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/.s2i/environment as .s2i/environment
I0620 01:00:14.582629   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/.travis.yml as .travis.yml
I0620 01:00:14.583264   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/Dockerfile as Dockerfile
I0620 01:00:14.583883   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/Gemfile as Gemfile
I0620 01:00:14.584548   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/Gemfile.lock as Gemfile.lock
I0620 01:00:14.585216   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/README.md as README.md
I0620 01:00:14.585899   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/Rakefile as Rakefile
I0620 01:00:14.586568   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/app.rb as app.rb
I0620 01:00:14.587261   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/assemble as assemble
I0620 01:00:14.588890   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/config as config
I0620 01:00:14.589623   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/config/database.rb as config/database.rb
I0620 01:00:14.590415   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/config/database.yml as config/database.yml
I0620 01:00:14.591619   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/config.ru as config.ru
I0620 01:00:14.592358   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/db as db
I0620 01:00:14.593029   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/db/migrate as db/migrate
I0620 01:00:14.593708   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/db/migrate/20141102191902_create_key_pair.rb as db/migrate/20141102191902_create_key_pair.rb
I0620 01:00:14.594355   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/models.rb as models.rb
I0620 01:00:14.594899   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/run as run
I0620 01:00:14.595542   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/run.sh as run.sh
I0620 01:00:14.596185   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/test as test
I0620 01:00:14.596677   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/test/sample_test.rb as test/sample_test.rb
I0620 01:00:14.597326   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/views as views
I0620 01:00:14.597886   14975 tar.go:337] Adding to tar: /tmp/s2i384784718/upload/src/views/main.erb as views/main.erb
{"stream":"Step 1/4 : FROM sti_test/sti-fake-onbuild\n"}
{"stream":"\u001b[91m# Executing 2 build triggers...\n\u001b[0m"}
{"stream":"Step 1/1 : RUN touch /sti-fake/src/onbuild\n"}
{"stream":" ---\u003e Running in d011c06e4f8f\n"}
{}
{"stream":"\u001b[91m\u001b[0m"}
{"stream":"Step 1/1 : COPY . /tmp/src/\n"}
{"stream":" ---\u003e af425eddc538\n"}
{"stream":"Removing intermediate container d011c06e4f8f\n"}
{"stream":"Removing intermediate container 5853cc9d7812\n"}
{"stream":"Step 2/4 : ENV RACK_ENV \"production\"\n"}
{"stream":" ---\u003e Running in 9d75f9f2ceb7\n"}
{"stream":" ---\u003e c5757e99f0ad\n"}
{"stream":"Removing intermediate container 9d75f9f2ceb7\n"}
{"stream":"Step 3/4 : RUN sh assemble\n"}
{"stream":" ---\u003e Running in 7db23458d1a6\n"}
{"stream":"\u001b[91m\u001b[0m"}
{}
{"stream":" ---\u003e 7b1dfc082447\n"}
{"stream":"Removing intermediate container 7db23458d1a6\n"}
{"stream":"Step 4/4 : ENTRYPOINT ./run\n"}
{"stream":" ---\u003e Running in 6714fe78685f\n"}
{"stream":" ---\u003e c550f22ca386\n"}
{"stream":"Removing intermediate container 6714fe78685f\n"}
{"stream":"Successfully built c550f22ca386\n"}
I0620 01:00:15.874785   14975 onbuild.go:129] Cleaning up temporary containers
I0620 01:00:15.874870   14975 cleanup.go:33] Removing temporary directory /tmp/s2i384784718
I0620 01:00:15.874951   14975 fs.go:314] Removing directory '/tmp/s2i384784718'
--- PASS: TestIncrementalBuildOnBuild (3.90s)
PASS
coverage: 0.0% of statements
ok  	github.com/openshift/source-to-image/test/integration/docker	50.987s	coverage: 0.0% of statements

Complete
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: EXECUTE DOCKER INTEGRATION TESTS [00h 01m 01s] ##########
[workspace] $ /bin/bash /tmp/jenkins6627155463669187272.sh
########## STARTING STAGE: EXECUTE S2I RUNIMAGE TESTS ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ mktemp
+ script=/tmp/tmp.DW6L5wkGih
+ cat
+ chmod +x /tmp/tmp.DW6L5wkGih
+ scp -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.DW6L5wkGih openshiftdevel:/tmp/tmp.DW6L5wkGih
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 7200 /tmp/tmp.DW6L5wkGih"'
+ cd /data/src/github.com/openshift/source-to-image
+ hack/test-stirunimage.sh
working dir:  /tmp/sti/test-work.TVKS
s2i working dir:  /tmp/sti/test-work.TVKS
/tmp/sti/test-work.TVKS /data/src/github.com/openshift/source-to-image

cloning source into working dir


TEST PASSED


s2i build with relative path without file://


TEST PASSED


s2i build with volume options


TEST PASSED

/data/src/github.com/openshift/source-to-image

s2i build with absolute path with file://


TEST PASSED


s2i build with absolute path without file://


TEST PASSED


s2i build with non-git repo file location


TEST PASSED

I0620 01:01:35.933758   22714 download.go:38] Copying sources from "/tmp/sti/test-work.TVKS/cakephp-ex" to "/tmp/s2i660964835/upload/src"

TEST PASSED


s2i rebuild


TEST PASSED


TEST PASSED


s2i usage


TEST PASSED

I0620 01:02:30.031161   24095 sti.go:711] Sample invocation:

TEST PASSED


s2i build with overriding assemble/run scripts

Running custom assemble

TEST PASSED

Running custom run

TEST PASSED


s2i build with add-host option

Gem::RemoteFetcher::FetchError: Errno::ECONNREFUSED: Failed to open TCP connection to rubygems.org:443 (Connection refused - connect(2) for "rubygems.org" port 443) (https://rubygems.org/gems/rake-12.3.0.gem)

TEST PASSED


s2i build with remote git repo


TEST PASSED


s2i build with runtime image


TEST PASSED


s2i build with Dockerfile output


TEST PASSED


s2i build with --run==true option

Waiting up to 15*TIME_MIN for the build to finish ...
The image test-jee-app:latest has been started in container 5cf05eb2865ab26d1f2d0b8b9995d276a190b2ced3bcb80e3bd2418e4ea24f29 as a result of the --run=true option.  The container's stdout/stderr will be redirected to this command's glog output to help you validate its behavior.  You can also inspect the container with docker commands if you like.  If the container is set up to stay running, you will have to Ctrl-C to exit this command, which should also stop the container 5cf05eb2865ab26d1f2d0b8b9995d276a190b2ced3bcb80e3bd2418e4ea24f29.  This particular invocation attempts to run with the port mappings 
[INFO] Success running command s2i --run=true
[INFO] Success terminating associated docker container
Cleaning up working dir /tmp/sti/test-work.TVKS

Complete
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: EXECUTE S2I RUNIMAGE TESTS [00h 04m 12s] ##########
[PostBuildScript] - Executing post build scripts.
[workspace] $ /bin/bash /tmp/jenkins5064675407411121142.sh
########## STARTING STAGE: DOWNLOAD ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/gathered
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/gathered
+ mkdir -p /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/gathered
+ tree /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/gathered
/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/gathered

0 directories, 0 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins3613873994996113034.sh
########## STARTING STAGE: GENERATE ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/generated
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/generated
+ mkdir /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/generated
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo docker version && sudo docker info && sudo docker images && sudo docker ps -a 2>&1'
  WARNING: You're not using the default seccomp profile
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo cat /etc/sysconfig/docker /etc/sysconfig/docker-network /etc/sysconfig/docker-storage /etc/sysconfig/docker-storage-setup /etc/systemd/system/docker.service 2>&1'
+ true
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo find /var/lib/docker/containers -name *.log | sudo xargs tail -vn +1 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo ausearch -m AVC -m SELINUX_ERR -m USER_AVC 2>&1'
+ true
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo df -T -h && sudo pvs && sudo vgs && sudo lvs && sudo findmnt --all 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo yum list installed 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl --dmesg --no-pager --all --lines=all 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl _PID=1 --no-pager --all --lines=all 2>&1'
+ tree /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/generated
/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/generated
├── avc_denials.log
├── containers.log
├── dmesg.log
├── docker.config
├── docker.info
├── filesystem.info
├── installed_packages.log
└── pid1.journal

0 directories, 8 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins5307070952648666332.sh
########## STARTING STAGE: FETCH SYSTEMD JOURNALS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/journals
+ rm -rf /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/journals
+ mkdir /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/journals
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit docker.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit dnsmasq.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit systemd-journald.service --no-pager --all --lines=all
+ tree /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/journals
/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/artifacts/journals
├── dnsmasq.service
├── docker.service
└── systemd-journald.service

0 directories, 3 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins6211393318620148500.sh
########## STARTING STAGE: ASSEMBLE GCS OUTPUT ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
+ trap 'exit 0' EXIT
+ mkdir -p gcs/artifacts gcs/artifacts/generated gcs/artifacts/journals gcs/artifacts/gathered
++ python -c 'import json; import urllib; print json.load(urllib.urlopen('\''https://ci.openshift.redhat.com/jenkins/job/test_pull_request_source_to_image_master_test/10/api/json'\''))['\''result'\'']'
+ result=SUCCESS
+ cat
++ date +%s
+ cat /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/builds/10/log
+ cp artifacts/generated/avc_denials.log artifacts/generated/containers.log artifacts/generated/dmesg.log artifacts/generated/docker.config artifacts/generated/docker.info artifacts/generated/filesystem.info artifacts/generated/installed_packages.log artifacts/generated/pid1.journal gcs/artifacts/generated/
+ cp artifacts/journals/dnsmasq.service artifacts/journals/docker.service artifacts/journals/systemd-journald.service gcs/artifacts/journals/
+ cp -r 'artifacts/gathered/*' gcs/artifacts/
cp: cannot stat ‘artifacts/gathered/*’: No such file or directory
++ export status=FAILURE
++ status=FAILURE
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins8068397834644806358.sh
########## STARTING STAGE: PUSH THE ARTIFACTS AND METADATA ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ mktemp
+ script=/tmp/tmp.GbZJbIV7Do
+ cat
+ chmod +x /tmp/tmp.GbZJbIV7Do
+ scp -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.GbZJbIV7Do openshiftdevel:/tmp/tmp.GbZJbIV7Do
+ ssh -F /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 300 /tmp/tmp.GbZJbIV7Do"'
+ cd /home/origin
+ trap 'exit 0' EXIT
+ [[ -n {"type":"presubmit","job":"test_pull_requests_s2i","buildid":"1139613926267293696","prowjobid":"688d07bb-8ed9-11e9-8373-0a58ac100721","refs":{"org":"openshift","repo":"source-to-image","repo_link":"https://github.com/openshift/source-to-image","base_ref":"master","base_sha":"3dee73c8b712954df879fc5d40f6bb66b4bc8ff5","base_link":"https://github.com/openshift/source-to-image/commit/3dee73c8b712954df879fc5d40f6bb66b4bc8ff5","pulls":[{"number":977,"author":"adambkaplan","sha":"af5a9777864782ef40fc3fc19d75bcfef84d3264","link":"https://github.com/openshift/source-to-image/pull/977","commit_link":"https://github.com/openshift/source-to-image/pull/977/commits/af5a9777864782ef40fc3fc19d75bcfef84d3264","author_link":"https://github.com/adambkaplan"}]}} ]]
++ jq --compact-output '.buildid |= "10"'
+ JOB_SPEC='{"type":"presubmit","job":"test_pull_requests_s2i","buildid":"10","prowjobid":"688d07bb-8ed9-11e9-8373-0a58ac100721","refs":{"org":"openshift","repo":"source-to-image","repo_link":"https://github.com/openshift/source-to-image","base_ref":"master","base_sha":"3dee73c8b712954df879fc5d40f6bb66b4bc8ff5","base_link":"https://github.com/openshift/source-to-image/commit/3dee73c8b712954df879fc5d40f6bb66b4bc8ff5","pulls":[{"number":977,"author":"adambkaplan","sha":"af5a9777864782ef40fc3fc19d75bcfef84d3264","link":"https://github.com/openshift/source-to-image/pull/977","commit_link":"https://github.com/openshift/source-to-image/pull/977/commits/af5a9777864782ef40fc3fc19d75bcfef84d3264","author_link":"https://github.com/adambkaplan"}]}}'
+ docker run -e 'JOB_SPEC={"type":"presubmit","job":"test_pull_requests_s2i","buildid":"10","prowjobid":"688d07bb-8ed9-11e9-8373-0a58ac100721","refs":{"org":"openshift","repo":"source-to-image","repo_link":"https://github.com/openshift/source-to-image","base_ref":"master","base_sha":"3dee73c8b712954df879fc5d40f6bb66b4bc8ff5","base_link":"https://github.com/openshift/source-to-image/commit/3dee73c8b712954df879fc5d40f6bb66b4bc8ff5","pulls":[{"number":977,"author":"adambkaplan","sha":"af5a9777864782ef40fc3fc19d75bcfef84d3264","link":"https://github.com/openshift/source-to-image/pull/977","commit_link":"https://github.com/openshift/source-to-image/pull/977/commits/af5a9777864782ef40fc3fc19d75bcfef84d3264","author_link":"https://github.com/adambkaplan"}]}}' -v /data:/data:z registry.svc.ci.openshift.org/ci/gcsupload:latest --dry-run=false --gcs-path=gs://origin-ci-test --gcs-credentials-file=/data/credentials.json --path-strategy=single --default-org=openshift --default-repo=origin '/data/gcs/*'
Unable to find image 'registry.svc.ci.openshift.org/ci/gcsupload:latest' locally
Trying to pull repository registry.svc.ci.openshift.org/ci/gcsupload ... 
latest: Pulling from registry.svc.ci.openshift.org/ci/gcsupload
a073c86ecf9e: Already exists
cc3fc741b1a9: Already exists
e467430f86b1: Pulling fs layer
85cea451eec0: Pulling fs layer
85cea451eec0: Verifying Checksum
85cea451eec0: Download complete
e467430f86b1: Verifying Checksum
e467430f86b1: Download complete
e467430f86b1: Pull complete
85cea451eec0: Pull complete
Digest: sha256:b2104700d4aedf89e1c7b9bbdd65c49a46de31c77f314b0c894f412c7996a4ce
Status: Downloaded newer image for registry.svc.ci.openshift.org/ci/gcsupload:latest
{"component":"gcsupload","file":"prow/gcsupload/run.go:94","func":"k8s.io/test-infra/prow/gcsupload.Options.assembleTargets","level":"warning","msg":"Encountered error in resolving items to upload for /data/gcs/*: stat /data/gcs/*: no such file or directory","time":"2019-06-20T01:04:58Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_requests_s2i/10.txt","file":"prow/pod-utils/gcs/upload.go:44","func":"k8s.io/test-infra/prow/pod-utils/gcs.Upload","level":"info","msg":"Queued for upload","time":"2019-06-20T01:04:58Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_requests_s2i/latest-build.txt","file":"prow/pod-utils/gcs/upload.go:44","func":"k8s.io/test-infra/prow/pod-utils/gcs.Upload","level":"info","msg":"Queued for upload","time":"2019-06-20T01:04:58Z"}
{"component":"gcsupload","dest":"pr-logs/pull/openshift_source-to-image/977/test_pull_requests_s2i/latest-build.txt","file":"prow/pod-utils/gcs/upload.go:44","func":"k8s.io/test-infra/prow/pod-utils/gcs.Upload","level":"info","msg":"Queued for upload","time":"2019-06-20T01:04:58Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_requests_s2i/10.txt","file":"prow/pod-utils/gcs/upload.go:50","func":"k8s.io/test-infra/prow/pod-utils/gcs.Upload.func1","level":"info","msg":"Finished upload","time":"2019-06-20T01:04:58Z"}
{"component":"gcsupload","dest":"pr-logs/directory/test_pull_requests_s2i/latest-build.txt","file":"prow/pod-utils/gcs/upload.go:50","func":"k8s.io/test-infra/prow/pod-utils/gcs.Upload.func1","level":"info","msg":"Finished upload","time":"2019-06-20T01:04:58Z"}
{"component":"gcsupload","dest":"pr-logs/pull/openshift_source-to-image/977/test_pull_requests_s2i/latest-build.txt","file":"prow/pod-utils/gcs/upload.go:50","func":"k8s.io/test-infra/prow/pod-utils/gcs.Upload.func1","level":"info","msg":"Finished upload","time":"2019-06-20T01:04:58Z"}
{"component":"gcsupload","file":"prow/gcsupload/run.go:65","func":"k8s.io/test-infra/prow/gcsupload.Options.Run","level":"info","msg":"Finished upload to GCS","time":"2019-06-20T01:04:58Z"}
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: PUSH THE ARTIFACTS AND METADATA [00h 00m 04s] ##########
[workspace] $ /bin/bash /tmp/jenkins7699374196304821895.sh
########## STARTING STAGE: DEPROVISION CLOUD RESOURCES ##########
+ [[ -s /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config
+ oct deprovision

PLAYBOOK: main.yml *************************************************************
4 plays in /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/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/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:9
skipping: [localhost] => (item=origin_ci_inventory_dir)  => {
    "changed": false, 
    "generated_timestamp": "2019-06-19 21:05:00.130579", 
    "item": "origin_ci_inventory_dir", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}
skipping: [localhost] => (item=origin_ci_aws_region)  => {
    "changed": false, 
    "generated_timestamp": "2019-06-19 21:05:00.134906", 
    "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/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:28
included: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/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/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:2
ok: [localhost] => {
    "changed": false, 
    "generated_timestamp": "2019-06-19 21:05:00.984939", 
    "msg": ""
}

TASK [rename EC2 instance for termination reaper] ******************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:8
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2019-06-19 21:05:01.679110", 
    "msg": "Tags {'Name': 'oct-terminate'} created for resource i-06d713cc83e5dc6fc."
}

TASK [tear down the EC2 instance] **********************************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:15
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2019-06-19 21:05:02.622002", 
    "instance_ids": [
        "i-06d713cc83e5dc6fc"
    ], 
    "instances": [
        {
            "ami_launch_index": "0", 
            "architecture": "x86_64", 
            "block_device_mapping": {
                "/dev/sda1": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-037e43ae2eaab6d98"
                }, 
                "/dev/sdb": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0df9bbeae7422a61d"
                }
            }, 
            "dns_name": "ec2-54-209-158-119.compute-1.amazonaws.com", 
            "ebs_optimized": false, 
            "groups": {
                "sg-7e73221a": "default"
            }, 
            "hypervisor": "xen", 
            "id": "i-06d713cc83e5dc6fc", 
            "image_id": "ami-0b77b87a37c3e662c", 
            "instance_type": "m4.xlarge", 
            "kernel": null, 
            "key_name": "libra", 
            "launch_time": "2019-06-20T00:55:38.000Z", 
            "placement": "us-east-1c", 
            "private_dns_name": "ip-172-18-21-126.ec2.internal", 
            "private_ip": "172.18.21.126", 
            "public_dns_name": "ec2-54-209-158-119.compute-1.amazonaws.com", 
            "public_ip": "54.209.158.119", 
            "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/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:22
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2019-06-19 21:05:02.876899", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory/host_vars/172.18.21.126.yml", 
    "state": "absent"
}

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

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

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

TASK [remove inventory configuration directory] ********************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:61
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2019-06-19 21:05:03.418974", 
    "path": "/var/lib/jenkins/jobs/test_pull_request_source_to_image_master_test/workspace/.config/origin-ci-tool/inventory", 
    "state": "absent"
}

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

+ set +o xtrace
########## FINISHED STAGE: SUCCESS: DEPROVISION CLOUD RESOURCES [00h 00m 05s] ##########
Archiving artifacts
Recording test results
[WS-CLEANUP] Deleting project workspace...
[WS-CLEANUP] Deferred wipeout is used...
[WS-CLEANUP] done
Finished: SUCCESS