[23:45:19.692] New invocation is queued and will start shortly
[23:45:20.834] Starting the invocation (attempt 1)
[23:45:20.876] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:45:20.876] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:45:20.876] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026958736798133216",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d3b60c52c92cb8dd241c549e8d4a81b39f736740\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9026958736798133216",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/d3b60c52c92cb8dd241c549e8d4a81b39f736740",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3b60c52c92cb8dd241c549e8d4a81b39f736740",
"gitiles_ref:refs/heads/master"
]
}
[23:45:21.968] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607384721063351",
"id": "8861528674684695120",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3b60c52c92cb8dd241c549e8d4a81b39f736740\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607384721580467",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/178433",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d3b60c52c92cb8dd241c549e8d4a81b39f736740",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3b60c52c92cb8dd241c549e8d4a81b39f736740",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026958736798133216",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8861528674684695120/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1607384721580605",
"url": "https://ci.chromium.org/b/8861528674684695120",
"utcnow_ts": "1607384721954641"
}
}
[23:45:21.968] Task URL: https://ci.chromium.org/b/8861528674684695120
[23:45:21.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:2:0) after 1m0s
[23:45:25.554] Received PubSub notification, asking Buildbucket for the build status
[23:45:26.004] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:22.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:2:0)
[23:46:22.025] Timer tick, asking Buildbucket for the build status
[23:46:22.141] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:22.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:4:0) after 1m0s
[23:47:22.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:4:0)
[23:47:22.134] Timer tick, asking Buildbucket for the build status
[23:47:22.211] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:22.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:5:0) after 1m0s
[23:48:22.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:5:0)
[23:48:22.289] Timer tick, asking Buildbucket for the build status
[23:48:22.381] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:22.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:6:0) after 1m0s
[23:49:22.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:6:0)
[23:49:22.426] Timer tick, asking Buildbucket for the build status
[23:49:22.581] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:22.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:7:0) after 1m0s
[23:50:22.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:7:0)
[23:50:22.667] Timer tick, asking Buildbucket for the build status
[23:50:22.982] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:22.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:8:0) after 1m0s
[23:51:23.002] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:8:0)
[23:51:23.002] Timer tick, asking Buildbucket for the build status
[23:51:23.400] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:23.400] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:9:0) after 1m0s
[23:52:23.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:9:0)
[23:52:23.405] Timer tick, asking Buildbucket for the build status
[23:52:23.481] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:23.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:10:0) after 1m0s
[23:53:23.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:10:0)
[23:53:23.549] Timer tick, asking Buildbucket for the build status
[23:53:25.411] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:25.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:11:0) after 1m0s
[23:54:25.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:11:0)
[23:54:25.434] Timer tick, asking Buildbucket for the build status
[23:54:25.494] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:25.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:12:0) after 1m0s
[23:55:25.527] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:12:0)
[23:55:25.527] Timer tick, asking Buildbucket for the build status
[23:55:25.657] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:25.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:13:0) after 1m0s
[23:56:25.724] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:13:0)
[23:56:25.724] Timer tick, asking Buildbucket for the build status
[23:56:25.830] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:25.830] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:14:0) after 1m0s
[23:57:25.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:14:0)
[23:57:25.841] Timer tick, asking Buildbucket for the build status
[23:57:25.922] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:25.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:15:0) after 1m0s
[23:58:25.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:15:0)
[23:58:25.945] Timer tick, asking Buildbucket for the build status
[23:58:26.063] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:26.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:16:0) after 1m0s
[23:59:26.110] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:16:0)
[23:59:26.110] Timer tick, asking Buildbucket for the build status
[23:59:26.184] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:26.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:17:0) after 1m0s
[00:00:26.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:17:0)
[00:00:26.209] Timer tick, asking Buildbucket for the build status
[00:00:26.486] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:26.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:18:0) after 1m0s
[00:01:27.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:18:0)
[00:01:27.981] Timer tick, asking Buildbucket for the build status
[00:01:28.245] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:28.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:19:0) after 1m0s
[00:02:28.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:19:0)
[00:02:28.295] Timer tick, asking Buildbucket for the build status
[00:02:28.526] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:28.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:20:0) after 1m0s
[00:03:28.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:20:0)
[00:03:28.646] Timer tick, asking Buildbucket for the build status
[00:03:28.707] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:28.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:21:0) after 1m0s
[00:04:28.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:21:0)
[00:04:28.777] Timer tick, asking Buildbucket for the build status
[00:04:28.895] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:28.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:22:0) after 1m0s
[00:05:28.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:22:0)
[00:05:28.921] Timer tick, asking Buildbucket for the build status
[00:05:29.710] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:29.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:23:0) after 1m0s
[00:06:29.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:23:0)
[00:06:29.791] Timer tick, asking Buildbucket for the build status
[00:06:29.841] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:29.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:24:0) after 1m0s
[00:07:29.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:24:0)
[00:07:29.949] Timer tick, asking Buildbucket for the build status
[00:07:30.148] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:30.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:25:0) after 1m0s
[00:08:30.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:25:0)
[00:08:30.197] Timer tick, asking Buildbucket for the build status
[00:08:30.263] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:30.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:26:0) after 1m0s
[00:09:30.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:26:0)
[00:09:30.274] Timer tick, asking Buildbucket for the build status
[00:09:30.404] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:30.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:27:0) after 1m0s
[00:10:30.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:27:0)
[00:10:30.466] Timer tick, asking Buildbucket for the build status
[00:10:30.662] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:30.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:28:0) after 1m0s
[00:11:30.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:28:0)
[00:11:30.729] Timer tick, asking Buildbucket for the build status
[00:11:30.852] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:30.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:29:0) after 1m0s
[00:12:30.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:29:0)
[00:12:30.945] Timer tick, asking Buildbucket for the build status
[00:12:31.048] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:31.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:30:0) after 1m0s
[00:13:31.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:30:0)
[00:13:31.114] Timer tick, asking Buildbucket for the build status
[00:13:31.186] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:31.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:31:0) after 1m0s
[00:14:31.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:31:0)
[00:14:31.310] Timer tick, asking Buildbucket for the build status
[00:14:31.385] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:31.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:32:0) after 1m0s
[00:15:31.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:32:0)
[00:15:31.567] Timer tick, asking Buildbucket for the build status
[00:15:31.736] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:31.736] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:33:0) after 1m0s
[00:16:31.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:33:0)
[00:16:31.891] Timer tick, asking Buildbucket for the build status
[00:16:31.967] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:31.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:34:0) after 1m0s
[00:17:32.060] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:34:0)
[00:17:32.060] Timer tick, asking Buildbucket for the build status
[00:17:32.158] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:32.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:35:0) after 1m0s
[00:18:32.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:35:0)
[00:18:32.181] Timer tick, asking Buildbucket for the build status
[00:18:32.350] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:32.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:36:0) after 1m0s
[00:19:32.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:36:0)
[00:19:32.432] Timer tick, asking Buildbucket for the build status
[00:19:32.572] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:32.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:37:0) after 1m0s
[00:20:32.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:37:0)
[00:20:32.618] Timer tick, asking Buildbucket for the build status
[00:20:32.744] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:32.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:38:0) after 1m0s
[00:21:32.839] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:38:0)
[00:21:32.839] Timer tick, asking Buildbucket for the build status
[00:21:32.969] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:32.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:39:0) after 1m0s
[00:22:33.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:39:0)
[00:22:33.107] Timer tick, asking Buildbucket for the build status
[00:22:33.220] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:33.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:40:0) after 1m0s
[00:23:33.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:40:0)
[00:23:33.248] Timer tick, asking Buildbucket for the build status
[00:23:33.314] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:33.314] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:41:0) after 1m0s
[00:24:33.361] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:41:0)
[00:24:33.361] Timer tick, asking Buildbucket for the build status
[00:24:33.439] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:33.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:42:0) after 1m0s
[00:25:33.456] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:42:0)
[00:25:33.456] Timer tick, asking Buildbucket for the build status
[00:25:33.605] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:33.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:43:0) after 1m0s
[00:26:33.616] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:43:0)
[00:26:33.616] Timer tick, asking Buildbucket for the build status
[00:26:33.680] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:33.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:44:0) after 1m0s
[00:27:33.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:44:0)
[00:27:33.738] Timer tick, asking Buildbucket for the build status
[00:27:33.852] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:33.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:45:0) after 1m0s
[00:28:33.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:45:0)
[00:28:33.895] Timer tick, asking Buildbucket for the build status
[00:28:33.956] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:33.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:46:0) after 1m0s
[00:29:33.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:46:0)
[00:29:33.979] Timer tick, asking Buildbucket for the build status
[00:29:34.651] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:34.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:47:0) after 1m0s
[00:30:34.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:47:0)
[00:30:34.679] Timer tick, asking Buildbucket for the build status
[00:30:34.798] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:34.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:48:0) after 1m0s
[00:31:34.824] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:48:0)
[00:31:34.824] Timer tick, asking Buildbucket for the build status
[00:31:35.230] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:35.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:49:0) after 1m0s
[00:32:35.250] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:49:0)
[00:32:35.250] Timer tick, asking Buildbucket for the build status
[00:32:35.513] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:35.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:50:0) after 1m0s
[00:33:35.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:50:0)
[00:33:35.539] Timer tick, asking Buildbucket for the build status
[00:33:35.614] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:35.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:51:0) after 1m0s
[00:34:35.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:51:0)
[00:34:35.641] Timer tick, asking Buildbucket for the build status
[00:34:35.708] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:35.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:52:0) after 1m0s
[00:35:35.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:52:0)
[00:35:35.755] Timer tick, asking Buildbucket for the build status
[00:35:35.847] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:35.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:53:0) after 1m0s
[00:36:35.839] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:53:0)
[00:36:35.839] Timer tick, asking Buildbucket for the build status
[00:36:36.055] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:36.055] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:54:0) after 1m0s
[00:37:36.133] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:54:0)
[00:37:36.133] Timer tick, asking Buildbucket for the build status
[00:37:36.519] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:36.519] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:55:0) after 1m0s
[00:38:36.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:55:0)
[00:38:36.570] Timer tick, asking Buildbucket for the build status
[00:38:36.694] Build 8861528674684695120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:36.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026958736798133216:56:0) after 1m0s
[00:39:31.482] Received PubSub notification, asking Buildbucket for the build status
[00:39:31.732] Build 8861528674684695120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:39:31.732] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607387970412668",
"created_by": "project:chromium",
"created_ts": "1607384721063351",
"id": "8861528674684695120",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3b60c52c92cb8dd241c549e8d4a81b39f736740\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"d3b60c52c92cb8dd241c549e8d4a81b39f736740\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-96-58jv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 178433, \"got_angle_revision\": \"a10d694e914ea0a2c3958f710b7607100a10899f\", \"got_dawn_revision\": \"042184128efea90552495d5054c3ae17b84cb3c3\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"d3b60c52c92cb8dd241c549e8d4a81b39f736740\", \"got_revision_cp\": \"refs/heads/master@{#834452}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7bcd79eccf28007fe7a60ed50251d91ea4e65674\", \"got_v8_revision\": \"5a377bcb492ded6525dbabed85a77563e63c7cf7\", \"got_v8_revision_cp\": \"refs/heads/8.9.117@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3b60c52c92cb8dd241c549e8d4a81b39f736740\", \"swarm_hashes_refs/heads/master(at){#834452}_without_patch\": {\"absl_hardening_tests\": \"6c7efb873fccb1bd13be61831c29bf0c8cfc9beb\", \"accessibility_unittests\": \"9570ed364f61b8e41fd0c6be4425e079c9891fe7\", \"angle_unittests\": \"4fd58e2685378f3df7f4dda6228baee07a814800\", \"app_shell_unittests\": \"49a824de4f0e9c0cd18bd9bb6405aa2678001031\", \"aura_unittests\": \"6e0664557787ae5adb564dca7044aae163e18039\", \"base_unittests\": \"3c1b7d9394de5d19328d68f12942ed56ffc0e55c\", \"base_util_unittests\": \"8c8d2617d2894fff3c1321b8bf1c6f174c7d5344\", \"blink_common_unittests\": \"b343c7f55dd87ae29a7b59b3dcf7b3606fe4052d\", \"blink_fuzzer_unittests\": \"d3c0b57a548c24658235747ee3477f8f81081bf9\", \"blink_heap_unittests\": \"dc16d70a224366507ca3f66c536999ac5795e252\", \"blink_platform_unittests\": \"903f0878d25c438e22de1d00567aaa5f16ff2ace\", \"blink_python_tests\": \"c0efb303c78f235cfd1c36993f7648150663c813\", \"blink_unittests\": \"74b6af09e0f76759287a7330bcd8c83531ef7d35\", \"blink_web_tests\": \"feba9d123f7eb3ee16f644881f9877d72a3c8bc2\", \"boringssl_crypto_tests\": \"10366d8306b11b17acdca89dae12c22da6f78023\", \"boringssl_ssl_tests\": \"5727809b17c57119bad7733399086d79919a0f9a\", \"browser_tests\": \"fb6f6b64e1eda506be4062ba5dcd5c32d62c6246\", \"capture_unittests\": \"9f25630f04448ef071325a17f0a61a1ba8fb0174\", \"cast_unittests\": \"8cee1383773d467fbdebc29f9698e6226077bbe1\", \"cc_unittests\": \"941a61f2f97f9b7de4423308358b5aba4b4d49e2\", \"chrome_app_unittests\": \"ec43939a226ca536ae04d74cebbd7814c414cf49\", \"chromedriver_unittests\": \"ef137e05f82fe309d5d7fed53fa3a95f6d9f1efa\", \"components_browsertests\": \"cc47f9ee6a77794acbab9efac2d7096512c90916\", \"components_unittests\": \"5a9225188efd2da8d4e3c66490a790b320e1a04d\", \"compositor_unittests\": \"f94d899daa6cb4f326a333b07b1a991ea5ae5b05\", \"content_browsertests\": \"712640c80c46e494f0a9304be4e59af0909f5e8a\", \"content_shell_crash_test\": \"e5a17a6ac70de89f578a72040d56f35bfb7fc6a3\", \"content_unittests\": \"1911af0595a543304605d63dccddde388a341208\", \"crashpad_tests\": \"55f2c0ac80f455e711888cd8f656ffd19299110a\", \"cronet_tests\": \"ba4d1e59e8bd39ee6939f4acd457b173f81a533c\", \"cronet_unittests\": \"8f1059ac8216a09aed0a84d13a3a35adc8fc9a0c\", \"crypto_unittests\": \"8d53ea92df4f6e107f4f2068255043cd061e065a\", \"dbus_unittests\": \"f6c40be6dc6653e2862586138bf870f332bd619a\", \"device_unittests\": \"373879c28a7b22861a6e74c32a258c9e8970ebfc\", \"display_unittests\": \"b12b07cea7dd0cc5242169895845bb03b5d42d99\", \"events_unittests\": \"74d840e7ba7f5f08efd2378835faa80fff469089\", \"extension_docserver_python_unittests\": \"cc29a9c656525c0d9e7d4f42779816ced58c3330\", \"extensions_browsertests\": \"63dde637156de36aafb68421d3c5db113cb7ba20\", \"extensions_unittests\": \"60b0a1d576d9e6b371efb95f32f91138ce338b3d\", \"filesystem_service_unittests\": \"040428d40da0d7cdf2f4d54702840f857931c372\", \"flatbuffers_unittests\": \"4d7f6411689e0ca026ebaa10a0eb906a6c506cdd\", \"gcm_unit_tests\": \"baf76ea9bbea1eeff35850404a6b09959593296e\", \"gfx_unittests\": \"497280c8d097cf1579541f5eb2278cbd9225b5ca\", \"gin_unittests\": \"a3b86c253fd1d25a45f5f068998ba9c363f1f6fe\", \"google_apis_unittests\": \"25cb3012ab36a2715dd640c4847301cad9ffa993\", \"gpu_unittests\": \"ccbac5242fa09707020fed159f68b3e70bdcabb5\", \"grit_python_unittests\": \"4240fd59c31e9646d2ab4fe1082f5d79a3314641\", \"gwp_asan_unittests\": \"fc3c89b514726637d4c8ef4e27151028db381ba8\", \"headless_browsertests\": \"b5e3c01dd8859738a45ea4f0d2d10d6da304f9eb\", \"headless_unittests\": \"9d90acd708740c101db27a04769f801dca6ac31d\", \"interactive_ui_tests\": \"7eefdf574c6c7aa55f1a51dc3fe4851551215ab4\", \"ipc_tests\": \"e8cdf25f8a5f33d7d85a047d800806d717b05147\", \"jingle_unittests\": \"d0c331393003291caaf1516e1ed4da66337fe9d7\", \"latency_unittests\": \"1060671dcd39a58b9142f1c003bbca95b14f7bf3\", \"libjingle_xmpp_unittests\": \"c3306a8fd2dfe3ce17837c27c13f6d41d9c41371\", \"liburlpattern_unittests\": \"7a9354cad73ba49e5699110f6843f7b6534c7054\", \"media_blink_unittests\": \"47ac4d9adea14fc6a126659262fecb915e7cc866\", \"media_unittests\": \"a4b0d5d70cba0c529d946ae219140530eb41b9a5\", \"message_center_unittests\": \"0e55c5baa48e304e0cf4de70aaee2de040efbf2b\", \"metrics_python_tests\": \"07929561e1c2ed162f1a6d7686f0ea096c7c8a69\", \"midi_unittests\": \"23b337b8dbedbea85af7f602e81353eee001c235\", \"mojo_core_unittests\": \"2c054005501a4476d011a4656319a911bf55dd23\", \"mojo_unittests\": \"6391a2b34bcc7d774eb11ae9e52c87dd25e07fb3\", \"nacl_helper_nonsfi_unittests\": \"1bdf19e3955cd646f6b89173e4c4f2a5876d7e1f\", \"nacl_loader_unittests\": \"7554d82ddde89a8fe6b14d3734fd84ff03eb2e84\", \"native_theme_unittests\": \"9499686e3958325829bb36ae5910f84bbdf10f3c\", \"net_unittests\": \"0da93069e2c084cd6af76fa2b08d061ef083e48b\", \"openscreen_unittests\": \"f345c79e107359584b5a0ff05fa6f1701062f352\", \"pdf_unittests\": \"5f34773c94e87f38df6a037f9a60289e563472b6\", \"perfetto_unittests\": \"606dcf45170f9f3be4d3a2708c6418077923e8c7\", \"ppapi_unittests\": \"e98cd2a389d8e890bc9ba5d6f268f3ab7ca54d54\", \"printing_unittests\": \"6486c545cfa73993c1aeda71a52e12c3ea18c85d\", \"remoting_unittests\": \"7ad9b980289b0bfc2f782a965b5999555ab746ba\", \"sandbox_linux_unittests\": \"11ac19f0e6c5a101307292ea731a3257d8a1f4c1\", \"service_manager_unittests\": \"8c7336022fdd8b550b7aec5d211eca6e30c4db92\", \"services_unittests\": \"319384f1f91358b6f93d26e8845756bc124f6598\", \"shell_dialogs_unittests\": \"f1dd753f1f4673cd236966b92fb5223d14326dc8\", \"skia_unittests\": \"b2427ea97629f63b8f3f9b81394f597cbcd5e792\", \"snapshot_unittests\": \"75c2576f27dd41660234b30259fcb126d52d5ce2\", \"sql_unittests\": \"eda887b9a6bb1283d66ed8727863a0099a981095\", \"storage_unittests\": \"1cc01a0fdb4ae3ab244e4a308b8c669080795941\", \"sync_integration_tests\": \"f54cd814c4eb1efaaac37b1b8b5eb8c74b3bb6bf\", \"telemetry_gpu_unittests\": \"3832a2f79ba6b789fdbecf6ba19aec8d9f12a0bb\", \"telemetry_perf_unittests\": \"1b28931a86a824aadf36130a7ba1448fe5f4db31\", \"telemetry_unittests\": \"3374733278dcc6b0309d74af28456b4358448c91\", \"traffic_annotation_auditor_unittests\": \"9f343f37391e0c7ac84a09190319b68ac6589f2b\", \"ui_base_unittests\": \"6d989c740394ec5c91cf43aecaf27296c3190505\", \"ui_touch_selection_unittests\": \"68fbb1aa268a697d3ece8a13e75c4366426f2a75\", \"unit_tests\": \"7f72b39fd38692d435866377d197cf013001691e\", \"url_unittests\": \"ee3f4e0d6add77b057306d0b35a99b00bab2ae6a\", \"views_perftests\": \"b7097a0f265985a35bf09099602aac359475b441\", \"views_unittests\": \"231d91e8822d25c037848a684ed0849515c8401b\", \"viz_unittests\": \"baae8453f98f7c6a5dc63f8d0f6533119ce5703a\", \"vr_common_unittests\": \"dac4918678d732a8e375ca92b43069301a2f1274\", \"vr_pixeltests\": \"e09b371d062e9db5aedca95c7e4af30f90d646b9\", \"webdriver_wpt_tests\": \"adbc75e2aaf2d07a4fcaa3daedb2a93479689371\", \"weblayer_browsertests\": \"c2ec2323a5d8fe28e3d53eaad32a5f62e5044e64\", \"weblayer_unittests\": \"3ef42af0fe5dcd706ffa05e2c62629f1288006c3\", \"wm_unittests\": \"4ea25f257b04a80f86d325798da1512c26921e4d\", \"wtf_unittests\": \"43dcd81032652aafef3b17fda7a5cc7baeeacc1f\", \"x11_unittests\": \"ab53bbc1c2edc193b833d1a0f4f59cacf443b278\", \"xr_browser_tests\": \"d18001726b45ff73500c1dd4e69abfa32458d669\", \"zlib_unittests\": \"93b88561b95418445057bcdc6093fcf737388dc8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-96-58jv\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607384724398629",
"status": "COMPLETED",
"status_changed_ts": "1607387970717181",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/178433",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d3b60c52c92cb8dd241c549e8d4a81b39f736740",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3b60c52c92cb8dd241c549e8d4a81b39f736740",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026958736798133216",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8861528674684695120/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:505869f1db460c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607387970792046",
"url": "https://ci.chromium.org/b/8861528674684695120",
"utcnow_ts": "1607387971706466"
}
}
[00:39:31.732] Invocation finished in 54m12.068915398s with status SUCCEEDED