builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-028 starttime: 1501723292.72 results: success (0) revision: 6f1914a4f241b8ac62953de069296397b7645cd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:32.719391) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:32.719846) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:32.720398) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:32.777449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:32.777770) ========= 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=836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 18:21:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2017-08-02 18:21:33 (43.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.203938 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:33.016356) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:33.016691) ========= 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=836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015121 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:33.065980) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-02 18:21:33.067344) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f1914a4f241b8ac62953de069296397b7645cd1 --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 6f1914a4f241b8ac62953de069296397b7645cd1 --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=836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 18:21:33,571 truncating revision to first 12 chars 2017-08-02 18:21:33,571 Setting DEBUG logging. 2017-08-02 18:21:33,571 attempt 1/10 2017-08-02 18:21:33,571 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f1914a4f241?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 18:21:34,112 attempt 1/10 2017-08-02 18:21:34,700 current task status: no status available at this point. state: STARTED 2017-08-02 18:21:34,701 sleeping for 10.00s (attempt 1/10) 2017-08-02 18:21:44,711 attempt 2/10 2017-08-02 18:21:45,046 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-02 18:21:45,320 unpacking tar archive at: mozilla-central-6f1914a4f241/testing/mozharness/ program finished with exit code 0 elapsedTime=12.179144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-02 18:21:45.263929) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:45.267256) ========= script_repo_revision: 6f1914a4f241b8ac62953de069296397b7645cd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:45.267616) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:45.267872) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 18:21:45.275591) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-02 18:21:45.275852) ========= /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=836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:21:45 INFO - MultiFileLogger online at 20170802 18:21:45 in /builds/slave/test 18:21:45 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 18:21:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:21:45 INFO - {'append_to_log': False, 18:21:45 INFO - 'base_work_dir': '/builds/slave/test', 18:21:45 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:21:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:21:45 INFO - 'branch': 'Firefox-Non-PGO', 18:21:45 INFO - 'buildbot_json_path': 'buildprops.json', 18:21:45 INFO - 'code_coverage': False, 18:21:45 INFO - 'config_files': ('talos/linux_config.py',), 18:21:45 INFO - 'default_actions': ('clobber', 18:21:45 INFO - 'read-buildbot-config', 18:21:45 INFO - 'download-and-extract', 18:21:45 INFO - 'populate-webroot', 18:21:45 INFO - 'create-virtualenv', 18:21:45 INFO - 'install', 18:21:45 INFO - 'setup-mitmproxy', 18:21:45 INFO - 'run-tests'), 18:21:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:21:45 INFO - 'disable_ccov_upload': False, 18:21:45 INFO - 'download_minidump_stackwalk': True, 18:21:45 INFO - 'download_symbols': 'ondemand', 18:21:45 INFO - 'enable_stylo': False, 18:21:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:21:45 INFO - 'tooltool.py': '/tools/tooltool.py', 18:21:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:21:45 INFO - '/tools/misc-python/virtualenv.py')}, 18:21:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:21:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:21:45 INFO - 'gecko_profile': False, 18:21:45 INFO - 'gecko_profile_interval': 0, 18:21:45 INFO - 'installer_path': 'installer.exe', 18:21:45 INFO - 'log_level': 'info', 18:21:45 INFO - 'log_name': 'talos', 18:21:45 INFO - 'log_to_console': True, 18:21:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:21:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:21:45 INFO - 'opt_config_files': (), 18:21:45 INFO - 'pip_index': False, 18:21:45 INFO - 'suite': 'dromaeojs-e10s', 18:21:45 INFO - 'system_bits': '32', 18:21:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:21:45 INFO - 'title': 'talos-linux64-ix-028', 18:21:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:21:45 INFO - 'use_talos_json': True, 18:21:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:21:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:21:45 INFO - 'work_dir': 'build'} 18:21:45 INFO - [mozharness: 2017-08-03 01:21:45.960673Z] Running clobber step. 18:21:45 INFO - Running pre-action listener: _resource_record_pre_action 18:21:45 INFO - Running main action method: clobber 18:21:45 INFO - rmtree: /builds/slave/test/build 18:21:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:21:46 INFO - Running post-action listener: _resource_record_post_action 18:21:46 INFO - [mozharness: 2017-08-03 01:21:46.935968Z] Finished clobber step (success) 18:21:46 INFO - [mozharness: 2017-08-03 01:21:46.936070Z] Running read-buildbot-config step. 18:21:46 INFO - Running pre-action listener: _resource_record_pre_action 18:21:46 INFO - Running main action method: read_buildbot_config 18:21:46 INFO - Using buildbot properties: 18:21:46 INFO - { 18:21:46 INFO - "project": "", 18:21:46 INFO - "product": "firefox", 18:21:46 INFO - "who": "kwierso@gmail.com", 18:21:46 INFO - "installer_path": "public/build/target.tar.bz2", 18:21:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:21:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:21:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:21:46 INFO - "stage_platform": "linux64", 18:21:46 INFO - "basedir": "/builds/slave/test", 18:21:46 INFO - "buildnumber": 754, 18:21:46 INFO - "platform": "ubuntu64_hw", 18:21:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:21:46 INFO - "slavebuilddir": "test", 18:21:46 INFO - "taskId": "Epi568hpQAa_HJKMNbyC7g", 18:21:46 INFO - "branch": "mozilla-central", 18:21:46 INFO - "script_repo_revision": "production", 18:21:46 INFO - "revision": "6f1914a4f241b8ac62953de069296397b7645cd1", 18:21:46 INFO - "slavename": "talos-linux64-ix-028", 18:21:46 INFO - "repo_path": "mozilla-central" 18:21:46 INFO - } 18:21:46 INFO - Finding installer, test and symbols from parent task. 18:21:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Epi568hpQAa_HJKMNbyC7g'}, attempt #1 18:21:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Epi568hpQAa_HJKMNbyC7g'}, attempt #1 18:21:47 INFO - Task dependencies: RZ_IcetKSemmvmdM-9uhXQ 18:21:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ'}, attempt #1 18:21:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:21:48 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json 18:21:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip 18:21:48 INFO - Running post-action listener: _resource_record_post_action 18:21:48 INFO - [mozharness: 2017-08-03 01:21:48.303575Z] Finished read-buildbot-config step (success) 18:21:48 INFO - [mozharness: 2017-08-03 01:21:48.303798Z] Running download-and-extract step. 18:21:48 INFO - Running pre-action listener: _resource_record_pre_action 18:21:48 INFO - Running main action method: download_and_extract 18:21:48 INFO - mkdir: /builds/slave/test/build/tests 18:21:48 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')]} 18:21:48 INFO - trying https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json 18:21:48 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:21:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:21:48 INFO - Downloaded 989 bytes. 18:21:48 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:21:48 INFO - Using the following test package requirements: 18:21:48 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:21:48 INFO - u'common': [u'target.common.tests.zip'], 18:21:48 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:21:48 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:21:48 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:21:48 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:21:48 INFO - u'mozbase': [u'target.common.tests.zip'], 18:21:48 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:21:48 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:21:48 INFO - u'web-platform': [u'target.common.tests.zip', 18:21:48 INFO - u'target.web-platform.tests.tar.gz'], 18:21:48 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:21:48 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:21:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip 18:21:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:21:48 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip into memory 18:21:49 INFO - Content-Length response header: 54278304 18:21:49 INFO - Bytes received: 54278304 18:22:01 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:22:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip 18:22:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:22:01 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip into memory 18:22:02 INFO - Content-Length response header: 54278304 18:22:02 INFO - Bytes received: 54278304 18:22:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.talos.tests.zip 18:22:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:22:14 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.talos.tests.zip into memory 18:22:15 INFO - Content-Length response header: 14496464 18:22:15 INFO - Bytes received: 14496464 18:22:15 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')]} 18:22:15 INFO - trying https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:22:15 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:22:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:22:16 INFO - Downloaded 62233195 bytes. 18:22:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:22:16 INFO - mkdir: /builds/slave/test/properties 18:22:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:16 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:16 INFO - Contents: 18:22:16 INFO - build_url:https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:22:16 INFO - Running post-action listener: _resource_record_post_action 18:22:16 INFO - Running post-action listener: set_extra_try_arguments 18:22:16 INFO - [mozharness: 2017-08-03 01:22:16.607357Z] Finished download-and-extract step (success) 18:22:16 INFO - [mozharness: 2017-08-03 01:22:16.607456Z] Running populate-webroot step. 18:22:16 INFO - Running pre-action listener: _resource_record_pre_action 18:22:16 INFO - Running main action method: populate_webroot 18:22:16 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:22:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:22:16 INFO - 'tests': ['damp', 'tps']}, 18:22:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:22:16 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:22:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:22:16 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:16 INFO - 'ts_paint', 18:22:16 INFO - 'tpaint', 18:22:16 INFO - 'sessionrestore', 18:22:16 INFO - 'sessionrestore_many_windows', 18:22:16 INFO - 'sessionrestore_no_auto_restore', 18:22:16 INFO - 'tabpaint']}, 18:22:16 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:22:16 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:22:16 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:22:16 INFO - 'talos_options': ['--mitmproxy', 18:22:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:22:16 INFO - '--firstNonBlankPaint'], 18:22:16 INFO - 'tests': ['quantum_pageload_google', 18:22:16 INFO - 'quantum_pageload_youtube', 18:22:16 INFO - 'quantum_pageload_amazon', 18:22:16 INFO - 'quantum_pageload_facebook']}, 18:22:16 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:22:16 INFO - 'talos_options': ['--stylo', 18:22:16 INFO - '--mitmproxy', 18:22:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:22:16 INFO - '--firstNonBlankPaint'], 18:22:16 INFO - 'tests': ['quantum_pageload_google', 18:22:16 INFO - 'quantum_pageload_youtube', 18:22:16 INFO - 'quantum_pageload_amazon', 18:22:16 INFO - 'quantum_pageload_facebook']}, 18:22:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:16 INFO - 'tsvgr_opacity', 18:22:16 INFO - 'tart', 18:22:16 INFO - 'tscrollx', 18:22:16 INFO - 'cart', 18:22:16 INFO - 'tsvg_static']}, 18:22:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:22:16 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:22:16 INFO - 'talos_options': ['--stylo', 18:22:16 INFO - '--stylo-threads=1', 18:22:16 INFO - '--mitmproxy', 18:22:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:22:16 INFO - '--firstNonBlankPaint'], 18:22:16 INFO - 'tests': ['tp6_google', 18:22:16 INFO - 'tp6_youtube', 18:22:16 INFO - 'tp6_amazon', 18:22:16 INFO - 'tp6_facebook']}, 18:22:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:22:16 INFO - 'talos_options': ['--xperf_path', 18:22:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:16 INFO - 'tests': ['tp5n']}}} 18:22:16 INFO - Running post-action listener: _resource_record_post_action 18:22:16 INFO - [mozharness: 2017-08-03 01:22:16.616125Z] Finished populate-webroot step (success) 18:22:16 INFO - [mozharness: 2017-08-03 01:22:16.616215Z] Running create-virtualenv step. 18:22:16 INFO - Running pre-action listener: _resource_record_pre_action 18:22:16 INFO - Running main action method: create_virtualenv 18:22:16 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:16 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 18:22:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:16 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:22:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:16 INFO - Using real prefix '/usr' 18:22:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:17 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:19 INFO - Installing pip.................done. 18:22:19 INFO - Return code: 0 18:22:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:22:19 INFO - Reading from file tmpfile_stdout 18:22:19 INFO - Output received: 18:22:19 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) 18:22:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:19 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')]} 18:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:19 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')]} 18:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:19 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:19 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 18:22:19 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 18:22:19 INFO - Using env: {'DISPLAY': ':0', 18:22:19 INFO - 'HOME': '/home/cltbld', 18:22:19 INFO - 'LANG': 'en_US.UTF-8', 18:22:19 INFO - 'LANGUAGE': 'en_US:en', 18:22:19 INFO - 'LOGNAME': 'cltbld', 18:22:19 INFO - 'MAIL': '/var/mail/cltbld', 18:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:19 INFO - 'MOZ_NO_REMOTE': '1', 18:22:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:19 INFO - 'NO_EM_RESTART': '1', 18:22:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:19 INFO - 'PWD': '/builds/slave/test', 18:22:19 INFO - 'SHELL': '/bin/bash', 18:22:19 INFO - 'SHLVL': '1', 18:22:19 INFO - 'TERM': 'linux', 18:22:19 INFO - 'TMOUT': '86400', 18:22:19 INFO - 'USER': 'cltbld', 18:22:19 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:19 INFO - '_': '/tools/buildbot/bin/python'} 18:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:19 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 18:22:19 INFO - Cleaning up... 18:22:19 INFO - Return code: 0 18:22:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:19 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')]} 18:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:19 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')]} 18:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:19 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:19 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 18:22:19 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 18:22:19 INFO - Using env: {'DISPLAY': ':0', 18:22:19 INFO - 'HOME': '/home/cltbld', 18:22:19 INFO - 'LANG': 'en_US.UTF-8', 18:22:19 INFO - 'LANGUAGE': 'en_US:en', 18:22:19 INFO - 'LOGNAME': 'cltbld', 18:22:19 INFO - 'MAIL': '/var/mail/cltbld', 18:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:19 INFO - 'MOZ_NO_REMOTE': '1', 18:22:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:19 INFO - 'NO_EM_RESTART': '1', 18:22:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:19 INFO - 'PWD': '/builds/slave/test', 18:22:19 INFO - 'SHELL': '/bin/bash', 18:22:19 INFO - 'SHLVL': '1', 18:22:19 INFO - 'TERM': 'linux', 18:22:19 INFO - 'TMOUT': '86400', 18:22:19 INFO - 'USER': 'cltbld', 18:22:19 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:19 INFO - '_': '/tools/buildbot/bin/python'} 18:22:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:20 INFO - Downloading/unpacking psutil>=3.1.1 18:22:20 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 18:22:20 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 18:22:20 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 18:22:20 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 18:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:21 INFO - Installing collected packages: psutil 18:22:21 INFO - Running setup.py install for psutil 18:22:21 INFO - building 'psutil._psutil_linux' extension 18:22:21 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 18:22:22 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 18:22:22 INFO - building 'psutil._psutil_posix' extension 18:22:22 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 18:22:22 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 18:22:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:22 INFO - Successfully installed psutil 18:22:22 INFO - Cleaning up... 18:22:22 INFO - Return code: 0 18:22:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22:22 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')]} 18:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:22 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')]} 18:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:22 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:22 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 18:22:22 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 18:22:22 INFO - Using env: {'DISPLAY': ':0', 18:22:22 INFO - 'HOME': '/home/cltbld', 18:22:22 INFO - 'LANG': 'en_US.UTF-8', 18:22:22 INFO - 'LANGUAGE': 'en_US:en', 18:22:22 INFO - 'LOGNAME': 'cltbld', 18:22:22 INFO - 'MAIL': '/var/mail/cltbld', 18:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:22 INFO - 'MOZ_NO_REMOTE': '1', 18:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:22 INFO - 'NO_EM_RESTART': '1', 18:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:22 INFO - 'PWD': '/builds/slave/test', 18:22:22 INFO - 'SHELL': '/bin/bash', 18:22:22 INFO - 'SHLVL': '1', 18:22:22 INFO - 'TERM': 'linux', 18:22:22 INFO - 'TMOUT': '86400', 18:22:22 INFO - 'USER': 'cltbld', 18:22:22 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:22 INFO - '_': '/tools/buildbot/bin/python'} 18:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:22 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 18:22:22 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 18:22:22 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 18:22:22 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 18:22:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:24 INFO - Installing collected packages: mozsystemmonitor 18:22:24 INFO - Running setup.py install for mozsystemmonitor 18:22:24 INFO - Successfully installed mozsystemmonitor 18:22:24 INFO - Cleaning up... 18:22:24 INFO - Return code: 0 18:22:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22:24 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')]} 18:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:24 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')]} 18:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:24 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:24 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 18:22:24 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 18:22:24 INFO - Using env: {'DISPLAY': ':0', 18:22:24 INFO - 'HOME': '/home/cltbld', 18:22:24 INFO - 'LANG': 'en_US.UTF-8', 18:22:24 INFO - 'LANGUAGE': 'en_US:en', 18:22:24 INFO - 'LOGNAME': 'cltbld', 18:22:24 INFO - 'MAIL': '/var/mail/cltbld', 18:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:24 INFO - 'MOZ_NO_REMOTE': '1', 18:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:24 INFO - 'NO_EM_RESTART': '1', 18:22:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:24 INFO - 'PWD': '/builds/slave/test', 18:22:24 INFO - 'SHELL': '/bin/bash', 18:22:24 INFO - 'SHLVL': '1', 18:22:24 INFO - 'TERM': 'linux', 18:22:24 INFO - 'TMOUT': '86400', 18:22:24 INFO - 'USER': 'cltbld', 18:22:24 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:24 INFO - '_': '/tools/buildbot/bin/python'} 18:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:24 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:24 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 18:22:24 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 18:22:24 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 18:22:24 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 18:22:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:22:25 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 18:22:25 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 18:22:25 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 18:22:25 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 18:22:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:25 INFO - warning: no files found matching '*.txt' 18:22:25 INFO - no previously-included directories found matching 'build' 18:22:25 INFO - no previously-included directories found matching 'dist' 18:22:25 INFO - no previously-included directories found matching '.git*' 18:22:25 INFO - Installing collected packages: jsonschema, functools32 18:22:25 INFO - Running setup.py install for functools32 18:22:25 INFO - warning: no files found matching '*.txt' 18:22:25 INFO - no previously-included directories found matching 'build' 18:22:25 INFO - no previously-included directories found matching 'dist' 18:22:25 INFO - no previously-included directories found matching '.git*' 18:22:25 INFO - Successfully installed jsonschema functools32 18:22:25 INFO - Cleaning up... 18:22:25 INFO - Return code: 0 18:22:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22: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')]} 18:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:25 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:25 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 18:22:25 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 18:22:25 INFO - Using env: {'DISPLAY': ':0', 18:22:25 INFO - 'HOME': '/home/cltbld', 18:22:25 INFO - 'LANG': 'en_US.UTF-8', 18:22:25 INFO - 'LANGUAGE': 'en_US:en', 18:22:25 INFO - 'LOGNAME': 'cltbld', 18:22:25 INFO - 'MAIL': '/var/mail/cltbld', 18:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:25 INFO - 'MOZ_NO_REMOTE': '1', 18:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:25 INFO - 'NO_EM_RESTART': '1', 18:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:25 INFO - 'PWD': '/builds/slave/test', 18:22:25 INFO - 'SHELL': '/bin/bash', 18:22:25 INFO - 'SHLVL': '1', 18:22:25 INFO - 'TERM': 'linux', 18:22:25 INFO - 'TMOUT': '86400', 18:22:25 INFO - 'USER': 'cltbld', 18:22:25 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:25 INFO - '_': '/tools/buildbot/bin/python'} 18:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:22:26 INFO - Cleaning up... 18:22:26 INFO - Return code: 0 18:22:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:26 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')]} 18:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:26 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')]} 18:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:26 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:26 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 18:22:26 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 18:22:26 INFO - Using env: {'DISPLAY': ':0', 18:22:26 INFO - 'HOME': '/home/cltbld', 18:22:26 INFO - 'LANG': 'en_US.UTF-8', 18:22:26 INFO - 'LANGUAGE': 'en_US:en', 18:22:26 INFO - 'LOGNAME': 'cltbld', 18:22:26 INFO - 'MAIL': '/var/mail/cltbld', 18:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:26 INFO - 'MOZ_NO_REMOTE': '1', 18:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:26 INFO - 'NO_EM_RESTART': '1', 18:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:26 INFO - 'PWD': '/builds/slave/test', 18:22:26 INFO - 'SHELL': '/bin/bash', 18:22:26 INFO - 'SHLVL': '1', 18:22:26 INFO - 'TERM': 'linux', 18:22:26 INFO - 'TMOUT': '86400', 18:22:26 INFO - 'USER': 'cltbld', 18:22:26 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:26 INFO - '_': '/tools/buildbot/bin/python'} 18:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:26 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:26 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 18:22:26 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 18:22:26 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 18:22:26 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 18:22:27 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:27 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 18:22:27 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 18:22:27 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 18:22:27 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 18:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:27 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 18:22:27 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 18:22:27 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 18:22:27 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 18:22:27 INFO - Downloading docopt-0.6.1.tar.gz 18:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:28 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:28 INFO - Running setup.py install for blobuploader 18:22:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:28 INFO - Running setup.py install for requests 18:22:28 INFO - Running setup.py install for docopt 18:22:28 INFO - Successfully installed blobuploader requests docopt 18:22:28 INFO - Cleaning up... 18:22:28 INFO - Return code: 0 18:22:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:28 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')]} 18:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:28 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')]} 18:22:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:28 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:28 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 18:22:28 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 18:22:28 INFO - Using env: {'DISPLAY': ':0', 18:22:28 INFO - 'HOME': '/home/cltbld', 18:22:28 INFO - 'LANG': 'en_US.UTF-8', 18:22:28 INFO - 'LANGUAGE': 'en_US:en', 18:22:28 INFO - 'LOGNAME': 'cltbld', 18:22:28 INFO - 'MAIL': '/var/mail/cltbld', 18:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:28 INFO - 'MOZ_NO_REMOTE': '1', 18:22:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:28 INFO - 'NO_EM_RESTART': '1', 18:22:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:28 INFO - 'PWD': '/builds/slave/test', 18:22:28 INFO - 'SHELL': '/bin/bash', 18:22:28 INFO - 'SHLVL': '1', 18:22:28 INFO - 'TERM': 'linux', 18:22:28 INFO - 'TMOUT': '86400', 18:22:28 INFO - 'USER': 'cltbld', 18:22:28 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:28 INFO - '_': '/tools/buildbot/bin/python'} 18:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:28 INFO - Running setup.py (path:/tmp/pip-xamssb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:28 INFO - Running setup.py (path:/tmp/pip-8E7n5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:28 INFO - Running setup.py (path:/tmp/pip-X9_KV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:29 INFO - Running setup.py (path:/tmp/pip-ADTGIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:29 INFO - Running setup.py (path:/tmp/pip-ZPZ7d1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:29 INFO - Running setup.py (path:/tmp/pip-7qDwo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:29 INFO - Running setup.py (path:/tmp/pip-_7PDIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:29 INFO - Running setup.py (path:/tmp/pip-pDq3VD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:29 INFO - Running setup.py (path:/tmp/pip-QkZSpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:29 INFO - Running setup.py (path:/tmp/pip-Unnjka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:29 INFO - Running setup.py (path:/tmp/pip-z6NUW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:29 INFO - Running setup.py (path:/tmp/pip-XyvKW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:29 INFO - Running setup.py (path:/tmp/pip-UKBafJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:29 INFO - Running setup.py (path:/tmp/pip-uszxTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:29 INFO - Running setup.py (path:/tmp/pip-PBoaMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:29 INFO - Running setup.py (path:/tmp/pip-3APNa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:29 INFO - Running setup.py (path:/tmp/pip-N7CRf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:30 INFO - Running setup.py install for manifestparser 18:22:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Running setup.py install for mozcrash 18:22:30 INFO - Running setup.py install for mozdebug 18:22:30 INFO - Running setup.py install for mozdevice 18:22:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Running setup.py install for mozfile 18:22:30 INFO - Running setup.py install for mozhttpd 18:22:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Running setup.py install for mozinfo 18:22:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Running setup.py install for mozInstall 18:22:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:30 INFO - Running setup.py install for mozleak 18:22:30 INFO - Running setup.py install for mozlog 18:22:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:31 INFO - Running setup.py install for moznetwork 18:22:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:31 INFO - Running setup.py install for mozprocess 18:22:31 INFO - Running setup.py install for mozprofile 18:22:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:31 INFO - Running setup.py install for mozrunner 18:22:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:31 INFO - Running setup.py install for mozscreenshot 18:22:31 INFO - Running setup.py install for moztest 18:22:31 INFO - Running setup.py install for mozversion 18:22:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:31 INFO - Cleaning up... 18:22:31 INFO - Return code: 0 18:22:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:31 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')]} 18:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:31 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')]} 18:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:31 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:31 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 18:22:31 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 18:22:31 INFO - Using env: {'DISPLAY': ':0', 18:22:31 INFO - 'HOME': '/home/cltbld', 18:22:31 INFO - 'LANG': 'en_US.UTF-8', 18:22:31 INFO - 'LANGUAGE': 'en_US:en', 18:22:31 INFO - 'LOGNAME': 'cltbld', 18:22:31 INFO - 'MAIL': '/var/mail/cltbld', 18:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:31 INFO - 'MOZ_NO_REMOTE': '1', 18:22:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:31 INFO - 'NO_EM_RESTART': '1', 18:22:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:31 INFO - 'PWD': '/builds/slave/test', 18:22:31 INFO - 'SHELL': '/bin/bash', 18:22:31 INFO - 'SHLVL': '1', 18:22:31 INFO - 'TERM': 'linux', 18:22:31 INFO - 'TMOUT': '86400', 18:22:31 INFO - 'USER': 'cltbld', 18:22:31 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:31 INFO - '_': '/tools/buildbot/bin/python'} 18:22:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:32 INFO - Running setup.py (path:/tmp/pip-TxVT6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:32 INFO - Running setup.py (path:/tmp/pip-G1FRX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:32 INFO - Running setup.py (path:/tmp/pip-qDVXp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:32 INFO - Running setup.py (path:/tmp/pip-uh001A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:32 INFO - Running setup.py (path:/tmp/pip-RM0aMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:32 INFO - Running setup.py (path:/tmp/pip-2cw8gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:32 INFO - Running setup.py (path:/tmp/pip-0XhbgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:32 INFO - Running setup.py (path:/tmp/pip-GS9xqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:32 INFO - Running setup.py (path:/tmp/pip-O8kFUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:32 INFO - Running setup.py (path:/tmp/pip-McprKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:32 INFO - Running setup.py (path:/tmp/pip-qG0O_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:32 INFO - Running setup.py (path:/tmp/pip-7V2qSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:32 INFO - Running setup.py (path:/tmp/pip-tReixy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:32 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)) 18:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:33 INFO - Running setup.py (path:/tmp/pip-ZM_Fjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:33 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)) 18:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:33 INFO - Running setup.py (path:/tmp/pip-mguL1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:33 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)) 18:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:33 INFO - Running setup.py (path:/tmp/pip-m4TSn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:33 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)) 18:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:33 INFO - Running setup.py (path:/tmp/pip-DT2Jg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:33 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)) 18:22:33 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)) 18:22:33 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)) 18:22:33 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)) 18:22:33 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)) 18:22:33 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)) 18:22:33 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)) 18:22:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:33 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 18:22:33 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 18:22:33 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 18:22:33 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 18:22:34 INFO - Downloading blessings-1.6.tar.gz 18:22:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:34 INFO - Installing collected packages: blessings 18:22:34 INFO - Running setup.py install for blessings 18:22:34 INFO - Successfully installed blessings 18:22:34 INFO - Cleaning up... 18:22:34 INFO - Return code: 0 18:22:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:34 INFO - Reading from file tmpfile_stdout 18:22:34 INFO - Current package versions: 18:22:34 INFO - argparse == 1.2.1 18:22:34 INFO - blessings == 1.6 18:22:34 INFO - blobuploader == 1.2.4 18:22:34 INFO - docopt == 0.6.1 18:22:34 INFO - functools32 == 3.2.3-2 18:22:34 INFO - jsonschema == 2.5.1 18:22:34 INFO - manifestparser == 1.1 18:22:34 INFO - mozInstall == 1.13 18:22:34 INFO - mozcrash == 1.0 18:22:34 INFO - mozdebug == 0.1 18:22:34 INFO - mozdevice == 0.51 18:22:34 INFO - mozfile == 1.2 18:22:34 INFO - mozhttpd == 0.7 18:22:34 INFO - mozinfo == 0.10 18:22:34 INFO - mozleak == 0.1 18:22:34 INFO - mozlog == 3.5 18:22:34 INFO - moznetwork == 0.27 18:22:34 INFO - mozprocess == 0.25 18:22:34 INFO - mozprofile == 0.28 18:22:34 INFO - mozrunner == 6.13 18:22:34 INFO - mozscreenshot == 0.1 18:22:34 INFO - mozsystemmonitor == 0.3 18:22:34 INFO - moztest == 0.8 18:22:34 INFO - mozversion == 1.4 18:22:34 INFO - psutil == 3.1.1 18:22:34 INFO - requests == 1.2.3 18:22:34 INFO - wsgiref == 0.1.2 18:22:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:34 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')]} 18:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:34 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')]} 18:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:34 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:34 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 18:22:34 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 18:22:34 INFO - Using env: {'DISPLAY': ':0', 18:22:34 INFO - 'HOME': '/home/cltbld', 18:22:34 INFO - 'LANG': 'en_US.UTF-8', 18:22:34 INFO - 'LANGUAGE': 'en_US:en', 18:22:34 INFO - 'LOGNAME': 'cltbld', 18:22:34 INFO - 'MAIL': '/var/mail/cltbld', 18:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:34 INFO - 'MOZ_NO_REMOTE': '1', 18:22:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:34 INFO - 'NO_EM_RESTART': '1', 18:22:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:34 INFO - 'PWD': '/builds/slave/test', 18:22:34 INFO - 'SHELL': '/bin/bash', 18:22:34 INFO - 'SHLVL': '1', 18:22:34 INFO - 'TERM': 'linux', 18:22:34 INFO - 'TMOUT': '86400', 18:22:34 INFO - 'USER': 'cltbld', 18:22:34 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:34 INFO - '_': '/tools/buildbot/bin/python'} 18:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:35 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)) 18:22:35 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)) 18:22:35 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)) 18:22:35 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)) 18:22:35 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)) 18:22:35 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)) 18:22:35 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)) 18:22:35 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)) 18:22:35 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)) 18:22:35 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:22:35 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 18:22:35 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 18:22:35 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 18:22:35 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 18:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:22:36 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)) 18:22:36 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)) 18:22:36 INFO - Installing collected packages: simplejson 18:22:36 INFO - Running setup.py install for simplejson 18:22:36 INFO - building 'simplejson._speedups' extension 18:22:36 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 18:22:37 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 18:22:37 INFO - Successfully installed simplejson 18:22:37 INFO - Cleaning up... 18:22:37 INFO - Return code: 0 18:22:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:22:37 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')]} 18:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:37 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')]} 18:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:37 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 0x17181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a88d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, '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': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', '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 18:22:37 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 18:22:37 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 18:22:37 INFO - Using env: {'DISPLAY': ':0', 18:22:37 INFO - 'HOME': '/home/cltbld', 18:22:37 INFO - 'LANG': 'en_US.UTF-8', 18:22:37 INFO - 'LANGUAGE': 'en_US:en', 18:22:37 INFO - 'LOGNAME': 'cltbld', 18:22:37 INFO - 'MAIL': '/var/mail/cltbld', 18:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:37 INFO - 'MOZ_NO_REMOTE': '1', 18:22:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:37 INFO - 'NO_EM_RESTART': '1', 18:22:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:37 INFO - 'PWD': '/builds/slave/test', 18:22:37 INFO - 'SHELL': '/bin/bash', 18:22:37 INFO - 'SHLVL': '1', 18:22:37 INFO - 'TERM': 'linux', 18:22:37 INFO - 'TMOUT': '86400', 18:22:37 INFO - 'USER': 'cltbld', 18:22:37 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:37 INFO - '_': '/tools/buildbot/bin/python'} 18:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:22:37 INFO - Cleaning up... 18:22:37 INFO - Return code: 0 18:22:37 INFO - Running post-action listener: _resource_record_post_action 18:22:37 INFO - Running post-action listener: _start_resource_monitoring 18:22:37 INFO - Starting resource monitoring. 18:22:37 INFO - [mozharness: 2017-08-03 01:22:37.622399Z] Finished create-virtualenv step (success) 18:22:37 INFO - [mozharness: 2017-08-03 01:22:37.623404Z] Running install step. 18:22:37 INFO - Running pre-action listener: _resource_record_pre_action 18:22:37 INFO - Running main action method: install 18:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:37 INFO - Reading from file tmpfile_stdout 18:22:37 INFO - Detecting whether we're running mozinstall >=1.0... 18:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:22:37 INFO - Reading from file tmpfile_stdout 18:22:37 INFO - Output received: 18:22:37 INFO - Usage: mozinstall [options] installer 18:22:37 INFO - Options: 18:22:37 INFO - -h, --help show this help message and exit 18:22:37 INFO - -d DEST, --destination=DEST 18:22:37 INFO - Directory to install application into. [default: 18:22:37 INFO - "/builds/slave/test"] 18:22:37 INFO - --app=APP Application being installed. [default: firefox] 18:22:37 INFO - mkdir: /builds/slave/test/build/application 18:22:37 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'] 18:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:22:56 INFO - Reading from file tmpfile_stdout 18:22:56 INFO - Output received: 18:22:56 INFO - /builds/slave/test/build/application/firefox/firefox 18:22:56 INFO - Running post-action listener: _resource_record_post_action 18:22:56 INFO - [mozharness: 2017-08-03 01:22:56.023577Z] Finished install step (success) 18:22:56 INFO - [mozharness: 2017-08-03 01:22:56.023728Z] Running setup-mitmproxy step. 18:22:56 INFO - Running pre-action listener: _resource_record_pre_action 18:22:56 INFO - Running main action method: setup_mitmproxy 18:22:56 INFO - Skipping: mitmproxy is not required 18:22:56 INFO - Running post-action listener: _resource_record_post_action 18:22:56 INFO - [mozharness: 2017-08-03 01:22:56.024251Z] Finished setup-mitmproxy step (success) 18:22:56 INFO - [mozharness: 2017-08-03 01:22:56.024354Z] Running run-tests step. 18:22:56 INFO - Running pre-action listener: _resource_record_pre_action 18:22:56 INFO - Running pre-action listener: _set_gcov_prefix 18:22:56 INFO - Running main action method: run_tests 18:22:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:22:56 INFO - Python 2.7.3 18:22:56 INFO - Return code: 0 18:22:56 INFO - grabbing minidump binary from tooltool 18:22:56 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')]} 18:22:56 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 0x1a85eb0>, '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 0x1a86db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a87240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:22:56 INFO - Return code: 0 18:22:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:22:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:22:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:22:56 INFO - ENV: RUST_BACKTRACE is now 1 18:22:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:22:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:22:56 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/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:22:56 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/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 18:22:56 INFO - Using env: {'DISPLAY': ':0', 18:22:56 INFO - 'HOME': '/home/cltbld', 18:22:56 INFO - 'LANG': 'en_US.UTF-8', 18:22:56 INFO - 'LANGUAGE': 'en_US:en', 18:22:56 INFO - 'LOGNAME': 'cltbld', 18:22:56 INFO - 'MAIL': '/var/mail/cltbld', 18:22:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:22:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:56 INFO - 'MOZ_NO_REMOTE': '1', 18:22:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:22:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:56 INFO - 'NO_EM_RESTART': '1', 18:22:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:56 INFO - 'PWD': '/builds/slave/test', 18:22:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:22:56 INFO - 'RUST_BACKTRACE': '1', 18:22:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:22:56 INFO - 'SHELL': '/bin/bash', 18:22:56 INFO - 'SHLVL': '1', 18:22:56 INFO - 'TERM': 'linux', 18:22:56 INFO - 'TMOUT': '86400', 18:22:56 INFO - 'USER': 'cltbld', 18:22:56 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416', 18:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:56 INFO - '_': '/tools/buildbot/bin/python'} 18:22:56 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/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:22:56 INFO - mozversion application_buildid: 20170803001202 18:22:56 INFO - mozversion application_changeset: 6f1914a4f241b8ac62953de069296397b7645cd1 18:22:56 INFO - mozversion application_display_name: Nightly 18:22:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:22:56 INFO - mozversion application_name: Firefox 18:22:56 INFO - mozversion application_remotingname: firefox 18:22:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:22:56 INFO - mozversion application_vendor: Mozilla 18:22:56 INFO - mozversion application_version: 57.0a1 18:22:56 INFO - mozversion platform_buildid: 20170803001202 18:22:56 INFO - mozversion platform_changeset: 6f1914a4f241b8ac62953de069296397b7645cd1 18:22:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:22:56 INFO - mozversion platform_version: 57.0a1 18:22:56 INFO - using testdate: 1501723376 18:22:56 INFO - actual date: 1501723376 18:22:56 INFO - starting webserver on 'localhost:58044' 18:22:56 INFO - SUITE-START | Running 2 tests 18:22:56 INFO - TEST-START | dromaeo_css 18:22:56 INFO - Initialising browser for dromaeo_css test... 18:22:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58044/getInfo.html -profile /tmp/tmpN22XA8/profile 18:22:56 INFO - TEST-INFO | started process 3380 (/builds/slave/test/build/application/firefox/firefox http://localhost:58044/getInfo.html) 18:23:05 INFO - TEST-INFO | 3380: exit 0 18:23:05 INFO - Browser initialized. 18:23:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:05 INFO - TEST-INFO | started process 3587 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN22XA8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:06 INFO - PID 3587 | 18:23:06 INFO - PID 3587 | (/builds/slave/test/build/application/firefox/firefox:3635): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:23:06 INFO - PID 3587 | 18:23:17 INFO - PID 3587 | RSS: Main: 171810816 18:23:17 INFO - PID 3587 | 18:26:16 INFO - PID 3587 | Cycle 1(1): loaded http://localhost:58044/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58044/tests/dromaeo/cssquery-ext.html) 18:26:17 INFO - PID 3587 | RSS: Main: 173563904 18:26:17 INFO - PID 3587 | 18:29:19 INFO - PID 3587 | Cycle 1(1): loaded http://localhost:58044/tests/dromaeo/cssquery-ext.html (next: http://localhost:58044/tests/dromaeo/cssquery-jquery.html) 18:29:20 INFO - PID 3587 | RSS: Main: 171933696 18:29:20 INFO - PID 3587 | 18:32:21 INFO - PID 3587 | Cycle 1(1): loaded http://localhost:58044/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58044/tests/dromaeo/cssquery-mootools.html) 18:32:22 INFO - PID 3587 | RSS: Main: 173780992 18:32:22 INFO - PID 3587 | 18:35:24 INFO - PID 3587 | Cycle 1(1): loaded http://localhost:58044/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58044/tests/dromaeo/cssquery-prototype.html) 18:35:24 INFO - PID 3587 | RSS: Main: 172314624 18:35:24 INFO - PID 3587 | 18:38:28 INFO - PID 3587 | Cycle 1(1): loaded http://localhost:58044/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58044/tests/dromaeo/cssquery-yui.html) 18:38:29 INFO - PID 3587 | RSS: Main: 175509504 18:38:29 INFO - PID 3587 | 18:41:30 INFO - PID 3587 | Cycle 1(1): loaded http://localhost:58044/tests/dromaeo/cssquery-yui.html (next: http://localhost:58044/tests/dromaeo/cssquery-dojo.html) 18:41:31 INFO - PID 3587 | RSS: Main: 176525312 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | __start_tp_report 18:41:31 INFO - PID 3587 | _x_x_mozilla_page_load 18:41:31 INFO - PID 3587 | _x_x_mozilla_page_load_details 18:41:31 INFO - PID 3587 | |i|pagename|runs| 18:41:31 INFO - PID 3587 | |0;dojo.html;3128.3117714102896;3135.561021456998;3138.152698771332;3172.4289627867;3205.7755957083014;6179.289381721103;6195.287541932679;6222.782202622909;6247.656378899159;6261.186045814038;6026.819195424145;6117.66352850593;6119.755209791603;6133.264008318997;6172.074188871671;5644.830655080344;5651.180578816069;5686.402927692595;5709.971450142764;5752.424757524256;5718.313802343704;5727.198192253088;5731.856703582402;5754.827355179351;5756.769729210827;5722.713864306789;5732.79935202268;5735.168400581913;5751.396103409144;5786.276715410574;5707.400722924116;5761.193432919395;5763.942360576382;5787.218725472049;5821.56338274629;5493.752781124819;5630.915536266959;5631.662100273997;5705.4579814917515;5711.20043193949;6133.754649814002;6153.784617538365;6186.690665466709;6207.441330280251;6214.25428948529;6688.83277918051;6691.498137639696;6790.185177778649;6799;6799.456043516556;6696.5647232929705;6760.932390676128;6769.593824370504;6846.897296540506;6848.7260509579555;6241.469475094623;6250.499960003189;6270.3416141305415;6271.749130034747;6287.9056814147825;6795;6816.045753594501;6905.482088843357;6907;6914.928186131157;6395.232572091379;6461.160049193575;6517.4134327910715;6580.276169621337;6622.86754264912;6604.306547812506;6627.436667883284;6628.270890202074;6636.568623039487;6639.302873198341;6580.736770529221;6626.138601981712;6642.368974947372;6646.900296495556;6659.833504162338;6584.5061620378665;6647.700853461606;6696.59820410777;6706.161729783777;6772.864542709217;5373.274607927958;5427.83716488506;5446.564274858002;5455.1544510600925;5462.863428414322;4569.200389931749;4593.035462552834;4595.977020114878;4637.976810115928;4676.8129274828625;5762.683052432073;5782.971085144547;5794.797182098607;5796.710164491758;5805.941940580625;6301.960176570906;6375.075614035939;6376.234851817811;6448.580842245333;6649.235337936103;5844.327902291291;5895.7052147393315;5905.704714764244;5908.468237858613;5915;5841.835721942131;5869.647821130746;5938.020226662638;5943.375945525744;5944.4352786485215;6351.904721429182;6400.967995159995;6450.838729031811;6475.546711730228;6480.384363485461;6755.020522024377;6780.69486873082;6788.660566971533;6795;6798;6432.935670643234;6522.836929076811;6553.967230163818;6572.737090516326;6575;6631.270560238273;6642.20293564756;6715.261321254756;6719.428848547928;6720.395164435225;6618.271990080991;6740.494462915399;6755.3582409671;6767.796966091025;6847.965760171167;6408.391202835724;6476.352364763486;6485.546011779319;6504.1219435375615;6507.967460162668;6424.678766061772;6546.279909210079;6579.868402631825;6593.044008618627;6595.571287866273;6395.392437718409;6432.614043157426;6500.122483464861;6509.837254068686;6514.739410423719;6474.676266186766;6479.449246813885;6544.705488252945;6608.7026083825385;6647;6251.937480625135;6253.124562561154;6295.842603934755;6300.621962682254;6308.053791931247 18:41:31 INFO - PID 3587 | |1;ext.html;834.411739723495;870.3472395703222;893.7586851550079;897.0536084430927;1123.7583919457315;31057.84471077647;32008.35983280333;43571.346429803576;44117.77941110298;44249.33625995613;43989.7800510997;44025.55974440255;44057.77971110148;44243.77878110613;44284.55715442845;7663.616819159048;7815.843683126334;7831.4909530880595;7832.686692532292;7838.725644602411;7736.071671399412;7835.216478352177;7850.293473587376;7858.096318923312;7878.094019187781;7870.842583148334;7872.960635196845;7903.525788452711;7910.841783164333;7912.643931023089;7767.6892924282965;7844.4901081429525;7844.803879902991;7864.72473463426;7883.881741773878;7630.351420129296;7706.6917323307;7719.807004824868;7732.768016959444;7755.844883102306;38914;38943.8052809737;39082.80458597689;39147.41278880819;39268.214635707125;3624.963750362489;3630.7276954228537;3654.908627284339;3655.5613326400735;3659.249853779969;32188.6781132188;32229.838850805594;32285.677143228735;32423;32477.350452991042;4926.211806111005;4939.209726443788;4952.851414457536;4960;5028;181816;183546;184553;184969;185318;49189;49268.50731492711;49323.26015109776;49421.50578494241;49459.75270123626;55024.72487637616;55738;55793.72103139458;55832.16251756227;55854.44145558574;16120.516384508484;16208.189590520427;16228.269727862275;16235.918820405823;16236.75644865328;13048.608541743763;13136.934315328363;13157.53948611794;13199.010074244277;13241.867581324255;15111.848881511263;15126.319315630823;15219.239038048054;15249.313780879887;15362.846371536141;5910.763569457174;6050.6974651267265;6111.96944015277;6136.539759518054;6148.1085242640465;1001.2890847498212;1023.3092662452815;1026.6714651311659;1029.0532709906904;1030.1141018723893;23470.295891123293;23471.41321466977;23533.646995295083;23543;23665.290041298787;1128.15388458656;1131.6944425005202;1139.2993309114834;1140.5551834784442;1142.8285757136496;12504.874951250553;12676.112672112864;16099;16392.918035409748;16600.418985335455;219640;230949;234079;234262;234511;49994.500054998985;58583;59878;60295.69852150799;60452.09321860175;1481.459267367403;1490.8509149085005;1493.5370035289095;1501.091839437125;1520.429838810446;27405.86297068502;27667.72332276732;27778.444431111668;27790.86104569464;27933.301667458476;52009.21986170135;52816.73591632017;52851.47148528465;52863.20705189502;52869;203722;204140;205271;205660;205757;36962.07594810151;36992.445113322785;37191.62808371882;37192.814035929645;37348.43977340397;44250.336244957;45709.77145114253;45841.54158458506;45864;46042.30936535887;32711;33361.332773343915;33497.66502334945;33513;33533.83233083819;32357.676423235465;32674.509882351307;32753.18117047093;33403.33193336168;33869;34880.47679284762;36287.81856090703;36549.45175822311;36565.45151822671;36589.451158232114;31705.524417134224;31888.36223275568;31904.521432179;31907.521387178745;32348.676513235507;23267;23779.335446518577;24179;24221.152259670827;24442.26673199807 18:41:31 INFO - PID 3587 | |2;jquery.html;568.6616463204394;580.5935844908564;584.5644994479113;585.7100735136108;594.1088367448826;22240.332790016306;22292.331230063082;23114;23152.65271020934;23169.420764480856;26292.737072629316;26843.463130737375;27002;27003.594946075824;27047.1885843425;6520.70656820443;6642.966785166067;6655.434288085518;6693.765718199864;6694.397504224618;6032.095185722133;6560.671966401685;6578.24350199726;6586.341365863399;6630.569013014163;6580.967095164541;6584;6584.736610535573;6591.143151390336;6599.373059559358;6517.576357536745;6543.345665433442;6574.605523668547;6574.769883054094;6577.736890524373;6125.448709616111;6129.632222066645;6132.294786099611;6133.570650054498;6146.17026701394;29192.41615167706;30298.242543936358;30462.84768576165;30664.77340906361;30761.077167685005;1937.8159074887863;1970.2808474906699;1970.5467742419323;1970.7930667279873;1971.5761111361126;19193.232270709155;19752.604947901105;19773.209071637117;19805.40583782489;19835.206591736314;2219.4340443187048;2282.988585057064;2284.6458798886033;2285.6685780561725;2422.9394265143337;132670;138667.30666346705;139187.30406347904;139333.3033334837;139618;14230.573082807532;14880.404783808743;14971.92514037423;15046.172460514557;15313.23433828304;23614.11087113027;23942.52114957691;24047.398815029414;24728.505429891302;24775;9926.950365248127;9987.250956178315;10044.347117437343;10084.193264538819;10252.282340236261;8873.112688731075;8901.688440904538;9025.142611451902;9032.23226025782;9058.275337972946;9128.58921348541;9402.670906518239;9477.952610236905;9545.284103692251;9623.663171788954;2629.500394924958;2681.9004208274905;2733.26201925479;2793.9441211175663;2852.5863749756168;972.6936015155203;987.7579992901785;993.980120397588;1003.6988903328972;1011.4841430870309;19420.320288789826;19484.318048868223;19593.216271349273;20150.193992240438;20362.898185509275;1039.6725031615017;1051.2010871737382;1056.6882769582992;1068.9572417103384;1078.2613909472036;10866.276195754797;11162.832557511643;11175.329480231212;11191.440427978569;11250.774984500264;456208;460934.8672796436;463060;479147;481144;112635;114123;114765;114881.42559287317;116908.41545792216;1221.1574013930538;1233.2846948769693;1257.3838818978634;1260.4013093780563;1263.7851565233932;17630.559236019202;17801.643967120326;17916.91041544836;17953.10234488244;18144.092795359917;51712.22431663453;52536.73731631317;53009;54171;54298;63875.68062159659;64123.67938160279;64146.35853641591;65121.67439162774;65262.34737652563;13956;14025.579232623038;14101.858981410054;14157.929210353883;14201.857981420053;22360.552788944457;22473;22580.5483890318;22658.773412265666;22880.313590592308;17311.30754769795;17836.821631783518;17978.37075702344;17985.820141798413;18018.639627207645;14954.85045149578;15226.23868806533;16356.427525036566;16452.917735411724;16660.83339166593;18306.084695765425;18452.723209151605;18599.90700046491;18698.252069917053;18703.625927481102;14339.641508962359;14362;15176.772348415003;15180.089194648357;15187;18870.056497174814;19008;19018.809811901705;19050.237990480226;19176.424707258804 18:41:31 INFO - PID 3587 | |3;mootools.html;1361.7889227169785;1390.2631605249214;1420.438926623983;1420.9715805683886;1437.065907160346;13165.999999999987;13577.932110339434;13752.724945501097;13755.037147399686;13755.5185568505;14085.366158522866;14381.137131772075;14398.784018239734;14402.927985360058;14414.639634009154;2575.6780402449695;2680.32991752062;2719.945601087977;2728.6998430172666;2731.3308239481353;2725.141580402167;2745.3960128771637;2745.4646343962936;2745.6567929008875;2755.0357374918817;2534.214393538;2552.9489410211786;2653.1642532602264;2687.516247075526;2700.338417087806;2501.774840264367;2555.4889022195634;2588.521123592132;2606.4656745367156;2610.2821724025853;2571.9871400643065;2606.4787042591556;2623.27859838544;2623.960640590393;2631.6184153297854;13434.596962091153;13722.65693357664;13740.244286564235;13773.449062037507;13774.724505509934;7592.430567707444;7843.6470358833985;7858.214178582153;7871.330936870373;7874.685012599489;4964.15609346412;5078.64449488536;5141.15170996785;5210.244514545369;5228.37259528859;1775.1656721340948;1796.8078943688809;1799.3194944255513;1830.6247219320007;1833.2941817400263;22315;22534.54930901389;22570.21004264843;22607.208747693916;22660.09359625613;6852.177738671391;6864.759733409406;7140.964295178491;7170.749023784142;7175.390091842148;15287.617809554627;15483.225838708246;15503.147326896904;15504.922475387551;15510.534683959499;7491.951126842247;7685.116211635622;7685.5004424712215;7698.846023079507;7733.3426658733515;6965.47758918073;7033.191183013917;7176.174739904978;7215.422766178693;7258.927410725931;5091.516305950929;5198.740062996911;5209.557187639018;5273.34083239595;5302.92045619316;1506.7965824613757;1518;1523.1089812459836;1529.9158546280062;1538.2693220720068;785.1363500149789;805.6817557065044;821.1583127294451;825.4263287015586;840.7771940435789;6243.875122497525;6424.967875160688;7278.813008942357;7477.626118694044;7599;918.5085979001202;964.8504481805331;973.2797729679986;976.3507267666922;982.7592239901127;4316.1583491219535;4425.203463376559;4428.623566996841;4435.467743870691;4485.820567177341;18234.179461924246;18906.621867562848;19127.426177214707;19128.234870605298;19147.90426047889;18010;18627;18660.906695466707;18781.436556903314;18838.24647014104;10184.847227291597;10386;10450.477476126163;10460.947695261475;10519.84220236697;6892.896606551006;7098.219195888551;7182.497225194186;7262.128544574685;7311.597862117635;16258.26837792326;16426.096564688578;16652.250648720594;16841.2421441033;17147.142642868057;16971.490855274362;16971.490855274362;17121.657566848844;17149.228284727466;17165.227564759367;10566.577336906437;10784.730381740206;10807.729806754895;10810.56757729682;10812.891871081503;12558.811617825557;12657.430415631134;12703.93648031754;12766.425510851848;12775.23348599087;9983.400995940267;10296.742581435525;10402.167826573705;10555.366677999345;10606;9640.132388085107;9684.176844968257;10242.6415075472;10262.43556604394;10395.220358473296;8701.260392866678;8849.955750221208;8974.506402147683;8979.10208979097;8993.190612844875;6271.4669253114;6284.183056202664;6296.653684047421;6314.431701146918;6339.809805705836;6340.270868850142;6391.2330520337855;6395.776147834804;6418.935810641834;6436.710348034443 18:41:31 INFO - PID 3587 | |4;prototype.html;503.25015726567415;504.3745755263474;524.331477366358;531.1395539226454;536.4045909040964;6112.847178820532;6184.907226391608;6302.810915672526;6313.179286692729;6384.808455746329;6032;6061.9696901515545;6091.817245482643;6095.939040609593;6121;4799.328094066834;4978.576820970222;5010.749462526877;5026.3558919783045;5081.568066714324;4982.327385802906;4991.32617096691;4992.975035124819;4995.225740010304;5026.698398096126;4929.162042452792;4944.629152813535;4944.678595891256;4959.206526955688;5004.674696144757;4877.829275975341;4927.679700819435;4952.579030782388;5016.8996620067755;5018.874528136789;4504.301833215857;4504.301833215857;4505.279155335131;4513.300438432048;4517.299818528133;5899.085641725539;6011.458968692795;6021.879562408771;6051.304100028465;6157.199564056644;5586.190002449622;5603.971980140114;5668.631538949996;5683.460071293221;5704.258446401941;6491.7078731457195;6515.315891831384;6553.737850485975;6599.57102788322;6610.834729131762;4797.808087676489;4843.830465933675;4860.902781944376;4899.8040078396825;4909.828156014558;6476.643784591845;6755.459563234978;6797.864042719168;6805.931940680629;6989.755358562476;4906.950930490721;4932.506749325039;4947.975260123676;4961.305417241589;4990.575801056878;4981.402231732143;4992.425871024806;5011.974940125276;5062.924201077614;5086.618503612244;5582.074184406373;5652.97173514138;5675.489205971482;5724.656520608777;5729.942700573024;6542.934570654328;6560.212774467094;6580.440662543641;6581.670916454158;6642.667866606747;1755.8331958463925;1765.5056584156457;1767.6641438126715;1787.1064467766116;1796.3173993882242;1161.3960740414936;1227.8096894981293;1232.0020783165485;1234.456838990841;1235.4131787400913;5770.307563092372;5784.65292082468;5802.1296805479515;5831.4168583141345;5848.502877255481;6667.233268174222;6671.633060181736;6696.665166741644;6702.664866756741;6739.123913891261;5619.971900140555;5714.885702286014;5744.253247077853;5748.815450129008;5774.23885714861;3499.3526197653514;3533.3993221152464;3534.770239934396;3554.111472131967;3595;6360.268569114519;6453.870922581522;6465.579737317154;6467.186773753432;6515.7067931943175;6710.731570737214;6778.98315252716;6789.830254243683;6818.727250909908;6851.143607049119;6284.051943897406;6379.170707807955;6384.968075159594;6391.744330226832;6405.231372235362;5930.258717660293;6556.803295901131;6692;6700.43357509653;6745.258021617717;6493.123428337114;6565.507586931095;6594.812294193126;6610.900836487361;6636.141880273532;6389.840253993687;6430.64631445275;6462.483001359974;6486.578372405778;6491.5131365148745;4920.989393644169;4927.260910863399;4953.3065370848835;4974;5008.398992120969;6464.41820236181;6475.935240647534;6582.736690532325;6649.401553860176;6767.93232067673;6447.6131432114225;6448.29068802422;6457.6448295342325;6463.482921366185;6499.115256575729;6465.965445528693;6486.254080780771;6502.414782669583;6507.12153859223;6543.5419520633095;6459.483241340776;6462.806115816533;6469.514786390945;6481.837954051187;6486.189226346707;6543.509236807164;6575.342465753386;6611.140551728437;6614.437772789367;6617.404433600999;6229.719662615103;6270.968645156745;6282;6356.650384228912;6413.16628838248 18:41:31 INFO - PID 3587 | |5;yui.html;912.0833562269918;918.9127032931872;936.6768464879614;940.2431043010374;954.8949615542289;20344.593108137848;21960.890195549;21993;22025.779742202572;22044.008019639114;19999.60000799983;20837;20892;20969;20984;1043.6921108273077;1077.784443111377;1078.3422112511362;1080.7676349584838;1080.9945950270278;947.9383840050409;1032.9225308101884;1050.2280823594651;1053.3627155570884;1054.3410368519676;1107.4850194659482;1113.7772445510889;1120.025577747361;1121.495327102803;1124.8144056230713;1084.9349039057681;1088.4612117002096;1091.5633746501462;1092.9125669946386;1098.6703988803367;797.6370751308141;798.5568009754642;801.9759407217792;802.8193656427316;803.3211935914143;16049.277782499816;16298.918505407515;17051.062191579458;17138.828611713972;17257.741133883002;20605.587888242153;20700.896495517576;21063.262785802424;21146.577068458697;21172.047257873433;15709.135997520132;15872.047677139293;16045.759313610306;16056.27746751399;16077.11575863327;3525;3558.768680035815;3570.357335679577;3573.6069032406676;3574.410222313315;125072.37463812713;128068.35965820111;128177;130047.34976325152;130206;15725.213739312989;15883.602909927113;16273.349066037463;16359.100249486393;16490;24175.879120604284;25247.495050098896;27966;29647.703522964493;29851.85074074616;8958.731238062868;9205.907940920639;9222.123898229658;9250.90749092514;9300.674476393293;4237.703360764778;4361.498427680794;4384.276594361894;4394.318880573516;4403.361512580658;5352.170413585901;5451.182322651634;5457.2359869618285;5490.039243132436;5493.09363954943;1105.2815669814686;1148.4315263944402;1159.7854396936593;1159.8318243854594;1159.9014083802801;689.8309914071085;702.4977141343963;706.1279320039747;712.7398499547628;730.9963450182715;11788.764224715458;12310;12830.743385132246;13372.531961381499;13733;699.1191099215025;699.8460338725472;702.2696395748364;712.5617745086838;716.451914285572;5286.841394758163;5291.047611429903;5630.761232528837;5694.715264236772;5776.075827867605;102287;103671.48164259281;103684;104356.47821760994;104972;90921.09078909259;92061;94874.52562737142;96938;97703;79166;79830.6008469954;80079.59960200162;84788;85565;12296.938515307365;12521.56174533887;12804.231746095264;12959.546415875398;13222.801657974946;35978.8201058993;36014.8199259002;36110.638893610725;37884.43173352457;38127.618723812404;44611.77694111509;44959.77520112378;47239.76380118077;59573.404265956786;67262.3273767256;18097.638047238717;18161.45515634533;18271.634567308312;18450.35423760162;18483;20124.79875201229;20819.687704684744;20877.791222087584;20884.37346879596;21038.68441973341;17991.550211244823;18004.90997545004;18111.184996674918;18117.818821811612;18451.354202602844;17908.55228619295;18247.635047298714;18464.169112390242;18548.536286592942;18632.627347453246;15887.602809929382;16092.195390230207;18387;19023.809761902205;19183.32858349951;12327.260364378166;13002;13362.665933351744;13999;14575.416983320549;11156.944215278872;14222.644433889236;14425.134491930517;14807.407703691732;14943.327550260046 18:41:31 INFO - PID 3587 | __end_tp_report 18:41:31 INFO - PID 3587 | __start_cc_report 18:41:31 INFO - PID 3587 | _x_x_mozilla_cycle_collect,382 18:41:31 INFO - PID 3587 | __end_cc_report 18:41:31 INFO - PID 3587 | __startTimestamp1501724491045__endTimestamp 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | ------- Summary: start ------- 18:41:31 INFO - PID 3587 | Number of tests: 6 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | [#0] dojo.html Cycles:165 Average:6149.87 Median:6375.08 stddev:724.11 (11.4%) stddev-sans-first:686.44 18:41:31 INFO - PID 3587 | Values: 3128.3 3135.6 3138.2 3172.4 3205.8 6179.3 6195.3 6222.8 6247.7 6261.2 6026.8 6117.7 6119.8 6133.3 6172.1 5644.8 5651.2 5686.4 5710.0 5752.4 5718.3 5727.2 5731.9 5754.8 5756.8 5722.7 5732.8 5735.2 5751.4 5786.3 5707.4 5761.2 5763.9 5787.2 5821.6 5493.8 5630.9 5631.7 5705.5 5711.2 6133.8 6153.8 6186.7 6207.4 6214.3 6688.8 6691.5 6790.2 6799.0 6799.5 6696.6 6760.9 6769.6 6846.9 6848.7 6241.5 6250.5 6270.3 6271.7 6287.9 6795.0 6816.0 6905.5 6907.0 6914.9 6395.2 6461.2 6517.4 6580.3 6622.9 6604.3 6627.4 6628.3 6636.6 6639.3 6580.7 6626.1 6642.4 6646.9 6659.8 6584.5 6647.7 6696.6 6706.2 6772.9 5373.3 5427.8 5446.6 5455.2 5462.9 4569.2 4593.0 4596.0 4638.0 4676.8 5762.7 5783.0 5794.8 5796.7 5805.9 6302.0 6375.1 6376.2 6448.6 6649.2 5844.3 5895.7 5905.7 5908.5 5915.0 5841.8 5869.6 5938.0 5943.4 5944.4 6351.9 6401.0 6450.8 6475.5 6480.4 6755.0 6780.7 6788.7 6795.0 6798.0 6432.9 6522.8 6554.0 6572.7 6575.0 6631.3 6642.2 6715.3 6719.4 6720.4 6618.3 6740.5 6755.4 6767.8 6848.0 6408.4 6476.4 6485.5 6504.1 6508.0 6424.7 6546.3 6579.9 6593.0 6595.6 6395.4 6432.6 6500.1 6509.8 6514.7 6474.7 6479.4 6544.7 6608.7 6647.0 6251.9 6253.1 6295.8 6300.6 6308.1 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | [#1] ext.html Cycles:180 Average:38882.95 Median:19959.27 stddev:53790.59 (269.5%) stddev-sans-first:53865.61 18:41:31 INFO - PID 3587 | Values: 834.4 870.3 893.8 897.1 1123.8 31057.8 32008.4 43571.3 44117.8 44249.3 43989.8 44025.6 44057.8 44243.8 44284.6 7663.6 7815.8 7831.5 7832.7 7838.7 7736.1 7835.2 7850.3 7858.1 7878.1 7870.8 7873.0 7903.5 7910.8 7912.6 7767.7 7844.5 7844.8 7864.7 7883.9 7630.4 7706.7 7719.8 7732.8 7755.8 38914.0 38943.8 39082.8 39147.4 39268.2 3625.0 3630.7 3654.9 3655.6 3659.2 32188.7 32229.8 32285.7 32423.0 32477.4 4926.2 4939.2 4952.9 4960.0 5028.0 181816.0 183546.0 184553.0 184969.0 185318.0 49189.0 49268.5 49323.3 49421.5 49459.8 55024.7 55738.0 55793.7 55832.2 55854.4 16120.5 16208.2 16228.3 16235.9 16236.8 13048.6 13136.9 13157.5 13199.0 13241.9 15111.8 15126.3 15219.2 15249.3 15362.8 5910.8 6050.7 6112.0 6136.5 6148.1 1001.3 1023.3 1026.7 1029.1 1030.1 23470.3 23471.4 23533.6 23543.0 23665.3 1128.2 1131.7 1139.3 1140.6 1142.8 12504.9 12676.1 16099.0 16392.9 16600.4 219640.0 230949.0 234079.0 234262.0 234511.0 49994.5 58583.0 59878.0 60295.7 60452.1 1481.5 1490.9 1493.5 1501.1 1520.4 27405.9 27667.7 27778.4 27790.9 27933.3 52009.2 52816.7 52851.5 52863.2 52869.0 203722.0 204140.0 205271.0 205660.0 205757.0 36962.1 36992.4 37191.6 37192.8 37348.4 44250.3 45709.8 45841.5 45864.0 46042.3 32711.0 33361.3 33497.7 33513.0 33533.8 32357.7 32674.5 32753.2 33403.3 33869.0 34880.5 36287.8 36549.5 36565.5 36589.5 31705.5 31888.4 31904.5 31907.5 32348.7 23267.0 23779.3 24179.0 24221.2 24442.3 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | [#2] jquery.html Cycles:180 Average:33900.97 Median:22619.66 stddev:79083.65 (349.6%) stddev-sans-first:79265.90 18:41:31 INFO - PID 3587 | Values: 568.7 580.6 584.6 585.7 594.1 22240.3 22292.3 23114.0 23152.7 23169.4 26292.7 26843.5 27002.0 27003.6 27047.2 6520.7 6643.0 6655.4 6693.8 6694.4 6032.1 6560.7 6578.2 6586.3 6630.6 6581.0 6584.0 6584.7 6591.1 6599.4 6517.6 6543.3 6574.6 6574.8 6577.7 6125.4 6129.6 6132.3 6133.6 6146.2 29192.4 30298.2 30462.8 30664.8 30761.1 1937.8 1970.3 1970.5 1970.8 1971.6 19193.2 19752.6 19773.2 19805.4 19835.2 2219.4 2283.0 2284.6 2285.7 2422.9 132670.0 138667.3 139187.3 139333.3 139618.0 14230.6 14880.4 14971.9 15046.2 15313.2 23614.1 23942.5 24047.4 24728.5 24775.0 9927.0 9987.3 10044.3 10084.2 10252.3 8873.1 8901.7 9025.1 9032.2 9058.3 9128.6 9402.7 9478.0 9545.3 9623.7 2629.5 2681.9 2733.3 2793.9 2852.6 972.7 987.8 994.0 1003.7 1011.5 19420.3 19484.3 19593.2 20150.2 20362.9 1039.7 1051.2 1056.7 1069.0 1078.3 10866.3 11162.8 11175.3 11191.4 11250.8 456208.0 460934.9 463060.0 479147.0 481144.0 112635.0 114123.0 114765.0 114881.4 116908.4 1221.2 1233.3 1257.4 1260.4 1263.8 17630.6 17801.6 17916.9 17953.1 18144.1 51712.2 52536.7 53009.0 54171.0 54298.0 63875.7 64123.7 64146.4 65121.7 65262.3 13956.0 14025.6 14101.9 14157.9 14201.9 22360.6 22473.0 22580.5 22658.8 22880.3 17311.3 17836.8 17978.4 17985.8 18018.6 14954.9 15226.2 16356.4 16452.9 16660.8 18306.1 18452.7 18599.9 18698.3 18703.6 14339.6 14362.0 15176.8 15180.1 15187.0 18870.1 19008.0 19018.8 19050.2 19176.4 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | [#3] mootools.html Cycles:180 Average:8472.82 Median:2608.38 stddev:5799.94 (222.4%) stddev-sans-first:5791.60 18:41:31 INFO - PID 3587 | Values: 1361.8 1390.3 1420.4 1421.0 1437.1 13166.0 13577.9 13752.7 13755.0 13755.5 14085.4 14381.1 14398.8 14402.9 14414.6 2575.7 2680.3 2719.9 2728.7 2731.3 2725.1 2745.4 2745.5 2745.7 2755.0 2534.2 2552.9 2653.2 2687.5 2700.3 2501.8 2555.5 2588.5 2606.5 2610.3 2572.0 2606.5 2623.3 2624.0 2631.6 13434.6 13722.7 13740.2 13773.4 13774.7 7592.4 7843.6 7858.2 7871.3 7874.7 4964.2 5078.6 5141.2 5210.2 5228.4 1775.2 1796.8 1799.3 1830.6 1833.3 22315.0 22534.5 22570.2 22607.2 22660.1 6852.2 6864.8 7141.0 7170.7 7175.4 15287.6 15483.2 15503.1 15504.9 15510.5 7492.0 7685.1 7685.5 7698.8 7733.3 6965.5 7033.2 7176.2 7215.4 7258.9 5091.5 5198.7 5209.6 5273.3 5302.9 1506.8 1518.0 1523.1 1529.9 1538.3 785.1 805.7 821.2 825.4 840.8 6243.9 6425.0 7278.8 7477.6 7599.0 918.5 964.9 973.3 976.4 982.8 4316.2 4425.2 4428.6 4435.5 4485.8 18234.2 18906.6 19127.4 19128.2 19147.9 18010.0 18627.0 18660.9 18781.4 18838.2 10184.8 10386.0 10450.5 10460.9 10519.8 6892.9 7098.2 7182.5 7262.1 7311.6 16258.3 16426.1 16652.3 16841.2 17147.1 16971.5 16971.5 17121.7 17149.2 17165.2 10566.6 10784.7 10807.7 10810.6 10812.9 12558.8 12657.4 12703.9 12766.4 12775.2 9983.4 10296.7 10402.2 10555.4 10606.0 9640.1 9684.2 10242.6 10262.4 10395.2 8701.3 8850.0 8974.5 8979.1 8993.2 6271.5 6284.2 6296.7 6314.4 6339.8 6340.3 6391.2 6395.8 6418.9 6436.7 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | [#4] prototype.html Cycles:180 Average:5476.73 Median:6056.64 stddev:1526.36 (25.2%) stddev-sans-first:1484.29 18:41:31 INFO - PID 3587 | Values: 503.3 504.4 524.3 531.1 536.4 6112.8 6184.9 6302.8 6313.2 6384.8 6032.0 6062.0 6091.8 6095.9 6121.0 4799.3 4978.6 5010.7 5026.4 5081.6 4982.3 4991.3 4993.0 4995.2 5026.7 4929.2 4944.6 4944.7 4959.2 5004.7 4877.8 4927.7 4952.6 5016.9 5018.9 4504.3 4504.3 4505.3 4513.3 4517.3 5899.1 6011.5 6021.9 6051.3 6157.2 5586.2 5604.0 5668.6 5683.5 5704.3 6491.7 6515.3 6553.7 6599.6 6610.8 4797.8 4843.8 4860.9 4899.8 4909.8 6476.6 6755.5 6797.9 6805.9 6989.8 4907.0 4932.5 4948.0 4961.3 4990.6 4981.4 4992.4 5012.0 5062.9 5086.6 5582.1 5653.0 5675.5 5724.7 5729.9 6542.9 6560.2 6580.4 6581.7 6642.7 1755.8 1765.5 1767.7 1787.1 1796.3 1161.4 1227.8 1232.0 1234.5 1235.4 5770.3 5784.7 5802.1 5831.4 5848.5 6667.2 6671.6 6696.7 6702.7 6739.1 5620.0 5714.9 5744.3 5748.8 5774.2 3499.4 3533.4 3534.8 3554.1 3595.0 6360.3 6453.9 6465.6 6467.2 6515.7 6710.7 6779.0 6789.8 6818.7 6851.1 6284.1 6379.2 6385.0 6391.7 6405.2 5930.3 6556.8 6692.0 6700.4 6745.3 6493.1 6565.5 6594.8 6610.9 6636.1 6389.8 6430.6 6462.5 6486.6 6491.5 4921.0 4927.3 4953.3 4974.0 5008.4 6464.4 6475.9 6582.7 6649.4 6767.9 6447.6 6448.3 6457.6 6463.5 6499.1 6466.0 6486.3 6502.4 6507.1 6543.5 6459.5 6462.8 6469.5 6481.8 6486.2 6543.5 6575.3 6611.1 6614.4 6617.4 6229.7 6271.0 6282.0 6356.7 6413.2 18:41:31 INFO - PID 3587 | 18:41:31 INFO - PID 3587 | [#5] yui.html Cycles:180 Average:22791.85 Median:20234.70 stddev:30789.36 (152.2%) stddev-sans-first:30831.90 18:41:31 INFO - PID 3587 | Values: 912.1 918.9 936.7 940.2 954.9 20344.6 21960.9 21993.0 22025.8 22044.0 19999.6 20837.0 20892.0 20969.0 20984.0 1043.7 1077.8 1078.3 1080.8 1081.0 947.9 1032.9 1050.2 1053.4 1054.3 1107.5 1113.8 1120.0 1121.5 1124.8 1084.9 1088.5 1091.6 1092.9 1098.7 797.6 798.6 802.0 802.8 803.3 16049.3 16298.9 17051.1 17138.8 17257.7 20605.6 20700.9 21063.3 21146.6 21172.0 15709.1 15872.0 16045.8 16056.3 16077.1 3525.0 3558.8 3570.4 3573.6 3574.4 125072.4 128068.4 128177.0 130047.3 130206.0 15725.2 15883.6 16273.3 16359.1 16490.0 24175.9 25247.5 27966.0 29647.7 29851.9 8958.7 9205.9 9222.1 9250.9 9300.7 4237.7 4361.5 4384.3 4394.3 4403.4 5352.2 5451.2 5457.2 5490.0 5493.1 1105.3 1148.4 1159.8 1159.8 1159.9 689.8 702.5 706.1 712.7 731.0 11788.8 12310.0 12830.7 13372.5 13733.0 699.1 699.8 702.3 712.6 716.5 5286.8 5291.0 5630.8 5694.7 5776.1 102287.0 103671.5 103684.0 104356.5 104972.0 90921.1 92061.0 94874.5 96938.0 97703.0 79166.0 79830.6 80079.6 84788.0 85565.0 12296.9 12521.6 12804.2 12959.5 13222.8 35978.8 36014.8 36110.6 37884.4 38127.6 44611.8 44959.8 47239.8 59573.4 67262.3 18097.6 18161.5 18271.6 18450.4 18483.0 20124.8 20819.7 20877.8 20884.4 21038.7 17991.6 18004.9 18111.2 18117.8 18451.4 17908.6 18247.6 18464.2 18548.5 18632.6 15887.6 16092.2 18387.0 19023.8 19183.3 12327.3 13002.0 13362.7 13999.0 14575.4 11156.9 14222.6 14425.1 14807.4 14943.3 18:41:31 INFO - PID 3587 | -------- Summary: end -------- 18:41:31 INFO - PID 3587 | 18:41:31 INFO - TEST-INFO | 3587: exit 0 18:41:31 INFO - TEST-OK | dromaeo_css | took 1115318ms 18:41:31 INFO - TEST-START | kraken 18:41:31 INFO - Initialising browser for kraken test... 18:41:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58044/getInfo.html -profile /tmp/tmpcOT0Wx/profile 18:41:31 INFO - TEST-INFO | started process 3941 (/builds/slave/test/build/application/firefox/firefox http://localhost:58044/getInfo.html) 18:41:37 INFO - TEST-INFO | 3941: exit 0 18:41:37 INFO - Browser initialized. 18:41:37 INFO - Running cycle 1/1 for kraken test... 18:41:37 INFO - TEST-INFO | started process 4149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcOT0Wx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:38 INFO - PID 4149 | 18:41:38 INFO - PID 4149 | (/builds/slave/test/build/application/firefox/firefox:4198): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:41:38 INFO - PID 4149 | 18:41:48 INFO - PID 4149 | RSS: Main: 171835392 18:41:48 INFO - PID 4149 | 18:41:51 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58044/tests/kraken/driver.html?testName=audio-beat-detection) 18:41:52 INFO - PID 4149 | RSS: Main: 176775168 18:41:52 INFO - PID 4149 | 18:41:55 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58044/tests/kraken/driver.html?testName=audio-dft) 18:41:55 INFO - PID 4149 | RSS: Main: 177410048 18:41:55 INFO - PID 4149 | 18:41:58 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58044/tests/kraken/driver.html?testName=audio-fft) 18:41:59 INFO - PID 4149 | RSS: Main: 172285952 18:41:59 INFO - PID 4149 | 18:42:01 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58044/tests/kraken/driver.html?testName=audio-oscillator) 18:42:02 INFO - PID 4149 | RSS: Main: 172883968 18:42:02 INFO - PID 4149 | 18:42:04 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58044/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:04 INFO - PID 4149 | RSS: Main: 173174784 18:42:04 INFO - PID 4149 | 18:42:07 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58044/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:08 INFO - PID 4149 | RSS: Main: 175894528 18:42:08 INFO - PID 4149 | 18:42:12 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58044/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:12 INFO - PID 4149 | RSS: Main: 175239168 18:42:12 INFO - PID 4149 | 18:42:15 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58044/tests/kraken/driver.html?testName=json-parse-financial) 18:42:16 INFO - PID 4149 | RSS: Main: 175648768 18:42:16 INFO - PID 4149 | 18:42:17 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58044/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:18 INFO - PID 4149 | RSS: Main: 175538176 18:42:18 INFO - PID 4149 | 18:42:20 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:20 INFO - PID 4149 | RSS: Main: 175869952 18:42:20 INFO - PID 4149 | 18:42:23 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:23 INFO - PID 4149 | RSS: Main: 172867584 18:42:23 INFO - PID 4149 | 18:42:26 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:27 INFO - PID 4149 | RSS: Main: 173539328 18:42:27 INFO - PID 4149 | 18:42:30 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:30 INFO - PID 4149 | RSS: Main: 173682688 18:42:30 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | Cycle 1(1): loaded http://localhost:58044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58044/tests/kraken/driver.html?testName=ai-astar) 18:42:32 INFO - PID 4149 | RSS: Main: 175267840 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | __start_tp_report 18:42:32 INFO - PID 4149 | _x_x_mozilla_page_load 18:42:32 INFO - PID 4149 | _x_x_mozilla_page_load_details 18:42:32 INFO - PID 4149 | |i|pagename|runs| 18:42:32 INFO - PID 4149 | |0;ai-astar;99;108;100;107;108;111;120;100;107;108 18:42:32 INFO - PID 4149 | |1;audio-beat-detection;154;120;145;185;119;145;155;132;168;131 18:42:32 INFO - PID 4149 | |2;audio-dft;164;163;164;168;162;162;164;165;169;164 18:42:32 INFO - PID 4149 | |3;audio-fft;74;74;76;172;75;75;75;74;159;75 18:42:32 INFO - PID 4149 | |4;audio-oscillator;79;79;126;79;83;81;133;79;80;127 18:42:32 INFO - PID 4149 | |5;imaging-gaussian-blur;114;114;114;114;114;124;115;114;115;115 18:42:32 INFO - PID 4149 | |6;imaging-darkroom;152;150;152;151;152;151;151;151;152;151 18:42:32 INFO - PID 4149 | |7;imaging-desaturate;95;94;94;93;94;95;94;94;94;95 18:42:32 INFO - PID 4149 | |8;json-parse-financial;55;55;55;54;55;54;54;54;53;55 18:42:32 INFO - PID 4149 | |9;json-stringify-tinderbox;54;54;55;54;54;54;54;54;55;54 18:42:32 INFO - PID 4149 | |10;stanford-crypto-aes;81;79;79;96;90;89;79;80;87;77 18:42:32 INFO - PID 4149 | |11;stanford-crypto-ccm;126;139;138;132;138;132;139;126;136;158 18:42:32 INFO - PID 4149 | |12;stanford-crypto-pbkdf2;182;172;180;185;171;186;174;175;178;173 18:42:32 INFO - PID 4149 | |13;stanford-crypto-sha256-iterative;69;70;67;70;75;66;66;69;65;66 18:42:32 INFO - PID 4149 | __end_tp_report 18:42:32 INFO - PID 4149 | __start_cc_report 18:42:32 INFO - PID 4149 | _x_x_mozilla_cycle_collect,951 18:42:32 INFO - PID 4149 | __end_cc_report 18:42:32 INFO - PID 4149 | __startTimestamp1501724552780__endTimestamp 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | ------- Summary: start ------- 18:42:32 INFO - PID 4149 | Number of tests: 14 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#0] ai-astar Cycles:10 Average:106.80 Median:108.00 stddev:6.23 (5.8%) stddev-sans-first:5.94 18:42:32 INFO - PID 4149 | Values: 99.0 108.0 100.0 107.0 108.0 111.0 120.0 100.0 107.0 108.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:149.50 stddev:21.01 (14.1%) stddev-sans-first:22.06 18:42:32 INFO - PID 4149 | Values: 154.0 120.0 145.0 185.0 119.0 145.0 155.0 132.0 168.0 131.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.00 stddev:2.32 (1.4%) stddev-sans-first:2.46 18:42:32 INFO - PID 4149 | Values: 164.0 163.0 164.0 168.0 162.0 162.0 164.0 165.0 169.0 164.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#3] audio-fft Cycles:10 Average:92.90 Median:75.00 stddev:38.39 (51.2%) stddev-sans-first:40.11 18:42:32 INFO - PID 4149 | Values: 74.0 74.0 76.0 172.0 75.0 75.0 75.0 74.0 159.0 75.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:79.00 stddev:23.61 (29.9%) stddev-sans-first:24.36 18:42:32 INFO - PID 4149 | Values: 79.0 79.0 126.0 79.0 83.0 81.0 133.0 79.0 80.0 127.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#5] imaging-gaussian-blur Cycles:10 Average:115.30 Median:114.50 stddev:3.09 (2.7%) stddev-sans-first:3.24 18:42:32 INFO - PID 4149 | Values: 114.0 114.0 114.0 114.0 114.0 124.0 115.0 114.0 115.0 115.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 18:42:32 INFO - PID 4149 | Values: 152.0 150.0 152.0 151.0 152.0 151.0 151.0 151.0 152.0 151.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 18:42:32 INFO - PID 4149 | Values: 95.0 94.0 94.0 93.0 94.0 95.0 94.0 94.0 94.0 95.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:0.70 (1.3%) stddev-sans-first:0.71 18:42:32 INFO - PID 4149 | Values: 55.0 55.0 55.0 54.0 55.0 54.0 54.0 54.0 53.0 55.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 18:42:32 INFO - PID 4149 | Values: 54.0 54.0 55.0 54.0 54.0 54.0 54.0 54.0 55.0 54.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:6.34 (7.6%) stddev-sans-first:6.65 18:42:32 INFO - PID 4149 | Values: 81.0 79.0 79.0 96.0 90.0 89.0 79.0 80.0 87.0 77.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:138.00 stddev:9.07 (6.6%) stddev-sans-first:8.80 18:42:32 INFO - PID 4149 | Values: 126.0 139.0 138.0 132.0 138.0 132.0 139.0 126.0 136.0 158.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:179.00 stddev:5.44 (3.0%) stddev-sans-first:5.53 18:42:32 INFO - PID 4149 | Values: 182.0 172.0 180.0 185.0 171.0 186.0 174.0 175.0 178.0 173.0 18:42:32 INFO - PID 4149 | 18:42:32 INFO - PID 4149 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:2.98 (4.3%) stddev-sans-first:3.15 18:42:32 INFO - PID 4149 | Values: 69.0 70.0 67.0 70.0 75.0 66.0 66.0 69.0 65.0 66.0 18:42:32 INFO - PID 4149 | -------- Summary: end -------- 18:42:32 INFO - PID 4149 | 18:42:33 INFO - PID 4149 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:42:33 INFO - TEST-INFO | 4149: exit 0 18:42:33 INFO - TEST-OK | kraken | took 61657ms 18:42:33 INFO - SUITE-END | took 1176s 18:42:33 INFO - Completed test suite (00:19:37) 18:42:33 INFO - javascript benchmark 18:42:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8478.922335962392, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.3117714102896, 3135.561021456998, 3138.152698771332, 3172.4289627867, 3205.7755957083014, 6179.289381721103, 6195.287541932679, 6222.782202622909, 6247.656378899159, 6261.186045814038, 6026.819195424145, 6117.66352850593, 6119.755209791603, 6133.264008318997, 6172.074188871671, 5644.830655080344, 5651.180578816069, 5686.402927692595, 5709.971450142764, 5752.424757524256, 5718.313802343704, 5727.198192253088, 5731.856703582402, 5754.827355179351, 5756.769729210827, 5722.713864306789, 5732.79935202268, 5735.168400581913, 5751.396103409144, 5786.276715410574, 5707.400722924116, 5761.193432919395, 5763.942360576382, 5787.218725472049, 5821.56338274629, 5493.752781124819, 5630.915536266959, 5631.662100273997, 5705.4579814917515, 5711.20043193949, 6133.754649814002, 6153.784617538365, 6186.690665466709, 6207.441330280251, 6214.25428948529, 6688.83277918051, 6691.498137639696, 6790.185177778649, 6799.0, 6799.456043516556, 6696.5647232929705, 6760.932390676128, 6769.593824370504, 6846.897296540506, 6848.7260509579555, 6241.469475094623, 6250.499960003189, 6270.3416141305415, 6271.749130034747, 6287.9056814147825, 6795.0, 6816.045753594501, 6905.482088843357, 6907.0, 6914.928186131157, 6395.232572091379, 6461.160049193575, 6517.4134327910715, 6580.276169621337, 6622.86754264912, 6604.306547812506, 6627.436667883284, 6628.270890202074, 6636.568623039487, 6639.302873198341, 6580.736770529221, 6626.138601981712, 6642.368974947372, 6646.900296495556, 6659.833504162338, 6584.5061620378665, 6647.700853461606, 6696.59820410777, 6706.161729783777, 6772.864542709217, 5373.274607927958, 5427.83716488506, 5446.564274858002, 5455.1544510600925, 5462.863428414322, 4569.200389931749, 4593.035462552834, 4595.977020114878, 4637.976810115928, 4676.8129274828625, 5762.683052432073, 5782.971085144547, 5794.797182098607, 5796.710164491758, 5805.941940580625, 6301.960176570906, 6375.075614035939, 6376.234851817811, 6448.580842245333, 6649.235337936103, 5844.327902291291, 5895.7052147393315, 5905.704714764244, 5908.468237858613, 5915.0, 5841.835721942131, 5869.647821130746, 5938.020226662638, 5943.375945525744, 5944.4352786485215, 6351.904721429182, 6400.967995159995, 6450.838729031811, 6475.546711730228, 6480.384363485461, 6755.020522024377, 6780.69486873082, 6788.660566971533, 6795.0, 6798.0, 6432.935670643234, 6522.836929076811, 6553.967230163818, 6572.737090516326, 6575.0, 6631.270560238273, 6642.20293564756, 6715.261321254756, 6719.428848547928, 6720.395164435225, 6618.271990080991, 6740.494462915399, 6755.3582409671, 6767.796966091025, 6847.965760171167, 6408.391202835724, 6476.352364763486, 6485.546011779319, 6504.1219435375615, 6507.967460162668, 6424.678766061772, 6546.279909210079, 6579.868402631825, 6593.044008618627, 6595.571287866273, 6395.392437718409, 6432.614043157426, 6500.122483464861, 6509.837254068686, 6514.739410423719, 6474.676266186766, 6479.449246813885, 6544.705488252945, 6608.7026083825385, 6647.0, 6251.937480625135, 6253.124562561154, 6295.842603934755, 6300.621962682254, 6308.053791931247], "value": 6095.531019280459, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.411739723495, 870.3472395703222, 893.7586851550079, 897.0536084430927, 1123.7583919457315, 31057.84471077647, 32008.35983280333, 43571.346429803576, 44117.77941110298, 44249.33625995613, 43989.7800510997, 44025.55974440255, 44057.77971110148, 44243.77878110613, 44284.55715442845, 7663.616819159048, 7815.843683126334, 7831.4909530880595, 7832.686692532292, 7838.725644602411, 7736.071671399412, 7835.216478352177, 7850.293473587376, 7858.096318923312, 7878.094019187781, 7870.842583148334, 7872.960635196845, 7903.525788452711, 7910.841783164333, 7912.643931023089, 7767.6892924282965, 7844.4901081429525, 7844.803879902991, 7864.72473463426, 7883.881741773878, 7630.351420129296, 7706.6917323307, 7719.807004824868, 7732.768016959444, 7755.844883102306, 38914.0, 38943.8052809737, 39082.80458597689, 39147.41278880819, 39268.214635707125, 3624.963750362489, 3630.7276954228537, 3654.908627284339, 3655.5613326400735, 3659.249853779969, 32188.6781132188, 32229.838850805594, 32285.677143228735, 32423.0, 32477.350452991042, 4926.211806111005, 4939.209726443788, 4952.851414457536, 4960.0, 5028.0, 181816.0, 183546.0, 184553.0, 184969.0, 185318.0, 49189.0, 49268.50731492711, 49323.26015109776, 49421.50578494241, 49459.75270123626, 55024.72487637616, 55738.0, 55793.72103139458, 55832.16251756227, 55854.44145558574, 16120.516384508484, 16208.189590520427, 16228.269727862275, 16235.918820405823, 16236.75644865328, 13048.608541743763, 13136.934315328363, 13157.53948611794, 13199.010074244277, 13241.867581324255, 15111.848881511263, 15126.319315630823, 15219.239038048054, 15249.313780879887, 15362.846371536141, 5910.763569457174, 6050.6974651267265, 6111.96944015277, 6136.539759518054, 6148.1085242640465, 1001.2890847498212, 1023.3092662452815, 1026.6714651311659, 1029.0532709906904, 1030.1141018723893, 23470.295891123293, 23471.41321466977, 23533.646995295083, 23543.0, 23665.290041298787, 1128.15388458656, 1131.6944425005202, 1139.2993309114834, 1140.5551834784442, 1142.8285757136496, 12504.874951250553, 12676.112672112864, 16099.0, 16392.918035409748, 16600.418985335455, 219640.0, 230949.0, 234079.0, 234262.0, 234511.0, 49994.500054998985, 58583.0, 59878.0, 60295.69852150799, 60452.09321860175, 1481.459267367403, 1490.8509149085005, 1493.5370035289095, 1501.091839437125, 1520.429838810446, 27405.86297068502, 27667.72332276732, 27778.444431111668, 27790.86104569464, 27933.301667458476, 52009.21986170135, 52816.73591632017, 52851.47148528465, 52863.20705189502, 52869.0, 203722.0, 204140.0, 205271.0, 205660.0, 205757.0, 36962.07594810151, 36992.445113322785, 37191.62808371882, 37192.814035929645, 37348.43977340397, 44250.336244957, 45709.77145114253, 45841.54158458506, 45864.0, 46042.30936535887, 32711.0, 33361.332773343915, 33497.66502334945, 33513.0, 33533.83233083819, 32357.676423235465, 32674.509882351307, 32753.18117047093, 33403.33193336168, 33869.0, 34880.47679284762, 36287.81856090703, 36549.45175822311, 36565.45151822671, 36589.451158232114, 31705.524417134224, 31888.36223275568, 31904.521432179, 31907.521387178745, 32348.676513235507, 23267.0, 23779.335446518577, 24179.0, 24221.152259670827, 24442.26673199807], "value": 17921.413964384166, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.6616463204394, 580.5935844908564, 584.5644994479113, 585.7100735136108, 594.1088367448826, 22240.332790016306, 22292.331230063082, 23114.0, 23152.65271020934, 23169.420764480856, 26292.737072629316, 26843.463130737375, 27002.0, 27003.594946075824, 27047.1885843425, 6520.70656820443, 6642.966785166067, 6655.434288085518, 6693.765718199864, 6694.397504224618, 6032.095185722133, 6560.671966401685, 6578.24350199726, 6586.341365863399, 6630.569013014163, 6580.967095164541, 6584.0, 6584.736610535573, 6591.143151390336, 6599.373059559358, 6517.576357536745, 6543.345665433442, 6574.605523668547, 6574.769883054094, 6577.736890524373, 6125.448709616111, 6129.632222066645, 6132.294786099611, 6133.570650054498, 6146.17026701394, 29192.41615167706, 30298.242543936358, 30462.84768576165, 30664.77340906361, 30761.077167685005, 1937.8159074887863, 1970.2808474906699, 1970.5467742419323, 1970.7930667279873, 1971.5761111361126, 19193.232270709155, 19752.604947901105, 19773.209071637117, 19805.40583782489, 19835.206591736314, 2219.4340443187048, 2282.988585057064, 2284.6458798886033, 2285.6685780561725, 2422.9394265143337, 132670.0, 138667.30666346705, 139187.30406347904, 139333.3033334837, 139618.0, 14230.573082807532, 14880.404783808743, 14971.92514037423, 15046.172460514557, 15313.23433828304, 23614.11087113027, 23942.52114957691, 24047.398815029414, 24728.505429891302, 24775.0, 9926.950365248127, 9987.250956178315, 10044.347117437343, 10084.193264538819, 10252.282340236261, 8873.112688731075, 8901.688440904538, 9025.142611451902, 9032.23226025782, 9058.275337972946, 9128.58921348541, 9402.670906518239, 9477.952610236905, 9545.284103692251, 9623.663171788954, 2629.500394924958, 2681.9004208274905, 2733.26201925479, 2793.9441211175663, 2852.5863749756168, 972.6936015155203, 987.7579992901785, 993.980120397588, 1003.6988903328972, 1011.4841430870309, 19420.320288789826, 19484.318048868223, 19593.216271349273, 20150.193992240438, 20362.898185509275, 1039.6725031615017, 1051.2010871737382, 1056.6882769582992, 1068.9572417103384, 1078.2613909472036, 10866.276195754797, 11162.832557511643, 11175.329480231212, 11191.440427978569, 11250.774984500264, 456208.0, 460934.8672796436, 463060.0, 479147.0, 481144.0, 112635.0, 114123.0, 114765.0, 114881.42559287317, 116908.41545792216, 1221.1574013930538, 1233.2846948769693, 1257.3838818978634, 1260.4013093780563, 1263.7851565233932, 17630.559236019202, 17801.643967120326, 17916.91041544836, 17953.10234488244, 18144.092795359917, 51712.22431663453, 52536.73731631317, 53009.0, 54171.0, 54298.0, 63875.68062159659, 64123.67938160279, 64146.35853641591, 65121.67439162774, 65262.34737652563, 13956.0, 14025.579232623038, 14101.858981410054, 14157.929210353883, 14201.857981420053, 22360.552788944457, 22473.0, 22580.5483890318, 22658.773412265666, 22880.313590592308, 17311.30754769795, 17836.821631783518, 17978.37075702344, 17985.820141798413, 18018.639627207645, 14954.85045149578, 15226.23868806533, 16356.427525036566, 16452.917735411724, 16660.83339166593, 18306.084695765425, 18452.723209151605, 18599.90700046491, 18698.252069917053, 18703.625927481102, 14339.641508962359, 14362.0, 15176.772348415003, 15180.089194648357, 15187.0, 18870.056497174814, 19008.0, 19018.809811901705, 19050.237990480226, 19176.424707258804], "value": 11930.35169969639, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.7889227169785, 1390.2631605249214, 1420.438926623983, 1420.9715805683886, 1437.065907160346, 13165.999999999987, 13577.932110339434, 13752.724945501097, 13755.037147399686, 13755.5185568505, 14085.366158522866, 14381.137131772075, 14398.784018239734, 14402.927985360058, 14414.639634009154, 2575.6780402449695, 2680.32991752062, 2719.945601087977, 2728.6998430172666, 2731.3308239481353, 2725.141580402167, 2745.3960128771637, 2745.4646343962936, 2745.6567929008875, 2755.0357374918817, 2534.214393538, 2552.9489410211786, 2653.1642532602264, 2687.516247075526, 2700.338417087806, 2501.774840264367, 2555.4889022195634, 2588.521123592132, 2606.4656745367156, 2610.2821724025853, 2571.9871400643065, 2606.4787042591556, 2623.27859838544, 2623.960640590393, 2631.6184153297854, 13434.596962091153, 13722.65693357664, 13740.244286564235, 13773.449062037507, 13774.724505509934, 7592.430567707444, 7843.6470358833985, 7858.214178582153, 7871.330936870373, 7874.685012599489, 4964.15609346412, 5078.64449488536, 5141.15170996785, 5210.244514545369, 5228.37259528859, 1775.1656721340948, 1796.8078943688809, 1799.3194944255513, 1830.6247219320007, 1833.2941817400263, 22315.0, 22534.54930901389, 22570.21004264843, 22607.208747693916, 22660.09359625613, 6852.177738671391, 6864.759733409406, 7140.964295178491, 7170.749023784142, 7175.390091842148, 15287.617809554627, 15483.225838708246, 15503.147326896904, 15504.922475387551, 15510.534683959499, 7491.951126842247, 7685.116211635622, 7685.5004424712215, 7698.846023079507, 7733.3426658733515, 6965.47758918073, 7033.191183013917, 7176.174739904978, 7215.422766178693, 7258.927410725931, 5091.516305950929, 5198.740062996911, 5209.557187639018, 5273.34083239595, 5302.92045619316, 1506.7965824613757, 1518.0, 1523.1089812459836, 1529.9158546280062, 1538.2693220720068, 785.1363500149789, 805.6817557065044, 821.1583127294451, 825.4263287015586, 840.7771940435789, 6243.875122497525, 6424.967875160688, 7278.813008942357, 7477.626118694044, 7599.0, 918.5085979001202, 964.8504481805331, 973.2797729679986, 976.3507267666922, 982.7592239901127, 4316.1583491219535, 4425.203463376559, 4428.623566996841, 4435.467743870691, 4485.820567177341, 18234.179461924246, 18906.621867562848, 19127.426177214707, 19128.234870605298, 19147.90426047889, 18010.0, 18627.0, 18660.906695466707, 18781.436556903314, 18838.24647014104, 10184.847227291597, 10386.0, 10450.477476126163, 10460.947695261475, 10519.84220236697, 6892.896606551006, 7098.219195888551, 7182.497225194186, 7262.128544574685, 7311.597862117635, 16258.26837792326, 16426.096564688578, 16652.250648720594, 16841.2421441033, 17147.142642868057, 16971.490855274362, 16971.490855274362, 17121.657566848844, 17149.228284727466, 17165.227564759367, 10566.577336906437, 10784.730381740206, 10807.729806754895, 10810.56757729682, 10812.891871081503, 12558.811617825557, 12657.430415631134, 12703.93648031754, 12766.425510851848, 12775.23348599087, 9983.400995940267, 10296.742581435525, 10402.167826573705, 10555.366677999345, 10606.0, 9640.132388085107, 9684.176844968257, 10242.6415075472, 10262.43556604394, 10395.220358473296, 8701.260392866678, 8849.955750221208, 8974.506402147683, 8979.10208979097, 8993.190612844875, 6271.4669253114, 6284.183056202664, 6296.653684047421, 6314.431701146918, 6339.809805705836, 6340.270868850142, 6391.2330520337855, 6395.776147834804, 6418.935810641834, 6436.710348034443], "value": 6205.81265658099, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.25015726567415, 504.3745755263474, 524.331477366358, 531.1395539226454, 536.4045909040964, 6112.847178820532, 6184.907226391608, 6302.810915672526, 6313.179286692729, 6384.808455746329, 6032.0, 6061.9696901515545, 6091.817245482643, 6095.939040609593, 6121.0, 4799.328094066834, 4978.576820970222, 5010.749462526877, 5026.3558919783045, 5081.568066714324, 4982.327385802906, 4991.32617096691, 4992.975035124819, 4995.225740010304, 5026.698398096126, 4929.162042452792, 4944.629152813535, 4944.678595891256, 4959.206526955688, 5004.674696144757, 4877.829275975341, 4927.679700819435, 4952.579030782388, 5016.8996620067755, 5018.874528136789, 4504.301833215857, 4504.301833215857, 4505.279155335131, 4513.300438432048, 4517.299818528133, 5899.085641725539, 6011.458968692795, 6021.879562408771, 6051.304100028465, 6157.199564056644, 5586.190002449622, 5603.971980140114, 5668.631538949996, 5683.460071293221, 5704.258446401941, 6491.7078731457195, 6515.315891831384, 6553.737850485975, 6599.57102788322, 6610.834729131762, 4797.808087676489, 4843.830465933675, 4860.902781944376, 4899.8040078396825, 4909.828156014558, 6476.643784591845, 6755.459563234978, 6797.864042719168, 6805.931940680629, 6989.755358562476, 4906.950930490721, 4932.506749325039, 4947.975260123676, 4961.305417241589, 4990.575801056878, 4981.402231732143, 4992.425871024806, 5011.974940125276, 5062.924201077614, 5086.618503612244, 5582.074184406373, 5652.97173514138, 5675.489205971482, 5724.656520608777, 5729.942700573024, 6542.934570654328, 6560.212774467094, 6580.440662543641, 6581.670916454158, 6642.667866606747, 1755.8331958463925, 1765.5056584156457, 1767.6641438126715, 1787.1064467766116, 1796.3173993882242, 1161.3960740414936, 1227.8096894981293, 1232.0020783165485, 1234.456838990841, 1235.4131787400913, 5770.307563092372, 5784.65292082468, 5802.1296805479515, 5831.4168583141345, 5848.502877255481, 6667.233268174222, 6671.633060181736, 6696.665166741644, 6702.664866756741, 6739.123913891261, 5619.971900140555, 5714.885702286014, 5744.253247077853, 5748.815450129008, 5774.23885714861, 3499.3526197653514, 3533.3993221152464, 3534.770239934396, 3554.111472131967, 3595.0, 6360.268569114519, 6453.870922581522, 6465.579737317154, 6467.186773753432, 6515.7067931943175, 6710.731570737214, 6778.98315252716, 6789.830254243683, 6818.727250909908, 6851.143607049119, 6284.051943897406, 6379.170707807955, 6384.968075159594, 6391.744330226832, 6405.231372235362, 5930.258717660293, 6556.803295901131, 6692.0, 6700.43357509653, 6745.258021617717, 6493.123428337114, 6565.507586931095, 6594.812294193126, 6610.900836487361, 6636.141880273532, 6389.840253993687, 6430.64631445275, 6462.483001359974, 6486.578372405778, 6491.5131365148745, 4920.989393644169, 4927.260910863399, 4953.3065370848835, 4974.0, 5008.398992120969, 6464.41820236181, 6475.935240647534, 6582.736690532325, 6649.401553860176, 6767.93232067673, 6447.6131432114225, 6448.29068802422, 6457.6448295342325, 6463.482921366185, 6499.115256575729, 6465.965445528693, 6486.254080780771, 6502.414782669583, 6507.12153859223, 6543.5419520633095, 6459.483241340776, 6462.806115816533, 6469.514786390945, 6481.837954051187, 6486.189226346707, 6543.509236807164, 6575.342465753386, 6611.140551728437, 6614.437772789367, 6617.404433600999, 6229.719662615103, 6270.968645156745, 6282.0, 6356.650384228912, 6413.16628838248], "value": 5030.702151589335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [912.0833562269918, 918.9127032931872, 936.6768464879614, 940.2431043010374, 954.8949615542289, 20344.593108137848, 21960.890195549, 21993.0, 22025.779742202572, 22044.008019639114, 19999.60000799983, 20837.0, 20892.0, 20969.0, 20984.0, 1043.6921108273077, 1077.784443111377, 1078.3422112511362, 1080.7676349584838, 1080.9945950270278, 947.9383840050409, 1032.9225308101884, 1050.2280823594651, 1053.3627155570884, 1054.3410368519676, 1107.4850194659482, 1113.7772445510889, 1120.025577747361, 1121.495327102803, 1124.8144056230713, 1084.9349039057681, 1088.4612117002096, 1091.5633746501462, 1092.9125669946386, 1098.6703988803367, 797.6370751308141, 798.5568009754642, 801.9759407217792, 802.8193656427316, 803.3211935914143, 16049.277782499816, 16298.918505407515, 17051.062191579458, 17138.828611713972, 17257.741133883002, 20605.587888242153, 20700.896495517576, 21063.262785802424, 21146.577068458697, 21172.047257873433, 15709.135997520132, 15872.047677139293, 16045.759313610306, 16056.27746751399, 16077.11575863327, 3525.0, 3558.768680035815, 3570.357335679577, 3573.6069032406676, 3574.410222313315, 125072.37463812713, 128068.35965820111, 128177.0, 130047.34976325152, 130206.0, 15725.213739312989, 15883.602909927113, 16273.349066037463, 16359.100249486393, 16490.0, 24175.879120604284, 25247.495050098896, 27966.0, 29647.703522964493, 29851.85074074616, 8958.731238062868, 9205.907940920639, 9222.123898229658, 9250.90749092514, 9300.674476393293, 4237.703360764778, 4361.498427680794, 4384.276594361894, 4394.318880573516, 4403.361512580658, 5352.170413585901, 5451.182322651634, 5457.2359869618285, 5490.039243132436, 5493.09363954943, 1105.2815669814686, 1148.4315263944402, 1159.7854396936593, 1159.8318243854594, 1159.9014083802801, 689.8309914071085, 702.4977141343963, 706.1279320039747, 712.7398499547628, 730.9963450182715, 11788.764224715458, 12310.0, 12830.743385132246, 13372.531961381499, 13733.0, 699.1191099215025, 699.8460338725472, 702.2696395748364, 712.5617745086838, 716.451914285572, 5286.841394758163, 5291.047611429903, 5630.761232528837, 5694.715264236772, 5776.075827867605, 102287.0, 103671.48164259281, 103684.0, 104356.47821760994, 104972.0, 90921.09078909259, 92061.0, 94874.52562737142, 96938.0, 97703.0, 79166.0, 79830.6008469954, 80079.59960200162, 84788.0, 85565.0, 12296.938515307365, 12521.56174533887, 12804.231746095264, 12959.546415875398, 13222.801657974946, 35978.8201058993, 36014.8199259002, 36110.638893610725, 37884.43173352457, 38127.618723812404, 44611.77694111509, 44959.77520112378, 47239.76380118077, 59573.404265956786, 67262.3273767256, 18097.638047238717, 18161.45515634533, 18271.634567308312, 18450.35423760162, 18483.0, 20124.79875201229, 20819.687704684744, 20877.791222087584, 20884.37346879596, 21038.68441973341, 17991.550211244823, 18004.90997545004, 18111.184996674918, 18117.818821811612, 18451.354202602844, 17908.55228619295, 18247.635047298714, 18464.169112390242, 18548.536286592942, 18632.627347453246, 15887.602809929382, 16092.195390230207, 18387.0, 19023.809761902205, 19183.32858349951, 12327.260364378166, 13002.0, 13362.665933351744, 13999.0, 14575.416983320549, 11156.944215278872, 14222.644433889236, 14425.134491930517, 14807.407703691732, 14943.327550260046], "value": 9131.690634981918, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 100.0, 107.0, 108.0, 111.0, 120.0, 100.0, 107.0, 108.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 145.0, 185.0, 119.0, 145.0, 155.0, 132.0, 168.0, 131.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 168.0, 162.0, 162.0, 164.0, 165.0, 169.0, 164.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 172.0, 75.0, 75.0, 75.0, 74.0, 159.0, 75.0], "value": 92.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 126.0, 79.0, 83.0, 81.0, 133.0, 79.0, 80.0, 127.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 124.0, 115.0, 114.0, 115.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 152.0, 151.0, 152.0, 151.0, 151.0, 151.0, 152.0, 151.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0, 54.0, 53.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 79.0, 96.0, 90.0, 89.0, 79.0, 80.0, 87.0, 77.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 139.0, 138.0, 132.0, 138.0, 132.0, 139.0, 126.0, 136.0, 158.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 172.0, 180.0, 185.0, 171.0, 186.0, 174.0, 175.0, 178.0, 173.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 67.0, 70.0, 75.0, 66.0, 66.0, 69.0, 65.0, 66.0], "value": 68.3, "unit": "score"}]}]} 18:42:33 INFO - Return code: 0 18:42:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:42:34 INFO - # TBPL SUCCESS # 18:42:34 INFO - Running post-action listener: _package_coverage_data 18:42:34 INFO - Running post-action listener: _resource_record_post_action 18:42:34 INFO - [mozharness: 2017-08-03 01:42:34.051600Z] Finished run-tests step (success) 18:42:34 INFO - Running post-run listener: _resource_record_post_run 18:42:34 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 5472256; Write bytes: 430739456; Read time: 1612; Write time: 424020 18:42:34 INFO - TinderboxPrint: CPU usage
13.1% 18:42:34 INFO - TinderboxPrint: I/O read bytes / time
5,472,256 / 1,612 18:42:34 INFO - TinderboxPrint: I/O write bytes / time
430,739,456 / 424,020 18:42:34 INFO - TinderboxPrint: CPU idle
8,280.4 (86.8%) 18:42:34 INFO - TinderboxPrint: CPU user
1,201.1 (12.6%) 18:42:34 INFO - TinderboxPrint: Swap in / out
0 / 0 18:42:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106790912; Read time: 0; Write time: 127580 18:42:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:42:34 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 3235840; Write bytes: 323948544; Read time: 1412; Write time: 296440 18:42:34 INFO - Running post-run listener: _upload_blobber_files 18:42:34 INFO - Blob upload gear active. 18:42:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:34 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 18:42:34 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'] 18:42:34 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 18:42:35 INFO - (blobuploader) - INFO - Open directory for files ... 18:42:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:42:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:42:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:36 INFO - (blobuploader) - INFO - Done attempting. 18:42:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:42:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:42:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:36 INFO - (blobuploader) - INFO - Done attempting. 18:42:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:42:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:42:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:40 INFO - (blobuploader) - INFO - Done attempting. 18:42:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:42:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:42:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:40 INFO - (blobuploader) - INFO - Done attempting. 18:42:40 INFO - (blobuploader) - INFO - Iteration through files over. 18:42:41 INFO - Return code: 0 18:42:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:42:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:42:41 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c53ea29d2bd5aa9a0e40ce0da22db594cab2951834adfd99c74742ff47cbd6219f562879f285be76cdeca8f1f3736d4e439b570fa610965ab23585abf3bb675c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d72537d1a1dc0b47f07014f9b5bef3752504144b9ecd62f9900469348d64f0a1782591aeb510df27474e8b75259ec2cbdfcdc59f9dde767130ee66f0ad51ad88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aae8fb864f3b16c9eed74777e9b7372842302211aeb5669a035fd9086c464770e9010e4e967d8a34b8b07a46ae7b32100535b8c6e5df6149d8d89105922a584b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7bd0fca12cc24643ac71fea1d41433da38d8f77926419a2a3fa9846326c4ecefb1bd79ddc0332de5eebdae2ba1d9e5f218adf1507fd51d1ef37af53351aa466"} 18:42:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:42:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:42:41 INFO - Contents: 18:42:41 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c53ea29d2bd5aa9a0e40ce0da22db594cab2951834adfd99c74742ff47cbd6219f562879f285be76cdeca8f1f3736d4e439b570fa610965ab23585abf3bb675c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d72537d1a1dc0b47f07014f9b5bef3752504144b9ecd62f9900469348d64f0a1782591aeb510df27474e8b75259ec2cbdfcdc59f9dde767130ee66f0ad51ad88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aae8fb864f3b16c9eed74777e9b7372842302211aeb5669a035fd9086c464770e9010e4e967d8a34b8b07a46ae7b32100535b8c6e5df6149d8d89105922a584b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7bd0fca12cc24643ac71fea1d41433da38d8f77926419a2a3fa9846326c4ecefb1bd79ddc0332de5eebdae2ba1d9e5f218adf1507fd51d1ef37af53351aa466"} 18:42:41 INFO - Running post-run listener: copy_logs_to_upload_dir 18:42:41 INFO - Copying logs to upload dir... 18:42:41 INFO - mkdir: /builds/slave/test/build/upload/logs 18:42:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.313535 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-02 18:42:40.994915) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 18:42:40.998422) ========= 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=836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416 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/c53ea29d2bd5aa9a0e40ce0da22db594cab2951834adfd99c74742ff47cbd6219f562879f285be76cdeca8f1f3736d4e439b570fa610965ab23585abf3bb675c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d72537d1a1dc0b47f07014f9b5bef3752504144b9ecd62f9900469348d64f0a1782591aeb510df27474e8b75259ec2cbdfcdc59f9dde767130ee66f0ad51ad88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aae8fb864f3b16c9eed74777e9b7372842302211aeb5669a035fd9086c464770e9010e4e967d8a34b8b07a46ae7b32100535b8c6e5df6149d8d89105922a584b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7bd0fca12cc24643ac71fea1d41433da38d8f77926419a2a3fa9846326c4ecefb1bd79ddc0332de5eebdae2ba1d9e5f218adf1507fd51d1ef37af53351aa466"} build_url:https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011204 build_url: 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c53ea29d2bd5aa9a0e40ce0da22db594cab2951834adfd99c74742ff47cbd6219f562879f285be76cdeca8f1f3736d4e439b570fa610965ab23585abf3bb675c", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d72537d1a1dc0b47f07014f9b5bef3752504144b9ecd62f9900469348d64f0a1782591aeb510df27474e8b75259ec2cbdfcdc59f9dde767130ee66f0ad51ad88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aae8fb864f3b16c9eed74777e9b7372842302211aeb5669a035fd9086c464770e9010e4e967d8a34b8b07a46ae7b32100535b8c6e5df6149d8d89105922a584b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7bd0fca12cc24643ac71fea1d41433da38d8f77926419a2a3fa9846326c4ecefb1bd79ddc0332de5eebdae2ba1d9e5f218adf1507fd51d1ef37af53351aa466"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 18:42:41.033405) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:42:41.033736) ========= 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=836cfdc75598d54cb747368e000001a9-1501723264.144964-113626416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004622 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:42:41.094781) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 18:42:41.097129) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 18:42:41.097401) ========= ========= Total master_lag: 0.57 =========