gistfile1.txt

umount.target                           static  
fstrim.timer                            enabled 
mdadm-last-resort@.timer                static  
snapper-cleanup.timer                   disabled
snapper-timeline.timer                  disabled
systemd-readahead-done.timer            static  
systemd-tmpfiles-clean.timer            static
[INFO    ] Legacy init script: "ipmi".
[INFO    ] Legacy init script: "snmpd".
[INFO    ] Legacy init script: "snmptrapd".
[INFO    ] Legacy init script: "pcscd".
[INFO    ] Legacy init script: "openct".
[INFO    ] Legacy init script: "cifs".
[INFO    ] Legacy init script: "postfix".
[INFO    ] Legacy init script: "boot.apparmor".
[INFO    ] Legacy init script: "rpmconfigcheck".
[INFO    ] Legacy init script: "raw".
[INFO    ] Legacy init script: "boot.local".
[INFO    ] Legacy init script: "halt.local".
[INFO    ] Legacy init script: "after.local".
[INFO    ] Legacy init script: "dbus".
[INFO    ] Executing command 'systemctl status docker.service' in directory '/root'
[DEBUG   ] output: docker.service - Docker Application Container Engine
   Loaded: loaded (/usr/lib/systemd/system/docker.service; disabled)
   Active: active (running) since Sat 2016-10-29 15:31:24 BST; 3 days ago
     Docs: http://docs.docker.com
 Main PID: 9870 (docker)
   CGroup: /system.slice/docker.service
           |- 9870 /usr/bin/docker daemon -H fd:// --containerd /run/containerd/containerd.sock
           `-16620 xz -d -c -q

Nov 02 12:26:36 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:26:36.976625303Z" level=error msg="Handler for DELETE /v1.23/images/sha256:ac269f220494b681cc9574e0f6ac4b4c7d7f47583f75e1f74b41d680dc5368a2 returned error: conflict: unable to delete ac269f220494 (must be forced) - image is being used by stopped container ffaee5ce610a"
Nov 02 12:26:55 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:26:55.720233285Z" level=error msg="Handler for DELETE /v1.23/images/sha256:ac269f220494b681cc9574e0f6ac4b4c7d7f47583f75e1f74b41d680dc5368a2 returned error: conflict: unable to delete ac269f220494 (must be forced) - image is being used by stopped container c25f1af66a78"
Nov 02 12:27:08 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:27:08.536196739Z" level=error msg="Handler for DELETE /v1.23/images/sha256:ac269f220494b681cc9574e0f6ac4b4c7d7f47583f75e1f74b41d680dc5368a2 returned error: conflict: unable to delete ac269f220494 (must be forced) - image is being used by stopped container bf0bae150ac9"
Nov 02 12:46:37 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:46:37.813606649Z" level=error msg="Handler for DELETE /v1.23/images/sha256:ac269f220494b681cc9574e0f6ac4b4c7d7f47583f75e1f74b41d680dc5368a2 returned error: conflict: unable to delete ac269f220494 (must be forced) - image is being used by stopped container d3b65e5381cb"
Nov 02 12:50:14 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:50:14.038870317Z" level=error msg="Handler for DELETE /v1.23/containers/autoinst.xml returned error: No such container: autoinst.xml"
Nov 02 12:50:14 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:50:14.039868769Z" level=error msg="Handler for DELETE /v1.23/containers/bin returned error: No such container: bin"
Nov 02 12:50:14 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:50:14.040475079Z" level=error msg="Handler for DELETE /v1.23/containers/client-config-overrides.txt returned error: No such container: client-config-overrides.txt"
Nov 02 12:50:14 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:50:14.041079742Z" level=error msg="Handler for DELETE /v1.23/containers/client_config_update.py returned error: No such container: client_config_update.py"
Nov 02 12:50:14 sotestsaltdoc01 docker[9870]: time="2016-11-02T12:50:14.041616218Z" level=error msg="Handler for DELETE /v1.23/containers/inst-sys returned error: No such container: inst-sys"
Nov 02 13:35:02 sotestsaltdoc01 docker[9870]: time="2016-11-02T13:35:02.577695908Z" level=error msg="Handler for DELETE /v1.23/images/sha256:ea64c0e8d34bf08f73b8b8ed3de7d8d29a180a2c64b83f3f5eaeaf9676115df3 returned error: conflict: unable to delete ea64c0e8d34b (must be forced) - image is being used by stopped container 5c2b5b76fe58"
[INFO    ] Executing command 'systemctl is-active docker.service' in directory '/root'
[DEBUG   ] output: active
[INFO    ] Executing command 'systemctl is-enabled docker.service' in directory '/root'
[DEBUG   ] output: disabled
[INFO    ] Executing command 'runlevel' in directory '/root'
[DEBUG   ] output: N 3
[INFO    ] The service docker is already running
[INFO    ] Completed state [docker] at time 14:03:47.508377
[DEBUG   ] LazyLoaded file.directory
[INFO    ] Running state [/docker] at time 14:03:47.511456
[INFO    ] Executing state file.directory for /docker
[INFO    ] Directory /docker is in the correct state
[INFO    ] Completed state [/docker] at time 14:03:47.513527
[INFO    ] Running state [/docker/nba/] at time 14:03:47.513995
[INFO    ] Executing state file.directory for /docker/nba/
[INFO    ] Directory /docker/nba is in the correct state
[INFO    ] Completed state [/docker/nba/] at time 14:03:47.515965
[DEBUG   ] LazyLoaded cmd.run
[INFO    ] Running state [sle2docker activate sles12sp1-docker.x86_64-1.0.5-Build10.18] at time 14:03:47.517604
[INFO    ] Executing state cmd.run for sle2docker activate sles12sp1-docker.x86_64-1.0.5-Build10.18
[INFO    ] Executing command 'sle2docker activate sles12sp1-docker.x86_64-1.0.5-Build10.18' in directory '/root'
[DEBUG   ] stderr: Image 'suse/sles12sp1:1.0.5' has already been activated.
[INFO    ] {'pid': 60019, 'retcode': 0, 'stderr': "Image 'suse/sles12sp1:1.0.5' has already been activated.", 'stdout': ''}
[INFO    ] Completed state [sle2docker activate sles12sp1-docker.x86_64-1.0.5-Build10.18] at time 14:03:47.749327
[INFO    ] Running state [/docker/nba/Dockerfile] at time 14:03:47.749618
[INFO    ] Executing state file.managed for /docker/nba/Dockerfile
[DEBUG   ] Initializing new AsyncZeroMQReqChannel for ('/etc/salt/pki/minion', 'sotestsaltdoc01.smartodds.co.uk', 'tcp://172.17.1.229:4506', 'aes')
[DEBUG   ] Initializing new SAuth for ('/etc/salt/pki/minion', 'sotestsaltdoc01.smartodds.co.uk', 'tcp://172.17.1.229:4506')
[DEBUG   ] In saltenv 'base', looking at rel_path u'docker/files/dockerfile' to resolve u'salt://docker/files/dockerfile'
[DEBUG   ] In saltenv 'base', ** considering ** path u'/var/cache/salt/minion/files/base/docker/files/dockerfile' to resolve u'salt://docker/files/dockerfile'
[INFO    ] Fetching file from saltenv 'base', ** skipped ** latest already in cache u'salt://docker/files/dockerfile'
[DEBUG   ] Jinja search path: ['/var/cache/salt/minion/files/base']
[INFO    ] File /docker/nba/Dockerfile is in the correct state
[INFO    ] Completed state [/docker/nba/Dockerfile] at time 14:03:47.766247
[DEBUG   ] LazyLoaded dockerng.image_absent
[INFO    ] Running state [docker/nba:latest] at time 14:03:47.771254
[INFO    ] Executing state dockerng.image_absent for docker/nba:latest
[DEBUG   ] "GET /v1.23/images/json?only_ids=0&all=0 HTTP/1.1" 200 658
[DEBUG   ] targets = ['docker/nba:latest']
[DEBUG   ] to_delete = []
[INFO    ] Image 'docker/nba:latest' is not present
[INFO    ] Completed state [docker/nba:latest] at time 14:03:47.776245
[INFO    ] Running state [docker/nba:latest] at time 14:03:47.776584
[INFO    ] Executing state dockerng.image_present for docker/nba:latest
[DEBUG   ] Looking for auth config
[DEBUG   ] No auth config in memory - loading from filesystem
[DEBUG   ] File doesn't exist
[DEBUG   ] No auth config found
[DEBUG   ] "POST /v1.23/build?q=False&pull=False&t=docker%2Fnba%3Alatest&nocache=False&forcerm=False&rm=True HTTP/1.1" 200 None
[ERROR   ] Encountered error building /docker/nba as docker/nba:latest: tuple index out of range
[INFO    ] Completed state [docker/nba:latest] at time 14:03:48.232445
[DEBUG   ] File /var/cache/salt/minion/accumulator/140095324329552 does not exist, no need to cleanup.
[DEBUG   ] Initializing new AsyncZeroMQReqChannel for ('/etc/salt/pki/minion', 'sotestsaltdoc01.smartodds.co.uk', 'tcp://172.17.1.229:4506', 'aes')
[DEBUG   ] Initializing new SAuth for ('/etc/salt/pki/minion', 'sotestsaltdoc01.smartodds.co.uk', 'tcp://172.17.1.229:4506')
[DEBUG   ] LazyLoaded highstate.output
[DEBUG   ] LazyLoaded nested.output

local:
----------
          ID: install docker
    Function: pkg.installed
        Name: docker
      Result: True
     Comment: Package docker is already installed
     Started: 14:04:58.872315
    Duration: 521.193 ms
     Changes:   
----------
          ID: install sle2docker
    Function: pkg.installed
        Name: sle2docker
      Result: True
     Comment: Package sle2docker is already installed
     Started: 14:04:59.393883
    Duration: 7.982 ms
     Changes:   
----------
          ID: install suse base image
    Function: pkg.installed
        Name: sles12sp1-docker-image
      Result: True
     Comment: Package sles12sp1-docker-image is already installed
     Started: 14:04:59.402215
    Duration: 7.8 ms
     Changes:   
----------
          ID: install docker-py
    Function: pkg.installed
        Name: python-docker-py
      Result: True
     Comment: Package python-docker-py is already installed
     Started: 14:04:59.410393
    Duration: 7.958 ms
     Changes:   
----------
          ID: start docker
    Function: service.running
        Name: docker
      Result: True
     Comment: The service docker is already running
     Started: 14:04:59.419342
    Duration: 252.865 ms
     Changes:   
----------
          ID: setup docker dir
    Function: file.directory
        Name: /docker
      Result: True
     Comment: Directory /docker is in the correct state
     Started: 14:04:59.673961
    Duration: 1.225 ms
     Changes:   
----------
          ID: setup app dir
    Function: file.directory
        Name: /docker/nba/
      Result: True
     Comment: Directory /docker/nba is in the correct state
     Started: 14:04:59.675526
    Duration: 1.102 ms
     Changes:   
----------
          ID: install docker image
    Function: cmd.run
        Name: sle2docker activate sles12sp1-docker.x86_64-1.0.5-Build10.18
      Result: True
     Comment: Command "sle2docker activate sles12sp1-docker.x86_64-1.0.5-Build10.18" run
     Started: 14:04:59.677629
    Duration: 200.203 ms
     Changes:   
              ----------
              pid:
                  60166
              retcode:
                  0
              stderr:
                  Image 'suse/sles12sp1:1.0.5' has already been activated.
              stdout:
----------
          ID: make dockerfile
    Function: file.managed
        Name: /docker/nba/Dockerfile
      Result: True
     Comment: File /docker/nba/Dockerfile is in the correct state
     Started: 14:04:59.878270
    Duration: 14.235 ms
     Changes:   
----------
          ID: remove_images
    Function: dockerng.image_absent
        Name: docker/nba:latest
      Result: True
     Comment: Image 'docker/nba:latest' is not present
     Started: 14:04:59.897295
    Duration: 4.966 ms
     Changes:   
----------
          ID: docker/nba:latest
    Function: dockerng.image_present
      Result: False
     Comment: Encountered error building /docker/nba as docker/nba:latest: tuple index out of range
     Started: 14:04:59.902624
    Duration: 375.404 ms
     Changes:   

Summary for local
-------------
Succeeded: 10 (changed=1)
Failed:     1