[18:14:52.598] New invocation is queued and will start shortly
[18:14:53.659] Starting the invocation (attempt 1)
[18:14:53.686] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/049c9a4c1dd64f272f13ec0218c2a4a147f16bf9"
[18:14:53.686] Popped gitiles commit info from properties and tags
[18:14:53.686] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:14:53.686] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:14:53.686] Buildbucket request:
{
"requestId": "8893167807621208384",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTFuchsia x64"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "049c9a4c1dd64f272f13ec0218c2a4a147f16bf9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893167807621208384"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTFuchsia x64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:14:54.253] Scheduled build:
{
"id": "8727737745754109089",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTFuchsia x64"
},
"number": 23275,
"createdBy": "project:chromium",
"createTime": "2024-12-23T18:14:53.752372445Z",
"updateTime": "2024-12-23T18:14:53.752372445Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "049c9a4c1dd64f272f13ec0218c2a4a147f16bf9",
"ref": "refs/heads/main"
}
}
}
[18:14:54.253] Task URL: https://cr-buildbucket.appspot.com/build/8727737745754109089
[18:14:54.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:2:0) after 4m1s
[18:14:55.271] Received PubSub notification, asking Buildbucket for the build status
[18:14:55.301] Build status: SCHEDULED
[18:16:07.734] Received PubSub notification, asking Buildbucket for the build status
[18:16:07.759] Build status: STARTED
[18:18:55.272] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:2:0)
[18:18:55.309] Build status: STARTED
[18:18:55.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:5:0) after 5m26s
[18:24:21.566] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:5:0)
[18:24:21.634] Build status: STARTED
[18:24:21.634] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:6:0) after 8m55s
[18:33:16.785] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:6:0)
[18:33:16.802] Build status: STARTED
[18:33:16.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:7:0) after 6m38s
[18:39:54.828] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:7:0)
[18:39:54.852] Build status: STARTED
[18:39:54.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:8:0) after 3m37s
[18:43:31.876] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:8:0)
[18:43:31.902] Build status: STARTED
[18:43:31.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:9:0) after 1m20s
[18:44:52.212] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:9:0)
[18:44:52.241] Build status: STARTED
[18:44:52.241] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:10:0) after 8m35s
[18:53:27.266] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:10:0)
[18:53:27.311] Build status: STARTED
[18:53:27.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:11:0) after 5m40s
[18:59:07.407] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:11:0)
[18:59:07.435] Build status: STARTED
[18:59:07.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:12:0) after 8m7s
[19:07:14.575] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:12:0)
[19:07:14.605] Build status: STARTED
[19:07:14.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:13:0) after 7m37s
[19:14:51.717] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:13:0)
[19:14:51.742] Build status: STARTED
[19:14:51.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:14:0) after 1m45s
[19:16:37.036] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:14:0)
[19:16:37.062] Build status: STARTED
[19:16:37.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:15:0) after 4m18s
[19:20:55.178] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:15:0)
[19:20:55.200] Build status: STARTED
[19:20:55.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:16:0) after 6m7s
[19:27:02.254] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:16:0)
[19:27:02.333] Build status: STARTED
[19:27:02.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:17:0) after 2m22s
[19:29:24.452] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:17:0)
[19:29:24.510] Build status: STARTED
[19:29:24.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:18:0) after 7m24s
[19:36:49.012] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:18:0)
[19:36:49.070] Build status: STARTED
[19:36:49.070] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:19:0) after 2m5s
[19:38:54.251] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:19:0)
[19:38:54.320] Build status: STARTED
[19:38:54.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:20:0) after 6m21s
[19:45:15.560] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:20:0)
[19:45:15.628] Build status: STARTED
[19:45:15.628] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:21:0) after 2m34s
[19:47:49.846] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:21:0)
[19:47:49.878] Build status: STARTED
[19:47:49.878] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:22:0) after 2m39s
[19:50:29.077] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:22:0)
[19:50:29.106] Build status: STARTED
[19:50:29.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:23:0) after 7m50s
[19:58:19.136] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:23:0)
[19:58:19.159] Build status: STARTED
[19:58:19.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:24:0) after 2m58s
[20:01:17.389] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:24:0)
[20:01:17.434] Build status: STARTED
[20:01:17.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:25:0) after 2m48s
[20:04:05.838] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:25:0)
[20:04:05.875] Build status: STARTED
[20:04:05.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:26:0) after 5m43s
[20:09:49.000] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:26:0)
[20:09:49.017] Build status: STARTED
[20:09:49.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:27:0) after 9m28s
[20:19:17.983] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:27:0)
[20:19:18.014] Build status: STARTED
[20:19:18.014] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:28:0) after 8m41s
[20:27:59.079] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:28:0)
[20:27:59.108] Build status: STARTED
[20:27:59.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:29:0) after 3m18s
[20:31:17.139] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:29:0)
[20:31:17.159] Build status: STARTED
[20:31:17.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:30:0) after 5m29s
[20:36:46.223] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:30:0)
[20:36:46.248] Build status: STARTED
[20:36:46.248] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:31:0) after 2m53s
[20:39:39.275] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:31:0)
[20:39:39.300] Build status: STARTED
[20:39:39.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:32:0) after 7m36s
[20:47:15.406] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:32:0)
[20:47:15.431] Build status: STARTED
[20:47:15.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:33:0) after 1m42s
[20:48:57.475] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:33:0)
[20:48:57.491] Build status: STARTED
[20:48:57.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:34:0) after 8m51s
[20:57:48.524] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:34:0)
[20:57:48.547] Build status: STARTED
[20:57:48.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:35:0) after 8m52s
[21:06:40.575] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:35:0)
[21:06:40.600] Build status: STARTED
[21:06:40.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:36:0) after 7m12s
[21:13:52.623] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:36:0)
[21:13:52.640] Build status: STARTED
[21:13:52.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:37:0) after 7m56s
[21:21:48.658] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:37:0)
[21:21:48.681] Build status: STARTED
[21:21:48.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:38:0) after 8m6s
[21:29:54.667] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:38:0)
[21:29:54.688] Build status: STARTED
[21:29:54.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:39:0) after 9m17s
[21:39:11.719] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:39:0)
[21:39:11.748] Build status: STARTED
[21:39:11.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:40:0) after 5m10s
[21:44:21.775] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:40:0)
[21:44:21.791] Build status: STARTED
[21:44:21.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:41:0) after 1m14s
[21:45:35.878] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:41:0)
[21:45:35.928] Build status: STARTED
[21:45:35.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:42:0) after 3m19s
[21:48:55.025] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:42:0)
[21:48:55.043] Build status: STARTED
[21:48:55.043] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:43:0) after 3m46s
[21:52:41.054] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:43:0)
[21:52:41.071] Build status: STARTED
[21:52:41.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:44:0) after 4m59s
[21:57:40.145] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:44:0)
[21:57:40.165] Build status: STARTED
[21:57:40.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:45:0) after 1m5s
[21:58:45.184] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:45:0)
[21:58:45.200] Build status: STARTED
[21:58:45.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:46:0) after 8m0s
[22:06:45.222] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:46:0)
[22:06:45.248] Build status: STARTED
[22:06:45.248] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:47:0) after 4m49s
[22:11:34.279] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:47:0)
[22:11:34.294] Build status: STARTED
[22:11:34.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:48:0) after 1m47s
[22:13:21.315] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:48:0)
[22:13:21.331] Build status: STARTED
[22:13:21.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:49:0) after 8m49s
[22:22:10.410] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:49:0)
[22:22:10.430] Build status: STARTED
[22:22:10.430] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:50:0) after 4m45s
[22:26:55.505] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:50:0)
[22:26:55.521] Build status: STARTED
[22:26:55.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:51:0) after 7m15s
[22:34:10.601] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:51:0)
[22:34:10.618] Build status: STARTED
[22:34:10.618] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:52:0) after 4m46s
[22:38:56.645] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:52:0)
[22:38:56.663] Build status: STARTED
[22:38:56.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:53:0) after 9m58s
[22:48:54.812] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:53:0)
[22:48:54.835] Build status: STARTED
[22:48:54.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:54:0) after 3m28s
[22:52:23.514] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:54:0)
[22:52:23.535] Build status: STARTED
[22:52:23.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:55:0) after 8m39s
[23:01:02.558] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:55:0)
[23:01:02.573] Build status: STARTED
[23:01:02.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:56:0) after 6m14s
[23:07:16.652] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:56:0)
[23:07:16.675] Build status: STARTED
[23:07:16.676] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:57:0) after 9m33s
[23:16:49.717] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:57:0)
[23:16:49.736] Build status: STARTED
[23:16:49.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:58:0) after 1m44s
[23:18:33.756] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:58:0)
[23:18:33.782] Build status: STARTED
[23:18:33.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:59:0) after 5m12s
[23:23:46.097] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:59:0)
[23:23:46.283] Build status: STARTED
[23:23:46.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:60:0) after 6m17s
[23:30:03.484] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:60:0)
[23:30:03.684] Build status: STARTED
[23:30:03.684] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:61:0) after 6m44s
[23:36:47.758] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:61:0)
[23:36:47.780] Build status: STARTED
[23:36:47.780] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:62:0) after 4m30s
[23:41:17.804] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:62:0)
[23:41:17.835] Build status: STARTED
[23:41:17.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:63:0) after 7m44s
[23:49:02.007] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:63:0)
[23:49:02.026] Build status: STARTED
[23:49:02.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:64:0) after 9m6s
[23:58:08.079] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:64:0)
[23:58:08.095] Build status: STARTED
[23:58:08.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:65:0) after 4m53s
[00:03:01.233] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:65:0)
[00:03:01.251] Build status: STARTED
[00:03:01.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:66:0) after 2m16s
[00:05:17.312] Handling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:66:0)
[00:05:17.378] Build status: STARTED
[00:05:17.378] Scheduling timer "check-buildbucket-build-status" (chromium/ToTFuchsia x64:8893167807621208384:67:0) after 4m9s
[00:06:19.391] Received PubSub notification, asking Buildbucket for the build status
[00:06:19.413] Build:
{
"id": "8727737745754109089",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTFuchsia x64"
},
"number": 23275,
"createdBy": "project:chromium",
"createTime": "2024-12-23T18:14:53.752372445Z",
"startTime": "2024-12-23T18:16:07.422340781Z",
"endTime": "2024-12-24T00:06:19.082917391Z",
"updateTime": "2024-12-24T00:06:19.082917391Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "049c9a4c1dd64f272f13ec0218c2a4a147f16bf9",
"ref": "refs/heads/main"
}
}
}
[00:06:19.413] Invocation finished in 5h51m26.824971554s with status SUCCEEDED