Closed Bug 1619107 Opened 5 years ago Closed 5 years ago

Perma [tier2] arsenic.errors.UnknownArsenicError: ('Tried to run command without establishing a connection', None, '')

Categories

(Testing :: Condprofile, defect, P5)

Product:
Testing
Component:
Condprofile
Version:
Version 3
Type:
defect

Tracking

(firefox75 fixed)

Status:
RESOLVED FIXED
Milestone:
mozilla75
Tracking Flags:
Tracking Status
firefox75 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: tarek)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Bug 1619107 - reduce work on Fennec
47 bytes, text/x-phabricator-request
Details | Review

Filed by: dvarga [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=291136116&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ZS9GlfosRfuWwelXCoCZkw/runs/0/artifacts/public/logs/live_backing.log

[task 2020-03-01T00:05:11.975Z] Traceback (most recent call last):
[task 2020-03-01T00:05:11.975Z]   File "condprof/runner.py", line 180, in run_all
[task 2020-03-01T00:05:11.975Z]     res.append(await one_run(scenario, customization))
[task 2020-03-01T00:05:11.975Z]   File "condprof/runner.py", line 151, in one_run
[task 2020-03-01T00:05:11.975Z]     ).run(not args.visible)
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/creator.py", line 77, in run
[task 2020-03-01T00:05:11.975Z]     metadata = await self.build_profile(device, headless)
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/creator.py", line 156, in build_profile
[task 2020-03-01T00:05:11.975Z]     metadata.update(await scenario_func(session, options))
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/scenarii/full.py", line 104, in full
[task 2020-03-01T00:05:11.975Z]     return await builder(session)
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/scenarii/full.py", line 85, in __call__
[task 2020-03-01T00:05:11.975Z]     await asyncio.wait_for(session.get(url), 5)
[task 2020-03-01T00:05:11.975Z]   File "/usr/lib/python3.6/asyncio/tasks.py", line 358, in wait_for
[task 2020-03-01T00:05:11.975Z]     return fut.result()
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/session.py", line 139, in get
[task 2020-03-01T00:05:11.975Z]     await self._request(url="/url", method="POST", data={"url": self.bind + url})
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/session.py", line 38, in _request
[task 2020-03-01T00:05:11.975Z]     self._check_response_error(status, data)
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/session.py", line 45, in _check_response_error
[task 2020-03-01T00:05:11.975Z]     check_response_error(status, data)
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/connection.py", line 39, in check_response_error
[task 2020-03-01T00:05:11.975Z]     errors.raise_exception(data, status)
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/errors.py", line 105, in raise_exception
[task 2020-03-01T00:05:11.975Z]     raise exception_class(message, screen, stacktrace)
[task 2020-03-01T00:05:11.975Z] arsenic.errors.UnknownArsenicError: ('Tried to run command without establishing a connection', None, '')
[task 2020-03-01T00:05:11.975Z] Traceback (most recent call last):
[task 2020-03-01T00:05:11.975Z]   File "condprof/runner.py", line 199, in <module>
[task 2020-03-01T00:05:11.975Z]     main()
[task 2020-03-01T00:05:11.975Z]   File "condprof/runner.py", line 189, in main
[task 2020-03-01T00:05:11.975Z]     failures, results = loop.run_until_complete(run_all(args))
[task 2020-03-01T00:05:11.975Z]   File "/usr/lib/python3.6/asyncio/base_events.py", line 484, in run_until_complete
[task 2020-03-01T00:05:11.975Z]     return future.result()
[task 2020-03-01T00:05:11.975Z]   File "condprof/runner.py", line 180, in run_all
[task 2020-03-01T00:05:11.975Z]     res.append(await one_run(scenario, customization))
[task 2020-03-01T00:05:11.975Z]   File "condprof/runner.py", line 151, in one_run
[task 2020-03-01T00:05:11.975Z]     ).run(not args.visible)
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/creator.py", line 77, in run
[task 2020-03-01T00:05:11.975Z]     metadata = await self.build_profile(device, headless)
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/creator.py", line 156, in build_profile
[task 2020-03-01T00:05:11.975Z]     metadata.update(await scenario_func(session, options))
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/scenarii/full.py", line 104, in full
[task 2020-03-01T00:05:11.975Z]     return await builder(session)
[task 2020-03-01T00:05:11.975Z]   File "condprof/../condprof/scenarii/full.py", line 85, in __call__
[task 2020-03-01T00:05:11.975Z]     await asyncio.wait_for(session.get(url), 5)
[task 2020-03-01T00:05:11.975Z]   File "/usr/lib/python3.6/asyncio/tasks.py", line 358, in wait_for
[task 2020-03-01T00:05:11.975Z]     return fut.result()
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/session.py", line 139, in get
[task 2020-03-01T00:05:11.975Z]     await self._request(url="/url", method="POST", data={"url": self.bind + url})
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/session.py", line 38, in _request
[task 2020-03-01T00:05:11.975Z]     self._check_response_error(status, data)
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/session.py", line 45, in _check_response_error
[task 2020-03-01T00:05:11.975Z]     check_response_error(status, data)
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/connection.py", line 39, in check_response_error
[task 2020-03-01T00:05:11.975Z]     errors.raise_exception(data, status)
[task 2020-03-01T00:05:11.975Z]   File "/builds/task_1583020619/fetches/condprofile/lib/python3.6/site-packages/arsenic/errors.py", line 105, in raise_exception
[task 2020-03-01T00:05:11.975Z]     raise exception_class(message, screen, stacktrace)
[task 2020-03-01T00:05:11.975Z] arsenic.errors.UnknownArsenicError: ('Tried to run command without establishing a connection', None, '')
[task 2020-03-01T00:05:11.975Z] script.py: command finished
[task 2020-03-01T00:05:11.975Z] script.py: enabling charging for device 'Pixel 2' ('HT7A51A04660')...
[task 2020-03-01T00:05:11.975Z] 
[task 2020-03-01T00:05:11.975Z] netstat -aop
[task 2020-03-01T00:05:11.975Z] Active Internet connections (servers and established)
[task 2020-03-01T00:05:11.975Z] Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name     Timer
[task 2020-03-01T00:05:11.975Z] tcp        0      0 127.0.0.11:36057        0.0.0.0:*               LISTEN      -                    off (0.00/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:8099          0.0.0.0:*               LISTEN      61/taskcluster-prox  off (0.00/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        1      0 bitbar-ubuntu-157:38728 lga15s43-in-f10.1:https CLOSE_WAIT  20/python            off (0.00/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:2828          localhost:54810         TIME_WAIT   -                    timewait (47.93/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:50061         TIME_WAIT   -                    timewait (53.57/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:50091         TIME_WAIT   -                    timewait (48.38/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:48975         TIME_WAIT   -                    timewait (59.90/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:37517         TIME_WAIT   -                    timewait (59.71/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:56809         TIME_WAIT   -                    timewait (59.80/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:50467         TIME_WAIT   -                    timewait (53.67/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 bitbar-ubuntu-157:38144 137.80.201.35.bc.:https ESTABLISHED 28/generic-worker    off (0.00/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:36053         localhost:38382         TIME_WAIT   -                    timewait (47.94/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:59221         TIME_WAIT   -                    timewait (53.98/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:60375         TIME_WAIT   -                    timewait (53.98/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:47845         TIME_WAIT   -                    timewait (53.46/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:39133         TIME_WAIT   -                    timewait (59.53/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:50489         TIME_WAIT   -                    timewait (53.46/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:33233         TIME_WAIT   -                    timewait (59.71/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:44867         TIME_WAIT   -                    timewait (53.67/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:37431         TIME_WAIT   -                    timewait (59.71/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:38381         TIME_WAIT   -                    timewait (53.98/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:36123         TIME_WAIT   -                    timewait (47.94/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:55675         TIME_WAIT   -                    timewait (53.96/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:55505         TIME_WAIT   -                    timewait (47.94/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:42712         localhost:60022         ESTABLISHED 28/generic-worker    keepalive (23.75/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:49265         TIME_WAIT   -                    timewait (53.67/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:59471         TIME_WAIT   -                    timewait (53.30/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:42983         TIME_WAIT   -                    timewait (59.37/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:37119         TIME_WAIT   -                    timewait (47.79/0/0)
[task 2020-03-01T00:05:11.975Z] tcp        0      0 localhost:5037          localhost:38947         TIME_WAIT   -                    timewait (59.37/0/0)
[task 2020-03-01T00:05:11.975Z] tcp6       0      0 [::]:60022              [::]:*                  LISTEN      52/livelog           off (0.00/0/0)
[task 2020-03-01T00:05:11.975Z] tcp6       0      0 [::]:60099              [::]:*                  LISTEN      52/livelog           off (0.00/0/0)
[task 2020-03-01T00:05:11.975Z] tcp6       0      0 localhost:60022         localhost:42712         ESTABLISHED 52/livelog           keepalive (79.38/0/0)
[task 2020-03-01T00:05:11.975Z] udp        0      0 127.0.0.11:37965        0.0.0.0:*                           -                    off (0.00/0/0)
[task 2020-03-01T00:05:11.975Z] Active UNIX domain sockets (servers and established)
Summary: Intermittent arsenic.errors.UnknownArsenicError: ('Tried to run command without establishing a connection', None, '') → Perma [tier2] arsenic.errors.UnknownArsenicError: ('Tried to run command without establishing a connection', None, '')

This will reduce the work done on Fennec in the full scenario

https://hg.mozilla.org/mozilla-central/rev/a672939cefe2

Status: NEW → RESOLVED
Closed: 5 years ago
status-firefox75: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla75
See Also: → 1623220
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: