Closed Bug 1703338 Opened 5 years ago Closed 3 years ago

Intermittent mozproxy b' File "mitmproxy/proxy/server.py", line 121, in handle'

Categories

(Testing :: Performance, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335552321&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/boHq7bcPTS6wH4rbRKIwtw/runs/0/artifacts/public/logs/live_backing.log


[task 2021-04-06T12:55:12.881Z] 12:55:12     INFO - closing window: 85
[task 2021-04-06T12:55:12.916Z] 12:55:12     INFO - closing window: 87
[task 2021-04-06T12:55:12.960Z] 12:55:12     INFO - navigating to about:blank
[task 2021-04-06T12:55:12.978Z] 12:55:12     INFO - navigated to about:blank
[task 2021-04-06T12:55:12.985Z] 12:55:12     INFO - starting checkpoint TabsClosedExtraProcesses...
[task 2021-04-06T12:55:13.132Z] 12:55:13     INFO - checkpoint created, stored in /opt/worker/tasks/task_161771310287479/build/tests/results/memory-report-TabsClosedExtraProcesses-0.json.gz
[task 2021-04-06T12:55:13.132Z] 12:55:13     INFO - closing preloaded browser
[task 2021-04-06T12:55:13.139Z] 12:55:13     INFO - starting checkpoint TabsClosed...
[task 2021-04-06T12:55:13.285Z] 12:55:13     INFO - checkpoint created, stored in /opt/worker/tasks/task_161771310287479/build/tests/results/memory-report-TabsClosed-0.json.gz
[task 2021-04-06T12:55:43.341Z] 12:55:43     INFO - starting checkpoint TabsClosedSettled...
[task 2021-04-06T12:55:43.456Z] 12:55:43     INFO - checkpoint created, stored in /opt/worker/tasks/task_161771310287479/build/tests/results/memory-report-TabsClosedSettled-0.json.gz
[task 2021-04-06T12:55:43.457Z] 12:55:43     INFO - starting checkpoint TabsClosedForceGC...
[task 2021-04-06T12:55:43.696Z] 12:55:43     INFO - checkpoint created, stored in /opt/worker/tasks/task_161771310287479/build/tests/results/memory-report-TabsClosedForceGC-0.json.gz
[task 2021-04-06T12:55:43.696Z] 12:55:43     INFO - setting results
[task 2021-04-06T12:55:43.697Z] 12:55:43     INFO - tearing down!
[task 2021-04-06T12:55:43.697Z] 12:55:43     INFO - tearing down webservers!
[task 2021-04-06T12:55:43.697Z] 12:55:43     INFO - mozproxy MitmproxyDesktop stop!!
[task 2021-04-06T12:55:43.698Z] 12:55:43     INFO - mozproxy Mitmproxy stop!!
[task 2021-04-06T12:55:43.698Z] 12:55:43     INFO - mozproxy Stopping mitmproxy playback, killing process 1027
[task 2021-04-06T12:55:43.824Z] 12:55:43    ERROR - mozproxy b'Traceback (most recent call last):'
[task 2021-04-06T12:55:43.825Z] 12:55:43    ERROR - mozproxy b'  File "mitmproxy/proxy/server.py", line 121, in handle'
[task 2021-04-06T12:55:43.825Z] 12:55:43    ERROR - mozproxy b"TypeError: 'NoneType' object is not callable"
[task 2021-04-06T12:55:43.826Z] 12:55:43    ERROR - mozproxy b''
[task 2021-04-06T12:55:43.826Z] 12:55:43    ERROR - mozproxy b'mitmproxy has crashed!'
[task 2021-04-06T12:55:43.826Z] 12:55:43    ERROR - mozproxy b'Please lodge a bug report at: https://github.com/mitmproxy/mitmproxy'
[task 2021-04-06T12:55:44.223Z] 12:55:44     INFO - mozproxy Successfully killed the mitmproxy playback process
[task 2021-04-06T12:55:44.224Z] 12:55:44     INFO - mozproxy Turning off the browser proxy
[task 2021-04-06T12:55:44.224Z] 12:55:44     INFO - mozproxy writing: /opt/worker/tasks/task_161771310287479/build/application/Firefox.app/Contents/Resources/distribution/policies.json
[task 2021-04-06T12:55:44.224Z] 12:55:44     INFO - processing data in /opt/worker/tasks/task_161771310287479/build/tests/results!
[task 2021-04-06T12:55:45.777Z] 12:55:45     INFO - PERFHERDER_DATA: {"framework": {"name": "awsy"}, "suites": [{"name": "Resident Memory", "subtests": [{"name": "Fresh start", "value": 255377408, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Fresh start [+30s]", "value": 306769920, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open", "value": 2134413312, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s]", "value": 1636888576, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s, forced GC]", "value": 1491189760, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed extra processes", "value": 419266560, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed", "value": 419733504, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s]", "value": 360181760, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s, forced GC]", "value": 334831616, "lowerIsBetter": true, "unit": "bytes"}], "lowerIsBetter": true, "unit": "bytes", "extraOptions": ["tp6"], "value": 589995431.0218388}, {"name": "Explicit Memory", "subtests": [{"name": "Fresh start", "value": 153930762, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Fresh start [+30s]", "value": 172727258, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open", "value": 1837378426, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s]", "value": 1815604090, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s, forced GC]", "value": 1656900474, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed extra processes", "value": 355544058, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed", "value": 356039674, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s]", "value": 269720570, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s, forced GC]", "value": 220593146, "lowerIsBetter": true, "unit": "bytes"}], "lowerIsBetter": true, "unit": "bytes", "extraOptions": ["tp6"], "value": 469425534.88467956}, {"name": "Heap Unclassified", "subtests": [{"name": "Fresh start", "value": 28008759, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Fresh start [+30s]", "value": 38074714, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open", "value": 251033199, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s]", "value": 243980407, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s, forced GC]", "value": 220880272, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed extra processes", "value": 80786827, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed", "value": 80725363, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s]", "value": 69651187, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s, forced GC]", "value": 59298776, "lowerIsBetter": true, "unit": "bytes"}], "lowerIsBetter": true, "unit": "bytes", "extraOptions": ["tp6"], "value": 90030302.0710261}, {"name": "JS", "subtests": [{"name": "Fresh start", "value": 64158320, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Fresh start [+30s]", "value": 63765928, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open", "value": 913859728, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s]", "value": 903264584, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s, forced GC]", "value": 763158712, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed extra processes", "value": 127296624, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed", "value": 127277072, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s]", "value": 75595736, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s, forced GC]", "value": 54812416, "lowerIsBetter": true, "unit": "bytes"}], "lowerIsBetter": true, "unit": "bytes", "extraOptions": ["tp6"], "value": 177287106.2636328}, {"name": "Images", "subtests": [{"name": "Fresh start", "value": 827232, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Fresh start [+30s]", "value": 1375136, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open", "value": 55571384, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s]", "value": 51331912, "lowerIsBetter": true, "unit": "bytes"}, {"name": "After tabs open [+30s, forced GC]", "value": 51331864, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed extra processes", "value": 14698312, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed", "value": 14698360, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s]", "value": 7054312, "lowerIsBetter": true, "unit": "bytes"}, {"name": "Tabs closed [+30s, forced GC]", "value": 2755720, "lowerIsBetter": true, "unit": "bytes"}], "lowerIsBetter": true, "unit": "bytes", "extraOptions": ["tp6"], "value": 9610772.715068886}]}
[task 2021-04-06T12:55:45.777Z] 12:55:45     INFO - Perfherder data written to /opt/worker/tasks/task_161771310287479/build/tests/results/perfherder_data.json
[task 2021-04-06T12:55:45.778Z] 12:55:45     INFO - done tearing down!
[task 2021-04-06T12:55:45.780Z] 12:55:45     INFO - TEST-PASS | awsy/test_memory_usage.py TestMemoryUsage.test_open_tabs | took 537471ms
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.