[19:28:07.987] New invocation is queued and will start shortly
[19:28:09.681] Starting the invocation (attempt 1)
[19:28:09.724] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:28:09.724] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:28:09.724] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056600126116152368",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bb06781c8917eee3026097cb7a807dfdee6c6213\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056600126116152368",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/bb06781c8917eee3026097cb7a807dfdee6c6213",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb06781c8917eee3026097cb7a807dfdee6c6213",
"gitiles_ref:refs/heads/master"
]
}
[19:28:11.186] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579116490232238",
"id": "8891170063096712368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb06781c8917eee3026097cb7a807dfdee6c6213\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579116490843883",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167414",
"builder:Linux Builder (dbg)",
"buildset:commit/git/bb06781c8917eee3026097cb7a807dfdee6c6213",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb06781c8917eee3026097cb7a807dfdee6c6213",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056600126116152368",
"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/8891170063096712368/+/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": "1579116490843997",
"url": "https://ci.chromium.org/b/8891170063096712368",
"utcnow_ts": "1579116491170216"
}
}
[19:28:11.186] Task URL: https://ci.chromium.org/b/8891170063096712368
[19:28:11.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:2:0) after 1m0s
[19:29:11.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:2:0)
[19:29:11.204] Timer tick, asking Buildbucket for the build status
[19:29:13.082] Build 8891170063096712368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:13.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:3:0) after 1m0s
[19:30:13.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:3:0)
[19:30:13.127] Timer tick, asking Buildbucket for the build status
[19:30:13.391] Build 8891170063096712368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:13.391] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:4:0) after 1m0s
[19:31:13.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:4:0)
[19:31:13.415] Timer tick, asking Buildbucket for the build status
[19:31:13.793] Build 8891170063096712368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:13.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:5:0) after 1m0s
[19:32:13.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:5:0)
[19:32:13.863] Timer tick, asking Buildbucket for the build status
[19:32:14.412] Build 8891170063096712368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:14.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:6:0) after 1m0s
[19:33:14.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:6:0)
[19:33:14.533] Timer tick, asking Buildbucket for the build status
[19:33:14.666] Build 8891170063096712368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:14.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:7:0) after 1m0s
[19:34:03.532] Received PubSub notification, asking Buildbucket for the build status
[19:34:03.725] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:14.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:7:0)
[19:34:14.791] Timer tick, asking Buildbucket for the build status
[19:34:15.398] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:15.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:9:0) after 1m0s
[19:35:15.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:9:0)
[19:35:15.397] Timer tick, asking Buildbucket for the build status
[19:35:16.250] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:16.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:10:0) after 1m0s
[19:36:16.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:10:0)
[19:36:16.300] Timer tick, asking Buildbucket for the build status
[19:36:16.438] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:16.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:11:0) after 1m0s
[19:37:16.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:11:0)
[19:37:16.530] Timer tick, asking Buildbucket for the build status
[19:37:16.709] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:16.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:12:0) after 1m0s
[19:38:16.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:12:0)
[19:38:16.853] Timer tick, asking Buildbucket for the build status
[19:38:16.982] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:16.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:13:0) after 1m0s
[19:39:17.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:13:0)
[19:39:17.106] Timer tick, asking Buildbucket for the build status
[19:39:17.272] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:17.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:14:0) after 1m0s
[19:40:17.448] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:14:0)
[19:40:17.448] Timer tick, asking Buildbucket for the build status
[19:40:17.622] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:17.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:15:0) after 1m0s
[19:41:17.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:15:0)
[19:41:17.742] Timer tick, asking Buildbucket for the build status
[19:41:17.933] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:17.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:16:0) after 1m0s
[19:42:18.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:16:0)
[19:42:18.079] Timer tick, asking Buildbucket for the build status
[19:42:18.380] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:18.380] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:17:0) after 1m0s
[19:43:18.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:17:0)
[19:43:18.391] Timer tick, asking Buildbucket for the build status
[19:43:19.255] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:19.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:18:0) after 1m0s
[19:44:19.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:18:0)
[19:44:19.371] Timer tick, asking Buildbucket for the build status
[19:44:19.578] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:19.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:19:0) after 1m0s
[19:45:19.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:19:0)
[19:45:19.629] Timer tick, asking Buildbucket for the build status
[19:45:19.832] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:19.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:20:0) after 1m0s
[19:46:19.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:20:0)
[19:46:19.845] Timer tick, asking Buildbucket for the build status
[19:46:20.368] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:20.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:21:0) after 1m0s
[19:47:20.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:21:0)
[19:47:20.523] Timer tick, asking Buildbucket for the build status
[19:47:20.727] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:20.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:22:0) after 1m0s
[19:48:20.750] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:22:0)
[19:48:20.750] Timer tick, asking Buildbucket for the build status
[19:48:21.010] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:21.010] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:23:0) after 1m0s
[19:49:21.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:23:0)
[19:49:21.117] Timer tick, asking Buildbucket for the build status
[19:49:21.737] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:21.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:24:0) after 1m0s
[19:50:21.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:24:0)
[19:50:21.769] Timer tick, asking Buildbucket for the build status
[19:50:21.898] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:21.898] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:25:0) after 1m0s
[19:51:22.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:25:0)
[19:51:22.059] Timer tick, asking Buildbucket for the build status
[19:51:22.181] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:22.181] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:26:0) after 1m0s
[19:52:22.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:26:0)
[19:52:22.356] Timer tick, asking Buildbucket for the build status
[19:52:22.934] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:22.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:27:0) after 1m0s
[19:53:22.990] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:27:0)
[19:53:22.990] Timer tick, asking Buildbucket for the build status
[19:53:23.458] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:23.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:28:0) after 1m0s
[19:54:23.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:28:0)
[19:54:23.501] Timer tick, asking Buildbucket for the build status
[19:54:24.063] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:24.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:29:0) after 1m0s
[19:55:24.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:29:0)
[19:55:24.107] Timer tick, asking Buildbucket for the build status
[19:55:24.273] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:24.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:30:0) after 1m0s
[19:56:24.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:30:0)
[19:56:24.435] Timer tick, asking Buildbucket for the build status
[19:56:24.818] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:24.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:31:0) after 1m0s
[19:57:24.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:31:0)
[19:57:24.972] Timer tick, asking Buildbucket for the build status
[19:57:25.143] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:25.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:32:0) after 1m0s
[19:58:25.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:32:0)
[19:58:25.202] Timer tick, asking Buildbucket for the build status
[19:58:25.779] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:25.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:33:0) after 1m0s
[19:59:25.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:33:0)
[19:59:25.850] Timer tick, asking Buildbucket for the build status
[19:59:26.104] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:26.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:34:0) after 1m0s
[20:00:26.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:34:0)
[20:00:26.146] Timer tick, asking Buildbucket for the build status
[20:00:26.315] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:26.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:35:0) after 1m0s
[20:01:26.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:35:0)
[20:01:26.389] Timer tick, asking Buildbucket for the build status
[20:01:26.498] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:26.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:36:0) after 1m0s
[20:02:26.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:36:0)
[20:02:26.647] Timer tick, asking Buildbucket for the build status
[20:02:26.894] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:26.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:37:0) after 1m0s
[20:03:27.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:37:0)
[20:03:27.121] Timer tick, asking Buildbucket for the build status
[20:03:28.420] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:28.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:38:0) after 1m0s
[20:04:28.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:38:0)
[20:04:28.401] Timer tick, asking Buildbucket for the build status
[20:04:28.502] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:28.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:39:0) after 1m0s
[20:05:28.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:39:0)
[20:05:28.586] Timer tick, asking Buildbucket for the build status
[20:05:29.383] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:29.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:40:0) after 1m0s
[20:06:29.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:40:0)
[20:06:29.434] Timer tick, asking Buildbucket for the build status
[20:06:30.135] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:30.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:41:0) after 1m0s
[20:07:30.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:41:0)
[20:07:30.168] Timer tick, asking Buildbucket for the build status
[20:07:30.796] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:30.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:42:0) after 1m0s
[20:08:30.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:42:0)
[20:08:30.879] Timer tick, asking Buildbucket for the build status
[20:08:31.126] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:31.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:43:0) after 1m0s
[20:09:31.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:43:0)
[20:09:31.168] Timer tick, asking Buildbucket for the build status
[20:09:31.664] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:31.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:44:0) after 1m0s
[20:10:31.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:44:0)
[20:10:31.693] Timer tick, asking Buildbucket for the build status
[20:10:32.161] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:32.161] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:45:0) after 1m0s
[20:11:32.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:45:0)
[20:11:32.168] Timer tick, asking Buildbucket for the build status
[20:11:32.917] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:32.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:46:0) after 1m0s
[20:12:32.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:46:0)
[20:12:32.989] Timer tick, asking Buildbucket for the build status
[20:12:35.237] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:35.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:47:0) after 1m0s
[20:13:35.362] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:47:0)
[20:13:35.362] Timer tick, asking Buildbucket for the build status
[20:13:36.300] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:36.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:48:0) after 1m0s
[20:14:36.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:48:0)
[20:14:36.306] Timer tick, asking Buildbucket for the build status
[20:14:36.889] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:36.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:49:0) after 1m0s
[20:15:36.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:49:0)
[20:15:36.989] Timer tick, asking Buildbucket for the build status
[20:15:37.214] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:37.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:50:0) after 1m0s
[20:16:37.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:50:0)
[20:16:37.278] Timer tick, asking Buildbucket for the build status
[20:16:37.501] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:37.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:51:0) after 1m0s
[20:17:37.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:51:0)
[20:17:37.568] Timer tick, asking Buildbucket for the build status
[20:17:37.773] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:37.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:52:0) after 1m0s
[20:18:37.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:52:0)
[20:18:37.902] Timer tick, asking Buildbucket for the build status
[20:18:38.666] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:38.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:53:0) after 1m0s
[20:19:38.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:53:0)
[20:19:38.708] Timer tick, asking Buildbucket for the build status
[20:19:39.007] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:39.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:54:0) after 1m0s
[20:20:39.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:54:0)
[20:20:39.211] Timer tick, asking Buildbucket for the build status
[20:20:39.372] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:39.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:55:0) after 1m0s
[20:21:39.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:55:0)
[20:21:39.445] Timer tick, asking Buildbucket for the build status
[20:21:39.563] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:39.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:56:0) after 1m0s
[20:22:39.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:56:0)
[20:22:39.597] Timer tick, asking Buildbucket for the build status
[20:22:39.813] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:39.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:57:0) after 1m0s
[20:23:39.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:57:0)
[20:23:39.888] Timer tick, asking Buildbucket for the build status
[20:23:39.988] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:39.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:58:0) after 1m0s
[20:24:40.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:58:0)
[20:24:40.289] Timer tick, asking Buildbucket for the build status
[20:24:40.503] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:40.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:59:0) after 1m0s
[20:25:40.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:59:0)
[20:25:40.664] Timer tick, asking Buildbucket for the build status
[20:25:40.838] Build 8891170063096712368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:40.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056600126116152368:60:0) after 1m0s
[20:26:36.948] Received PubSub notification, asking Buildbucket for the build status
[20:26:38.118] Build 8891170063096712368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:26:38.118] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579119996155331",
"created_by": "project:chromium",
"created_ts": "1579116490232238",
"id": "8891170063096712368",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb06781c8917eee3026097cb7a807dfdee6c6213\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-83-op2i\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167414, \"got_angle_revision\": \"86f730971751eb18460f2e13e3be1b97a68abacc\", \"got_dawn_revision\": \"2b24c3d92df5a473b752a0c145b79096441f5d50\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"bb06781c8917eee3026097cb7a807dfdee6c6213\", \"got_revision_cp\": \"refs/heads/master@{#732093}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"63468081e52e4f482adf1c4152b7196dc261144b\", \"got_v8_revision\": \"cd34145326def51cb6dcf87aed7d0caf9f62bb4f\", \"got_v8_revision_cp\": \"refs/heads/8.1.187@{#1}\", \"got_webrtc_revision\": \"ccab06fb72be1fd8b165d865aefad9daeff8631f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30265}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb06781c8917eee3026097cb7a807dfdee6c6213\", \"swarm_hashes_refs/heads/master(at){#732093}_without_patch\": {\"accessibility_unittests\": \"e0d85451b382ab38b3454dd8c2f698996ae57d08\", \"angle_unittests\": \"b0320ccc767b590033a8ce9de6899341052e66ac\", \"app_shell_unittests\": \"f28ea52e73cceb35de7589ad3dbdc3d2837d5ef4\", \"aura_unittests\": \"b25a29c9df152b4bf4066b12a477796e6a2956cf\", \"base_unittests\": \"159ee54e42ea985694e6049ba6be8ee8f48e13ca\", \"base_util_unittests\": \"356c0de397d70fe9a9d27e234e81e91737f95f03\", \"blink_common_unittests\": \"269122827eeaf59e97f69473994c7f6312e9e28a\", \"blink_fuzzer_unittests\": \"6a0c54b0291c3e1b09ad965c3605af3031b5cc1c\", \"blink_heap_unittests\": \"dc659970006575b424f9f600bb745add7ef0f090\", \"blink_platform_unittests\": \"2dd9b92cab61bc2ff9ce1c4017572c34f6fb571f\", \"blink_python_tests\": \"187221c13d47c6537188d649df20f9e50be771c2\", \"blink_unittests\": \"c3b4b566d68e313100c74946b7b354088c98e9a7\", \"blink_web_tests\": \"1a5aa97efa673c0ee43b2ccd1efe3d2a8daa7db3\", \"boringssl_crypto_tests\": \"0e9c4d12ee229ec90b9991619048f0bc75bd603c\", \"boringssl_ssl_tests\": \"c22c0df6d7cbe1575d665ed2c9e441121333d09c\", \"browser_tests\": \"e47439997eb671f2526b070bbb8ec232f898c5ff\", \"cacheinvalidation_unittests\": \"99a8d88db0c4987e71c34060c542e1d9cbf4f85a\", \"capture_unittests\": \"34f4cc71ce6a13b33788c302bb286cbaa1301dc6\", \"cast_unittests\": \"9cd8e86dae563be0c8918dcdc38dc594058fc4cf\", \"cc_unittests\": \"e4f6afa89c2b48d95528982ecb1d22cbb43810a4\", \"chrome_app_unittests\": \"761a3cbeca6cc01d237ee4357a8a88d9401c3f1d\", \"chromedriver_unittests\": \"3d85d6c1653c4b5cd3043c243c32e54333145c04\", \"components_browsertests\": \"1ce6832a88b5c44c2fc8798a44b85ba1910dbba9\", \"components_unittests\": \"940a5a7790091cdcd3cd9de4f9c99073ae35288c\", \"compositor_unittests\": \"cd93fe3ec167840b546d9fef84193263e270e0e3\", \"content_browsertests\": \"0de166ed32ba9cb2d75d93ed7615f64f4ba67f6d\", \"content_shell_crash_test\": \"c54ebfbe980b1b1deedc759487cc77bdb7548c7d\", \"content_unittests\": \"95b66a15db00c640755df05f8eeeb457fce8263d\", \"crashpad_tests\": \"07dc3235f7280c54b00e6d7dcbb79aa2449df836\", \"cronet_tests\": \"cfae7797169e3dd1df7bb9e9677cd1b152aa70e9\", \"cronet_unittests\": \"c7efd2e30426c428ff5aa6a49e878f218b2ef1a5\", \"crypto_unittests\": \"ef5c3ec1123d3d110c053418bce91bdde8b5a9df\", \"dbus_unittests\": \"f1ec78359b409d34f8cf64bf82efd80a63a1dcbf\", \"device_unittests\": \"44fdd3ebc9367633716e2a3dcd722078f093a743\", \"devtools_lint_check\": \"9a495d36abf15b885438194f4b2bcd79c71e00f6\", \"devtools_type_check\": \"318cc03dc7329ca1b26e036696928b33868800c7\", \"display_unittests\": \"dbe9c9021c1e51f1cf7326347fa2ab6210adadb8\", \"events_unittests\": \"f018e00d527e98e880fe0cb1c283cccb042bd8e9\", \"extensions_browsertests\": \"56f24b8292e5a6ce31d1bcf94626dabf30464ee7\", \"extensions_unittests\": \"7213bbe677a8a4fdc9060973bf78c7d1252e1bf9\", \"filesystem_service_unittests\": \"374a9109dc639d6359ef185a3978ad38ccb760c5\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"1575d19ec4debb220c60ce0ebd7a6c6bd36bfc53\", \"gfx_unittests\": \"9e291d5ea45a476c24f07b6ca5d56534c739d73b\", \"gin_unittests\": \"32cc19c5fe822b5a8ddec44d6c11835f85ba8668\", \"google_apis_unittests\": \"cc9b71f07ce4db5427f05e68f23e1f030bbe8c14\", \"gpu_unittests\": \"77c9181f280e707881ea283d58ed83d0ea1f991a\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"41ab29dfd19f1fd65bee631a7c2fc6559108685e\", \"headless_browsertests\": \"b7556265fb1090db263b2b19d85b687cd2066b83\", \"headless_unittests\": \"db1d66ca21305ee1ae8e3297b9261bba34210008\", \"interactive_ui_tests\": \"ec4380d6a82402e73b01c413b3d975aec063442c\", \"ipc_tests\": \"14f125a065946f2dcbba60883f8143c052634b55\", \"jingle_unittests\": \"e4f7c3fc46c010b25daeadd9ee1cd5d72b97991d\", \"latency_unittests\": \"348f79d11eb2373aef75a86dcd7e7760712e6546\", \"libjingle_xmpp_unittests\": \"0c77d37b0406d376b00105da3c5cce32ba98fb85\", \"media_blink_unittests\": \"bf6d8bfff9688f8c077c54398e2e90481b67f9d3\", \"media_service_unittests\": \"90ea1f60099e1526db7486c53dcb6436f3fd171f\", \"media_unittests\": \"8890a53aa944dbdcc51b695fed4aef8af503efa9\", \"message_center_unittests\": \"4d049a4ad8ca0c5b14827094a7a91e9f1f8794aa\", \"metrics_python_tests\": \"7b6af8ec63e3d2a7da9b18197b724588325ee27d\", \"midi_unittests\": \"1c22fe02d479dedf34511541b6a39d1acfbc4e47\", \"mojo_core_unittests\": \"8e666263c377026e1e85122e0807db689ec6e816\", \"mojo_unittests\": \"55b02099cf89f811870b1e0d3f60b2eac66067f3\", \"nacl_helper_nonsfi_unittests\": \"4ba48a26631713ef05f29344ea5e977745a484c6\", \"nacl_loader_unittests\": \"99da8f010c299baf539c5ab2f0a27ca6c9f97f68\", \"native_theme_unittests\": \"266a9486dec83987eb929f4635468a74853e1a1f\", \"net_unittests\": \"e79697a7268b0ba5b013b23f9587adf2e08b33ec\", \"pdf_unittests\": \"dc5fe31133b0974700551b8733cff07e1c269811\", \"perfetto_unittests\": \"fab41d7a9e97710d5c4f29fd6fc34cbee14a0ce4\", \"ppapi_unittests\": \"39452ed973a474fc75cd02dcac521b0b146867bf\", \"printing_unittests\": \"ae27988992e01e05b007b1b09a9fb6059b3711e6\", \"remoting_unittests\": \"e142d4f0505a60548054b181d5f13f1572bea41e\", \"sandbox_linux_unittests\": \"bee539c60983a73d1aacc91e58e5da088f35f9ec\", \"service_manager_unittests\": \"918503f6e01705c86640338f8b04685474fcfc5f\", \"services_unittests\": \"bbf0ea4847686dc56ca42a166f853d234b3fe575\", \"shell_dialogs_unittests\": \"14a2213f0e11416d0366ebd19d505009d45c79e6\", \"skia_unittests\": \"85167a7f1b5312a5a191155bcd3dd0b3c435babc\", \"snapshot_unittests\": \"8a9205216f7e642531127773fa9b86cc40ffeac4\", \"sql_unittests\": \"feb87fbbaadd80b80ae8625e995f5e06753e8847\", \"storage_unittests\": \"f3467f3ce92bbb86cd742c0a711fcba918da04c0\", \"sync_integration_tests\": \"d17898035a06b1acb985837f11b6bf46142ab0c6\", \"telemetry_gpu_unittests\": \"8145ece15f9494674fdc7d7d6302cb649477dd09\", \"telemetry_perf_unittests\": \"ce25fc92e28762e27f586a941db8ead8a536b3fa\", \"telemetry_unittests\": \"435e6eb33dcfcacc35bd4bfa127e530c311e1b1b\", \"traffic_annotation_auditor_unittests\": \"5a96fe47775fa77101f2327e9a8d40ed08c326db\", \"ui_base_unittests\": \"0ee6095fda5bb04905356c0bdcb825747607e90a\", \"ui_touch_selection_unittests\": \"0f686cebadde81be85bbb36f0c023e3c33623ce5\", \"unit_tests\": \"4404d1a465ba61e46ab6dbb1d63e87a8763db4b5\", \"url_unittests\": \"0621e8c9b1ecb26df2a3d8a8aa2ed0ff7e6b15fd\", \"views_perftests\": \"9b0c83dc9b9806afed1a5689cf8bf5e1c3042bd5\", \"views_unittests\": \"6faccdc6b4a3bf41cbe0f8945f47ac58e50d55fb\", \"viz_unittests\": \"f36c37716a4d0888220a2ef660826aade57c71d6\", \"vr_common_unittests\": \"13d0b5d9b9c3158d8507bc55b3c117f103968483\", \"vr_pixeltests\": \"63508646418e5d4c88f2357c8df358369ac70a8f\", \"webdriver_wpt_tests\": \"c7ea228573dbaa9a062d4dc79ceed68a3f247197\", \"weblayer_browsertests\": \"de8ded839c9bcdc13d347f311bbef4e227dc93e8\", \"wm_unittests\": \"4dba9ee32e224fdace73e7c96611fae74b8bcb25\", \"wtf_unittests\": \"79a0d87275ea2fb8a4f52f08ef2c95b52645ad94\", \"xr_browser_tests\": \"3b904224f8ffcccbdf2f775bdb4610b81949eef5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_e5bbff72983945f6847543519ce1052420cedd06fb8495f4080bfa2e6bcfd13f_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-83-op2i\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4760-d9a0e6d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579116842703442",
"status": "COMPLETED",
"status_changed_ts": "1579119996407760",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167414",
"builder:Linux Builder (dbg)",
"buildset:commit/git/bb06781c8917eee3026097cb7a807dfdee6c6213",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb06781c8917eee3026097cb7a807dfdee6c6213",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056600126116152368",
"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/8891170063096712368/+/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:49c38021a359f610",
"user_agent:luci-scheduler"
],
"updated_ts": "1579119996426116",
"url": "https://ci.chromium.org/b/8891170063096712368",
"utcnow_ts": "1579119998061671"
}
}
[20:26:38.118] Invocation finished in 58m30.148516135s with status SUCCEEDED