[22:53:02.379] New invocation is queued and will start shortly
[22:53:03.484] Starting the invocation (attempt 1)
[22:53:03.530] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c3548f9e7c427265a2b1dcacb36adc3a4336952"
[22:53:03.530] Popped gitiles commit info from properties and tags
[22:53:03.530] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:53:03.531] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:53:03.531] Buildbucket request:
{
"requestId": "8965627880237263120",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5c3548f9e7c427265a2b1dcacb36adc3a4336952",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8965627880237263120"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:53:04.136] Scheduled build:
{
"id": "8800197818271013345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 28093,
"createdBy": "project:chromium",
"createTime": "2022-10-15T22:53:03.628451866Z",
"updateTime": "2022-10-15T22:53:03.628451866Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5c3548f9e7c427265a2b1dcacb36adc3a4336952",
"ref": "refs/heads/main"
}
}
}
[22:53:04.136] Task URL: https://cr-buildbucket.appspot.com/build/8800197818271013345
[22:53:04.136] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:2:0) after 2m38s
[22:53:25.410] Received PubSub notification, asking Buildbucket for the build status
[22:53:25.441] Build status: STARTED
[22:55:42.122] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:2:0)
[22:55:42.144] Build status: STARTED
[22:55:42.144] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:4:0) after 5m30s
[23:01:12.165] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:4:0)
[23:01:12.203] Build status: STARTED
[23:01:12.203] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:5:0) after 3m33s
[23:04:45.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:5:0)
[23:04:45.275] Build status: STARTED
[23:04:45.275] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:6:0) after 7m18s
[23:12:03.300] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:6:0)
[23:12:03.331] Build status: STARTED
[23:12:03.331] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:7:0) after 7m34s
[23:19:37.460] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:7:0)
[23:19:37.487] Build status: STARTED
[23:19:37.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:8:0) after 9m26s
[23:29:03.519] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:8:0)
[23:29:03.542] Build status: STARTED
[23:29:03.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:9:0) after 3m1s
[23:32:04.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:9:0)
[23:32:04.636] Build status: STARTED
[23:32:04.636] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:10:0) after 2m42s
[23:34:46.809] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:10:0)
[23:34:46.836] Build status: STARTED
[23:34:46.836] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:11:0) after 1m22s
[23:36:08.861] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:11:0)
[23:36:08.885] Build status: STARTED
[23:36:08.885] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:12:0) after 9m43s
[23:45:51.992] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:12:0)
[23:45:52.028] Build status: STARTED
[23:45:52.028] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:13:0) after 2m50s
[23:48:42.049] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:13:0)
[23:48:42.072] Build status: STARTED
[23:48:42.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:14:0) after 4m26s
[23:53:08.324] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:14:0)
[23:53:08.348] Build status: STARTED
[23:53:08.348] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:15:0) after 8m44s
[00:01:52.387] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:15:0)
[00:01:52.423] Build status: STARTED
[00:01:52.423] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:16:0) after 4m3s
[00:05:55.549] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:16:0)
[00:05:55.576] Build status: STARTED
[00:05:55.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:17:0) after 7m16s
[00:13:11.615] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:17:0)
[00:13:11.656] Build status: STARTED
[00:13:11.656] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:18:0) after 1m33s
[00:14:44.680] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:18:0)
[00:14:44.708] Build status: STARTED
[00:14:44.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:19:0) after 6m0s
[00:20:44.731] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:19:0)
[00:20:44.754] Build status: STARTED
[00:20:44.754] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:20:0) after 2m39s
[00:23:23.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:20:0)
[00:23:23.832] Build status: STARTED
[00:23:23.832] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8965627880237263120:21:0) after 7m54s
[00:30:39.572] Received PubSub notification, asking Buildbucket for the build status
[00:30:39.597] Build:
{
"id": "8800197818271013345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 28093,
"createdBy": "project:chromium",
"createTime": "2022-10-15T22:53:03.628451866Z",
"startTime": "2022-10-15T22:53:24.740272Z",
"endTime": "2022-10-16T00:30:39.385271850Z",
"updateTime": "2022-10-16T00:30:39.385271850Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5c3548f9e7c427265a2b1dcacb36adc3a4336952",
"ref": "refs/heads/main"
}
}
}
[00:30:39.597] Invocation finished in 1h37m37.228277012s with status SUCCEEDED