[03:29:44.554] New invocation is queued and will start shortly
[03:29:45.791] Starting the invocation (attempt 1)
[03:29:45.829] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:29:45.829] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:29:45.829] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053217738114181296",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5549bc81b0bcb0178af928d5e86d413eff2907c6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053217738114181296",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5549bc81b0bcb0178af928d5e86d413eff2907c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5549bc81b0bcb0178af928d5e86d413eff2907c6",
"gitiles_ref:refs/heads/master"
]
}
[03:29:46.506] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582342185965725",
"id": "8887787675968068512",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5549bc81b0bcb0178af928d5e86d413eff2907c6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582342186382236",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168878",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5549bc81b0bcb0178af928d5e86d413eff2907c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5549bc81b0bcb0178af928d5e86d413eff2907c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053217738114181296",
"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/8887787675968068512/+/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": "1582342186382336",
"url": "https://ci.chromium.org/b/8887787675968068512",
"utcnow_ts": "1582342186500049"
}
}
[03:29:46.506] Task URL: https://ci.chromium.org/b/8887787675968068512
[03:29:46.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:2:0) after 1m0s
[03:30:46.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:2:0)
[03:30:46.560] Timer tick, asking Buildbucket for the build status
[03:30:46.804] Build 8887787675968068512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:3:0) after 1m0s
[03:31:46.924] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:3:0)
[03:31:46.924] Timer tick, asking Buildbucket for the build status
[03:31:47.524] Build 8887787675968068512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:47.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:4:0) after 1m0s
[03:32:47.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:4:0)
[03:32:47.611] Timer tick, asking Buildbucket for the build status
[03:32:47.677] Build 8887787675968068512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:47.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:5:0) after 1m0s
[03:33:47.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:5:0)
[03:33:47.671] Timer tick, asking Buildbucket for the build status
[03:33:47.770] Build 8887787675968068512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:47.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:6:0) after 1m0s
[03:34:08.987] Received PubSub notification, asking Buildbucket for the build status
[03:34:09.074] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:47.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:6:0)
[03:34:47.792] Timer tick, asking Buildbucket for the build status
[03:34:47.946] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:47.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:8:0) after 1m0s
[03:35:47.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:8:0)
[03:35:47.928] Timer tick, asking Buildbucket for the build status
[03:35:48.054] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:48.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:9:0) after 1m0s
[03:36:48.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:9:0)
[03:36:48.078] Timer tick, asking Buildbucket for the build status
[03:36:48.140] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:48.140] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:10:0) after 1m0s
[03:37:48.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:10:0)
[03:37:48.147] Timer tick, asking Buildbucket for the build status
[03:37:48.598] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:48.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:11:0) after 1m0s
[03:38:48.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:11:0)
[03:38:48.666] Timer tick, asking Buildbucket for the build status
[03:38:48.782] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:48.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:12:0) after 1m0s
[03:39:48.788] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:12:0)
[03:39:48.788] Timer tick, asking Buildbucket for the build status
[03:39:48.921] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:48.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:13:0) after 1m0s
[03:40:48.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:13:0)
[03:40:48.988] Timer tick, asking Buildbucket for the build status
[03:40:49.075] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:49.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:14:0) after 1m0s
[03:41:49.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:14:0)
[03:41:49.094] Timer tick, asking Buildbucket for the build status
[03:41:49.244] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:49.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:15:0) after 1m0s
[03:42:49.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:15:0)
[03:42:49.461] Timer tick, asking Buildbucket for the build status
[03:42:49.585] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:49.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:16:0) after 1m0s
[03:43:49.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:16:0)
[03:43:49.695] Timer tick, asking Buildbucket for the build status
[03:43:49.938] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:49.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:17:0) after 1m0s
[03:44:49.990] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:17:0)
[03:44:49.990] Timer tick, asking Buildbucket for the build status
[03:44:50.085] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:50.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:18:0) after 1m0s
[03:45:50.175] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:18:0)
[03:45:50.175] Timer tick, asking Buildbucket for the build status
[03:45:50.272] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:50.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:19:0) after 1m0s
[03:46:50.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:19:0)
[03:46:50.399] Timer tick, asking Buildbucket for the build status
[03:46:50.452] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:50.452] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:20:0) after 1m0s
[03:47:50.520] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:20:0)
[03:47:50.520] Timer tick, asking Buildbucket for the build status
[03:47:50.576] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:50.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:21:0) after 1m0s
[03:48:50.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:21:0)
[03:48:50.716] Timer tick, asking Buildbucket for the build status
[03:48:50.953] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:50.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:22:0) after 1m0s
[03:49:50.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:22:0)
[03:49:50.991] Timer tick, asking Buildbucket for the build status
[03:49:51.164] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:51.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:23:0) after 1m0s
[03:50:51.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:23:0)
[03:50:51.184] Timer tick, asking Buildbucket for the build status
[03:50:51.286] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:51.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:24:0) after 1m0s
[03:51:51.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:24:0)
[03:51:51.343] Timer tick, asking Buildbucket for the build status
[03:51:51.453] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:51.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:25:0) after 1m0s
[03:52:51.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:25:0)
[03:52:51.452] Timer tick, asking Buildbucket for the build status
[03:52:51.599] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:51.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:26:0) after 1m0s
[03:53:51.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:26:0)
[03:53:51.662] Timer tick, asking Buildbucket for the build status
[03:53:52.013] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:52.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:27:0) after 1m0s
[03:54:52.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:27:0)
[03:54:52.014] Timer tick, asking Buildbucket for the build status
[03:54:52.108] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:52.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:28:0) after 1m0s
[03:55:52.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:28:0)
[03:55:52.198] Timer tick, asking Buildbucket for the build status
[03:55:52.322] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:52.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:29:0) after 1m0s
[03:56:52.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:29:0)
[03:56:52.342] Timer tick, asking Buildbucket for the build status
[03:56:52.435] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:52.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:30:0) after 1m0s
[03:57:52.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:30:0)
[03:57:52.497] Timer tick, asking Buildbucket for the build status
[03:57:52.887] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:52.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:31:0) after 1m0s
[03:58:52.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:31:0)
[03:58:52.987] Timer tick, asking Buildbucket for the build status
[03:58:53.065] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:53.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:32:0) after 1m0s
[03:59:53.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:32:0)
[03:59:53.064] Timer tick, asking Buildbucket for the build status
[03:59:53.386] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:53.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:33:0) after 1m0s
[04:00:53.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:33:0)
[04:00:53.382] Timer tick, asking Buildbucket for the build status
[04:00:53.647] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:53.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:34:0) after 1m0s
[04:01:53.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:34:0)
[04:01:53.699] Timer tick, asking Buildbucket for the build status
[04:01:53.954] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:53.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:35:0) after 1m0s
[04:02:54.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:35:0)
[04:02:54.034] Timer tick, asking Buildbucket for the build status
[04:02:54.123] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:54.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:36:0) after 1m0s
[04:03:54.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:36:0)
[04:03:54.354] Timer tick, asking Buildbucket for the build status
[04:03:54.573] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:54.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:37:0) after 1m0s
[04:04:54.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:37:0)
[04:04:54.768] Timer tick, asking Buildbucket for the build status
[04:04:54.872] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:54.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:38:0) after 1m0s
[04:05:55.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:38:0)
[04:05:55.093] Timer tick, asking Buildbucket for the build status
[04:05:55.222] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:55.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:39:0) after 1m0s
[04:06:55.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:39:0)
[04:06:55.369] Timer tick, asking Buildbucket for the build status
[04:06:55.729] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:55.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:40:0) after 1m0s
[04:07:55.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:40:0)
[04:07:55.746] Timer tick, asking Buildbucket for the build status
[04:07:55.838] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:55.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:41:0) after 1m0s
[04:08:55.985] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:41:0)
[04:08:55.985] Timer tick, asking Buildbucket for the build status
[04:08:56.091] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:56.091] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:42:0) after 1m0s
[04:09:56.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:42:0)
[04:09:56.114] Timer tick, asking Buildbucket for the build status
[04:09:56.217] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:56.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:43:0) after 1m0s
[04:10:56.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:43:0)
[04:10:56.375] Timer tick, asking Buildbucket for the build status
[04:10:56.769] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:56.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:44:0) after 1m0s
[04:11:56.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:44:0)
[04:11:56.829] Timer tick, asking Buildbucket for the build status
[04:11:56.949] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:56.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:45:0) after 1m0s
[04:12:57.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:45:0)
[04:12:57.024] Timer tick, asking Buildbucket for the build status
[04:12:57.146] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:57.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:46:0) after 1m0s
[04:13:57.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:46:0)
[04:13:57.171] Timer tick, asking Buildbucket for the build status
[04:13:57.259] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:57.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:47:0) after 1m0s
[04:14:57.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:47:0)
[04:14:57.394] Timer tick, asking Buildbucket for the build status
[04:14:57.500] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:57.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:48:0) after 1m0s
[04:15:57.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:48:0)
[04:15:57.560] Timer tick, asking Buildbucket for the build status
[04:15:57.960] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:57.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:49:0) after 1m0s
[04:16:57.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:49:0)
[04:16:57.960] Timer tick, asking Buildbucket for the build status
[04:16:58.136] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:58.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:50:0) after 1m0s
[04:17:58.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:50:0)
[04:17:58.136] Timer tick, asking Buildbucket for the build status
[04:17:58.217] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:58.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:51:0) after 1m0s
[04:18:58.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:51:0)
[04:18:58.240] Timer tick, asking Buildbucket for the build status
[04:18:58.343] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:58.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:52:0) after 1m0s
[04:19:58.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:52:0)
[04:19:58.402] Timer tick, asking Buildbucket for the build status
[04:19:59.553] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:59.553] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:53:0) after 1m0s
[04:20:59.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:53:0)
[04:20:59.709] Timer tick, asking Buildbucket for the build status
[04:21:00.093] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:00.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:54:0) after 1m0s
[04:22:00.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:54:0)
[04:22:00.107] Timer tick, asking Buildbucket for the build status
[04:22:00.358] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:00.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:55:0) after 1m0s
[04:23:00.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:55:0)
[04:23:00.385] Timer tick, asking Buildbucket for the build status
[04:23:00.704] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:00.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:56:0) after 1m0s
[04:24:01.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:56:0)
[04:24:01.087] Timer tick, asking Buildbucket for the build status
[04:24:01.515] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:01.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:57:0) after 1m0s
[04:25:01.576] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:57:0)
[04:25:01.576] Timer tick, asking Buildbucket for the build status
[04:25:01.675] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:01.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:58:0) after 1m0s
[04:26:01.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:58:0)
[04:26:01.695] Timer tick, asking Buildbucket for the build status
[04:26:01.807] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:01.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:59:0) after 1m0s
[04:27:01.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:59:0)
[04:27:01.849] Timer tick, asking Buildbucket for the build status
[04:27:01.967] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:01.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:60:0) after 1m0s
[04:28:01.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:60:0)
[04:28:01.975] Timer tick, asking Buildbucket for the build status
[04:28:02.262] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:02.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:61:0) after 1m0s
[04:29:02.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:61:0)
[04:29:02.240] Timer tick, asking Buildbucket for the build status
[04:29:02.459] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:02.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:62:0) after 1m0s
[04:30:02.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:62:0)
[04:30:02.511] Timer tick, asking Buildbucket for the build status
[04:30:02.626] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:02.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:63:0) after 1m0s
[04:31:02.645] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:63:0)
[04:31:02.645] Timer tick, asking Buildbucket for the build status
[04:31:03.087] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:03.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:64:0) after 1m0s
[04:32:03.108] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:64:0)
[04:32:03.108] Timer tick, asking Buildbucket for the build status
[04:32:03.252] Build 8887787675968068512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:03.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053217738114181296:65:0) after 1m0s
[04:32:14.563] Received PubSub notification, asking Buildbucket for the build status
[04:32:14.939] Build 8887787675968068512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:32:14.939] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582345933276725",
"created_by": "project:chromium",
"created_ts": "1582342185965725",
"id": "8887787675968068512",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5549bc81b0bcb0178af928d5e86d413eff2907c6\"}}",
"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\"}, \"$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-80-843c\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168878, \"got_angle_revision\": \"eacec98a34990d832d59705562af02fd29d02b25\", \"got_dawn_revision\": \"bc34d908f60d458c6d9e416a7404eac4703e1192\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"5549bc81b0bcb0178af928d5e86d413eff2907c6\", \"got_revision_cp\": \"refs/heads/master@{#743775}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"847856089dc3f740f46f6f6af1be4c7cde50cdb2\", \"got_v8_revision\": \"4db453d66194197aade9f48e4ca61bc1c704dd56\", \"got_v8_revision_cp\": \"refs/heads/8.2.150@{#1}\", \"got_webrtc_revision\": \"1dea1ea412021819bddbc9400dcca7d9dd2eba74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30581}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5549bc81b0bcb0178af928d5e86d413eff2907c6\", \"swarm_hashes_refs/heads/master(at){#743775}_without_patch\": {\"accessibility_unittests\": \"6720b27a6fd6d9c0057915be03847289e171f478\", \"angle_unittests\": \"28aa96f52f45af415433898b07a860857f905ad8\", \"app_shell_unittests\": \"83491f47012562af8ec0d83d23ef25dee6ca1e45\", \"aura_unittests\": \"18c8bd84f7fa29070ebe15b832e5aeeb917124d5\", \"base_unittests\": \"a39062f80f2af6be2e9b97d0520b2ad32ddb5c63\", \"base_util_unittests\": \"709893934b8402f706b2b9f51084a665ddbc7f60\", \"blink_common_unittests\": \"18a15d6e89bd04e0be4bb2686d97c58b3b438096\", \"blink_fuzzer_unittests\": \"233e8ad5745e397563760315075fa728c2fbc7f0\", \"blink_heap_unittests\": \"108c41bfd99c96127d21fe7cc43b3eff596019b3\", \"blink_platform_unittests\": \"46d0e53fe704aa0310059db1b58d04de28ef379b\", \"blink_python_tests\": \"c47a65a2721d9810c8d40eeb27a44fb7b7254c03\", \"blink_unittests\": \"5333530dc5a144494da53300c55cd1f08b30c177\", \"blink_web_tests\": \"43f7c0c9b51ffaf8364368f18ca5130a65b6bb0a\", \"boringssl_crypto_tests\": \"232bcba2674d3dde52e8b95f9edb9c3717986d1a\", \"boringssl_ssl_tests\": \"3b880770546a25c043f4ebb7442c9bb8967f58a9\", \"browser_tests\": \"0571a6a74b011fce5275910e3a0aaed34b0f9b07\", \"cacheinvalidation_unittests\": \"a4cd3a038698aa743277926e0d66c071be443055\", \"capture_unittests\": \"d0f41cc4a0e6e33b5122d0f531b5756a2ad45cb6\", \"cast_unittests\": \"b40bc69d26fdc999bde2fa30f96fd34cb3b24548\", \"cc_unittests\": \"686fbbb4a90742e9db1d3ee7d257329de86aae93\", \"chrome_app_unittests\": \"3274e753321f194169cf7bc01d8e0fb7538f7dac\", \"chromedriver_unittests\": \"2be5371554615c9e73b1046d1849454486f5654a\", \"components_browsertests\": \"05294b5c8d5f7b41ea76c1738cf9b8d0a2398552\", \"components_unittests\": \"a98722603e2bbf3303b92941627439269f16527b\", \"compositor_unittests\": \"5d014e278c11693286112f3f74375af3889e897b\", \"content_browsertests\": \"da5117c038d4dcc9ef764e526df783a2d4343513\", \"content_shell_crash_test\": \"462644a67d6f72b5eb49d4ed8c192a5a712e7698\", \"content_unittests\": \"a5754a13b91268b639c822541752baaed1cf516e\", \"crashpad_tests\": \"9ce1e8e5ba1f7086cc641256213d63361fa3dabc\", \"cronet_tests\": \"b8a1fb95ff5415d069f08b11bce87be18b5b6717\", \"cronet_unittests\": \"1e7ab1aad37804bbb5f188d5c9bef3be9ec4d0c4\", \"crypto_unittests\": \"5e7b73d6a255dba63887844222ece64e420b81ca\", \"dbus_unittests\": \"b6ef333c362baf1b1764d441c1d3d300d3ab4b58\", \"device_unittests\": \"2cbc866fe5fd1f7f1667084f99d751a047d3f123\", \"display_unittests\": \"b01a9304476b9a03cfd7004c92d370e15ea1bf5a\", \"events_unittests\": \"08343d3e2f900959ae92fb0338fb61867719219c\", \"extensions_browsertests\": \"b36dcaa69368ade5909683c81f740b573474eca9\", \"extensions_unittests\": \"4872bc2ac39a2efb803525f329ecb5c052185063\", \"filesystem_service_unittests\": \"effe4547fb31a91aec92c11f4ae53bb017a4862a\", \"flatbuffers_unittests\": \"c3db37cb8981f7422026864ae3bd0765e7fa7de4\", \"gcm_unit_tests\": \"944ffa327d781c55c4790e9f6f2b3ab89cb1c5db\", \"gfx_unittests\": \"d5fb3753405983ec2fbb943d211d80065dad810c\", \"gin_unittests\": \"b246bad94af226ea9d063b7fce035c199f611c26\", \"google_apis_unittests\": \"51db240383e402a96650f12d40337545459fe0b6\", \"gpu_unittests\": \"5ca0cc24f1f3a6d637b5c4d71e05d759b21131a0\", \"grit_python_unittests\": \"73624ac0238f8ac67cfd1de0fd5785fdae8fa44b\", \"gwp_asan_unittests\": \"6f7725e4d75b630c77a9c956713800485f9a89a9\", \"headless_browsertests\": \"2f3f006a0a1026204d5d07c8e6f79e4f0d92be3d\", \"headless_unittests\": \"d8f2922f1d764faab859d79aed518c6813e2e6a9\", \"interactive_ui_tests\": \"e18c1aa4cac2d85cd80c7a7e7b15f65edfade6cd\", \"ipc_tests\": \"c903b587c0e82161ddadd7841b42eaff3b6d9149\", \"jingle_unittests\": \"f64a06e8d3258fe33ed72b64c71707be705887c5\", \"latency_unittests\": \"29f4a142ba8bc67111866d2ca475446bec8fa442\", \"libjingle_xmpp_unittests\": \"f29477f4b86720cc2b4d42b5ebf7b4e313210d52\", \"media_blink_unittests\": \"c67110a06912ac2c0f04202378abe682a6f4b02a\", \"media_service_unittests\": \"cd36d196d83e87bf1275f2d9e46d300184524467\", \"media_unittests\": \"f854c9c7102c3bbe58e7cf340734426263852412\", \"message_center_unittests\": \"74b87d2476e90b243d4a655f3659dab20ed90688\", \"metrics_python_tests\": \"3e97b24f3418f11cf0a0b0740a5a7856509f653f\", \"midi_unittests\": \"060c94e8b72442febf4b38c65a4554c9779d5e38\", \"mojo_core_unittests\": \"63ed2c6f6ce4d4fd968577446ee56c6839a0ebef\", \"mojo_unittests\": \"c10833071fbbab73376cfc3e0043318997b165c1\", \"nacl_helper_nonsfi_unittests\": \"3e0aad9eb8ba2cfd0c1b319ec5e035dd5d228563\", \"nacl_loader_unittests\": \"ba7097e59d75e8e3766ed78cc32007fc54cdb658\", \"native_theme_unittests\": \"006a2321cdcad1cb7698785f08345a5e64daeaf4\", \"net_unittests\": \"886a13c34731f49b4b4139df395b2509d9a9390a\", \"pdf_unittests\": \"793adb9a4db97820c7d60f2e6d38507ac649c77f\", \"perfetto_unittests\": \"8f6f8fd6863ce620bd86f1483a35fdecc747e756\", \"ppapi_unittests\": \"5dc8388de6f8133c6174afc684e2dd07a526b420\", \"printing_unittests\": \"1502ba00943d42aeb049fe03f0bc42b0cf851e32\", \"remoting_unittests\": \"fd3ffe3cf7ff435976ea9fcda4e28d238e3238a3\", \"sandbox_linux_unittests\": \"ca20553c5ae59046999f62fa7c8871e80cde47f0\", \"service_manager_unittests\": \"0c0c7a33deb0ccf63e348802f4b206bdce6eb5f7\", \"services_unittests\": \"48e007cb9ea95e81bed14f6fdbbbd88797d38281\", \"shell_dialogs_unittests\": \"4359bb2dc5b87472dd5dcd382181032afeda7759\", \"skia_unittests\": \"940f0865a17fd953bdca3d136a9c8fc5b2e760b8\", \"snapshot_unittests\": \"8668b12f815dd98557210f50e4836e937cb35239\", \"sql_unittests\": \"893c4d0797e7a6cf34ececff680a31bce1c01975\", \"storage_unittests\": \"491e247100a253b8b1ab24f8149e71a2384483cc\", \"sync_integration_tests\": \"a32e0a5dd933b12bfa10b1e34e0d5668066a77c2\", \"telemetry_gpu_unittests\": \"7133435c55acf8484c7dc16681b6d201c63e6421\", \"telemetry_perf_unittests\": \"c07a7e143ae5ad339d2171243a3bfbe3bddde1f9\", \"telemetry_unittests\": \"e9e4f9d5c49d76fb0890fa0ac0c9fb11423d7ec7\", \"traffic_annotation_auditor_unittests\": \"4e2cdbda17cd6ea28c74c8dc183fddca51e06a88\", \"ui_base_unittests\": \"2d3b0889453046986c543dff81cadf578bff605d\", \"ui_touch_selection_unittests\": \"4218d069cf84575ce15821461e8191bc8c3812bb\", \"unit_tests\": \"d296923cd486e20e934f2816f610bb5f482f3424\", \"url_unittests\": \"6e39fa5e5da288d4e3047026a8a363a50fcdfa01\", \"views_perftests\": \"68049854f15f681461f9704b18bf8409ae92eb4f\", \"views_unittests\": \"f75430ef0cbc5e0d3480ee6b0145ca487e9fb1b9\", \"viz_unittests\": \"454106bcf7860d7fe1f0e3aac3c76948efd0a7ef\", \"vr_common_unittests\": \"bb635c56cd079b268de6b80bf3d241abdd937c32\", \"vr_pixeltests\": \"8c3df5e1a3f85a37e32bf8b8cdd94075f28241fb\", \"webdriver_wpt_tests\": \"6ac4c1ef9d6f90e052b66f4ec5928b5d81da36b9\", \"weblayer_browsertests\": \"fef797888af7c4dc538b0d19356b2f56a02ad063\", \"wm_unittests\": \"d5c43319d9f2452898913115d18ef3bcb4a6e79d\", \"wtf_unittests\": \"234443fa53214483d122c7d9fdba3b98dba36df2\", \"xr_browser_tests\": \"3554ca6644c21fd67d07828315029c3c79efe417\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0cd6cf41ec42413cc9e5bdc0dde565dc7bee061bff1a52db1e25d07760950df7_v2\", \"builder_430de131691c92065dcde51cfe816220b2f886d2d859c4fe33bcc0b34c123c61_v2\", \"builder_479a9ab4e006ab9f281a30e38952e2c387e86fd61a54f6cd68389780c770d389_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_bdaf1433b62bd3619468ac2d2b745ba32f34c58221b7630e6cf9dedcc4b44d8b_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-80-843c\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582342447912052",
"status": "COMPLETED",
"status_changed_ts": "1582345933920443",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168878",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5549bc81b0bcb0178af928d5e86d413eff2907c6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5549bc81b0bcb0178af928d5e86d413eff2907c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053217738114181296",
"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/8887787675968068512/+/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:4a83c32e8c07c710",
"user_agent:luci-scheduler"
],
"updated_ts": "1582345933941130",
"url": "https://ci.chromium.org/b/8887787675968068512",
"utcnow_ts": "1582345934896181"
}
}
[04:32:14.939] Invocation finished in 1h2m30.403318296s with status SUCCEEDED