[10:44:18.096] New invocation is queued and will start shortly
[10:44:19.186] Starting the invocation (attempt 1)
[10:44:19.225] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e625a27fa0f05993698c770b9ad6a0638a7eb8c"
[10:44:19.225] Popped gitiles commit info from properties and tags
[10:44:19.225] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:44:19.225] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:44:19.225] Buildbucket request:
{
"requestId": "8974461634220637840",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4e625a27fa0f05993698c770b9ad6a0638a7eb8c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8974461634220637840"
},
{
"key": "scheduler_job_id",
"value": "chromium/Mac deterministic"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:44:19.866] Scheduled build:
{
"id": "8809031572272745489",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac deterministic"
},
"number": 56897,
"createdBy": "project:chromium",
"createTime": "2022-07-10T10:44:19.319663336Z",
"updateTime": "2022-07-10T10:44:19.319663336Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4e625a27fa0f05993698c770b9ad6a0638a7eb8c",
"ref": "refs/heads/main"
}
}
}
[10:44:19.866] Task URL: https://cr-buildbucket.appspot.com/build/8809031572272745489
[10:44:19.866] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:2:0) after 3m27s
[10:47:46.885] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:2:0)
[10:47:46.950] Build status: SCHEDULED
[10:47:46.950] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:3:0) after 9m1s
[10:56:47.968] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:3:0)
[10:56:47.996] Build status: SCHEDULED
[10:56:47.996] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:4:0) after 8m14s
[11:05:02.012] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:4:0)
[11:05:02.059] Build status: SCHEDULED
[11:05:02.059] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:5:0) after 1m30s
[11:06:32.079] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:5:0)
[11:06:32.099] Build status: SCHEDULED
[11:06:32.099] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:6:0) after 7m28s
[11:14:00.248] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:6:0)
[11:14:00.287] Build status: SCHEDULED
[11:14:00.287] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:7:0) after 3m44s
[11:17:44.256] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:7:0)
[11:17:44.285] Build status: SCHEDULED
[11:17:44.285] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:8:0) after 9m41s
[11:27:25.277] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:8:0)
[11:27:25.295] Build status: SCHEDULED
[11:27:25.295] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:9:0) after 7m12s
[11:34:37.314] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:9:0)
[11:34:37.345] Build status: SCHEDULED
[11:34:37.345] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:10:0) after 7m18s
[11:41:55.414] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:10:0)
[11:41:55.451] Build status: SCHEDULED
[11:41:55.451] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:11:0) after 3m54s
[11:45:49.488] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:11:0)
[11:45:49.538] Build status: SCHEDULED
[11:45:49.538] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:12:0) after 1m55s
[11:47:44.554] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:12:0)
[11:47:44.609] Build status: SCHEDULED
[11:47:44.609] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:13:0) after 7m29s
[11:55:13.640] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:13:0)
[11:55:13.677] Build status: SCHEDULED
[11:55:13.677] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:14:0) after 7m4s
[12:02:17.701] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:14:0)
[12:02:17.719] Build status: SCHEDULED
[12:02:17.719] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:15:0) after 7m40s
[12:09:57.738] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:15:0)
[12:09:57.759] Build status: SCHEDULED
[12:09:57.759] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:16:0) after 2m42s
[12:12:39.930] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:16:0)
[12:12:39.952] Build status: SCHEDULED
[12:12:39.952] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:17:0) after 6m36s
[12:19:16.036] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:17:0)
[12:19:16.064] Build status: SCHEDULED
[12:19:16.064] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:18:0) after 9m29s
[12:28:45.095] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:18:0)
[12:28:45.353] Build status: SCHEDULED
[12:28:45.353] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:19:0) after 9m3s
[12:37:48.377] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:19:0)
[12:37:48.458] Build status: SCHEDULED
[12:37:48.458] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:20:0) after 5m10s
[12:42:58.492] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:20:0)
[12:42:58.555] Build status: SCHEDULED
[12:42:58.555] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:21:0) after 2m2s
[12:45:00.575] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:21:0)
[12:45:00.625] Build status: SCHEDULED
[12:45:00.625] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:22:0) after 6m2s
[12:51:02.644] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:22:0)
[12:51:02.666] Build status: SCHEDULED
[12:51:02.666] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:23:0) after 3m5s
[12:54:07.764] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:23:0)
[12:54:07.796] Build status: SCHEDULED
[12:54:07.796] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:24:0) after 8m50s
[13:02:57.822] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:24:0)
[13:02:57.857] Build status: SCHEDULED
[13:02:57.857] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:25:0) after 9m26s
[13:12:23.941] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:25:0)
[13:12:24.085] Build status: SCHEDULED
[13:12:24.085] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:26:0) after 7m24s
[13:19:48.104] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:26:0)
[13:19:48.125] Build status: SCHEDULED
[13:19:48.125] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:27:0) after 5m50s
[13:25:38.144] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:27:0)
[13:25:38.163] Build status: SCHEDULED
[13:25:38.163] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:28:0) after 7m35s
[13:33:13.339] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:28:0)
[13:33:13.373] Build status: SCHEDULED
[13:33:13.373] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:29:0) after 6m56s
[13:40:09.490] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:29:0)
[13:40:09.526] Build status: SCHEDULED
[13:40:09.526] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:30:0) after 8m56s
[13:49:05.796] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:30:0)
[13:49:05.828] Build status: SCHEDULED
[13:49:05.829] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:31:0) after 4m29s
[13:53:34.847] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:31:0)
[13:53:34.883] Build status: SCHEDULED
[13:53:34.883] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:32:0) after 2m5s
[13:55:39.976] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:32:0)
[13:55:40.031] Build status: SCHEDULED
[13:55:40.031] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:33:0) after 6m42s
[14:02:23.236] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:33:0)
[14:02:23.278] Build status: SCHEDULED
[14:02:23.278] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:34:0) after 7m53s
[14:10:16.301] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:34:0)
[14:10:16.325] Build status: SCHEDULED
[14:10:16.325] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:35:0) after 2m7s
[14:12:23.305] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:35:0)
[14:12:23.339] Build status: SCHEDULED
[14:12:23.339] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:36:0) after 3m21s
[14:15:44.465] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:36:0)
[14:15:44.486] Build status: SCHEDULED
[14:15:44.486] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:37:0) after 4m55s
[14:20:39.569] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:37:0)
[14:20:39.604] Build status: SCHEDULED
[14:20:39.604] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:38:0) after 8m9s
[14:28:48.945] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:38:0)
[14:28:49.016] Build status: SCHEDULED
[14:28:49.016] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:39:0) after 8m50s
[14:37:39.040] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:39:0)
[14:37:39.061] Build status: SCHEDULED
[14:37:39.061] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:40:0) after 2m20s
[14:39:59.081] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:40:0)
[14:39:59.114] Build status: SCHEDULED
[14:39:59.114] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:41:0) after 2m28s
[14:42:27.966] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:41:0)
[14:42:27.989] Build status: SCHEDULED
[14:42:27.990] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:42:0) after 3m43s
[14:46:11.008] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:42:0)
[14:46:11.043] Build status: SCHEDULED
[14:46:11.043] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:43:0) after 1m44s
[14:47:55.061] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:43:0)
[14:47:55.098] Build status: SCHEDULED
[14:47:55.098] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:44:0) after 3m23s
[14:51:18.323] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:44:0)
[14:51:18.369] Build status: SCHEDULED
[14:51:18.369] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:45:0) after 7m19s
[14:58:37.478] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:45:0)
[14:58:37.506] Build status: SCHEDULED
[14:58:37.506] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:46:0) after 1m37s
[15:00:14.618] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:46:0)
[15:00:14.664] Build status: SCHEDULED
[15:00:14.664] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:47:0) after 6m17s
[15:06:31.681] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:47:0)
[15:06:31.711] Build status: SCHEDULED
[15:06:31.711] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:48:0) after 6m9s
[15:12:40.743] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:48:0)
[15:12:40.765] Build status: SCHEDULED
[15:12:40.765] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:49:0) after 2m52s
[15:15:32.794] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:49:0)
[15:15:32.814] Build status: SCHEDULED
[15:15:32.814] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:50:0) after 5m27s
[15:20:59.961] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:50:0)
[15:20:59.980] Build status: SCHEDULED
[15:20:59.980] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:51:0) after 8m27s
[15:29:27.135] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:51:0)
[15:29:27.157] Build status: SCHEDULED
[15:29:27.157] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:52:0) after 8m24s
[15:37:51.179] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:52:0)
[15:37:51.228] Build status: SCHEDULED
[15:37:51.228] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:53:0) after 6m24s
[15:44:15.378] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:53:0)
[15:44:15.431] Build status: SCHEDULED
[15:44:15.431] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:54:0) after 3m13s
[15:47:28.473] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:54:0)
[15:47:28.500] Build status: SCHEDULED
[15:47:28.500] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:55:0) after 5m31s
[15:52:59.657] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:55:0)
[15:52:59.676] Build status: SCHEDULED
[15:52:59.676] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:56:0) after 3m31s
[15:56:30.697] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:56:0)
[15:56:30.726] Build status: SCHEDULED
[15:56:30.726] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:57:0) after 8m32s
[16:05:02.782] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:57:0)
[16:05:02.829] Build status: SCHEDULED
[16:05:02.829] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:58:0) after 4m27s
[16:09:29.848] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:58:0)
[16:09:29.879] Build status: SCHEDULED
[16:09:29.879] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:59:0) after 7m14s
[16:16:43.899] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:59:0)
[16:16:43.937] Build status: SCHEDULED
[16:16:43.937] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:60:0) after 1m36s
[16:18:20.004] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:60:0)
[16:18:20.452] Build status: SCHEDULED
[16:18:20.452] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:61:0) after 4m3s
[16:22:23.574] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:61:0)
[16:22:23.604] Build status: SCHEDULED
[16:22:23.604] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:62:0) after 4m46s
[16:27:09.767] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:62:0)
[16:27:09.799] Build status: SCHEDULED
[16:27:09.800] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:63:0) after 3m9s
[16:30:18.924] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:63:0)
[16:30:18.959] Build status: SCHEDULED
[16:30:18.959] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:64:0) after 2m51s
[16:33:10.072] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:64:0)
[16:33:10.094] Build status: SCHEDULED
[16:33:10.094] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:65:0) after 2m27s
[16:35:37.117] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:65:0)
[16:35:37.142] Build status: SCHEDULED
[16:35:37.142] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:66:0) after 5m48s
[16:41:25.167] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:66:0)
[16:41:26.840] Build status: SCHEDULED
[16:41:26.840] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:8974461634220637840:67:0) after 4m22s
[16:45:04.843] Received PubSub notification, asking Buildbucket for the build status
[16:45:04.890] Build:
{
"id": "8809031572272745489",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Mac deterministic"
},
"number": 56897,
"createdBy": "project:chromium",
"createTime": "2022-07-10T10:44:19.319663336Z",
"endTime": "2022-07-10T16:45:02.432590Z",
"updateTime": "2022-07-10T16:45:04.666736Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"resourceExhaustion": {
},
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4e625a27fa0f05993698c770b9ad6a0638a7eb8c",
"ref": "refs/heads/main"
}
}
}
[16:45:04.891] Invocation finished in 6h0m46.812686145s with status FAILED