[06:24:56.081] New invocation is queued and will start shortly
[06:24:57.218] Starting the invocation (attempt 1)
[06:24:57.248] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/226c9c66d5e5d056ac86a2ccc8022bc39695e223"
[06:24:57.248] Popped gitiles commit info from properties and tags
[06:24:57.248] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:24:57.248] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:24:57.249] Buildbucket request:
{
"requestId": "8938601553473984544",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-blink-web-tests-force-accessibility-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "226c9c66d5e5d056ac86a2ccc8022bc39695e223",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8938601553473984544"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-blink-web-tests-force-accessibility-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:24:58.278] Scheduled build:
{
"id": "8773171491515713889",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-blink-web-tests-force-accessibility-rel"
},
"number": 16416,
"createdBy": "project:chromium",
"createTime": "2023-08-10T06:24:57.321436588Z",
"updateTime": "2023-08-10T06:24:57.321436588Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "226c9c66d5e5d056ac86a2ccc8022bc39695e223",
"ref": "refs/heads/main"
}
}
}
[06:24:58.278] Task URL: https://cr-buildbucket.appspot.com/build/8773171491515713889
[06:24:58.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:2:0) after 1m2s
[06:24:59.028] Received PubSub notification, asking Buildbucket for the build status
[06:24:59.055] Build status: SCHEDULED
[06:25:47.150] Received PubSub notification, asking Buildbucket for the build status
[06:25:47.181] Build status: STARTED
[06:26:00.401] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:2:0)
[06:26:00.428] Build status: STARTED
[06:26:00.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:5:0) after 3m48s
[06:29:48.466] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:5:0)
[06:29:48.485] Build status: STARTED
[06:29:48.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:6:0) after 1m38s
[06:31:26.530] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:6:0)
[06:31:26.573] Build status: STARTED
[06:31:26.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:7:0) after 3m57s
[06:35:23.590] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:7:0)
[06:35:23.609] Build status: STARTED
[06:35:23.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:8:0) after 2m40s
[06:38:03.783] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:8:0)
[06:38:03.799] Build status: STARTED
[06:38:03.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:9:0) after 1m50s
[06:39:53.893] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:9:0)
[06:39:53.916] Build status: STARTED
[06:39:53.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:10:0) after 4m46s
[06:44:40.020] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:10:0)
[06:44:40.106] Build status: STARTED
[06:44:40.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:11:0) after 5m22s
[06:50:02.137] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:11:0)
[06:50:02.165] Build status: STARTED
[06:50:02.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:12:0) after 1m47s
[06:51:49.185] Handling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:12:0)
[06:51:49.207] Build status: STARTED
[06:51:49.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-blink-web-tests-force-accessibility-rel:8938601553473984544:13:0) after 5m58s
[06:53:41.014] Received PubSub notification, asking Buildbucket for the build status
[06:53:41.033] Build:
{
"id": "8773171491515713889",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-blink-web-tests-force-accessibility-rel"
},
"number": 16416,
"createdBy": "project:chromium",
"createTime": "2023-08-10T06:24:57.321436588Z",
"startTime": "2023-08-10T06:25:46.544353Z",
"endTime": "2023-08-10T06:53:40.525073120Z",
"updateTime": "2023-08-10T06:53:40.525073120Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "226c9c66d5e5d056ac86a2ccc8022bc39695e223",
"ref": "refs/heads/main"
}
}
}
[06:53:41.033] Invocation finished in 28m44.963686778s with status SUCCEEDED