[18:40:47.185] New invocation is queued and will start shortly
[18:40:48.341] Starting the invocation (attempt 1)
[18:40:48.373] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/333449160b5c4e84c00bad0791e166cc6bba08f5"
[18:40:48.373] Popped gitiles commit info from properties and tags
[18:40:48.373] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:40:48.373] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:40:48.373] Buildbucket request:
{
"requestId": "8938827048587201152",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "333449160b5c4e84c00bad0791e166cc6bba08f5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938827048587201152"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMacASan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:40:49.000] Scheduled build:
{
"id": "8773396986602894865",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 29312,
"createdBy": "project:chromium",
"createTime": "2023-08-07T18:40:48.448658316Z",
"updateTime": "2023-08-07T18:40:48.448658316Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "333449160b5c4e84c00bad0791e166cc6bba08f5",
"ref": "refs/heads/main"
}
}
}
[18:40:49.000] Task URL: https://cr-buildbucket.appspot.com/build/8773396986602894865
[18:40:49.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:2:0) after 3m39s
[18:40:49.096] Received PubSub notification, asking Buildbucket for the build status
[18:40:49.123] Build status: SCHEDULED
[18:44:28.019] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:2:0)
[18:44:28.048] Build status: SCHEDULED
[18:44:28.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:4:0) after 9m12s
[18:45:22.404] Received PubSub notification, asking Buildbucket for the build status
[18:45:22.426] Build status: STARTED
[18:53:40.173] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:4:0)
[18:53:40.208] Build status: STARTED
[18:53:40.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:6:0) after 2m24s
[18:56:04.259] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:6:0)
[18:56:04.318] Build status: STARTED
[18:56:04.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:7:0) after 3m21s
[18:59:26.352] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:7:0)
[18:59:26.370] Build status: STARTED
[18:59:26.371] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:8:0) after 4m23s
[19:03:49.416] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:8:0)
[19:03:49.497] Build status: STARTED
[19:03:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:9:0) after 2m53s
[19:06:42.530] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:9:0)
[19:06:42.561] Build status: STARTED
[19:06:42.561] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:10:0) after 5m23s
[19:12:05.899] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:10:0)
[19:12:06.219] Build status: STARTED
[19:12:06.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:11:0) after 2m28s
[19:14:34.517] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:11:0)
[19:14:34.553] Build status: STARTED
[19:14:34.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:12:0) after 9m55s
[19:24:29.576] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:12:0)
[19:24:29.596] Build status: STARTED
[19:24:29.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:13:0) after 3m9s
[19:27:38.587] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:13:0)
[19:27:38.617] Build status: STARTED
[19:27:38.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:14:0) after 4m13s
[19:31:51.646] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:14:0)
[19:31:51.672] Build status: STARTED
[19:31:51.672] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:15:0) after 4m3s
[19:35:55.046] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:15:0)
[19:35:55.154] Build status: STARTED
[19:35:55.155] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:16:0) after 6m43s
[19:42:38.266] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:16:0)
[19:42:38.291] Build status: STARTED
[19:42:38.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:17:0) after 1m27s
[19:44:05.646] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:17:0)
[19:44:05.668] Build status: STARTED
[19:44:05.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:18:0) after 2m27s
[19:46:33.067] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:18:0)
[19:46:33.086] Build status: STARTED
[19:46:33.086] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:19:0) after 5m44s
[19:52:17.170] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:19:0)
[19:52:17.191] Build status: STARTED
[19:52:17.192] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:20:0) after 9m1s
[20:01:18.494] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:20:0)
[20:01:18.514] Build status: STARTED
[20:01:18.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:21:0) after 1m45s
[20:03:03.558] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:21:0)
[20:03:03.575] Build status: STARTED
[20:03:03.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:22:0) after 3m33s
[20:06:36.870] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:22:0)
[20:06:36.904] Build status: STARTED
[20:06:36.904] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:23:0) after 5m59s
[20:12:35.923] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:23:0)
[20:12:35.950] Build status: STARTED
[20:12:35.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:24:0) after 5m11s
[20:17:47.428] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:24:0)
[20:17:47.445] Build status: STARTED
[20:17:47.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:25:0) after 1m48s
[20:19:35.551] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:25:0)
[20:19:35.595] Build status: STARTED
[20:19:35.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:26:0) after 8m5s
[20:27:40.616] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:26:0)
[20:27:40.641] Build status: STARTED
[20:27:40.641] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:27:0) after 1m46s
[20:29:26.914] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:27:0)
[20:29:26.986] Build status: STARTED
[20:29:26.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:28:0) after 2m22s
[20:31:49.355] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:28:0)
[20:31:49.373] Build status: STARTED
[20:31:49.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:29:0) after 9m24s
[20:41:13.426] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:29:0)
[20:41:13.446] Build status: STARTED
[20:41:13.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:30:0) after 8m38s
[20:49:51.891] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:30:0)
[20:49:51.922] Build status: STARTED
[20:49:51.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:31:0) after 6m26s
[20:56:17.994] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:31:0)
[20:56:18.034] Build status: STARTED
[20:56:18.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:32:0) after 1m37s
[20:57:55.217] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:32:0)
[20:57:55.235] Build status: STARTED
[20:57:55.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:33:0) after 1m50s
[20:59:45.365] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:33:0)
[20:59:45.410] Build status: STARTED
[20:59:45.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:34:0) after 7m10s
[21:06:55.586] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:34:0)
[21:06:55.660] Build status: STARTED
[21:06:55.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:35:0) after 1m57s
[21:08:52.912] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:35:0)
[21:08:52.929] Build status: STARTED
[21:08:52.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:36:0) after 4m21s
[21:13:14.275] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:36:0)
[21:13:14.326] Build status: STARTED
[21:13:14.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:37:0) after 1m37s
[21:14:51.380] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:37:0)
[21:14:51.448] Build status: STARTED
[21:14:51.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:38:0) after 3m33s
[21:18:24.469] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:38:0)
[21:18:24.550] Build status: STARTED
[21:18:24.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:39:0) after 8m22s
[21:26:46.677] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:39:0)
[21:26:46.696] Build status: STARTED
[21:26:46.696] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:40:0) after 6m20s
[21:33:07.015] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:40:0)
[21:33:07.051] Build status: STARTED
[21:33:07.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:41:0) after 4m28s
[21:37:35.074] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:41:0)
[21:37:35.109] Build status: STARTED
[21:37:35.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:42:0) after 8m15s
[21:45:50.131] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:42:0)
[21:45:50.154] Build status: STARTED
[21:45:50.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:43:0) after 2m48s
[21:48:38.374] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:43:0)
[21:48:38.391] Build status: STARTED
[21:48:38.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:44:0) after 7m8s
[21:55:46.606] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:44:0)
[21:55:46.637] Build status: STARTED
[21:55:46.637] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:45:0) after 7m58s
[22:03:44.979] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:45:0)
[22:03:45.048] Build status: STARTED
[22:03:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:46:0) after 6m40s
[22:10:25.583] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:46:0)
[22:10:25.679] Build status: STARTED
[22:10:25.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:47:0) after 3m19s
[22:13:44.753] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:47:0)
[22:13:44.776] Build status: STARTED
[22:13:44.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8938827048587201152:48:0) after 1m15s
[22:13:47.808] Received PubSub notification, asking Buildbucket for the build status
[22:13:47.988] Build:
{
"id": "8773396986602894865",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 29312,
"createdBy": "project:chromium",
"createTime": "2023-08-07T18:40:48.448658316Z",
"startTime": "2023-08-07T18:45:21.874868Z",
"endTime": "2023-08-07T22:13:47.460112233Z",
"updateTime": "2023-08-07T22:13:47.460112233Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "333449160b5c4e84c00bad0791e166cc6bba08f5",
"ref": "refs/heads/main"
}
}
}
[22:13:47.988] Invocation finished in 3h33m0.815909731s with status FAILED