[22:36:44.380] New invocation is queued and will start shortly
[22:36:45.612] Starting the invocation (attempt 1)
[22:36:45.747] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:36:45.748] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:36:45.748] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9082589589373986112",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5e90d1d1d4e7ff5a9da59aad8fc779166c67f527\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9082589589373986112",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5e90d1d1d4e7ff5a9da59aad8fc779166c67f527",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e90d1d1d4e7ff5a9da59aad8fc779166c67f527",
"gitiles_ref:refs/heads/master"
]
}
[22:36:46.800] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554331006099836",
"id": "8917159526906913872",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5e90d1d1d4e7ff5a9da59aad8fc779166c67f527\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554331006680303",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/6026",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/5e90d1d1d4e7ff5a9da59aad8fc779166c67f527",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e90d1d1d4e7ff5a9da59aad8fc779166c67f527",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082589589373986112",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8917159526906913872/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1554331006680413",
"url": "https://ci.chromium.org/b/8917159526906913872",
"utcnow_ts": "1554331006792041"
}
}
[22:36:46.800] Task URL: https://ci.chromium.org/b/8917159526906913872
[22:36:46.800] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:2:0) after 1m0s
[22:36:50.802] Received PubSub notification, asking Buildbucket for the build status
[22:36:51.317] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:46.821] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:2:0)
[22:37:46.821] Timer tick, asking Buildbucket for the build status
[22:37:47.003] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:47.003] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:4:0) after 1m0s
[22:38:47.024] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:4:0)
[22:38:47.024] Timer tick, asking Buildbucket for the build status
[22:38:47.142] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:47.142] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:5:0) after 1m0s
[22:39:47.142] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:5:0)
[22:39:47.142] Timer tick, asking Buildbucket for the build status
[22:39:47.259] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:47.259] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:6:0) after 1m0s
[22:40:47.254] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:6:0)
[22:40:47.254] Timer tick, asking Buildbucket for the build status
[22:40:47.782] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:47.782] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:7:0) after 1m0s
[22:41:47.802] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:7:0)
[22:41:47.802] Timer tick, asking Buildbucket for the build status
[22:41:48.549] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:48.549] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:8:0) after 1m0s
[22:42:48.535] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:8:0)
[22:42:48.535] Timer tick, asking Buildbucket for the build status
[22:42:49.301] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:49.301] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:9:0) after 1m0s
[22:43:49.318] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:9:0)
[22:43:49.318] Timer tick, asking Buildbucket for the build status
[22:43:49.675] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:49.675] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:10:0) after 1m0s
[22:44:49.706] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:10:0)
[22:44:49.706] Timer tick, asking Buildbucket for the build status
[22:44:50.553] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:50.553] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:11:0) after 1m0s
[22:45:50.585] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:11:0)
[22:45:50.585] Timer tick, asking Buildbucket for the build status
[22:45:50.668] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:50.668] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:12:0) after 1m0s
[22:46:50.688] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:12:0)
[22:46:50.688] Timer tick, asking Buildbucket for the build status
[22:46:50.800] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:50.800] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:13:0) after 1m0s
[22:47:50.802] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:13:0)
[22:47:50.802] Timer tick, asking Buildbucket for the build status
[22:47:50.902] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:50.902] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:14:0) after 1m0s
[22:48:50.916] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:14:0)
[22:48:50.916] Timer tick, asking Buildbucket for the build status
[22:48:51.026] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:51.026] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:15:0) after 1m0s
[22:49:51.047] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:15:0)
[22:49:51.047] Timer tick, asking Buildbucket for the build status
[22:49:51.194] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:51.194] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:16:0) after 1m0s
[22:50:51.215] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:16:0)
[22:50:51.215] Timer tick, asking Buildbucket for the build status
[22:50:52.090] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:52.090] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:17:0) after 1m0s
[22:51:52.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:17:0)
[22:51:52.098] Timer tick, asking Buildbucket for the build status
[22:51:52.569] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:52.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:18:0) after 1m0s
[22:52:52.589] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:18:0)
[22:52:52.589] Timer tick, asking Buildbucket for the build status
[22:52:52.894] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:52.894] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:19:0) after 1m0s
[22:53:52.920] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:19:0)
[22:53:52.920] Timer tick, asking Buildbucket for the build status
[22:53:53.096] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:53.096] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:20:0) after 1m0s
[22:54:53.072] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:20:0)
[22:54:53.072] Timer tick, asking Buildbucket for the build status
[22:54:53.209] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:53.209] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:21:0) after 1m0s
[22:55:53.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:21:0)
[22:55:53.235] Timer tick, asking Buildbucket for the build status
[22:55:53.319] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:53.319] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:22:0) after 1m0s
[22:56:53.341] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:22:0)
[22:56:53.341] Timer tick, asking Buildbucket for the build status
[22:56:53.496] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.496] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:23:0) after 1m0s
[22:57:53.488] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:23:0)
[22:57:53.488] Timer tick, asking Buildbucket for the build status
[22:57:53.569] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:24:0) after 1m0s
[22:58:53.566] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:24:0)
[22:58:53.566] Timer tick, asking Buildbucket for the build status
[22:58:53.677] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:53.677] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:25:0) after 1m0s
[22:59:53.741] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:25:0)
[22:59:53.741] Timer tick, asking Buildbucket for the build status
[22:59:54.084] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:54.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:26:0) after 1m0s
[23:00:54.071] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:26:0)
[23:00:54.071] Timer tick, asking Buildbucket for the build status
[23:00:54.377] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:54.377] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:27:0) after 1m0s
[23:01:54.399] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:27:0)
[23:01:54.399] Timer tick, asking Buildbucket for the build status
[23:01:54.531] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:54.531] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:28:0) after 1m0s
[23:02:54.519] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:28:0)
[23:02:54.519] Timer tick, asking Buildbucket for the build status
[23:02:54.891] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:54.891] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:29:0) after 1m0s
[23:03:54.868] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:29:0)
[23:03:54.868] Timer tick, asking Buildbucket for the build status
[23:03:55.773] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:55.773] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:30:0) after 1m0s
[23:04:55.787] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:30:0)
[23:04:55.787] Timer tick, asking Buildbucket for the build status
[23:04:55.950] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:55.950] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:31:0) after 1m0s
[23:05:55.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:31:0)
[23:05:55.962] Timer tick, asking Buildbucket for the build status
[23:05:56.215] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:56.215] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:32:0) after 1m0s
[23:06:56.238] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:32:0)
[23:06:56.238] Timer tick, asking Buildbucket for the build status
[23:06:56.397] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:56.397] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:33:0) after 1m0s
[23:07:56.421] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:33:0)
[23:07:56.421] Timer tick, asking Buildbucket for the build status
[23:07:56.770] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:56.770] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:34:0) after 1m0s
[23:08:56.788] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:34:0)
[23:08:56.788] Timer tick, asking Buildbucket for the build status
[23:08:57.067] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:57.067] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:35:0) after 1m0s
[23:09:57.090] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:35:0)
[23:09:57.090] Timer tick, asking Buildbucket for the build status
[23:09:58.144] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:58.144] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:36:0) after 1m0s
[23:10:58.137] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:36:0)
[23:10:58.137] Timer tick, asking Buildbucket for the build status
[23:10:58.289] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:58.289] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:37:0) after 1m0s
[23:11:58.295] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:37:0)
[23:11:58.295] Timer tick, asking Buildbucket for the build status
[23:11:58.868] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:58.868] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:38:0) after 1m0s
[23:12:58.889] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:38:0)
[23:12:58.889] Timer tick, asking Buildbucket for the build status
[23:12:59.977] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:59.977] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:39:0) after 1m0s
[23:13:59.998] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:39:0)
[23:13:59.998] Timer tick, asking Buildbucket for the build status
[23:14:00.202] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:00.202] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:40:0) after 1m0s
[23:15:00.224] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:40:0)
[23:15:00.224] Timer tick, asking Buildbucket for the build status
[23:15:01.256] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:01.256] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:41:0) after 1m0s
[23:16:01.280] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:41:0)
[23:16:01.280] Timer tick, asking Buildbucket for the build status
[23:16:01.598] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:01.598] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:42:0) after 1m0s
[23:17:01.636] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:42:0)
[23:17:01.636] Timer tick, asking Buildbucket for the build status
[23:17:01.825] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:01.825] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:43:0) after 1m0s
[23:18:01.855] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:43:0)
[23:18:01.855] Timer tick, asking Buildbucket for the build status
[23:18:01.966] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:01.966] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:44:0) after 1m0s
[23:19:01.992] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:44:0)
[23:19:01.992] Timer tick, asking Buildbucket for the build status
[23:19:02.121] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:02.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:45:0) after 1m0s
[23:20:02.166] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:45:0)
[23:20:02.166] Timer tick, asking Buildbucket for the build status
[23:20:02.406] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:02.406] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:46:0) after 1m0s
[23:21:02.430] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:46:0)
[23:21:02.431] Timer tick, asking Buildbucket for the build status
[23:21:02.560] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:02.560] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:47:0) after 1m0s
[23:22:02.546] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:47:0)
[23:22:02.546] Timer tick, asking Buildbucket for the build status
[23:22:02.708] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:02.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:48:0) after 1m0s
[23:23:02.734] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:48:0)
[23:23:02.734] Timer tick, asking Buildbucket for the build status
[23:23:03.276] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:03.276] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:49:0) after 1m0s
[23:24:03.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:49:0)
[23:24:03.268] Timer tick, asking Buildbucket for the build status
[23:24:03.351] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:03.351] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:50:0) after 1m0s
[23:25:03.378] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:50:0)
[23:25:03.378] Timer tick, asking Buildbucket for the build status
[23:25:03.581] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:03.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:51:0) after 1m0s
[23:26:03.604] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:51:0)
[23:26:03.604] Timer tick, asking Buildbucket for the build status
[23:26:04.644] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:04.644] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:52:0) after 1m0s
[23:27:04.605] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:52:0)
[23:27:04.605] Timer tick, asking Buildbucket for the build status
[23:27:05.078] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:05.078] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:53:0) after 1m0s
[23:28:05.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:53:0)
[23:28:05.098] Timer tick, asking Buildbucket for the build status
[23:28:05.690] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:05.690] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:54:0) after 1m0s
[23:29:05.689] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:54:0)
[23:29:05.689] Timer tick, asking Buildbucket for the build status
[23:29:06.014] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:06.014] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:55:0) after 1m0s
[23:30:06.041] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:55:0)
[23:30:06.041] Timer tick, asking Buildbucket for the build status
[23:30:06.141] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:06.141] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:56:0) after 1m0s
[23:31:06.173] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:56:0)
[23:31:06.173] Timer tick, asking Buildbucket for the build status
[23:31:06.686] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:06.686] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:57:0) after 1m0s
[23:32:06.684] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:57:0)
[23:32:06.684] Timer tick, asking Buildbucket for the build status
[23:32:06.830] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:06.830] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:58:0) after 1m0s
[23:33:06.826] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:58:0)
[23:33:06.826] Timer tick, asking Buildbucket for the build status
[23:33:08.802] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:08.802] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:59:0) after 1m0s
[23:34:08.827] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:59:0)
[23:34:08.827] Timer tick, asking Buildbucket for the build status
[23:34:08.997] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:08.997] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:60:0) after 1m0s
[23:35:09.018] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:60:0)
[23:35:09.018] Timer tick, asking Buildbucket for the build status
[23:35:09.109] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:09.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:61:0) after 1m0s
[23:36:09.134] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:61:0)
[23:36:09.134] Timer tick, asking Buildbucket for the build status
[23:36:09.492] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:09.492] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:62:0) after 1m0s
[23:37:09.511] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:62:0)
[23:37:09.511] Timer tick, asking Buildbucket for the build status
[23:37:09.779] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:09.779] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:63:0) after 1m0s
[23:38:09.758] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:63:0)
[23:38:09.758] Timer tick, asking Buildbucket for the build status
[23:38:09.860] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:09.860] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:64:0) after 1m0s
[23:39:09.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:64:0)
[23:39:09.884] Timer tick, asking Buildbucket for the build status
[23:39:10.170] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:10.170] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:65:0) after 1m0s
[23:40:10.195] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:65:0)
[23:40:10.195] Timer tick, asking Buildbucket for the build status
[23:40:10.597] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:10.597] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:66:0) after 1m0s
[23:41:10.595] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:66:0)
[23:41:10.595] Timer tick, asking Buildbucket for the build status
[23:41:10.779] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:10.779] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:67:0) after 1m0s
[23:42:10.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:67:0)
[23:42:10.799] Timer tick, asking Buildbucket for the build status
[23:42:11.809] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:11.809] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:68:0) after 1m0s
[23:43:11.819] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:68:0)
[23:43:11.819] Timer tick, asking Buildbucket for the build status
[23:43:12.694] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:12.694] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:69:0) after 1m0s
[23:44:12.839] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:69:0)
[23:44:12.839] Timer tick, asking Buildbucket for the build status
[23:44:13.168] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:13.169] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:70:0) after 1m0s
[23:45:13.176] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:70:0)
[23:45:13.176] Timer tick, asking Buildbucket for the build status
[23:45:13.255] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:13.255] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:71:0) after 1m0s
[23:46:13.247] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:71:0)
[23:46:13.247] Timer tick, asking Buildbucket for the build status
[23:46:13.705] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:13.705] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:72:0) after 1m0s
[23:47:13.741] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:72:0)
[23:47:13.741] Timer tick, asking Buildbucket for the build status
[23:47:13.836] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:13.836] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:73:0) after 1m0s
[23:48:13.860] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:73:0)
[23:48:13.860] Timer tick, asking Buildbucket for the build status
[23:48:14.054] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:14.054] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:74:0) after 1m0s
[23:49:14.076] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:74:0)
[23:49:14.076] Timer tick, asking Buildbucket for the build status
[23:49:14.207] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:14.207] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:75:0) after 1m0s
[23:50:14.228] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:75:0)
[23:50:14.228] Timer tick, asking Buildbucket for the build status
[23:50:14.333] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:14.333] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:76:0) after 1m0s
[23:51:14.356] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:76:0)
[23:51:14.356] Timer tick, asking Buildbucket for the build status
[23:51:14.677] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:14.677] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:77:0) after 1m0s
[23:52:14.689] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:77:0)
[23:52:14.689] Timer tick, asking Buildbucket for the build status
[23:52:14.802] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:14.803] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:78:0) after 1m0s
[23:53:14.856] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:78:0)
[23:53:14.856] Timer tick, asking Buildbucket for the build status
[23:53:15.029] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:15.029] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:79:0) after 1m0s
[23:54:15.044] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:79:0)
[23:54:15.044] Timer tick, asking Buildbucket for the build status
[23:54:15.147] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:15.147] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:80:0) after 1m0s
[23:55:15.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:80:0)
[23:55:15.184] Timer tick, asking Buildbucket for the build status
[23:55:15.576] Build 8917159526906913872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:15.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9082589589373986112:81:0) after 1m0s
[23:55:37.377] Received PubSub notification, asking Buildbucket for the build status
[23:55:37.473] Build 8917159526906913872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:55:37.473] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1554335736892783",
"created_by": "project:chromium",
"created_ts": "1554331006099836",
"id": "8917159526906913872",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5e90d1d1d4e7ff5a9da59aad8fc779166c67f527\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0}, \"$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\": \"gce-trusty-e833d7b0-us-west1-b-5n6b\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 6026, \"got_angle_revision\": \"0719a88e7f248f5e9d46e54f73c182ed7fb1b5c5\", \"got_dawn_revision\": \"20b0c33913e45cd3d553b42a43b75b27af817833\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"5e90d1d1d4e7ff5a9da59aad8fc779166c67f527\", \"got_revision_cp\": \"refs/heads/master@{#647503}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"0b04d2f10d1093317050c40772c2ce550ee08d58\", \"got_v8_revision_cp\": \"refs/heads/7.5.178@{#1}\", \"got_webrtc_revision\": \"1c1b1ea137cef7b40e569eb79ff21849ddbdc3c5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27442}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5e90d1d1d4e7ff5a9da59aad8fc779166c67f527\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Deterministic Linux (dbg)\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-b-5n6b\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4240-c9c218d\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554331009637197",
"status": "COMPLETED",
"status_changed_ts": "1554335737185878",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/6026",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/5e90d1d1d4e7ff5a9da59aad8fc779166c67f527",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5e90d1d1d4e7ff5a9da59aad8fc779166c67f527",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082589589373986112",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8917159526906913872/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43fe2a7fe6671610",
"user_agent:luci-scheduler"
],
"updated_ts": "1554335737191746",
"url": "https://ci.chromium.org/b/8917159526906913872",
"utcnow_ts": "1554335737464231"
}
}
[23:55:37.473] Invocation finished in 1h18m53.110145516s with status SUCCEEDED