[20:26:28.054] New invocation is queued and will start shortly
[20:26:29.131] Starting the invocation (attempt 1)
[20:26:29.165] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb0abffcba6293b6aead4ee8f12f4896b4526457"
[20:26:29.165] Popped gitiles commit info from properties and tags
[20:26:29.165] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[20:26:29.167] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[20:26:29.167] Buildbucket request:
{
"requestId": "8929760703058171440",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-jammy-dev"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cb0abffcba6293b6aead4ee8f12f4896b4526457",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8929760703058171440"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-rel-jammy-dev"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[20:26:30.367] Scheduled build:
{
"id": "8764330641163453569",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-jammy-dev"
},
"number": 4818,
"createdBy": "project:chromium",
"createTime": "2023-11-15T20:26:29.238048458Z",
"updateTime": "2023-11-15T20:26:29.238048458Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cb0abffcba6293b6aead4ee8f12f4896b4526457",
"ref": "refs/heads/main"
}
}
}
[20:26:30.367] Task URL: https://cr-buildbucket-dev.appspot.com/build/8764330641163453569
[20:26:30.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:2:0) after 1m20s
[20:26:31.059] Received PubSub notification, asking Buildbucket for the build status
[20:26:31.085] Build status: SCHEDULED
[20:27:50.385] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:2:0)
[20:27:50.498] Build status: SCHEDULED
[20:27:50.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:4:0) after 7m6s
[20:31:42.898] Received PubSub notification, asking Buildbucket for the build status
[20:31:42.932] Build status: STARTED
[20:34:56.514] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:4:0)
[20:34:56.547] Build status: STARTED
[20:34:56.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:6:0) after 7m58s
[20:42:54.569] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:6:0)
[20:42:54.617] Build status: STARTED
[20:42:54.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:7:0) after 6m50s
[20:49:44.641] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:7:0)
[20:49:44.679] Build status: STARTED
[20:49:44.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:8:0) after 7m20s
[20:57:04.707] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:8:0)
[20:57:04.749] Build status: STARTED
[20:57:04.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:9:0) after 4m0s
[21:01:04.780] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:9:0)
[21:01:04.824] Build status: STARTED
[21:01:04.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:10:0) after 3m28s
[21:04:32.845] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:10:0)
[21:04:32.881] Build status: STARTED
[21:04:32.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:11:0) after 5m8s
[21:09:40.902] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:11:0)
[21:09:40.947] Build status: STARTED
[21:09:40.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:12:0) after 9m14s
[21:18:54.968] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:12:0)
[21:18:54.998] Build status: STARTED
[21:18:54.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:13:0) after 4m17s
[21:23:12.023] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:13:0)
[21:23:12.055] Build status: STARTED
[21:23:12.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:14:0) after 9m38s
[21:32:50.075] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:14:0)
[21:32:50.101] Build status: STARTED
[21:32:50.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:15:0) after 5m51s
[21:38:41.119] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:15:0)
[21:38:41.157] Build status: STARTED
[21:38:41.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:16:0) after 8m3s
[21:46:44.186] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:16:0)
[21:46:44.209] Build status: STARTED
[21:46:44.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:17:0) after 2m8s
[21:48:52.233] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:17:0)
[21:48:52.281] Build status: STARTED
[21:48:52.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:18:0) after 8m33s
[21:57:25.302] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:18:0)
[21:57:25.330] Build status: STARTED
[21:57:25.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:19:0) after 3m16s
[22:00:41.351] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:19:0)
[22:00:41.398] Build status: STARTED
[22:00:41.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-jammy-dev:8929760703058171440:20:0) after 2m48s
[22:02:46.979] Received PubSub notification, asking Buildbucket for the build status
[22:02:47.038] Build:
{
"id": "8764330641163453569",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-jammy-dev"
},
"number": 4818,
"createdBy": "project:chromium",
"createTime": "2023-11-15T20:26:29.238048458Z",
"startTime": "2023-11-15T20:30:38.540037Z",
"endTime": "2023-11-15T22:02:43.928967Z",
"updateTime": "2023-11-15T22:02:43.928967Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cb0abffcba6293b6aead4ee8f12f4896b4526457",
"ref": "refs/heads/main"
}
}
}
[22:02:47.038] Invocation finished in 1h36m18.990902626s with status SUCCEEDED