[11:32:27.054] New invocation is queued and will start shortly
[11:32:28.130] Starting the invocation (attempt 1)
[11:32:28.169] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5938ba5cd07bbf91a8a6e82e907defb22f6864ca"
[11:32:28.169] Popped gitiles commit info from properties and tags
[11:32:28.169] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:32:28.169] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:32:28.169] Buildbucket request:
{
"requestId": "9015317836770326016",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacCoverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5938ba5cd07bbf91a8a6e82e907defb22f6864ca",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9015317836770326016"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMacCoverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:32:28.773] Scheduled build:
{
"id": "8849887774795245728",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacCoverage"
},
"number": 43271,
"createdBy": "project:chromium",
"createTime": "2021-04-15T11:32:28.308086Z",
"updateTime": "2021-04-15T11:32:28.710540Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5938ba5cd07bbf91a8a6e82e907defb22f6864ca",
"ref": "refs/heads/master"
}
}
}
[11:32:28.773] Task URL: https://cr-buildbucket.appspot.com/build/8849887774795245728
[11:32:28.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:2:0) after 1m40s
[11:34:08.788] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:2:0)
[11:34:08.813] Build status: SCHEDULED
[11:34:08.813] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:3:0) after 5m27s
[11:34:28.976] Received PubSub notification, asking Buildbucket for the build status
[11:34:29.045] Build status: STARTED
[11:39:36.063] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:3:0)
[11:39:36.090] Build status: STARTED
[11:39:36.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:5:0) after 4m38s
[11:44:14.109] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:5:0)
[11:44:14.174] Build status: STARTED
[11:44:14.174] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:6:0) after 1m9s
[11:45:23.224] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:6:0)
[11:45:23.257] Build status: STARTED
[11:45:23.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:7:0) after 2m25s
[11:47:48.344] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:7:0)
[11:47:48.373] Build status: STARTED
[11:47:48.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:8:0) after 1m25s
[11:49:13.392] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:8:0)
[11:49:13.422] Build status: STARTED
[11:49:13.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:9:0) after 9m48s
[11:59:01.499] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:9:0)
[11:59:01.526] Build status: STARTED
[11:59:01.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:10:0) after 4m56s
[12:03:57.551] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:10:0)
[12:03:57.582] Build status: STARTED
[12:03:57.582] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:11:0) after 8m51s
[12:12:48.612] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:11:0)
[12:12:48.644] Build status: STARTED
[12:12:48.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:12:0) after 7m31s
[12:20:19.731] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:12:0)
[12:20:19.774] Build status: STARTED
[12:20:19.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:13:0) after 4m57s
[12:25:16.882] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:13:0)
[12:25:16.925] Build status: STARTED
[12:25:16.925] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:14:0) after 1m13s
[12:26:29.944] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:14:0)
[12:26:29.968] Build status: STARTED
[12:26:29.968] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:15:0) after 7m51s
[12:34:21.024] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:15:0)
[12:34:21.057] Build status: STARTED
[12:34:21.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:16:0) after 2m14s
[12:36:35.031] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:16:0)
[12:36:35.057] Build status: STARTED
[12:36:35.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:17:0) after 1m57s
[12:38:32.075] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:17:0)
[12:38:32.100] Build status: STARTED
[12:38:32.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:18:0) after 6m30s
[12:45:02.085] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:18:0)
[12:45:02.120] Build status: STARTED
[12:45:02.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:19:0) after 1m3s
[12:46:05.179] Handling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:19:0)
[12:46:05.204] Build status: STARTED
[12:46:05.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacCoverage:9015317836770326016:20:0) after 8m30s
[12:46:36.627] Received PubSub notification, asking Buildbucket for the build status
[12:46:36.656] Build:
{
"id": "8849887774795245728",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacCoverage"
},
"number": 43271,
"createdBy": "project:chromium",
"createTime": "2021-04-15T11:32:28.308086Z",
"startTime": "2021-04-15T11:34:28.141132Z",
"endTime": "2021-04-15T12:46:36.106667Z",
"updateTime": "2021-04-15T12:46:36.430610Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5938ba5cd07bbf91a8a6e82e907defb22f6864ca",
"ref": "refs/heads/master"
}
}
}
[12:46:36.656] Invocation finished in 1h14m9.615448365s with status SUCCEEDED