[20:01:12.169] New invocation is queued and will start shortly
[20:01:13.293] Starting the invocation (attempt 1)
[20:01:13.335] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2ee31a716048103ed95070b668bd808ae7dc897c"
[20:01:13.336] Popped gitiles commit info from properties and tags
[20:01:13.336] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:01:13.336] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:01:13.336] Buildbucket request:
{
"requestId": "8987744351083743136",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxCoverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ee31a716048103ed95070b668bd808ae7dc897c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8987744351083743136"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTLinuxCoverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:01:13.856] Scheduled build:
{
"id": "8822314289123019217",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxCoverage"
},
"number": 48369,
"createdBy": "project:chromium",
"createTime": "2022-02-13T20:01:13.407126422Z",
"updateTime": "2022-02-13T20:01:13.407126422Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ee31a716048103ed95070b668bd808ae7dc897c",
"ref": "refs/heads/main"
}
}
}
[20:01:13.856] Task URL: https://cr-buildbucket.appspot.com/build/8822314289123019217
[20:01:13.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:2:0) after 6m12s
[20:01:22.488] Received PubSub notification, asking Buildbucket for the build status
[20:01:22.520] Build status: STARTED
[20:07:25.870] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:2:0)
[20:07:25.887] Build status: STARTED
[20:07:25.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:4:0) after 7m29s
[20:14:54.902] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:4:0)
[20:14:54.931] Build status: STARTED
[20:14:54.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:5:0) after 1m30s
[20:16:24.945] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:5:0)
[20:16:24.969] Build status: STARTED
[20:16:24.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:6:0) after 1m59s
[20:18:23.984] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:6:0)
[20:18:24.007] Build status: STARTED
[20:18:24.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:7:0) after 7m31s
[20:25:55.395] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:7:0)
[20:25:55.415] Build status: STARTED
[20:25:55.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:8:0) after 1m3s
[20:26:58.467] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:8:0)
[20:26:58.487] Build status: STARTED
[20:26:58.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:9:0) after 4m11s
[20:31:10.083] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:9:0)
[20:31:10.114] Build status: STARTED
[20:31:10.114] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:10:0) after 1m59s
[20:33:09.136] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:10:0)
[20:33:09.162] Build status: STARTED
[20:33:09.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:11:0) after 6m4s
[20:39:13.179] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:11:0)
[20:39:13.198] Build status: STARTED
[20:39:13.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxCoverage:8987744351083743136:12:0) after 6m13s
[20:42:30.975] Received PubSub notification, asking Buildbucket for the build status
[20:42:30.995] Build:
{
"id": "8822314289123019217",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxCoverage"
},
"number": 48369,
"createdBy": "project:chromium",
"createTime": "2022-02-13T20:01:13.407126422Z",
"startTime": "2022-02-13T20:01:21.955318Z",
"endTime": "2022-02-13T20:42:30.729957958Z",
"updateTime": "2022-02-13T20:42:30.729957958Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2ee31a716048103ed95070b668bd808ae7dc897c",
"ref": "refs/heads/main"
}
}
}
[20:42:30.995] Invocation finished in 41m18.841426514s with status SUCCEEDED