[21:23:13.544] New invocation is queued and will start shortly
[21:23:14.758] Starting the invocation (attempt 1)
[21:23:14.782] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4f6b84c05c18973990ab07d8bf7c02b07069d3a"
[21:23:14.782] Popped gitiles commit info from properties and tags
[21:23:14.782] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:23:14.783] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:23:14.783] Buildbucket request:
{
"requestId": "8915533408415897888",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f4f6b84c05c18973990ab07d8bf7c02b07069d3a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915533408415897888"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:23:15.351] Scheduled build:
{
"id": "8750103346405901313",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 15031,
"createdBy": "project:chromium",
"createTime": "2024-04-20T21:23:14.840497040Z",
"updateTime": "2024-04-20T21:23:14.840497040Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f4f6b84c05c18973990ab07d8bf7c02b07069d3a",
"ref": "refs/heads/main"
}
}
}
[21:23:15.351] Task URL: https://cr-buildbucket.appspot.com/build/8750103346405901313
[21:23:15.351] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:2:0) after 5m25s
[21:23:16.446] Received PubSub notification, asking Buildbucket for the build status
[21:23:16.465] Build status: SCHEDULED
[21:28:40.368] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:2:0)
[21:28:40.396] Build status: SCHEDULED
[21:28:40.396] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:4:0) after 7m0s
[21:34:27.637] Received PubSub notification, asking Buildbucket for the build status
[21:34:27.656] Build status: STARTED
[21:35:40.621] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:4:0)
[21:35:40.663] Build status: STARTED
[21:35:40.663] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:6:0) after 4m0s
[21:39:40.689] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:6:0)
[21:39:40.708] Build status: STARTED
[21:39:40.708] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:7:0) after 1m24s
[21:41:04.726] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:7:0)
[21:41:04.745] Build status: STARTED
[21:41:04.745] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:8:0) after 1m8s
[21:42:12.796] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:8:0)
[21:42:12.836] Build status: STARTED
[21:42:12.836] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:9:0) after 3m56s
[21:46:09.116] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:9:0)
[21:46:09.154] Build status: STARTED
[21:46:09.154] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:10:0) after 1m11s
[21:47:20.257] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:10:0)
[21:47:20.279] Build status: STARTED
[21:47:20.279] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:11:0) after 4m48s
[21:52:08.298] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:11:0)
[21:52:08.319] Build status: STARTED
[21:52:08.319] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:12:0) after 9m0s
[22:01:08.425] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:12:0)
[22:01:08.451] Build status: STARTED
[22:01:08.451] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:13:0) after 7m0s
[22:08:08.470] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:13:0)
[22:08:08.492] Build status: STARTED
[22:08:08.493] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:14:0) after 5m45s
[22:13:53.502] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:14:0)
[22:13:53.560] Build status: STARTED
[22:13:53.560] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:15:0) after 2m26s
[22:16:19.583] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:15:0)
[22:16:19.608] Build status: STARTED
[22:16:19.608] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:16:0) after 4m45s
[22:21:04.625] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:16:0)
[22:21:04.643] Build status: STARTED
[22:21:04.643] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:17:0) after 4m1s
[22:25:05.732] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:17:0)
[22:25:05.803] Build status: STARTED
[22:25:05.803] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:18:0) after 1m32s
[22:26:38.017] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:18:0)
[22:26:38.139] Build status: STARTED
[22:26:38.139] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:19:0) after 9m58s
[22:36:36.166] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:19:0)
[22:36:36.183] Build status: STARTED
[22:36:36.183] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:20:0) after 5m52s
[22:42:28.350] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:20:0)
[22:42:28.372] Build status: STARTED
[22:42:28.372] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:21:0) after 7m58s
[22:50:26.565] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:21:0)
[22:50:26.588] Build status: STARTED
[22:50:26.588] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:22:0) after 1m47s
[22:52:13.631] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:22:0)
[22:52:13.652] Build status: STARTED
[22:52:13.652] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:23:0) after 8m56s
[23:01:09.755] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:23:0)
[23:01:09.775] Build status: STARTED
[23:01:09.775] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:24:0) after 9m46s
[23:10:55.922] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:24:0)
[23:10:55.958] Build status: STARTED
[23:10:55.958] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:25:0) after 4m16s
[23:15:11.985] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:25:0)
[23:15:12.008] Build status: STARTED
[23:15:12.008] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:26:0) after 1m23s
[23:16:35.053] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:26:0)
[23:16:35.079] Build status: STARTED
[23:16:35.079] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:27:0) after 4m46s
[23:21:21.312] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:27:0)
[23:21:21.379] Build status: STARTED
[23:21:21.379] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:28:0) after 6m52s
[23:28:13.456] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:28:0)
[23:28:13.491] Build status: STARTED
[23:28:13.491] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:29:0) after 1m49s
[23:30:02.595] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:29:0)
[23:30:02.614] Build status: STARTED
[23:30:02.614] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:30:0) after 6m11s
[23:36:13.635] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:30:0)
[23:36:13.657] Build status: STARTED
[23:36:13.657] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:31:0) after 9m5s
[23:45:18.677] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:31:0)
[23:45:18.696] Build status: STARTED
[23:45:18.696] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:32:0) after 5m41s
[23:51:00.145] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:32:0)
[23:51:00.181] Build status: STARTED
[23:51:00.181] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:33:0) after 4m28s
[23:55:28.213] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:33:0)
[23:55:28.236] Build status: STARTED
[23:55:28.236] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:34:0) after 8m32s
[00:04:00.284] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:34:0)
[00:04:00.318] Build status: STARTED
[00:04:00.318] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:35:0) after 1m14s
[00:05:14.462] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:35:0)
[00:05:14.482] Build status: STARTED
[00:05:14.482] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:36:0) after 5m14s
[00:10:28.557] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:36:0)
[00:10:28.630] Build status: STARTED
[00:10:28.630] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:37:0) after 9m14s
[00:19:42.682] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:37:0)
[00:19:42.805] Build status: STARTED
[00:19:42.805] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:38:0) after 3m43s
[00:23:25.944] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:38:0)
[00:23:25.966] Build status: STARTED
[00:23:25.966] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:39:0) after 3m1s
[00:26:26.994] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:39:0)
[00:26:27.151] Build status: STARTED
[00:26:27.151] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:40:0) after 9m52s
[00:36:19.173] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:40:0)
[00:36:19.193] Build status: STARTED
[00:36:19.193] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:41:0) after 8m24s
[00:44:43.258] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:41:0)
[00:44:43.323] Build status: STARTED
[00:44:43.323] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:42:0) after 2m55s
[00:47:38.510] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:42:0)
[00:47:38.529] Build status: STARTED
[00:47:38.529] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:43:0) after 4m22s
[00:52:00.546] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:43:0)
[00:52:00.573] Build status: STARTED
[00:52:00.573] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:44:0) after 8m2s
[01:00:02.604] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:44:0)
[01:00:02.625] Build status: STARTED
[01:00:02.625] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:45:0) after 3m2s
[01:03:04.646] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:45:0)
[01:03:04.707] Build status: STARTED
[01:03:04.707] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:46:0) after 7m57s
[01:11:01.730] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:46:0)
[01:11:01.753] Build status: STARTED
[01:11:01.753] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:47:0) after 3m18s
[01:14:19.774] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:47:0)
[01:14:19.795] Build status: STARTED
[01:14:19.795] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:48:0) after 5m12s
[01:19:31.859] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:48:0)
[01:19:31.882] Build status: STARTED
[01:19:31.882] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:49:0) after 6m55s
[01:26:26.881] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:49:0)
[01:26:26.920] Build status: STARTED
[01:26:26.920] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:50:0) after 2m15s
[01:28:41.942] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:50:0)
[01:28:42.011] Build status: STARTED
[01:28:42.011] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:51:0) after 4m43s
[01:33:25.037] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:51:0)
[01:33:25.055] Build status: STARTED
[01:33:25.055] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:52:0) after 3m12s
[01:36:37.115] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:52:0)
[01:36:37.142] Build status: STARTED
[01:36:37.142] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:53:0) after 6m10s
[01:42:47.174] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:53:0)
[01:42:47.196] Build status: STARTED
[01:42:47.196] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:54:0) after 9m53s
[01:52:40.371] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:54:0)
[01:52:40.387] Build status: STARTED
[01:52:40.387] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:55:0) after 1m14s
[01:53:54.486] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:55:0)
[01:53:54.560] Build status: STARTED
[01:53:54.560] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:56:0) after 7m59s
[02:01:53.647] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:56:0)
[02:01:53.977] Build status: STARTED
[02:01:53.977] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:57:0) after 2m48s
[02:04:42.024] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:57:0)
[02:04:42.048] Build status: STARTED
[02:04:42.048] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:58:0) after 7m40s
[02:12:22.179] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:58:0)
[02:12:22.199] Build status: STARTED
[02:12:22.199] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:59:0) after 5m44s
[02:18:06.355] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:59:0)
[02:18:06.462] Build status: STARTED
[02:18:06.462] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:60:0) after 9m14s
[02:27:20.493] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:60:0)
[02:27:20.518] Build status: STARTED
[02:27:20.518] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:61:0) after 8m45s
[02:36:05.643] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:61:0)
[02:36:05.670] Build status: STARTED
[02:36:05.670] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:62:0) after 8m9s
[02:44:14.755] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:62:0)
[02:44:14.776] Build status: STARTED
[02:44:14.776] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:63:0) after 2m43s
[02:46:57.894] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:63:0)
[02:46:57.921] Build status: STARTED
[02:46:57.921] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:64:0) after 8m55s
[02:55:53.015] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:64:0)
[02:55:53.040] Build status: STARTED
[02:55:53.040] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:65:0) after 2m8s
[02:58:01.147] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:65:0)
[02:58:01.172] Build status: STARTED
[02:58:01.172] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8915533408415897888:66:0) after 6m44s
[03:02:32.198] Received PubSub notification, asking Buildbucket for the build status
[03:02:32.237] Build:
{
"id": "8750103346405901313",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 15031,
"createdBy": "project:chromium",
"createTime": "2024-04-20T21:23:14.840497040Z",
"startTime": "2024-04-20T21:34:27.464744211Z",
"endTime": "2024-04-21T03:02:31.829272607Z",
"updateTime": "2024-04-21T03:02:31.829272607Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f4f6b84c05c18973990ab07d8bf7c02b07069d3a",
"ref": "refs/heads/main"
}
}
}
[03:02:32.237] Invocation finished in 5h39m18.698538843s with status SUCCEEDED