Closed Bug 1775743 Opened 2 years ago Closed 2 years ago

Intermittent Decision Task ValueError: time data '2022-06-23T19:33:54Z' does not match format '%Y-%m-%dT%H:%M:%S.%fZ'

Categories

(Firefox Build System :: Task Configuration, defect, P5)

Product:
Firefox Build System
Component:
Task Configuration
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=382186091&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Gwoum04zT5eHinQ6kfwroQ/runs/0/artifacts/public/logs/live_backing.log

[task 2022-06-22T19:33:54.028Z] https://firefox-ci-tc.services.mozilla.com:443 "GET /api/index/v1/task/gecko.cache.level-3.content.v1.fxc2.hash.68be7dc919f849dc2d0d03870aaf9f4823b6086ef325f1f3f5b0a4c690800152 HTTP/1.1" 200 241
[task 2022-06-22T19:33:54.053Z] https://firefox-ci-tc.services.mozilla.com:443 "GET /api/queue/v1/task/IC7tDN8pTheJzAslTy7T8Q/status HTTP/1.1" 200 834
[task 2022-06-22T19:33:54.055Z] PERFHERDER_DATA: {"framework": {"name": "build_metrics"}, "suites": [{"name": "bugbug_push_schedules_time", "value": 0.5130113490013173, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}, {"name": "bugbug_push_schedules_retries", "value": 0, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}]}
[task 2022-06-22T19:33:54.055Z] PERFHERDER_DATA: {"framework": {"name": "build_metrics"}, "suites": [{"name": "bugbug_push_schedules_time", "value": 0.4639888130041072, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}, {"name": "bugbug_push_schedules_retries", "value": 0, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}]}
[task 2022-06-22T19:33:54.056Z] Traceback (most recent call last):
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/mach_commands.py", line 272, in taskgraph_decision
[task 2022-06-22T19:33:54.056Z]     ret = taskgraph_commands["decision"].func(options)
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/main.py", line 617, in decision
[task 2022-06-22T19:33:54.056Z]     taskgraph_decision(options)
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/decision.py", line 252, in taskgraph_decision
[task 2022-06-22T19:33:54.056Z]     write_artifact("task-graph.json", tgg.morphed_task_graph.to_json())
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/generator.py", line 219, in morphed_task_graph
[task 2022-06-22T19:33:54.056Z]     return self._run_until("morphed_task_graph")
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/generator.py", line 427, in _run_until
[task 2022-06-22T19:33:54.056Z]     k, v = next(self._run)
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/generator.py", line 397, in _run
[task 2022-06-22T19:33:54.056Z]     optimized_task_graph, label_to_taskid = optimize_task_graph(
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/optimize/__init__.py", line 77, in optimize_task_graph
[task 2022-06-22T19:33:54.056Z]     replaced_tasks = replace_tasks(
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/optimize/__init__.py", line 309, in replace_tasks
[task 2022-06-22T19:33:54.056Z]     repl = opt.should_replace_task(task, params, deadline, arg)
[task 2022-06-22T19:33:54.056Z]   File "/builds/worker/checkouts/gecko/taskcluster/gecko_taskgraph/optimize/strategies.py", line 49, in should_replace_task
[task 2022-06-22T19:33:54.056Z]     ) < datetime.strptime(deadline, self.fmt):
[task 2022-06-22T19:33:54.056Z]   File "/usr/lib/python3.8/_strptime.py", line 568, in _strptime_datetime
[task 2022-06-22T19:33:54.056Z]     tt, fraction, gmtoff_fraction = _strptime(data_string, format)
[task 2022-06-22T19:33:54.056Z]   File "/usr/lib/python3.8/_strptime.py", line 349, in _strptime
[task 2022-06-22T19:33:54.056Z]     raise ValueError("time data %r does not match format %r" %
[task 2022-06-22T19:33:54.056Z] ValueError: time data '2022-06-23T19:33:54Z' does not match format '%Y-%m-%dT%H:%M:%S.%fZ'
[task 2022-06-22T19:33:54.056Z] Creating default state directory: /builds/worker/.mozbuild
[taskcluster 2022-06-22 19:33:55.282Z] === Task Finished ===
[taskcluster 2022-06-22 19:34:04.479Z] Unsuccessful task run with exit code: 1 completed in 118.317 seconds

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.