Skip to content

Instantly share code, notes, and snippets.

Show Gist options
  • Save josteinaj/276b24698405b76eee16 to your computer and use it in GitHub Desktop.
Save josteinaj/276b24698405b76eee16 to your computer and use it in GitHub Desktop.
Job Summary - 09d64df4-5e80-493c-9782-75dc8d22092d
2014-05-02 14:40:42,690 [INFO ] org.daisy.pipeline.job.Job - Changing job status to: RUNNING
2014-05-02 14:40:42,696 [DEBUG] org.daisy.pipeline.push.Poster - Status changed 09d64df4-5e80-493c-9782-75dc8d22092d->RUNNING
2014-05-02 14:40:42,697 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:40:42,699 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:40:42,700 [DEBUG] o.d.p.p.jobs.PersistentJobContext - Post loading jobcontext
2014-05-02 14:40:42,700 [DEBUG] o.d.pipeline.job.JobContextFactory - configuring object org.daisy.pipeline.persistence.jobs.PersistentJobContext@554b2083
2014-05-02 14:40:42,701 [DEBUG] o.d.pipeline.job.JobContextFactory - configuring object org.daisy.pipeline.persistence.jobs.PersistentJobContext@554b2083
2014-05-02 14:40:42,701 [INFO ] o.d.p.persistence.jobs.PersistentJob - Changing Status:RUNNING
2014-05-02 14:40:42,701 [DEBUG] o.d.p.persistence.jobs.PersistentJob - Updating object
2014-05-02 14:40:42,702 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:40:42,702 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:40:42,703 [DEBUG] o.d.p.p.jobs.PersistentJobContext - Post loading jobcontext
2014-05-02 14:40:42,703 [DEBUG] o.d.pipeline.job.JobContextFactory - configuring object org.daisy.pipeline.persistence.jobs.PersistentJobContext@2926da41
2014-05-02 14:40:42,704 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:40:42,863 [DEBUG] o.d.c.x.c.DynamicXProcConfigurationFactory - Reading Calabash configuration from file:/home/jostein/Skrivebord/daisy-pipeline/etc/config-calabash.xml
2014-05-02 14:40:45,686 [DEBUG] o.d.c.x.c.DynamicXProcConfigurationFactory - Reading Calabash configuration from file:/home/jostein/Skrivebord/daisy-pipeline/etc/config-calabash.xml
2014-05-02 14:40:47,318 [INFO ] com.xmlcalabash.runtime.XAtomicStep - bundle://20.0:1/xml/xproc/html-to-epub3.convert.xpl:54:21:[WARNING] Replacing remote image '//no.wikipedia.org/wiki/Special:CentralAutoLogin/start?type=1x1' by alternative text.
2014-05-02 14:40:53,657 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.21.9.4
2014-05-02 14:40:53,658 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://20.0:1/xml/xproc/html-to-epub3.convert.xpl:156:65:Message:Navigation Document Created.
2014-05-02 14:40:53,666 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://20.0:1/xml/xproc/html-to-epub3.convert.xpl:156:65:Message step !1.21.9.4 read bundle://27.0:1/xml/xproc/fileset-create.xpl
2014-05-02 14:40:58,673 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.27.9
2014-05-02 14:40:58,674 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://20.0:1/xml/xproc/html-to-epub3.convert.xpl:242:58:Message:Package Document Created.
2014-05-02 14:40:58,680 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://20.0:1/xml/xproc/html-to-epub3.convert.xpl:242:58:Message step !1.27.9 read bundle://27.0:1/xml/xproc/fileset-create.xpl
2014-05-02 14:40:59,985 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.2.2
2014-05-02 14:40:59,985 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message:Writing in-memory document to EPUB/Den_syntetiske_stemmen_Brage_-_Wikipedia.xhtml
2014-05-02 14:40:59,998 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message step !1.14.21.2.2 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,058 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.2.2
2014-05-02 14:41:00,058 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message:Writing in-memory document to EPUB/toc.xhtml
2014-05-02 14:41:00,068 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message step !1.14.21.2.2 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,087 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,087 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory: EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/
2014-05-02 14:41:00,098 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,102 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,102 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied index_003.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_003.php
2014-05-02 14:41:00,107 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,115 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,115 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied index.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index.php
2014-05-02 14:41:00,120 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,127 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,128 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied index_007.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_007.php
2014-05-02 14:41:00,138 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,144 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,144 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied index_005.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_005.php
2014-05-02 14:41:00,149 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,157 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,158 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied index_002.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_002.php
2014-05-02 14:41:00,163 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,170 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,171 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied index_006.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_006.php
2014-05-02 14:41:00,181 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,187 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,187 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied load.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load.php
2014-05-02 14:41:00,191 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,197 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,197 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied checkLoggedIn to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/checkLoggedIn
2014-05-02 14:41:00,207 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,214 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:00,214 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied load.css to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load.css
2014-05-02 14:41:00,220 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:00,228 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,228 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,234 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,236 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,236 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,247 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,249 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,249 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,257 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,264 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,264 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,269 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,271 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,271 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,282 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,283 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,284 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,292 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,299 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,300 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,305 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,306 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,306 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,317 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,318 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,319 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,332 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,343 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,344 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,348 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,349 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,349 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,354 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,355 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,355 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,365 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,371 [DEBUG] o.a.commons.httpclient.HttpClient - Java version: 1.7.0_55
2014-05-02 14:41:00,371 [DEBUG] o.a.commons.httpclient.HttpClient - Java vendor: Oracle Corporation
2014-05-02 14:41:00,371 [DEBUG] o.a.commons.httpclient.HttpClient - Java class path: /home/jostein/Skrivebord/daisy-pipeline/system/bootstrap/felix.jar
2014-05-02 14:41:00,371 [DEBUG] o.a.commons.httpclient.HttpClient - Operating system name: Linux
2014-05-02 14:41:00,371 [DEBUG] o.a.commons.httpclient.HttpClient - Operating system architecture: amd64
2014-05-02 14:41:00,371 [DEBUG] o.a.commons.httpclient.HttpClient - Operating system version: 3.13.0-24-generic
2014-05-02 14:41:00,392 [DEBUG] o.a.commons.httpclient.HttpClient - SUN 1.7: SUN (DSA key/parameter generation; DSA signing; SHA-1, MD5 digests; SecureRandom; X.509 certificates; JKS keystore; PKIX CertPathValidator; PKIX CertPathBuilder; LDAP, Collection CertStores, JavaPolicy Policy; JavaLoginConfig Configuration)
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - SunRsaSign 1.7: Sun RSA signature provider
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - SunJSSE 1.7: Sun JSSE provider(PKCS12, SunX509 key/trust factories, SSLv3, TLSv1)
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - SunJCE 1.7: SunJCE Provider (implements RSA, DES, Triple DES, AES, Blowfish, ARCFOUR, RC2, PBE, Diffie-Hellman, HMAC)
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - SunJGSS 1.7: Sun (Kerberos v5, SPNEGO)
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - SunSASL 1.7: Sun SASL provider(implements client mechanisms for: DIGEST-MD5, GSSAPI, EXTERNAL, PLAIN, CRAM-MD5, NTLM; server mechanisms for: DIGEST-MD5, GSSAPI, CRAM-MD5, NTLM)
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - XMLDSig 1.0: XMLDSig (DOM XMLSignatureFactory; DOM KeyInfoFactory)
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - SunPCSC 1.7: Sun PC/SC provider
2014-05-02 14:41:00,393 [DEBUG] o.a.commons.httpclient.HttpClient - SunPKCS11-NSS 1.7: SunPKCS11-NSS using library null
2014-05-02 14:41:00,394 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.useragent = Jakarta Commons-HttpClient/3.1
2014-05-02 14:41:00,395 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.version = HTTP/1.1
2014-05-02 14:41:00,396 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.connection-manager.class = class org.apache.commons.httpclient.SimpleHttpConnectionManager
2014-05-02 14:41:00,396 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = default
2014-05-02 14:41:00,396 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.element-charset = US-ASCII
2014-05-02 14:41:00,396 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.content-charset = ISO-8859-1
2014-05-02 14:41:00,397 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.method.retry-handler = org.apache.commons.httpclient.DefaultHttpMethodRetryHandler@dacb255
2014-05-02 14:41:00,397 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.dateparser.patterns = [EEE, dd MMM yyyy HH:mm:ss zzz, EEEE, dd-MMM-yy HH:mm:ss zzz, EEE MMM d HH:mm:ss yyyy, EEE, dd-MMM-yyyy HH:mm:ss z, EEE, dd-MMM-yyyy HH-mm-ss z, EEE, dd MMM yy HH:mm:ss z, EEE dd-MMM-yyyy HH:mm:ss z, EEE dd MMM yyyy HH:mm:ss z, EEE dd-MMM-yyyy HH-mm-ss z, EEE dd-MMM-yy HH:mm:ss z, EEE dd MMM yy HH:mm:ss z, EEE,dd-MMM-yy HH:mm:ss z, EEE,dd-MMM-yyyy HH:mm:ss z, EEE, dd-MM-yyyy HH:mm:ss z]
2014-05-02 14:41:00,399 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,399 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,402 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,402 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,407 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,408 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,408 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,412 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,414 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,414 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,424 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,434 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,435 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,441 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,442 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,443 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,447 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,448 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,449 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,458 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,466 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,466 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,477 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,478 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,478 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,485 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,487 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,487 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,493 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,499 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,499 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,500 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,500 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,511 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,512 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,513 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,518 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,521 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,521 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,527 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,534 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,534 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,545 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,546 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,546 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,553 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,555 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,555 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,562 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,568 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,568 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,573 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,574 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,575 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,586 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,587 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,588 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,596 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,600 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,600 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,600 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,601 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,605 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,606 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,606 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,616 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,617 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,617 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,622 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,627 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,628 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,632 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,633 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,633 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,643 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,644 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,645 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,649 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,657 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,658 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,658 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,658 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,668 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,669 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,669 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,674 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,675 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,675 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,680 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,684 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,684 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,695 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,696 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,696 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,700 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,701 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,701 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,705 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,716 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,716 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,717 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,717 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,727 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,728 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,728 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,733 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,734 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,734 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,738 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,743 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,743 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,753 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,754 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,754 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,764 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,765 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,765 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,769 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,773 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,774 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,774 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,775 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,779 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,780 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,780 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,790 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,791 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,791 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,802 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,807 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,807 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,811 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,812 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,812 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,817 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,818 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,818 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,828 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,843 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,843 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,844 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,844 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,848 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,849 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,849 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,862 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,863 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,864 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,868 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,872 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,872 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,877 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,878 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,878 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,891 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,892 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,892 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,897 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,901 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,901 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,902 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,902 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,906 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,907 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,907 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,917 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,918 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,918 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,922 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,927 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,927 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,931 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,932 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,932 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,942 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,943 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,944 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,948 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,951 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:00,952 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:00,953 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,953 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,957 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,958 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,958 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,968 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,969 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,969 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,973 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,977 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:00,977 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:00,981 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,982 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:00,982 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:00,992 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:00,993 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:00,993 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:00,997 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,002 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,002 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,006 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,007 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,007 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,017 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,018 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,018 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,022 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,026 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,027 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,028 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,028 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,038 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,039 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,039 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,043 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,044 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,044 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,049 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,053 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,053 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,054 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,054 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,071 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,072 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,072 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,077 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,078 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,078 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,088 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,093 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,093 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,097 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,099 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,099 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,105 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,107 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,107 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,112 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,118 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,119 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,133 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,135 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,135 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,141 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,143 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,143 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,149 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,154 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,154 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,155 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,155 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,167 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,168 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,168 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,173 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,175 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,176 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,180 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,187 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,187 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,200 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,201 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,202 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,207 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,209 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,209 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,219 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,225 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,225 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,226 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,226 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,231 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,232 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,232 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,236 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,237 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,238 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,248 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,255 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,256 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,257 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,257 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,263 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,264 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,265 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,270 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,271 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,271 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,282 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,289 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,289 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,295 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,296 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,297 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,302 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,304 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,304 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,316 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,321 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,322 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,328 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,329 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,330 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,335 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,337 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,338 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,349 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,356 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,356 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,357 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,358 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,366 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,368 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,369 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,379 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,382 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,382 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,396 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,404 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,405 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,407 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,408 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,415 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,417 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,417 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,423 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,426 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,427 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,438 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,444 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,444 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,454 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,456 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,456 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,461 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,463 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,463 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,468 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,476 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,476 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,477 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,477 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,489 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,491 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,491 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,510 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,512 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,512 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,524 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,529 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,530 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,535 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,536 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,536 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,542 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,544 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,544 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,554 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,561 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,561 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,562 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,562 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,568 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,570 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,570 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,581 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,583 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,583 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,587 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,591 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,592 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,596 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,597 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,597 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,607 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,608 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,609 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,614 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,618 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,618 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,623 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,624 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,624 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,634 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,635 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,635 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,639 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,643 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,643 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,644 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,644 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,648 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,649 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,650 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,659 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,661 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,661 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,665 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,669 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,669 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,673 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,675 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,675 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,685 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,686 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,686 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,690 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,694 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,694 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,694 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,694 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,699 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,700 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,700 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,710 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,711 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,711 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,715 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,719 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,719 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,729 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,730 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,731 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,735 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,736 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,736 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,740 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,745 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,746 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,747 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,747 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,757 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,758 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,759 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,763 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,764 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,765 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,769 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,772 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,773 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,790 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,791 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,791 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,801 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,802 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,802 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,813 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,816 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,816 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,817 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,817 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,821 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,822 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,822 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,826 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,827 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,827 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,837 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,841 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,841 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,845 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,846 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,846 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,850 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,851 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,851 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,861 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,864 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,864 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,865 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,865 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,869 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,870 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,870 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,874 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,875 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,875 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,885 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,891 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,891 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,902 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,903 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,903 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,908 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,910 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,910 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,915 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,920 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,920 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,921 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,921 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,931 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,932 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,932 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,936 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,937 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,937 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,941 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,945 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,945 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,955 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,956 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,956 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,960 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,961 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,962 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,966 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,969 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:01,969 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:01,970 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,970 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:01,980 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,981 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:01,981 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:01,986 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,987 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:01,987 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:01,992 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:01,998 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:01,998 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,009 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,010 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,010 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,015 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,017 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,017 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,022 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,028 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,028 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,039 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,040 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,040 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,045 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,047 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,047 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,052 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,057 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,058 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,059 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,060 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,070 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,071 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,072 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,077 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,078 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,079 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,089 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,094 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,094 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,095 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,096 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,100 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,101 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,102 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,113 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,115 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,115 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,120 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,124 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,124 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,128 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,130 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,130 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,140 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,141 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,142 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,148 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,152 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,153 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,155 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,155 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,166 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,168 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,168 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,173 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,175 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,176 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,180 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,184 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,185 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,194 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,196 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,196 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,201 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,203 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,203 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,209 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,213 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,213 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,214 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,215 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,226 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,227 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,228 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,232 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,234 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,234 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,240 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,247 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,247 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,257 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,258 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,259 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,269 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,270 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,271 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,276 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,286 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,287 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,288 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,288 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,297 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,298 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,299 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,310 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,312 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,312 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,319 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,327 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,328 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,335 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,336 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,337 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,349 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,351 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,351 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,357 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,368 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,369 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,370 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,371 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,378 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,379 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,379 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,395 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,397 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,397 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,418 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,423 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,424 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,429 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,430 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,431 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,442 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,444 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,444 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,450 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,458 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,459 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,460 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,461 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,465 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,467 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,468 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,482 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,483 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,484 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,489 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,498 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,499 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,506 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,508 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,508 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,520 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,522 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,522 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,532 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,536 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,536 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,537 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,537 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,548 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,549 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,549 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,554 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,556 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,556 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,561 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,565 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,565 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,575 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,576 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,576 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,580 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,581 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,581 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,585 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,590 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,590 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,590 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,591 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,600 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,601 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,601 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,605 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,606 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,606 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,610 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,614 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,614 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,618 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,620 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,620 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,629 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,630 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,631 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,635 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,638 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,639 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,643 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,644 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,644 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,654 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,655 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,655 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,659 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,663 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,663 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,667 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,668 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,668 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,678 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,679 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,679 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,683 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,687 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,687 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,691 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,692 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,692 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,702 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,703 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,703 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,707 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,711 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,711 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,717 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,719 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,719 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,729 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,731 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,731 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,739 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,746 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:02,746 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied load_002.css to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_002.css
2014-05-02 14:41:02,751 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:02,754 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,754 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,765 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,766 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,766 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,773 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,776 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,776 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,787 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,794 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,794 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,801 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,803 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,803 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,809 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,810 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,810 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,821 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,828 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,828 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,832 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,833 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,833 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,837 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,838 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,838 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,849 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,859 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,859 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,865 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,866 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,867 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,876 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,877 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,877 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,888 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,898 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,898 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,899 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,899 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,904 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,905 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,905 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,909 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,910 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,910 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,921 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,933 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,933 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,939 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,940 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,941 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,945 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,946 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,947 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,957 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,962 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:02,962 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:02,963 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,964 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,968 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,969 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,970 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:02,974 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,976 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:02,976 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:02,986 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,992 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:02,992 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:02,997 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:02,998 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:02,998 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,003 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,004 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,004 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,013 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,017 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:03,017 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:03,018 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,018 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,022 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,023 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,023 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,027 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,028 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,029 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,038 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,042 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,042 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,046 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,047 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,047 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,051 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,052 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,052 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,062 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,065 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,066 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,070 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,071 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,071 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,075 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,076 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,076 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,086 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,090 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,090 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,095 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,111 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,111 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,115 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,117 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,117 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,127 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,131 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,132 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,141 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,142 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,143 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,147 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,149 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,149 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,161 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,165 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,165 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,176 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,177 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,177 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,182 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,183 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,183 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,189 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,195 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,195 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,206 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,207 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,208 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,213 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,214 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,215 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,219 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,225 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:03,226 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:03,227 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,227 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,239 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,241 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,241 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,247 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,248 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,248 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,258 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,263 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,264 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,268 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,269 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,269 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,275 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,277 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,277 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,295 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,301 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:03,302 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:03,303 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,303 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,308 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,310 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,310 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,315 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,317 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,317 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,327 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,331 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,331 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,336 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,337 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,337 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,343 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,344 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,344 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,354 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,361 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.cookie-policy = rfc2109
2014-05-02 14:41:03,361 [DEBUG] o.a.c.h.params.DefaultHttpParams - Set parameter http.protocol.single-cookie-header = true
2014-05-02 14:41:03,362 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,363 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,367 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,368 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,369 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,373 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,375 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,376 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,389 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,394 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,394 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,398 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,399 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,399 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,403 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,404 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,404 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,413 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,418 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:03,418 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied load_003.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_003.php
2014-05-02 14:41:03,422 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,427 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:03,427 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied load_002.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_002.php
2014-05-02 14:41:03,431 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,436 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:03,436 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied load_004.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_004.php
2014-05-02 14:41:03,446 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,449 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,449 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,453 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,454 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,454 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,464 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,465 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,465 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,470 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,473 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.1
2014-05-02 14:41:03,474 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message:Making directory:
2014-05-02 14:41:03,478 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:182:37:Message step !1.14.21.5.5.1.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,479 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.5.1.2.2.1
2014-05-02 14:41:03,479 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message:Could not create directory:
2014-05-02 14:41:03,488 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:194:45:Message step !1.14.21.5.5.1.2.2.1 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,489 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.2.3
2014-05-02 14:41:03,489 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message:Copy error:
2014-05-02 14:41:03,493 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:251:37:Message step !1.14.21.5.6.2.3 read bundle://27.0:1/xml/xproc/fileset-store.xpl
2014-05-02 14:41:03,498 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:03,498 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied 20px-I.png to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/20px-I.png
2014-05-02 14:41:03,505 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,510 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:03,510 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied index_004.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_004.php
2014-05-02 14:41:03,519 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,524 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.5.6.1.4
2014-05-02 14:41:03,524 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message:Copied load_005.php to EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_005.php
2014-05-02 14:41:03,528 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:236:37:Message step !1.14.21.5.6.1.4 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,531 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.2.2
2014-05-02 14:41:03,531 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message:Writing in-memory document to EPUB/package.opf
2014-05-02 14:41:03,535 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message step !1.14.21.2.2 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,542 [DEBUG] o.d.c.xproc.calabash.steps.Message - Running {http://xmlcalabash.com/ns/extensions}message !1.14.21.2.2
2014-05-02 14:41:03,543 [INFO ] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message:Writing in-memory document to META-INF/container.xml
2014-05-02 14:41:03,552 [DEBUG] o.d.c.xproc.calabash.steps.Message - bundle://27.0:1/xml/xproc/fileset-store.xpl:73:29:Message step !1.14.21.2.2 read file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/
2014-05-02 14:41:03,635 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/META-INF/container.xml path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/META-INF/container.xml']
2014-05-02 14:41:03,635 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/mimetype path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/mimetype']
2014-05-02 14:41:03,635 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/package.opf path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/package.opf']
2014-05-02 14:41:03,635 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/toc.xhtml path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/toc.xhtml']
2014-05-02 14:41:03,635 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Den_syntetiske_stemmen_Brage_-_Wikipedia.xhtml path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Den_syntetiske_stemmen_Brage_-_Wikipedia.xhtml']
2014-05-02 14:41:03,635 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_002.css path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_002.css']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_007.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_007.php']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_003.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_003.php']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_006.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_006.php']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_004.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_004.php']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_003.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_003.php']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/20px-I.png path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/20px-I.png']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_002.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_002.php']
2014-05-02 14:41:03,636 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/checkLoggedIn path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/checkLoggedIn']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_004.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_004.php']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_005.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_005.php']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_002.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index_002.php']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/index.php']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_005.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load_005.php']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load.css path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load.css']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load.php path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/epub/EPUB/Filer_for_Den_syntetiske_stemmen_Brage_-_Wikipedia/load.php']
2014-05-02 14:41:03,637 [DEBUG] org.daisy.pipeline.job.ResultSet - Adding result JobResult [#=output-dir/Den%20syntetiske%20stemmen%20Brage%20-%20Wikipedia.epub path='file:/home/jostein/Skrivebord/daisy-pipeline/data/jobs/09d64df4-5e80-493c-9782-75dc8d22092d/output/output-dir/Den%20syntetiske%20stemmen%20Brage%20-%20Wikipedia.epub']
2014-05-02 14:41:03,638 [INFO ] org.daisy.pipeline.job.Job - Changing job status to: DONE
2014-05-02 14:41:03,639 [DEBUG] org.daisy.pipeline.push.Poster - Status changed 09d64df4-5e80-493c-9782-75dc8d22092d->DONE
2014-05-02 14:41:03,639 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:41:03,639 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:41:03,640 [DEBUG] o.d.p.p.jobs.PersistentJobContext - Post loading jobcontext
2014-05-02 14:41:03,640 [DEBUG] o.d.pipeline.job.JobContextFactory - configuring object org.daisy.pipeline.persistence.jobs.PersistentJobContext@1c94f74d
2014-05-02 14:41:03,640 [DEBUG] o.d.pipeline.job.JobContextFactory - configuring object org.daisy.pipeline.persistence.jobs.PersistentJobContext@1c94f74d
2014-05-02 14:41:03,640 [INFO ] o.d.p.persistence.jobs.PersistentJob - Changing Status:DONE
2014-05-02 14:41:03,640 [DEBUG] o.d.p.persistence.jobs.PersistentJob - Updating object
2014-05-02 14:41:03,641 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:41:03,641 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:41:03,641 [DEBUG] o.d.p.p.jobs.PersistentJobContext - Post loading jobcontext
2014-05-02 14:41:03,641 [DEBUG] o.d.pipeline.job.JobContextFactory - configuring object org.daisy.pipeline.persistence.jobs.PersistentJobContext@6d08e416
2014-05-02 14:41:03,641 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
2014-05-02 14:41:03,710 [DEBUG] o.d.p.p.jobs.PersistentJobContext - load script XProcScript[name=HTML to EPUB3]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment