[16:55:36.082] New invocation is queued and will start shortly
[16:55:37.152] Starting the invocation (attempt 1)
[16:55:37.189] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:55:37.189] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:55:37.189] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083064036773517536",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"6ebb5a6b35f05b5bf6d4abc9699d956829682279\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9083064036773517536",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/6ebb5a6b35f05b5bf6d4abc9699d956829682279",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ebb5a6b35f05b5bf6d4abc9699d956829682279",
"gitiles_ref:refs/heads/master"
]
}
[16:55:38.084] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553878537327250",
"id": "8917633974802246896",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6ebb5a6b35f05b5bf6d4abc9699d956829682279\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553878537830301",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5930",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/6ebb5a6b35f05b5bf6d4abc9699d956829682279",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ebb5a6b35f05b5bf6d4abc9699d956829682279",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083064036773517536",
"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/8917633974802246896/+/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": "1553878537830394",
"url": "https://ci.chromium.org/b/8917633974802246896",
"utcnow_ts": "1553878538074910"
}
}
[16:55:38.084] Task URL: https://ci.chromium.org/b/8917633974802246896
[16:55:38.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:2:0) after 1m0s
[16:55:40.691] Received PubSub notification, asking Buildbucket for the build status
[16:55:40.789] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:38.108] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:2:0)
[16:56:38.108] Timer tick, asking Buildbucket for the build status
[16:56:38.319] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:38.319] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:4:0) after 1m0s
[16:57:38.344] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:4:0)
[16:57:38.344] Timer tick, asking Buildbucket for the build status
[16:57:38.448] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:38.448] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:5:0) after 1m0s
[16:58:38.468] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:5:0)
[16:58:38.468] Timer tick, asking Buildbucket for the build status
[16:58:38.793] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:38.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:6:0) after 1m0s
[16:59:38.814] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:6:0)
[16:59:38.814] Timer tick, asking Buildbucket for the build status
[16:59:39.234] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:39.234] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:7:0) after 1m0s
[17:00:39.222] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:7:0)
[17:00:39.222] Timer tick, asking Buildbucket for the build status
[17:00:39.577] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:39.577] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:8:0) after 1m0s
[17:01:39.597] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:8:0)
[17:01:39.598] Timer tick, asking Buildbucket for the build status
[17:01:39.812] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:39.812] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:9:0) after 1m0s
[17:02:39.807] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:9:0)
[17:02:39.807] Timer tick, asking Buildbucket for the build status
[17:02:39.947] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:39.947] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:10:0) after 1m0s
[17:03:39.967] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:10:0)
[17:03:39.967] Timer tick, asking Buildbucket for the build status
[17:03:40.666] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:40.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:11:0) after 1m0s
[17:04:40.666] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:11:0)
[17:04:40.666] Timer tick, asking Buildbucket for the build status
[17:04:40.745] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:40.746] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:12:0) after 1m0s
[17:05:40.728] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:12:0)
[17:05:40.728] Timer tick, asking Buildbucket for the build status
[17:05:41.044] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:41.044] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:13:0) after 1m0s
[17:06:41.064] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:13:0)
[17:06:41.064] Timer tick, asking Buildbucket for the build status
[17:06:41.300] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:41.300] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:14:0) after 1m0s
[17:07:41.357] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:14:0)
[17:07:41.357] Timer tick, asking Buildbucket for the build status
[17:07:41.460] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:41.460] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:15:0) after 1m0s
[17:08:41.448] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:15:0)
[17:08:41.448] Timer tick, asking Buildbucket for the build status
[17:08:42.404] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:42.404] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:16:0) after 1m0s
[17:09:42.408] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:16:0)
[17:09:42.408] Timer tick, asking Buildbucket for the build status
[17:09:42.562] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:42.562] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:17:0) after 1m0s
[17:10:42.581] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:17:0)
[17:10:42.581] Timer tick, asking Buildbucket for the build status
[17:10:42.846] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:42.846] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:18:0) after 1m0s
[17:11:42.866] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:18:0)
[17:11:42.866] Timer tick, asking Buildbucket for the build status
[17:11:42.964] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:42.964] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:19:0) after 1m0s
[17:12:42.985] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:19:0)
[17:12:42.985] Timer tick, asking Buildbucket for the build status
[17:12:43.173] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:43.174] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:20:0) after 1m0s
[17:13:43.193] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:20:0)
[17:13:43.193] Timer tick, asking Buildbucket for the build status
[17:13:43.294] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:43.294] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:21:0) after 1m0s
[17:14:43.319] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:21:0)
[17:14:43.319] Timer tick, asking Buildbucket for the build status
[17:14:43.409] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:43.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:22:0) after 1m0s
[17:15:43.428] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:22:0)
[17:15:43.428] Timer tick, asking Buildbucket for the build status
[17:15:43.562] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:43.562] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:23:0) after 1m0s
[17:16:43.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:23:0)
[17:16:43.556] Timer tick, asking Buildbucket for the build status
[17:16:43.681] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:43.681] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:24:0) after 1m0s
[17:17:43.685] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:24:0)
[17:17:43.685] Timer tick, asking Buildbucket for the build status
[17:17:44.310] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:44.310] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:25:0) after 1m0s
[17:18:44.396] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:25:0)
[17:18:44.396] Timer tick, asking Buildbucket for the build status
[17:18:44.534] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:44.534] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:26:0) after 1m0s
[17:19:44.522] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:26:0)
[17:19:44.522] Timer tick, asking Buildbucket for the build status
[17:19:44.968] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:44.968] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:27:0) after 1m0s
[17:20:45.057] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:27:0)
[17:20:45.057] Timer tick, asking Buildbucket for the build status
[17:20:45.598] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:45.598] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:28:0) after 1m0s
[17:21:45.617] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:28:0)
[17:21:45.617] Timer tick, asking Buildbucket for the build status
[17:21:45.806] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:45.806] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:29:0) after 1m0s
[17:22:45.845] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:29:0)
[17:22:45.846] Timer tick, asking Buildbucket for the build status
[17:22:45.971] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:45.971] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:30:0) after 1m0s
[17:23:45.948] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:30:0)
[17:23:45.948] Timer tick, asking Buildbucket for the build status
[17:23:46.389] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:46.389] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:31:0) after 1m0s
[17:24:46.412] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:31:0)
[17:24:46.412] Timer tick, asking Buildbucket for the build status
[17:24:46.586] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:46.586] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:32:0) after 1m0s
[17:25:46.610] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:32:0)
[17:25:46.610] Timer tick, asking Buildbucket for the build status
[17:25:46.742] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:46.742] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:33:0) after 1m0s
[17:26:46.768] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:33:0)
[17:26:46.768] Timer tick, asking Buildbucket for the build status
[17:26:46.949] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:46.949] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:34:0) after 1m0s
[17:27:46.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:34:0)
[17:27:46.962] Timer tick, asking Buildbucket for the build status
[17:27:47.193] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:47.193] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:35:0) after 1m0s
[17:28:47.213] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:35:0)
[17:28:47.213] Timer tick, asking Buildbucket for the build status
[17:28:47.320] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:47.320] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:36:0) after 1m0s
[17:29:47.345] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:36:0)
[17:29:47.346] Timer tick, asking Buildbucket for the build status
[17:29:47.496] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:47.497] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:37:0) after 1m0s
[17:30:47.514] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:37:0)
[17:30:47.514] Timer tick, asking Buildbucket for the build status
[17:30:47.627] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:47.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:38:0) after 1m0s
[17:31:47.647] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:38:0)
[17:31:47.647] Timer tick, asking Buildbucket for the build status
[17:31:47.871] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:47.871] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:39:0) after 1m0s
[17:32:47.907] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:39:0)
[17:32:47.907] Timer tick, asking Buildbucket for the build status
[17:32:48.011] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:48.011] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:40:0) after 1m0s
[17:33:48.031] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:40:0)
[17:33:48.031] Timer tick, asking Buildbucket for the build status
[17:33:48.164] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:48.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:41:0) after 1m0s
[17:34:48.194] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:41:0)
[17:34:48.194] Timer tick, asking Buildbucket for the build status
[17:34:48.370] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:48.370] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:42:0) after 1m0s
[17:35:48.400] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:42:0)
[17:35:48.400] Timer tick, asking Buildbucket for the build status
[17:35:48.487] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:48.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:43:0) after 1m0s
[17:36:48.459] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:43:0)
[17:36:48.459] Timer tick, asking Buildbucket for the build status
[17:36:48.587] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:48.587] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:44:0) after 1m0s
[17:37:48.612] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:44:0)
[17:37:48.612] Timer tick, asking Buildbucket for the build status
[17:37:48.719] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:48.719] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:45:0) after 1m0s
[17:38:48.733] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:45:0)
[17:38:48.733] Timer tick, asking Buildbucket for the build status
[17:38:49.023] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:49.023] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:46:0) after 1m0s
[17:39:49.060] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:46:0)
[17:39:49.060] Timer tick, asking Buildbucket for the build status
[17:39:49.165] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:49.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:47:0) after 1m0s
[17:40:49.200] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:47:0)
[17:40:49.200] Timer tick, asking Buildbucket for the build status
[17:40:49.503] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:49.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:48:0) after 1m0s
[17:41:49.522] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:48:0)
[17:41:49.522] Timer tick, asking Buildbucket for the build status
[17:41:49.615] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:49.615] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:49:0) after 1m0s
[17:42:49.626] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:49:0)
[17:42:49.627] Timer tick, asking Buildbucket for the build status
[17:42:49.798] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:49.798] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:50:0) after 1m0s
[17:43:49.833] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:50:0)
[17:43:49.833] Timer tick, asking Buildbucket for the build status
[17:43:50.212] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:50.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:51:0) after 1m0s
[17:44:50.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:51:0)
[17:44:50.235] Timer tick, asking Buildbucket for the build status
[17:44:50.412] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:50.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:52:0) after 1m0s
[17:45:50.473] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:52:0)
[17:45:50.473] Timer tick, asking Buildbucket for the build status
[17:45:50.867] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:50.867] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:53:0) after 1m0s
[17:46:50.853] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:53:0)
[17:46:50.853] Timer tick, asking Buildbucket for the build status
[17:46:50.981] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:50.981] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:54:0) after 1m0s
[17:47:51.002] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:54:0)
[17:47:51.002] Timer tick, asking Buildbucket for the build status
[17:47:51.287] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:51.287] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:55:0) after 1m0s
[17:48:51.286] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:55:0)
[17:48:51.286] Timer tick, asking Buildbucket for the build status
[17:48:51.535] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:51.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:56:0) after 1m0s
[17:49:51.534] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:56:0)
[17:49:51.534] Timer tick, asking Buildbucket for the build status
[17:49:51.632] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:51.632] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:57:0) after 1m0s
[17:50:51.820] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:57:0)
[17:50:51.820] Timer tick, asking Buildbucket for the build status
[17:50:51.916] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:51.917] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:58:0) after 1m0s
[17:51:51.898] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:58:0)
[17:51:51.898] Timer tick, asking Buildbucket for the build status
[17:51:52.055] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:52.055] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:59:0) after 1m0s
[17:52:52.077] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:59:0)
[17:52:52.077] Timer tick, asking Buildbucket for the build status
[17:52:52.198] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:52.198] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:60:0) after 1m0s
[17:53:52.219] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:60:0)
[17:53:52.219] Timer tick, asking Buildbucket for the build status
[17:53:52.326] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:52.326] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:61:0) after 1m0s
[17:54:52.357] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:61:0)
[17:54:52.358] Timer tick, asking Buildbucket for the build status
[17:54:52.790] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:52.790] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:62:0) after 1m0s
[17:55:52.816] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:62:0)
[17:55:52.816] Timer tick, asking Buildbucket for the build status
[17:55:52.934] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:52.934] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:63:0) after 1m0s
[17:56:52.913] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:63:0)
[17:56:52.913] Timer tick, asking Buildbucket for the build status
[17:56:53.114] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:53.114] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:64:0) after 1m0s
[17:57:53.137] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:64:0)
[17:57:53.137] Timer tick, asking Buildbucket for the build status
[17:57:53.280] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:53.280] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:65:0) after 1m0s
[17:58:53.298] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:65:0)
[17:58:53.298] Timer tick, asking Buildbucket for the build status
[17:58:53.399] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:53.399] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:66:0) after 1m0s
[17:59:53.407] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:66:0)
[17:59:53.407] Timer tick, asking Buildbucket for the build status
[17:59:53.569] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:53.570] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:67:0) after 1m0s
[18:00:53.607] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:67:0)
[18:00:53.607] Timer tick, asking Buildbucket for the build status
[18:00:53.873] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:53.873] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:68:0) after 1m0s
[18:01:53.878] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:68:0)
[18:01:53.878] Timer tick, asking Buildbucket for the build status
[18:01:53.963] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:53.963] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:69:0) after 1m0s
[18:02:53.972] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:69:0)
[18:02:53.972] Timer tick, asking Buildbucket for the build status
[18:02:54.066] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:54.066] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:70:0) after 1m0s
[18:03:54.102] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:70:0)
[18:03:54.102] Timer tick, asking Buildbucket for the build status
[18:03:54.207] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:54.207] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:71:0) after 1m0s
[18:04:54.245] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:71:0)
[18:04:54.245] Timer tick, asking Buildbucket for the build status
[18:04:54.415] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:54.415] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:72:0) after 1m0s
[18:05:54.442] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:72:0)
[18:05:54.442] Timer tick, asking Buildbucket for the build status
[18:05:54.756] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:54.756] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:73:0) after 1m0s
[18:06:54.740] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:73:0)
[18:06:54.740] Timer tick, asking Buildbucket for the build status
[18:06:54.867] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:54.867] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:74:0) after 1m0s
[18:07:54.889] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:74:0)
[18:07:54.889] Timer tick, asking Buildbucket for the build status
[18:07:55.187] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:55.187] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:75:0) after 1m0s
[18:08:55.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:75:0)
[18:08:55.209] Timer tick, asking Buildbucket for the build status
[18:08:55.777] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:55.777] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:76:0) after 1m0s
[18:09:55.809] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:76:0)
[18:09:55.809] Timer tick, asking Buildbucket for the build status
[18:09:56.556] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:56.556] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:77:0) after 1m0s
[18:10:56.537] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:77:0)
[18:10:56.537] Timer tick, asking Buildbucket for the build status
[18:10:56.647] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:56.647] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:78:0) after 1m0s
[18:11:56.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:78:0)
[18:11:56.696] Timer tick, asking Buildbucket for the build status
[18:11:56.784] Build 8917633974802246896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:56.784] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9083064036773517536:79:0) after 1m0s
[18:12:45.679] Received PubSub notification, asking Buildbucket for the build status
[18:12:45.782] Build 8917633974802246896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:12:45.782] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553883165244225",
"created_by": "project:chromium",
"created_ts": "1553878537327250",
"id": "8917633974802246896",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6ebb5a6b35f05b5bf6d4abc9699d956829682279\"}}",
"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-a-s6lc\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 5930, \"got_angle_revision\": \"8b40e9414396ddcb554b0698fe3551492e68f0a9\", \"got_dawn_revision\": \"10c24684ab9cd1c747e251d7cb4590c43eecad6b\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"6ebb5a6b35f05b5bf6d4abc9699d956829682279\", \"got_revision_cp\": \"refs/heads/master@{#645815}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"02579434b90177d3065f939405c76dd596b8f611\", \"got_v8_revision_cp\": \"refs/heads/7.5.143@{#1}\", \"got_webrtc_revision\": \"d9b62d32283e12cb6b49a03e16f9128229047d81\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27353}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6ebb5a6b35f05b5bf6d4abc9699d956829682279\"}, \"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-a-s6lc\"], \"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\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-a\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553878539687739",
"status": "COMPLETED",
"status_changed_ts": "1553883165499349",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/5930",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/6ebb5a6b35f05b5bf6d4abc9699d956829682279",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ebb5a6b35f05b5bf6d4abc9699d956829682279",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083064036773517536",
"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/8917633974802246896/+/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:43e3325fa603b910",
"user_agent:luci-scheduler"
],
"updated_ts": "1553883165502508",
"url": "https://ci.chromium.org/b/8917633974802246896",
"utcnow_ts": "1553883165771830"
}
}
[18:12:45.782] Invocation finished in 1h17m9.719264393s with status SUCCEEDED