[15:29:00.202] New invocation is queued and will start shortly
[15:29:01.703] Starting the invocation (attempt 1)
[15:29:01.746] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1bb8a7bd8b3876cca2a49bed5e9c6f045f3ac4bd"
[15:29:01.746] Popped gitiles commit info from properties and tags
[15:29:01.746] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:29:01.746] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:29:01.747] Buildbucket request:
{
"requestId": "9000263857757024112",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1bb8a7bd8b3876cca2a49bed5e9c6f045f3ac4bd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9000263857757024112"
},
{
"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"
}
}
[15:29:05.560] Scheduled build:
{
"id": "8834833795402354209",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 3657,
"createdBy": "project:chromium",
"createTime": "2021-09-28T15:29:01.814793781Z",
"updateTime": "2021-09-28T15:29:05.300966529Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1bb8a7bd8b3876cca2a49bed5e9c6f045f3ac4bd",
"ref": "refs/heads/main"
}
},
"canary": true
}
[15:29:05.560] Task URL: https://cr-buildbucket.appspot.com/build/8834833795402354209
[15:29:05.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:2:0) after 8m53s
[15:30:37.185] Received PubSub notification, asking Buildbucket for the build status
[15:30:37.211] Build status: STARTED
[15:37:58.680] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:2:0)
[15:37:58.716] Build status: STARTED
[15:37:58.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:4:0) after 8m41s
[15:46:40.210] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:4:0)
[15:46:40.242] Build status: STARTED
[15:46:40.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:5:0) after 6m50s
[15:53:30.505] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:5:0)
[15:53:30.571] Build status: STARTED
[15:53:30.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:6:0) after 6m52s
[16:00:22.593] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:6:0)
[16:00:22.624] Build status: STARTED
[16:00:22.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:7:0) after 3m49s
[16:04:11.660] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:7:0)
[16:04:11.688] Build status: STARTED
[16:04:11.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:8:0) after 2m43s
[16:06:54.872] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:8:0)
[16:06:54.903] Build status: STARTED
[16:06:54.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:9:0) after 8m7s
[16:15:01.924] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:9:0)
[16:15:01.952] Build status: STARTED
[16:15:01.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:10:0) after 8m59s
[16:24:01.413] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:10:0)
[16:24:01.450] Build status: STARTED
[16:24:01.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:11:0) after 3m19s
[16:27:20.571] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:11:0)
[16:27:20.604] Build status: STARTED
[16:27:20.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:12:0) after 9m41s
[16:37:01.626] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:12:0)
[16:37:01.663] Build status: STARTED
[16:37:01.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:13:0) after 3m2s
[16:40:03.721] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:13:0)
[16:40:03.753] Build status: STARTED
[16:40:03.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:14:0) after 4m12s
[16:44:15.742] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:14:0)
[16:44:15.776] Build status: STARTED
[16:44:15.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:15:0) after 5m4s
[16:49:19.934] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:15:0)
[16:49:19.982] Build status: STARTED
[16:49:19.982] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:16:0) after 2m3s
[16:51:23.207] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:16:0)
[16:51:23.351] Build status: STARTED
[16:51:23.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:17:0) after 2m17s
[16:53:40.595] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:17:0)
[16:53:40.661] Build status: STARTED
[16:53:40.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:18:0) after 8m50s
[17:02:30.741] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:18:0)
[17:02:30.772] Build status: STARTED
[17:02:30.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:19:0) after 4m6s
[17:06:36.862] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:19:0)
[17:06:36.896] Build status: STARTED
[17:06:36.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:20:0) after 7m36s
[17:14:12.973] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:20:0)
[17:14:13.021] Build status: STARTED
[17:14:13.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:21:0) after 5m40s
[17:19:53.354] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:21:0)
[17:19:53.394] Build status: STARTED
[17:19:53.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:22:0) after 5m47s
[17:25:40.434] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:22:0)
[17:25:40.482] Build status: STARTED
[17:25:40.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:23:0) after 3m46s
[17:29:26.509] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:23:0)
[17:29:26.540] Build status: STARTED
[17:29:26.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:24:0) after 6m36s
[17:36:02.662] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:24:0)
[17:36:02.695] Build status: STARTED
[17:36:02.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:25:0) after 5m57s
[17:41:59.947] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:25:0)
[17:41:59.980] Build status: STARTED
[17:41:59.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:26:0) after 3m36s
[17:45:36.010] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:26:0)
[17:45:36.049] Build status: STARTED
[17:45:36.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:27:0) after 6m31s
[17:52:07.203] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:27:0)
[17:52:07.344] Build status: STARTED
[17:52:07.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:28:0) after 1m34s
[17:53:41.625] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:28:0)
[17:53:41.655] Build status: STARTED
[17:53:41.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:29:0) after 5m13s
[17:58:54.679] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:29:0)
[17:58:54.711] Build status: STARTED
[17:58:54.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:30:0) after 4m38s
[18:03:33.208] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:30:0)
[18:03:33.384] Build status: STARTED
[18:03:33.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:31:0) after 1m43s
[18:05:16.408] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:31:0)
[18:05:16.453] Build status: STARTED
[18:05:16.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:32:0) after 9m0s
[18:14:16.516] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:32:0)
[18:14:16.542] Build status: STARTED
[18:14:16.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:33:0) after 7m10s
[18:21:26.566] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:33:0)
[18:21:26.596] Build status: STARTED
[18:21:26.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:34:0) after 8m56s
[18:30:23.155] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:34:0)
[18:30:23.203] Build status: STARTED
[18:30:23.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:35:0) after 3m54s
[18:34:17.227] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:35:0)
[18:34:17.259] Build status: STARTED
[18:34:17.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:36:0) after 4m21s
[18:38:38.379] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:36:0)
[18:38:38.520] Build status: STARTED
[18:38:38.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:37:0) after 8m8s
[18:46:46.494] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:37:0)
[18:46:46.524] Build status: STARTED
[18:46:46.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:38:0) after 3m42s
[18:50:28.584] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:38:0)
[18:50:28.613] Build status: STARTED
[18:50:28.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:39:0) after 8m54s
[18:59:23.171] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:39:0)
[18:59:23.204] Build status: STARTED
[18:59:23.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:40:0) after 8m36s
[19:07:59.211] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:40:0)
[19:07:59.245] Build status: STARTED
[19:07:59.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:41:0) after 6m16s
[19:14:15.268] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:41:0)
[19:14:15.301] Build status: STARTED
[19:14:15.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:42:0) after 3m38s
[19:17:53.929] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:42:0)
[19:17:53.961] Build status: STARTED
[19:17:53.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:43:0) after 6m22s
[19:24:16.098] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:43:0)
[19:24:16.133] Build status: STARTED
[19:24:16.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:44:0) after 3m54s
[19:28:10.472] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:44:0)
[19:28:10.506] Build status: STARTED
[19:28:10.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:45:0) after 8m21s
[19:36:31.541] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:45:0)
[19:36:31.585] Build status: STARTED
[19:36:31.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:46:0) after 4m31s
[19:41:02.830] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:46:0)
[19:41:02.870] Build status: STARTED
[19:41:02.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:47:0) after 5m56s
[19:46:59.490] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:47:0)
[19:46:59.554] Build status: STARTED
[19:46:59.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:48:0) after 3m53s
[19:50:53.136] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:48:0)
[19:50:53.171] Build status: STARTED
[19:50:53.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:49:0) after 2m36s
[19:53:29.340] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:49:0)
[19:53:29.370] Build status: STARTED
[19:53:29.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:50:0) after 1m27s
[19:54:56.397] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:50:0)
[19:54:56.429] Build status: STARTED
[19:54:56.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:51:0) after 4m43s
[19:59:39.494] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:51:0)
[19:59:39.521] Build status: STARTED
[19:59:39.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:52:0) after 4m42s
[20:04:21.564] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:52:0)
[20:04:21.621] Build status: STARTED
[20:04:21.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:53:0) after 9m34s
[20:13:56.035] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:53:0)
[20:13:56.085] Build status: STARTED
[20:13:56.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:54:0) after 3m9s
[20:17:05.295] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:54:0)
[20:17:05.328] Build status: STARTED
[20:17:05.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9000263857757024112:55:0) after 8m39s
[20:22:47.661] Received PubSub notification, asking Buildbucket for the build status
[20:22:47.691] Build:
{
"id": "8834833795402354209",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 3657,
"createdBy": "project:chromium",
"createTime": "2021-09-28T15:29:01.814793781Z",
"startTime": "2021-09-28T15:30:35.961304Z",
"endTime": "2021-09-28T20:22:47.192069388Z",
"updateTime": "2021-09-28T20:22:47.192422061Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1bb8a7bd8b3876cca2a49bed5e9c6f045f3ac4bd",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:22:47.691] Invocation finished in 4h53m47.505208471s with status SUCCEEDED