[15:35:03.117] New invocation is queued and will start shortly
[15:35:04.203] Starting the invocation (attempt 1)
[15:35:04.250] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:35:04.250] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:35:04.250] Buildbucket request:
{
"bucket": "luci.v8.try.triggered",
"client_operation_id": "9017567497794584704",
"parameters_json": "{\"builder_name\":\"v8_verify_flakes\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"17178\"},\"id\":\"cron:v1:17178\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:v8_verify_flakes",
"scheduler_invocation_id:9017567497794584704",
"scheduler_job_id:v8/v8_verify_flakes",
"user_agent:luci-scheduler"
]
}
[15:35:04.739] Buildbucket response:
{
"build": {
"bucket": "luci.v8.try.triggered",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1616340904422107",
"id": "8852137435762618768",
"parameters_json": "{\"builder_name\": \"v8_verify_flakes\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"17178\"}, \"id\": \"cron:v1:17178\"}]}}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-try-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616340904625657",
"tags": [
"builder:v8_verify_flakes",
"scheduler_invocation_id:9017567497794584704",
"scheduler_job_id:v8/v8_verify_flakes",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8852137435762618768/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8/verify_flakes",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1616340904625765",
"url": "https://ci.chromium.org/b/8852137435762618768",
"utcnow_ts": "1616340904731871"
}
}
[15:35:04.739] Task URL: https://ci.chromium.org/b/8852137435762618768
[15:35:04.739] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:2:0) after 1m0s
[15:35:06.090] Received PubSub notification, asking Buildbucket for the build status
[15:35:06.137] Build status: STARTED
[15:36:05.182] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:2:0)
[15:36:05.182] Timer tick, asking Buildbucket for the build status
[15:36:05.252] Build 8852137435762618768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:05.252] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:4:0) after 1m0s
[15:37:05.302] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:4:0)
[15:37:05.302] Timer tick, asking Buildbucket for the build status
[15:37:05.412] Build 8852137435762618768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:05.412] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:5:0) after 1m0s
[15:38:06.326] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:5:0)
[15:38:06.326] Timer tick, asking Buildbucket for the build status
[15:38:06.435] Build 8852137435762618768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:06.435] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:6:0) after 1m0s
[15:39:06.450] Handling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:6:0)
[15:39:06.477] Build status: STARTED
[15:39:06.477] Scheduling timer "check-buildbucket-build-status" (v8/v8_verify_flakes:9017567497794584704:7:0) after 5m37s
[15:41:21.242] Received PubSub notification, asking Buildbucket for the build status
[15:41:21.274] Build:
{
"id": "8852137435762618768",
"builder": {
"project": "v8",
"bucket": "try.triggered",
"builder": "v8_verify_flakes"
},
"createdBy": "project:v8",
"createTime": "2021-03-21T15:35:04.422107Z",
"startTime": "2021-03-21T15:35:05.572212Z",
"endTime": "2021-03-21T15:41:20.807308345Z",
"updateTime": "2021-03-21T15:41:20.807597012Z",
"status": "FAILURE",
"input": {
}
}
[15:41:21.274] Invocation finished in 6m18.173038143s with status FAILED