[19:38:28.889] New invocation is queued and will start shortly
[19:38:30.141] Starting the invocation (attempt 1)
[19:38:30.167] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e4e186080abd406ee41c677c17073cdecb16bea2"
[19:38:30.168] Popped gitiles commit info from properties and tags
[19:38:30.168] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:38:30.168] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:38:30.168] Buildbucket request:
{
"requestId": "8915539998355805264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e4e186080abd406ee41c677c17073cdecb16bea2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915539998355805264"
},
{
"key": "scheduler_job_id",
"value": "chromium/win10-rel-no-external-ip"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:38:31.119] Scheduled build:
{
"id": "8750109936172740033",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"number": 3244,
"createdBy": "project:chromium",
"createTime": "2024-04-20T19:38:30.348293307Z",
"updateTime": "2024-04-20T19:38:30.348293307Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e4e186080abd406ee41c677c17073cdecb16bea2",
"ref": "refs/heads/main"
}
}
}
[19:38:31.119] Task URL: https://cr-buildbucket.appspot.com/build/8750109936172740033
[19:38:31.119] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:2:0) after 5m46s
[19:38:31.208] Received PubSub notification, asking Buildbucket for the build status
[19:38:31.233] Build status: SCHEDULED
[19:39:39.562] Received PubSub notification, asking Buildbucket for the build status
[19:39:39.588] Build status: STARTED
[19:44:17.163] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:2:0)
[19:44:17.199] Build status: STARTED
[19:44:17.199] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:5:0) after 5m44s
[19:50:01.219] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:5:0)
[19:50:01.245] Build status: STARTED
[19:50:01.245] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:6:0) after 7m19s
[19:57:21.238] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:6:0)
[19:57:21.261] Build status: STARTED
[19:57:21.261] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:7:0) after 5m59s
[20:03:20.396] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:7:0)
[20:03:20.416] Build status: STARTED
[20:03:20.416] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:8:0) after 1m58s
[20:05:18.453] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:8:0)
[20:05:18.476] Build status: STARTED
[20:05:18.476] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:9:0) after 2m10s
[20:07:28.513] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:9:0)
[20:07:28.540] Build status: STARTED
[20:07:28.540] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:10:0) after 4m19s
[20:11:47.897] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:10:0)
[20:11:47.915] Build status: STARTED
[20:11:47.915] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:11:0) after 7m42s
[20:19:30.025] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:11:0)
[20:19:30.044] Build status: STARTED
[20:19:30.044] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:12:0) after 9m57s
[20:29:27.195] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:12:0)
[20:29:27.218] Build status: STARTED
[20:29:27.218] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:13:0) after 8m17s
[20:37:44.366] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:13:0)
[20:37:44.493] Build status: STARTED
[20:37:44.493] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:14:0) after 3m25s
[20:41:09.489] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:14:0)
[20:41:09.510] Build status: STARTED
[20:41:09.510] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:15:0) after 4m41s
[20:45:50.554] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:15:0)
[20:45:50.632] Build status: STARTED
[20:45:50.632] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:16:0) after 5m27s
[20:51:17.724] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:16:0)
[20:51:17.771] Build status: STARTED
[20:51:17.771] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:17:0) after 2m9s
[20:53:26.802] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:17:0)
[20:53:26.827] Build status: STARTED
[20:53:26.827] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:18:0) after 8m59s
[21:02:25.847] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:18:0)
[21:02:25.871] Build status: STARTED
[21:02:25.871] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:19:0) after 9m48s
[21:12:13.964] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:19:0)
[21:12:13.994] Build status: STARTED
[21:12:13.994] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:20:0) after 3m14s
[21:15:28.070] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:20:0)
[21:15:28.095] Build status: STARTED
[21:15:28.095] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:21:0) after 3m20s
[21:18:48.117] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:21:0)
[21:18:48.139] Build status: STARTED
[21:18:48.139] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:22:0) after 6m25s
[21:25:13.467] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:22:0)
[21:25:13.489] Build status: STARTED
[21:25:13.489] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:23:0) after 4m40s
[21:29:53.507] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:23:0)
[21:29:53.528] Build status: STARTED
[21:29:53.529] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:24:0) after 9m52s
[21:39:45.550] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:24:0)
[21:39:45.574] Build status: STARTED
[21:39:45.574] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:25:0) after 8m56s
[21:48:41.655] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:25:0)
[21:48:41.676] Build status: STARTED
[21:48:41.676] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:26:0) after 2m22s
[21:51:03.774] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:26:0)
[21:51:03.802] Build status: STARTED
[21:51:03.802] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:27:0) after 9m22s
[22:00:26.011] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:27:0)
[22:00:26.038] Build status: STARTED
[22:00:26.038] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:28:0) after 5m13s
[22:05:39.032] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:28:0)
[22:05:39.054] Build status: STARTED
[22:05:39.054] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:29:0) after 8m21s
[22:14:00.422] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:29:0)
[22:14:00.543] Build status: STARTED
[22:14:00.543] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:30:0) after 7m32s
[22:21:32.577] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:30:0)
[22:21:32.603] Build status: STARTED
[22:21:32.603] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:31:0) after 2m44s
[22:24:16.630] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:31:0)
[22:24:16.672] Build status: STARTED
[22:24:16.672] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:32:0) after 9m35s
[22:33:51.895] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:32:0)
[22:33:51.982] Build status: STARTED
[22:33:51.982] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:33:0) after 7m11s
[22:41:03.003] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:33:0)
[22:41:03.026] Build status: STARTED
[22:41:03.026] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:34:0) after 6m4s
[22:47:07.154] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:34:0)
[22:47:07.183] Build status: STARTED
[22:47:07.183] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:35:0) after 3m7s
[22:50:14.245] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:35:0)
[22:50:14.272] Build status: STARTED
[22:50:14.272] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:36:0) after 1m51s
[22:52:05.247] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:36:0)
[22:52:05.268] Build status: STARTED
[22:52:05.268] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:37:0) after 4m49s
[22:56:54.498] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:37:0)
[22:56:54.535] Build status: STARTED
[22:56:54.535] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:38:0) after 3m56s
[23:00:50.565] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:38:0)
[23:00:50.588] Build status: STARTED
[23:00:50.588] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:39:0) after 5m32s
[23:06:22.612] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:39:0)
[23:06:22.630] Build status: STARTED
[23:06:22.630] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:40:0) after 6m26s
[23:12:48.668] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:40:0)
[23:12:48.694] Build status: STARTED
[23:12:48.694] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:41:0) after 6m30s
[23:19:18.827] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:41:0)
[23:19:18.849] Build status: STARTED
[23:19:18.849] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:42:0) after 7m24s
[23:26:42.953] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:42:0)
[23:26:42.972] Build status: STARTED
[23:26:42.972] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:43:0) after 4m1s
[23:30:44.022] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:43:0)
[23:30:44.126] Build status: STARTED
[23:30:44.126] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:44:0) after 9m38s
[23:40:22.220] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:44:0)
[23:40:22.242] Build status: STARTED
[23:40:22.242] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:45:0) after 5m12s
[23:45:34.265] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:45:0)
[23:45:34.282] Build status: STARTED
[23:45:34.282] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:46:0) after 6m16s
[23:51:50.390] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:46:0)
[23:51:50.420] Build status: STARTED
[23:51:50.420] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:47:0) after 5m19s
[23:57:09.528] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:47:0)
[23:57:09.554] Build status: STARTED
[23:57:09.554] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:48:0) after 7m5s
[00:04:14.659] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:48:0)
[00:04:14.677] Build status: STARTED
[00:04:14.678] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:49:0) after 2m44s
[00:06:58.699] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:49:0)
[00:06:58.730] Build status: STARTED
[00:06:58.730] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:50:0) after 6m39s
[00:13:37.770] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:50:0)
[00:13:37.794] Build status: STARTED
[00:13:37.794] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:51:0) after 6m5s
[00:19:42.864] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:51:0)
[00:19:42.891] Build status: STARTED
[00:19:42.891] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:52:0) after 6m58s
[00:26:41.007] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:52:0)
[00:26:41.032] Build status: STARTED
[00:26:41.032] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:53:0) after 2m37s
[00:29:18.059] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:53:0)
[00:29:18.079] Build status: STARTED
[00:29:18.079] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:54:0) after 4m54s
[00:34:12.185] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:54:0)
[00:34:12.253] Build status: STARTED
[00:34:12.253] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:55:0) after 5m11s
[00:39:23.276] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:55:0)
[00:39:23.410] Build status: STARTED
[00:39:23.410] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:56:0) after 2m48s
[00:42:11.713] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:56:0)
[00:42:11.733] Build status: STARTED
[00:42:11.733] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:57:0) after 9m36s
[00:51:47.894] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:57:0)
[00:51:47.932] Build status: STARTED
[00:51:47.932] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:58:0) after 2m10s
[00:53:57.950] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:58:0)
[00:53:57.971] Build status: STARTED
[00:53:57.971] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:59:0) after 4m53s
[00:58:50.990] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:59:0)
[00:58:51.030] Build status: STARTED
[00:58:51.030] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:60:0) after 7m20s
[01:06:11.086] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:60:0)
[01:06:11.109] Build status: STARTED
[01:06:11.109] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:61:0) after 8m52s
[01:15:03.127] Handling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:61:0)
[01:15:03.145] Build status: STARTED
[01:15:03.145] Scheduling timer "check-buildbucket-build-status" (chromium/win10-rel-no-external-ip:8915539998355805264:62:0) after 5m25s
[01:18:04.876] Received PubSub notification, asking Buildbucket for the build status
[01:18:04.896] Build:
{
"id": "8750109936172740033",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win10-rel-no-external-ip"
},
"number": 3244,
"createdBy": "project:chromium",
"createTime": "2024-04-20T19:38:30.348293307Z",
"startTime": "2024-04-20T19:39:39.293551631Z",
"endTime": "2024-04-21T01:18:04.450368262Z",
"updateTime": "2024-04-21T01:18:04.450368262Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e4e186080abd406ee41c677c17073cdecb16bea2",
"ref": "refs/heads/main"
}
}
}
[01:18:04.896] Invocation finished in 5h39m36.012691752s with status FAILED