[17:12:22.386] New invocation is queued and will start shortly
[17:12:23.603] Starting the invocation (attempt 1)
[17:12:23.635] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5fd7adbd1b5e9e315d80d3992affbc94fe00e920"
[17:12:23.635] Popped gitiles commit info from properties and tags
[17:12:23.635] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:12:23.636] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:12:23.636] Buildbucket request:
{
"requestId": "8913284266547580464",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5fd7adbd1b5e9e315d80d3992affbc94fe00e920",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913284266547580464"
},
{
"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"
}
}
[17:12:24.273] Scheduled build:
{
"id": "8747854204509437905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 30827,
"createdBy": "project:chromium",
"createTime": "2024-05-15T17:12:23.700126017Z",
"updateTime": "2024-05-15T17:12:23.700126017Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5fd7adbd1b5e9e315d80d3992affbc94fe00e920",
"ref": "refs/heads/main"
}
}
}
[17:12:24.273] Task URL: https://cr-buildbucket.appspot.com/build/8747854204509437905
[17:12:24.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:2:0) after 8m16s
[17:12:25.264] Received PubSub notification, asking Buildbucket for the build status
[17:12:25.292] Build status: SCHEDULED
[17:12:30.558] Received PubSub notification, asking Buildbucket for the build status
[17:12:30.598] Build status: STARTED
[17:20:40.348] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:2:0)
[17:20:40.422] Build status: STARTED
[17:20:40.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:5:0) after 1m36s
[17:22:16.491] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:5:0)
[17:22:16.523] Build status: STARTED
[17:22:16.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:6:0) after 3m44s
[17:26:00.551] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:6:0)
[17:26:00.570] Build status: STARTED
[17:26:00.570] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:7:0) after 5m14s
[17:31:14.590] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:7:0)
[17:31:14.619] Build status: STARTED
[17:31:14.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:8:0) after 2m11s
[17:33:25.783] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:8:0)
[17:33:25.860] Build status: STARTED
[17:33:25.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:9:0) after 6m4s
[17:39:29.900] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:9:0)
[17:39:29.920] Build status: STARTED
[17:39:29.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:10:0) after 4m21s
[17:43:50.942] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:10:0)
[17:43:51.253] Build status: STARTED
[17:43:51.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:11:0) after 2m31s
[17:46:22.283] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:11:0)
[17:46:22.314] Build status: STARTED
[17:46:22.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:12:0) after 7m15s
[17:53:37.411] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:12:0)
[17:53:37.434] Build status: STARTED
[17:53:37.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:13:0) after 9m51s
[18:03:28.567] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:13:0)
[18:03:28.592] Build status: STARTED
[18:03:28.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:14:0) after 3m7s
[18:06:35.757] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:14:0)
[18:06:35.773] Build status: STARTED
[18:06:35.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:15:0) after 8m49s
[18:15:24.821] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:15:0)
[18:15:24.877] Build status: STARTED
[18:15:24.877] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:16:0) after 9m3s
[18:24:27.898] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:16:0)
[18:24:27.923] Build status: STARTED
[18:24:27.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:17:0) after 3m43s
[18:28:10.972] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:17:0)
[18:28:10.994] Build status: STARTED
[18:28:10.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:18:0) after 3m3s
[18:31:13.973] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:18:0)
[18:31:14.053] Build status: STARTED
[18:31:14.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:19:0) after 6m25s
[18:37:39.180] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:19:0)
[18:37:39.198] Build status: STARTED
[18:37:39.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:20:0) after 5m40s
[18:43:19.219] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:20:0)
[18:43:19.240] Build status: STARTED
[18:43:19.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:21:0) after 7m18s
[18:50:37.281] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:21:0)
[18:50:37.346] Build status: STARTED
[18:50:37.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:22:0) after 3m25s
[18:54:02.382] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:22:0)
[18:54:02.418] Build status: STARTED
[18:54:02.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:23:0) after 7m52s
[19:01:54.463] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:23:0)
[19:01:54.484] Build status: STARTED
[19:01:54.484] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:24:0) after 1m44s
[19:03:38.572] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:24:0)
[19:03:38.602] Build status: STARTED
[19:03:38.602] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:25:0) after 5m18s
[19:08:56.681] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:25:0)
[19:08:56.703] Build status: STARTED
[19:08:56.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:26:0) after 9m32s
[19:18:28.735] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:26:0)
[19:18:28.757] Build status: STARTED
[19:18:28.757] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:27:0) after 2m23s
[19:20:51.977] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:27:0)
[19:20:52.082] Build status: STARTED
[19:20:52.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:28:0) after 1m1s
[19:21:53.121] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:28:0)
[19:21:53.141] Build status: STARTED
[19:21:53.141] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:29:0) after 3m51s
[19:25:44.170] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:29:0)
[19:25:44.223] Build status: STARTED
[19:25:44.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:30:0) after 5m36s
[19:31:20.252] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:30:0)
[19:31:20.271] Build status: STARTED
[19:31:20.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:31:0) after 2m0s
[19:33:20.358] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:31:0)
[19:33:20.382] Build status: STARTED
[19:33:20.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:32:0) after 2m23s
[19:35:43.476] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:32:0)
[19:35:43.500] Build status: STARTED
[19:35:43.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:33:0) after 6m36s
[19:42:19.536] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:33:0)
[19:42:19.558] Build status: STARTED
[19:42:19.558] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:34:0) after 1m26s
[19:43:45.623] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:34:0)
[19:43:45.661] Build status: STARTED
[19:43:45.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:35:0) after 4m12s
[19:47:57.686] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:35:0)
[19:47:57.706] Build status: STARTED
[19:47:57.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:36:0) after 8m43s
[19:56:40.734] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:36:0)
[19:56:40.757] Build status: STARTED
[19:56:40.757] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:37:0) after 5m52s
[20:02:32.786] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:37:0)
[20:02:32.810] Build status: STARTED
[20:02:32.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:38:0) after 3m53s
[20:06:25.834] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:38:0)
[20:06:25.855] Build status: STARTED
[20:06:25.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:39:0) after 8m43s
[20:15:08.957] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:39:0)
[20:15:08.997] Build status: STARTED
[20:15:08.997] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:40:0) after 5m31s
[20:20:40.150] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:40:0)
[20:20:40.177] Build status: STARTED
[20:20:40.177] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:41:0) after 2m3s
[20:22:43.290] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:41:0)
[20:22:43.314] Build status: STARTED
[20:22:43.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:42:0) after 9m17s
[20:32:00.382] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:42:0)
[20:32:00.402] Build status: STARTED
[20:32:00.402] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:43:0) after 2m17s
[20:34:17.435] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:43:0)
[20:34:17.490] Build status: STARTED
[20:34:17.490] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:44:0) after 1m39s
[20:35:56.519] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:44:0)
[20:35:56.537] Build status: STARTED
[20:35:56.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:45:0) after 6m39s
[20:42:35.707] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:45:0)
[20:42:35.734] Build status: STARTED
[20:42:35.734] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:46:0) after 6m52s
[20:49:27.927] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:46:0)
[20:49:27.964] Build status: STARTED
[20:49:27.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:47:0) after 4m31s
[20:53:58.970] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:47:0)
[20:53:58.986] Build status: STARTED
[20:53:58.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:48:0) after 7m36s
[21:01:35.051] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:48:0)
[21:01:35.076] Build status: STARTED
[21:01:35.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:49:0) after 5m55s
[21:07:30.145] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:49:0)
[21:07:30.173] Build status: STARTED
[21:07:30.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:50:0) after 2m9s
[21:09:39.195] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:50:0)
[21:09:39.214] Build status: STARTED
[21:09:39.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:51:0) after 4m9s
[21:13:48.347] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:51:0)
[21:13:48.366] Build status: STARTED
[21:13:48.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:52:0) after 7m4s
[21:20:52.479] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:52:0)
[21:20:52.506] Build status: STARTED
[21:20:52.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:53:0) after 8m21s
[21:29:13.688] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:53:0)
[21:29:13.707] Build status: STARTED
[21:29:13.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:54:0) after 3m37s
[21:32:50.783] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:54:0)
[21:32:50.832] Build status: STARTED
[21:32:50.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:55:0) after 2m4s
[21:34:54.865] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:55:0)
[21:34:54.890] Build status: STARTED
[21:34:54.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:56:0) after 1m49s
[21:36:43.959] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:56:0)
[21:36:43.975] Build status: STARTED
[21:36:43.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:57:0) after 6m55s
[21:43:39.066] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:57:0)
[21:43:39.102] Build status: STARTED
[21:43:39.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:58:0) after 8m20s
[21:51:59.137] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:58:0)
[21:51:59.159] Build status: STARTED
[21:51:59.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:59:0) after 9m36s
[22:01:35.189] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:59:0)
[22:01:35.218] Build status: STARTED
[22:01:35.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:60:0) after 4m0s
[22:05:35.314] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:60:0)
[22:05:35.335] Build status: STARTED
[22:05:35.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:61:0) after 9m9s
[22:14:44.354] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:61:0)
[22:14:44.379] Build status: STARTED
[22:14:44.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:62:0) after 1m55s
[22:16:39.512] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:62:0)
[22:16:39.536] Build status: STARTED
[22:16:39.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:63:0) after 4m43s
[22:21:22.559] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:63:0)
[22:21:22.577] Build status: STARTED
[22:21:22.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:64:0) after 8m6s
[22:29:28.609] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:64:0)
[22:29:28.624] Build status: STARTED
[22:29:28.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:65:0) after 5m51s
[22:35:19.700] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:65:0)
[22:35:19.721] Build status: STARTED
[22:35:19.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:66:0) after 6m36s
[22:41:55.831] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:66:0)
[22:41:55.865] Build status: STARTED
[22:41:55.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:67:0) after 6m0s
[22:47:55.937] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:67:0)
[22:47:55.964] Build status: STARTED
[22:47:55.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:68:0) after 5m21s
[22:53:17.098] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:68:0)
[22:53:17.122] Build status: STARTED
[22:53:17.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:69:0) after 6m53s
[23:00:10.222] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:69:0)
[23:00:10.260] Build status: STARTED
[23:00:10.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8913284266547580464:70:0) after 9m23s
[23:05:01.529] Received PubSub notification, asking Buildbucket for the build status
[23:05:01.546] Build:
{
"id": "8747854204509437905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 30827,
"createdBy": "project:chromium",
"createTime": "2024-05-15T17:12:23.700126017Z",
"startTime": "2024-05-15T17:12:30.256532461Z",
"endTime": "2024-05-15T23:05:01.145340524Z",
"updateTime": "2024-05-15T23:05:01.145340524Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5fd7adbd1b5e9e315d80d3992affbc94fe00e920",
"ref": "refs/heads/main"
}
}
}
[23:05:01.546] Invocation finished in 5h52m39.173742959s with status FAILED