[10:16:16.067] New invocation is queued and will start shortly
[10:16:17.139] Starting the invocation (attempt 1)
[10:16:17.174] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d2510bdc1e8e56e5d6aa6800bed77d38dbb46f6"
[10:16:17.174] Popped gitiles commit info from properties and tags
[10:16:17.174] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:16:17.174] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:16:17.174] Buildbucket request:
{
"requestId": "8991858015501827760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8d2510bdc1e8e56e5d6aa6800bed77d38dbb46f6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8991858015501827760"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMac"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:16:17.748] Scheduled build:
{
"id": "8826427953543264561",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac"
},
"number": 27389,
"createdBy": "project:chromium",
"createTime": "2021-12-30T10:16:17.303546712Z",
"updateTime": "2021-12-30T10:16:17.303546712Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8d2510bdc1e8e56e5d6aa6800bed77d38dbb46f6",
"ref": "refs/heads/main"
}
}
}
[10:16:17.748] Task URL: https://cr-buildbucket.appspot.com/build/8826427953543264561
[10:16:17.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:2:0) after 7m38s
[10:23:55.861] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:2:0)
[10:23:55.879] Build status: SCHEDULED
[10:23:55.879] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:3:0) after 3m9s
[10:27:04.922] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:3:0)
[10:27:04.952] Build status: SCHEDULED
[10:27:04.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:4:0) after 6m35s
[10:33:39.971] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:4:0)
[10:33:39.992] Build status: SCHEDULED
[10:33:39.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:5:0) after 2m20s
[10:36:00.067] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:5:0)
[10:36:00.085] Build status: SCHEDULED
[10:36:00.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:6:0) after 2m20s
[10:38:20.065] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:6:0)
[10:38:20.085] Build status: SCHEDULED
[10:38:20.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:7:0) after 7m55s
[10:43:59.361] Received PubSub notification, asking Buildbucket for the build status
[10:43:59.393] Build status: STARTED
[10:46:15.360] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:7:0)
[10:46:15.388] Build status: STARTED
[10:46:15.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:9:0) after 2m54s
[10:49:09.462] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:9:0)
[10:49:09.489] Build status: STARTED
[10:49:09.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:10:0) after 7m6s
[10:56:15.506] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:10:0)
[10:56:15.533] Build status: STARTED
[10:56:15.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:11:0) after 9m26s
[11:05:41.613] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:11:0)
[11:05:41.653] Build status: STARTED
[11:05:41.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:12:0) after 7m3s
[11:12:44.881] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:12:0)
[11:12:44.916] Build status: STARTED
[11:12:44.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:13:0) after 4m8s
[11:16:52.983] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:13:0)
[11:16:53.019] Build status: STARTED
[11:16:53.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:14:0) after 3m26s
[11:20:19.040] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:14:0)
[11:20:19.071] Build status: STARTED
[11:20:19.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:15:0) after 2m37s
[11:22:56.082] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:15:0)
[11:22:56.102] Build status: STARTED
[11:22:56.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:16:0) after 3m46s
[11:26:42.121] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:16:0)
[11:26:42.144] Build status: STARTED
[11:26:42.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:17:0) after 9m53s
[11:36:35.238] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:17:0)
[11:36:35.270] Build status: STARTED
[11:36:35.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:18:0) after 2m55s
[11:39:30.305] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:18:0)
[11:39:30.326] Build status: STARTED
[11:39:30.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:19:0) after 3m41s
[11:43:11.484] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:19:0)
[11:43:11.536] Build status: STARTED
[11:43:11.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:20:0) after 2m0s
[11:45:11.690] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:20:0)
[11:45:11.711] Build status: STARTED
[11:45:11.711] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:21:0) after 1m41s
[11:46:52.731] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:21:0)
[11:46:52.758] Build status: STARTED
[11:46:52.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:22:0) after 7m31s
[11:54:24.129] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:22:0)
[11:54:24.161] Build status: STARTED
[11:54:24.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:23:0) after 6m11s
[12:00:35.209] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:23:0)
[12:00:35.300] Build status: STARTED
[12:00:35.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:24:0) after 1m22s
[12:01:57.360] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:24:0)
[12:01:57.445] Build status: STARTED
[12:01:57.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:25:0) after 5m5s
[12:07:02.463] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:25:0)
[12:07:02.496] Build status: STARTED
[12:07:02.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:26:0) after 2m35s
[12:09:38.004] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:26:0)
[12:09:38.034] Build status: STARTED
[12:09:38.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:27:0) after 4m45s
[12:14:23.057] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:27:0)
[12:14:23.086] Build status: STARTED
[12:14:23.086] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:28:0) after 8m40s
[12:23:03.128] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:28:0)
[12:23:03.149] Build status: STARTED
[12:23:03.149] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:29:0) after 9m6s
[12:32:09.173] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:29:0)
[12:32:09.224] Build status: STARTED
[12:32:09.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:30:0) after 1m9s
[12:33:18.298] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:30:0)
[12:33:18.360] Build status: STARTED
[12:33:18.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:31:0) after 9m46s
[12:43:04.377] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:31:0)
[12:43:04.397] Build status: STARTED
[12:43:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:32:0) after 4m27s
[12:47:31.444] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:32:0)
[12:47:31.474] Build status: STARTED
[12:47:31.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:33:0) after 3m20s
[12:50:51.494] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:33:0)
[12:50:51.531] Build status: STARTED
[12:50:51.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:34:0) after 9m4s
[12:59:55.554] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:34:0)
[12:59:55.592] Build status: STARTED
[12:59:55.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:35:0) after 8m42s
[13:08:37.614] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:35:0)
[13:08:37.641] Build status: STARTED
[13:08:37.641] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:36:0) after 6m47s
[13:15:24.643] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:36:0)
[13:15:24.694] Build status: STARTED
[13:15:24.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:37:0) after 8m34s
[13:23:58.823] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:37:0)
[13:23:58.856] Build status: STARTED
[13:23:58.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:38:0) after 7m50s
[13:31:48.880] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:38:0)
[13:31:48.905] Build status: STARTED
[13:31:48.905] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:39:0) after 3m32s
[13:35:21.166] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:39:0)
[13:35:21.197] Build status: STARTED
[13:35:21.197] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:40:0) after 8m18s
[13:43:39.746] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:40:0)
[13:43:39.780] Build status: STARTED
[13:43:39.780] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:41:0) after 7m7s
[13:50:46.804] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:41:0)
[13:50:46.823] Build status: STARTED
[13:50:46.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:42:0) after 7m58s
[13:58:44.844] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:42:0)
[13:58:44.936] Build status: STARTED
[13:58:44.936] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:43:0) after 8m29s
[14:07:14.008] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:43:0)
[14:07:14.040] Build status: STARTED
[14:07:14.040] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:44:0) after 5m5s
[14:12:19.059] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:44:0)
[14:12:19.089] Build status: STARTED
[14:12:19.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:45:0) after 4m2s
[14:16:21.208] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:45:0)
[14:16:21.237] Build status: STARTED
[14:16:21.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:46:0) after 3m23s
[14:19:44.462] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:46:0)
[14:19:44.486] Build status: STARTED
[14:19:44.486] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:47:0) after 6m57s
[14:26:41.544] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:47:0)
[14:26:41.580] Build status: STARTED
[14:26:41.580] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:48:0) after 8m4s
[14:34:45.605] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:48:0)
[14:34:45.664] Build status: STARTED
[14:34:45.664] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:49:0) after 2m26s
[14:37:11.718] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:49:0)
[14:37:11.753] Build status: STARTED
[14:37:11.753] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:50:0) after 2m12s
[14:39:24.025] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:50:0)
[14:39:24.062] Build status: STARTED
[14:39:24.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:51:0) after 1m29s
[14:40:53.081] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:51:0)
[14:40:53.103] Build status: STARTED
[14:40:53.103] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:52:0) after 5m1s
[14:45:54.120] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:52:0)
[14:45:54.140] Build status: STARTED
[14:45:54.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:53:0) after 5m21s
[14:51:16.217] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:53:0)
[14:51:16.254] Build status: STARTED
[14:51:16.254] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:54:0) after 3m25s
[14:54:41.438] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:54:0)
[14:54:41.461] Build status: STARTED
[14:54:41.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:55:0) after 1m35s
[14:56:16.595] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:55:0)
[14:56:16.615] Build status: STARTED
[14:56:16.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:56:0) after 9m8s
[15:05:24.585] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:56:0)
[15:05:24.603] Build status: STARTED
[15:05:24.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:57:0) after 6m14s
[15:11:38.643] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:57:0)
[15:11:38.782] Build status: STARTED
[15:11:38.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:58:0) after 4m36s
[15:16:14.970] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:58:0)
[15:16:15.031] Build status: STARTED
[15:16:15.031] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:59:0) after 8m11s
[15:24:26.183] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:59:0)
[15:24:26.219] Build status: STARTED
[15:24:26.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:60:0) after 5m43s
[15:30:09.413] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:60:0)
[15:30:09.464] Build status: STARTED
[15:30:09.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:61:0) after 9m17s
[15:39:26.486] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:61:0)
[15:39:26.506] Build status: STARTED
[15:39:26.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:62:0) after 9m21s
[15:48:47.531] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:62:0)
[15:48:47.550] Build status: STARTED
[15:48:47.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:63:0) after 7m10s
[15:55:57.580] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:63:0)
[15:55:57.605] Build status: STARTED
[15:55:57.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:64:0) after 5m17s
[16:01:14.726] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:64:0)
[16:01:14.752] Build status: STARTED
[16:01:14.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:65:0) after 6m44s
[16:07:58.773] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:65:0)
[16:07:58.795] Build status: STARTED
[16:07:58.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:66:0) after 7m6s
[16:15:04.823] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:66:0)
[16:15:04.948] Build status: STARTED
[16:15:04.948] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:67:0) after 4m47s
[16:19:51.960] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:67:0)
[16:19:51.979] Build status: STARTED
[16:19:51.979] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:68:0) after 4m33s
[16:24:25.002] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:68:0)
[16:24:25.039] Build status: STARTED
[16:24:25.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:69:0) after 2m18s
[16:26:43.128] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:69:0)
[16:26:43.150] Build status: STARTED
[16:26:43.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:70:0) after 2m31s
[16:29:14.196] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:70:0)
[16:29:14.236] Build status: STARTED
[16:29:14.236] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:71:0) after 4m29s
[16:33:43.256] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:71:0)
[16:33:43.368] Build status: STARTED
[16:33:43.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:72:0) after 9m55s
[16:43:38.488] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:72:0)
[16:43:38.602] Build status: STARTED
[16:43:38.602] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:73:0) after 7m51s
[16:51:29.628] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:73:0)
[16:51:29.660] Build status: STARTED
[16:51:29.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:74:0) after 5m56s
[16:57:25.683] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:74:0)
[16:57:25.753] Build status: STARTED
[16:57:25.753] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:75:0) after 9m35s
[17:07:00.778] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:75:0)
[17:07:00.825] Build status: STARTED
[17:07:00.825] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:76:0) after 1m40s
[17:08:40.844] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:76:0)
[17:08:40.871] Build status: STARTED
[17:08:40.871] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:77:0) after 7m58s
[17:16:38.890] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:77:0)
[17:16:38.915] Build status: STARTED
[17:16:38.915] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:78:0) after 6m36s
[17:23:14.937] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:78:0)
[17:23:14.965] Build status: STARTED
[17:23:14.965] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:79:0) after 7m56s
[17:31:10.998] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:79:0)
[17:31:11.091] Build status: STARTED
[17:31:11.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:80:0) after 4m40s
[17:35:51.115] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:80:0)
[17:35:51.133] Build status: STARTED
[17:35:51.133] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:81:0) after 5m46s
[17:41:37.162] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:81:0)
[17:41:37.186] Build status: STARTED
[17:41:37.187] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:82:0) after 1m29s
[17:43:06.208] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:82:0)
[17:43:06.229] Build status: STARTED
[17:43:06.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:83:0) after 6m14s
[17:49:20.340] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:83:0)
[17:49:20.361] Build status: STARTED
[17:49:20.361] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:84:0) after 2m27s
[17:51:47.379] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:84:0)
[17:51:47.396] Build status: STARTED
[17:51:47.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:85:0) after 5m31s
[17:57:18.419] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:85:0)
[17:57:18.451] Build status: STARTED
[17:57:18.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:86:0) after 9m33s
[18:06:51.652] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:86:0)
[18:06:51.685] Build status: STARTED
[18:06:51.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:87:0) after 5m14s
[18:12:05.805] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:87:0)
[18:12:05.828] Build status: STARTED
[18:12:05.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:88:0) after 1m23s
[18:13:28.995] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:88:0)
[18:13:29.014] Build status: STARTED
[18:13:29.014] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:89:0) after 8m42s
[18:22:11.039] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:89:0)
[18:22:11.064] Build status: STARTED
[18:22:11.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:90:0) after 2m33s
[18:24:44.087] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:90:0)
[18:24:44.150] Build status: STARTED
[18:24:44.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:91:0) after 2m40s
[18:27:24.173] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:91:0)
[18:27:24.212] Build status: STARTED
[18:27:24.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:92:0) after 1m21s
[18:28:45.246] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:92:0)
[18:28:45.294] Build status: STARTED
[18:28:45.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:93:0) after 2m52s
[18:31:37.315] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:93:0)
[18:31:37.336] Build status: STARTED
[18:31:37.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:94:0) after 5m36s
[18:37:13.363] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:94:0)
[18:37:13.394] Build status: STARTED
[18:37:13.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:95:0) after 5m24s
[18:42:37.413] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:95:0)
[18:42:37.435] Build status: STARTED
[18:42:37.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:96:0) after 2m48s
[18:45:25.469] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:96:0)
[18:45:25.502] Build status: STARTED
[18:45:25.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:97:0) after 1m12s
[18:46:37.526] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:97:0)
[18:46:37.549] Build status: STARTED
[18:46:37.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:98:0) after 7m4s
[18:53:41.570] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:98:0)
[18:53:41.589] Build status: STARTED
[18:53:41.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:99:0) after 9m14s
[19:02:55.709] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:99:0)
[19:02:55.896] Build status: STARTED
[19:02:55.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:100:0) after 4m22s
[19:07:18.057] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:100:0)
[19:07:18.088] Build status: STARTED
[19:07:18.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:101:0) after 8m27s
[19:15:45.110] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:101:0)
[19:15:45.128] Build status: STARTED
[19:15:45.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:102:0) after 7m55s
[19:23:40.348] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:102:0)
[19:23:40.377] Build status: STARTED
[19:23:40.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:103:0) after 4m58s
[19:28:38.364] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:103:0)
[19:28:38.432] Build status: STARTED
[19:28:38.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:104:0) after 3m50s
[19:32:28.541] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:104:0)
[19:32:28.567] Build status: STARTED
[19:32:28.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:105:0) after 2m3s
[19:34:31.640] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:105:0)
[19:34:31.659] Build status: STARTED
[19:34:31.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:106:0) after 6m2s
[19:40:33.680] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:106:0)
[19:40:33.702] Build status: STARTED
[19:40:33.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:107:0) after 3m43s
[19:44:16.724] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:107:0)
[19:44:16.746] Build status: STARTED
[19:44:16.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:108:0) after 7m23s
[19:51:39.771] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:108:0)
[19:51:39.791] Build status: STARTED
[19:51:39.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:109:0) after 4m12s
[19:55:51.811] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:109:0)
[19:55:51.831] Build status: STARTED
[19:55:51.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:110:0) after 5m29s
[20:01:20.874] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:110:0)
[20:01:20.897] Build status: STARTED
[20:01:20.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:111:0) after 9m12s
[20:10:33.000] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:111:0)
[20:10:33.049] Build status: STARTED
[20:10:33.049] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:112:0) after 7m50s
[20:18:23.070] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:112:0)
[20:18:23.092] Build status: STARTED
[20:18:23.092] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:113:0) after 5m41s
[20:24:04.270] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:113:0)
[20:24:04.296] Build status: STARTED
[20:24:04.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:114:0) after 2m11s
[20:26:15.681] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:114:0)
[20:26:15.747] Build status: STARTED
[20:26:15.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:115:0) after 9m34s
[20:35:49.847] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:115:0)
[20:35:49.891] Build status: STARTED
[20:35:49.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:116:0) after 7m15s
[20:43:04.952] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:116:0)
[20:43:05.021] Build status: STARTED
[20:43:05.021] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:117:0) after 7m42s
[20:50:47.148] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:117:0)
[20:50:47.303] Build status: STARTED
[20:50:47.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:118:0) after 4m50s
[20:55:37.410] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:118:0)
[20:55:37.446] Build status: STARTED
[20:55:37.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:119:0) after 8m34s
[21:04:11.651] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:119:0)
[21:04:11.679] Build status: STARTED
[21:04:11.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:120:0) after 5m39s
[21:09:50.861] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:120:0)
[21:09:50.899] Build status: STARTED
[21:09:50.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:121:0) after 1m42s
[21:11:32.902] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:121:0)
[21:11:32.936] Build status: STARTED
[21:11:32.936] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:122:0) after 6m32s
[21:18:04.960] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:122:0)
[21:18:04.988] Build status: STARTED
[21:18:04.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:123:0) after 5m51s
[21:23:56.012] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:123:0)
[21:23:56.044] Build status: STARTED
[21:23:56.044] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8991858015501827760:124:0) after 7m40s
[21:27:56.599] Received PubSub notification, asking Buildbucket for the build status
[21:27:56.618] Build:
{
"id": "8826427953543264561",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac"
},
"number": 27389,
"createdBy": "project:chromium",
"createTime": "2021-12-30T10:16:17.303546712Z",
"startTime": "2021-12-30T10:43:58.436889Z",
"endTime": "2021-12-30T21:27:56.238723835Z",
"updateTime": "2021-12-30T21:27:56.238723835Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8d2510bdc1e8e56e5d6aa6800bed77d38dbb46f6",
"ref": "refs/heads/main"
}
}
}
[21:27:56.618] Invocation finished in 11h11m40.565379838s with status FAILED