[21:23:12.135] New invocation is queued and will start shortly
[21:23:13.349] Starting the invocation (attempt 1)
[21:23:13.383] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6646955a87579d758af54d93dbed103765260730"
[21:23:13.383] Popped gitiles commit info from properties and tags
[21:23:13.383] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:23:13.383] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:23:13.383] Buildbucket request:
{
"requestId": "8999969782587759456",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6646955a87579d758af54d93dbed103765260730",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8999969782587759456"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:23:13.949] Scheduled build:
{
"id": "8834539720548682401",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux"
},
"number": 31824,
"createdBy": "project:chromium",
"createTime": "2021-10-01T21:23:13.449307449Z",
"updateTime": "2021-10-01T21:23:13.738141090Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6646955a87579d758af54d93dbed103765260730",
"ref": "refs/heads/main"
}
}
}
[21:23:13.949] Task URL: https://cr-buildbucket.appspot.com/build/8834539720548682401
[21:23:13.949] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:2:0) after 4m36s
[21:27:50.030] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:2:0)
[21:27:50.120] Build status: SCHEDULED
[21:27:50.120] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:3:0) after 1m10s
[21:29:00.332] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:3:0)
[21:29:00.360] Build status: SCHEDULED
[21:29:00.360] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:4:0) after 2m21s
[21:30:15.259] Received PubSub notification, asking Buildbucket for the build status
[21:30:15.293] Build status: STARTED
[21:31:21.388] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:4:0)
[21:31:21.419] Build status: STARTED
[21:31:21.419] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:6:0) after 4m46s
[21:36:07.505] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:6:0)
[21:36:07.535] Build status: STARTED
[21:36:07.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:7:0) after 4m40s
[21:40:47.573] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:7:0)
[21:40:47.631] Build status: STARTED
[21:40:47.631] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:8:0) after 8m54s
[21:49:41.670] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:8:0)
[21:49:41.704] Build status: STARTED
[21:49:41.704] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:9:0) after 4m17s
[21:53:58.705] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:9:0)
[21:53:58.758] Build status: STARTED
[21:53:58.758] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:10:0) after 6m31s
[22:00:29.791] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:10:0)
[22:00:29.818] Build status: STARTED
[22:00:29.818] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:11:0) after 4m31s
[22:05:00.842] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:11:0)
[22:05:00.884] Build status: STARTED
[22:05:00.884] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:12:0) after 6m56s
[22:11:56.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:12:0)
[22:11:57.016] Build status: STARTED
[22:11:57.016] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:13:0) after 4m40s
[22:16:37.140] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:13:0)
[22:16:37.168] Build status: STARTED
[22:16:37.168] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:14:0) after 7m24s
[22:24:01.255] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:14:0)
[22:24:01.471] Build status: STARTED
[22:24:01.471] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:15:0) after 4m38s
[22:28:39.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:15:0)
[22:28:39.681] Build status: STARTED
[22:28:39.681] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:16:0) after 3m55s
[22:32:34.993] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:16:0)
[22:32:35.024] Build status: STARTED
[22:32:35.024] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:17:0) after 6m14s
[22:38:49.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:17:0)
[22:38:49.195] Build status: STARTED
[22:38:49.195] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:18:0) after 9m34s
[22:48:23.285] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:18:0)
[22:48:23.317] Build status: STARTED
[22:48:23.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:19:0) after 6m44s
[22:55:07.440] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:19:0)
[22:55:07.473] Build status: STARTED
[22:55:07.473] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:20:0) after 8m39s
[23:03:46.679] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:20:0)
[23:03:46.734] Build status: STARTED
[23:03:46.734] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:21:0) after 3m20s
[23:07:06.865] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:21:0)
[23:07:06.904] Build status: STARTED
[23:07:06.904] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:22:0) after 8m15s
[23:15:21.980] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:22:0)
[23:15:22.006] Build status: STARTED
[23:15:22.006] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:23:0) after 6m27s
[23:21:49.191] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:23:0)
[23:21:49.368] Build status: STARTED
[23:21:49.368] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:24:0) after 7m20s
[23:29:09.540] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:24:0)
[23:29:09.572] Build status: STARTED
[23:29:09.572] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:25:0) after 6m25s
[23:35:34.657] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:25:0)
[23:35:34.685] Build status: STARTED
[23:35:34.685] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:26:0) after 3m7s
[23:38:41.711] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:26:0)
[23:38:41.754] Build status: STARTED
[23:38:41.754] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:27:0) after 7m43s
[23:46:24.864] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:27:0)
[23:46:24.891] Build status: STARTED
[23:46:24.891] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:28:0) after 3m25s
[23:49:49.992] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:28:0)
[23:49:50.024] Build status: STARTED
[23:49:50.024] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:29:0) after 2m0s
[23:51:50.043] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:29:0)
[23:51:50.117] Build status: STARTED
[23:51:50.117] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:30:0) after 4m29s
[23:56:19.138] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:30:0)
[23:56:19.168] Build status: STARTED
[23:56:19.168] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:31:0) after 2m42s
[23:59:01.501] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:31:0)
[23:59:01.532] Build status: STARTED
[23:59:01.532] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:32:0) after 2m13s
[00:01:14.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:32:0)
[00:01:14.848] Build status: STARTED
[00:01:14.848] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:33:0) after 3m36s
[00:04:50.868] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:33:0)
[00:04:50.899] Build status: STARTED
[00:04:50.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:34:0) after 7m46s
[00:12:37.113] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:34:0)
[00:12:37.142] Build status: STARTED
[00:12:37.142] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:35:0) after 2m59s
[00:15:36.191] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:35:0)
[00:15:36.219] Build status: STARTED
[00:15:36.219] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:36:0) after 5m13s
[00:20:49.342] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:36:0)
[00:20:49.389] Build status: STARTED
[00:20:49.389] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:37:0) after 1m10s
[00:21:59.473] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:37:0)
[00:21:59.509] Build status: STARTED
[00:21:59.509] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:38:0) after 9m0s
[00:30:59.585] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:38:0)
[00:30:59.616] Build status: STARTED
[00:30:59.616] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:39:0) after 3m15s
[00:34:14.638] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:39:0)
[00:34:14.675] Build status: STARTED
[00:34:14.675] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:40:0) after 6m54s
[00:41:08.989] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:40:0)
[00:41:09.021] Build status: STARTED
[00:41:09.021] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:41:0) after 4m9s
[00:45:18.302] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:41:0)
[00:45:18.354] Build status: STARTED
[00:45:18.354] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:42:0) after 9m55s
[00:55:13.438] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:42:0)
[00:55:13.469] Build status: STARTED
[00:55:13.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:43:0) after 4m50s
[01:00:03.658] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:43:0)
[01:00:03.695] Build status: STARTED
[01:00:03.695] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:44:0) after 3m35s
[01:03:38.848] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:44:0)
[01:03:38.877] Build status: STARTED
[01:03:38.877] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8999969782587759456:45:0) after 7m20s
[01:07:57.807] Received PubSub notification, asking Buildbucket for the build status
[01:07:57.835] Build:
{
"id": "8834539720548682401",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux"
},
"number": 31824,
"createdBy": "project:chromium",
"createTime": "2021-10-01T21:23:13.449307449Z",
"startTime": "2021-10-01T21:30:10.150403Z",
"endTime": "2021-10-02T01:07:57.128103555Z",
"updateTime": "2021-10-02T01:07:57.136894778Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6646955a87579d758af54d93dbed103765260730",
"ref": "refs/heads/main"
}
}
}
[01:07:57.835] Invocation finished in 3h44m45.713873895s with status SUCCEEDED