builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-076 starttime: 1501622233.17 results: success (0) revision: a3e675a3b10a0ea289c301bedc31866f3daf7875 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.169560) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.170000) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.170283) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.186023) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.186380) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-01 14:17:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 58.2K=0.2s 2017-08-01 14:17:13 (58.2 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.337023 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.536829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.537146) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015816 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.591674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:13.592036) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3e675a3b10a0ea289c301bedc31866f3daf7875 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev a3e675a3b10a0ea289c301bedc31866f3daf7875 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-01 14:17:13,943 truncating revision to first 12 chars 2017-08-01 14:17:13,943 Setting DEBUG logging. 2017-08-01 14:17:13,943 attempt 1/10 2017-08-01 14:17:13,943 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3e675a3b10a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-01 14:17:14,429 unpacking tar archive at: mozilla-central-a3e675a3b10a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.811490 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:14.415968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:14.416292) ========= script_repo_revision: a3e675a3b10a0ea289c301bedc31866f3daf7875 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:14.419683) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:14.419957) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-01 14:17:14.429103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-01 14:17:14.429375) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:17:14 INFO - MultiFileLogger online at 20170801 14:17:14 in /builds/slave/test 14:17:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:17:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:17:14 INFO - {'append_to_log': False, 14:17:14 INFO - 'base_work_dir': '/builds/slave/test', 14:17:14 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:17:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:17:14 INFO - 'branch': 'Firefox-Non-PGO', 14:17:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:17:14 INFO - 'code_coverage': False, 14:17:14 INFO - 'config_files': ('talos/linux_config.py',), 14:17:14 INFO - 'default_actions': ('clobber', 14:17:14 INFO - 'read-buildbot-config', 14:17:14 INFO - 'download-and-extract', 14:17:14 INFO - 'populate-webroot', 14:17:14 INFO - 'create-virtualenv', 14:17:14 INFO - 'install', 14:17:14 INFO - 'setup-mitmproxy', 14:17:14 INFO - 'run-tests'), 14:17:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:17:14 INFO - 'disable_ccov_upload': False, 14:17:14 INFO - 'download_minidump_stackwalk': True, 14:17:14 INFO - 'download_symbols': 'ondemand', 14:17:14 INFO - 'enable_stylo': False, 14:17:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:17:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:17:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:17:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:17:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:17:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:17:14 INFO - 'gecko_profile': False, 14:17:14 INFO - 'gecko_profile_interval': 0, 14:17:14 INFO - 'installer_path': 'installer.exe', 14:17:14 INFO - 'log_level': 'info', 14:17:14 INFO - 'log_name': 'talos', 14:17:14 INFO - 'log_to_console': True, 14:17:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:17:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:17:14 INFO - 'opt_config_files': (), 14:17:14 INFO - 'pip_index': False, 14:17:14 INFO - 'suite': 'dromaeojs-e10s', 14:17:14 INFO - 'system_bits': '32', 14:17:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:17:14 INFO - 'title': 'talos-linux64-ix-076', 14:17:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:17:14 INFO - 'use_talos_json': True, 14:17:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:17:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:17:14 INFO - 'work_dir': 'build'} 14:17:14 INFO - [mozharness: 2017-08-01 21:17:14.888549Z] Running clobber step. 14:17:14 INFO - Running pre-action listener: _resource_record_pre_action 14:17:14 INFO - Running main action method: clobber 14:17:14 INFO - rmtree: /builds/slave/test/build 14:17:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:17:16 INFO - Running post-action listener: _resource_record_post_action 14:17:16 INFO - [mozharness: 2017-08-01 21:17:16.049101Z] Finished clobber step (success) 14:17:16 INFO - [mozharness: 2017-08-01 21:17:16.049203Z] Running read-buildbot-config step. 14:17:16 INFO - Running pre-action listener: _resource_record_pre_action 14:17:16 INFO - Running main action method: read_buildbot_config 14:17:16 INFO - Using buildbot properties: 14:17:16 INFO - { 14:17:16 INFO - "project": "", 14:17:16 INFO - "product": "firefox", 14:17:16 INFO - "who": "kwierso@gmail.com", 14:17:16 INFO - "installer_path": "public/build/target.tar.bz2", 14:17:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:17:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:17:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:17:16 INFO - "stage_platform": "linux64", 14:17:16 INFO - "basedir": "/builds/slave/test", 14:17:16 INFO - "buildnumber": 748, 14:17:16 INFO - "platform": "ubuntu64_hw", 14:17:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:17:16 INFO - "slavebuilddir": "test", 14:17:16 INFO - "taskId": "OM-21pV9TJW6_6lejknt7Q", 14:17:16 INFO - "branch": "mozilla-central", 14:17:16 INFO - "script_repo_revision": "production", 14:17:16 INFO - "revision": "a3e675a3b10a0ea289c301bedc31866f3daf7875", 14:17:16 INFO - "slavename": "talos-linux64-ix-076", 14:17:16 INFO - "repo_path": "mozilla-central" 14:17:16 INFO - } 14:17:16 INFO - Finding installer, test and symbols from parent task. 14:17:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OM-21pV9TJW6_6lejknt7Q'}, attempt #1 14:17:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OM-21pV9TJW6_6lejknt7Q'}, attempt #1 14:17:16 INFO - Task dependencies: Q3D2GEEATdOj72Ov3q5KTg 14:17:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg'}, attempt #1 14:17:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2 14:17:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.test_packages.json 14:17:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.crashreporter-symbols.zip 14:17:17 INFO - Running post-action listener: _resource_record_post_action 14:17:17 INFO - [mozharness: 2017-08-01 21:17:17.454287Z] Finished read-buildbot-config step (success) 14:17:17 INFO - [mozharness: 2017-08-01 21:17:17.454509Z] Running download-and-extract step. 14:17:17 INFO - Running pre-action listener: _resource_record_pre_action 14:17:17 INFO - Running main action method: download_and_extract 14:17:17 INFO - mkdir: /builds/slave/test/build/tests 14:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:17 INFO - trying https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.test_packages.json 14:17:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:17:18 INFO - Downloaded 989 bytes. 14:17:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:17:18 INFO - Using the following test package requirements: 14:17:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:17:18 INFO - u'common': [u'target.common.tests.zip'], 14:17:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:17:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:17:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:17:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:17:18 INFO - u'mozbase': [u'target.common.tests.zip'], 14:17:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:17:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:17:18 INFO - u'web-platform': [u'target.common.tests.zip', 14:17:18 INFO - u'target.web-platform.tests.tar.gz'], 14:17:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:17:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:17:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.common.tests.zip 14:17:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:17:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.common.tests.zip into memory 14:17:19 INFO - Content-Length response header: 72390510 14:17:19 INFO - Bytes received: 72390510 14:17:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:17:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.common.tests.zip 14:17:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:17:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.common.tests.zip into memory 14:17:31 INFO - Content-Length response header: 72390510 14:17:31 INFO - Bytes received: 72390510 14:17:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.talos.tests.zip 14:17:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:17:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.talos.tests.zip into memory 14:17:45 INFO - Content-Length response header: 14496467 14:17:45 INFO - Bytes received: 14496467 14:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:46 INFO - trying https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2 14:17:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:17:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:17:47 INFO - Downloaded 62159884 bytes. 14:17:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2 14:17:47 INFO - mkdir: /builds/slave/test/properties 14:17:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:17:47 INFO - Writing to file /builds/slave/test/properties/build_url 14:17:47 INFO - Contents: 14:17:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2 14:17:47 INFO - Running post-action listener: _resource_record_post_action 14:17:47 INFO - Running post-action listener: set_extra_try_arguments 14:17:47 INFO - [mozharness: 2017-08-01 21:17:47.313257Z] Finished download-and-extract step (success) 14:17:47 INFO - [mozharness: 2017-08-01 21:17:47.313363Z] Running populate-webroot step. 14:17:47 INFO - Running pre-action listener: _resource_record_pre_action 14:17:47 INFO - Running main action method: populate_webroot 14:17:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:17:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:17:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:17:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:17:47 INFO - 'tests': ['damp', 'tps']}, 14:17:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:17:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:17:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:17:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:17:47 INFO - 'other-e10s': {'tests': ['a11yr', 14:17:47 INFO - 'ts_paint', 14:17:47 INFO - 'tpaint', 14:17:47 INFO - 'sessionrestore', 14:17:47 INFO - 'sessionrestore_many_windows', 14:17:47 INFO - 'sessionrestore_no_auto_restore', 14:17:47 INFO - 'tabpaint']}, 14:17:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 14:17:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 14:17:47 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:17:47 INFO - 'talos_options': ['--mitmproxy', 14:17:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:17:47 INFO - '--firstNonBlankPaint'], 14:17:47 INFO - 'tests': ['quantum_pageload_google', 14:17:47 INFO - 'quantum_pageload_youtube', 14:17:47 INFO - 'quantum_pageload_amazon', 14:17:47 INFO - 'quantum_pageload_facebook']}, 14:17:47 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:17:47 INFO - 'talos_options': ['--stylo', 14:17:47 INFO - '--mitmproxy', 14:17:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:17:47 INFO - '--firstNonBlankPaint'], 14:17:47 INFO - 'tests': ['quantum_pageload_google', 14:17:47 INFO - 'quantum_pageload_youtube', 14:17:47 INFO - 'quantum_pageload_amazon', 14:17:47 INFO - 'quantum_pageload_facebook']}, 14:17:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:17:47 INFO - 'tsvgr_opacity', 14:17:47 INFO - 'tart', 14:17:47 INFO - 'tscrollx', 14:17:47 INFO - 'cart', 14:17:47 INFO - 'tsvg_static']}, 14:17:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:17:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:17:47 INFO - 'talos_options': ['--stylo', 14:17:47 INFO - '--stylo-threads=1', 14:17:47 INFO - '--mitmproxy', 14:17:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:17:47 INFO - '--firstNonBlankPaint'], 14:17:47 INFO - 'tests': ['tp6_google', 14:17:47 INFO - 'tp6_youtube', 14:17:47 INFO - 'tp6_amazon', 14:17:47 INFO - 'tp6_facebook']}, 14:17:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:17:47 INFO - 'talos_options': ['--xperf_path', 14:17:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:47 INFO - 'tests': ['tp5n']}}} 14:17:47 INFO - Running post-action listener: _resource_record_post_action 14:17:47 INFO - [mozharness: 2017-08-01 21:17:47.322022Z] Finished populate-webroot step (success) 14:17:47 INFO - [mozharness: 2017-08-01 21:17:47.322113Z] Running create-virtualenv step. 14:17:47 INFO - Running pre-action listener: _resource_record_pre_action 14:17:47 INFO - Running main action method: create_virtualenv 14:17:47 INFO - Creating virtualenv /builds/slave/test/build/venv 14:17:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:17:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:17:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:17:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:17:47 INFO - Using real prefix '/usr' 14:17:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:17:47 INFO - Installing distribute.............................................................................................................................................................................................done. 14:17:49 INFO - Installing pip.................done. 14:17:49 INFO - Return code: 0 14:17:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:17:50 INFO - Reading from file tmpfile_stdout 14:17:50 INFO - Output received: 14:17:50 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:17:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:17:50 INFO - Using env: {'DISPLAY': ':0', 14:17:50 INFO - 'HOME': '/home/cltbld', 14:17:50 INFO - 'LANG': 'en_US.UTF-8', 14:17:50 INFO - 'LANGUAGE': 'en_US:en', 14:17:50 INFO - 'LOGNAME': 'cltbld', 14:17:50 INFO - 'MAIL': '/var/mail/cltbld', 14:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:50 INFO - 'MOZ_NO_REMOTE': '1', 14:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:50 INFO - 'NO_EM_RESTART': '1', 14:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:50 INFO - 'PWD': '/builds/slave/test', 14:17:50 INFO - 'SHELL': '/bin/bash', 14:17:50 INFO - 'SHLVL': '1', 14:17:50 INFO - 'TERM': 'linux', 14:17:50 INFO - 'TMOUT': '86400', 14:17:50 INFO - 'USER': 'cltbld', 14:17:50 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:50 INFO - '_': '/tools/buildbot/bin/python'} 14:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:17:50 INFO - Cleaning up... 14:17:50 INFO - Return code: 0 14:17:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:17:50 INFO - Using env: {'DISPLAY': ':0', 14:17:50 INFO - 'HOME': '/home/cltbld', 14:17:50 INFO - 'LANG': 'en_US.UTF-8', 14:17:50 INFO - 'LANGUAGE': 'en_US:en', 14:17:50 INFO - 'LOGNAME': 'cltbld', 14:17:50 INFO - 'MAIL': '/var/mail/cltbld', 14:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:50 INFO - 'MOZ_NO_REMOTE': '1', 14:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:50 INFO - 'NO_EM_RESTART': '1', 14:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:50 INFO - 'PWD': '/builds/slave/test', 14:17:50 INFO - 'SHELL': '/bin/bash', 14:17:50 INFO - 'SHLVL': '1', 14:17:50 INFO - 'TERM': 'linux', 14:17:50 INFO - 'TMOUT': '86400', 14:17:50 INFO - 'USER': 'cltbld', 14:17:50 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:50 INFO - '_': '/tools/buildbot/bin/python'} 14:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:50 INFO - Downloading/unpacking psutil>=3.1.1 14:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:17:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:51 INFO - Installing collected packages: psutil 14:17:51 INFO - Running setup.py install for psutil 14:17:51 INFO - building 'psutil._psutil_linux' extension 14:17:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:17:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:17:51 INFO - building 'psutil._psutil_posix' extension 14:17:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:17:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:17:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:52 INFO - Successfully installed psutil 14:17:52 INFO - Cleaning up... 14:17:52 INFO - Return code: 0 14:17:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:17:52 INFO - Using env: {'DISPLAY': ':0', 14:17:52 INFO - 'HOME': '/home/cltbld', 14:17:52 INFO - 'LANG': 'en_US.UTF-8', 14:17:52 INFO - 'LANGUAGE': 'en_US:en', 14:17:52 INFO - 'LOGNAME': 'cltbld', 14:17:52 INFO - 'MAIL': '/var/mail/cltbld', 14:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:52 INFO - 'MOZ_NO_REMOTE': '1', 14:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:52 INFO - 'NO_EM_RESTART': '1', 14:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:52 INFO - 'PWD': '/builds/slave/test', 14:17:52 INFO - 'SHELL': '/bin/bash', 14:17:52 INFO - 'SHLVL': '1', 14:17:52 INFO - 'TERM': 'linux', 14:17:52 INFO - 'TMOUT': '86400', 14:17:52 INFO - 'USER': 'cltbld', 14:17:52 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:52 INFO - '_': '/tools/buildbot/bin/python'} 14:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:17:53 INFO - Installing collected packages: mozsystemmonitor 14:17:53 INFO - Running setup.py install for mozsystemmonitor 14:17:53 INFO - Successfully installed mozsystemmonitor 14:17:53 INFO - Cleaning up... 14:17:53 INFO - Return code: 0 14:17:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:17:53 INFO - Using env: {'DISPLAY': ':0', 14:17:53 INFO - 'HOME': '/home/cltbld', 14:17:53 INFO - 'LANG': 'en_US.UTF-8', 14:17:53 INFO - 'LANGUAGE': 'en_US:en', 14:17:53 INFO - 'LOGNAME': 'cltbld', 14:17:53 INFO - 'MAIL': '/var/mail/cltbld', 14:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:53 INFO - 'MOZ_NO_REMOTE': '1', 14:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:53 INFO - 'NO_EM_RESTART': '1', 14:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:53 INFO - 'PWD': '/builds/slave/test', 14:17:53 INFO - 'SHELL': '/bin/bash', 14:17:53 INFO - 'SHLVL': '1', 14:17:53 INFO - 'TERM': 'linux', 14:17:53 INFO - 'TMOUT': '86400', 14:17:53 INFO - 'USER': 'cltbld', 14:17:53 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:53 INFO - '_': '/tools/buildbot/bin/python'} 14:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:53 INFO - Downloading/unpacking jsonschema==2.5.1 14:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:17:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:55 INFO - Downloading functools32-3.2.3-2.tar.gz 14:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:17:55 INFO - warning: no files found matching '*.txt' 14:17:55 INFO - no previously-included directories found matching 'build' 14:17:55 INFO - no previously-included directories found matching 'dist' 14:17:55 INFO - no previously-included directories found matching '.git*' 14:17:55 INFO - Installing collected packages: jsonschema, functools32 14:17:55 INFO - Running setup.py install for functools32 14:17:55 INFO - warning: no files found matching '*.txt' 14:17:55 INFO - no previously-included directories found matching 'build' 14:17:55 INFO - no previously-included directories found matching 'dist' 14:17:55 INFO - no previously-included directories found matching '.git*' 14:17:55 INFO - Successfully installed jsonschema functools32 14:17:55 INFO - Cleaning up... 14:17:55 INFO - Return code: 0 14:17:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:17:55 INFO - Using env: {'DISPLAY': ':0', 14:17:55 INFO - 'HOME': '/home/cltbld', 14:17:55 INFO - 'LANG': 'en_US.UTF-8', 14:17:55 INFO - 'LANGUAGE': 'en_US:en', 14:17:55 INFO - 'LOGNAME': 'cltbld', 14:17:55 INFO - 'MAIL': '/var/mail/cltbld', 14:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:55 INFO - 'MOZ_NO_REMOTE': '1', 14:17:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:55 INFO - 'NO_EM_RESTART': '1', 14:17:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:55 INFO - 'PWD': '/builds/slave/test', 14:17:55 INFO - 'SHELL': '/bin/bash', 14:17:55 INFO - 'SHLVL': '1', 14:17:55 INFO - 'TERM': 'linux', 14:17:55 INFO - 'TMOUT': '86400', 14:17:55 INFO - 'USER': 'cltbld', 14:17:55 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:55 INFO - '_': '/tools/buildbot/bin/python'} 14:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:17:55 INFO - Cleaning up... 14:17:55 INFO - Return code: 0 14:17:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:17:55 INFO - Using env: {'DISPLAY': ':0', 14:17:55 INFO - 'HOME': '/home/cltbld', 14:17:55 INFO - 'LANG': 'en_US.UTF-8', 14:17:55 INFO - 'LANGUAGE': 'en_US:en', 14:17:55 INFO - 'LOGNAME': 'cltbld', 14:17:55 INFO - 'MAIL': '/var/mail/cltbld', 14:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:55 INFO - 'MOZ_NO_REMOTE': '1', 14:17:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:55 INFO - 'NO_EM_RESTART': '1', 14:17:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:55 INFO - 'PWD': '/builds/slave/test', 14:17:55 INFO - 'SHELL': '/bin/bash', 14:17:55 INFO - 'SHLVL': '1', 14:17:55 INFO - 'TERM': 'linux', 14:17:55 INFO - 'TMOUT': '86400', 14:17:55 INFO - 'USER': 'cltbld', 14:17:55 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:55 INFO - '_': '/tools/buildbot/bin/python'} 14:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:55 INFO - Downloading/unpacking blobuploader==1.2.4 14:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:56 INFO - Downloading blobuploader-1.2.4.tar.gz 14:17:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:17:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:17:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:57 INFO - Downloading docopt-0.6.1.tar.gz 14:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:17:57 INFO - Installing collected packages: blobuploader, requests, docopt 14:17:57 INFO - Running setup.py install for blobuploader 14:17:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:17:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:17:57 INFO - Running setup.py install for requests 14:17:57 INFO - Running setup.py install for docopt 14:17:58 INFO - Successfully installed blobuploader requests docopt 14:17:58 INFO - Cleaning up... 14:17:58 INFO - Return code: 0 14:17:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:17:58 INFO - Using env: {'DISPLAY': ':0', 14:17:58 INFO - 'HOME': '/home/cltbld', 14:17:58 INFO - 'LANG': 'en_US.UTF-8', 14:17:58 INFO - 'LANGUAGE': 'en_US:en', 14:17:58 INFO - 'LOGNAME': 'cltbld', 14:17:58 INFO - 'MAIL': '/var/mail/cltbld', 14:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:58 INFO - 'MOZ_NO_REMOTE': '1', 14:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:58 INFO - 'NO_EM_RESTART': '1', 14:17:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:58 INFO - 'PWD': '/builds/slave/test', 14:17:58 INFO - 'SHELL': '/bin/bash', 14:17:58 INFO - 'SHLVL': '1', 14:17:58 INFO - 'TERM': 'linux', 14:17:58 INFO - 'TMOUT': '86400', 14:17:58 INFO - 'USER': 'cltbld', 14:17:58 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:58 INFO - '_': '/tools/buildbot/bin/python'} 14:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:58 INFO - Running setup.py (path:/tmp/pip-zgmmAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:58 INFO - Running setup.py (path:/tmp/pip-peaNTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:58 INFO - Running setup.py (path:/tmp/pip-iYNFqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:58 INFO - Running setup.py (path:/tmp/pip-PyYgCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:58 INFO - Running setup.py (path:/tmp/pip-tCtOgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:58 INFO - Running setup.py (path:/tmp/pip-6kmc9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:58 INFO - Running setup.py (path:/tmp/pip-ivaU8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:58 INFO - Running setup.py (path:/tmp/pip-KXk6IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:58 INFO - Running setup.py (path:/tmp/pip-edZnGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:58 INFO - Running setup.py (path:/tmp/pip-biKbX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:58 INFO - Running setup.py (path:/tmp/pip-VqGbcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:59 INFO - Running setup.py (path:/tmp/pip-HoIMu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:59 INFO - Running setup.py (path:/tmp/pip-BRO2C6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:59 INFO - Running setup.py (path:/tmp/pip-KJWEWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:59 INFO - Running setup.py (path:/tmp/pip-JY34Z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:59 INFO - Running setup.py (path:/tmp/pip-6a9Zgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:59 INFO - Running setup.py (path:/tmp/pip-HXxPvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:17:59 INFO - Running setup.py install for manifestparser 14:17:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:17:59 INFO - Running setup.py install for mozcrash 14:17:59 INFO - Running setup.py install for mozdebug 14:17:59 INFO - Running setup.py install for mozdevice 14:17:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:17:59 INFO - Running setup.py install for mozfile 14:18:00 INFO - Running setup.py install for mozhttpd 14:18:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Running setup.py install for mozinfo 14:18:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Running setup.py install for mozInstall 14:18:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Running setup.py install for mozleak 14:18:00 INFO - Running setup.py install for mozlog 14:18:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Running setup.py install for moznetwork 14:18:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Running setup.py install for mozprocess 14:18:00 INFO - Running setup.py install for mozprofile 14:18:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Running setup.py install for mozrunner 14:18:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:18:00 INFO - Running setup.py install for mozscreenshot 14:18:01 INFO - Running setup.py install for moztest 14:18:01 INFO - Running setup.py install for mozversion 14:18:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:18:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:18:01 INFO - Cleaning up... 14:18:01 INFO - Return code: 0 14:18:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:18:01 INFO - Using env: {'DISPLAY': ':0', 14:18:01 INFO - 'HOME': '/home/cltbld', 14:18:01 INFO - 'LANG': 'en_US.UTF-8', 14:18:01 INFO - 'LANGUAGE': 'en_US:en', 14:18:01 INFO - 'LOGNAME': 'cltbld', 14:18:01 INFO - 'MAIL': '/var/mail/cltbld', 14:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:01 INFO - 'MOZ_NO_REMOTE': '1', 14:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:01 INFO - 'NO_EM_RESTART': '1', 14:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:01 INFO - 'PWD': '/builds/slave/test', 14:18:01 INFO - 'SHELL': '/bin/bash', 14:18:01 INFO - 'SHLVL': '1', 14:18:01 INFO - 'TERM': 'linux', 14:18:01 INFO - 'TMOUT': '86400', 14:18:01 INFO - 'USER': 'cltbld', 14:18:01 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:01 INFO - '_': '/tools/buildbot/bin/python'} 14:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:01 INFO - Running setup.py (path:/tmp/pip-4YXRkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:01 INFO - Running setup.py (path:/tmp/pip-bvq6gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:01 INFO - Running setup.py (path:/tmp/pip-3pshzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:01 INFO - Running setup.py (path:/tmp/pip-r2eFwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:01 INFO - Running setup.py (path:/tmp/pip-e7Ml89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:01 INFO - Running setup.py (path:/tmp/pip-X9uH9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:01 INFO - Running setup.py (path:/tmp/pip-CKFyK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:02 INFO - Running setup.py (path:/tmp/pip-gU0WCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:02 INFO - Running setup.py (path:/tmp/pip-HbiItj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:02 INFO - Running setup.py (path:/tmp/pip-aD9FZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:02 INFO - Running setup.py (path:/tmp/pip-Dfhu2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:02 INFO - Running setup.py (path:/tmp/pip-Te5GPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:02 INFO - Running setup.py (path:/tmp/pip-T_OiF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:02 INFO - Running setup.py (path:/tmp/pip-GHw1gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:02 INFO - Running setup.py (path:/tmp/pip-WwoQEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:02 INFO - Running setup.py (path:/tmp/pip-dcntoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:02 INFO - Running setup.py (path:/tmp/pip-J0aPM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:18:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:03 INFO - Downloading blessings-1.6.tar.gz 14:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:18:03 INFO - Installing collected packages: blessings 14:18:03 INFO - Running setup.py install for blessings 14:18:04 INFO - Successfully installed blessings 14:18:04 INFO - Cleaning up... 14:18:04 INFO - Return code: 0 14:18:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:18:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:04 INFO - Reading from file tmpfile_stdout 14:18:04 INFO - Current package versions: 14:18:04 INFO - argparse == 1.2.1 14:18:04 INFO - blessings == 1.6 14:18:04 INFO - blobuploader == 1.2.4 14:18:04 INFO - docopt == 0.6.1 14:18:04 INFO - functools32 == 3.2.3-2 14:18:04 INFO - jsonschema == 2.5.1 14:18:04 INFO - manifestparser == 1.1 14:18:04 INFO - mozInstall == 1.13 14:18:04 INFO - mozcrash == 1.0 14:18:04 INFO - mozdebug == 0.1 14:18:04 INFO - mozdevice == 0.51 14:18:04 INFO - mozfile == 1.2 14:18:04 INFO - mozhttpd == 0.7 14:18:04 INFO - mozinfo == 0.10 14:18:04 INFO - mozleak == 0.1 14:18:04 INFO - mozlog == 3.5 14:18:04 INFO - moznetwork == 0.27 14:18:04 INFO - mozprocess == 0.25 14:18:04 INFO - mozprofile == 0.28 14:18:04 INFO - mozrunner == 6.13 14:18:04 INFO - mozscreenshot == 0.1 14:18:04 INFO - mozsystemmonitor == 0.3 14:18:04 INFO - moztest == 0.8 14:18:04 INFO - mozversion == 1.4 14:18:04 INFO - psutil == 3.1.1 14:18:04 INFO - requests == 1.2.3 14:18:04 INFO - wsgiref == 0.1.2 14:18:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:18:04 INFO - Using env: {'DISPLAY': ':0', 14:18:04 INFO - 'HOME': '/home/cltbld', 14:18:04 INFO - 'LANG': 'en_US.UTF-8', 14:18:04 INFO - 'LANGUAGE': 'en_US:en', 14:18:04 INFO - 'LOGNAME': 'cltbld', 14:18:04 INFO - 'MAIL': '/var/mail/cltbld', 14:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:04 INFO - 'MOZ_NO_REMOTE': '1', 14:18:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:04 INFO - 'NO_EM_RESTART': '1', 14:18:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:04 INFO - 'PWD': '/builds/slave/test', 14:18:04 INFO - 'SHELL': '/bin/bash', 14:18:04 INFO - 'SHLVL': '1', 14:18:04 INFO - 'TERM': 'linux', 14:18:04 INFO - 'TMOUT': '86400', 14:18:04 INFO - 'USER': 'cltbld', 14:18:04 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:04 INFO - '_': '/tools/buildbot/bin/python'} 14:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:18:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:18:05 INFO - Installing collected packages: simplejson 14:18:05 INFO - Running setup.py install for simplejson 14:18:05 INFO - building 'simplejson._speedups' extension 14:18:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 14:18:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 14:18:06 INFO - Successfully installed simplejson 14:18:06 INFO - Cleaning up... 14:18:06 INFO - Return code: 0 14:18:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d98a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:18:06 INFO - Using env: {'DISPLAY': ':0', 14:18:06 INFO - 'HOME': '/home/cltbld', 14:18:06 INFO - 'LANG': 'en_US.UTF-8', 14:18:06 INFO - 'LANGUAGE': 'en_US:en', 14:18:06 INFO - 'LOGNAME': 'cltbld', 14:18:06 INFO - 'MAIL': '/var/mail/cltbld', 14:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:06 INFO - 'MOZ_NO_REMOTE': '1', 14:18:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:06 INFO - 'NO_EM_RESTART': '1', 14:18:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:06 INFO - 'PWD': '/builds/slave/test', 14:18:06 INFO - 'SHELL': '/bin/bash', 14:18:06 INFO - 'SHLVL': '1', 14:18:06 INFO - 'TERM': 'linux', 14:18:06 INFO - 'TMOUT': '86400', 14:18:06 INFO - 'USER': 'cltbld', 14:18:06 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:06 INFO - '_': '/tools/buildbot/bin/python'} 14:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:18:06 INFO - Cleaning up... 14:18:06 INFO - Return code: 0 14:18:06 INFO - Running post-action listener: _resource_record_post_action 14:18:06 INFO - Running post-action listener: _start_resource_monitoring 14:18:06 INFO - Starting resource monitoring. 14:18:06 INFO - [mozharness: 2017-08-01 21:18:06.988306Z] Finished create-virtualenv step (success) 14:18:06 INFO - [mozharness: 2017-08-01 21:18:06.988773Z] Running install step. 14:18:06 INFO - Running pre-action listener: _resource_record_pre_action 14:18:06 INFO - Running main action method: install 14:18:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:07 INFO - Reading from file tmpfile_stdout 14:18:07 INFO - Detecting whether we're running mozinstall >=1.0... 14:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:18:07 INFO - Reading from file tmpfile_stdout 14:18:07 INFO - Output received: 14:18:07 INFO - Usage: mozinstall [options] installer 14:18:07 INFO - Options: 14:18:07 INFO - -h, --help show this help message and exit 14:18:07 INFO - -d DEST, --destination=DEST 14:18:07 INFO - Directory to install application into. [default: 14:18:07 INFO - "/builds/slave/test"] 14:18:07 INFO - --app=APP Application being installed. [default: firefox] 14:18:07 INFO - mkdir: /builds/slave/test/build/application 14:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:18:25 INFO - Reading from file tmpfile_stdout 14:18:25 INFO - Output received: 14:18:25 INFO - /builds/slave/test/build/application/firefox/firefox 14:18:25 INFO - Running post-action listener: _resource_record_post_action 14:18:25 INFO - [mozharness: 2017-08-01 21:18:25.355758Z] Finished install step (success) 14:18:25 INFO - [mozharness: 2017-08-01 21:18:25.356010Z] Running setup-mitmproxy step. 14:18:25 INFO - Running pre-action listener: _resource_record_pre_action 14:18:25 INFO - Running main action method: setup_mitmproxy 14:18:25 INFO - Skipping: mitmproxy is not required 14:18:25 INFO - Running post-action listener: _resource_record_post_action 14:18:25 INFO - [mozharness: 2017-08-01 21:18:25.357136Z] Finished setup-mitmproxy step (success) 14:18:25 INFO - [mozharness: 2017-08-01 21:18:25.357417Z] Running run-tests step. 14:18:25 INFO - Running pre-action listener: _resource_record_pre_action 14:18:25 INFO - Running pre-action listener: _set_gcov_prefix 14:18:25 INFO - Running main action method: run_tests 14:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:18:25 INFO - Python 2.7.3 14:18:25 INFO - Return code: 0 14:18:25 INFO - grabbing minidump binary from tooltool 14:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f38eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:18:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:18:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:18:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:18:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:18:25 INFO - Return code: 0 14:18:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:18:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:18:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:18:25 INFO - ENV: RUST_BACKTRACE is now 1 14:18:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:18:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-076 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:18:25 INFO - Using env: {'DISPLAY': ':0', 14:18:25 INFO - 'HOME': '/home/cltbld', 14:18:25 INFO - 'LANG': 'en_US.UTF-8', 14:18:25 INFO - 'LANGUAGE': 'en_US:en', 14:18:25 INFO - 'LOGNAME': 'cltbld', 14:18:25 INFO - 'MAIL': '/var/mail/cltbld', 14:18:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:18:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:25 INFO - 'MOZ_NO_REMOTE': '1', 14:18:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:18:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:25 INFO - 'NO_EM_RESTART': '1', 14:18:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:25 INFO - 'PWD': '/builds/slave/test', 14:18:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:18:25 INFO - 'RUST_BACKTRACE': '1', 14:18:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:18:25 INFO - 'SHELL': '/bin/bash', 14:18:25 INFO - 'SHLVL': '1', 14:18:25 INFO - 'TERM': 'linux', 14:18:25 INFO - 'TMOUT': '86400', 14:18:25 INFO - 'USER': 'cltbld', 14:18:25 INFO - 'XDG_SESSION_COOKIE': '9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498', 14:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:25 INFO - '_': '/tools/buildbot/bin/python'} 14:18:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:18:25 INFO - mozversion application_buildid: 20170801201729 14:18:25 INFO - mozversion application_changeset: a3e675a3b10a0ea289c301bedc31866f3daf7875 14:18:25 INFO - mozversion application_display_name: Nightly 14:18:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:18:25 INFO - mozversion application_name: Firefox 14:18:25 INFO - mozversion application_remotingname: firefox 14:18:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:18:25 INFO - mozversion application_vendor: Mozilla 14:18:25 INFO - mozversion application_version: 56.0a1 14:18:25 INFO - mozversion platform_buildid: 20170801201729 14:18:25 INFO - mozversion platform_changeset: a3e675a3b10a0ea289c301bedc31866f3daf7875 14:18:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:18:25 INFO - mozversion platform_version: 56.0a1 14:18:25 INFO - using testdate: 1501622305 14:18:25 INFO - actual date: 1501622305 14:18:25 INFO - starting webserver on 'localhost:56821' 14:18:25 INFO - SUITE-START | Running 2 tests 14:18:25 INFO - TEST-START | dromaeo_css 14:18:25 INFO - Initialising browser for dromaeo_css test... 14:18:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56821/getInfo.html -profile /tmp/tmpuSb2ST/profile 14:18:25 INFO - TEST-INFO | started process 4797 (/builds/slave/test/build/application/firefox/firefox http://localhost:56821/getInfo.html) 14:18:31 INFO - TEST-INFO | 4797: exit 0 14:18:31 INFO - Browser initialized. 14:18:31 INFO - Running cycle 1/1 for dromaeo_css test... 14:18:31 INFO - TEST-INFO | started process 5005 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuSb2ST/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:18:32 INFO - PID 5005 | 14:18:32 INFO - PID 5005 | (/builds/slave/test/build/application/firefox/firefox:5053): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:18:32 INFO - PID 5005 | 14:18:42 INFO - PID 5005 | RSS: Main: 171462656 14:18:42 INFO - PID 5005 | 14:21:42 INFO - PID 5005 | Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56821/tests/dromaeo/cssquery-ext.html) 14:21:44 INFO - PID 5005 | RSS: Main: 173568000 14:21:44 INFO - PID 5005 | 14:24:45 INFO - PID 5005 | Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-ext.html (next: http://localhost:56821/tests/dromaeo/cssquery-jquery.html) 14:24:46 INFO - PID 5005 | RSS: Main: 171044864 14:24:46 INFO - PID 5005 | 14:27:48 INFO - PID 5005 | Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56821/tests/dromaeo/cssquery-mootools.html) 14:27:48 INFO - PID 5005 | RSS: Main: 173064192 14:27:48 INFO - PID 5005 | 14:30:50 INFO - PID 5005 | Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56821/tests/dromaeo/cssquery-prototype.html) 14:30:51 INFO - PID 5005 | RSS: Main: 171700224 14:30:51 INFO - PID 5005 | 14:33:54 INFO - PID 5005 | Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56821/tests/dromaeo/cssquery-yui.html) 14:33:55 INFO - PID 5005 | RSS: Main: 174575616 14:33:55 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | Cycle 1(1): loaded http://localhost:56821/tests/dromaeo/cssquery-yui.html (next: http://localhost:56821/tests/dromaeo/cssquery-dojo.html) 14:36:57 INFO - PID 5005 | RSS: Main: 175165440 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | __start_tp_report 14:36:57 INFO - PID 5005 | _x_x_mozilla_page_load 14:36:57 INFO - PID 5005 | _x_x_mozilla_page_load_details 14:36:57 INFO - PID 5005 | |i|pagename|runs| 14:36:57 INFO - PID 5005 | |0;dojo.html;3166.2242750526125;3192.2179066128797;3198.088544764742;3224.854881530331;3232.321212545367;6159.168512250845;6276.1527193828815;6316.621002739833;6378.979363301878;6402.903956440651;6224.097505861648;6252.968735156329;6279.403456671609;6292.748290068404;6296.401841825031;5704.172894930232;5755.971220143904;5757.251557297545;5769.451902069297;5804.767809287645;5733.512651424634;5752.769889204426;5758.9424105759035;5767.884642307151;5770.596058275923;5734.79928202513;5735.971320143393;5740.655560666352;5745.454181852718;5747.856303592423;5735.369109397963;5737.684427356494;5740;5764.625299355571;5765.625234359774;5618.4662457066925;5676.602637815355;5712.343080545749;5741.110127930177;5816.88366232673;6042.274927008743;6064.514838812885;6110.663913484756;6122.724477398528;6133.049377346474;6662.100616416818;6691.665416729144;6724.562903411263;6754.898676519807;6755.932440675629;6754.8311292217595;6831.965840170817;6833.760818371333;6842.178938527359;6843.657817109125;6224.937750622526;6229.127922090911;6297.937020629781;6307.432331090224;6309.211348581427;6780.8304792379595;6920.204176519664;6945.201301850352;6999.545029573163;7044.943258511162;6630.370114839083;6698.8995165072565;6705.1283333166375;6707.564008339442;6709.597424154566;6804.897926531007;6830.897536536956;6854.485913556503;6855.622940738208;6860.279670634612;6709.865802683919;6728.293529179464;6769.526133170631;6833.692483838239;6839.965800170968;6638.867222655616;6748.898766518506;6750.864982700318;6786.660666966632;6871.072405225331;5353.0899747043095;5375.5162035417;5377.704226267515;5434.565234781208;5441.727913604304;4648.302754586839;4671.1358398695675;4698.130845793539;4702.224133018014;4729.456112547101;5872;5878.676672783038;5879.118132280107;5886.735096920649;5893.852653683693;6793.082933803972;6803.829904252433;6895.931040689629;6896.655167241718;6902;5715.485606295453;5794.942050579524;5795.797147099831;5888.735006924699;5890.14592884038;5911.083782013795;5921.881562368729;5927.022041363127;5931.822045338647;5932.2584676915385;6515.348465153542;6546.050822630692;6550.213974323016;6552.63960482178;6561.048647946021;5690.715464226855;6760.256371799;6783.18601767791;6841.110655614738;6848.14398200225;6592.24189218246;6618.536702430783;6655.23464801554;6681.632510211791;6686.498512611671;6438.645874476949;6652.83367915806;6674.532782705164;6760.256371799;6778.593284402951;6719.899201512079;6748.291429400038;6790.796276111724;6840.6921688525135;6891.310868913269;6579.572327798678;6599.769008084694;6607.702653380513;6608.041833934149;6614.933850661624;6587;6588.868222635616;6600.306967768315;6621.238557565749;6645.767398141042;6445.806625801233;6458.869697802772;6474.061261117017;6566.179227596551;6568.047633093079;6427.453666438405;6493.512986525935;6589.637569933508;6691.698873550799;6698.430633396021;6338.366163383625;6353.142325786144;6426.807195784134;6427.967860160857;6466.967665161644 14:36:57 INFO - PID 5005 | |1;ext.html;833.62903507939;852.3394369363746;881.9867701984471;907.3603109807584;911.0388540090206;42622.360664590014;43158.784206079006;43511.564884351144;43518.34722479158;43534.564654353446;38464.80767596165;38512.422313665316;38907.221855562944;38931;38993;7064.611446370448;7187.676554555058;7195.42436605073;7212.314830091159;7214.711411543558;7054.6825392857445;7110.573365598055;7119.21688614252;7128.180259270199;7134.678939447711;7067.469939754514;7077.504574679774;7081.539699919489;7106.289371062878;7128.9643551782165;7010.263922288163;7047.612381319025;7085.114360704912;7091.18451378093;7098.396636285896;6485;6509.93490065098;6527.640979746112;6529.31442198567;6550.213974323064;34113.31773364544;34128.31743365119;34352.65647343519;34359.65640343614;34396.48405273923;3416.231347946717;3452.5511683481236;3454.4300190468543;3456.3605732939486;3461.446168613035;27661.308467288276;28085.157445276673;28110.859445702845;28165.718342816515;28199.295017624725;4302.956970430287;4369.191699535596;4420.027593929331;4431.0251744616135;4459.888502787424;180158;183680;187794.06102969535;188094;194137;51009;51028.74485627548;51045.489545104814;51072;51132.744336278825;51866.48133518692;52299.47700523022;52306;52397.7380113097;52432.737836310574;16305.755413668805;16420.096894670907;16426.507204783877;16465.176741162893;16508.25712842925;13870.988706637481;13876.375563099684;13882;13882.375293111834;13899.305034748424;13900.443982240799;13927.303634818218;14034.157950523002;14050.648733781532;14129.434822607187;5569.498745112959;5597.888042239132;5651.3893540340805;5659.745311460994;5664.49019588239;993.9552720127467;996.850472429127;1018.4195008844887;1019.8317277649105;1038.745507350704;21734.34796956094;22636.773632263797;22964.770352296597;23244;23373.367624659655;1075.8801244046197;1100.8403781451646;1116.319045382316;1140.0708422635525;1141.2809929744208;11984.880151198551;12287.631371058882;12344.444499997522;12449.128561000824;12452.750944981231;217045;218767;219456;219555;219983;53377;53442.46557534375;53447;53483.732581336844;53557.73221133869;1423.964400889986;1459.2193176650228;1498.3781730581904;1506.1339729655274;1524.8246451657978;22169.445763856034;22230.444238894157;22677.8866105675;22938.76000759649;23398.64902026437;37294.25411491809;38445.23109537738;38532.80733596314;38559.228815424096;38754.80622596869;199683;199688;200454;200646;202787;31735.52396714005;32084.5187322195;32341.676583234806;32357.191070223435;35674.82162589274;39604.80197598993;39753.20493590054;39835.2032959345;42885.7855710732;43790;31474.213144671565;31965.654524927504;32417.837910810296;32739.34521309608;33021.17447063843;28747.42505149927;29654.703452965194;31768;31896.840515797274;32210.516842246918;35503;35630.28739425249;35797.64202357943;35810;35897.28205435929;28865.567016495188;29466.263343416587;30016.84991575028;30073;30916.845415772776;22770;23249.418764531023;23392.688661585424;23438.17966371237;23456.53086938286 14:36:57 INFO - PID 5005 | |2;jquery.html;588.3146134753013;590.2592246730353;601.542827451137;607.4654304212293;613.6101729582497;22925.42686432838;23844.165454209106;23933.64099538508;23972.8801355993;24005.75994240057;21523.46191345217;23427.828608569587;24941;27380.58929116065;27559.17322480324;6465.418112369885;6616.371444712745;6621.404073633372;6621.834454138637;6633.900491492607;6184.381561843825;6449.258335291431;6450.064740612608;6481.546291759579;6482.546221764478;6440.710168042449;6442.871142577145;6445.645489498076;6452.387023232785;6461.418472337488;6438.742450301982;6443.774467893625;6454.031895215709;6459;6460.22477302722;6012.068129439944;6036.667983260919;6036.8188954331445;6045.909311360333;6051.848703782441;28331.858340708164;29855.10434686963;30365.69634303673;30429.695703042908;30433.543496847564;1915.7126431035317;1953.1601411393094;1956.774970878353;1957.109515170594;1959.0596513673374;19414.22343106274;20079.39761807148;20084.29704960334;20100.396988090382;20113.899430502755;2304.5275718477837;2317.385892738426;2375.631777074539;2381.118985975183;2412.65016572596;129822;135921;136365.3181734095;139705.301473493;140859.29570352082;14578.56962169712;14671.119732816065;14737.114995363441;14928.178950157846;15343;23783.286501404986;23807.166749163694;23807.404814879766;23827.40431489192;23965.04139834387;9866.605335786631;9942.502874856229;10096.545655445376;10160.13638840692;10269.691909242734;9265.861012084824;9281.58232879522;9355.391899526508;9420.670276540288;9434.622615095457;9307.255419566418;9358.766030849283;9402.34183607141;9595.952020239854;9632.229421646252;2631.6052592110955;2646.4839356325333;2700.9594856077174;2710.2411324828686;2780.610714499931;949.7957939043139;961.5672947173799;969.0454901921602;971.4511301114989;986.8569057486768;19568.21727130899;19677;19833.305834296018;20039.499012524804;20179.192832286542;1024.0578667625803;1024.3393011507535;1032.9070383665355;1050.947452627381;1051.232600201857;10798.56805727762;10822.861977157867;10857.239993200401;10876.238663293494;11121.108511786993;444483;458422;461788;467671;471560;107047;107961;109749;109973.45013274883;110759.44620277008;1191.618682021762;1211.6168596040327;1229.9200551964093;1232.063631639942;1235.32057368447;16376.754348685017;20857.89571052135;21828.236011739515;21955.121795128656;21973.560528789654;56729.14906276492;56779;56874.43125568691;57388.42611573831;58458.12312815226;66822.66588667025;66927.33072669405;67560;68076.6596167016;68134.31865681478;15167.089974601558;15266.542003739462;15285.465008724641;15334.76997845011;15350.078995259873;23774.16790412327;23879.16422925189;23909;23967.52064958656;24024.87987560051;18504.722429163303;19006;19087;19099.14053867607;19128.90435547869;16208.35166593323;16312.51062468128;16690.332386704882;17342.479725608253;17650.470485885446;18670.81329186745;19742.605147897248;19889.801101989375;19989.600207995467;19999.400017999484;15000.849991499945;15043;15289;15303.081815091131;15308.540743777705;18498.16758245909;18907.243710251987;18973.241070357584;19113.90443047776;19129.13918873682 14:36:57 INFO - PID 5005 | |3;mootools.html;1345.2533843716737;1380.2684577174095;1394.6792237785317;1396.4972609860454;1407.261187876365;13580.3209839508;14321.99746017778;14322;14327.713445731079;14349.139051656908;13627.863721362808;14063.156210627343;14519.346629401676;14582.562523124298;14639.487617933375;2702.2433718558786;2775.875085621152;2776.0422654184376;2778.3193117686096;2779.7359250871136;2586.8706564671693;2702.2838947678783;2717.171262764856;2754.1049159023296;2757.310672331917;2620.9213723588323;2637.393399518112;2741.616173735679;2755.1321333779897;2761.9171424857286;2690.838549687025;2701.6352792372973;2732.303262668017;2732.412531305779;2733.808633395663;2597.753213444739;2598.5192739343283;2599.2072417912527;2605.270524253212;2615.3461634591354;13758.380872860746;14023.64940876476;14119.364628591738;14199.219042952633;14200.786988195185;7406.370458511033;8001.279884810337;8019.599020048974;8030.839383212361;8078.111407745143;5154.5103215194495;5354.14333706609;5434.266374039494;5438.374586922476;5495.807646732345;1871.1392759330724;1873.3420922483565;1902.6289873474677;1946.883187008784;1960.8724983134612;23683.407914802094;23722.76277237223;23973.40066498334;24042.158524451734;24084.27747167588;6676.9666151668935;6696.698648560827;6739.494537909578;6796.796096117125;6826.488013399015;15958;16061.518154455385;16102.355905763638;16136.112513811853;16156.75764863528;7491.138519070268;7642.700740874065;7661.15727270011;7665.348445382205;7667.961660191663;6922.653867306614;6984.3364880336285;7069.550742097857;7078.398336141383;7089.61007144612;5054.545090941833;5169.715665638426;5202.453742356998;5204.635675502678;5205.141151709925;1514.1823415355584;1519.5061604978425;1538.8530455219104;1540.2067935013476;1544.7682847572953;772.9884051739228;888.1606881497053;890.0565400675305;896.0636135238691;906.8005038891433;7681;7806.414518911104;7838.294553490099;7989.161138080534;8056.194380562014;941.5715849288557;963.633819148733;965.3194497878978;982.2240430060315;987.9604815807431;4801.975990120027;4817.662763606584;4820.493848145964;4893.853184404403;5128.461511541309;18962.810371896103;19008.334708285143;19663.213471461266;19713.802861971482;19719.802801972084;18245.908770456328;19405.126769295417;19432.70850937237;19663.311784087487;19673.21307147698;10495.47522623881;10841.512131954081;11021.173411994132;11023.393713345842;11037.83443248352;7827.491213071132;7855.646495907584;7977.5612341321785;7983.680652774056;8497.74506764798;16186.271617776993;16268.51194464168;16460.341586336413;16461.34154633801;16481;16609;16724.414645487348;16957.66084678324;16989.32042718327;17135.070347585362;11467.369294688539;11681.88318116842;11698.649040528799;11735;11739.354335511629;12804.29576373271;13014.4143513544;13059.738805224033;13093.214407135221;13173.47306107745;9980.650677226262;10296.536655850654;10403.531841067019;10694.62568810088;10757.623483178051;10374.429406382722;10668.519916603616;10669.35983840972;10794.136469082612;10912.181586380895;9340.439573625605;9346.53267336644;9366.2038726709;9456.905430945602;9490.810183796148;6772.695228714621;6974;6985.860282794417;7076.221615622175;7127.964360178166;6194.504439644727;6378.054693318397;6790.287019862843;6873.518853680194;6955.443564514928 14:36:57 INFO - PID 5005 | |4;prototype.html;472.58176513785287;496.02933381315256;511.11066743865683;512.0373697448796;512.884600964783;6213.347598502154;6264;6318.336574659664;6338.33447488014;6383.489320854332;5961.850953726159;6135.171751813504;6162.352952939933;6204.503639708823;6223.595466294688;4959.727215003173;4962.330085438473;4969.9751501242445;5006.173981293082;5008.12357837377;4970.130227210224;5033.84898453047;5034.370703662043;5053.21675140352;5072.442031376564;4752.334673145762;4920.13897567926;4926.211806111023;4945.612255604904;4991.675541089818;4963.7021778693215;4965.90068198634;4966.417462919262;4997.150484417658;5022.924656130161;4403.119376124788;4419.9337009944875;4435.514102775574;4450.554944505556;4478.798454069574;6053.3946605339115;6053.697315134225;6152.3847615238565;6167.167432396659;6208.782692605782;5688.544916406678;5697.145428185764;5709.286339207599;5732.455416735404;5793.710314484259;6542.892126240045;6612.974988876732;6618.801435956929;6638.5684930479365;6692.665366731692;4850.296706977504;4897.265410188465;4899.265110233458;4906.57709264313;4931.457539670633;6980.930190698028;7000.579965202104;7035;7059.788206353766;7080;5009.674371165863;5019.84940451787;5027.622928280393;5028.296038554606;5080.822171223989;5006.424261210008;5039.118154322979;5065.366829146356;5068.391792984792;5081.2378143278065;5756.1653560233535;5756.856078598069;5772.220750198753;5797.797077102281;5812.041013232769;6600.900986485207;6646.8005959821285;6694.531382803157;6726.831829204309;6782.898256526156;1639.88520803545;1693.5427434592837;1694.4916525042438;1750.1774166141893;1776.5025792778042;1186.163754553038;1194.7729931313022;1209.2744353387898;1212.660455072581;1212.8423304970474;5732.828015159552;5776;5799.768009279666;5811.476967072984;5842.269716285465;6628.204615446178;6771.796846094625;6781.559198652072;6812.48906332027;6832.59004459724;5716.514096301861;5719.3136823581435;5762.9135562966585;5783.537317014713;5817;3566.465030245432;3602.9459558106123;3607.7294202934886;3616.240589476187;3671.1189314564313;6501.934980650228;6628.635425051572;6656.706424356263;6675.966620166868;6679.298673639295;6996.6851491683;7008.649567521706;7028.508004439743;7101.715931362688;7115.644217789193;6593.637349945703;6605.372489613479;6642.435392991553;6659.700313485905;6713;6861.55399899005;6898.931010689829;6911.3779759821855;6912.688928998307;6956.930430695628;6679.799606011828;6743.662816859235;6810.467644779885;6861.348171923659;6870.141232345957;6467.256265529524;6497.675116244074;6515.869682606227;6538.967305163634;6593.505487088583;4926.989967056817;4949.455559888337;4952.529509696573;5019.949800501949;5054.721990290569;6739.629320387426;6871.209810871614;6878.518503704692;6905.1713794342995;6973.6164510952385;6541.6075035498025;6645.3022432643875;6651.667416629246;6654.767083152066;6687.264400915798;6560.408638587826;6569.7479747525285;6651.73393064272;6674.532782705164;6681;6252.843678907881;6448.419642232222;6476.31998640136;6490.643014634241;6614.206295244601;6508.414242717989;6510.674466276572;6520.18497687789;6536.248331441755;6662.233843107911;6376.74493020274;6400.807975760735;6431.163948686641;6455.0640157176;6472.546921715435 14:36:57 INFO - PID 5005 | |5;yui.html;903.7288813355993;921.6497730862271;940.8447606144987;941.9670311539097;945.1399226703701;16346.10096444695;16845.578860528494;16878.662426751456;16883.324667013334;17941.551461213476;20673.586528269425;21315.786842131572;21327;21479.140834366608;21509.354719358445;1008.7377281906702;1020.7090979070966;1020.8468729690532;1030;1030.0369154840248;1059.0521483272466;1063.0379506546587;1065.0095411267519;1065.3607835298797;1068.834330678746;1008.2236677758121;1119.6920846767123;1128.4752590045628;1128.4921785196652;1129.0290350298737;1116.3246236027207;1119.289251325406;1121.966341009767;1122.360254654847;1122.3939072900623;803.5821372886124;804.0873608454447;805.3879051920583;806.9878951815728;807.7334479621711;17841.46475605734;18640.906795465937;18728.15723292455;18768.155433005548;19180.80819191804;19743.40769776909;20417.4895627609;20805.687914681293;20839.687404688942;20859.687104693443;14923.925380373137;15847.841521584753;16054.75917861233;16118.838811611851;16137.354505819752;3409.215880347534;3422.6577342265823;3440.9483857742152;3447.052060683307;3447.5862896452336;120787;123587;125478.37260813637;127272;129141;16108.275127619285;16376.099314537576;16378.836211637969;16417.58956026109;16431.83568164303;29131.41737165245;30440.695593044227;31219.68780312213;31353.529697054564;31395.37209255848;8214.58927053645;8229.506229626242;8260.669573217125;8275.172482751677;8289.626966786509;4122.9381559276635;4245.681573881971;4255.29787585045;4261.31818908973;4279.7218180818145;5141.126008578551;5292;5307.52232299095;5336.733163341817;5350.197470379474;972.3582435592616;1007.0382784440841;1019.7705516258783;1020.4489575629226;1023.7338292044122;672.7847088931495;674.908887300218;688.6659969914588;688.9517733758589;703.5813690853934;11110.888891110986;11358.886411135949;12053.457594408274;12322.137450378566;12468;667.3214840992857;680.7685386968454;705.1186017478152;723.989140162898;724.7680742162457;5265.973670131625;5889.440503152194;5910.940890591125;6201.534884883652;6347.841303967438;103353.48323258336;105087.4745626267;107615;107888;111679.44160279309;92537.53731231301;94218.52890735502;95238;95474.52262738642;96169.51915240519;81142;82493.58753206195;83590;84029.57985210035;84748;12006.159568830448;12319.260844349368;12379.814302785286;12570.308633025272;12603.432845521791;36053.63946360503;37826;37866.24267514579;38464.80767596144;39270.803645981585;46803.53196467992;48743;48947.755261223465;50786.49213507817;50830.74584627053;18325;18586.442406727278;18590.907045464686;18933.242670293035;19108.61782764309;20261.29085482001;20579.58840823205;20698.896505517376;20773.376798696063;21048.789512104682;18918.243270269035;18966;19031.143598537823;19046.523836904187;19280.132394042583;18617.90691046536;18914.243430262635;18934.810651893855;19004.619907602046;19133.33033343826;16982.490525284262;17001.40495082666;17038.40365587248;17145.399911003056;17366.47900562985;11969.760604788029;12429.813552796535;12546.247225166519;12704.872951270369;12756.043296752596;12648.6837829055;13068.934655326662;13198.868011319764;13267.800982985456;14738.926305368404 14:36:57 INFO - PID 5005 | __end_tp_report 14:36:57 INFO - PID 5005 | __start_cc_report 14:36:57 INFO - PID 5005 | _x_x_mozilla_cycle_collect,380 14:36:57 INFO - PID 5005 | __end_cc_report 14:36:57 INFO - PID 5005 | __startTimestamp1501623417384__endTimestamp 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | ------- Summary: start ------- 14:36:57 INFO - PID 5005 | Number of tests: 6 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | [#0] dojo.html Cycles:165 Average:6209.38 Median:6445.81 stddev:744.13 (11.5%) stddev-sans-first:707.08 14:36:57 INFO - PID 5005 | Values: 3166.2 3192.2 3198.1 3224.9 3232.3 6159.2 6276.2 6316.6 6379.0 6402.9 6224.1 6253.0 6279.4 6292.7 6296.4 5704.2 5756.0 5757.3 5769.5 5804.8 5733.5 5752.8 5758.9 5767.9 5770.6 5734.8 5736.0 5740.7 5745.5 5747.9 5735.4 5737.7 5740.0 5764.6 5765.6 5618.5 5676.6 5712.3 5741.1 5816.9 6042.3 6064.5 6110.7 6122.7 6133.0 6662.1 6691.7 6724.6 6754.9 6755.9 6754.8 6832.0 6833.8 6842.2 6843.7 6224.9 6229.1 6297.9 6307.4 6309.2 6780.8 6920.2 6945.2 6999.5 7044.9 6630.4 6698.9 6705.1 6707.6 6709.6 6804.9 6830.9 6854.5 6855.6 6860.3 6709.9 6728.3 6769.5 6833.7 6840.0 6638.9 6748.9 6750.9 6786.7 6871.1 5353.1 5375.5 5377.7 5434.6 5441.7 4648.3 4671.1 4698.1 4702.2 4729.5 5872.0 5878.7 5879.1 5886.7 5893.9 6793.1 6803.8 6895.9 6896.7 6902.0 5715.5 5794.9 5795.8 5888.7 5890.1 5911.1 5921.9 5927.0 5931.8 5932.3 6515.3 6546.1 6550.2 6552.6 6561.0 5690.7 6760.3 6783.2 6841.1 6848.1 6592.2 6618.5 6655.2 6681.6 6686.5 6438.6 6652.8 6674.5 6760.3 6778.6 6719.9 6748.3 6790.8 6840.7 6891.3 6579.6 6599.8 6607.7 6608.0 6614.9 6587.0 6588.9 6600.3 6621.2 6645.8 6445.8 6458.9 6474.1 6566.2 6568.0 6427.5 6493.5 6589.6 6691.7 6698.4 6338.4 6353.1 6426.8 6428.0 6467.0 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | [#1] ext.html Cycles:180 Average:36858.19 Median:34356.16 stddev:52547.65 (152.9%) stddev-sans-first:52625.44 14:36:57 INFO - PID 5005 | Values: 833.6 852.3 882.0 907.4 911.0 42622.4 43158.8 43511.6 43518.3 43534.6 38464.8 38512.4 38907.2 38931.0 38993.0 7064.6 7187.7 7195.4 7212.3 7214.7 7054.7 7110.6 7119.2 7128.2 7134.7 7067.5 7077.5 7081.5 7106.3 7129.0 7010.3 7047.6 7085.1 7091.2 7098.4 6485.0 6509.9 6527.6 6529.3 6550.2 34113.3 34128.3 34352.7 34359.7 34396.5 3416.2 3452.6 3454.4 3456.4 3461.4 27661.3 28085.2 28110.9 28165.7 28199.3 4303.0 4369.2 4420.0 4431.0 4459.9 180158.0 183680.0 187794.1 188094.0 194137.0 51009.0 51028.7 51045.5 51072.0 51132.7 51866.5 52299.5 52306.0 52397.7 52432.7 16305.8 16420.1 16426.5 16465.2 16508.3 13871.0 13876.4 13882.0 13882.4 13899.3 13900.4 13927.3 14034.2 14050.6 14129.4 5569.5 5597.9 5651.4 5659.7 5664.5 994.0 996.9 1018.4 1019.8 1038.7 21734.3 22636.8 22964.8 23244.0 23373.4 1075.9 1100.8 1116.3 1140.1 1141.3 11984.9 12287.6 12344.4 12449.1 12452.8 217045.0 218767.0 219456.0 219555.0 219983.0 53377.0 53442.5 53447.0 53483.7 53557.7 1424.0 1459.2 1498.4 1506.1 1524.8 22169.4 22230.4 22677.9 22938.8 23398.6 37294.3 38445.2 38532.8 38559.2 38754.8 199683.0 199688.0 200454.0 200646.0 202787.0 31735.5 32084.5 32341.7 32357.2 35674.8 39604.8 39753.2 39835.2 42885.8 43790.0 31474.2 31965.7 32417.8 32739.3 33021.2 28747.4 29654.7 31768.0 31896.8 32210.5 35503.0 35630.3 35797.6 35810.0 35897.3 28865.6 29466.3 30016.8 30073.0 30916.8 22770.0 23249.4 23392.7 23438.2 23456.5 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | [#2] jquery.html Cycles:180 Average:33893.47 Median:23795.23 stddev:77782.18 (326.9%) stddev-sans-first:77960.19 14:36:57 INFO - PID 5005 | Values: 588.3 590.3 601.5 607.5 613.6 22925.4 23844.2 23933.6 23972.9 24005.8 21523.5 23427.8 24941.0 27380.6 27559.2 6465.4 6616.4 6621.4 6621.8 6633.9 6184.4 6449.3 6450.1 6481.5 6482.5 6440.7 6442.9 6445.6 6452.4 6461.4 6438.7 6443.8 6454.0 6459.0 6460.2 6012.1 6036.7 6036.8 6045.9 6051.8 28331.9 29855.1 30365.7 30429.7 30433.5 1915.7 1953.2 1956.8 1957.1 1959.1 19414.2 20079.4 20084.3 20100.4 20113.9 2304.5 2317.4 2375.6 2381.1 2412.7 129822.0 135921.0 136365.3 139705.3 140859.3 14578.6 14671.1 14737.1 14928.2 15343.0 23783.3 23807.2 23807.4 23827.4 23965.0 9866.6 9942.5 10096.5 10160.1 10269.7 9265.9 9281.6 9355.4 9420.7 9434.6 9307.3 9358.8 9402.3 9596.0 9632.2 2631.6 2646.5 2701.0 2710.2 2780.6 949.8 961.6 969.0 971.5 986.9 19568.2 19677.0 19833.3 20039.5 20179.2 1024.1 1024.3 1032.9 1050.9 1051.2 10798.6 10822.9 10857.2 10876.2 11121.1 444483.0 458422.0 461788.0 467671.0 471560.0 107047.0 107961.0 109749.0 109973.5 110759.4 1191.6 1211.6 1229.9 1232.1 1235.3 16376.8 20857.9 21828.2 21955.1 21973.6 56729.1 56779.0 56874.4 57388.4 58458.1 66822.7 66927.3 67560.0 68076.7 68134.3 15167.1 15266.5 15285.5 15334.8 15350.1 23774.2 23879.2 23909.0 23967.5 24024.9 18504.7 19006.0 19087.0 19099.1 19128.9 16208.4 16312.5 16690.3 17342.5 17650.5 18670.8 19742.6 19889.8 19989.6 19999.4 15000.8 15043.0 15289.0 15303.1 15308.5 18498.2 18907.2 18973.2 19113.9 19129.1 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | [#3] mootools.html Cycles:180 Average:8749.47 Median:2629.16 stddev:5970.04 (227.1%) stddev-sans-first:5960.86 14:36:57 INFO - PID 5005 | Values: 1345.3 1380.3 1394.7 1396.5 1407.3 13580.3 14322.0 14322.0 14327.7 14349.1 13627.9 14063.2 14519.3 14582.6 14639.5 2702.2 2775.9 2776.0 2778.3 2779.7 2586.9 2702.3 2717.2 2754.1 2757.3 2620.9 2637.4 2741.6 2755.1 2761.9 2690.8 2701.6 2732.3 2732.4 2733.8 2597.8 2598.5 2599.2 2605.3 2615.3 13758.4 14023.6 14119.4 14199.2 14200.8 7406.4 8001.3 8019.6 8030.8 8078.1 5154.5 5354.1 5434.3 5438.4 5495.8 1871.1 1873.3 1902.6 1946.9 1960.9 23683.4 23722.8 23973.4 24042.2 24084.3 6677.0 6696.7 6739.5 6796.8 6826.5 15958.0 16061.5 16102.4 16136.1 16156.8 7491.1 7642.7 7661.2 7665.3 7668.0 6922.7 6984.3 7069.6 7078.4 7089.6 5054.5 5169.7 5202.5 5204.6 5205.1 1514.2 1519.5 1538.9 1540.2 1544.8 773.0 888.2 890.1 896.1 906.8 7681.0 7806.4 7838.3 7989.2 8056.2 941.6 963.6 965.3 982.2 988.0 4802.0 4817.7 4820.5 4893.9 5128.5 18962.8 19008.3 19663.2 19713.8 19719.8 18245.9 19405.1 19432.7 19663.3 19673.2 10495.5 10841.5 11021.2 11023.4 11037.8 7827.5 7855.6 7977.6 7983.7 8497.7 16186.3 16268.5 16460.3 16461.3 16481.0 16609.0 16724.4 16957.7 16989.3 17135.1 11467.4 11681.9 11698.6 11735.0 11739.4 12804.3 13014.4 13059.7 13093.2 13173.5 9980.7 10296.5 10403.5 10694.6 10757.6 10374.4 10668.5 10669.4 10794.1 10912.2 9340.4 9346.5 9366.2 9456.9 9490.8 6772.7 6974.0 6985.9 7076.2 7128.0 6194.5 6378.1 6790.3 6873.5 6955.4 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | [#4] prototype.html Cycles:180 Average:5560.53 Median:6164.76 stddev:1585.33 (25.7%) stddev-sans-first:1543.09 14:36:57 INFO - PID 5005 | Values: 472.6 496.0 511.1 512.0 512.9 6213.3 6264.0 6318.3 6338.3 6383.5 5961.9 6135.2 6162.4 6204.5 6223.6 4959.7 4962.3 4970.0 5006.2 5008.1 4970.1 5033.8 5034.4 5053.2 5072.4 4752.3 4920.1 4926.2 4945.6 4991.7 4963.7 4965.9 4966.4 4997.2 5022.9 4403.1 4419.9 4435.5 4450.6 4478.8 6053.4 6053.7 6152.4 6167.2 6208.8 5688.5 5697.1 5709.3 5732.5 5793.7 6542.9 6613.0 6618.8 6638.6 6692.7 4850.3 4897.3 4899.3 4906.6 4931.5 6980.9 7000.6 7035.0 7059.8 7080.0 5009.7 5019.8 5027.6 5028.3 5080.8 5006.4 5039.1 5065.4 5068.4 5081.2 5756.2 5756.9 5772.2 5797.8 5812.0 6600.9 6646.8 6694.5 6726.8 6782.9 1639.9 1693.5 1694.5 1750.2 1776.5 1186.2 1194.8 1209.3 1212.7 1212.8 5732.8 5776.0 5799.8 5811.5 5842.3 6628.2 6771.8 6781.6 6812.5 6832.6 5716.5 5719.3 5762.9 5783.5 5817.0 3566.5 3602.9 3607.7 3616.2 3671.1 6501.9 6628.6 6656.7 6676.0 6679.3 6996.7 7008.6 7028.5 7101.7 7115.6 6593.6 6605.4 6642.4 6659.7 6713.0 6861.6 6898.9 6911.4 6912.7 6956.9 6679.8 6743.7 6810.5 6861.3 6870.1 6467.3 6497.7 6515.9 6539.0 6593.5 4927.0 4949.5 4952.5 5019.9 5054.7 6739.6 6871.2 6878.5 6905.2 6973.6 6541.6 6645.3 6651.7 6654.8 6687.3 6560.4 6569.7 6651.7 6674.5 6681.0 6252.8 6448.4 6476.3 6490.6 6614.2 6508.4 6510.7 6520.2 6536.2 6662.2 6376.7 6400.8 6431.2 6455.1 6472.5 14:36:57 INFO - PID 5005 | 14:36:57 INFO - PID 5005 | [#5] yui.html Cycles:180 Average:22717.46 Median:19120.97 stddev:30782.88 (161.0%) stddev-sans-first:30825.65 14:36:57 INFO - PID 5005 | Values: 903.7 921.6 940.8 942.0 945.1 16346.1 16845.6 16878.7 16883.3 17941.6 20673.6 21315.8 21327.0 21479.1 21509.4 1008.7 1020.7 1020.8 1030.0 1030.0 1059.1 1063.0 1065.0 1065.4 1068.8 1008.2 1119.7 1128.5 1128.5 1129.0 1116.3 1119.3 1122.0 1122.4 1122.4 803.6 804.1 805.4 807.0 807.7 17841.5 18640.9 18728.2 18768.2 19180.8 19743.4 20417.5 20805.7 20839.7 20859.7 14923.9 15847.8 16054.8 16118.8 16137.4 3409.2 3422.7 3440.9 3447.1 3447.6 120787.0 123587.0 125478.4 127272.0 129141.0 16108.3 16376.1 16378.8 16417.6 16431.8 29131.4 30440.7 31219.7 31353.5 31395.4 8214.6 8229.5 8260.7 8275.2 8289.6 4122.9 4245.7 4255.3 4261.3 4279.7 5141.1 5292.0 5307.5 5336.7 5350.2 972.4 1007.0 1019.8 1020.4 1023.7 672.8 674.9 688.7 689.0 703.6 11110.9 11358.9 12053.5 12322.1 12468.0 667.3 680.8 705.1 724.0 724.8 5266.0 5889.4 5910.9 6201.5 6347.8 103353.5 105087.5 107615.0 107888.0 111679.4 92537.5 94218.5 95238.0 95474.5 96169.5 81142.0 82493.6 83590.0 84029.6 84748.0 12006.2 12319.3 12379.8 12570.3 12603.4 36053.6 37826.0 37866.2 38464.8 39270.8 46803.5 48743.0 48947.8 50786.5 50830.7 18325.0 18586.4 18590.9 18933.2 19108.6 20261.3 20579.6 20698.9 20773.4 21048.8 18918.2 18966.0 19031.1 19046.5 19280.1 18617.9 18914.2 18934.8 19004.6 19133.3 16982.5 17001.4 17038.4 17145.4 17366.5 11969.8 12429.8 12546.2 12704.9 12756.0 12648.7 13068.9 13198.9 13267.8 14738.9 14:36:57 INFO - PID 5005 | -------- Summary: end -------- 14:36:57 INFO - PID 5005 | 14:36:58 INFO - TEST-INFO | 5005: exit 0 14:36:58 INFO - TEST-OK | dromaeo_css | took 1112391ms 14:36:58 INFO - TEST-START | kraken 14:36:58 INFO - Initialising browser for kraken test... 14:36:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56821/getInfo.html -profile /tmp/tmpbSIQHM/profile 14:36:58 INFO - TEST-INFO | started process 5365 (/builds/slave/test/build/application/firefox/firefox http://localhost:56821/getInfo.html) 14:37:03 INFO - TEST-INFO | 5365: exit 0 14:37:03 INFO - Browser initialized. 14:37:03 INFO - Running cycle 1/1 for kraken test... 14:37:03 INFO - TEST-INFO | started process 5573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbSIQHM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:37:04 INFO - PID 5573 | 14:37:04 INFO - PID 5573 | (/builds/slave/test/build/application/firefox/firefox:5621): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:37:04 INFO - PID 5573 | 14:37:15 INFO - PID 5573 | RSS: Main: 172535808 14:37:15 INFO - PID 5573 | 14:37:17 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-beat-detection) 14:37:18 INFO - PID 5573 | RSS: Main: 177168384 14:37:18 INFO - PID 5573 | 14:37:21 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-dft) 14:37:21 INFO - PID 5573 | RSS: Main: 177446912 14:37:21 INFO - PID 5573 | 14:37:24 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-fft) 14:37:25 INFO - PID 5573 | RSS: Main: 171978752 14:37:25 INFO - PID 5573 | 14:37:27 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56821/tests/kraken/driver.html?testName=audio-oscillator) 14:37:28 INFO - PID 5573 | RSS: Main: 172601344 14:37:28 INFO - PID 5573 | 14:37:30 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56821/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:37:31 INFO - PID 5573 | RSS: Main: 172924928 14:37:31 INFO - PID 5573 | 14:37:34 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56821/tests/kraken/driver.html?testName=imaging-darkroom) 14:37:34 INFO - PID 5573 | RSS: Main: 175382528 14:37:34 INFO - PID 5573 | 14:37:38 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56821/tests/kraken/driver.html?testName=imaging-desaturate) 14:37:38 INFO - PID 5573 | RSS: Main: 175259648 14:37:38 INFO - PID 5573 | 14:37:41 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56821/tests/kraken/driver.html?testName=json-parse-financial) 14:37:42 INFO - PID 5573 | RSS: Main: 175501312 14:37:42 INFO - PID 5573 | 14:37:44 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56821/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:37:44 INFO - PID 5573 | RSS: Main: 175165440 14:37:44 INFO - PID 5573 | 14:37:46 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:37:47 INFO - PID 5573 | RSS: Main: 175042560 14:37:47 INFO - PID 5573 | 14:37:49 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:37:49 INFO - PID 5573 | RSS: Main: 172527616 14:37:49 INFO - PID 5573 | 14:37:52 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:37:53 INFO - PID 5573 | RSS: Main: 172892160 14:37:53 INFO - PID 5573 | 14:37:56 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:37:56 INFO - PID 5573 | RSS: Main: 173277184 14:37:56 INFO - PID 5573 | 14:37:58 INFO - PID 5573 | Cycle 1(1): loaded http://localhost:56821/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56821/tests/kraken/driver.html?testName=ai-astar) 14:37:59 INFO - PID 5573 | RSS: Main: 174645248 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | __start_tp_report 14:37:59 INFO - PID 5573 | _x_x_mozilla_page_load 14:37:59 INFO - PID 5573 | _x_x_mozilla_page_load_details 14:37:59 INFO - PID 5573 | |i|pagename|runs| 14:37:59 INFO - PID 5573 | |0;ai-astar;99;107;101;108;108;110;116;100;107;111 14:37:59 INFO - PID 5573 | |1;audio-beat-detection;149;125;143;170;118;146;150;118;174;118 14:37:59 INFO - PID 5573 | |2;audio-dft;164;163;164;162;163;164;163;165;170;166 14:37:59 INFO - PID 5573 | |3;audio-fft;74;75;75;177;76;74;75;88;96;76 14:37:59 INFO - PID 5573 | |4;audio-oscillator;79;78;131;80;79;79;127;79;79;128 14:37:59 INFO - PID 5573 | |5;imaging-gaussian-blur;114;113;113;113;124;114;116;115;114;115 14:37:59 INFO - PID 5573 | |6;imaging-darkroom;150;151;152;150;150;150;150;150;150;150 14:37:59 INFO - PID 5573 | |7;imaging-desaturate;94;94;93;94;94;94;98;94;94;93 14:37:59 INFO - PID 5573 | |8;json-parse-financial;54;54;54;54;57;54;54;54;55;54 14:37:59 INFO - PID 5573 | |9;json-stringify-tinderbox;55;54;54;54;54;55;55;55;54;54 14:37:59 INFO - PID 5573 | |10;stanford-crypto-aes;77;77;78;77;82;76;78;78;90;87 14:37:59 INFO - PID 5573 | |11;stanford-crypto-ccm;138;139;128;125;136;136;129;129;137;178 14:37:59 INFO - PID 5573 | |12;stanford-crypto-pbkdf2;182;176;182;176;178;175;174;175;179;176 14:37:59 INFO - PID 5573 | |13;stanford-crypto-sha256-iterative;68;67;76;70;67;69;66;66;65;71 14:37:59 INFO - PID 5573 | __end_tp_report 14:37:59 INFO - PID 5573 | __start_cc_report 14:37:59 INFO - PID 5573 | _x_x_mozilla_cycle_collect,996 14:37:59 INFO - PID 5573 | __end_cc_report 14:37:59 INFO - PID 5573 | __startTimestamp1501623479003__endTimestamp 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | ------- Summary: start ------- 14:37:59 INFO - PID 5573 | Number of tests: 14 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#0] ai-astar Cycles:10 Average:106.70 Median:109.00 stddev:5.33 (4.9%) stddev-sans-first:4.88 14:37:59 INFO - PID 5573 | Values: 99.0 107.0 101.0 108.0 108.0 110.0 116.0 100.0 107.0 111.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:147.50 stddev:20.94 (14.2%) stddev-sans-first:22.02 14:37:59 INFO - PID 5573 | Values: 149.0 125.0 143.0 170.0 118.0 146.0 150.0 118.0 174.0 118.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:2.27 (1.4%) stddev-sans-first:2.40 14:37:59 INFO - PID 5573 | Values: 164.0 163.0 164.0 162.0 163.0 164.0 163.0 165.0 170.0 166.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#3] audio-fft Cycles:10 Average:88.60 Median:75.50 stddev:31.92 (42.3%) stddev-sans-first:33.41 14:37:59 INFO - PID 5573 | Values: 74.0 75.0 75.0 177.0 76.0 74.0 75.0 88.0 96.0 76.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:79.00 stddev:24.02 (30.4%) stddev-sans-first:24.86 14:37:59 INFO - PID 5573 | Values: 79.0 78.0 131.0 80.0 79.0 79.0 127.0 79.0 79.0 128.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:114.50 stddev:3.28 (2.9%) stddev-sans-first:3.46 14:37:59 INFO - PID 5573 | Values: 114.0 113.0 113.0 113.0 124.0 114.0 116.0 115.0 114.0 115.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#6] imaging-darkroom Cycles:10 Average:150.30 Median:150.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 14:37:59 INFO - PID 5573 | Values: 150.0 151.0 152.0 150.0 150.0 150.0 150.0 150.0 150.0 150.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.40 (1.5%) stddev-sans-first:1.48 14:37:59 INFO - PID 5573 | Values: 94.0 94.0 93.0 94.0 94.0 94.0 98.0 94.0 94.0 93.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 14:37:59 INFO - PID 5573 | Values: 54.0 54.0 54.0 54.0 57.0 54.0 54.0 54.0 55.0 54.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#9] json-stringify-tinderbox Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 14:37:59 INFO - PID 5573 | Values: 55.0 54.0 54.0 54.0 54.0 55.0 55.0 55.0 54.0 54.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:78.00 stddev:4.81 (6.2%) stddev-sans-first:4.97 14:37:59 INFO - PID 5573 | Values: 77.0 77.0 78.0 77.0 82.0 76.0 78.0 78.0 90.0 87.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:136.50 stddev:15.05 (11.0%) stddev-sans-first:15.96 14:37:59 INFO - PID 5573 | Values: 138.0 139.0 128.0 125.0 136.0 136.0 129.0 129.0 137.0 178.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:177.00 stddev:2.87 (1.6%) stddev-sans-first:2.49 14:37:59 INFO - PID 5573 | Values: 182.0 176.0 182.0 176.0 178.0 175.0 174.0 175.0 179.0 176.0 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:3.24 (4.7%) stddev-sans-first:3.43 14:37:59 INFO - PID 5573 | Values: 68.0 67.0 76.0 70.0 67.0 69.0 66.0 66.0 65.0 71.0 14:37:59 INFO - PID 5573 | -------- Summary: end -------- 14:37:59 INFO - PID 5573 | 14:37:59 INFO - PID 5573 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 14:37:59 INFO - TEST-INFO | 5573: exit 0 14:37:59 INFO - TEST-OK | kraken | took 61631ms 14:37:59 INFO - SUITE-END | took 1174s 14:38:00 INFO - Completed test suite (00:19:34) 14:38:00 INFO - javascript benchmark 14:38:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8449.01043401637, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3166.2242750526125, 3192.2179066128797, 3198.088544764742, 3224.854881530331, 3232.321212545367, 6159.168512250845, 6276.1527193828815, 6316.621002739833, 6378.979363301878, 6402.903956440651, 6224.097505861648, 6252.968735156329, 6279.403456671609, 6292.748290068404, 6296.401841825031, 5704.172894930232, 5755.971220143904, 5757.251557297545, 5769.451902069297, 5804.767809287645, 5733.512651424634, 5752.769889204426, 5758.9424105759035, 5767.884642307151, 5770.596058275923, 5734.79928202513, 5735.971320143393, 5740.655560666352, 5745.454181852718, 5747.856303592423, 5735.369109397963, 5737.684427356494, 5740.0, 5764.625299355571, 5765.625234359774, 5618.4662457066925, 5676.602637815355, 5712.343080545749, 5741.110127930177, 5816.88366232673, 6042.274927008743, 6064.514838812885, 6110.663913484756, 6122.724477398528, 6133.049377346474, 6662.100616416818, 6691.665416729144, 6724.562903411263, 6754.898676519807, 6755.932440675629, 6754.8311292217595, 6831.965840170817, 6833.760818371333, 6842.178938527359, 6843.657817109125, 6224.937750622526, 6229.127922090911, 6297.937020629781, 6307.432331090224, 6309.211348581427, 6780.8304792379595, 6920.204176519664, 6945.201301850352, 6999.545029573163, 7044.943258511162, 6630.370114839083, 6698.8995165072565, 6705.1283333166375, 6707.564008339442, 6709.597424154566, 6804.897926531007, 6830.897536536956, 6854.485913556503, 6855.622940738208, 6860.279670634612, 6709.865802683919, 6728.293529179464, 6769.526133170631, 6833.692483838239, 6839.965800170968, 6638.867222655616, 6748.898766518506, 6750.864982700318, 6786.660666966632, 6871.072405225331, 5353.0899747043095, 5375.5162035417, 5377.704226267515, 5434.565234781208, 5441.727913604304, 4648.302754586839, 4671.1358398695675, 4698.130845793539, 4702.224133018014, 4729.456112547101, 5872.0, 5878.676672783038, 5879.118132280107, 5886.735096920649, 5893.852653683693, 6793.082933803972, 6803.829904252433, 6895.931040689629, 6896.655167241718, 6902.0, 5715.485606295453, 5794.942050579524, 5795.797147099831, 5888.735006924699, 5890.14592884038, 5911.083782013795, 5921.881562368729, 5927.022041363127, 5931.822045338647, 5932.2584676915385, 6515.348465153542, 6546.050822630692, 6550.213974323016, 6552.63960482178, 6561.048647946021, 5690.715464226855, 6760.256371799, 6783.18601767791, 6841.110655614738, 6848.14398200225, 6592.24189218246, 6618.536702430783, 6655.23464801554, 6681.632510211791, 6686.498512611671, 6438.645874476949, 6652.83367915806, 6674.532782705164, 6760.256371799, 6778.593284402951, 6719.899201512079, 6748.291429400038, 6790.796276111724, 6840.6921688525135, 6891.310868913269, 6579.572327798678, 6599.769008084694, 6607.702653380513, 6608.041833934149, 6614.933850661624, 6587.0, 6588.868222635616, 6600.306967768315, 6621.238557565749, 6645.767398141042, 6445.806625801233, 6458.869697802772, 6474.061261117017, 6566.179227596551, 6568.047633093079, 6427.453666438405, 6493.512986525935, 6589.637569933508, 6691.698873550799, 6698.430633396021, 6338.366163383625, 6353.142325786144, 6426.807195784134, 6427.967860160857, 6466.967665161644], "value": 6153.74361812362, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.62903507939, 852.3394369363746, 881.9867701984471, 907.3603109807584, 911.0388540090206, 42622.360664590014, 43158.784206079006, 43511.564884351144, 43518.34722479158, 43534.564654353446, 38464.80767596165, 38512.422313665316, 38907.221855562944, 38931.0, 38993.0, 7064.611446370448, 7187.676554555058, 7195.42436605073, 7212.314830091159, 7214.711411543558, 7054.6825392857445, 7110.573365598055, 7119.21688614252, 7128.180259270199, 7134.678939447711, 7067.469939754514, 7077.504574679774, 7081.539699919489, 7106.289371062878, 7128.9643551782165, 7010.263922288163, 7047.612381319025, 7085.114360704912, 7091.18451378093, 7098.396636285896, 6485.0, 6509.93490065098, 6527.640979746112, 6529.31442198567, 6550.213974323064, 34113.31773364544, 34128.31743365119, 34352.65647343519, 34359.65640343614, 34396.48405273923, 3416.231347946717, 3452.5511683481236, 3454.4300190468543, 3456.3605732939486, 3461.446168613035, 27661.308467288276, 28085.157445276673, 28110.859445702845, 28165.718342816515, 28199.295017624725, 4302.956970430287, 4369.191699535596, 4420.027593929331, 4431.0251744616135, 4459.888502787424, 180158.0, 183680.0, 187794.06102969535, 188094.0, 194137.0, 51009.0, 51028.74485627548, 51045.489545104814, 51072.0, 51132.744336278825, 51866.48133518692, 52299.47700523022, 52306.0, 52397.7380113097, 52432.737836310574, 16305.755413668805, 16420.096894670907, 16426.507204783877, 16465.176741162893, 16508.25712842925, 13870.988706637481, 13876.375563099684, 13882.0, 13882.375293111834, 13899.305034748424, 13900.443982240799, 13927.303634818218, 14034.157950523002, 14050.648733781532, 14129.434822607187, 5569.498745112959, 5597.888042239132, 5651.3893540340805, 5659.745311460994, 5664.49019588239, 993.9552720127467, 996.850472429127, 1018.4195008844887, 1019.8317277649105, 1038.745507350704, 21734.34796956094, 22636.773632263797, 22964.770352296597, 23244.0, 23373.367624659655, 1075.8801244046197, 1100.8403781451646, 1116.319045382316, 1140.0708422635525, 1141.2809929744208, 11984.880151198551, 12287.631371058882, 12344.444499997522, 12449.128561000824, 12452.750944981231, 217045.0, 218767.0, 219456.0, 219555.0, 219983.0, 53377.0, 53442.46557534375, 53447.0, 53483.732581336844, 53557.73221133869, 1423.964400889986, 1459.2193176650228, 1498.3781730581904, 1506.1339729655274, 1524.8246451657978, 22169.445763856034, 22230.444238894157, 22677.8866105675, 22938.76000759649, 23398.64902026437, 37294.25411491809, 38445.23109537738, 38532.80733596314, 38559.228815424096, 38754.80622596869, 199683.0, 199688.0, 200454.0, 200646.0, 202787.0, 31735.52396714005, 32084.5187322195, 32341.676583234806, 32357.191070223435, 35674.82162589274, 39604.80197598993, 39753.20493590054, 39835.2032959345, 42885.7855710732, 43790.0, 31474.213144671565, 31965.654524927504, 32417.837910810296, 32739.34521309608, 33021.17447063843, 28747.42505149927, 29654.703452965194, 31768.0, 31896.840515797274, 32210.516842246918, 35503.0, 35630.28739425249, 35797.64202357943, 35810.0, 35897.28205435929, 28865.567016495188, 29466.263343416587, 30016.84991575028, 30073.0, 30916.845415772776, 22770.0, 23249.418764531023, 23392.688661585424, 23438.17966371237, 23456.53086938286], "value": 16695.489205306338, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.3146134753013, 590.2592246730353, 601.542827451137, 607.4654304212293, 613.6101729582497, 22925.42686432838, 23844.165454209106, 23933.64099538508, 23972.8801355993, 24005.75994240057, 21523.46191345217, 23427.828608569587, 24941.0, 27380.58929116065, 27559.17322480324, 6465.418112369885, 6616.371444712745, 6621.404073633372, 6621.834454138637, 6633.900491492607, 6184.381561843825, 6449.258335291431, 6450.064740612608, 6481.546291759579, 6482.546221764478, 6440.710168042449, 6442.871142577145, 6445.645489498076, 6452.387023232785, 6461.418472337488, 6438.742450301982, 6443.774467893625, 6454.031895215709, 6459.0, 6460.22477302722, 6012.068129439944, 6036.667983260919, 6036.8188954331445, 6045.909311360333, 6051.848703782441, 28331.858340708164, 29855.10434686963, 30365.69634303673, 30429.695703042908, 30433.543496847564, 1915.7126431035317, 1953.1601411393094, 1956.774970878353, 1957.109515170594, 1959.0596513673374, 19414.22343106274, 20079.39761807148, 20084.29704960334, 20100.396988090382, 20113.899430502755, 2304.5275718477837, 2317.385892738426, 2375.631777074539, 2381.118985975183, 2412.65016572596, 129822.0, 135921.0, 136365.3181734095, 139705.301473493, 140859.29570352082, 14578.56962169712, 14671.119732816065, 14737.114995363441, 14928.178950157846, 15343.0, 23783.286501404986, 23807.166749163694, 23807.404814879766, 23827.40431489192, 23965.04139834387, 9866.605335786631, 9942.502874856229, 10096.545655445376, 10160.13638840692, 10269.691909242734, 9265.861012084824, 9281.58232879522, 9355.391899526508, 9420.670276540288, 9434.622615095457, 9307.255419566418, 9358.766030849283, 9402.34183607141, 9595.952020239854, 9632.229421646252, 2631.6052592110955, 2646.4839356325333, 2700.9594856077174, 2710.2411324828686, 2780.610714499931, 949.7957939043139, 961.5672947173799, 969.0454901921602, 971.4511301114989, 986.8569057486768, 19568.21727130899, 19677.0, 19833.305834296018, 20039.499012524804, 20179.192832286542, 1024.0578667625803, 1024.3393011507535, 1032.9070383665355, 1050.947452627381, 1051.232600201857, 10798.56805727762, 10822.861977157867, 10857.239993200401, 10876.238663293494, 11121.108511786993, 444483.0, 458422.0, 461788.0, 467671.0, 471560.0, 107047.0, 107961.0, 109749.0, 109973.45013274883, 110759.44620277008, 1191.618682021762, 1211.6168596040327, 1229.9200551964093, 1232.063631639942, 1235.32057368447, 16376.754348685017, 20857.89571052135, 21828.236011739515, 21955.121795128656, 21973.560528789654, 56729.14906276492, 56779.0, 56874.43125568691, 57388.42611573831, 58458.12312815226, 66822.66588667025, 66927.33072669405, 67560.0, 68076.6596167016, 68134.31865681478, 15167.089974601558, 15266.542003739462, 15285.465008724641, 15334.76997845011, 15350.078995259873, 23774.16790412327, 23879.16422925189, 23909.0, 23967.52064958656, 24024.87987560051, 18504.722429163303, 19006.0, 19087.0, 19099.14053867607, 19128.90435547869, 16208.35166593323, 16312.51062468128, 16690.332386704882, 17342.479725608253, 17650.470485885446, 18670.81329186745, 19742.605147897248, 19889.801101989375, 19989.600207995467, 19999.400017999484, 15000.849991499945, 15043.0, 15289.0, 15303.081815091131, 15308.540743777705, 18498.16758245909, 18907.243710251987, 18973.241070357584, 19113.90443047776, 19129.13918873682], "value": 12057.646719142685, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.2533843716737, 1380.2684577174095, 1394.6792237785317, 1396.4972609860454, 1407.261187876365, 13580.3209839508, 14321.99746017778, 14322.0, 14327.713445731079, 14349.139051656908, 13627.863721362808, 14063.156210627343, 14519.346629401676, 14582.562523124298, 14639.487617933375, 2702.2433718558786, 2775.875085621152, 2776.0422654184376, 2778.3193117686096, 2779.7359250871136, 2586.8706564671693, 2702.2838947678783, 2717.171262764856, 2754.1049159023296, 2757.310672331917, 2620.9213723588323, 2637.393399518112, 2741.616173735679, 2755.1321333779897, 2761.9171424857286, 2690.838549687025, 2701.6352792372973, 2732.303262668017, 2732.412531305779, 2733.808633395663, 2597.753213444739, 2598.5192739343283, 2599.2072417912527, 2605.270524253212, 2615.3461634591354, 13758.380872860746, 14023.64940876476, 14119.364628591738, 14199.219042952633, 14200.786988195185, 7406.370458511033, 8001.279884810337, 8019.599020048974, 8030.839383212361, 8078.111407745143, 5154.5103215194495, 5354.14333706609, 5434.266374039494, 5438.374586922476, 5495.807646732345, 1871.1392759330724, 1873.3420922483565, 1902.6289873474677, 1946.883187008784, 1960.8724983134612, 23683.407914802094, 23722.76277237223, 23973.40066498334, 24042.158524451734, 24084.27747167588, 6676.9666151668935, 6696.698648560827, 6739.494537909578, 6796.796096117125, 6826.488013399015, 15958.0, 16061.518154455385, 16102.355905763638, 16136.112513811853, 16156.75764863528, 7491.138519070268, 7642.700740874065, 7661.15727270011, 7665.348445382205, 7667.961660191663, 6922.653867306614, 6984.3364880336285, 7069.550742097857, 7078.398336141383, 7089.61007144612, 5054.545090941833, 5169.715665638426, 5202.453742356998, 5204.635675502678, 5205.141151709925, 1514.1823415355584, 1519.5061604978425, 1538.8530455219104, 1540.2067935013476, 1544.7682847572953, 772.9884051739228, 888.1606881497053, 890.0565400675305, 896.0636135238691, 906.8005038891433, 7681.0, 7806.414518911104, 7838.294553490099, 7989.161138080534, 8056.194380562014, 941.5715849288557, 963.633819148733, 965.3194497878978, 982.2240430060315, 987.9604815807431, 4801.975990120027, 4817.662763606584, 4820.493848145964, 4893.853184404403, 5128.461511541309, 18962.810371896103, 19008.334708285143, 19663.213471461266, 19713.802861971482, 19719.802801972084, 18245.908770456328, 19405.126769295417, 19432.70850937237, 19663.311784087487, 19673.21307147698, 10495.47522623881, 10841.512131954081, 11021.173411994132, 11023.393713345842, 11037.83443248352, 7827.491213071132, 7855.646495907584, 7977.5612341321785, 7983.680652774056, 8497.74506764798, 16186.271617776993, 16268.51194464168, 16460.341586336413, 16461.34154633801, 16481.0, 16609.0, 16724.414645487348, 16957.66084678324, 16989.32042718327, 17135.070347585362, 11467.369294688539, 11681.88318116842, 11698.649040528799, 11735.0, 11739.354335511629, 12804.29576373271, 13014.4143513544, 13059.738805224033, 13093.214407135221, 13173.47306107745, 9980.650677226262, 10296.536655850654, 10403.531841067019, 10694.62568810088, 10757.623483178051, 10374.429406382722, 10668.519916603616, 10669.35983840972, 10794.136469082612, 10912.181586380895, 9340.439573625605, 9346.53267336644, 9366.2038726709, 9456.905430945602, 9490.810183796148, 6772.695228714621, 6974.0, 6985.860282794417, 7076.221615622175, 7127.964360178166, 6194.504439644727, 6378.054693318397, 6790.287019862843, 6873.518853680194, 6955.443564514928], "value": 6406.937873701709, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.58176513785287, 496.02933381315256, 511.11066743865683, 512.0373697448796, 512.884600964783, 6213.347598502154, 6264.0, 6318.336574659664, 6338.33447488014, 6383.489320854332, 5961.850953726159, 6135.171751813504, 6162.352952939933, 6204.503639708823, 6223.595466294688, 4959.727215003173, 4962.330085438473, 4969.9751501242445, 5006.173981293082, 5008.12357837377, 4970.130227210224, 5033.84898453047, 5034.370703662043, 5053.21675140352, 5072.442031376564, 4752.334673145762, 4920.13897567926, 4926.211806111023, 4945.612255604904, 4991.675541089818, 4963.7021778693215, 4965.90068198634, 4966.417462919262, 4997.150484417658, 5022.924656130161, 4403.119376124788, 4419.9337009944875, 4435.514102775574, 4450.554944505556, 4478.798454069574, 6053.3946605339115, 6053.697315134225, 6152.3847615238565, 6167.167432396659, 6208.782692605782, 5688.544916406678, 5697.145428185764, 5709.286339207599, 5732.455416735404, 5793.710314484259, 6542.892126240045, 6612.974988876732, 6618.801435956929, 6638.5684930479365, 6692.665366731692, 4850.296706977504, 4897.265410188465, 4899.265110233458, 4906.57709264313, 4931.457539670633, 6980.930190698028, 7000.579965202104, 7035.0, 7059.788206353766, 7080.0, 5009.674371165863, 5019.84940451787, 5027.622928280393, 5028.296038554606, 5080.822171223989, 5006.424261210008, 5039.118154322979, 5065.366829146356, 5068.391792984792, 5081.2378143278065, 5756.1653560233535, 5756.856078598069, 5772.220750198753, 5797.797077102281, 5812.041013232769, 6600.900986485207, 6646.8005959821285, 6694.531382803157, 6726.831829204309, 6782.898256526156, 1639.88520803545, 1693.5427434592837, 1694.4916525042438, 1750.1774166141893, 1776.5025792778042, 1186.163754553038, 1194.7729931313022, 1209.2744353387898, 1212.660455072581, 1212.8423304970474, 5732.828015159552, 5776.0, 5799.768009279666, 5811.476967072984, 5842.269716285465, 6628.204615446178, 6771.796846094625, 6781.559198652072, 6812.48906332027, 6832.59004459724, 5716.514096301861, 5719.3136823581435, 5762.9135562966585, 5783.537317014713, 5817.0, 3566.465030245432, 3602.9459558106123, 3607.7294202934886, 3616.240589476187, 3671.1189314564313, 6501.934980650228, 6628.635425051572, 6656.706424356263, 6675.966620166868, 6679.298673639295, 6996.6851491683, 7008.649567521706, 7028.508004439743, 7101.715931362688, 7115.644217789193, 6593.637349945703, 6605.372489613479, 6642.435392991553, 6659.700313485905, 6713.0, 6861.55399899005, 6898.931010689829, 6911.3779759821855, 6912.688928998307, 6956.930430695628, 6679.799606011828, 6743.662816859235, 6810.467644779885, 6861.348171923659, 6870.141232345957, 6467.256265529524, 6497.675116244074, 6515.869682606227, 6538.967305163634, 6593.505487088583, 4926.989967056817, 4949.455559888337, 4952.529509696573, 5019.949800501949, 5054.721990290569, 6739.629320387426, 6871.209810871614, 6878.518503704692, 6905.1713794342995, 6973.6164510952385, 6541.6075035498025, 6645.3022432643875, 6651.667416629246, 6654.767083152066, 6687.264400915798, 6560.408638587826, 6569.7479747525285, 6651.73393064272, 6674.532782705164, 6681.0, 6252.843678907881, 6448.419642232222, 6476.31998640136, 6490.643014634241, 6614.206295244601, 6508.414242717989, 6510.674466276572, 6520.18497687789, 6536.248331441755, 6662.233843107911, 6376.74493020274, 6400.807975760735, 6431.163948686641, 6455.0640157176, 6472.546921715435], "value": 5087.1229173583215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [903.7288813355993, 921.6497730862271, 940.8447606144987, 941.9670311539097, 945.1399226703701, 16346.10096444695, 16845.578860528494, 16878.662426751456, 16883.324667013334, 17941.551461213476, 20673.586528269425, 21315.786842131572, 21327.0, 21479.140834366608, 21509.354719358445, 1008.7377281906702, 1020.7090979070966, 1020.8468729690532, 1030.0, 1030.0369154840248, 1059.0521483272466, 1063.0379506546587, 1065.0095411267519, 1065.3607835298797, 1068.834330678746, 1008.2236677758121, 1119.6920846767123, 1128.4752590045628, 1128.4921785196652, 1129.0290350298737, 1116.3246236027207, 1119.289251325406, 1121.966341009767, 1122.360254654847, 1122.3939072900623, 803.5821372886124, 804.0873608454447, 805.3879051920583, 806.9878951815728, 807.7334479621711, 17841.46475605734, 18640.906795465937, 18728.15723292455, 18768.155433005548, 19180.80819191804, 19743.40769776909, 20417.4895627609, 20805.687914681293, 20839.687404688942, 20859.687104693443, 14923.925380373137, 15847.841521584753, 16054.75917861233, 16118.838811611851, 16137.354505819752, 3409.215880347534, 3422.6577342265823, 3440.9483857742152, 3447.052060683307, 3447.5862896452336, 120787.0, 123587.0, 125478.37260813637, 127272.0, 129141.0, 16108.275127619285, 16376.099314537576, 16378.836211637969, 16417.58956026109, 16431.83568164303, 29131.41737165245, 30440.695593044227, 31219.68780312213, 31353.529697054564, 31395.37209255848, 8214.58927053645, 8229.506229626242, 8260.669573217125, 8275.172482751677, 8289.626966786509, 4122.9381559276635, 4245.681573881971, 4255.29787585045, 4261.31818908973, 4279.7218180818145, 5141.126008578551, 5292.0, 5307.52232299095, 5336.733163341817, 5350.197470379474, 972.3582435592616, 1007.0382784440841, 1019.7705516258783, 1020.4489575629226, 1023.7338292044122, 672.7847088931495, 674.908887300218, 688.6659969914588, 688.9517733758589, 703.5813690853934, 11110.888891110986, 11358.886411135949, 12053.457594408274, 12322.137450378566, 12468.0, 667.3214840992857, 680.7685386968454, 705.1186017478152, 723.989140162898, 724.7680742162457, 5265.973670131625, 5889.440503152194, 5910.940890591125, 6201.534884883652, 6347.841303967438, 103353.48323258336, 105087.4745626267, 107615.0, 107888.0, 111679.44160279309, 92537.53731231301, 94218.52890735502, 95238.0, 95474.52262738642, 96169.51915240519, 81142.0, 82493.58753206195, 83590.0, 84029.57985210035, 84748.0, 12006.159568830448, 12319.260844349368, 12379.814302785286, 12570.308633025272, 12603.432845521791, 36053.63946360503, 37826.0, 37866.24267514579, 38464.80767596144, 39270.803645981585, 46803.53196467992, 48743.0, 48947.755261223465, 50786.49213507817, 50830.74584627053, 18325.0, 18586.442406727278, 18590.907045464686, 18933.242670293035, 19108.61782764309, 20261.29085482001, 20579.58840823205, 20698.896505517376, 20773.376798696063, 21048.789512104682, 18918.243270269035, 18966.0, 19031.143598537823, 19046.523836904187, 19280.132394042583, 18617.90691046536, 18914.243430262635, 18934.810651893855, 19004.619907602046, 19133.33033343826, 16982.490525284262, 17001.40495082666, 17038.40365587248, 17145.399911003056, 17366.47900562985, 11969.760604788029, 12429.813552796535, 12546.247225166519, 12704.872951270369, 12756.043296752596, 12648.6837829055, 13068.934655326662, 13198.868011319764, 13267.800982985456, 14738.926305368404], "value": 9009.188666370103, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 101.0, 108.0, 108.0, 110.0, 116.0, 100.0, 107.0, 111.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 125.0, 143.0, 170.0, 118.0, 146.0, 150.0, 118.0, 174.0, 118.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 162.0, 163.0, 164.0, 163.0, 165.0, 170.0, 166.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 177.0, 76.0, 74.0, 75.0, 88.0, 96.0, 76.0], "value": 88.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 131.0, 80.0, 79.0, 79.0, 127.0, 79.0, 79.0, 128.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 124.0, 114.0, 116.0, 115.0, 114.0, 115.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 152.0, 150.0, 150.0, 150.0, 150.0, 150.0, 150.0, 150.0], "value": 150.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 98.0, 94.0, 94.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 57.0, 54.0, 54.0, 54.0, 55.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 78.0, 77.0, 82.0, 76.0, 78.0, 78.0, 90.0, 87.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 128.0, 125.0, 136.0, 136.0, 129.0, 129.0, 137.0, 178.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 176.0, 182.0, 176.0, 178.0, 175.0, 174.0, 175.0, 179.0, 176.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 76.0, 70.0, 67.0, 69.0, 66.0, 66.0, 65.0, 71.0], "value": 68.5, "unit": "score"}]}]} 14:38:00 INFO - Return code: 0 14:38:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:38:00 INFO - # TBPL SUCCESS # 14:38:00 INFO - Running post-action listener: _package_coverage_data 14:38:00 INFO - Running post-action listener: _resource_record_post_action 14:38:00 INFO - [mozharness: 2017-08-01 21:38:00.283882Z] Finished run-tests step (success) 14:38:00 INFO - Running post-run listener: _resource_record_post_run 14:38:00 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 436346880; Read time: 184; Write time: 303924 14:38:00 INFO - TinderboxPrint: CPU usage
13.1% 14:38:00 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 184 14:38:00 INFO - TinderboxPrint: I/O write bytes / time
436,346,880 / 303,924 14:38:00 INFO - TinderboxPrint: CPU idle
8,262.9 (86.9%) 14:38:00 INFO - TinderboxPrint: CPU user
1,200.6 (12.6%) 14:38:00 INFO - TinderboxPrint: Swap in / out
0 / 0 14:38:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116371456; Read time: 0; Write time: 115584 14:38:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:38:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 319975424; Read time: 184; Write time: 188340 14:38:00 INFO - Running post-run listener: _upload_blobber_files 14:38:00 INFO - Blob upload gear active. 14:38:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:38:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:38:01 INFO - (blobuploader) - INFO - Open directory for files ... 14:38:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:38:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:38:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:02 INFO - (blobuploader) - INFO - Done attempting. 14:38:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:38:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:09 INFO - (blobuploader) - INFO - Done attempting. 14:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:38:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:10 INFO - (blobuploader) - INFO - Done attempting. 14:38:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:38:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:38:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:11 INFO - (blobuploader) - INFO - Done attempting. 14:38:11 INFO - (blobuploader) - INFO - Iteration through files over. 14:38:11 INFO - Return code: 0 14:38:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:38:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:38:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815828271529d5dd5c9930552d69ed38618d3b22531165a72bdaf24ba5e691aafa894b58c01cdff48c79cf9cad19c422d0d87354758d9488c95e9fedc9f6e865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356a14693b3d62b7c703ddd73d44d50c85668b50a0cf07e955ec22a1292d8fce7010d302557f722ea0f1bca7d9d3999f40ca1130664320e4b76c392fc9c064df", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f2cf59a95d7ff3fc76da8c9ce6eb2c5b6a976a8893db87da11b7df86acaf59c2d9cff7ab34fa89ef93bad85f39fe1651f32bcd174db7a25be76822e79d7ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb8ff0d3a3d8a4789e6bb5c47c13dc685106f3f11fc9fb037e27a94f23a5459308d5c98e900a95958b85270fb94424f91936340a9fbadc0ded66d9d80608af76"} 14:38:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:38:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:38:11 INFO - Contents: 14:38:11 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815828271529d5dd5c9930552d69ed38618d3b22531165a72bdaf24ba5e691aafa894b58c01cdff48c79cf9cad19c422d0d87354758d9488c95e9fedc9f6e865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356a14693b3d62b7c703ddd73d44d50c85668b50a0cf07e955ec22a1292d8fce7010d302557f722ea0f1bca7d9d3999f40ca1130664320e4b76c392fc9c064df", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f2cf59a95d7ff3fc76da8c9ce6eb2c5b6a976a8893db87da11b7df86acaf59c2d9cff7ab34fa89ef93bad85f39fe1651f32bcd174db7a25be76822e79d7ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb8ff0d3a3d8a4789e6bb5c47c13dc685106f3f11fc9fb037e27a94f23a5459308d5c98e900a95958b85270fb94424f91936340a9fbadc0ded66d9d80608af76"} 14:38:11 INFO - Running post-run listener: copy_logs_to_upload_dir 14:38:11 INFO - Copying logs to upload dir... 14:38:11 INFO - mkdir: /builds/slave/test/build/upload/logs 14:38:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.345888 ========= master_lag: -0.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-01 14:38:10.467045) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-01 14:38:10.472067) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815828271529d5dd5c9930552d69ed38618d3b22531165a72bdaf24ba5e691aafa894b58c01cdff48c79cf9cad19c422d0d87354758d9488c95e9fedc9f6e865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356a14693b3d62b7c703ddd73d44d50c85668b50a0cf07e955ec22a1292d8fce7010d302557f722ea0f1bca7d9d3999f40ca1130664320e4b76c392fc9c064df", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f2cf59a95d7ff3fc76da8c9ce6eb2c5b6a976a8893db87da11b7df86acaf59c2d9cff7ab34fa89ef93bad85f39fe1651f32bcd174db7a25be76822e79d7ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb8ff0d3a3d8a4789e6bb5c47c13dc685106f3f11fc9fb037e27a94f23a5459308d5c98e900a95958b85270fb94424f91936340a9fbadc0ded66d9d80608af76"} build_url:https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/Q3D2GEEATdOj72Ov3q5KTg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/815828271529d5dd5c9930552d69ed38618d3b22531165a72bdaf24ba5e691aafa894b58c01cdff48c79cf9cad19c422d0d87354758d9488c95e9fedc9f6e865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356a14693b3d62b7c703ddd73d44d50c85668b50a0cf07e955ec22a1292d8fce7010d302557f722ea0f1bca7d9d3999f40ca1130664320e4b76c392fc9c064df", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/886f2cf59a95d7ff3fc76da8c9ce6eb2c5b6a976a8893db87da11b7df86acaf59c2d9cff7ab34fa89ef93bad85f39fe1651f32bcd174db7a25be76822e79d7ad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb8ff0d3a3d8a4789e6bb5c47c13dc685106f3f11fc9fb037e27a94f23a5459308d5c98e900a95958b85270fb94424f91936340a9fbadc0ded66d9d80608af76"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-01 14:38:10.502507) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:38:10.502887) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9bfb7e13875ed353a705960e000001c0-1501622232.735502-266746498 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-01 14:38:10.584770) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-01 14:38:10.585148) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-01 14:38:10.585508) ========= ========= Total master_lag: -0.15 =========