gistfile1.txt

jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::handlers: job_group_create message: origin: "robertgzr" package: "it-works" deps_only: false target: "x86_64-linux" origin_only: false package_only: false
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::handlers: Resolved project name: PT0.000041059S sec
jobsrv.1    |
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::handlers: Graph rdeps: 0 items (PT0.000823451S sec)
jobsrv.1    |
jobsrv.1    | DEBUG:habitat_builder_jobsrv::data_store: JobGroup robertgzr/it-works not queued (not found)
jobsrv.1    | DEBUG:habitat_builder_jobsrv::data_store: JobGroup created: id: 862295310855315456 state: GroupQueued projects {name: "robertgzr/it-works" ident: "robertgzr/it-works/0.1.1/20171129144944" state: NotStarted} created_at: "2017-11-30T16:36:42.382149+00:00" project_name: "robertgzr/it-works"
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Process status: found 0 updated jobs
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Setting group robertgzr/it-works from queued to pending
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Dispatching group 862295310855315456
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Found 1 dispatchable projects for group 862295310855315456
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Dispatching project: "robertgzr/it-works"
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Job created: id: 862295312239280128 owner_id: 862295310855315456 state: Pending project {id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582} created_at: "2017-11-30T16:36:42.539969+00:00" is_archived: false channel: "bldr-862295310855315456"
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Found 0 dispatchable projects for group 862295310855315456
jobsrv.1    | DEBUG:habitat_core::crypto::keys::box_key_pair: Decrypt key path = /hab/svc/builder-jobsrv/files
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::worker_manager: Dispatching job to worker "20862@vagrant": Job { id: 862295312239280128, owner_id: 862295310855315456, state: Dispatched, project: id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582, created_at: "2017-11-30T16:36:42.539969+00:00", channel: "bldr-862295310855315456", build_started_at: "", build_finished_at: "", package_ident: , integrations: [origin: "robertgzr" integration: "docker" name: "dockerhub" body: "[secure]"], project_integrations: [origin: "robertgzr" name: "it-works" integration: "dockerhub" integration_name: "default" body: "{\"docker_hub_repo_name\":\"robertgzr/it-works\",\"latest_tag\":true,\"version_tag\":true,\"version_release_tag\":true}"] }
worker.1    | DEBUG:habitat_builder_worker::runner: Received work, job=Job { id: 862295312239280128, owner_id: 862295310855315456, state: Dispatched, project: id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582, created_at: "2017-11-30T16:36:42.539969+00:00", channel: "bldr-862295310855315456", build_started_at: "", build_finished_at: "", package_ident: , integrations: [origin: "robertgzr" integration: "docker" name: "dockerhub" body: "[secure]"], project_integrations: [origin: "robertgzr" name: "it-works" integration: "dockerhub" integration_name: "default" body: "{\"docker_hub_repo_name\":\"robertgzr/it-works\",\"latest_tag\":true,\"version_tag\":true,\"version_release_tag\":true}"] }
worker.1    | DEBUG:habitat_builder_worker::heartbeat: heartbeat resumed
worker.1    | DEBUG:habitat_builder_worker::heartbeat: heartbeat pulsed: endpoint: "20862@vagrant" os: Linux state: Busy
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::worker_manager: Got heartbeat: endpoint: "20862@vagrant" os: Linux state: Busy
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::worker_manager: Got job status: Job { id: 862295312239280128, owner_id: 862295310855315456, state: Dispatched, project: id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582, created_at: "2017-11-30T16:36:42.539969+00:00", channel: "bldr-862295310855315456", build_started_at: "", build_finished_at: "", package_ident: , integrations: [origin: "robertgzr" integration: "docker" name: "dockerhub" body: "[secure]"], project_integrations: [origin: "robertgzr" name: "it-works" integration: "dockerhub" integration_name: "default" body: "{\"docker_hub_repo_name\":\"robertgzr/it-works\",\"latest_tag\":true,\"version_tag\":true,\"version_release_tag\":true}"] }
worker.1    | DEBUG:habitat_http_client::ssl: Setting CA file for SSL context to: /hab/pkgs/core/cacerts/2017.09.20/20171014212239/ssl/cert.pem
worker.1    | DEBUG:habitat_http_client::api_client: User-Agent: builder-worker/1d1a019d7475db2894650e7258d89cc5eb9ee4ae (x86_64-linux; 4.10.0-19-generic)
worker.1    | DEBUG:habitat_builder_worker::runner::util: validated integrations
worker.1    | DEBUG:habitat_builder_worker::runner::util: building chown command, cmd="chown" "-R" "1001:1001" "/home/krangschnak/.hab"
worker.1    | DEBUG:habitat_builder_worker::runner::util: spawning chown command
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Process status: found 1 updated jobs
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Syncing job status: job=id: 862295312239280128 owner_id: 862295310855315456 state: Dispatched project {id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582} created_at: "2017-11-30T16:36:42.539969+00:00" is_archived: false channel: "bldr-862295310855315456"
worker.1    | DEBUG:habitat_builder_worker::runner::util: completed chown command, status=ExitStatus(ExitStatus(0))
worker.1    | DEBUG:habitat_http_client::api_client: GET http://localhost:9636/v1/depot/origins/robertgzr/secret_keys/latest with ApiClient { endpoint: "http://localhost:9636/v1", inner: Client { redirect_policy: FollowAll, read_timeout: Some(Duration { secs: 60, nanos: 0 }), write_timeout: Some(Duration { secs: 60, nanos: 0 }), proxy: None }, proxy: None, target_scheme: "http", user_agent_header: UserAgent("builder-worker/1d1a019d7475db2894650e7258d89cc5eb9ee4ae (x86_64-linux; 4.10.0-19-generic)") }
worker.1    | DEBUG:habitat_depot_client: Response: Response { status: Ok, headers: Headers { Content-Length: 124
worker.1    | , Content-Disposition: attachment; filename="robertgzr-20171129113349.sig.key"
worker.1    | , X-Filename: robertgzr-20171129113349.sig.key
worker.1    | , Cache-Control: private, no-cache, no-store
worker.1    | , Access-Control-Allow-Origin: *
worker.1    | , Access-Control-Allow-Headers: authorization, range
worker.1    | , Access-Control-Allow-Methods: PUT, DELETE, PATCH
worker.1    | , Access-Control-Expose-Headers: content-disposition
worker.1    | , Content-Type: text/plain
worker.1    | , Date: Thu, 30 Nov 2017 16:36:44 GMT
worker.1    | , }, version: Http11, url: "http://localhost:9636/v1/depot/origins/robertgzr/secret_keys/latest", status_raw: RawStatus(200, "OK"), message: Http11Message { is_proxied: false, method: None, stream: Wrapper { obj: Some(Reading(SizedReader(remaining=124))) } } }
worker.1    | DEBUG:habitat_depot_client: Writing to /home/krangschnak/.hab/cache/keys/robertgzr-20171129113349.sig.key.tmp-pxSABqMw
worker.1    | DEBUG:habitat_depot_client: Moving /home/krangschnak/.hab/cache/keys/robertgzr-20171129113349.sig.key.tmp-pxSABqMw to /home/krangschnak/.hab/cache/keys/robertgzr-20171129113349.sig.key
worker.1    | DEBUG:habitat_builder_worker::runner: Imported origin secret key, dst="/home/krangschnak/.hab/cache/keys/robertgzr-20171129113349.sig.key".
worker.1    | DEBUG:github_api_client::client: GitHub response body, {"message":"'Expiration time' claim ('exp') must be a numeric value representing the future time at which the assertion expires","documentation_url":"https://developer.github.com/v3"}
worker.1    | DEBUG:habitat_builder_worker::runner: Failed to clone remote source repository for robertgzr/it-works, err=GithubAppAuthErr(AppAuth(AppAuthErr { message: "\'Expiration time\' claim (\'exp\') must be a numeric value representing the future time at which the assertion expires", documentation_url: "https://developer.github.com/v3" }))
worker.1    | WARN:habitat_builder_worker::runner: Failed to remove studio dir /hab/svc/builder-worker/data/862295312239280128/studio, err: Error { repr: Os { code: 2, message: "No such file or directory" } }
worker.1    | DEBUG:habitat_builder_worker::heartbeat: heartbeat pulsed: endpoint: "20862@vagrant" os: Linux state: Busy
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::worker_manager: Got heartbeat: endpoint: "20862@vagrant" os: Linux state: Busy
worker.1    | DEBUG:habitat_builder_worker::heartbeat: heartbeat pulsed: endpoint: "20862@vagrant" os: Linux state: Busy
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::worker_manager: Got heartbeat: endpoint: "20862@vagrant" os: Linux state: Busy
worker.1    | DEBUG:habitat_builder_worker::runner: Got result from spawned runner: Job { id: 862295312239280128, owner_id: 862295310855315456, state: Failed, project: id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582, created_at: "2017-11-30T16:36:42.539969+00:00", channel: "bldr-862295310855315456", build_started_at: "", build_finished_at: "", package_ident: , integrations: [origin: "robertgzr" integration: "docker" name: "dockerhub" body: "[secure]"], project_integrations: [origin: "robertgzr" name: "it-works" integration: "dockerhub" integration_name: "default" body: "{\"docker_hub_repo_name\":\"robertgzr/it-works\",\"latest_tag\":true,\"version_tag\":true,\"version_release_tag\":true}"] }
worker.1    | DEBUG:habitat_builder_worker::runner: Completed work, job=Job { id: 862295312239280128, owner_id: 862295310855315456, state: Failed, project: id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582, created_at: "2017-11-30T16:36:42.539969+00:00", channel: "bldr-862295310855315456", build_started_at: "", build_finished_at: "", package_ident: , integrations: [origin: "robertgzr" integration: "docker" name: "dockerhub" body: "[secure]"], project_integrations: [origin: "robertgzr" name: "it-works" integration: "dockerhub" integration_name: "default" body: "{\"docker_hub_repo_name\":\"robertgzr/it-works\",\"latest_tag\":true,\"version_tag\":true,\"version_release_tag\":true}"] }
worker.1    | DEBUG:habitat_builder_worker::heartbeat: heartbeat resumed
worker.1    | DEBUG:habitat_builder_worker::heartbeat: heartbeat pulsed: endpoint: "20862@vagrant" os: Linux state: Ready
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::worker_manager: Got job status: Job { id: 862295312239280128, owner_id: 862295310855315456, state: Failed, project: id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582, created_at: "2017-11-30T16:36:42.539969+00:00", channel: "bldr-862295310855315456", build_started_at: "", build_finished_at: "", package_ident: , integrations: [origin: "robertgzr" integration: "docker" name: "dockerhub" body: "[secure]"], project_integrations: [origin: "robertgzr" name: "it-works" integration: "dockerhub" integration_name: "default" body: "{\"docker_hub_repo_name\":\"robertgzr/it-works\",\"latest_tag\":true,\"version_tag\":true,\"version_release_tag\":true}"] }
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Process status: found 1 updated jobs
jobsrv.1    | DEBUG:habitat_builder_jobsrv::server::scheduler: Syncing job status: job=id: 862295312239280128 owner_id: 862295310855315456 state: Failed project {id: 861499683338461298 origin_name: "robertgzr" package_name: "it-works" name: "robertgzr/it-works" plan_path: "habitat/plan.sh" owner_id: 861417858423332978 vcs_type: "git" vcs_data: "https://github.com/robertgzr/it-works.git" vcs_installation_id: 70582} error {code: VCS_CLONE msg: "wk:run:4"} created_at: "2017-11-30T16:36:42.539969+00:00" is_archived: false channel: "bldr-862295310855315456"