[13:17:21.425] New invocation is queued and will start shortly
[13:17:22.836] Starting the invocation (attempt 1)
[13:17:22.869] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:17:22.871] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:17:22.871] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032977645095876784",
"parameters_json": "{\"builder_name\":\"Mac deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Mac deterministic",
"scheduler_invocation_id:9032977645095876784",
"scheduler_job_id:chromium/Mac deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62",
"gitiles_ref:refs/heads/master"
]
}
[13:17:23.899] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1601644642959742",
"id": "8867547582822687568",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601644643605665",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/41222",
"builder:Mac deterministic",
"buildset:commit/git/915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032977645095876784",
"scheduler_job_id:chromium/Mac deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8867547582822687568/+/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": "1601644643605809",
"url": "https://ci.chromium.org/b/8867547582822687568",
"utcnow_ts": "1601644643889062"
}
}
[13:17:23.899] Task URL: https://ci.chromium.org/b/8867547582822687568
[13:17:23.899] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:2:0) after 1m0s
[13:17:28.405] Received PubSub notification, asking Buildbucket for the build status
[13:17:28.479] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:23.956] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:2:0)
[13:18:23.956] Timer tick, asking Buildbucket for the build status
[13:18:24.119] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:24.119] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:4:0) after 1m0s
[13:19:24.126] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:4:0)
[13:19:24.126] Timer tick, asking Buildbucket for the build status
[13:19:24.391] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:24.391] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:5:0) after 1m0s
[13:20:24.558] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:5:0)
[13:20:24.558] Timer tick, asking Buildbucket for the build status
[13:20:24.724] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:24.724] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:6:0) after 1m0s
[13:21:24.824] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:6:0)
[13:21:24.824] Timer tick, asking Buildbucket for the build status
[13:21:26.594] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:26.594] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:7:0) after 1m0s
[13:22:26.671] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:7:0)
[13:22:26.671] Timer tick, asking Buildbucket for the build status
[13:22:26.846] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:26.846] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:8:0) after 1m0s
[13:23:26.951] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:8:0)
[13:23:26.951] Timer tick, asking Buildbucket for the build status
[13:23:27.149] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:27.149] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:9:0) after 1m0s
[13:24:27.286] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:9:0)
[13:24:27.286] Timer tick, asking Buildbucket for the build status
[13:24:27.556] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:27.556] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:10:0) after 1m0s
[13:25:27.599] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:10:0)
[13:25:27.599] Timer tick, asking Buildbucket for the build status
[13:25:27.671] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:27.671] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:11:0) after 1m0s
[13:26:27.773] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:11:0)
[13:26:27.773] Timer tick, asking Buildbucket for the build status
[13:26:27.873] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:27.873] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:12:0) after 1m0s
[13:27:27.892] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:12:0)
[13:27:27.892] Timer tick, asking Buildbucket for the build status
[13:27:28.072] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:28.072] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:13:0) after 1m0s
[13:28:28.155] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:13:0)
[13:28:28.155] Timer tick, asking Buildbucket for the build status
[13:28:28.486] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:28.486] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:14:0) after 1m0s
[13:29:28.513] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:14:0)
[13:29:28.513] Timer tick, asking Buildbucket for the build status
[13:29:28.637] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:28.637] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:15:0) after 1m0s
[13:30:28.690] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:15:0)
[13:30:28.690] Timer tick, asking Buildbucket for the build status
[13:30:28.771] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:28.771] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:16:0) after 1m0s
[13:31:28.793] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:16:0)
[13:31:28.793] Timer tick, asking Buildbucket for the build status
[13:31:28.870] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:28.870] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:17:0) after 1m0s
[13:32:28.989] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:17:0)
[13:32:28.989] Timer tick, asking Buildbucket for the build status
[13:32:29.202] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:29.202] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:18:0) after 1m0s
[13:33:29.236] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:18:0)
[13:33:29.236] Timer tick, asking Buildbucket for the build status
[13:33:30.361] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:30.361] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:19:0) after 1m0s
[13:34:30.373] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:19:0)
[13:34:30.373] Timer tick, asking Buildbucket for the build status
[13:34:30.510] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:30.510] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:20:0) after 1m0s
[13:35:30.728] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:20:0)
[13:35:30.728] Timer tick, asking Buildbucket for the build status
[13:35:30.811] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:30.811] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:21:0) after 1m0s
[13:36:30.866] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:21:0)
[13:36:30.867] Timer tick, asking Buildbucket for the build status
[13:36:31.062] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:31.062] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:22:0) after 1m0s
[13:37:31.142] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:22:0)
[13:37:31.142] Timer tick, asking Buildbucket for the build status
[13:37:31.645] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:31.645] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:23:0) after 1m0s
[13:38:31.670] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:23:0)
[13:38:31.670] Timer tick, asking Buildbucket for the build status
[13:38:31.756] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:31.756] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:24:0) after 1m0s
[13:39:31.801] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:24:0)
[13:39:31.801] Timer tick, asking Buildbucket for the build status
[13:39:32.004] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:32.004] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:25:0) after 1m0s
[13:40:32.026] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:25:0)
[13:40:32.026] Timer tick, asking Buildbucket for the build status
[13:40:32.139] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:32.139] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:26:0) after 1m0s
[13:41:32.168] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:26:0)
[13:41:32.168] Timer tick, asking Buildbucket for the build status
[13:41:32.313] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:32.314] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:27:0) after 1m0s
[13:42:32.346] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:27:0)
[13:42:32.346] Timer tick, asking Buildbucket for the build status
[13:42:32.519] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:32.519] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:28:0) after 1m0s
[13:43:32.611] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:28:0)
[13:43:32.611] Timer tick, asking Buildbucket for the build status
[13:43:32.780] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:32.780] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:29:0) after 1m0s
[13:44:32.789] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:29:0)
[13:44:32.789] Timer tick, asking Buildbucket for the build status
[13:44:32.882] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:32.882] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:30:0) after 1m0s
[13:45:32.907] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:30:0)
[13:45:32.907] Timer tick, asking Buildbucket for the build status
[13:45:34.042] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:34.042] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:31:0) after 1m0s
[13:46:34.066] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:31:0)
[13:46:34.066] Timer tick, asking Buildbucket for the build status
[13:46:34.499] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:34.499] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:32:0) after 1m0s
[13:47:34.525] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:32:0)
[13:47:34.525] Timer tick, asking Buildbucket for the build status
[13:47:34.673] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:34.673] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:33:0) after 1m0s
[13:48:34.789] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:33:0)
[13:48:34.789] Timer tick, asking Buildbucket for the build status
[13:48:35.051] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:35.051] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:34:0) after 1m0s
[13:49:35.038] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:34:0)
[13:49:35.038] Timer tick, asking Buildbucket for the build status
[13:49:35.176] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:35.176] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:35:0) after 1m0s
[13:50:35.198] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:35:0)
[13:50:35.198] Timer tick, asking Buildbucket for the build status
[13:50:35.314] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:35.314] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:36:0) after 1m0s
[13:51:35.408] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:36:0)
[13:51:35.408] Timer tick, asking Buildbucket for the build status
[13:51:35.588] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:35.588] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:37:0) after 1m0s
[13:52:35.636] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:37:0)
[13:52:35.636] Timer tick, asking Buildbucket for the build status
[13:52:35.798] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:35.798] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:38:0) after 1m0s
[13:53:35.862] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:38:0)
[13:53:35.862] Timer tick, asking Buildbucket for the build status
[13:53:35.927] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:35.927] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:39:0) after 1m0s
[13:54:35.907] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:39:0)
[13:54:35.907] Timer tick, asking Buildbucket for the build status
[13:54:35.981] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:35.981] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:40:0) after 1m0s
[13:55:35.966] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:40:0)
[13:55:35.966] Timer tick, asking Buildbucket for the build status
[13:55:36.138] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:36.138] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:41:0) after 1m0s
[13:56:36.211] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:41:0)
[13:56:36.211] Timer tick, asking Buildbucket for the build status
[13:56:36.299] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:36.299] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:42:0) after 1m0s
[13:57:36.400] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:42:0)
[13:57:36.400] Timer tick, asking Buildbucket for the build status
[13:57:36.627] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:36.627] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:43:0) after 1m0s
[13:58:36.674] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:43:0)
[13:58:36.674] Timer tick, asking Buildbucket for the build status
[13:58:36.873] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:36.873] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:44:0) after 1m0s
[13:59:36.888] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:44:0)
[13:59:36.888] Timer tick, asking Buildbucket for the build status
[13:59:37.148] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:37.148] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:45:0) after 1m0s
[14:00:37.168] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:45:0)
[14:00:37.168] Timer tick, asking Buildbucket for the build status
[14:00:37.490] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:37.491] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:46:0) after 1m0s
[14:01:37.563] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:46:0)
[14:01:37.563] Timer tick, asking Buildbucket for the build status
[14:01:37.635] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:37.636] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:47:0) after 1m0s
[14:02:37.671] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:47:0)
[14:02:37.671] Timer tick, asking Buildbucket for the build status
[14:02:37.804] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:37.804] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:48:0) after 1m0s
[14:03:37.919] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:48:0)
[14:03:37.919] Timer tick, asking Buildbucket for the build status
[14:03:38.501] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:38.501] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:49:0) after 1m0s
[14:04:38.534] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:49:0)
[14:04:38.534] Timer tick, asking Buildbucket for the build status
[14:04:38.660] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:38.660] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:50:0) after 1m0s
[14:05:38.959] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:50:0)
[14:05:38.959] Timer tick, asking Buildbucket for the build status
[14:05:42.465] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:42.465] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:51:0) after 1m0s
[14:06:42.485] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:51:0)
[14:06:42.485] Timer tick, asking Buildbucket for the build status
[14:06:42.754] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:42.755] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:52:0) after 1m0s
[14:07:42.783] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:52:0)
[14:07:42.783] Timer tick, asking Buildbucket for the build status
[14:07:42.932] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:42.932] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:53:0) after 1m0s
[14:08:42.956] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:53:0)
[14:08:42.956] Timer tick, asking Buildbucket for the build status
[14:08:43.017] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:43.017] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:54:0) after 1m0s
[14:09:43.084] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:54:0)
[14:09:43.084] Timer tick, asking Buildbucket for the build status
[14:09:43.155] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:43.155] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:55:0) after 1m0s
[14:10:43.266] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:55:0)
[14:10:43.266] Timer tick, asking Buildbucket for the build status
[14:10:43.422] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:43.423] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:56:0) after 1m0s
[14:11:43.501] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:56:0)
[14:11:43.501] Timer tick, asking Buildbucket for the build status
[14:11:43.614] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:43.614] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:57:0) after 1m0s
[14:12:43.687] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:57:0)
[14:12:43.687] Timer tick, asking Buildbucket for the build status
[14:12:43.908] Build 8867547582822687568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:43.908] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9032977645095876784:58:0) after 1m0s
[14:13:12.840] Received PubSub notification, asking Buildbucket for the build status
[14:13:12.915] Build 8867547582822687568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:13:12.915] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1601647990452579",
"created_by": "project:chromium",
"created_ts": "1601644642959742",
"id": "8867547582822687568",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$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\": \"build204-m9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Mac deterministic\", \"buildnumber\": 41222, \"clang_revision\": \"llvmorg-12-init-5035-gd0abc757-3\", \"got_angle_revision\": \"7530bab30c12f7712301f00bcafc84defa8baade\", \"got_dawn_revision\": \"16ebcf601d7f814ec1e017f499276b7da92d61b5\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62\", \"got_revision_cp\": \"refs/heads/master@{#813158}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"02818d1fdd05410fa9d3ffa6f9ce313a63d03dcf\", \"got_v8_revision\": \"0d81cd72688512abcbe1601015baee390c484a6a\", \"got_v8_revision_cp\": \"refs/heads/8.7.220@{#1}\", \"got_webrtc_revision\": \"5b5abd79106a1770bb6fdab9e40f7270c7d4259a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32285}\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Mac deterministic\"], \"caches\": [\"builder_7b52a3a1245e897041cdcf338a3870df21540919220c864c03da3b37f2e3c589_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build204-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\", \"Mac-10.13.6-17G66\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"5564-e41b668\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1601644647088355",
"status": "COMPLETED",
"status_changed_ts": "1601647990932115",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/41222",
"builder:Mac deterministic",
"buildset:commit/git/915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/915c839f1a60c5b5c3ee8b86d0f7fe215a7d4d62",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032977645095876784",
"scheduler_job_id:chromium/Mac deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8867547582822687568/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4f02474e4d50de10",
"user_agent:luci-scheduler"
],
"updated_ts": "1601647991007125",
"url": "https://ci.chromium.org/b/8867547582822687568",
"utcnow_ts": "1601647992907254"
}
}
[14:13:12.915] Invocation finished in 55m51.50650332s with status SUCCEEDED