[20:36:30.929] New invocation is queued and will start shortly
[20:36:31.998] Starting the invocation (attempt 1)
[20:36:32.033] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49b093a53ac196bad18a3f825e3ac83a118599db"
[20:36:32.033] Popped gitiles commit info from properties and tags
[20:36:32.033] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:36:32.033] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:36:32.033] Buildbucket request:
{
"requestId": "9005136746958268816",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "49b093a53ac196bad18a3f825e3ac83a118599db",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9005136746958268816"
},
{
"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"
}
}
[20:36:32.601] Scheduled build:
{
"id": "8839706685022278512",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 3176,
"createdBy": "project:chromium",
"createTime": "2021-08-05T20:36:32.137940Z",
"updateTime": "2021-08-05T20:36:32.527792Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "49b093a53ac196bad18a3f825e3ac83a118599db",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:36:32.601] Task URL: https://cr-buildbucket.appspot.com/build/8839706685022278512
[20:36:32.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:2:0) after 8m30s
[20:38:12.816] Received PubSub notification, asking Buildbucket for the build status
[20:38:12.870] Build status: STARTED
[20:45:02.732] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:2:0)
[20:45:02.799] Build status: STARTED
[20:45:02.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:4:0) after 2m2s
[20:47:05.014] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:4:0)
[20:47:05.060] Build status: STARTED
[20:47:05.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:5:0) after 3m47s
[20:50:52.241] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:5:0)
[20:50:52.343] Build status: STARTED
[20:50:52.343] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:6:0) after 2m4s
[20:52:56.518] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:6:0)
[20:52:56.660] Build status: STARTED
[20:52:56.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:7:0) after 9m15s
[21:02:12.011] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:7:0)
[21:02:12.043] Build status: STARTED
[21:02:12.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:8:0) after 5m33s
[21:07:45.352] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:8:0)
[21:07:45.378] Build status: STARTED
[21:07:45.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:9:0) after 2m47s
[21:10:32.547] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:9:0)
[21:10:32.593] Build status: STARTED
[21:10:32.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:10:0) after 6m27s
[21:16:59.650] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:10:0)
[21:16:59.686] Build status: STARTED
[21:16:59.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:11:0) after 3m29s
[21:20:28.823] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:11:0)
[21:20:28.853] Build status: STARTED
[21:20:28.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:12:0) after 2m56s
[21:23:24.882] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:12:0)
[21:23:24.911] Build status: STARTED
[21:23:24.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:13:0) after 8m58s
[21:32:22.934] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:13:0)
[21:32:23.020] Build status: STARTED
[21:32:23.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:14:0) after 8m17s
[21:40:40.290] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:14:0)
[21:40:40.321] Build status: STARTED
[21:40:40.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:15:0) after 5m5s
[21:45:45.349] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:15:0)
[21:45:45.411] Build status: STARTED
[21:45:45.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:16:0) after 5m55s
[21:51:40.574] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:16:0)
[21:51:40.606] Build status: STARTED
[21:51:40.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:17:0) after 1m41s
[21:53:21.719] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:17:0)
[21:53:21.745] Build status: STARTED
[21:53:21.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:18:0) after 3m15s
[21:56:36.787] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:18:0)
[21:56:36.818] Build status: STARTED
[21:56:36.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:19:0) after 1m11s
[21:57:47.841] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:19:0)
[21:57:47.868] Build status: STARTED
[21:57:47.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:20:0) after 3m53s
[22:01:40.893] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:20:0)
[22:01:40.917] Build status: STARTED
[22:01:40.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:21:0) after 7m39s
[22:09:19.931] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:21:0)
[22:09:20.031] Build status: STARTED
[22:09:20.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:22:0) after 2m43s
[22:12:03.177] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:22:0)
[22:12:03.208] Build status: STARTED
[22:12:03.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:23:0) after 1m53s
[22:13:56.243] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:23:0)
[22:13:56.287] Build status: STARTED
[22:13:56.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:24:0) after 4m44s
[22:18:40.313] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:24:0)
[22:18:40.360] Build status: STARTED
[22:18:40.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:25:0) after 2m15s
[22:20:55.379] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:25:0)
[22:20:55.409] Build status: STARTED
[22:20:55.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:26:0) after 3m15s
[22:24:10.435] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:26:0)
[22:24:10.505] Build status: STARTED
[22:24:10.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:27:0) after 2m26s
[22:26:36.493] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:27:0)
[22:26:36.521] Build status: STARTED
[22:26:36.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:28:0) after 1m46s
[22:28:22.744] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:28:0)
[22:28:22.773] Build status: STARTED
[22:28:22.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:29:0) after 9m49s
[22:38:11.915] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:29:0)
[22:38:12.140] Build status: STARTED
[22:38:12.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:30:0) after 6m0s
[22:44:12.238] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:30:0)
[22:44:12.271] Build status: STARTED
[22:44:12.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:31:0) after 6m30s
[22:50:42.308] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:31:0)
[22:50:42.341] Build status: STARTED
[22:50:42.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:32:0) after 9m31s
[23:00:13.389] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:32:0)
[23:00:13.457] Build status: STARTED
[23:00:13.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:33:0) after 1m8s
[23:01:21.638] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:33:0)
[23:01:21.677] Build status: STARTED
[23:01:21.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:34:0) after 9m30s
[23:10:51.809] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:34:0)
[23:10:51.839] Build status: STARTED
[23:10:51.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:35:0) after 1m28s
[23:12:20.338] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:35:0)
[23:12:20.366] Build status: STARTED
[23:12:20.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:36:0) after 9m8s
[23:21:28.496] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:36:0)
[23:21:28.521] Build status: STARTED
[23:21:28.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:37:0) after 4m24s
[23:25:52.546] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:37:0)
[23:25:52.574] Build status: STARTED
[23:25:52.574] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:38:0) after 9m16s
[23:35:08.606] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:38:0)
[23:35:08.638] Build status: STARTED
[23:35:08.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:39:0) after 7m20s
[23:42:28.723] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:39:0)
[23:42:28.750] Build status: STARTED
[23:42:28.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:40:0) after 3m9s
[23:45:37.771] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:40:0)
[23:45:37.809] Build status: STARTED
[23:45:37.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:41:0) after 8m29s
[23:54:07.125] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:41:0)
[23:54:07.153] Build status: STARTED
[23:54:07.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:42:0) after 1m56s
[23:56:03.176] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:42:0)
[23:56:15.804] Build status: STARTED
[23:56:15.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:43:0) after 4m0s
[00:00:15.908] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:43:0)
[00:00:15.938] Build status: STARTED
[00:00:15.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:44:0) after 2m34s
[00:02:49.980] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:44:0)
[00:02:50.047] Build status: STARTED
[00:02:50.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:45:0) after 5m37s
[00:08:27.143] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:45:0)
[00:08:27.180] Build status: STARTED
[00:08:27.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:46:0) after 4m52s
[00:13:19.270] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:46:0)
[00:13:19.307] Build status: STARTED
[00:13:19.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:47:0) after 6m51s
[00:20:10.689] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:47:0)
[00:20:10.717] Build status: STARTED
[00:20:10.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:48:0) after 6m43s
[00:26:53.812] Handling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:48:0)
[00:26:53.858] Build status: STARTED
[00:26:53.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:9005136746958268816:49:0) after 6m19s
[00:29:17.479] Received PubSub notification, asking Buildbucket for the build status
[00:29:17.509] Build:
{
"id": "8839706685022278512",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 3176,
"createdBy": "project:chromium",
"createTime": "2021-08-05T20:36:32.137940Z",
"startTime": "2021-08-05T20:38:12.256389Z",
"endTime": "2021-08-06T00:29:17.255748336Z",
"updateTime": "2021-08-06T00:29:17.256115129Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "49b093a53ac196bad18a3f825e3ac83a118599db",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:29:17.509] Invocation finished in 3h52m46.601951967s with status SUCCEEDED