[04:58:48.081] New invocation is queued and will start shortly
[04:58:49.195] Starting the invocation (attempt 1)
[04:58:49.273] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/575b97ca1077f1dede4c963b6891d42f68cb4d93"
[04:58:49.273] Popped gitiles commit info from properties and tags
[04:58:49.273] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:58:49.273] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:58:49.273] Buildbucket request:
{
"requestId": "8978560234711555872",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "575b97ca1077f1dede4c963b6891d42f68cb4d93",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8978560234711555872"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:58:49.875] Scheduled build:
{
"id": "8813130172655322881",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4966,
"createdBy": "project:chromium",
"createTime": "2022-05-26T04:58:49.401622222Z",
"updateTime": "2022-05-26T04:58:49.401622222Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "575b97ca1077f1dede4c963b6891d42f68cb4d93",
"ref": "refs/heads/main"
}
}
}
[04:58:49.875] Task URL: https://cr-buildbucket.appspot.com/build/8813130172655322881
[04:58:49.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:2:0) after 5m5s
[04:59:23.433] Received PubSub notification, asking Buildbucket for the build status
[04:59:23.500] Build status: STARTED
[05:03:55.052] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:2:0)
[05:03:55.085] Build status: STARTED
[05:03:55.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:4:0) after 6m38s
[05:10:33.074] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:4:0)
[05:10:33.118] Build status: STARTED
[05:10:33.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:5:0) after 7m25s
[05:17:58.279] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:5:0)
[05:17:58.308] Build status: STARTED
[05:17:58.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:6:0) after 5m26s
[05:23:24.345] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:6:0)
[05:23:24.365] Build status: STARTED
[05:23:24.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:7:0) after 9m40s
[05:33:04.470] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:7:0)
[05:33:04.557] Build status: STARTED
[05:33:04.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:8:0) after 2m57s
[05:36:01.648] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:8:0)
[05:36:01.673] Build status: STARTED
[05:36:01.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:9:0) after 9m20s
[05:45:21.716] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:9:0)
[05:45:21.834] Build status: STARTED
[05:45:21.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:10:0) after 6m23s
[05:51:44.963] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:10:0)
[05:51:44.985] Build status: STARTED
[05:51:44.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:11:0) after 1m2s
[05:52:47.776] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:11:0)
[05:52:47.796] Build status: STARTED
[05:52:47.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:12:0) after 3m27s
[05:56:14.812] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:12:0)
[05:56:14.851] Build status: STARTED
[05:56:14.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:13:0) after 9m38s
[06:05:52.923] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:13:0)
[06:05:52.946] Build status: STARTED
[06:05:52.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:14:0) after 5m4s
[06:10:56.974] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:14:0)
[06:10:57.002] Build status: STARTED
[06:10:57.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:15:0) after 2m23s
[06:13:20.026] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:15:0)
[06:13:20.048] Build status: STARTED
[06:13:20.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:16:0) after 7m43s
[06:21:03.074] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:16:0)
[06:21:03.120] Build status: STARTED
[06:21:03.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:17:0) after 6m26s
[06:27:29.187] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:17:0)
[06:27:29.215] Build status: STARTED
[06:27:29.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:18:0) after 9m53s
[06:37:22.232] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:18:0)
[06:37:22.288] Build status: STARTED
[06:37:22.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:19:0) after 8m32s
[06:45:54.426] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:19:0)
[06:45:54.450] Build status: STARTED
[06:45:54.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:20:0) after 6m51s
[06:52:45.575] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:20:0)
[06:52:45.603] Build status: STARTED
[06:52:45.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:21:0) after 7m59s
[07:00:44.695] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:21:0)
[07:00:44.758] Build status: STARTED
[07:00:44.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:22:0) after 4m43s
[07:05:28.009] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:22:0)
[07:05:28.144] Build status: STARTED
[07:05:28.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:23:0) after 3m43s
[07:09:11.173] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:23:0)
[07:09:11.196] Build status: STARTED
[07:09:11.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:24:0) after 4m45s
[07:13:56.245] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:24:0)
[07:13:56.298] Build status: STARTED
[07:13:56.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:25:0) after 1m56s
[07:15:52.517] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:25:0)
[07:15:52.538] Build status: STARTED
[07:15:52.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:26:0) after 1m46s
[07:17:38.871] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:26:0)
[07:17:38.895] Build status: STARTED
[07:17:38.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:27:0) after 9m56s
[07:27:35.173] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:27:0)
[07:27:35.196] Build status: STARTED
[07:27:35.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:28:0) after 7m8s
[07:34:43.214] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:28:0)
[07:34:43.263] Build status: STARTED
[07:34:43.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:29:0) after 1m55s
[07:36:38.387] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:29:0)
[07:36:38.421] Build status: STARTED
[07:36:38.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:30:0) after 3m39s
[07:40:17.452] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:30:0)
[07:40:17.474] Build status: STARTED
[07:40:17.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:31:0) after 8m38s
[07:48:55.509] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:31:0)
[07:48:55.531] Build status: STARTED
[07:48:55.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:32:0) after 7m27s
[07:56:22.554] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:32:0)
[07:56:22.622] Build status: STARTED
[07:56:22.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:33:0) after 3m48s
[08:00:10.644] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:33:0)
[08:00:10.686] Build status: STARTED
[08:00:10.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:34:0) after 2m58s
[08:03:08.724] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:34:0)
[08:03:08.750] Build status: STARTED
[08:03:08.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:35:0) after 5m57s
[08:09:05.938] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:35:0)
[08:09:05.963] Build status: STARTED
[08:09:05.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:36:0) after 2m31s
[08:11:37.105] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:36:0)
[08:11:37.126] Build status: STARTED
[08:11:37.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:37:0) after 3m48s
[08:15:25.150] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:37:0)
[08:15:25.181] Build status: STARTED
[08:15:25.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:38:0) after 8m16s
[08:23:41.244] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:38:0)
[08:23:41.265] Build status: STARTED
[08:23:41.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:39:0) after 4m5s
[08:27:46.420] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:39:0)
[08:27:46.441] Build status: STARTED
[08:27:46.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:40:0) after 3m49s
[08:31:35.424] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:40:0)
[08:31:35.448] Build status: STARTED
[08:31:35.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:41:0) after 1m9s
[08:32:44.467] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:41:0)
[08:32:44.511] Build status: STARTED
[08:32:44.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:42:0) after 3m3s
[08:35:47.682] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:42:0)
[08:35:47.705] Build status: STARTED
[08:35:47.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:43:0) after 1m24s
[08:37:11.832] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:43:0)
[08:37:11.861] Build status: STARTED
[08:37:11.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:44:0) after 2m7s
[08:39:18.883] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:44:0)
[08:39:18.939] Build status: STARTED
[08:39:18.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:45:0) after 7m52s
[08:47:10.970] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:45:0)
[08:47:10.993] Build status: STARTED
[08:47:10.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:46:0) after 9m8s
[08:56:19.024] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:46:0)
[08:56:19.061] Build status: STARTED
[08:56:19.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:47:0) after 3m43s
[09:00:02.121] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:47:0)
[09:00:02.176] Build status: STARTED
[09:00:02.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:48:0) after 2m46s
[09:02:48.197] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:48:0)
[09:02:48.219] Build status: STARTED
[09:02:48.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:49:0) after 1m12s
[09:04:00.238] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:49:0)
[09:04:00.261] Build status: STARTED
[09:04:00.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:50:0) after 7m44s
[09:11:44.282] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:50:0)
[09:11:44.302] Build status: STARTED
[09:11:44.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:51:0) after 6m40s
[09:18:24.347] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:51:0)
[09:18:24.380] Build status: STARTED
[09:18:24.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:52:0) after 7m2s
[09:25:26.504] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:52:0)
[09:25:26.529] Build status: STARTED
[09:25:26.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:53:0) after 1m48s
[09:27:14.628] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:53:0)
[09:27:14.665] Build status: STARTED
[09:27:14.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:54:0) after 5m55s
[09:33:09.686] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:54:0)
[09:33:09.746] Build status: STARTED
[09:33:09.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:55:0) after 5m47s
[09:38:56.768] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:55:0)
[09:38:56.804] Build status: STARTED
[09:38:56.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:56:0) after 4m28s
[09:43:25.060] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:56:0)
[09:43:25.083] Build status: STARTED
[09:43:25.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:57:0) after 2m26s
[09:45:51.126] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:57:0)
[09:45:51.164] Build status: STARTED
[09:45:51.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:58:0) after 9m4s
[09:54:55.289] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:58:0)
[09:54:55.314] Build status: STARTED
[09:54:55.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:59:0) after 8m50s
[10:03:45.539] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:59:0)
[10:03:45.567] Build status: STARTED
[10:03:45.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:60:0) after 2m43s
[10:06:28.602] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:60:0)
[10:06:28.626] Build status: STARTED
[10:06:28.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:61:0) after 7m50s
[10:14:18.775] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:61:0)
[10:14:18.837] Build status: STARTED
[10:14:18.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8978560234711555872:62:0) after 8m14s
[10:22:13.929] Received PubSub notification, asking Buildbucket for the build status
[10:22:13.967] Build:
{
"id": "8813130172655322881",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4966,
"createdBy": "project:chromium",
"createTime": "2022-05-26T04:58:49.401622222Z",
"startTime": "2022-05-26T04:59:22.699409Z",
"endTime": "2022-05-26T10:22:13.686967906Z",
"updateTime": "2022-05-26T10:22:13.686967906Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "575b97ca1077f1dede4c963b6891d42f68cb4d93",
"ref": "refs/heads/main"
}
}
}
[10:22:13.967] Invocation finished in 5h23m25.910548251s with status SUCCEEDED