[11:49:30.102] New invocation is queued and will start shortly
[11:49:31.222] Starting the invocation (attempt 1)
[11:49:31.255] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/16d6328b88570c6b3b7c0a433e2a6ff93a26ec76"
[11:49:31.255] Popped gitiles commit info from properties and tags
[11:49:31.255] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:49:31.255] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:49:31.255] Buildbucket request:
{
"requestId": "9013414227734906480",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/2.2",
"repo": "https://r8.googlesource.com/r8",
"revision": "16d6328b88570c6b3b7c0a433e2a6ff93a26ec76"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/2.2@16d6328b88570c6b3b7c0a433e2a6ff93a26ec76",
"title": "16d6328b88570c6b3b7c0a433e2a6ff93a26ec76",
"url": "https://r8.googlesource.com/r8/+/16d6328b88570c6b3b7c0a433e2a6ff93a26ec76"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "16d6328b88570c6b3b7c0a433e2a6ff93a26ec76",
"ref": "refs/heads/2.2"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9013414227734906480"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal_release"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:49:31.889] Scheduled build:
{
"id": "8847984165785305712",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 427,
"createdBy": "project:r8",
"createTime": "2021-05-06T11:49:31.329022Z",
"updateTime": "2021-05-06T11:49:31.776083Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "16d6328b88570c6b3b7c0a433e2a6ff93a26ec76",
"ref": "refs/heads/2.2"
}
}
}
[11:49:31.889] Task URL: https://cr-buildbucket.appspot.com/build/8847984165785305712
[11:49:31.889] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:2:0) after 1m5s
[11:50:36.973] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:2:0)
[11:50:37.000] Build status: SCHEDULED
[11:50:37.000] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:3:0) after 7m55s
[11:58:32.030] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:3:0)
[11:58:32.059] Build status: SCHEDULED
[11:58:32.059] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:4:0) after 8m54s
[12:07:26.092] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:4:0)
[12:07:26.124] Build status: SCHEDULED
[12:07:26.124] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:5:0) after 6m46s
[12:14:12.153] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:5:0)
[12:14:12.179] Build status: SCHEDULED
[12:14:12.179] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:6:0) after 6m10s
[12:20:22.255] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:6:0)
[12:20:22.280] Build status: SCHEDULED
[12:20:22.280] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:7:0) after 9m32s
[12:29:54.420] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:7:0)
[12:29:54.451] Build status: SCHEDULED
[12:29:54.451] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:8:0) after 1m14s
[12:31:08.513] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:8:0)
[12:31:08.541] Build status: SCHEDULED
[12:31:08.541] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:9:0) after 6m29s
[12:37:37.595] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:9:0)
[12:37:37.637] Build status: SCHEDULED
[12:37:37.637] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:10:0) after 9m32s
[12:47:09.795] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:10:0)
[12:47:09.830] Build status: SCHEDULED
[12:47:09.830] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:11:0) after 2m3s
[12:49:12.955] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:11:0)
[12:49:12.981] Build status: SCHEDULED
[12:49:12.981] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:12:0) after 5m52s
[12:55:05.049] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:12:0)
[12:55:05.075] Build status: SCHEDULED
[12:55:05.075] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:13:0) after 9m38s
[13:04:43.214] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:13:0)
[13:04:43.243] Build status: SCHEDULED
[13:04:43.243] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:14:0) after 3m9s
[13:07:52.268] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:14:0)
[13:07:52.306] Build status: SCHEDULED
[13:07:52.306] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:15:0) after 2m40s
[13:10:32.680] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:15:0)
[13:10:32.709] Build status: SCHEDULED
[13:10:32.709] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:16:0) after 2m9s
[13:12:41.779] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:16:0)
[13:12:41.821] Build status: SCHEDULED
[13:12:41.821] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:17:0) after 4m55s
[13:17:37.088] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:17:0)
[13:17:37.115] Build status: SCHEDULED
[13:17:37.115] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:18:0) after 5m49s
[13:23:26.380] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:18:0)
[13:23:26.411] Build status: SCHEDULED
[13:23:26.411] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:19:0) after 8m45s
[13:32:11.517] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:19:0)
[13:32:11.547] Build status: SCHEDULED
[13:32:11.547] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:20:0) after 2m19s
[13:34:30.663] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:20:0)
[13:34:30.695] Build status: SCHEDULED
[13:34:30.695] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:21:0) after 8m52s
[13:43:22.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:21:0)
[13:43:22.853] Build status: SCHEDULED
[13:43:22.853] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:22:0) after 8m4s
[13:51:26.870] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:22:0)
[13:51:26.896] Build status: SCHEDULED
[13:51:26.896] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:23:0) after 3m5s
[13:54:32.014] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:23:0)
[13:54:32.043] Build status: SCHEDULED
[13:54:32.043] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:24:0) after 2m40s
[13:57:12.160] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:24:0)
[13:57:12.188] Build status: SCHEDULED
[13:57:12.188] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:25:0) after 1m3s
[13:58:15.272] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:25:0)
[13:58:15.300] Build status: SCHEDULED
[13:58:15.300] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:26:0) after 8m29s
[14:06:44.327] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:26:0)
[14:06:44.384] Build status: SCHEDULED
[14:06:44.384] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:27:0) after 7m3s
[14:13:47.584] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:27:0)
[14:13:47.608] Build status: SCHEDULED
[14:13:47.608] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:28:0) after 9m55s
[14:23:24.390] Received PubSub notification, asking Buildbucket for the build status
[14:23:24.420] Build status: STARTED
[14:23:42.627] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:28:0)
[14:23:42.659] Build status: STARTED
[14:23:42.659] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:30:0) after 4m2s
[14:27:44.692] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:30:0)
[14:27:44.756] Build status: STARTED
[14:27:44.756] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:31:0) after 3m8s
[14:30:52.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:31:0)
[14:30:52.852] Build status: STARTED
[14:30:52.852] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:32:0) after 3m11s
[14:34:03.839] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:32:0)
[14:34:03.867] Build status: STARTED
[14:34:03.868] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:33:0) after 4m51s
[14:38:55.050] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:33:0)
[14:38:55.078] Build status: STARTED
[14:38:55.078] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:34:0) after 5m58s
[14:44:53.096] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:34:0)
[14:44:53.132] Build status: STARTED
[14:44:53.132] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:35:0) after 7m4s
[14:51:57.147] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:35:0)
[14:51:57.179] Build status: STARTED
[14:51:57.179] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:36:0) after 4m0s
[14:55:57.202] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:36:0)
[14:55:57.258] Build status: STARTED
[14:55:57.258] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:37:0) after 8m15s
[15:04:12.528] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:37:0)
[15:04:12.569] Build status: STARTED
[15:04:12.569] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:38:0) after 4m53s
[15:09:05.585] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:38:0)
[15:09:05.630] Build status: STARTED
[15:09:05.630] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:39:0) after 6m47s
[15:15:52.649] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:39:0)
[15:15:52.691] Build status: STARTED
[15:15:52.691] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:40:0) after 5m19s
[15:21:11.711] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:40:0)
[15:21:11.743] Build status: STARTED
[15:21:11.743] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:41:0) after 9m3s
[15:30:15.234] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:41:0)
[15:30:15.274] Build status: STARTED
[15:30:15.274] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:42:0) after 2m15s
[15:32:30.292] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:42:0)
[15:32:30.324] Build status: STARTED
[15:32:30.324] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:43:0) after 6m19s
[15:38:49.415] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:43:0)
[15:38:49.474] Build status: STARTED
[15:38:49.474] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:44:0) after 4m27s
[15:43:16.725] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:44:0)
[15:43:16.750] Build status: STARTED
[15:43:16.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:45:0) after 4m41s
[15:47:57.769] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:45:0)
[15:47:57.797] Build status: STARTED
[15:47:57.797] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:46:0) after 9m41s
[15:57:38.827] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:46:0)
[15:57:38.854] Build status: STARTED
[15:57:38.854] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:47:0) after 4m17s
[16:01:55.949] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:47:0)
[16:01:55.992] Build status: STARTED
[16:01:55.992] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:48:0) after 2m42s
[16:04:38.051] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:48:0)
[16:04:38.081] Build status: STARTED
[16:04:38.081] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:49:0) after 6m39s
[16:11:17.204] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:49:0)
[16:11:17.626] Build status: STARTED
[16:11:17.626] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:50:0) after 1m49s
[16:13:06.669] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:50:0)
[16:13:06.699] Build status: STARTED
[16:13:06.699] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:51:0) after 7m52s
[16:20:58.791] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:51:0)
[16:20:58.842] Build status: STARTED
[16:20:58.842] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:52:0) after 6m13s
[16:27:11.979] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:52:0)
[16:27:12.012] Build status: STARTED
[16:27:12.012] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:53:0) after 1m34s
[16:28:46.065] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:53:0)
[16:28:46.120] Build status: STARTED
[16:28:46.120] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:54:0) after 9m45s
[16:38:31.152] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:54:0)
[16:38:31.181] Build status: STARTED
[16:38:31.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:55:0) after 5m5s
[16:43:36.209] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:55:0)
[16:43:36.254] Build status: STARTED
[16:43:36.254] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:56:0) after 9m42s
[16:53:18.911] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:56:0)
[16:53:18.940] Build status: STARTED
[16:53:18.940] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:57:0) after 8m45s
[17:02:04.191] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:57:0)
[17:02:04.230] Build status: STARTED
[17:02:04.230] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:58:0) after 1m33s
[17:03:37.273] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:58:0)
[17:03:37.308] Build status: STARTED
[17:03:37.308] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:59:0) after 6m14s
[17:09:51.441] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:59:0)
[17:09:51.472] Build status: STARTED
[17:09:51.472] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:60:0) after 2m13s
[17:12:04.493] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:60:0)
[17:12:04.543] Build status: STARTED
[17:12:04.543] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:61:0) after 5m3s
[17:17:07.660] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:61:0)
[17:17:07.691] Build status: STARTED
[17:17:07.691] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:62:0) after 1m49s
[17:18:56.727] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:62:0)
[17:18:56.754] Build status: STARTED
[17:18:56.754] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:63:0) after 4m51s
[17:23:48.245] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:63:0)
[17:23:48.274] Build status: STARTED
[17:23:48.274] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:64:0) after 4m34s
[17:28:22.326] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:64:0)
[17:28:22.361] Build status: STARTED
[17:28:22.361] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:65:0) after 5m31s
[17:33:53.982] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:65:0)
[17:33:54.021] Build status: STARTED
[17:33:54.021] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:66:0) after 1m46s
[17:35:40.569] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:66:0)
[17:35:40.597] Build status: STARTED
[17:35:40.597] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:67:0) after 7m49s
[17:43:29.718] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:67:0)
[17:43:29.747] Build status: STARTED
[17:43:29.747] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:68:0) after 7m47s
[17:51:16.845] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:68:0)
[17:51:16.875] Build status: STARTED
[17:51:16.876] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:69:0) after 4m44s
[17:56:00.891] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:69:0)
[17:56:00.920] Build status: STARTED
[17:56:00.920] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:70:0) after 4m26s
[18:00:26.960] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:70:0)
[18:00:27.002] Build status: STARTED
[18:00:27.002] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:71:0) after 8m2s
[18:08:29.088] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:71:0)
[18:08:29.115] Build status: STARTED
[18:08:29.115] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:72:0) after 8m50s
[18:17:19.349] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:72:0)
[18:17:19.395] Build status: STARTED
[18:17:19.395] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:73:0) after 6m38s
[18:23:57.468] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:73:0)
[18:23:57.502] Build status: STARTED
[18:23:57.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:74:0) after 4m30s
[18:28:27.528] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:74:0)
[18:28:27.556] Build status: STARTED
[18:28:27.556] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:75:0) after 3m16s
[18:31:43.577] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:75:0)
[18:31:43.610] Build status: STARTED
[18:31:43.610] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:76:0) after 9m14s
[18:40:57.601] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:76:0)
[18:40:57.648] Build status: STARTED
[18:40:57.648] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:77:0) after 8m19s
[18:49:16.666] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:77:0)
[18:49:16.692] Build status: STARTED
[18:49:16.692] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:78:0) after 3m25s
[18:52:41.709] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:78:0)
[18:52:41.742] Build status: STARTED
[18:52:41.742] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:79:0) after 2m44s
[18:55:25.821] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:79:0)
[18:55:25.848] Build status: STARTED
[18:55:25.848] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:80:0) after 8m27s
[19:03:52.904] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:80:0)
[19:03:52.933] Build status: STARTED
[19:03:52.933] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:81:0) after 2m9s
[19:06:01.994] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:81:0)
[19:06:02.025] Build status: STARTED
[19:06:02.025] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:82:0) after 5m31s
[19:11:33.049] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:82:0)
[19:11:33.086] Build status: STARTED
[19:11:33.086] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:83:0) after 1m7s
[19:12:40.102] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:83:0)
[19:12:40.132] Build status: STARTED
[19:12:40.132] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:84:0) after 1m16s
[19:13:56.196] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:84:0)
[19:13:56.236] Build status: STARTED
[19:13:56.236] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:85:0) after 5m11s
[19:19:07.345] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:85:0)
[19:19:07.375] Build status: STARTED
[19:19:07.375] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:86:0) after 4m49s
[19:23:56.357] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:86:0)
[19:23:56.447] Build status: STARTED
[19:23:56.447] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:9013414227734906480:87:0) after 2m37s
[19:25:09.695] Received PubSub notification, asking Buildbucket for the build status
[19:25:09.722] Build:
{
"id": "8847984165785305712",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 427,
"createdBy": "project:r8",
"createTime": "2021-05-06T11:49:31.329022Z",
"startTime": "2021-05-06T14:23:23.493841Z",
"endTime": "2021-05-06T19:25:08.873954Z",
"updateTime": "2021-05-06T19:25:09.276764Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "16d6328b88570c6b3b7c0a433e2a6ff93a26ec76",
"ref": "refs/heads/2.2"
}
}
}
[19:25:09.722] Invocation finished in 7h35m39.635970732s with status SUCCEEDED