[23:05:47.242] New invocation is queued and will start shortly
[23:05:48.405] Starting the invocation (attempt 1)
[23:05:48.440] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25fd6ece2c485310bcd817ea248fbd383eeb4ba6"
[23:05:48.440] Popped gitiles commit info from properties and tags
[23:05:48.440] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:05:48.442] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:05:48.442] Buildbucket request:
{
"requestId": "8890884580942898576",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "25fd6ece2c485310bcd817ea248fbd383eeb4ba6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8890884580942898576"
},
{
"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"
}
}
[23:05:49.067] Scheduled build:
{
"id": "8725454518946323025",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 33214,
"createdBy": "project:chromium",
"createTime": "2025-01-17T23:05:48.518840393Z",
"updateTime": "2025-01-17T23:05:48.518840393Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "25fd6ece2c485310bcd817ea248fbd383eeb4ba6",
"ref": "refs/heads/main"
}
}
}
[23:05:49.068] Task URL: https://cr-buildbucket.appspot.com/build/8725454518946323025
[23:05:49.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:2:0) after 3m7s
[23:05:49.158] Received PubSub notification, asking Buildbucket for the build status
[23:05:49.183] Build status: SCHEDULED
[23:05:53.589] Received PubSub notification, asking Buildbucket for the build status
[23:05:53.608] Build status: STARTED
[23:08:56.091] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:2:0)
[23:08:56.115] Build status: STARTED
[23:08:56.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:5:0) after 2m48s
[23:11:44.138] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:5:0)
[23:11:44.166] Build status: STARTED
[23:11:44.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:6:0) after 8m6s
[23:19:50.282] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:6:0)
[23:19:50.304] Build status: STARTED
[23:19:50.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:7:0) after 8m53s
[23:28:43.366] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:7:0)
[23:28:43.423] Build status: STARTED
[23:28:43.423] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:8:0) after 5m59s
[23:34:42.473] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:8:0)
[23:34:42.495] Build status: STARTED
[23:34:42.495] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:9:0) after 8m44s
[23:43:26.552] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:9:0)
[23:43:26.568] Build status: STARTED
[23:43:26.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:10:0) after 7m9s
[23:50:35.650] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:10:0)
[23:50:35.667] Build status: STARTED
[23:50:35.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:11:0) after 2m29s
[23:53:04.690] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:11:0)
[23:53:04.709] Build status: STARTED
[23:53:04.709] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:12:0) after 2m27s
[23:55:31.766] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:12:0)
[23:55:31.785] Build status: STARTED
[23:55:31.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:13:0) after 2m25s
[23:57:56.793] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:13:0)
[23:57:56.816] Build status: STARTED
[23:57:56.816] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:14:0) after 4m40s
[00:02:36.907] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:14:0)
[00:02:36.935] Build status: STARTED
[00:02:36.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:15:0) after 1m36s
[00:04:12.963] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:15:0)
[00:04:12.982] Build status: STARTED
[00:04:12.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:16:0) after 4m45s
[00:08:58.019] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:16:0)
[00:08:58.035] Build status: STARTED
[00:08:58.035] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:17:0) after 6m42s
[00:15:40.138] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:17:0)
[00:15:40.171] Build status: STARTED
[00:15:40.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:18:0) after 5m19s
[00:20:59.198] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:18:0)
[00:20:59.217] Build status: STARTED
[00:20:59.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:19:0) after 3m52s
[00:24:51.320] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:19:0)
[00:24:51.335] Build status: STARTED
[00:24:51.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:20:0) after 2m25s
[00:27:16.364] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:20:0)
[00:27:16.438] Build status: STARTED
[00:27:16.439] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:21:0) after 6m45s
[00:34:01.478] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:21:0)
[00:34:01.496] Build status: STARTED
[00:34:01.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:22:0) after 3m33s
[00:37:34.522] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:22:0)
[00:37:34.540] Build status: STARTED
[00:37:34.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:23:0) after 5m40s
[00:43:14.648] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:23:0)
[00:43:14.689] Build status: STARTED
[00:43:14.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:24:0) after 9m12s
[00:52:26.819] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:24:0)
[00:52:26.838] Build status: STARTED
[00:52:26.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:25:0) after 4m47s
[00:57:13.914] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:25:0)
[00:57:13.932] Build status: STARTED
[00:57:13.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:26:0) after 3m3s
[01:00:16.958] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:26:0)
[01:00:16.976] Build status: STARTED
[01:00:16.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:27:0) after 4m14s
[01:04:31.055] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:27:0)
[01:04:31.075] Build status: STARTED
[01:04:31.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:28:0) after 5m59s
[01:10:30.145] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:28:0)
[01:10:30.160] Build status: STARTED
[01:10:30.160] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:29:0) after 1m37s
[01:12:07.184] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:29:0)
[01:12:07.213] Build status: STARTED
[01:12:07.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:30:0) after 1m55s
[01:14:02.227] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:30:0)
[01:14:02.251] Build status: STARTED
[01:14:02.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:31:0) after 4m57s
[01:18:59.277] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:31:0)
[01:18:59.292] Build status: STARTED
[01:18:59.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:32:0) after 9m20s
[01:28:19.366] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:32:0)
[01:28:19.385] Build status: STARTED
[01:28:19.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:33:0) after 7m46s
[01:36:05.455] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:33:0)
[01:36:05.479] Build status: STARTED
[01:36:05.479] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:34:0) after 5m50s
[01:41:55.513] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:34:0)
[01:41:55.528] Build status: STARTED
[01:41:55.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:35:0) after 8m26s
[01:50:21.563] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:35:0)
[01:50:21.593] Build status: STARTED
[01:50:21.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:36:0) after 3m37s
[01:53:58.624] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:36:0)
[01:53:58.648] Build status: STARTED
[01:53:58.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:37:0) after 8m59s
[02:02:57.852] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:37:0)
[02:02:57.872] Build status: STARTED
[02:02:57.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:38:0) after 8m19s
[02:11:16.969] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:38:0)
[02:11:16.993] Build status: STARTED
[02:11:16.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:39:0) after 3m8s
[02:14:25.159] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:39:0)
[02:14:25.179] Build status: STARTED
[02:14:25.179] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:40:0) after 3m9s
[02:17:34.210] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:40:0)
[02:17:34.240] Build status: STARTED
[02:17:34.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:41:0) after 4m30s
[02:22:04.502] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:41:0)
[02:22:04.519] Build status: STARTED
[02:22:04.519] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:42:0) after 5m39s
[02:27:43.538] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:42:0)
[02:27:43.555] Build status: STARTED
[02:27:43.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:43:0) after 2m21s
[02:30:04.584] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:43:0)
[02:30:04.606] Build status: STARTED
[02:30:04.606] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:44:0) after 7m3s
[02:37:07.770] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:44:0)
[02:37:07.785] Build status: STARTED
[02:37:07.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:45:0) after 2m9s
[02:39:16.809] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:45:0)
[02:39:16.829] Build status: STARTED
[02:39:16.829] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:46:0) after 4m57s
[02:44:14.018] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:46:0)
[02:44:14.037] Build status: STARTED
[02:44:14.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:47:0) after 2m11s
[02:46:25.096] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:47:0)
[02:46:25.132] Build status: STARTED
[02:46:25.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:48:0) after 2m53s
[02:49:18.214] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:48:0)
[02:49:18.229] Build status: STARTED
[02:49:18.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:49:0) after 8m7s
[02:57:25.220] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:49:0)
[02:57:25.237] Build status: STARTED
[02:57:25.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:50:0) after 3m52s
[03:01:17.267] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:50:0)
[03:01:17.284] Build status: STARTED
[03:01:17.284] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:51:0) after 4m13s
[03:05:30.309] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:51:0)
[03:05:30.329] Build status: STARTED
[03:05:30.329] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:52:0) after 5m31s
[03:11:01.735] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:52:0)
[03:11:01.751] Build status: STARTED
[03:11:01.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:53:0) after 2m23s
[03:13:25.357] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:53:0)
[03:13:25.373] Build status: STARTED
[03:13:25.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:54:0) after 6m40s
[03:20:05.781] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:54:0)
[03:20:05.835] Build status: STARTED
[03:20:05.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:55:0) after 4m32s
[03:24:38.142] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:55:0)
[03:24:38.170] Build status: STARTED
[03:24:38.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:56:0) after 2m52s
[03:27:30.599] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:56:0)
[03:27:30.618] Build status: STARTED
[03:27:30.618] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:57:0) after 6m19s
[03:33:49.638] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:57:0)
[03:33:49.716] Build status: STARTED
[03:33:49.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8890884580942898576:58:0) after 7m27s
[03:38:44.370] Received PubSub notification, asking Buildbucket for the build status
[03:38:44.429] Build:
{
"id": "8725454518946323025",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 33214,
"createdBy": "project:chromium",
"createTime": "2025-01-17T23:05:48.518840393Z",
"startTime": "2025-01-17T23:05:53.263027002Z",
"endTime": "2025-01-18T03:38:44.070300359Z",
"updateTime": "2025-01-18T03:38:44.070300359Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "25fd6ece2c485310bcd817ea248fbd383eeb4ba6",
"ref": "refs/heads/main"
}
}
}
[03:38:44.429] Invocation finished in 4h32m57.198811638s with status SUCCEEDED