Docker on Windows issues with /var/opt/ directory volume

Summary

Docker container of latest omnibus package of gitlab fails to properly preserve data onto a directory mounted on host.

Steps to reproduce

docker pull gitlab/gitlab-ce:latest
docker run --detach
    --name gitlab2
    --restart always
    --volume D:/gitlab/test:/var/opt/gitlab
    --volume D:/gitlab/testlog:/var/log/gitlab
    gitlab/gitlab-ce:latest

Observe ruby_block error.

[2018-04-16T17:57:45+00:00] FATAL: Stacktrace dumped to /opt/gitlab/embedded/cookbooks/cache/chef-stacktrace.out
[2018-04-16T17:57:45+00:00] FATAL: Please provide the contents of the stacktrace.out file if you file a bug report
[2018-04-16T17:57:45+00:00] FATAL: Mixlib::ShellOut::ShellCommandFailed: storage_directory[/var/opt/gitlab/.ssh] (gitlab::gitlab-shell line 38) had an error: Mixlib::ShellOut::ShellCommandFailed: ruby_block[directory resource: /var/opt/gitlab/.ssh] (/opt/gitlab/embedded/cookbooks/cache/cookbooks/package/resources/storage_directory.rb line 33) had an error: Mixlib::ShellOut::ShellCommandFailed: Failed asserting that ownership of "/var/opt/gitlab/.ssh" was git:git

As noted by some user, change the volume mount directory go to higher in hierarchy:

docker stop gitlab2
docker rm gitlab2
docker run --detach
    --name gitlab2
    --restart always
    --volume D:/gitlab/test:/var/opt
    --volume D:/gitlab/testlog:/var/log/gitlab
    gitlab/gitlab-ce:latest

No ruby_block error now, but dir D:\gitlab\test\gitlab shows nothing, but docker exec -i -t gitlab2 /bin/bash -c "ls /var/opt/gitlab" results in:

backups   gitlab-ci     mattermost  prometheus  trusted-certs-directory-hash
consul    gitlab-rails  nginx       redis
git-data  gitlab-shell  postgresql  registry

What is the current bug behavior?

Either a ruby_block failure or content from /var/opt/gitlab not persisted on the host machine.

What is the expected correct behavior?

Whichever mount point I use (/var, /var/opt or /var/opt/gitlab) host machine should get all the files written by container.

Relevant logs

tail of logs (nothing really of interest, but pasting for completeness) 
==> /var/log/gitlab/gitaly/current <==
2018-04-16_18:34:45.48598 time="2018-04-16T18:34:45Z" level=info msg="Starting Gitaly" version="Gitaly, version 0.91.0, built 20180329.122459"
2018-04-16_18:34:45.48650 time="2018-04-16T18:34:45Z" level=warning msg="git path not configured. Using default path resolution" resolvedPath=/opt/gitlab/embedded/bin/git
2018-04-16_18:34:46.52235 time="2018-04-16T18:34:46Z" level=warning msg="git path not configured. Using default path resolution" resolvedPath=/opt/gitlab/embedded/bin/git
2018-04-16_18:35:02.05802 time="2018-04-16T18:35:02Z" level=info msg="listening on unix socket" address=/var/opt/gitlab/gitaly/gitaly.socket
2018-04-16_18:35:02.05825 time="2018-04-16T18:35:02Z" level=info msg="Starting prometheus listener" address="localhost:9236"
2018-04-16_18:35:02.05893 time="2018-04-16T18:35:02Z" level=info msg="starting RSS monitor" supervisor.name=gitaly-ruby.0 supervisor.rss_threshold=314572800
2018-04-16_18:35:02.05938 time="2018-04-16T18:35:02Z" level=warning msg=spawned supervisor.args="[bundle exec bin/ruby-cd /var/opt/gitlab/gitaly /opt/gitlab/embedded/service/gitaly-ruby/bin/gitaly-ruby 1859 /tmp/gitaly-ruby030762917/socket.0]" supervisor.name=gitaly-ruby.0 supervisor.pid=1929
2018-04-16_18:35:02.08902 time="2018-04-16T18:35:02Z" level=info msg="starting RSS monitor" supervisor.name=gitaly-ruby.1 supervisor.rss_threshold=314572800
2018-04-16_18:35:02.10152 time="2018-04-16T18:35:02Z" level=warning msg=spawned supervisor.args="[bundle exec bin/ruby-cd /var/opt/gitlab/gitaly /opt/gitlab/embedded/service/gitaly-ruby/bin/gitaly-ruby 1859 /tmp/gitaly-ruby030762917/socket.1]" supervisor.name=gitaly-ruby.1 supervisor.pid=1934
2018-04-16_18:35:02.15306 time="2018-04-16T18:35:02Z" level=info msg="finished tempdir cleaner walk" storage=default time_ms=95

==> /var/log/gitlab/gitlab-monitor/current <==
2018-04-16_18:36:58.23480 127.0.0.1 - - [16/Apr/2018:18:36:58 UTC] "GET /database HTTP/1.1" 200 55725
2018-04-16_18:36:58.23482 - -> /database
2018-04-16_18:37:00.59529 127.0.0.1 - - [16/Apr/2018:18:37:00 UTC] "GET /process HTTP/1.1" 200 2234
2018-04-16_18:37:00.59530 - -> /process
2018-04-16_18:37:11.35048 127.0.0.1 - - [16/Apr/2018:18:37:11 UTC] "GET /sidekiq HTTP/1.1" 200 11741
2018-04-16_18:37:11.35049 - -> /sidekiq
2018-04-16_18:37:13.23406 127.0.0.1 - - [16/Apr/2018:18:37:13 UTC] "GET /database HTTP/1.1" 200 55725
2018-04-16_18:37:13.23419 - -> /database
2018-04-16_18:37:15.91204 127.0.0.1 - - [16/Apr/2018:18:37:15 UTC] "GET /process HTTP/1.1" 200 2235
2018-04-16_18:37:15.91205 - -> /process


==> /var/log/gitlab/gitlab-rails/api_json.log <==



Logfile created on 2018-04-16 18:20:09 +0000 by logger.rb/56438


==> /var/log/gitlab/gitlab-rails/application.log <==



Logfile created on 2018-04-16 18:21:47 +0000 by logger.rb/56438


April 16, 2018 18:21: User "Administrator" (admin@example.com) was created


==> /var/log/gitlab/gitlab-rails/gitlab-rails-db-migrate-2018-04-16-18-19-41.log <==
-> 0.2120s


== Seed from /opt/gitlab/embedded/service/gitlab-rails/db/fixtures/production/001_admin.rb
Administrator account created:


login:    root
password: You'll be prompted to create one on your first visit.


== Seed from /opt/gitlab/embedded/service/gitlab-rails/db/fixtures/production/010_settings.rb


==> /var/log/gitlab/gitlab-rails/grpc.log <==



Logfile created on 2018-04-16 18:20:09 +0000 by logger.rb/56438


==> /var/log/gitlab/gitlab-rails/production.log <==
Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms)
Started GET "/help" for 127.0.0.1 at 2018-04-16 18:36:53 +0000
Processing by HelpController#index as /
Started GET "/-/metrics" for 127.0.0.1 at 2018-04-16 18:37:04 +0000
Processing by MetricsController#index as HTML
Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms)
Completed 200 OK in 12210ms (Views: 12203.8ms | ActiveRecord: 0.0ms)
Started GET "/-/metrics" for 127.0.0.1 at 2018-04-16 18:37:19 +0000
Processing by MetricsController#index as HTML
Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.0ms)


==> /var/log/gitlab/gitlab-rails/production_json.log <==
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":3.55,"view":0.4,"db":0.0,"time":"2018-04-16T18:35:34.407Z","params":[],"remote_ip":null,"user_id":null,"username":null}
{"method":"GET","path":"/help","format":"/","controller":"HelpController","action":"index","status":200,"duration":6092.15,"view":5965.32,"db":0.0,"time":"2018-04-16T18:35:41.748Z","params":[],"remote_ip":"127.0.0.1","user_id":null,"username":null}
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":3.37,"view":0.45,"db":0.0,"time":"2018-04-16T18:35:49.407Z","params":[],"remote_ip":null,"user_id":null,"username":null}
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":3.39,"view":0.34,"db":0.0,"time":"2018-04-16T18:36:04.407Z","params":[],"remote_ip":null,"user_id":null,"username":null}
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":3.39,"view":0.42,"db":0.0,"time":"2018-04-16T18:36:19.407Z","params":[],"remote_ip":null,"user_id":null,"username":null}
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":3.1,"view":0.33,"db":0.0,"time":"2018-04-16T18:36:34.407Z","params":[],"remote_ip":null,"user_id":null,"username":null}
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":2.83,"view":0.33,"db":0.0,"time":"2018-04-16T18:36:49.405Z","params":[],"remote_ip":null,"user_id":null,"username":null}
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":2.93,"view":0.32,"db":0.0,"time":"2018-04-16T18:37:04.407Z","params":[],"remote_ip":null,"user_id":null,"username":null}
{"method":"GET","path":"/help","format":"/","controller":"HelpController","action":"index","status":200,"duration":12706.17,"view":12203.76,"db":0.0,"time":"2018-04-16T18:36:53.212Z","params":[],"remote_ip":"127.0.0.1","user_id":null,"username":null}
{"method":"GET","path":"/-/metrics","format":"html","controller":"MetricsController","action":"index","status":200,"duration":2.57,"view":0.31,"db":0.0,"time":"2018-04-16T18:37:19.405Z","params":[],"remote_ip":null,"user_id":null,"username":null}


==> /var/log/gitlab/gitlab-rails/sidekiq.log <==
2018-04-16_18:28:25.29711 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/fetch.rb:36:in retrieve_work' 2018-04-16_18:28:25.29719 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:91:in get_one'
2018-04-16_18:28:25.29735 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:101:in fetch' 2018-04-16_18:28:25.29759 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:84:in process_one'
2018-04-16_18:28:25.29860 2018-04-16T18:28:25.298Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:73:in run' 2018-04-16_18:28:25.29942 2018-04-16T18:28:25.299Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/util.rb:16:in watchdog'
2018-04-16_18:28:25.29959 2018-04-16T18:28:25.299Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/util.rb:25:in `block in safe_thread'
2018-04-16_18:29:20.92229 2018-04-16T18:28:28.649Z 936 TID-ornccorh4 INFO: Redis is online, 9.558417 sec downtime
2018-04-16_18:30:08.57611 2018-04-16T18:30:08.575Z 936 TID-orn6ts1ac PagesDomainVerificationCronWorker JID-0bf44d88a4acab04387d6dd1 INFO: start
2018-04-16_18:30:10.45752 2018-04-16T18:30:10.457Z 936 TID-orn6ts1ac PagesDomainVerificationCronWorker JID-0bf44d88a4acab04387d6dd1 INFO: done: 1.881 sec


==> /var/log/gitlab/gitlab-shell/gitlab-shell.log <==



Logfile created on 2018-04-16 18:15:57 +0000 by logger.rb/56438


==> /var/log/gitlab/gitlab-workhorse/current <==
2018-04-16_18:29:32.68100 time="2018-04-16T18:29:32Z" level=error msg=error error="badgateway: failed after 62s: dial unix /var/opt/gitlab/gitlab-rails/sockets/gitlab.socket: i/o timeout" method=GET uri=/help
2018-04-16_18:29:34.13244 localhost @ - - [2018/04/16:18:29:34 +0000] "GET /help HTTP/1.1" 502 2916 "" "curl/7.56.1" 273.050
2018-04-16_18:29:50.17791 localhost @ - - [2018/04/16:18:29:50 +0000] "GET /help HTTP/1.1" 200 30800 "" "curl/7.56.1" 9.254
2018-04-16_18:33:05.73768 time="2018-04-16T18:33:05Z" level=error msg=error error="badgateway: failed after 72s: EOF" method=GET uri=/help
2018-04-16_18:33:06.72299 localhost @ - - [2018/04/16:18:33:06 +0000] "GET /help HTTP/1.1" 502 2916 "" "curl/7.56.1" 98.293
2018-04-16_18:34:43.58097 time="2018-04-16T18:34:43Z" level=info msg=Starting version="gitlab-workhorse v4.0.0-20180327.113012"
2018-04-16_18:34:44.45500 time="2018-04-16T18:34:44Z" level=info msg="keywatcher: starting process loop"
2018-04-16_18:34:44.45547 time="2018-04-16T18:34:44Z" level=info msg="redis: dialing" address=/var/opt/gitlab/redis/redis.socket network=unix
2018-04-16_18:35:47.84330 localhost @ - - [2018/04/16:18:35:47 +0000] "GET /help HTTP/1.1" 200 30800 "" "curl/7.56.1" 6.243
2018-04-16_18:37:05.92042 localhost @ - - [2018/04/16:18:37:05 +0000] "GET /help HTTP/1.1" 200 30800 "" "curl/7.56.1" 12.718


==> /var/log/gitlab/logrotate/current <==


==> /var/log/gitlab/nginx/access.log <==


==> /var/log/gitlab/nginx/current <==


==> /var/log/gitlab/nginx/error.log <==


==> /var/log/gitlab/nginx/gitlab_access.log <==
127.0.0.1 - - [16/Apr/2018:18:23:55 +0000] "GET /help HTTP/1.1" 502 2916 "" "curl/7.56.1"
127.0.0.1 - - [16/Apr/2018:18:29:34 +0000] "GET /help HTTP/1.1" 502 2916 "" "curl/7.56.1"
127.0.0.1 - - [16/Apr/2018:18:29:50 +0000] "GET /help HTTP/1.1" 200 30813 "" "curl/7.56.1"
127.0.0.1 - - [16/Apr/2018:18:33:06 +0000] "GET /help HTTP/1.1" 502 2916 "" "curl/7.56.1"
127.0.0.1 - - [16/Apr/2018:18:34:41 +0000] "GET /help HTTP/1.1" 502 2916 "" "curl/7.56.1"
127.0.0.1 - - [16/Apr/2018:18:35:47 +0000] "GET /help HTTP/1.1" 200 30813 "" "curl/7.56.1"
127.0.0.1 - - [16/Apr/2018:18:37:05 +0000] "GET /help HTTP/1.1" 200 30813 "" "curl/7.56.1"


==> /var/log/gitlab/nginx/gitlab_error.log <==
2018/04/16 18:34:41 [error] 967#0: *9 upstream prematurely closed connection while reading response header from upstream, client: 127.0.0.1, server: d301fdfb8cfa, request: "GET /help HTTP/1.1", upstream: "http://unix:/var/opt/gitlab/gitlab-workhorse/socket:/help", host: "localhost"


==> /var/log/gitlab/node-exporter/current <==
2018-04-16_18:23:03.96910 time="2018-04-16T18:23:03Z" level=info msg=" - stat" source="node_exporter.go:52"
2018-04-16_18:23:03.96914 time="2018-04-16T18:23:03Z" level=info msg=" - filefd" source="node_exporter.go:52"
2018-04-16_18:23:03.96915 time="2018-04-16T18:23:03Z" level=info msg=" - diskstats" source="node_exporter.go:52"
2018-04-16_18:23:03.96922 time="2018-04-16T18:23:03Z" level=info msg=" - filesystem" source="node_exporter.go:52"
2018-04-16_18:23:03.96924 time="2018-04-16T18:23:03Z" level=info msg=" - timex" source="node_exporter.go:52"
2018-04-16_18:23:03.96928 time="2018-04-16T18:23:03Z" level=info msg=" - infiniband" source="node_exporter.go:52"
2018-04-16_18:23:03.96929 time="2018-04-16T18:23:03Z" level=info msg=" - edac" source="node_exporter.go:52"
2018-04-16_18:23:03.96930 time="2018-04-16T18:23:03Z" level=info msg=" - arp" source="node_exporter.go:52"
2018-04-16_18:23:03.96934 time="2018-04-16T18:23:03Z" level=info msg=" - vmstat" source="node_exporter.go:52"
2018-04-16_18:23:03.96964 time="2018-04-16T18:23:03Z" level=info msg="Listening on localhost:9100" source="node_exporter.go:76"


==> /var/log/gitlab/postgres-exporter/current <==
2018-04-16_18:23:31.72989 time="2018-04-16T18:23:31Z" level=info msg="Established new database connection." source="postgres_exporter.go:986"
2018-04-16_18:23:32.37960 time="2018-04-16T18:23:32Z" level=info msg="Semantic Version Changed: 0.0.0 -> 9.6.8" source="postgres_exporter.go:919"
2018-04-16_18:23:34.44216 time="2018-04-16T18:23:34Z" level=info msg="Starting Server: localhost:9187" source="postgres_exporter.go:1114"
2018-04-16_18:34:51.40504 time="2018-04-16T18:34:51Z" level=info msg="Established new database connection." source="postgres_exporter.go:986"
2018-04-16_18:34:52.12697 time="2018-04-16T18:34:52Z" level=info msg="Semantic Version Changed: 0.0.0 -> 9.6.8" source="postgres_exporter.go:919"
2018-04-16_18:34:54.58282 time="2018-04-16T18:34:54Z" level=info msg="Starting Server: localhost:9187" source="postgres_exporter.go:1114"


==> /var/log/gitlab/postgresql/current <==
2018-04-16_18:19:38.19949 LOG:  database system was shut down at 2018-04-16 18:19:32 GMT
2018-04-16_18:19:38.24539 LOG:  MultiXact member wraparound protections are now enabled
2018-04-16_18:19:38.28856 LOG:  database system is ready to accept connections
2018-04-16_18:19:38.28903 LOG:  autovacuum launcher started
2018-04-16_18:29:33.16200 LOG:  could not send data to client: Broken pipe
2018-04-16_18:29:33.44565 FATAL:  connection to client lost
2018-04-16_18:33:01.83549 LOG:  could not send data to client: Broken pipe
2018-04-16_18:33:01.83605 FATAL:  connection to client lost


==> /var/log/gitlab/prometheus/current <==
2018-04-16_18:34:56.16340 time="2018-04-16T18:34:56Z" level=info msg="Loading series map and head chunks..." source="storage.go:428"
2018-04-16_18:34:56.22522 time="2018-04-16T18:34:56Z" level=info msg="5782 series loaded." source="storage.go:439"
2018-04-16_18:34:56.22570 time="2018-04-16T18:34:56Z" level=info msg="Server is Ready to receive requests." source="main.go:230"
2018-04-16_18:34:56.22590 time="2018-04-16T18:34:56Z" level=info msg="Starting target manager..." source="targetmanager.go:63"
2018-04-16_18:34:56.28338 time="2018-04-16T18:34:56Z" level=error msg="Error creating HTTP client for job "kubernetes-cadvisor": unable to use specified CA cert /var/run/secrets/kubernetes.io/serviceaccount/ca.crt: open /var/run/secrets/kubernetes.io/serviceaccount/ca.crt: no such file or directory" source="scrape.go:121"
2018-04-16_18:34:56.28374 time="2018-04-16T18:34:56Z" level=error msg="Cannot create Kubernetes discovery: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory" source="discovery.go:86"
2018-04-16_18:34:56.28394 time="2018-04-16T18:34:56Z" level=error msg="Error creating HTTP client for job "kubernetes-nodes": unable to use specified CA cert /var/run/secrets/kubernetes.io/serviceaccount/ca.crt: open /var/run/secrets/kubernetes.io/serviceaccount/ca.crt: no such file or directory" source="scrape.go:121"
2018-04-16_18:34:56.28415 time="2018-04-16T18:34:56Z" level=error msg="Cannot create Kubernetes discovery: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory" source="discovery.go:86"
2018-04-16_18:34:56.28437 time="2018-04-16T18:34:56Z" level=error msg="Error creating HTTP client for job "kubernetes-pods": unable to use specified CA cert /var/run/secrets/kubernetes.io/serviceaccount/ca.crt: open /var/run/secrets/kubernetes.io/serviceaccount/ca.crt: no such file or directory" source="scrape.go:121"
2018-04-16_18:34:56.28438 time="2018-04-16T18:34:56Z" level=error msg="Cannot create Kubernetes discovery: open /var/run/secrets/kubernetes.io/serviceaccount/token: no such file or directory" source="discovery.go:86"


==> /var/log/gitlab/redis/current <==
2018-04-16_18:29:31.73777 638:M 16 Apr 18:29:29.095 * 10 changes in 300 seconds. Saving...
2018-04-16_18:29:31.73819 638:M 16 Apr 18:29:31.667 * Background saving started by pid 1405
2018-04-16_18:29:33.52847 1405:C 16 Apr 18:29:33.528 * DB saved on disk
2018-04-16_18:29:33.52918 1405:C 16 Apr 18:29:33.529 * RDB: 1 MB of memory used by copy-on-write
2018-04-16_18:29:33.57109 638:M 16 Apr 18:29:33.570 * Background saving terminated with success
2018-04-16_18:34:35.06327 638:M 16 Apr 18:34:34.103 * 10 changes in 300 seconds. Saving...
2018-04-16_18:34:35.29846 638:M 16 Apr 18:34:35.298 * Background saving started by pid 1830
2018-04-16_18:34:35.73336 1830:C 16 Apr 18:34:35.733 * DB saved on disk
2018-04-16_18:34:35.73400 1830:C 16 Apr 18:34:35.733 * RDB: 1 MB of memory used by copy-on-write
2018-04-16_18:34:35.80125 638:M 16 Apr 18:34:35.801 * Background saving terminated with success


==> /var/log/gitlab/redis-exporter/current <==
2018-04-16_18:23:18.39597 time="2018-04-16T18:23:18Z" level=info msg="Redis Metrics Exporter <<< filled in by build >>>    build date: <<< filled in by build >>>    sha1: <<< filled in by build >>>    Go: go1.9.2"
2018-04-16_18:23:18.39649 time="2018-04-16T18:23:18Z" level=info msg="Providing metrics at localhost:9121/metrics"
2018-04-16_18:23:18.39650 time="2018-04-16T18:23:18Z" level=info msg="Connecting to redis hosts: []string{"unix:///var/opt/gitlab/redis/redis.socket"}"
2018-04-16_18:23:18.39651 time="2018-04-16T18:23:18Z" level=info msg="Using alias: []string{""}"


==> /var/log/gitlab/sidekiq/current <==
2018-04-16_18:28:25.29711 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/fetch.rb:36:in retrieve_work' 2018-04-16_18:28:25.29719 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:91:in get_one'
2018-04-16_18:28:25.29735 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:101:in fetch' 2018-04-16_18:28:25.29759 2018-04-16T18:28:25.297Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:84:in process_one'
2018-04-16_18:28:25.29860 2018-04-16T18:28:25.298Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/processor.rb:73:in run' 2018-04-16_18:28:25.29942 2018-04-16T18:28:25.299Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/util.rb:16:in watchdog'
2018-04-16_18:28:25.29959 2018-04-16T18:28:25.299Z 936 TID-ornccorh4 ERROR: /opt/gitlab/embedded/lib/ruby/gems/2.3.0/gems/sidekiq-5.0.5/lib/sidekiq/util.rb:25:in `block in safe_thread'
2018-04-16_18:29:20.92229 2018-04-16T18:28:28.649Z 936 TID-ornccorh4 INFO: Redis is online, 9.558417 sec downtime
2018-04-16_18:30:08.57611 2018-04-16T18:30:08.575Z 936 TID-orn6ts1ac PagesDomainVerificationCronWorker JID-0bf44d88a4acab04387d6dd1 INFO: start
2018-04-16_18:30:10.45752 2018-04-16T18:30:10.457Z 936 TID-orn6ts1ac PagesDomainVerificationCronWorker JID-0bf44d88a4acab04387d6dd1 INFO: done: 1.881 sec


==> /var/log/gitlab/sshd/current <==
2018-04-16_18:14:29.12973 Server listening on :: port 22.
2018-04-16_18:14:42.51397 Server listening on 0.0.0.0 port 22.
2018-04-16_18:14:42.51441 Server listening on :: port 22.
2018-04-16_18:14:55.01448 Server listening on 0.0.0.0 port 22.
2018-04-16_18:14:55.01526 Server listening on :: port 22.
2018-04-16_18:15:10.21349 Server listening on 0.0.0.0 port 22.
2018-04-16_18:15:10.21392 Server listening on :: port 22.
2018-04-16_18:15:18.48708 Received signal 15; terminating.
2018-04-16_18:15:49.70417 Server listening on 0.0.0.0 port 22.
2018-04-16_18:15:49.70463 Server listening on :: port 22.


==> /var/log/gitlab/unicorn/current <==
2018-04-16_18:22:24.90155 starting new unicorn master
2018-04-16_18:29:33.07029 adopted new unicorn master 921


==> /var/log/gitlab/unicorn/unicorn_stderr.log <==
I, [2018-04-16T18:24:23.071456 #1314]  INFO -- : worker=3 ready
I, [2018-04-16T18:24:23.071273 #1308]  INFO -- : worker=1 ready
I, [2018-04-16T18:24:23.071102 #1317]  INFO -- : worker=4 ready
D, [2018-04-16T18:25:23.290174 #921] DEBUG -- : waiting 31.0s after suspend/hibernation
E, [2018-04-16T18:29:24.055220 #921] ERROR -- : worker=1 PID:1308 timeout (61s > 60s), killing
E, [2018-04-16T18:29:29.460326 #921] ERROR -- : reaped #<Process::Status: pid 1308 SIGKILL (signal 9)> worker=1
I, [2018-04-16T18:29:37.558516 #1411]  INFO -- : worker=1 ready
E, [2018-04-16T18:32:57.826005 #921] ERROR -- : worker=1 PID:1411 timeout (63s > 60s), killing
E, [2018-04-16T18:33:00.915211 #921] ERROR -- : reaped #<Process::Status: pid 1411 SIGKILL (signal 9)> worker=1
I, [2018-04-16T18:33:07.366483 #1639]  INFO -- : worker=1 ready


==> /var/log/gitlab/unicorn/unicorn_stdout.log <==


==> /var/log/gitlab/gitlab-monitor/current <==
2018-04-16_18:37:26.34640 127.0.0.1 - - [16/Apr/2018:18:37:26 UTC] "GET /sidekiq HTTP/1.1" 200 11741
2018-04-16_18:37:26.34642 - -> /sidekiq
2018-04-16_18:37:28.23469 127.0.0.1 - - [16/Apr/2018:18:37:28 UTC] "GET /database HTTP/1.1" 200 55725
2018-04-16_18:37:28.23471 - -> /database

Details of package version

Provide the package version installation details 
||/ Name                                      Version                   Architecture              Description
+++-=========================================-=========================-=========================-========================================================================================
ii  gitlab-ce                                 10.6.2-ce.0               amd64                     GitLab Community Edition (including NGINX, Postgres, Redis)
un  gitlab-ee                                                                         (no description available)

Environment details

  • Operating System: Windows 10 Pro (16299)
  • Installation Target, remove incorrect values:
    • Docker CE (Version 18.03.0-ce-win59 (16762))
  • Installation Type, remove incorrect values:
    • New Installation
  • Is there any other software running on the machine: Nope
  • Is this a single or multiple node installation? Single node
  • Resources
    • CPU: Ryzen 1500
    • Memory total: 4GB for Docker (16GB total)

Configuration details

Nothing was changed - vanilla config was used.