Skip to content

Instantly share code, notes, and snippets.

@alexcurtis
Created October 27, 2015 17:16
Show Gist options
  • Save alexcurtis/049dd811dd1c8dbceb3f to your computer and use it in GitHub Desktop.
Save alexcurtis/049dd811dd1c8dbceb3f to your computer and use it in GitHub Desktop.
Pencilblue plugin Install Issue -- Silly Log
2015-10-27T17:13:16.710Z - silly: [1] New Request: 40d06ace-2082-41b8-99d4-772f288cff21
2015-10-27T17:13:16.712Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:16.712Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:16.712Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:16.713Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.713Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.713Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.713Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.713Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.714Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.714Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.714Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/navigation/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.714Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/navigation/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.714Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/topics/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.714Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/topics/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.714Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/articles/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.715Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/articles/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.715Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/pages/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.715Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/pages/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.715Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/media/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.715Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/media/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.715Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/comments/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.716Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/objects/types/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.716Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/objects/types/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.716Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/objects/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.716Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/objects/[^/]+/sort[/]{0,1}$] Result [false]
2015-10-27T17:13:16.716Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/objects/[^/]+/new[/]{0,1}$] Result [false]
2015-10-27T17:13:16.717Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/content/objects/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.717Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/objects/[^/]+/sort[/]{0,1}$] Result [false]
2015-10-27T17:13:16.717Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/objects/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.718Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/actions/admin/content/objects/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.718Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/plugins/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.718Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/admin/plugins/[^/]+/settings[/]{0,1}$] Result [false]
2015-10-27T17:13:16.718Z - silly: [1] RequestHandler: Comparing Path [/api/plugins/install/wp_import] to Pattern [^/api/plugins/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:16.718Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:16.720Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/plugins/install/wp_import]
2015-10-27T17:13:16.721Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:16.722Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:16.722Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:16.722Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:16.722Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:16.722Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:16.725Z - debug: [1] Response Time: 15ms URL=[POST]/api/plugins/install/wp_import CODE=200
2015-10-27T17:13:16.729Z - debug: [1] INSTALL_PLUGIN_wp_import: Verifying that plugin wp_import is not already installed
2015-10-27T17:13:16.729Z - warn: [1] PencilBlue: pb.plugins is deprecated. Use new pb.PluginService instead
2015-10-27T17:13:16.740Z - debug: [1] IS_AVAILABLE_wp_import: Updating job [11659b11-e446-49ff-878c-557c5cbbe0f0:IS_AVAILABLE_wp_import] by 0 percent with status:
2015-10-27T17:13:16.742Z - silly: [1] RedisCommandBroker: Sending command [192.168.1.5:8080:1:localhost.local]{"jobId":"11659b11-e446-49ff-878c-557c5cbbe0f0","pluginUid":"wp_import","to":"192.168.1.5:8080:1:localhost.local","from":"192.168.1.5:8080:1:localhost.local","type":"is_plugin_available","date":"2015-10-27T17:13:16.742Z","id":"01e874e2-1922-4c48-a18f-55e2c5575c92"}
2015-10-27T17:13:16.772Z - silly: [1] New Request: 1d2aeca5-40a8-45e7-811a-6b3b5299b909
2015-10-27T17:13:16.775Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:16.775Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:16.775Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:16.775Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.775Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.775Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.777Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.778Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.779Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.780Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:16.781Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:16.790Z - silly: [1] RedisCommandBroker: Command recieved [192.168.1.5:8080:1:localhost.local]{"jobId":"11659b11-e446-49ff-878c-557c5cbbe0f0","pluginUid":"wp_import","to":"192.168.1.5:8080:1:localhost.local","from":"192.168.1.5:8080:1:localhost.local","type":"is_plugin_available","date":"2015-10-27T17:13:16.742Z","id":"01e874e2-1922-4c48-a18f-55e2c5575c92"}
2015-10-27T17:13:16.795Z - debug: [1] IS_AVAILABLE_wp_import: Inspecting plugin on disk at: pencil-scrub/plugins/wp_import/details.json
2015-10-27T17:13:16.796Z - silly: [1] New Request: 4d11d7d3-12bf-4ed3-a488-4bbe8c824a80
2015-10-27T17:13:16.798Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:16.798Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:16.802Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:16.804Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:16.804Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:16.804Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.804Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.805Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.806Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.807Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.807Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:16.807Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:16.815Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:16.839Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:16.839Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:16.839Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:16.839Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:16.839Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:16.842Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:16.842Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:16.844Z - silly: [1] RedisCommandBroker: Sending command [192.168.1.5:8080:1:localhost.local]{"result":true,"id":"38982690-9c38-4f77-9a4d-fa0759ae55ea","to":"192.168.1.5:8080:1:localhost.local","replyTo":"01e874e2-1922-4c48-a18f-55e2c5575c92","from":"192.168.1.5:8080:1:localhost.local","type":"is_plugin_available","date":"2015-10-27T17:13:16.844Z"}
2015-10-27T17:13:16.844Z - debug: [1] Response Time: 72ms URL=[POST]/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:16.870Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:16.870Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:16.870Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:16.870Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:16.870Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:16.888Z - debug: [1] Response Time: 92ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:16.910Z - silly: [1] RedisCommandBroker: Command recieved [192.168.1.5:8080:1:localhost.local]{"result":true,"id":"38982690-9c38-4f77-9a4d-fa0759ae55ea","to":"192.168.1.5:8080:1:localhost.local","replyTo":"01e874e2-1922-4c48-a18f-55e2c5575c92","from":"192.168.1.5:8080:1:localhost.local","type":"is_plugin_available","date":"2015-10-27T17:13:16.844Z"}
2015-10-27T17:13:16.930Z - debug: [1] INSTALL_PLUGIN_wp_import: Loading details file for install persistence operations from: pencil-scrub/plugins/wp_import/details.json
2015-10-27T17:13:16.945Z - debug: [1] INSTALL_PLUGIN_wp_import: Setting system install flags for wp_import
2015-10-27T17:13:16.947Z - debug: [1] INSTALL_PLUGIN_wp_import: Adding settings for wp_import
2015-10-27T17:13:16.948Z - warn: [1] PencilBlue: pb.plugins is deprecated. Use new pb.PluginService instead
2015-10-27T17:13:16.998Z - debug: [1] INSTALL_PLUGIN_wp_import: Executing wp_import 'onInstall' function
2015-10-27T17:13:16.998Z - debug: [1] INSTALL_PLUGIN_wp_import: Updating job [11659b11-e446-49ff-878c-557c5cbbe0f0:INSTALL_PLUGIN_wp_import] by 25 percent with status:
2015-10-27T17:13:17.013Z - debug: [1] INITIALIZE_PLUGIN_wp_import: Updating job [11659b11-e446-49ff-878c-557c5cbbe0f0:INITIALIZE_PLUGIN_wp_import] by 0 percent with status:
2015-10-27T17:13:17.014Z - silly: [1] RedisCommandBroker: Sending command [192.168.1.5:8080:1:localhost.local]{"jobId":"11659b11-e446-49ff-878c-557c5cbbe0f0","pluginUid":"wp_import","to":"192.168.1.5:8080:1:localhost.local","from":"192.168.1.5:8080:1:localhost.local","type":"initialize_plugin","date":"2015-10-27T17:13:17.014Z","id":"3cac19b9-06d0-47a0-9e13-0ee727614c48"}
2015-10-27T17:13:17.055Z - silly: [1] RedisCommandBroker: Command recieved [192.168.1.5:8080:1:localhost.local]{"jobId":"11659b11-e446-49ff-878c-557c5cbbe0f0","pluginUid":"wp_import","to":"192.168.1.5:8080:1:localhost.local","from":"192.168.1.5:8080:1:localhost.local","type":"initialize_plugin","date":"2015-10-27T17:13:17.014Z","id":"3cac19b9-06d0-47a0-9e13-0ee727614c48"}
2015-10-27T17:13:17.057Z - warn: [1] PencilBlue: pb.plugins is deprecated. Use new pb.PluginService instead
2015-10-27T17:13:17.058Z - debug: [1] INITIALIZE_PLUGIN_wp_import: Initializing plugin wp_import
2015-10-27T17:13:17.058Z - warn: [1] PencilBlue: pb.plugins is deprecated. Use new pb.PluginService instead
2015-10-27T17:13:17.059Z - debug: [1] PluginService:[INIT] Beginning initialization of WordPress Import (wp_import)
2015-10-27T17:13:17.059Z - debug: [1] PluginService:[INIT] Attempting to load details.json file for WordPress Import
2015-10-27T17:13:17.060Z - debug: [1] PluginService:[INIT] Validating details of WordPress Import
2015-10-27T17:13:17.062Z - warn: [1] PluginService: Plugin WordPress Import is missing dependency xml2js
2015-10-27T17:13:17.071Z - silly: [1] PluginService: Dependency check failed for plugin WordPress Import
2015-10-27T17:13:17.098Z - warn: [1] PluginService: Plugin WordPress Import is missing dependency xml2js
2015-10-27T17:13:17.098Z - silly: [1] PluginService: Installing dependencies for wp_import.
2015-10-27T17:13:17.897Z - silly: [1] New Request: 8504a88b-650a-431a-ba15-565a67a9295c
2015-10-27T17:13:17.978Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:17.978Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:17.978Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:17.978Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:17.979Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:17.979Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:17.979Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:17.979Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:17.979Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:17.979Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:17.979Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:17.980Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:17.980Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:17.981Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:17.981Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:17.981Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:17.982Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:17.982Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:17.991Z - debug: [1] Response Time: 94ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:18.856Z - silly: [1] New Request: 0180324f-df11-49cd-ac0c-4711a1fc1b0f
2015-10-27T17:13:18.857Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:18.857Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:18.857Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:18.857Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:18.857Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:18.857Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:18.858Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:18.858Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:18.858Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:18.858Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:18.858Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:18.859Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:18.859Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:18.860Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:18.860Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:18.860Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:18.860Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:18.861Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:18.863Z - debug: [1] Response Time: 7ms URL=[POST]/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:18.996Z - silly: [1] New Request: 2e355be4-603e-4105-a0b3-c1468fb5602e
2015-10-27T17:13:18.997Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:18.997Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:18.998Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:19.000Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:19.001Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:19.001Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:19.001Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:19.001Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:19.001Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:19.002Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:19.002Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:19.003Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:19.003Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:19.004Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:19.004Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:19.004Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:19.005Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:19.005Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:19.006Z - debug: [1] Response Time: 10ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:20.039Z - silly: [1] New Request: d64cb740-314a-44e5-b129-c5fa33b9c1dd
2015-10-27T17:13:20.041Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:20.042Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:20.042Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:20.043Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.043Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.043Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.043Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.043Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.043Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.043Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:20.044Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:20.044Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:20.045Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:20.045Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:20.045Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:20.046Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:20.046Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:20.046Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:20.049Z - debug: [1] Response Time: 9ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:20.912Z - silly: [1] New Request: 4bbdeb53-3b3c-4c8d-bb4e-5e01edb67471
2015-10-27T17:13:20.913Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:20.913Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:20.913Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:20.913Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.913Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.914Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.914Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.914Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.914Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:20.914Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:20.915Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:20.918Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:20.918Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:20.920Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:20.920Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:20.920Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:20.920Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:20.921Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:20.922Z - debug: [1] Response Time: 10ms URL=[POST]/api/jobs/getLogs/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:21.072Z - silly: [1] New Request: c2833878-c291-4617-a532-274526022967
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:21.074Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:21.075Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:21.075Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:21.076Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:21.076Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:21.077Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:21.077Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:21.077Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:21.077Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:21.077Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:21.079Z - debug: [1] Response Time: 7ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:22.095Z - silly: [1] New Request: 7a79681b-8bf6-4956-8993-08aa441e2857
2015-10-27T17:13:22.096Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:22.096Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:22.097Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:22.097Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:22.097Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:22.097Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:22.098Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:22.098Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:22.098Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:22.099Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:22.099Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:22.101Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:22.101Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:22.102Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:22.102Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:22.102Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:22.103Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:22.103Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:22.104Z - debug: [1] Response Time: 9ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:23.269Z - silly: [1] New Request: 72040385-613e-462d-8b28-6c8af3d9e50e
2015-10-27T17:13:23.272Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:23.272Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:23.273Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:23.273Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:23.273Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:23.273Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:23.274Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:23.274Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:23.274Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:23.274Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:23.276Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:23.277Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:23.277Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:23.278Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:23.278Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:23.278Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:23.278Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:23.278Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:23.284Z - debug: [1] Response Time: 14ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
[email protected] plugins/wp_import/node_modules/xml2js
├── [email protected]
└── [email protected] ([email protected])
2015-10-27T17:13:23.639Z - debug: [1] PluginService:[INIT] Loading permission sets for plugin [wp_import]
2015-10-27T17:13:23.639Z - debug: [1] PluginService:[INIT] Attempting to call onStartup function for wp_import.
2015-10-27T17:13:23.640Z - debug: [1] PluginService:[INIT] Plugin wp_import onStartup returned with result: true
2015-10-27T17:13:23.643Z - debug: [1] PluginService: Attempting to load service [pencil-scrub/plugins/wp_import/services/wp_xml_parse.js]
2015-10-27T17:13:24.042Z - debug: [1] PluginService: Initializing service [pencil-scrub/plugins/wp_import/services/wp_xml_parse.js]
2015-10-27T17:13:24.043Z - debug: [1] WPXMLParseService: Initialized
2015-10-27T17:13:24.050Z - debug: [1] RequestHander: Registered Static Route - Theme [wp_import] Path [GET][/admin/plugins/wp_import/settings/import]
2015-10-27T17:13:24.053Z - debug: [1] RequestHander: Registered Static Route - Theme [wp_import] Path [POST][/actions/admin/plugins/wp_import/settings/import]
2015-10-27T17:13:24.055Z - debug: [1] RequestHander: Registered Static Route - Theme [wp_import] Path [GET][/admin/plugins/wp_import/settings/manage_new_users]
2015-10-27T17:13:24.056Z - debug: [1] RequestHander: Registered Static Route - Theme [wp_import] Path [POST][/actions/admin/plugins/wp_import/settings/manage_new_users]
2015-10-27T17:13:24.066Z - debug: [1] PluginService:[wp_import] Registering localizations for locale [en-us]
2015-10-27T17:13:24.067Z - debug: [1] PluginService:[wp_import] Registering localizations for locale [ro-ro]
2015-10-27T17:13:24.068Z - info: [1] PluginService: Initialized plugin WordPress Import (wp_import). RESULT=[true]
2015-10-27T17:13:24.069Z - debug: [1] INITIALIZE_PLUGIN_wp_import: Completed initialization RESULT=[true] ERROR=[n/a]
2015-10-27T17:13:24.069Z - silly: [1] RedisCommandBroker: Sending command [192.168.1.5:8080:1:localhost.local]{"result":true,"id":"652b7b2a-5f17-43e6-b21e-19baf1a9470c","to":"192.168.1.5:8080:1:localhost.local","replyTo":"3cac19b9-06d0-47a0-9e13-0ee727614c48","from":"192.168.1.5:8080:1:localhost.local","type":"initialize_plugin","date":"2015-10-27T17:13:24.069Z"}
2015-10-27T17:13:24.137Z - silly: [1] RedisCommandBroker: Command recieved [192.168.1.5:8080:1:localhost.local]{"result":true,"id":"652b7b2a-5f17-43e6-b21e-19baf1a9470c","to":"192.168.1.5:8080:1:localhost.local","replyTo":"3cac19b9-06d0-47a0-9e13-0ee727614c48","from":"192.168.1.5:8080:1:localhost.local","type":"initialize_plugin","date":"2015-10-27T17:13:24.069Z"}
2015-10-27T17:13:24.138Z - warn: [1] CommandService: The command was in reply to [3cac19b9-06d0-47a0-9e13-0ee727614c48] but no callback was registered. Skipping.
2015-10-27T17:13:24.291Z - silly: [1] New Request: ea0e2659-f0ed-49f0-999f-84b939260311
2015-10-27T17:13:24.292Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:24.293Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:24.293Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:24.293Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:24.293Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:24.293Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:24.293Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:24.294Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:24.294Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:24.294Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:24.294Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:24.294Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:24.295Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:24.299Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:24.300Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:24.300Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:24.300Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:24.301Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:24.309Z - debug: [1] Response Time: 18ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:25.318Z - silly: [1] New Request: 8d175454-650e-4638-b6c4-47a0c4162058
2015-10-27T17:13:25.319Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:25.319Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:25.319Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:25.319Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:25.319Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:25.320Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:25.320Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:25.320Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:25.320Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:25.320Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:25.320Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:25.321Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:25.321Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:25.321Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:25.321Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:25.322Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:25.322Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:25.322Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:25.323Z - debug: [1] Response Time: 5ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:26.336Z - silly: [1] New Request: 5b05d8c9-2b6f-4ee3-a8cb-8b9b9d463116
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:26.337Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:26.338Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:26.338Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:26.338Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:26.338Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:26.339Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:26.339Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:26.339Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:26.339Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:26.339Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:26.342Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:27.354Z - silly: [1] New Request: 47b9d919-f415-4977-98a9-5aa5ba1b81c9
2015-10-27T17:13:27.356Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:27.356Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:27.357Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:27.357Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:27.358Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:27.358Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:27.359Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:27.359Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:27.359Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:27.359Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:27.359Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:27.361Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:28.374Z - silly: [1] New Request: f55eeb08-21d2-4a4e-99f5-00b0c90d92b5
2015-10-27T17:13:28.375Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:28.375Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:28.375Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:28.375Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:28.375Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:28.376Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:28.376Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:28.376Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:28.376Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:28.376Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:28.376Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:28.378Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:28.378Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:28.380Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:28.381Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:28.381Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:28.381Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:28.381Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:28.382Z - debug: [1] Response Time: 8ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:29.392Z - silly: [1] New Request: b4412721-9b3a-461a-a35e-5347eaa1035f
2015-10-27T17:13:29.400Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:29.400Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:29.400Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:29.400Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:29.400Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:29.400Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:29.401Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:29.401Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:29.401Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:29.401Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:29.401Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:29.402Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:29.403Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:29.404Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:29.405Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:29.405Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:29.405Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:29.405Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:29.414Z - debug: [1] Response Time: 22ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:30.433Z - silly: [1] New Request: 5c3ee9b8-12af-4144-8f11-cd4c6906dd20
2015-10-27T17:13:30.434Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:30.435Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:30.435Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:30.435Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:30.435Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:30.435Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:30.435Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:30.436Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:30.436Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:30.436Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:30.436Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:30.437Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:30.437Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:30.438Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:30.438Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:30.438Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:30.438Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:30.438Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:30.439Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:31.450Z - silly: [1] New Request: f9ba17a2-bec3-4cf8-a692-ffa941da0d0a
2015-10-27T17:13:31.452Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:31.452Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:31.452Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:31.452Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:31.452Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:31.452Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:31.453Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:31.453Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:31.453Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:31.453Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:31.453Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:31.454Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:31.454Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:31.455Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:31.456Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:31.456Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:31.456Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:31.456Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:31.458Z - debug: [1] Response Time: 8ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:32.480Z - silly: [1] New Request: 3e675b0e-8cb3-4e0f-a99e-57899988bfd1
2015-10-27T17:13:32.482Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:32.482Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:32.482Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:32.482Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:32.482Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:32.482Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:32.483Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:32.483Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:32.483Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:32.483Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:32.483Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:32.484Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:32.484Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:32.484Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:32.484Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:32.484Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:32.485Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:32.485Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:32.486Z - debug: [1] Response Time: 5ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:33.498Z - silly: [1] New Request: 4170da7f-b7e7-40c5-a139-6c327f9bb568
2015-10-27T17:13:33.499Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:33.499Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:33.499Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:33.499Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:33.500Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:33.500Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:33.500Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:33.500Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:33.500Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:33.500Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:33.500Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:33.501Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:33.501Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:33.502Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:33.502Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:33.502Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:33.502Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:33.502Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:33.504Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:34.544Z - silly: [1] New Request: 7557a589-b406-445f-aa31-367d02ce88ab
2015-10-27T17:13:34.560Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:34.561Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:34.561Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:34.561Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:34.561Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:34.561Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:34.562Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:34.562Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:34.562Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:34.562Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:34.563Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:34.585Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:34.585Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:34.594Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:34.595Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:34.595Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:34.595Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:34.595Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:34.597Z - debug: [1] Response Time: 53ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:35.613Z - silly: [1] New Request: 8dbae206-0a48-4df0-9695-fa50e9c88869
2015-10-27T17:13:35.614Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:35.615Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:35.616Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:35.616Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:35.616Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:35.617Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:35.617Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:35.617Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:35.617Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:35.618Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:35.619Z - debug: [1] Response Time: 5ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:36.628Z - silly: [1] New Request: cecbb79e-d3e8-4a59-82a1-b62ea8e1dfee
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:36.630Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:36.631Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:36.631Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:36.631Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:36.631Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:36.632Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:36.632Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:36.632Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:36.632Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:36.632Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:36.633Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:36.634Z - debug: [1] Response Time: 5ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:37.647Z - silly: [1] New Request: 0369abaa-8641-4159-b37b-02e8e15c6555
2015-10-27T17:13:37.648Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:37.649Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:37.649Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:37.649Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:37.649Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:37.649Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:37.650Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:37.650Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:37.650Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:37.650Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:37.650Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:37.651Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:37.652Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:37.652Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:37.652Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:37.652Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:37.653Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:37.653Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:37.654Z - debug: [1] Response Time: 7ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:38.666Z - silly: [1] New Request: c8ed3a8f-6c3b-4a7e-aea5-f374a3db31d5
2015-10-27T17:13:38.667Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:38.667Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:38.667Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:38.667Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:38.668Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:38.668Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:38.668Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:38.668Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:38.668Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:38.668Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:38.668Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:38.669Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:38.669Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:38.670Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:38.670Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:38.671Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:38.671Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:38.671Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:38.674Z - debug: [1] Response Time: 8ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:39.686Z - silly: [1] New Request: 2c233a21-f2a8-435d-997f-4e708e9042af
2015-10-27T17:13:39.687Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:39.687Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:39.687Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:39.687Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:39.687Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:39.687Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:39.687Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:39.688Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:39.688Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:39.688Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:39.688Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:39.689Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:39.689Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:39.693Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:39.693Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:39.693Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:39.693Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:39.694Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:39.695Z - debug: [1] Response Time: 9ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:40.702Z - silly: [1] New Request: f0714179-fb0c-4510-b9c3-422fb329cd5e
2015-10-27T17:13:40.703Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:40.704Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:40.705Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:40.705Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:40.707Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:40.708Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:40.709Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:40.709Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:40.709Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:40.709Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:40.709Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:40.711Z - debug: [1] Response Time: 8ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:41.719Z - silly: [1] New Request: 8b3816d7-8978-4889-96a0-9c83044dde5a
2015-10-27T17:13:41.720Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:41.720Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:41.720Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:41.720Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:41.721Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:41.721Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:41.721Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:41.721Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:41.721Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:41.721Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:41.721Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:41.724Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:41.725Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:41.726Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:41.726Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:41.726Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:41.726Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:41.726Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:41.727Z - debug: [1] Response Time: 8ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:42.744Z - silly: [1] New Request: f23099e3-b45e-4dba-b033-d3295c4e0ade
2015-10-27T17:13:42.748Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:42.748Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:42.749Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:42.749Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:42.749Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:42.749Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:42.749Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:42.749Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:42.750Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:42.750Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:42.750Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:42.751Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:42.751Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:42.752Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:42.752Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:42.752Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:42.752Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:42.752Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:42.753Z - debug: [1] Response Time: 9ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:43.762Z - silly: [1] New Request: c000cd2f-f47a-4cd5-80ae-0eaedbd7a9d8
2015-10-27T17:13:43.764Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:43.764Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:43.764Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:43.764Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:43.765Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:43.765Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:43.765Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:43.765Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:43.766Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:43.766Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:43.766Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:43.767Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:43.767Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:43.768Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:43.768Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:43.768Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:43.768Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:43.768Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:43.769Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:44.781Z - silly: [1] New Request: 421b807e-d6c7-4b96-a666-673b50b6b1d9
2015-10-27T17:13:44.782Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:44.782Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:44.782Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:44.782Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:44.782Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:44.783Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:44.783Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:44.783Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:44.783Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:44.783Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:44.783Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:44.784Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:44.784Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:44.785Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:44.785Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:44.785Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:44.785Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:44.786Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:44.789Z - debug: [1] Response Time: 8ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:45.807Z - silly: [1] New Request: 6f006500-5f04-4a05-b63b-e8819dab2443
2015-10-27T17:13:45.809Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:45.809Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:45.809Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:45.810Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:45.810Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:45.810Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:45.810Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:45.810Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:45.810Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:45.811Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:45.811Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:45.811Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:45.812Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:45.812Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:45.812Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:45.812Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:45.813Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:45.813Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:45.814Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:46.830Z - silly: [1] New Request: cbd435d5-11d8-4e2d-82a7-4c9fb4191a47
2015-10-27T17:13:46.832Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:46.832Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:46.832Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:46.832Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:46.833Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:46.833Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:46.834Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:46.834Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:46.834Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:46.834Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:46.834Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:46.840Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:46.840Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:46.844Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:46.844Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:46.845Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:46.845Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:46.845Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:46.849Z - debug: [1] Response Time: 19ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:47.859Z - silly: [1] New Request: 0a29ffd4-9a83-4153-90b1-c7f80c046066
2015-10-27T17:13:47.860Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:47.860Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:47.860Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:47.860Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:47.860Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:47.860Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:47.860Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:47.861Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:47.861Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:47.861Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:47.861Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:47.862Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:47.862Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:47.862Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:47.862Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:47.863Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:47.863Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:47.863Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:47.865Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:48.875Z - silly: [1] New Request: f2ccb181-8a31-483c-b665-be5318ff3500
2015-10-27T17:13:48.877Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:48.877Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:48.877Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:48.877Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:48.877Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:48.878Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:48.878Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:48.878Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:48.878Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:48.878Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:48.878Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:48.879Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:48.879Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:48.880Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:48.880Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:48.880Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:48.880Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:48.880Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:48.882Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:49.892Z - silly: [1] New Request: ef59d0ab-ede2-4eb3-b095-885c72ffcd23
2015-10-27T17:13:49.893Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:49.893Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:49.893Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:49.893Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:49.894Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:49.894Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:49.894Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:49.894Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:49.894Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:49.894Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:49.894Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:49.895Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:49.895Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:49.896Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:49.896Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:49.896Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:49.896Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:49.897Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:49.898Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:50.914Z - silly: [1] New Request: 563a8b89-5fdd-4325-9f5c-017d5704c406
2015-10-27T17:13:50.915Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:50.915Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:50.915Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:50.915Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:50.916Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:50.916Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:50.916Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:50.916Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:50.917Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:50.917Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:50.918Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:50.919Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:50.919Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:50.923Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:50.923Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:50.924Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:50.924Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:50.924Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:50.928Z - debug: [1] Response Time: 14ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:51.943Z - silly: [1] New Request: 13ec9ca2-3d86-4488-b0e4-b45669e3019f
2015-10-27T17:13:51.944Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:51.944Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:51.944Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:51.944Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:51.944Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:51.945Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:51.945Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:51.945Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:51.945Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:51.945Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:51.945Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:51.946Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:51.946Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:51.948Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:51.948Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:51.948Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:51.948Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:51.948Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:51.950Z - debug: [1] Response Time: 7ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:52.962Z - silly: [1] New Request: c9d648b0-b9a8-4e9a-8abd-433b478858f3
2015-10-27T17:13:52.963Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:52.963Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:52.964Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:52.964Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:52.964Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:52.964Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:52.964Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:52.965Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:52.965Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:52.965Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:52.965Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:52.966Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:52.966Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:52.968Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:52.969Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:52.969Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:52.969Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:52.969Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:52.971Z - debug: [1] Response Time: 8ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:53.982Z - silly: [1] New Request: c23f1db0-7293-48dd-b9bd-23c30d34f3a7
2015-10-27T17:13:53.990Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:53.990Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:53.991Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:53.992Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:53.993Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:53.993Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:53.994Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:53.994Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:53.994Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:53.994Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:53.994Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:53.996Z - debug: [1] Response Time: 14ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:55.006Z - silly: [1] New Request: 5b1419d2-b0a6-424a-ab69-c9992ae5fc1e
2015-10-27T17:13:55.007Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:55.008Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:55.008Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:55.008Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:55.008Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:55.008Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:55.008Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:55.008Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:55.009Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:55.009Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:55.009Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:55.009Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:55.010Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:55.011Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:55.011Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:55.011Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:55.011Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:55.011Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:55.012Z - debug: [1] Response Time: 5ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:56.025Z - silly: [1] New Request: d5d99762-a2a9-48af-932e-cfe9bc0f3341
2015-10-27T17:13:56.026Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:56.026Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:56.027Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:56.028Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:56.028Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:56.028Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:56.029Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:56.029Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:56.029Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:56.029Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:56.029Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:56.031Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:57.039Z - silly: [1] New Request: 3e4e3d36-4e00-460e-a12c-4087b5a964a1
2015-10-27T17:13:57.040Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:57.040Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:57.041Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:57.041Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:57.042Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:57.043Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:57.043Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:57.043Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:57.043Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:57.044Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:57.044Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:57.045Z - debug: [1] Response Time: 6ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
2015-10-27T17:13:58.055Z - silly: [1] New Request: 21842dd6-4421-4c81-ad2f-b46179d60957
2015-10-27T17:13:58.056Z - silly: [1] RequestHandler: Session ID [e3a3b429-461b-423e-a9ec-35a333fcd9af] Cookie SID [undefined] Created [false] Expired [true]
2015-10-27T17:13:58.056Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/media/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:58.056Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/public/[^/]+/.*[/]{0,1}$] Result [false]
2015-10-27T17:13:58.056Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/preview/[^/]+/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:58.057Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/section/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:58.057Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/article/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:58.057Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/page/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:58.057Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/url/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:58.057Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/cluster/[^/]+[/]{0,1}$] Result [false]
2015-10-27T17:13:58.057Z - silly: [1] RequestHandler: Comparing Path [/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0] to Pattern [^/api/jobs/[^/]+/[^/]+[/]{0,1}$] Result [true]
2015-10-27T17:13:58.057Z - silly: [1] SettingService1: Checking Service [DB] for Key [active_theme]
2015-10-27T17:13:58.058Z - silly: [1] RequestHandler: Settling on theme [pencilblue] and method [POST] for URL=[POST:/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0]
2015-10-27T17:13:58.058Z - silly: [1] SettingService1: Checking Service [DB] for Key [system_initialized]
2015-10-27T17:13:58.059Z - silly: [1] RequestHandler: Security Result=[true]
2015-10-27T17:13:58.059Z - silly: [1] RequestHandler:checkSystemSetup: {"success":true}
2015-10-27T17:13:58.059Z - silly: [1] RequestHandler:checkRequiresAuth: {"success":true}
2015-10-27T17:13:58.059Z - silly: [1] RequestHandler:checkAdminLevel: {"success":true}
2015-10-27T17:13:58.059Z - silly: [1] RequestHandler:checkPermissions: {"success":true}
2015-10-27T17:13:58.060Z - debug: [1] Response Time: 5ms URL=[POST]/api/jobs/get/11659b11-e446-49ff-878c-557c5cbbe0f0 CODE=200
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment