[09:14:17.036] New invocation is queued and will start shortly
[09:14:26.820] Starting the invocation (attempt 1)
[09:14:26.845] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf2a441b36df2fdd692d8c5819e54cda6d538ea4"
[09:14:26.845] Popped gitiles commit info from properties and tags
[09:14:26.845] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:14:26.847] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:14:26.847] Buildbucket request:
{
  "requestId": "8869193622701729184",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "properties": {
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "cf2a441b36df2fdd692d8c5819e54cda6d538ea4",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8869193622701729184"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/Libfuzzer Upload Linux MSan"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[09:14:27.304] Scheduled build:
{
  "id": "8703763551680329489",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "number": 218614,
  "createdBy": "project:chromium",
  "createTime": "2025-09-14T09:14:26.897681950Z",
  "updateTime": "2025-09-14T09:14:26.897681950Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "cf2a441b36df2fdd692d8c5819e54cda6d538ea4",
      "ref": "refs/heads/main"
    }
  }
}
[09:14:27.304] Task URL: https://cr-buildbucket.appspot.com/build/8703763551680329489
[09:14:27.304] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:2:0) after 5m58s
[09:14:28.252] Received PubSub notification, asking Buildbucket for the build status
[09:14:28.270] Build status: SCHEDULED
[09:15:14.459] Received PubSub notification, asking Buildbucket for the build status
[09:15:14.481] Build status: STARTED
[09:20:25.535] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:2:0)
[09:20:25.559] Build status: STARTED
[09:20:25.559] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:5:0) after 7m12s
[09:27:37.574] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:5:0)
[09:27:37.593] Build status: STARTED
[09:27:37.593] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:6:0) after 1m5s
[09:28:42.701] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:6:0)
[09:28:42.752] Build status: STARTED
[09:28:42.752] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:7:0) after 5m11s
[09:33:53.766] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:7:0)
[09:33:53.790] Build status: STARTED
[09:33:53.790] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:8:0) after 7m55s
[09:41:48.810] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:8:0)
[09:41:49.044] Build status: STARTED
[09:41:49.044] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:9:0) after 3m18s
[09:45:07.128] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:9:0)
[09:45:07.164] Build status: STARTED
[09:45:07.164] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:10:0) after 8m23s
[09:53:30.198] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:10:0)
[09:53:30.216] Build status: STARTED
[09:53:30.216] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:11:0) after 4m0s
[09:57:30.338] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:11:0)
[09:57:30.366] Build status: STARTED
[09:57:30.366] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:12:0) after 3m53s
[10:01:23.390] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:12:0)
[10:01:23.409] Build status: STARTED
[10:01:23.409] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:13:0) after 7m59s
[10:09:22.495] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:13:0)
[10:09:22.517] Build status: STARTED
[10:09:22.517] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:14:0) after 6m45s
[10:16:07.507] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:14:0)
[10:16:07.525] Build status: STARTED
[10:16:07.525] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:15:0) after 9m26s
[10:25:33.517] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:15:0)
[10:25:33.550] Build status: STARTED
[10:25:33.550] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:16:0) after 9m12s
[10:34:45.550] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:16:0)
[10:34:45.586] Build status: STARTED
[10:34:45.586] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:17:0) after 2m20s
[10:37:05.696] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:17:0)
[10:37:05.710] Build status: STARTED
[10:37:05.711] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:18:0) after 2m22s
[10:39:27.771] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:18:0)
[10:39:27.791] Build status: STARTED
[10:39:27.791] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:19:0) after 9m32s
[10:48:59.843] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:19:0)
[10:48:59.861] Build status: STARTED
[10:48:59.861] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:20:0) after 7m12s
[10:56:11.884] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:20:0)
[10:56:11.903] Build status: STARTED
[10:56:11.903] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:21:0) after 9m54s
[11:06:06.019] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:21:0)
[11:06:06.051] Build status: STARTED
[11:06:06.051] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:22:0) after 4m40s
[11:10:46.069] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:22:0)
[11:10:46.086] Build status: STARTED
[11:10:46.086] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:23:0) after 1m6s
[11:11:52.252] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:23:0)
[11:11:52.271] Build status: STARTED
[11:11:52.271] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:24:0) after 3m20s
[11:15:12.292] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:24:0)
[11:15:12.338] Build status: STARTED
[11:15:12.338] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:25:0) after 1m19s
[11:16:31.499] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:25:0)
[11:16:31.516] Build status: STARTED
[11:16:31.516] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:26:0) after 8m11s
[11:24:42.587] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:26:0)
[11:24:42.614] Build status: STARTED
[11:24:42.614] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869193622701729184:27:0) after 3m55s
[11:27:10.773] Received PubSub notification, asking Buildbucket for the build status
[11:27:10.795] Build:
{
  "id": "8703763551680329489",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "number": 218614,
  "createdBy": "project:chromium",
  "createTime": "2025-09-14T09:14:26.897681950Z",
  "startTime": "2025-09-14T09:15:14.220002639Z",
  "endTime": "2025-09-14T11:27:10.232422268Z",
  "updateTime": "2025-09-14T11:27:10.232422268Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "cf2a441b36df2fdd692d8c5819e54cda6d538ea4",
      "ref": "refs/heads/main"
    }
  }
}
[11:27:10.795] Invocation finished in 2h12m53.791801117s with status SUCCEEDED