builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-028 starttime: 1477436055.36 results: success (0) buildid: 20161025131024 builduid: dcc442041cff4bcea2f2bd01e46ce008 revision: c45f9369c95cb492a2dee21e9c5cefde192f573c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.363210) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.363770) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.364071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.382004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.382366) ========= 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-1477436054.964565-1374205825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-25 15:54:15-- 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% 32.8M=0s 2016-10-25 15:54:16 (32.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128679 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.525221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.525575) ========= 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-1477436054.964565-1374205825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015026 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.575184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:15.578626) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c45f9369c95cb492a2dee21e9c5cefde192f573c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c45f9369c95cb492a2dee21e9c5cefde192f573c --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-1477436054.964565-1374205825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-25 15:54:16,127 truncating revision to first 12 chars 2016-10-25 15:54:16,127 Setting DEBUG logging. 2016-10-25 15:54:16,127 attempt 1/10 2016-10-25 15:54:16,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c45f9369c95c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 15:54:16,446 unpacking tar archive at: mozilla-beta-c45f9369c95c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.713148 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:16.305284) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:16.305613) ========= script_repo_revision: c45f9369c95cb492a2dee21e9c5cefde192f573c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:16.306001) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:16.306261) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 15:54:16.319950) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-25 15:54:16.320310) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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-1477436054.964565-1374205825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:54:16 INFO - MultiFileLogger online at 20161025 15:54:16 in /builds/slave/test 15:54:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:54:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:54:16 INFO - {'append_to_log': False, 15:54:16 INFO - 'base_work_dir': '/builds/slave/test', 15:54:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:54:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:54:16 INFO - 'branch': 'Mozilla-Beta', 15:54:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:54:16 INFO - 'config_files': ('talos/linux_config.py',), 15:54:16 INFO - 'default_actions': ('clobber', 15:54:16 INFO - 'read-buildbot-config', 15:54:16 INFO - 'download-and-extract', 15:54:16 INFO - 'populate-webroot', 15:54:16 INFO - 'create-virtualenv', 15:54:16 INFO - 'install', 15:54:16 INFO - 'run-tests'), 15:54:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:54:16 INFO - 'download_minidump_stackwalk': True, 15:54:16 INFO - 'download_symbols': 'ondemand', 15:54:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:54:16 INFO - 'tooltool.py': '/tools/tooltool.py', 15:54:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:54:16 INFO - '/tools/misc-python/virtualenv.py')}, 15:54:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:54:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:54:16 INFO - 'installer_path': 'installer.exe', 15:54:16 INFO - 'log_level': 'info', 15:54:16 INFO - 'log_name': 'talos', 15:54:16 INFO - 'log_to_console': True, 15:54:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:54:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:54:16 INFO - 'opt_config_files': (), 15:54:16 INFO - 'pip_index': False, 15:54:16 INFO - 'sps_profile': False, 15:54:16 INFO - 'sps_profile_interval': 0, 15:54:16 INFO - 'suite': 'dromaeojs-e10s', 15:54:16 INFO - 'system_bits': '32', 15:54:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:54:16 INFO - 'title': 'talos-linux64-ix-028', 15:54:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:54:16 INFO - 'use_talos_json': True, 15:54:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:54:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:54:16 INFO - 'work_dir': 'build'} 15:54:16 INFO - [mozharness: 2016-10-25 22:54:16.969543Z] Running clobber step. 15:54:16 INFO - Running pre-action listener: _resource_record_pre_action 15:54:16 INFO - Running main action method: clobber 15:54:16 INFO - rmtree: /builds/slave/test/build 15:54:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:54:17 INFO - Running post-action listener: _resource_record_post_action 15:54:17 INFO - [mozharness: 2016-10-25 22:54:17.429249Z] Finished clobber step (success) 15:54:17 INFO - [mozharness: 2016-10-25 22:54:17.429348Z] Running read-buildbot-config step. 15:54:17 INFO - Running pre-action listener: _resource_record_pre_action 15:54:17 INFO - Running main action method: read_buildbot_config 15:54:17 INFO - Using buildbot properties: 15:54:17 INFO - { 15:54:17 INFO - "project": "", 15:54:17 INFO - "product": "firefox", 15:54:17 INFO - "script_repo_revision": "production", 15:54:17 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 15:54:17 INFO - "repository": "", 15:54:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 15:54:17 INFO - "buildid": "20161025131024", 15:54:17 INFO - "pgo_build": "True", 15:54:17 INFO - "basedir": "/builds/slave/test", 15:54:17 INFO - "buildnumber": 171, 15:54:17 INFO - "slavename": "talos-linux64-ix-028", 15:54:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:54:17 INFO - "platform": "ubuntu64_hw", 15:54:17 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:54:17 INFO - "branch": "mozilla-beta", 15:54:17 INFO - "repo_path": "releases/mozilla-beta", 15:54:17 INFO - "revision": "c45f9369c95cb492a2dee21e9c5cefde192f573c", 15:54:17 INFO - "stage_platform": "linux64-pgo", 15:54:17 INFO - "builduid": "dcc442041cff4bcea2f2bd01e46ce008", 15:54:17 INFO - "slavebuilddir": "test" 15:54:17 INFO - } 15:54:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 15:54:17 INFO - Running post-action listener: _resource_record_post_action 15:54:17 INFO - [mozharness: 2016-10-25 22:54:17.431776Z] Finished read-buildbot-config step (success) 15:54:17 INFO - [mozharness: 2016-10-25 22:54:17.431865Z] Running download-and-extract step. 15:54:17 INFO - Running pre-action listener: _resource_record_pre_action 15:54:17 INFO - Running main action method: download_and_extract 15:54:17 INFO - mkdir: /builds/slave/test/build/tests 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:54:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:54:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:54:17 INFO - retry: Failed, sleeping 30 seconds before retrying 15:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 15:54:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:54:47 INFO - retry: Failed, sleeping 60 seconds before retrying 15:55:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:55:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:55:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:55:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 15:55:47 INFO - Caught exception: HTTP Error 404: Not Found 15:55:47 INFO - Caught exception: HTTP Error 404: Not Found 15:55:47 INFO - Caught exception: HTTP Error 404: Not Found 15:55:47 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:55:47 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:55:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:55:48 INFO - Downloaded 1327 bytes. 15:55:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:55:48 INFO - Using the following test package requirements: 15:55:48 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:55:48 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:55:48 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 15:55:48 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'jsshell-linux-x86_64.zip'], 15:55:48 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:55:48 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:55:48 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 15:55:48 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 15:55:48 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:55:48 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:55:48 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:55:48 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:48 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:55:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:55:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:55:48 INFO - retry: Failed, sleeping 30 seconds before retrying 15:56:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:56:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:56:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:56:18 INFO - retry: Failed, sleeping 60 seconds before retrying 15:57:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:57:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:57:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:57:18 INFO - Caught exception: HTTP Error 404: Not Found 15:57:18 INFO - Caught exception: HTTP Error 404: Not Found 15:57:18 INFO - Caught exception: HTTP Error 404: Not Found 15:57:18 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:18 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:57:19 INFO - Downloaded 21774599 bytes. 15:57:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:57:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:57:20 INFO - Return code: 0 15:57:20 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:20 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:57:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:57:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:57:20 INFO - retry: Failed, sleeping 30 seconds before retrying 15:57:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:57:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:57:50 INFO - retry: Failed, sleeping 60 seconds before retrying 15:58:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:58:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:58:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:58:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:58:50 INFO - Caught exception: HTTP Error 404: Not Found 15:58:50 INFO - Caught exception: HTTP Error 404: Not Found 15:58:50 INFO - Caught exception: HTTP Error 404: Not Found 15:58:50 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:58:50 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:58:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:58:51 INFO - Downloaded 21774599 bytes. 15:58:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:58:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:58:52 INFO - Return code: 0 15:58:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:52 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:58:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:58:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:58:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:58:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:58:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:58:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:58:52 INFO - retry: Failed, sleeping 30 seconds before retrying 15:59:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:59:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:59:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:59:22 INFO - retry: Failed, sleeping 60 seconds before retrying 16:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 16:00:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:00:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:00:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 16:00:22 INFO - Caught exception: HTTP Error 404: Not Found 16:00:22 INFO - Caught exception: HTTP Error 404: Not Found 16:00:22 INFO - Caught exception: HTTP Error 404: Not Found 16:00:22 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:00:22 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:00:23 INFO - Downloaded 14413762 bytes. 16:00:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:00:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:00:23 INFO - Return code: 0 16:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:23 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:00:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:00:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:00:23 INFO - retry: Failed, sleeping 30 seconds before retrying 16:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 16:00:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:00:53 INFO - retry: Failed, sleeping 60 seconds before retrying 16:01:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 16:01:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:01:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:01:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 16:01:53 INFO - Caught exception: HTTP Error 404: Not Found 16:01:53 INFO - Caught exception: HTTP Error 404: Not Found 16:01:53 INFO - Caught exception: HTTP Error 404: Not Found 16:01:53 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:01:53 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:01:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:01:54 INFO - Downloaded 56776167 bytes. 16:01:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:01:54 INFO - mkdir: /builds/slave/test/properties 16:01:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:54 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:54 INFO - Contents: 16:01:54 INFO - build_url:https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:01:55 INFO - Running post-action listener: _resource_record_post_action 16:01:55 INFO - Running post-action listener: set_extra_try_arguments 16:01:55 INFO - [mozharness: 2016-10-25 23:01:55.711419Z] Finished download-and-extract step (success) 16:01:55 INFO - [mozharness: 2016-10-25 23:01:55.711651Z] Running populate-webroot step. 16:01:55 INFO - Running pre-action listener: _resource_record_pre_action 16:01:55 INFO - Running main action method: populate_webroot 16:01:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:01:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:01:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:01:55 INFO - '--tppagecycles', 16:01:55 INFO - '7'], 16:01:55 INFO - 'tests': ['tsvgm']}}, 16:01:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:01:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:01:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['damp', 'tps']}, 16:01:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'tests': ['damp', 'tps']}, 16:01:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['dromaeo_dom']}, 16:01:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['basic_compositor_video']}, 16:01:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:01:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['a11yr', 16:01:55 INFO - 'ts_paint', 16:01:55 INFO - 'tpaint', 16:01:55 INFO - 'sessionrestore', 16:01:55 INFO - 'sessionrestore_no_auto_restore', 16:01:55 INFO - 'tabpaint']}, 16:01:55 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:55 INFO - 'ts_paint', 16:01:55 INFO - 'tpaint', 16:01:55 INFO - 'sessionrestore', 16:01:55 INFO - 'sessionrestore_no_auto_restore', 16:01:55 INFO - 'tabpaint']}, 16:01:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:01:55 INFO - 'ts_paint', 16:01:55 INFO - 'tpaint', 16:01:55 INFO - 'sessionrestore', 16:01:55 INFO - 'sessionrestore_no_auto_restore', 16:01:55 INFO - 'tabpaint']}, 16:01:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:01:55 INFO - 'ts_paint', 16:01:55 INFO - 'tpaint', 16:01:55 INFO - 'sessionrestore', 16:01:55 INFO - 'sessionrestore_no_auto_restore', 16:01:55 INFO - 'tabpaint']}, 16:01:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['a11yr', 16:01:55 INFO - 'ts_paint', 16:01:55 INFO - 'tpaint', 16:01:55 INFO - 'sessionrestore', 16:01:55 INFO - 'sessionrestore_no_auto_restore', 16:01:55 INFO - 'tabpaint']}, 16:01:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['a11yr', 16:01:55 INFO - 'ts_paint', 16:01:55 INFO - 'tpaint', 16:01:55 INFO - 'sessionrestore', 16:01:55 INFO - 'sessionrestore_no_auto_restore', 16:01:55 INFO - 'tabpaint']}, 16:01:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['tsvgx', 16:01:55 INFO - 'tsvgr_opacity', 16:01:55 INFO - 'tart', 16:01:55 INFO - 'tscrollx', 16:01:55 INFO - 'cart']}, 16:01:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:55 INFO - 'tsvgr_opacity', 16:01:55 INFO - 'tart', 16:01:55 INFO - 'tscrollx', 16:01:55 INFO - 'cart']}, 16:01:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'talos_options': ['--disable-e10s'], 16:01:55 INFO - 'tests': ['tp5o']}, 16:01:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'tests': ['tp5o']}, 16:01:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'talos_options': ['--disable-e10s', 16:01:55 INFO - '--xperf_path', 16:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:55 INFO - 'tests': ['tp5n']}, 16:01:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:01:55 INFO - 'talos_options': ['--xperf_path', 16:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:55 INFO - 'tests': ['tp5n']}}, 16:01:55 INFO - 'talos.zip': {'path': '', 16:01:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:01:55 INFO - Running post-action listener: _resource_record_post_action 16:01:55 INFO - [mozharness: 2016-10-25 23:01:55.728343Z] Finished populate-webroot step (success) 16:01:55 INFO - [mozharness: 2016-10-25 23:01:55.728431Z] Running create-virtualenv step. 16:01:55 INFO - Running pre-action listener: _resource_record_pre_action 16:01:55 INFO - Running main action method: create_virtualenv 16:01:55 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:55 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 16:01:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:55 INFO - Using real prefix '/usr' 16:01:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:56 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:58 INFO - Installing pip.................done. 16:01:58 INFO - Return code: 0 16:01:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:58 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:01:58 INFO - Using env: {'DISPLAY': ':0', 16:01:58 INFO - 'HOME': '/home/cltbld', 16:01:58 INFO - 'LANG': 'en_US.UTF-8', 16:01:58 INFO - 'LANGUAGE': 'en_US:en', 16:01:58 INFO - 'LOGNAME': 'cltbld', 16:01:58 INFO - 'MAIL': '/var/mail/cltbld', 16:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:58 INFO - 'MOZ_NO_REMOTE': '1', 16:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:58 INFO - 'NO_EM_RESTART': '1', 16:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:58 INFO - 'PWD': '/builds/slave/test', 16:01:58 INFO - 'SHELL': '/bin/bash', 16:01:58 INFO - 'SHLVL': '1', 16:01:58 INFO - 'TERM': 'linux', 16:01:58 INFO - 'TMOUT': '86400', 16:01:58 INFO - 'USER': 'cltbld', 16:01:58 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:58 INFO - '_': '/tools/buildbot/bin/python'} 16:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:58 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 16:01:58 INFO - Cleaning up... 16:01:58 INFO - Return code: 0 16:01:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:58 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:01:58 INFO - Using env: {'DISPLAY': ':0', 16:01:58 INFO - 'HOME': '/home/cltbld', 16:01:58 INFO - 'LANG': 'en_US.UTF-8', 16:01:58 INFO - 'LANGUAGE': 'en_US:en', 16:01:58 INFO - 'LOGNAME': 'cltbld', 16:01:58 INFO - 'MAIL': '/var/mail/cltbld', 16:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:58 INFO - 'MOZ_NO_REMOTE': '1', 16:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:58 INFO - 'NO_EM_RESTART': '1', 16:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:58 INFO - 'PWD': '/builds/slave/test', 16:01:58 INFO - 'SHELL': '/bin/bash', 16:01:58 INFO - 'SHLVL': '1', 16:01:58 INFO - 'TERM': 'linux', 16:01:58 INFO - 'TMOUT': '86400', 16:01:58 INFO - 'USER': 'cltbld', 16:01:58 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:58 INFO - '_': '/tools/buildbot/bin/python'} 16:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:58 INFO - Downloading/unpacking psutil>=3.1.1 16:01:58 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 16:01:58 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 16:01:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:58 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 16:01:58 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 16:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:02:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:00 INFO - Installing collected packages: psutil 16:02:00 INFO - Running setup.py install for psutil 16:02:00 INFO - building 'psutil._psutil_linux' extension 16:02:00 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 16:02:00 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 16:02:01 INFO - building 'psutil._psutil_posix' extension 16:02:01 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 16:02:01 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 16:02:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:01 INFO - Successfully installed psutil 16:02:01 INFO - Cleaning up... 16:02:01 INFO - Return code: 0 16:02:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:02:01 INFO - Using env: {'DISPLAY': ':0', 16:02:01 INFO - 'HOME': '/home/cltbld', 16:02:01 INFO - 'LANG': 'en_US.UTF-8', 16:02:01 INFO - 'LANGUAGE': 'en_US:en', 16:02:01 INFO - 'LOGNAME': 'cltbld', 16:02:01 INFO - 'MAIL': '/var/mail/cltbld', 16:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:01 INFO - 'MOZ_NO_REMOTE': '1', 16:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:01 INFO - 'NO_EM_RESTART': '1', 16:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:01 INFO - 'PWD': '/builds/slave/test', 16:02:01 INFO - 'SHELL': '/bin/bash', 16:02:01 INFO - 'SHLVL': '1', 16:02:01 INFO - 'TERM': 'linux', 16:02:01 INFO - 'TMOUT': '86400', 16:02:01 INFO - 'USER': 'cltbld', 16:02:01 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:01 INFO - '_': '/tools/buildbot/bin/python'} 16:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:02:01 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 16:02:01 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 16:02:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:01 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 16:02:01 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 16:02:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:02:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:02:03 INFO - Installing collected packages: mozsystemmonitor 16:02:03 INFO - Running setup.py install for mozsystemmonitor 16:02:03 INFO - Successfully installed mozsystemmonitor 16:02:03 INFO - Cleaning up... 16:02:03 INFO - Return code: 0 16:02:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:03 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:02:03 INFO - Using env: {'DISPLAY': ':0', 16:02:03 INFO - 'HOME': '/home/cltbld', 16:02:03 INFO - 'LANG': 'en_US.UTF-8', 16:02:03 INFO - 'LANGUAGE': 'en_US:en', 16:02:03 INFO - 'LOGNAME': 'cltbld', 16:02:03 INFO - 'MAIL': '/var/mail/cltbld', 16:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:03 INFO - 'MOZ_NO_REMOTE': '1', 16:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:03 INFO - 'NO_EM_RESTART': '1', 16:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:03 INFO - 'PWD': '/builds/slave/test', 16:02:03 INFO - 'SHELL': '/bin/bash', 16:02:03 INFO - 'SHLVL': '1', 16:02:03 INFO - 'TERM': 'linux', 16:02:03 INFO - 'TMOUT': '86400', 16:02:03 INFO - 'USER': 'cltbld', 16:02:03 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:03 INFO - '_': '/tools/buildbot/bin/python'} 16:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:03 INFO - Downloading/unpacking jsonschema==2.5.1 16:02:03 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 16:02:03 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 16:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:03 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 16:02:03 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 16:02:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:02:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:02:05 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 16:02:05 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 16:02:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:05 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 16:02:05 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 16:02:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:02:06 INFO - warning: no files found matching '*.txt' 16:02:06 INFO - no previously-included directories found matching 'build' 16:02:06 INFO - no previously-included directories found matching 'dist' 16:02:06 INFO - no previously-included directories found matching '.git*' 16:02:06 INFO - Installing collected packages: jsonschema, functools32 16:02:06 INFO - Running setup.py install for functools32 16:02:06 INFO - warning: no files found matching '*.txt' 16:02:06 INFO - no previously-included directories found matching 'build' 16:02:06 INFO - no previously-included directories found matching 'dist' 16:02:06 INFO - no previously-included directories found matching '.git*' 16:02:06 INFO - Successfully installed jsonschema functools32 16:02:06 INFO - Cleaning up... 16:02:06 INFO - Return code: 0 16:02:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:02:06 INFO - Using env: {'DISPLAY': ':0', 16:02:06 INFO - 'HOME': '/home/cltbld', 16:02:06 INFO - 'LANG': 'en_US.UTF-8', 16:02:06 INFO - 'LANGUAGE': 'en_US:en', 16:02:06 INFO - 'LOGNAME': 'cltbld', 16:02:06 INFO - 'MAIL': '/var/mail/cltbld', 16:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:06 INFO - 'MOZ_NO_REMOTE': '1', 16:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:06 INFO - 'NO_EM_RESTART': '1', 16:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:06 INFO - 'PWD': '/builds/slave/test', 16:02:06 INFO - 'SHELL': '/bin/bash', 16:02:06 INFO - 'SHLVL': '1', 16:02:06 INFO - 'TERM': 'linux', 16:02:06 INFO - 'TMOUT': '86400', 16:02:06 INFO - 'USER': 'cltbld', 16:02:06 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:06 INFO - '_': '/tools/buildbot/bin/python'} 16:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:02:06 INFO - Cleaning up... 16:02:06 INFO - Return code: 0 16:02:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:02:06 INFO - Using env: {'DISPLAY': ':0', 16:02:06 INFO - 'HOME': '/home/cltbld', 16:02:06 INFO - 'LANG': 'en_US.UTF-8', 16:02:06 INFO - 'LANGUAGE': 'en_US:en', 16:02:06 INFO - 'LOGNAME': 'cltbld', 16:02:06 INFO - 'MAIL': '/var/mail/cltbld', 16:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:06 INFO - 'MOZ_NO_REMOTE': '1', 16:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:06 INFO - 'NO_EM_RESTART': '1', 16:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:06 INFO - 'PWD': '/builds/slave/test', 16:02:06 INFO - 'SHELL': '/bin/bash', 16:02:06 INFO - 'SHLVL': '1', 16:02:06 INFO - 'TERM': 'linux', 16:02:06 INFO - 'TMOUT': '86400', 16:02:06 INFO - 'USER': 'cltbld', 16:02:06 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:06 INFO - '_': '/tools/buildbot/bin/python'} 16:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:06 INFO - Downloading/unpacking blobuploader==1.2.4 16:02:06 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 16:02:06 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 16:02:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:06 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 16:02:06 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 16:02:08 INFO - Downloading blobuploader-1.2.4.tar.gz 16:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:02:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:02:08 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 16:02:08 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 16:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:08 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 16:02:08 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 16:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:02:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:02:09 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 16:02:09 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 16:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:09 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 16:02:09 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 16:02:09 INFO - Downloading docopt-0.6.1.tar.gz 16:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:02:09 INFO - Installing collected packages: blobuploader, requests, docopt 16:02:09 INFO - Running setup.py install for blobuploader 16:02:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:02:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:02:09 INFO - Running setup.py install for requests 16:02:09 INFO - Running setup.py install for docopt 16:02:09 INFO - Successfully installed blobuploader requests docopt 16:02:09 INFO - Cleaning up... 16:02:09 INFO - Return code: 0 16:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:02:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:02:09 INFO - Using env: {'DISPLAY': ':0', 16:02:09 INFO - 'HOME': '/home/cltbld', 16:02:09 INFO - 'LANG': 'en_US.UTF-8', 16:02:09 INFO - 'LANGUAGE': 'en_US:en', 16:02:09 INFO - 'LOGNAME': 'cltbld', 16:02:09 INFO - 'MAIL': '/var/mail/cltbld', 16:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:09 INFO - 'MOZ_NO_REMOTE': '1', 16:02:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:09 INFO - 'NO_EM_RESTART': '1', 16:02:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:09 INFO - 'PWD': '/builds/slave/test', 16:02:09 INFO - 'SHELL': '/bin/bash', 16:02:09 INFO - 'SHLVL': '1', 16:02:09 INFO - 'TERM': 'linux', 16:02:09 INFO - 'TMOUT': '86400', 16:02:09 INFO - 'USER': 'cltbld', 16:02:09 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:09 INFO - '_': '/tools/buildbot/bin/python'} 16:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:10 INFO - Running setup.py (path:/tmp/pip-mlainZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:10 INFO - Running setup.py (path:/tmp/pip-eehdJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:10 INFO - Running setup.py (path:/tmp/pip-VYvUBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:10 INFO - Running setup.py (path:/tmp/pip-WXZY6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:10 INFO - Running setup.py (path:/tmp/pip-BLgMP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:10 INFO - Running setup.py (path:/tmp/pip-prILQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:10 INFO - Running setup.py (path:/tmp/pip-c6yGUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:10 INFO - Running setup.py (path:/tmp/pip-gpckKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:10 INFO - Running setup.py (path:/tmp/pip-ctPGoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:10 INFO - Running setup.py (path:/tmp/pip-pK0YzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:10 INFO - Running setup.py (path:/tmp/pip-dUceVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:10 INFO - Running setup.py (path:/tmp/pip-Xg8UQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:11 INFO - Running setup.py (path:/tmp/pip-2kjpWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:11 INFO - Running setup.py (path:/tmp/pip-IXfQeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:11 INFO - Running setup.py (path:/tmp/pip-2TNr3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:11 INFO - Running setup.py (path:/tmp/pip-C5uZnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:11 INFO - Running setup.py (path:/tmp/pip-Qi6EuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:02:11 INFO - Running setup.py install for manifestparser 16:02:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:02:11 INFO - Running setup.py install for mozcrash 16:02:11 INFO - Running setup.py install for mozdebug 16:02:11 INFO - Running setup.py install for mozdevice 16:02:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:02:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:02:11 INFO - Running setup.py install for mozfile 16:02:11 INFO - Running setup.py install for mozhttpd 16:02:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Running setup.py install for mozinfo 16:02:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Running setup.py install for mozInstall 16:02:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Running setup.py install for mozleak 16:02:12 INFO - Running setup.py install for mozlog 16:02:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Running setup.py install for moznetwork 16:02:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Running setup.py install for mozprocess 16:02:12 INFO - Running setup.py install for mozprofile 16:02:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Running setup.py install for mozrunner 16:02:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:02:12 INFO - Running setup.py install for mozscreenshot 16:02:13 INFO - Running setup.py install for moztest 16:02:13 INFO - Running setup.py install for mozversion 16:02:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:02:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:02:13 INFO - Cleaning up... 16:02:13 INFO - Return code: 0 16:02:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:13 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:13 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:02:13 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:02:13 INFO - Using env: {'DISPLAY': ':0', 16:02:13 INFO - 'HOME': '/home/cltbld', 16:02:13 INFO - 'LANG': 'en_US.UTF-8', 16:02:13 INFO - 'LANGUAGE': 'en_US:en', 16:02:13 INFO - 'LOGNAME': 'cltbld', 16:02:13 INFO - 'MAIL': '/var/mail/cltbld', 16:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:13 INFO - 'MOZ_NO_REMOTE': '1', 16:02:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:13 INFO - 'NO_EM_RESTART': '1', 16:02:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:13 INFO - 'PWD': '/builds/slave/test', 16:02:13 INFO - 'SHELL': '/bin/bash', 16:02:13 INFO - 'SHLVL': '1', 16:02:13 INFO - 'TERM': 'linux', 16:02:13 INFO - 'TMOUT': '86400', 16:02:13 INFO - 'USER': 'cltbld', 16:02:13 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:13 INFO - '_': '/tools/buildbot/bin/python'} 16:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:13 INFO - Running setup.py (path:/tmp/pip-LzTxIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:13 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)) 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:13 INFO - Running setup.py (path:/tmp/pip-_zuuPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:13 INFO - Running setup.py (path:/tmp/pip-AFfhta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:13 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)) 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:13 INFO - Running setup.py (path:/tmp/pip-9dgay_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:13 INFO - Running setup.py (path:/tmp/pip-YGwE62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:13 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)) 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:13 INFO - Running setup.py (path:/tmp/pip-oK9mPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:13 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)) 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:13 INFO - Running setup.py (path:/tmp/pip-T21F8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 16:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:13 INFO - Running setup.py (path:/tmp/pip-2mXkhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:14 INFO - Running setup.py (path:/tmp/pip-JOnHQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:14 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:14 INFO - Running setup.py (path:/tmp/pip-Py0Il9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:14 INFO - Running setup.py (path:/tmp/pip-tt1AGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:14 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:14 INFO - Running setup.py (path:/tmp/pip-6K3w1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:14 INFO - Running setup.py (path:/tmp/pip-yrxl1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:14 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:14 INFO - Running setup.py (path:/tmp/pip-5mJMSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:14 INFO - Running setup.py (path:/tmp/pip-e8fZ0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:14 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:14 INFO - Running setup.py (path:/tmp/pip-Etxmcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 16:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:14 INFO - Running setup.py (path:/tmp/pip-y_XHas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:14 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)) 16:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:14 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)) 16:02:14 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:14 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:14 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 16:02:14 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 16:02:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:02:14 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 16:02:14 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 16:02:16 INFO - Downloading blessings-1.6.tar.gz 16:02:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:16 INFO - Installing collected packages: blessings 16:02:16 INFO - Running setup.py install for blessings 16:02:16 INFO - Successfully installed blessings 16:02:16 INFO - Cleaning up... 16:02:16 INFO - Return code: 0 16:02:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:17 INFO - Reading from file tmpfile_stdout 16:02:17 INFO - Current package versions: 16:02:17 INFO - argparse == 1.2.1 16:02:17 INFO - blessings == 1.6 16:02:17 INFO - blobuploader == 1.2.4 16:02:17 INFO - docopt == 0.6.1 16:02:17 INFO - functools32 == 3.2.3-2 16:02:17 INFO - jsonschema == 2.5.1 16:02:17 INFO - manifestparser == 1.1 16:02:17 INFO - mozInstall == 1.12 16:02:17 INFO - mozcrash == 0.17 16:02:17 INFO - mozdebug == 0.1 16:02:17 INFO - mozdevice == 0.48 16:02:17 INFO - mozfile == 1.2 16:02:17 INFO - mozhttpd == 0.7 16:02:17 INFO - mozinfo == 0.9 16:02:17 INFO - mozleak == 0.1 16:02:17 INFO - mozlog == 3.3 16:02:17 INFO - moznetwork == 0.27 16:02:17 INFO - mozprocess == 0.23 16:02:17 INFO - mozprofile == 0.28 16:02:17 INFO - mozrunner == 6.12 16:02:17 INFO - mozscreenshot == 0.1 16:02:17 INFO - mozsystemmonitor == 0.3 16:02:17 INFO - moztest == 0.7 16:02:17 INFO - mozversion == 1.4 16:02:17 INFO - psutil == 3.1.1 16:02:17 INFO - requests == 1.2.3 16:02:17 INFO - wsgiref == 0.1.2 16:02:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 16:02:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:02:17 INFO - Using env: {'DISPLAY': ':0', 16:02:17 INFO - 'HOME': '/home/cltbld', 16:02:17 INFO - 'LANG': 'en_US.UTF-8', 16:02:17 INFO - 'LANGUAGE': 'en_US:en', 16:02:17 INFO - 'LOGNAME': 'cltbld', 16:02:17 INFO - 'MAIL': '/var/mail/cltbld', 16:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:17 INFO - 'MOZ_NO_REMOTE': '1', 16:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:17 INFO - 'NO_EM_RESTART': '1', 16:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:17 INFO - 'PWD': '/builds/slave/test', 16:02:17 INFO - 'SHELL': '/bin/bash', 16:02:17 INFO - 'SHLVL': '1', 16:02:17 INFO - 'TERM': 'linux', 16:02:17 INFO - 'TMOUT': '86400', 16:02:17 INFO - 'USER': 'cltbld', 16:02:17 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:17 INFO - '_': '/tools/buildbot/bin/python'} 16:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 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)) 16:02:17 INFO - Cleaning up... 16:02:17 INFO - Return code: 0 16:02:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:02:17 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x2a5d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c8c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, '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-1477436054.964565-1374205825', '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 16:02:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:02:17 INFO - Using env: {'DISPLAY': ':0', 16:02:17 INFO - 'HOME': '/home/cltbld', 16:02:17 INFO - 'LANG': 'en_US.UTF-8', 16:02:17 INFO - 'LANGUAGE': 'en_US:en', 16:02:17 INFO - 'LOGNAME': 'cltbld', 16:02:17 INFO - 'MAIL': '/var/mail/cltbld', 16:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:17 INFO - 'MOZ_NO_REMOTE': '1', 16:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:17 INFO - 'NO_EM_RESTART': '1', 16:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:17 INFO - 'PWD': '/builds/slave/test', 16:02:17 INFO - 'SHELL': '/bin/bash', 16:02:17 INFO - 'SHLVL': '1', 16:02:17 INFO - 'TERM': 'linux', 16:02:17 INFO - 'TMOUT': '86400', 16:02:17 INFO - 'USER': 'cltbld', 16:02:17 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:17 INFO - '_': '/tools/buildbot/bin/python'} 16:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:02:17 INFO - Cleaning up... 16:02:17 INFO - Return code: 0 16:02:17 INFO - Running post-action listener: _resource_record_post_action 16:02:17 INFO - Running post-action listener: _start_resource_monitoring 16:02:17 INFO - Starting resource monitoring. 16:02:17 INFO - [mozharness: 2016-10-25 23:02:17.569967Z] Finished create-virtualenv step (success) 16:02:17 INFO - [mozharness: 2016-10-25 23:02:17.570436Z] Running install step. 16:02:17 INFO - Running pre-action listener: _resource_record_pre_action 16:02:17 INFO - Running main action method: install 16:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:17 INFO - Reading from file tmpfile_stdout 16:02:17 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:17 INFO - Reading from file tmpfile_stdout 16:02:17 INFO - Output received: 16:02:17 INFO - Usage: mozinstall [options] installer 16:02:17 INFO - Options: 16:02:17 INFO - -h, --help show this help message and exit 16:02:17 INFO - -d DEST, --destination=DEST 16:02:17 INFO - Directory to install application into. [default: 16:02:17 INFO - "/builds/slave/test"] 16:02:17 INFO - --app=APP Application being installed. [default: firefox] 16:02:17 INFO - mkdir: /builds/slave/test/build/application 16:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:02:32 INFO - Reading from file tmpfile_stdout 16:02:32 INFO - Output received: 16:02:32 INFO - /builds/slave/test/build/application/firefox/firefox 16:02:32 INFO - Running post-action listener: _resource_record_post_action 16:02:32 INFO - [mozharness: 2016-10-25 23:02:32.073343Z] Finished install step (success) 16:02:32 INFO - [mozharness: 2016-10-25 23:02:32.073623Z] Running run-tests step. 16:02:32 INFO - Running pre-action listener: _resource_record_pre_action 16:02:32 INFO - Running main action method: run_tests 16:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:32 INFO - Python 2.7.3 16:02:32 INFO - Return code: 0 16:02:32 INFO - grabbing minidump binary from tooltool 16:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:02:32 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 0x2d1b570>, '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 0x2d1c470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d1c900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:32 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 16:02:32 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 16:02:32 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 16:02:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:32 INFO - Return code: 0 16:02:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:02:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:02:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.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 16:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.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 16:02:32 INFO - Using env: {'DISPLAY': ':0', 16:02:32 INFO - 'HOME': '/home/cltbld', 16:02:32 INFO - 'LANG': 'en_US.UTF-8', 16:02:32 INFO - 'LANGUAGE': 'en_US:en', 16:02:32 INFO - 'LOGNAME': 'cltbld', 16:02:32 INFO - 'MAIL': '/var/mail/cltbld', 16:02:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:32 INFO - 'MOZ_NO_REMOTE': '1', 16:02:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:32 INFO - 'NO_EM_RESTART': '1', 16:02:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:32 INFO - 'PWD': '/builds/slave/test', 16:02:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:32 INFO - 'SHELL': '/bin/bash', 16:02:32 INFO - 'SHLVL': '1', 16:02:32 INFO - 'TERM': 'linux', 16:02:32 INFO - 'TMOUT': '86400', 16:02:32 INFO - 'USER': 'cltbld', 16:02:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1477436054.964565-1374205825', 16:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:32 INFO - '_': '/tools/buildbot/bin/python'} 16:02:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.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 16:02:32 INFO - mozversion application_buildid: 20161025131024 16:02:32 INFO - mozversion application_changeset: c45f9369c95cb492a2dee21e9c5cefde192f573c 16:02:32 INFO - mozversion application_display_name: Firefox 16:02:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:32 INFO - mozversion application_name: Firefox 16:02:32 INFO - mozversion application_remotingname: firefox 16:02:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:02:32 INFO - mozversion application_vendor: Mozilla 16:02:32 INFO - mozversion application_version: 50.0 16:02:32 INFO - mozversion platform_buildid: 20161025131024 16:02:32 INFO - mozversion platform_changeset: c45f9369c95cb492a2dee21e9c5cefde192f573c 16:02:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:02:32 INFO - mozversion platform_version: 50.0 16:02:32 INFO - using testdate: 1477436552 16:02:32 INFO - actual date: 1477436552 16:02:32 INFO - starting webserver on 'localhost:47675' 16:02:32 INFO - SUITE-START | Running 2 tests 16:02:32 INFO - TEST-START | dromaeo_css 16:02:32 INFO - operating with platform_type : linux_ 16:02:32 INFO - Initialising browser for dromaeo_css test... 16:02:32 INFO - TEST-INFO | started process 24435 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfHYr4q/profile http://localhost:47675/getInfo.html) 16:02:37 INFO - PROCESS | 24435 | __metrics Screen width/height:1600/1200 16:02:37 INFO - PROCESS | 24435 | colorDepth:24 16:02:37 INFO - PROCESS | 24435 | Browser inner width/height: 1024/697 16:02:37 INFO - PROCESS | 24435 | __metrics 16:02:37 ERROR - PROCESS | 24435 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:02:38 INFO - TEST-INFO | 24435: exit 0 16:02:38 INFO - Browser initialized. 16:02:38 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:38 INFO - TEST-INFO | started process 24559 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfHYr4q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:50 INFO - PROCESS | 24559 | RSS: Main: 150056960 16:02:50 INFO - PROCESS | 24559 | 16:03:39 INFO - PROCESS | 24559 | 1477436619808 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:05:48 INFO - PROCESS | 24559 | Cycle 1(1): loaded http://localhost:47675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47675/tests/dromaeo/cssquery-ext.html) 16:05:49 INFO - PROCESS | 24559 | RSS: Main: 158584832 16:05:49 INFO - PROCESS | 24559 | 16:08:51 INFO - PROCESS | 24559 | Cycle 1(1): loaded http://localhost:47675/tests/dromaeo/cssquery-ext.html (next: http://localhost:47675/tests/dromaeo/cssquery-jquery.html) 16:08:52 INFO - PROCESS | 24559 | RSS: Main: 155344896 16:08:52 INFO - PROCESS | 24559 | 16:11:54 INFO - PROCESS | 24559 | Cycle 1(1): loaded http://localhost:47675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47675/tests/dromaeo/cssquery-mootools.html) 16:11:54 INFO - PROCESS | 24559 | RSS: Main: 155037696 16:11:54 INFO - PROCESS | 24559 | 16:14:56 INFO - PROCESS | 24559 | Cycle 1(1): loaded http://localhost:47675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47675/tests/dromaeo/cssquery-prototype.html) 16:14:56 INFO - PROCESS | 24559 | RSS: Main: 156073984 16:14:56 INFO - PROCESS | 24559 | 16:17:59 INFO - PROCESS | 24559 | Cycle 1(1): loaded http://localhost:47675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47675/tests/dromaeo/cssquery-yui.html) 16:18:00 INFO - PROCESS | 24559 | RSS: Main: 157577216 16:18:00 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | Cycle 1(1): loaded http://localhost:47675/tests/dromaeo/cssquery-yui.html (next: http://localhost:47675/tests/dromaeo/cssquery-dojo.html) 16:21:02 INFO - PROCESS | 24559 | RSS: Main: 157364224 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | __start_tp_report 16:21:02 INFO - PROCESS | 24559 | _x_x_mozilla_page_load 16:21:02 INFO - PROCESS | 24559 | _x_x_mozilla_page_load_details 16:21:02 INFO - PROCESS | 24559 | |i|pagename|runs| 16:21:02 INFO - PROCESS | 24559 | |0;dojo.html;3327.118313646883;3337.349216902705;3347.9788664457337;3351.212465070708;3373.241020770327;5829.533637309016;5840.53275737941;5930.555208359374;5932.11018347247;5949.583529152956;5785.305763308409;5790.536757059435;5815.302163740346;5815.651060936336;5820.941790582093;5232.8691782705355;5267.28891599633;5293.258943747859;5333.679979201241;5368;5321.680699158043;5326.6537675050995;5326.760295786699;5330.653507521999;5367.114426119686;5258.6318957672975;5268.341457317835;5313.309269794921;5313.415524292324;5313.787448502056;5315.920261196065;5405.756740946647;5414.972925135388;5415.729213539307;5442.319710036246;5295.152775555893;5391.056565101131;5395.622306438551;5403.351597808249;5403.567714582863;5698.487136157766;5704.201411802351;5826.7377967991115;5941.70291485424;5969.822001634448;6146.323904370515;6261.311255761862;6272.529560282951;6289.0880822280515;6294.087357333161;6321.178246827929;6324.810255692337;6325.177726895474;6331.905021424683;6333.461655759266;5740.167675687044;5794.507466865363;5862.472377486047;5870.501007414375;5883.352831188566;6319.24169099711;6393.456556192776;6416.935830641633;6420.6147631142285;6440;6075.119107729354;6152.477039451607;6166.753329866845;6178.382161783876;6206.875862482815;6070.7875224366935;6197.349278325711;6220.346863579259;6264.9373506265265;6290.402411770874;6238.157848690459;6258.624482531062;6263.3736626337;6276.089966954766;6295.055741638791;6249.812505624838;6253.906191407133;6264.780732674334;6278.717457714414;6312.274088479791;4660.836870709509;4719.811207551659;4788.976055119771;4792.0895029944195;4808.230683090758;4204.159168166379;4263.339182426729;4274.1451709658195;4289.356596510548;4298.075913678512;6005.489533389624;6054.697265136726;6075.362086980892;6077.331493535796;6111.694415279218;6114.236541168675;6136.355682653347;6311.526635502264;6368.395002474757;6399.392057754506;5936.762529498772;5991.880162396727;5998.190244316961;6003;6023.66869822164;5378.139497680352;5384.596155288369;5388.326459192601;5395.2986111806;5397.4062853086125;6163.96918015416;6411.551191416556;6442.677866106769;6448.871022579522;6474.870502589922;6366.172397588284;6374.904376434264;6379.425851673372;6382.234131904015;6387.489000880011;6505.381988711065;6508.121403610452;6569.770058047945;6572.76995305162;6573.605583664996;5937.079752638348;6053.061775424816;6063;6253.96873015632;6259.812205633838;6280.340564240689;6284.748610055546;6285.59143655661;6289.811305660837;6290.339514351111;6104.847378815565;6109.358517355614;6113.602615829956;6119.816405507842;6122.571420000557;5969.582129250911;6110.908336374869;6114.174586430775;6122.908156377569;6140.723667435065;6130.325664176848;6149.692515374303;6149.7847575334645;6156.138140660221;6176.413240742122;6188.907166392598;6192.752289908353;6201.751929922752;6229.844253893508;6232.594881332699;5857.531397488276;5863.794767183128;5957.463828255304;6079.848003799763;6094.54290928191 16:21:02 INFO - PROCESS | 24559 | |1;ext.html;586.293516312843;628.8050704281673;657.6415853359919;662.9701663425145;703.2440126863623;41845.58154418455;46061.30908036382;46096.53903460968;46210;46254.999999999956;46998.29502557465;47508.2873756893;47538.0492390152;47719.28421073678;47726.76136619312;8965.641374345034;9225.769355766126;9227.169554740072;9241.49171795551;9244.953775231148;9219;9289.396189247676;9291.628334866631;9297.442153470813;9311.394759340621;9301.395409298408;9327.440353578773;9340.15938565529;9356.953215233914;9377.578008989578;9245.768855778591;9267;9308.813823723522;9340.112689294507;9344.859827102599;9065.546722663907;9170.770730731718;9233.722988310361;9243.02948190437;9259.212966897821;43590.34614480754;44071.77964110159;44149.11701765979;44372.77813610944;44375.55624443747;3646.4165733482514;3780.1683629601443;3790.2040571480024;3894.844206231747;4159.064210552601;34736.826315868515;35259.647403525894;35385.46921796175;37162.25675486502;37180.62819371826;4713.457952335491;4815.229563269895;4853.077915196137;4864.318995340655;4960.627952903511;257092;261495;262334;262443;263037.6848115766;38427.807860960515;39319.41020884689;62992.370076299565;63292.68353658202;63317.366826332065;40630.39054414186;40725.18549628991;40810;40881.59118408837;40940.1811963759;13217.273049982343;13226.933865330804;13313.600591982256;13318.800217996737;13349.532766353324;10689.091427228772;10749.462526873782;10760.56957721698;10762.677119686421;10764.677059688222;9270.490123043297;9273.350865439354;9307.162355388047;9412.811743765087;9443.197328227041;3563.233904710448;3591.1560783215923;3603.3694103531775;3615.9819200903826;3627.3108109459117;1121.6018313498753;1145.9025982791388;1171.908143963022;1185.7984142695761;1195.892369686715;34209;34253.48619770656;34605.480917786255;34683.13292167665;35058.64941350605;1175.788358095551;1177.9411029448493;1187.6971372300009;1221.1207930290177;1268.9302088385043;18050.368237111637;18061.09694515269;18109.456716298268;18206;18366.449006529827;547065;550854;550930;553043;555443;63390.36609633937;64196.358036419035;64223.35776642174;64228;64249.67875160688;1386.230641993683;1412.3644360038024;1427.3434220258798;1430.7567713488525;1432.8495507971513;32712.182195445304;33215.169620759676;33327.33345333128;33637.66362336443;34002.14994625055;55283.17075243955;55301.72349138228;55514.44485555093;55625.721871390386;55765.163522548006;477704;480337;481848;481898;483266;30710.385792284476;32155.035348939567;32218.35563288674;32266.516002260454;32420.35159296848;52016.479835201164;52623;53051.73474132604;53234.73382633062;53540;43811;44479;45319.773401132785;46953.53046469628;46965.76517117392;45364.31953520634;46008.309875351224;46796.29805552851;47047.764761175975;47465;44342.55657443513;45431.54568454273;45686.086278273586;45734.313985289584;47605.523944761495;38864.6113538861;40067.79966100151;40329.79835100806;40779.18441631091;41009.794951025055;30005.549916751705;30682.232944176576;30919.536206956465;31109.68890311068;31345.8432707835 16:21:02 INFO - PROCESS | 24559 | |2;jquery.html;407.9469668943035;414.1241274704002;427.81176282435734;428.47511798047395;429.75289208705004;19168.329108481204;20629.793702062976;20715.689264661003;20722.585548289044;21350.679739803916;20109.29617463389;20127.899360503212;20174.495637609067;20564.794352056473;20718;5943.881122377561;6150.6924653767355;6151.046587778879;6153.876922461571;6156.261248650168;5933.88132237355;5987.940120598803;6030.577859549833;6056.969715151418;6058.697065146747;5809.941900581003;5922.170896074553;5953.3451320354725;5957.612755170921;5958.28500579931;5931.733072011748;5949.940500594982;5961.791337303195;5973.970130149365;5994.460498555108;5346.625736198468;5380.757865896044;5382.058139825514;5385.272988146589;5393.325834270716;23813.166539171045;24667.506649867082;24691.50616987668;24779.380515487075;24971.62542561863;2551.885165167572;2613.529564678357;2753.8072334936564;2760.7791376689815;2812.409394027257;14722.926385368004;15391.153486558234;15413.306401211972;15814.683706325925;15986.60033499172;2051.7332746742827;2068.2657656531865;2081.302763574189;2131.094284928899;2171.913123475075;73792.63103684447;75350.62324688342;78593.21406785917;82495.5875220626;83609;11066.557337706401;11307.943460282646;11315.83026254607;11667.88332116685;12252.142350035412;16218;16325.918370408071;16389.262483188286;16392.75410868838;16417.343306267856;7772.8056798579355;7959.880601790977;8002.1997800219515;8044;8065.354771618257;7393.519421237603;7400.148982867039;7600.46796724224;7612.048493938258;7652.464327497022;6913.89629155563;6986.825329366807;7053.435725141976;7097.503174777819;7118.394936430358;1993.681011038256;2057.845661575388;2077.919491820753;2102.2011635578383;2135.7185688586724;490.25726025072044;492.33534728116757;984.4437892590806;1058.539535302146;1087.1954753482366;12829.29438880852;13121.803172952414;13242.801357979639;13316.334183290797;13370.665733356744;500.35204410288037;524.1796588339237;525.8921921006184;525.9053370393366;526.0504788856075;7046.823829404203;7067.399271062018;7123.536970096927;7160.713571457083;7190.280971902873;543508;554707;559647;566428;570038;100216;101444;103054.48472757588;104223.47888260662;105870;1004.5178314409125;1045.717656232813;1079.3362082319318;1096.8135416979133;1099.6261271167841;16577.336906523604;18761.249550017845;19226.423207303804;19245.230190792212;19292.13185406632;43526.347104794084;44648;44975;45618.315725263485;45739.31391029071;58706.412935871806;64732.352676472634;65677.67161164164;66811.66594166998;67655.66172169108;8893.73318800437;8993.820123597623;9105.817883642423;9145.314101442285;9169.54152292396;14074;14090.506832260822;14123.223222722438;14367.712645747235;14478.782818257523;11826.940865295619;11907.04743620491;11910.702232444259;11973;12192.634220973385;11887.643370698894;12041.759164816829;12172.513099475922;12186.756264874475;12395.132340736061;11822.935098466198;11837.940810295893;12096.637100886988;12190.2076365036;12220.633380998584;9262.53687315645;9342.626294948126;9359.15767580921;9463;9491.288153388385;10646.787064258826;11430.371329576954;11580;11753.764924701629;11923.403829808649 16:21:02 INFO - PROCESS | 24559 | |3;mootools.html;1521.855423734745;1542.9382824687013;1684.6714890596331;1787.1332403784163;1790.6329202513487;8246.67013319468;8585.484870907734;8854.291656667467;9518.524073796309;9530.809383812328;8618.138186181395;8879.644814207424;8886.55567221638;8912.688055918044;8936.15106564877;2540.796736261095;2572.768450839424;2573.652556904822;2583.147561304774;2588.0812311629384;2472.171822439485;2543.122622695167;2555.987220063897;2558.9488210235786;2559.641650168978;2510.0587279770084;2520.3825062859623;2579.21333993132;2583.651207087038;2584.496023275462;2517.0435234610823;2523.823332366726;2529.8229123961332;2555.3100662820934;2569.935751606206;2524.2048754642415;2525.3434107132093;2529.051605647882;2557.731438198981;2579.8968041278326;9348.766280843034;9407.247420206435;9465.90534094657;9477.147056764925;9501.192398646124;5809.2447981762525;5881.735321910524;5917.289925208959;5924.851878703023;5934.376890426486;3648.525691660094;3761.153740408414;3778.6032466591164;3823.063349479395;3849.191669749356;1295.0999055656323;1372.4167228927665;1378.63466181461;1390.7635701930594;1415.3984556563419;12948.611541653765;12999.285039322927;13010.089293749441;13029.153105048237;13060.542880999214;4899.6570240083565;4966.776495057731;5131.7177555234075;5160.664556803796;5243.68537887728;10520.053195212467;10533.736656583493;10553.472326383804;10589.682309530726;10643.201759867887;5837.591368604242;5841.1238314253205;5850.063989761617;5850.268716410449;5851.180834683148;5203.16749320107;5335.62650614461;5424.918626220609;5472.124460086447;5507.972460137674;4061.226285077515;4136.31750761121;4148.983499042693;4197.160567886435;4217.683673724483;1200.7178313096415;1215.1493954232074;1230.9569165079179;1240.6092080994638;1242.8694987026413;864.4424346296604;924.075924075924;987.5457289646843;1007.4710776842217;1023.4012457503011;5652.604317697722;6093.908591371133;6094.055421409688;6097.512199024068;6117.051856962178;501.4985014985015;501.7541404711665;503.05676855895194;506.3366989244102;507.652258203132;4078.0824314529586;4081;4099.815508302134;4117.032497363117;4123.505179378494;11250.943745281385;11467.942660286646;11635.301881887113;11638.534458621729;11643.76712465746;11428.314301141792;11523.423828808694;11550.306981581132;11557.24877882935;11573.826392604118;8486;8836.337274704423;8876.822463550692;9112.179903808688;9117.042710515432;6077.51379889599;6114.755409783559;6293.087502312048;6301.747930082745;6460.483161347175;10624.681259562225;11088.833667495155;11222.775544489228;11262.718432039264;11313.094952403953;11349.716257093638;11360.261582997078;11389.772204556028;11401.543938242377;11496.655100347003;8038.115807261313;8242.752717418487;8319;8358.45670031446;8445.746627601182;9147.817043658957;9214.308926830381;9221.538923053955;9224.492652903886;9225.677101301422;8212.794680133044;8212.917870821215;8238.134995825352;8275.255227029596;8417.789555261168;8016.799580010546;8053.476524025919;8082.110967793656;8109.432339736398;8137.226963438464;6788.4569234462115;6836.316368363124;6883.552569082994;6903.0335752993615;6960.825979350557;5221.503957124067;5283.524482796567;5321.255024296524;5374.785008599612;5401.48685874857;5253.894922101613;5274.97138058075;5355.3305836770405;5369.54358879484;5443.265159203457 16:21:02 INFO - PROCESS | 24559 | |4;prototype.html;343.5053522926985;355.0874253169355;356.57033274903745;356.59526437493446;357.85864583489524;5673.602847800656;5737.3688894221605;5807.157962095499;5870.919710800995;5871.295444546655;5664.830055098343;5747.913781293273;5753.683547404901;5755.223044888939;5768.451997060283;4567.155076310876;4568.291914753218;4569.29175977724;4571;4578.587927086562;4462.843800466984;4560.133574620828;4585;4603.539646035403;4637.350770892078;4549;4553.861534616346;4562.224421848277;4565.059849563922;4574.679772415932;4575.062112266977;4581.61056310214;4588.862334129964;4597.310403439477;4602.148602508547;3924.50943632046;4048.4332193492933;4050.5139383274195;4095.385692146172;4095.7747323897174;5550.611457197998;5601.327840659147;5615.7472913718575;5692.203091567184;5692.77228910843;5228.189630607262;5240.161574148156;5252.395974462909;5271.920921186147;5283.365996080456;5773.769049238067;6025.849353766147;6038;6045.486133678643;6045.758169673208;4446.7998940047455;4459.152760975404;4470.485894122185;4503.774811259424;4516.638668906479;6150.7232174552255;6340.4293613575;6350.33321501245;6382.553221274513;6388.39310265524;4566.493057291153;4573.062522183011;4576.656750743708;4577.954220457819;4582.152301824173;4675.6908065741645;4692.225782745877;4703.435587729495;4707.152712511713;4712.481627021025;5110.48895110486;5286.15421532553;5302.1781623848365;5326.573874090141;5344.599155063386;5952.285725712942;6056.424639659225;6062.151298818169;6128.406614280319;6140.232470941132;1573.9291731872092;1592.004996876952;1603.783489228939;1611.846874546916;1628.6335574495645;1027.1833892055834;1038.631285893512;1061.0874647802884;1069.7967386196597;1073.88187299397;5285;5296.205569164579;5310.575153987672;5319.760610772524;5346.887979248175;5469.835904922858;5483.561315094783;5500.436899263513;5520.806771763049;5540.63985840919;4947.703137811743;5235.267062611238;5252.185911183773;5271.603025198326;5298.735063246822;2944.1568619620602;2958.556216567489;2980.7033940236065;2992.3267264865667;2992.865321060514;5632.633878797865;5674.638086859124;5711.800086997018;5721.197822685841;5748.971255143698;6191.690415479208;6411.96794016027;6442.420182183532;6446.323136070645;6452.548321617442;6537.378948999837;6543.574667646588;6545.247296560861;6550.639714815636;6581.539292249592;6128.264608247039;6293.779717710041;6311.747530098744;6351.682415879095;6356.618602883842;6257.155284036597;6266.717997690023;6292.640259902537;6304.968475157778;6369.90445143314;6401;6417.903731443938;6448.935510645021;6565.770198043046;6617.43751781104;4381.693281470394;4390.582894625006;4391.5827996340295;4421.6683748719615;4429.180601588717;6078.635281883101;6188;6331.2085989251345;6350.841228969312;6359.20509936258;6099.9390006099375;6112.602680825732;6119;6119.296280927751;6137.570370074052;6041.2750469943885;6124.663143527148;6128.448439640454;6152.692365381624;6198.7602479503375;6043.909341359795;6051.33435322123;6076.544259180668;6091.634501929899;6099.420555047263;6182.1345011697495;6192.903548225887;6209.59637623553;6232.189815323979;6233;5819.817568411846;5982.816272060753;5987.131865879336;6023.6385816851125;6067.150598916067 16:21:02 INFO - PROCESS | 24559 | |5;yui.html;790.3361176611645;795.141247452751;806.9556174410408;835.8453686068076;883.055131009819;16547.172641367928;17537.824621753778;17789.46631601054;17827.732584011217;17940.282388704436;16193.271302791372;16266.674666506662;16267.023978561265;16283.185840707976;16307.51077467675;715.2418436457345;834.7036801935317;834.8079941613432;854.0903937306748;854.0989256334565;856.9614367353453;857.0700789643214;857.8798968144465;859.9183077607618;860.5009094725076;912.9771755706095;914.1087439746261;914.6935776514842;915.1031988651092;918.1186061381082;905.5743800413795;905.7282815155427;907.5915837872917;909.7452713240303;909.822584596004;667.7195577857311;667.9298673639247;670.6311528659218;670.9060731497594;671.9361660642231;15708.214589270605;15715.371385144581;15716.764248536281;15718.921405392899;15738.842611573853;15746.76379854303;16088.356465741357;16168.757468637863;17221.655566888592;17584.472465826046;14431.35058922351;14550.27248637564;14695.559133226021;14717.705645887023;14750.336234869457;4923;4952.034353301107;4975.701457912537;4996.25056241563;5173.3016042834115;67092;69203;88146;88787;91412.54293728556;12835.037372197123;13021.609351719464;13208.669783255304;13210.867891321155;13233.470661173638;24919.62620560693;25012.249632511055;25284.11505597332;25306.240812775646;25322.1137260195;9316.673916412892;9502.144806967406;9552.42685438876;9578.808423831484;9705.126538611417;5180.145276029489;5195.558377537952;5327.680339179662;5329.120695085345;5358.4373640767935;3778.9244215115546;3916.7649941003633;3929.7838618875667;3932.2332145231976;3941.6452519273403;713.169157931015;718.4037249083353;722.7470385365081;735.2390276064248;741.492077926622;769.3960241210623;773.880048592469;781.6052893288853;782.5187509681618;806.467731297341;10283.845742313872;10655;10670.146388288918;10827.24209305341;10941.28881622692;826.3430572694602;829.1832544943226;834.9791255218547;838.932885369169;852.6120615120105;5007.9499205008215;5095.26118712792;5106.872328191751;5195.6622819516615;5198.610104242197;127544.36227818801;128034;128188.3590582041;129350;131325;70757.64621176862;72342.63828680823;88585;101510;118945;83616.58191709002;99423.50288248512;99462.50268748609;101871.49064254631;102282;9508.334416590773;9909.801803964025;10178.949105254427;10243.334183278062;10384.792304154025;58842.41157588369;59595.70202148962;60285.69857150686;60551.69724151351;60560.86157289052;78852.60573697095;85936.57031714801;88700;89522.8666996652;91048;12507.687307817378;12976.870231297566;13720.313984300945;13912;14228.430862765788;14965.77551336709;15085.54743357701;15604;15663.216839158224;15856.127912964897;14405.92797036008;14693;14901.180435076174;15453.459128930434;15465.690686186439;13925.303734813016;14034.165832185083;14185.148891066568;15126.168060756763;15145.394184232508;13415;14383.208923509308;14942.626434339229;15189.6202594936;16637.168141593113;11853.5851245206;11894.940525297317;11904.940475297568;12427.875721242672;12471.251724896536;11278.830817537579;11997.280163190237;12192.57325993586;12310.13829031995;12398.938005309916 16:21:02 INFO - PROCESS | 24559 | __end_tp_report 16:21:02 INFO - PROCESS | 24559 | __start_cc_report 16:21:02 INFO - PROCESS | 24559 | _x_x_mozilla_cycle_collect,315 16:21:02 INFO - PROCESS | 24559 | __end_cc_report 16:21:02 INFO - PROCESS | 24559 | __startTimestamp1477437662687__endTimestamp 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | ------- Summary: start ------- 16:21:02 INFO - PROCESS | 24559 | Number of tests: 6 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | [#0] dojo.html Cycles:165 Average:5826.44 Median:6077.33 stddev:670.86 (11.0%) stddev-sans-first:643.63 16:21:02 INFO - PROCESS | 24559 | Values: 3327.1 3337.3 3348.0 3351.2 3373.2 5829.5 5840.5 5930.6 5932.1 5949.6 5785.3 5790.5 5815.3 5815.7 5820.9 5232.9 5267.3 5293.3 5333.7 5368.0 5321.7 5326.7 5326.8 5330.7 5367.1 5258.6 5268.3 5313.3 5313.4 5313.8 5315.9 5405.8 5415.0 5415.7 5442.3 5295.2 5391.1 5395.6 5403.4 5403.6 5698.5 5704.2 5826.7 5941.7 5969.8 6146.3 6261.3 6272.5 6289.1 6294.1 6321.2 6324.8 6325.2 6331.9 6333.5 5740.2 5794.5 5862.5 5870.5 5883.4 6319.2 6393.5 6416.9 6420.6 6440.0 6075.1 6152.5 6166.8 6178.4 6206.9 6070.8 6197.3 6220.3 6264.9 6290.4 6238.2 6258.6 6263.4 6276.1 6295.1 6249.8 6253.9 6264.8 6278.7 6312.3 4660.8 4719.8 4789.0 4792.1 4808.2 4204.2 4263.3 4274.1 4289.4 4298.1 6005.5 6054.7 6075.4 6077.3 6111.7 6114.2 6136.4 6311.5 6368.4 6399.4 5936.8 5991.9 5998.2 6003.0 6023.7 5378.1 5384.6 5388.3 5395.3 5397.4 6164.0 6411.6 6442.7 6448.9 6474.9 6366.2 6374.9 6379.4 6382.2 6387.5 6505.4 6508.1 6569.8 6572.8 6573.6 5937.1 6053.1 6063.0 6254.0 6259.8 6280.3 6284.7 6285.6 6289.8 6290.3 6104.8 6109.4 6113.6 6119.8 6122.6 5969.6 6110.9 6114.2 6122.9 6140.7 6130.3 6149.7 6149.8 6156.1 6176.4 6188.9 6192.8 6201.8 6229.8 6232.6 5857.5 5863.8 5957.5 6079.8 6094.5 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | [#1] ext.html Cycles:180 Average:59905.68 Median:44899.39 stddev:119379.18 (265.9%) stddev-sans-first:119630.99 16:21:02 INFO - PROCESS | 24559 | Values: 586.3 628.8 657.6 663.0 703.2 41845.6 46061.3 46096.5 46210.0 46255.0 46998.3 47508.3 47538.0 47719.3 47726.8 8965.6 9225.8 9227.2 9241.5 9245.0 9219.0 9289.4 9291.6 9297.4 9311.4 9301.4 9327.4 9340.2 9357.0 9377.6 9245.8 9267.0 9308.8 9340.1 9344.9 9065.5 9170.8 9233.7 9243.0 9259.2 43590.3 44071.8 44149.1 44372.8 44375.6 3646.4 3780.2 3790.2 3894.8 4159.1 34736.8 35259.6 35385.5 37162.3 37180.6 4713.5 4815.2 4853.1 4864.3 4960.6 257092.0 261495.0 262334.0 262443.0 263037.7 38427.8 39319.4 62992.4 63292.7 63317.4 40630.4 40725.2 40810.0 40881.6 40940.2 13217.3 13226.9 13313.6 13318.8 13349.5 10689.1 10749.5 10760.6 10762.7 10764.7 9270.5 9273.4 9307.2 9412.8 9443.2 3563.2 3591.2 3603.4 3616.0 3627.3 1121.6 1145.9 1171.9 1185.8 1195.9 34209.0 34253.5 34605.5 34683.1 35058.6 1175.8 1177.9 1187.7 1221.1 1268.9 18050.4 18061.1 18109.5 18206.0 18366.4 547065.0 550854.0 550930.0 553043.0 555443.0 63390.4 64196.4 64223.4 64228.0 64249.7 1386.2 1412.4 1427.3 1430.8 1432.8 32712.2 33215.2 33327.3 33637.7 34002.1 55283.2 55301.7 55514.4 55625.7 55765.2 477704.0 480337.0 481848.0 481898.0 483266.0 30710.4 32155.0 32218.4 32266.5 32420.4 52016.5 52623.0 53051.7 53234.7 53540.0 43811.0 44479.0 45319.8 46953.5 46965.8 45364.3 46008.3 46796.3 47047.8 47465.0 44342.6 45431.5 45686.1 45734.3 47605.5 38864.6 40067.8 40329.8 40779.2 41009.8 30005.5 30682.2 30919.5 31109.7 31345.8 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | [#2] jquery.html Cycles:180 Average:31661.09 Median:2786.59 stddev:92047.19 (3303.2%) stddev-sans-first:92275.49 16:21:02 INFO - PROCESS | 24559 | Values: 407.9 414.1 427.8 428.5 429.8 19168.3 20629.8 20715.7 20722.6 21350.7 20109.3 20127.9 20174.5 20564.8 20718.0 5943.9 6150.7 6151.0 6153.9 6156.3 5933.9 5987.9 6030.6 6057.0 6058.7 5809.9 5922.2 5953.3 5957.6 5958.3 5931.7 5949.9 5961.8 5974.0 5994.5 5346.6 5380.8 5382.1 5385.3 5393.3 23813.2 24667.5 24691.5 24779.4 24971.6 2551.9 2613.5 2753.8 2760.8 2812.4 14722.9 15391.2 15413.3 15814.7 15986.6 2051.7 2068.3 2081.3 2131.1 2171.9 73792.6 75350.6 78593.2 82495.6 83609.0 11066.6 11307.9 11315.8 11667.9 12252.1 16218.0 16325.9 16389.3 16392.8 16417.3 7772.8 7959.9 8002.2 8044.0 8065.4 7393.5 7400.1 7600.5 7612.0 7652.5 6913.9 6986.8 7053.4 7097.5 7118.4 1993.7 2057.8 2077.9 2102.2 2135.7 490.3 492.3 984.4 1058.5 1087.2 12829.3 13121.8 13242.8 13316.3 13370.7 500.4 524.2 525.9 525.9 526.1 7046.8 7067.4 7123.5 7160.7 7190.3 543508.0 554707.0 559647.0 566428.0 570038.0 100216.0 101444.0 103054.5 104223.5 105870.0 1004.5 1045.7 1079.3 1096.8 1099.6 16577.3 18761.2 19226.4 19245.2 19292.1 43526.3 44648.0 44975.0 45618.3 45739.3 58706.4 64732.4 65677.7 66811.7 67655.7 8893.7 8993.8 9105.8 9145.3 9169.5 14074.0 14090.5 14123.2 14367.7 14478.8 11826.9 11907.0 11910.7 11973.0 12192.6 11887.6 12041.8 12172.5 12186.8 12395.1 11822.9 11837.9 12096.6 12190.2 12220.6 9262.5 9342.6 9359.2 9463.0 9491.3 10646.8 11430.4 11580.0 11753.8 11923.4 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | [#3] mootools.html Cycles:180 Average:6155.41 Median:502.41 stddev:3509.82 (698.6%) stddev-sans-first:3502.39 16:21:02 INFO - PROCESS | 24559 | Values: 1521.9 1542.9 1684.7 1787.1 1790.6 8246.7 8585.5 8854.3 9518.5 9530.8 8618.1 8879.6 8886.6 8912.7 8936.2 2540.8 2572.8 2573.7 2583.1 2588.1 2472.2 2543.1 2556.0 2558.9 2559.6 2510.1 2520.4 2579.2 2583.7 2584.5 2517.0 2523.8 2529.8 2555.3 2569.9 2524.2 2525.3 2529.1 2557.7 2579.9 9348.8 9407.2 9465.9 9477.1 9501.2 5809.2 5881.7 5917.3 5924.9 5934.4 3648.5 3761.2 3778.6 3823.1 3849.2 1295.1 1372.4 1378.6 1390.8 1415.4 12948.6 12999.3 13010.1 13029.2 13060.5 4899.7 4966.8 5131.7 5160.7 5243.7 10520.1 10533.7 10553.5 10589.7 10643.2 5837.6 5841.1 5850.1 5850.3 5851.2 5203.2 5335.6 5424.9 5472.1 5508.0 4061.2 4136.3 4149.0 4197.2 4217.7 1200.7 1215.1 1231.0 1240.6 1242.9 864.4 924.1 987.5 1007.5 1023.4 5652.6 6093.9 6094.1 6097.5 6117.1 501.5 501.8 503.1 506.3 507.7 4078.1 4081.0 4099.8 4117.0 4123.5 11250.9 11467.9 11635.3 11638.5 11643.8 11428.3 11523.4 11550.3 11557.2 11573.8 8486.0 8836.3 8876.8 9112.2 9117.0 6077.5 6114.8 6293.1 6301.7 6460.5 10624.7 11088.8 11222.8 11262.7 11313.1 11349.7 11360.3 11389.8 11401.5 11496.7 8038.1 8242.8 8319.0 8358.5 8445.7 9147.8 9214.3 9221.5 9224.5 9225.7 8212.8 8212.9 8238.1 8275.3 8417.8 8016.8 8053.5 8082.1 8109.4 8137.2 6788.5 6836.3 6883.6 6903.0 6960.8 5221.5 5283.5 5321.3 5374.8 5401.5 5253.9 5275.0 5355.3 5369.5 5443.3 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | [#4] prototype.html Cycles:180 Average:5084.37 Median:5575.97 stddev:1487.87 (26.7%) stddev-sans-first:1448.86 16:21:02 INFO - PROCESS | 24559 | Values: 343.5 355.1 356.6 356.6 357.9 5673.6 5737.4 5807.2 5870.9 5871.3 5664.8 5747.9 5753.7 5755.2 5768.5 4567.2 4568.3 4569.3 4571.0 4578.6 4462.8 4560.1 4585.0 4603.5 4637.4 4549.0 4553.9 4562.2 4565.1 4574.7 4575.1 4581.6 4588.9 4597.3 4602.1 3924.5 4048.4 4050.5 4095.4 4095.8 5550.6 5601.3 5615.7 5692.2 5692.8 5228.2 5240.2 5252.4 5271.9 5283.4 5773.8 6025.8 6038.0 6045.5 6045.8 4446.8 4459.2 4470.5 4503.8 4516.6 6150.7 6340.4 6350.3 6382.6 6388.4 4566.5 4573.1 4576.7 4578.0 4582.2 4675.7 4692.2 4703.4 4707.2 4712.5 5110.5 5286.2 5302.2 5326.6 5344.6 5952.3 6056.4 6062.2 6128.4 6140.2 1573.9 1592.0 1603.8 1611.8 1628.6 1027.2 1038.6 1061.1 1069.8 1073.9 5285.0 5296.2 5310.6 5319.8 5346.9 5469.8 5483.6 5500.4 5520.8 5540.6 4947.7 5235.3 5252.2 5271.6 5298.7 2944.2 2958.6 2980.7 2992.3 2992.9 5632.6 5674.6 5711.8 5721.2 5749.0 6191.7 6412.0 6442.4 6446.3 6452.5 6537.4 6543.6 6545.2 6550.6 6581.5 6128.3 6293.8 6311.7 6351.7 6356.6 6257.2 6266.7 6292.6 6305.0 6369.9 6401.0 6417.9 6448.9 6565.8 6617.4 4381.7 4390.6 4391.6 4421.7 4429.2 6078.6 6188.0 6331.2 6350.8 6359.2 6099.9 6112.6 6119.0 6119.3 6137.6 6041.3 6124.7 6128.4 6152.7 6198.8 6043.9 6051.3 6076.5 6091.6 6099.4 6182.1 6192.9 6209.6 6232.2 6233.0 5819.8 5982.8 5987.1 6023.6 6067.2 16:21:02 INFO - PROCESS | 24559 | 16:21:02 INFO - PROCESS | 24559 | [#5] yui.html Cycles:180 Average:22712.53 Median:3936.94 stddev:32482.81 (825.1%) stddev-sans-first:32532.23 16:21:02 INFO - PROCESS | 24559 | Values: 790.3 795.1 807.0 835.8 883.1 16547.2 17537.8 17789.5 17827.7 17940.3 16193.3 16266.7 16267.0 16283.2 16307.5 715.2 834.7 834.8 854.1 854.1 857.0 857.1 857.9 859.9 860.5 913.0 914.1 914.7 915.1 918.1 905.6 905.7 907.6 909.7 909.8 667.7 667.9 670.6 670.9 671.9 15708.2 15715.4 15716.8 15718.9 15738.8 15746.8 16088.4 16168.8 17221.7 17584.5 14431.4 14550.3 14695.6 14717.7 14750.3 4923.0 4952.0 4975.7 4996.3 5173.3 67092.0 69203.0 88146.0 88787.0 91412.5 12835.0 13021.6 13208.7 13210.9 13233.5 24919.6 25012.2 25284.1 25306.2 25322.1 9316.7 9502.1 9552.4 9578.8 9705.1 5180.1 5195.6 5327.7 5329.1 5358.4 3778.9 3916.8 3929.8 3932.2 3941.6 713.2 718.4 722.7 735.2 741.5 769.4 773.9 781.6 782.5 806.5 10283.8 10655.0 10670.1 10827.2 10941.3 826.3 829.2 835.0 838.9 852.6 5007.9 5095.3 5106.9 5195.7 5198.6 127544.4 128034.0 128188.4 129350.0 131325.0 70757.6 72342.6 88585.0 101510.0 118945.0 83616.6 99423.5 99462.5 101871.5 102282.0 9508.3 9909.8 10178.9 10243.3 10384.8 58842.4 59595.7 60285.7 60551.7 60560.9 78852.6 85936.6 88700.0 89522.9 91048.0 12507.7 12976.9 13720.3 13912.0 14228.4 14965.8 15085.5 15604.0 15663.2 15856.1 14405.9 14693.0 14901.2 15453.5 15465.7 13925.3 14034.2 14185.1 15126.2 15145.4 13415.0 14383.2 14942.6 15189.6 16637.2 11853.6 11894.9 11904.9 12427.9 12471.3 11278.8 11997.3 12192.6 12310.1 12398.9 16:21:02 INFO - PROCESS | 24559 | -------- Summary: end -------- 16:21:02 INFO - PROCESS | 24559 | 16:21:03 INFO - TEST-INFO | 24559: exit 0 16:21:03 INFO - TEST-OK | dromaeo_css | took 1111077ms 16:21:03 INFO - TEST-START | kraken 16:21:03 INFO - operating with platform_type : linux_ 16:21:03 INFO - Initialising browser for kraken test... 16:21:03 INFO - TEST-INFO | started process 24804 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQKPKF0/profile http://localhost:47675/getInfo.html) 16:21:07 INFO - PROCESS | 24804 | __metrics Screen width/height:1600/1200 16:21:07 INFO - PROCESS | 24804 | colorDepth:24 16:21:07 INFO - PROCESS | 24804 | Browser inner width/height: 1024/697 16:21:07 INFO - PROCESS | 24804 | __metrics 16:21:08 ERROR - PROCESS | 24804 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:21:09 INFO - TEST-INFO | 24804: exit 0 16:21:09 INFO - Browser initialized. 16:21:09 INFO - Running cycle 1/1 for kraken test... 16:21:09 INFO - TEST-INFO | started process 24927 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQKPKF0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:21 INFO - PROCESS | 24927 | RSS: Main: 150351872 16:21:21 INFO - PROCESS | 24927 | 16:21:23 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47675/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:24 INFO - PROCESS | 24927 | RSS: Main: 156868608 16:21:24 INFO - PROCESS | 24927 | 16:21:27 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47675/tests/kraken/driver.html?testName=audio-dft) 16:21:27 INFO - PROCESS | 24927 | RSS: Main: 155656192 16:21:27 INFO - PROCESS | 24927 | 16:21:30 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47675/tests/kraken/driver.html?testName=audio-fft) 16:21:31 INFO - PROCESS | 24927 | RSS: Main: 156356608 16:21:31 INFO - PROCESS | 24927 | 16:21:33 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47675/tests/kraken/driver.html?testName=audio-oscillator) 16:21:33 INFO - PROCESS | 24927 | RSS: Main: 155955200 16:21:33 INFO - PROCESS | 24927 | 16:21:36 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:36 INFO - PROCESS | 24927 | RSS: Main: 156516352 16:21:36 INFO - PROCESS | 24927 | 16:21:39 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47675/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:40 INFO - PROCESS | 24927 | RSS: Main: 155971584 16:21:40 INFO - PROCESS | 24927 | 16:21:43 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47675/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:44 INFO - PROCESS | 24927 | RSS: Main: 157020160 16:21:44 INFO - PROCESS | 24927 | 16:21:47 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47675/tests/kraken/driver.html?testName=json-parse-financial) 16:21:47 INFO - PROCESS | 24927 | RSS: Main: 156844032 16:21:47 INFO - PROCESS | 24927 | 16:21:49 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:49 INFO - PROCESS | 24927 | RSS: Main: 156012544 16:21:49 INFO - PROCESS | 24927 | 16:21:51 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:52 INFO - PROCESS | 24927 | RSS: Main: 156270592 16:21:52 INFO - PROCESS | 24927 | 16:21:54 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:54 INFO - PROCESS | 24927 | RSS: Main: 156610560 16:21:54 INFO - PROCESS | 24927 | 16:21:57 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:58 INFO - PROCESS | 24927 | RSS: Main: 157044736 16:21:58 INFO - PROCESS | 24927 | 16:22:00 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:22:01 INFO - PROCESS | 24927 | RSS: Main: 157310976 16:22:01 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | Cycle 1(1): loaded http://localhost:47675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47675/tests/kraken/driver.html?testName=ai-astar) 16:22:03 INFO - PROCESS | 24927 | RSS: Main: 158507008 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | __start_tp_report 16:22:03 INFO - PROCESS | 24927 | _x_x_mozilla_page_load 16:22:03 INFO - PROCESS | 24927 | _x_x_mozilla_page_load_details 16:22:03 INFO - PROCESS | 24927 | |i|pagename|runs| 16:22:03 INFO - PROCESS | 24927 | |0;ai-astar;100;112;107;112;113;112;111;112;112;110 16:22:03 INFO - PROCESS | 24927 | |1;audio-beat-detection;148;117;153;177;118;149;139;118;165;118 16:22:03 INFO - PROCESS | 24927 | |2;audio-dft;163;163;165;163;166;161;160;162;161;161 16:22:03 INFO - PROCESS | 24927 | |3;audio-fft;75;75;83;182;76;76;75;75;181;76 16:22:03 INFO - PROCESS | 24927 | |4;audio-oscillator;84;83;142;85;84;85;148;83;83;152 16:22:03 INFO - PROCESS | 24927 | |5;imaging-gaussian-blur;118;117;116;117;117;117;118;117;117;116 16:22:03 INFO - PROCESS | 24927 | |6;imaging-darkroom;148;147;147;146;148;146;146;147;147;147 16:22:03 INFO - PROCESS | 24927 | |7;imaging-desaturate;95;95;94;96;96;95;95;95;95;95 16:22:03 INFO - PROCESS | 24927 | |8;json-parse-financial;53;51;57;54;63;52;50;51;56;51 16:22:03 INFO - PROCESS | 24927 | |9;json-stringify-tinderbox;64;61;60;61;62;61;60;61;61;62 16:22:03 INFO - PROCESS | 24927 | |10;stanford-crypto-aes;73;74;73;72;83;74;73;73;73;74 16:22:03 INFO - PROCESS | 24927 | |11;stanford-crypto-ccm;120;136;123;121;128;120;139;123;173;121 16:22:03 INFO - PROCESS | 24927 | |12;stanford-crypto-pbkdf2;153;157;157;157;154;158;156;155;153;158 16:22:03 INFO - PROCESS | 24927 | |13;stanford-crypto-sha256-iterative;62;64;65;61;65;64;62;63;62;62 16:22:03 INFO - PROCESS | 24927 | __end_tp_report 16:22:03 INFO - PROCESS | 24927 | __start_cc_report 16:22:03 INFO - PROCESS | 24927 | _x_x_mozilla_cycle_collect,859 16:22:03 INFO - PROCESS | 24927 | __end_cc_report 16:22:03 INFO - PROCESS | 24927 | __startTimestamp1477437723382__endTimestamp 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | ------- Summary: start ------- 16:22:03 INFO - PROCESS | 24927 | Number of tests: 14 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#0] ai-astar Cycles:10 Average:110.10 Median:112.00 stddev:3.93 (3.5%) stddev-sans-first:1.79 16:22:03 INFO - PROCESS | 24927 | Values: 100.0 112.0 107.0 112.0 113.0 112.0 111.0 112.0 112.0 110.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:148.50 stddev:21.83 (14.7%) stddev-sans-first:22.97 16:22:03 INFO - PROCESS | 24927 | Values: 148.0 117.0 153.0 177.0 118.0 149.0 139.0 118.0 165.0 118.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.90 (1.2%) stddev-sans-first:2.01 16:22:03 INFO - PROCESS | 24927 | Values: 163.0 163.0 165.0 163.0 166.0 161.0 160.0 162.0 161.0 161.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#3] audio-fft Cycles:10 Average:97.40 Median:75.50 stddev:44.39 (58.8%) stddev-sans-first:46.34 16:22:03 INFO - PROCESS | 24927 | Values: 75.0 75.0 83.0 182.0 76.0 76.0 75.0 75.0 181.0 76.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#4] audio-oscillator Cycles:10 Average:102.90 Median:83.50 stddev:30.76 (36.8%) stddev-sans-first:31.86 16:22:03 INFO - PROCESS | 24927 | Values: 84.0 83.0 142.0 85.0 84.0 85.0 148.0 83.0 83.0 152.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 16:22:03 INFO - PROCESS | 24927 | Values: 118.0 117.0 116.0 117.0 117.0 117.0 118.0 117.0 117.0 116.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 16:22:03 INFO - PROCESS | 24927 | Values: 148.0 147.0 147.0 146.0 148.0 146.0 146.0 147.0 147.0 147.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:22:03 INFO - PROCESS | 24927 | Values: 95.0 95.0 94.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:3.97 (7.4%) stddev-sans-first:4.20 16:22:03 INFO - PROCESS | 24927 | Values: 53.0 51.0 57.0 54.0 63.0 52.0 50.0 51.0 56.0 51.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:1.16 (1.9%) stddev-sans-first:0.71 16:22:03 INFO - PROCESS | 24927 | Values: 64.0 61.0 60.0 61.0 62.0 61.0 60.0 61.0 61.0 62.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:73.50 stddev:3.16 (4.3%) stddev-sans-first:3.32 16:22:03 INFO - PROCESS | 24927 | Values: 73.0 74.0 73.0 72.0 83.0 74.0 73.0 73.0 73.0 74.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:125.50 stddev:16.43 (13.1%) stddev-sans-first:16.99 16:22:03 INFO - PROCESS | 24927 | Values: 120.0 136.0 123.0 121.0 128.0 120.0 139.0 123.0 173.0 121.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.80 Median:157.00 stddev:1.93 (1.2%) stddev-sans-first:1.76 16:22:03 INFO - PROCESS | 24927 | Values: 153.0 157.0 157.0 157.0 154.0 158.0 156.0 155.0 153.0 158.0 16:22:03 INFO - PROCESS | 24927 | 16:22:03 INFO - PROCESS | 24927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.50 stddev:1.41 (2.2%) stddev-sans-first:1.45 16:22:03 INFO - PROCESS | 24927 | Values: 62.0 64.0 65.0 61.0 65.0 64.0 62.0 63.0 62.0 62.0 16:22:03 INFO - PROCESS | 24927 | -------- Summary: end -------- 16:22:03 INFO - PROCESS | 24927 | 16:22:04 INFO - TEST-INFO | 24927: exit 0 16:22:04 INFO - TEST-OK | kraken | took 60922ms 16:22:04 INFO - SUITE-END | took 1171s 16:22:04 INFO - Completed test suite (00:19:32) 16:22:04 INFO - javascript benchmark 16:22:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7661.740198013357, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.118313646883, 3337.349216902705, 3347.9788664457337, 3351.212465070708, 3373.241020770327, 5829.533637309016, 5840.53275737941, 5930.555208359374, 5932.11018347247, 5949.583529152956, 5785.305763308409, 5790.536757059435, 5815.302163740346, 5815.651060936336, 5820.941790582093, 5232.8691782705355, 5267.28891599633, 5293.258943747859, 5333.679979201241, 5368.0, 5321.680699158043, 5326.6537675050995, 5326.760295786699, 5330.653507521999, 5367.114426119686, 5258.6318957672975, 5268.341457317835, 5313.309269794921, 5313.415524292324, 5313.787448502056, 5315.920261196065, 5405.756740946647, 5414.972925135388, 5415.729213539307, 5442.319710036246, 5295.152775555893, 5391.056565101131, 5395.622306438551, 5403.351597808249, 5403.567714582863, 5698.487136157766, 5704.201411802351, 5826.7377967991115, 5941.70291485424, 5969.822001634448, 6146.323904370515, 6261.311255761862, 6272.529560282951, 6289.0880822280515, 6294.087357333161, 6321.178246827929, 6324.810255692337, 6325.177726895474, 6331.905021424683, 6333.461655759266, 5740.167675687044, 5794.507466865363, 5862.472377486047, 5870.501007414375, 5883.352831188566, 6319.24169099711, 6393.456556192776, 6416.935830641633, 6420.6147631142285, 6440.0, 6075.119107729354, 6152.477039451607, 6166.753329866845, 6178.382161783876, 6206.875862482815, 6070.7875224366935, 6197.349278325711, 6220.346863579259, 6264.9373506265265, 6290.402411770874, 6238.157848690459, 6258.624482531062, 6263.3736626337, 6276.089966954766, 6295.055741638791, 6249.812505624838, 6253.906191407133, 6264.780732674334, 6278.717457714414, 6312.274088479791, 4660.836870709509, 4719.811207551659, 4788.976055119771, 4792.0895029944195, 4808.230683090758, 4204.159168166379, 4263.339182426729, 4274.1451709658195, 4289.356596510548, 4298.075913678512, 6005.489533389624, 6054.697265136726, 6075.362086980892, 6077.331493535796, 6111.694415279218, 6114.236541168675, 6136.355682653347, 6311.526635502264, 6368.395002474757, 6399.392057754506, 5936.762529498772, 5991.880162396727, 5998.190244316961, 6003.0, 6023.66869822164, 5378.139497680352, 5384.596155288369, 5388.326459192601, 5395.2986111806, 5397.4062853086125, 6163.96918015416, 6411.551191416556, 6442.677866106769, 6448.871022579522, 6474.870502589922, 6366.172397588284, 6374.904376434264, 6379.425851673372, 6382.234131904015, 6387.489000880011, 6505.381988711065, 6508.121403610452, 6569.770058047945, 6572.76995305162, 6573.605583664996, 5937.079752638348, 6053.061775424816, 6063.0, 6253.96873015632, 6259.812205633838, 6280.340564240689, 6284.748610055546, 6285.59143655661, 6289.811305660837, 6290.339514351111, 6104.847378815565, 6109.358517355614, 6113.602615829956, 6119.816405507842, 6122.571420000557, 5969.582129250911, 6110.908336374869, 6114.174586430775, 6122.908156377569, 6140.723667435065, 6130.325664176848, 6149.692515374303, 6149.7847575334645, 6156.138140660221, 6176.413240742122, 6188.907166392598, 6192.752289908353, 6201.751929922752, 6229.844253893508, 6232.594881332699, 5857.531397488276, 5863.794767183128, 5957.463828255304, 6079.848003799763, 6094.54290928191], "value": 5780.190664562366, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.293516312843, 628.8050704281673, 657.6415853359919, 662.9701663425145, 703.2440126863623, 41845.58154418455, 46061.30908036382, 46096.53903460968, 46210.0, 46254.999999999956, 46998.29502557465, 47508.2873756893, 47538.0492390152, 47719.28421073678, 47726.76136619312, 8965.641374345034, 9225.769355766126, 9227.169554740072, 9241.49171795551, 9244.953775231148, 9219.0, 9289.396189247676, 9291.628334866631, 9297.442153470813, 9311.394759340621, 9301.395409298408, 9327.440353578773, 9340.15938565529, 9356.953215233914, 9377.578008989578, 9245.768855778591, 9267.0, 9308.813823723522, 9340.112689294507, 9344.859827102599, 9065.546722663907, 9170.770730731718, 9233.722988310361, 9243.02948190437, 9259.212966897821, 43590.34614480754, 44071.77964110159, 44149.11701765979, 44372.77813610944, 44375.55624443747, 3646.4165733482514, 3780.1683629601443, 3790.2040571480024, 3894.844206231747, 4159.064210552601, 34736.826315868515, 35259.647403525894, 35385.46921796175, 37162.25675486502, 37180.62819371826, 4713.457952335491, 4815.229563269895, 4853.077915196137, 4864.318995340655, 4960.627952903511, 257092.0, 261495.0, 262334.0, 262443.0, 263037.6848115766, 38427.807860960515, 39319.41020884689, 62992.370076299565, 63292.68353658202, 63317.366826332065, 40630.39054414186, 40725.18549628991, 40810.0, 40881.59118408837, 40940.1811963759, 13217.273049982343, 13226.933865330804, 13313.600591982256, 13318.800217996737, 13349.532766353324, 10689.091427228772, 10749.462526873782, 10760.56957721698, 10762.677119686421, 10764.677059688222, 9270.490123043297, 9273.350865439354, 9307.162355388047, 9412.811743765087, 9443.197328227041, 3563.233904710448, 3591.1560783215923, 3603.3694103531775, 3615.9819200903826, 3627.3108109459117, 1121.6018313498753, 1145.9025982791388, 1171.908143963022, 1185.7984142695761, 1195.892369686715, 34209.0, 34253.48619770656, 34605.480917786255, 34683.13292167665, 35058.64941350605, 1175.788358095551, 1177.9411029448493, 1187.6971372300009, 1221.1207930290177, 1268.9302088385043, 18050.368237111637, 18061.09694515269, 18109.456716298268, 18206.0, 18366.449006529827, 547065.0, 550854.0, 550930.0, 553043.0, 555443.0, 63390.36609633937, 64196.358036419035, 64223.35776642174, 64228.0, 64249.67875160688, 1386.230641993683, 1412.3644360038024, 1427.3434220258798, 1430.7567713488525, 1432.8495507971513, 32712.182195445304, 33215.169620759676, 33327.33345333128, 33637.66362336443, 34002.14994625055, 55283.17075243955, 55301.72349138228, 55514.44485555093, 55625.721871390386, 55765.163522548006, 477704.0, 480337.0, 481848.0, 481898.0, 483266.0, 30710.385792284476, 32155.035348939567, 32218.35563288674, 32266.516002260454, 32420.35159296848, 52016.479835201164, 52623.0, 53051.73474132604, 53234.73382633062, 53540.0, 43811.0, 44479.0, 45319.773401132785, 46953.53046469628, 46965.76517117392, 45364.31953520634, 46008.309875351224, 46796.29805552851, 47047.764761175975, 47465.0, 44342.55657443513, 45431.54568454273, 45686.086278273586, 45734.313985289584, 47605.523944761495, 38864.6113538861, 40067.79966100151, 40329.79835100806, 40779.18441631091, 41009.794951025055, 30005.549916751705, 30682.232944176576, 30919.536206956465, 31109.68890311068, 31345.8432707835], "value": 19939.021619600164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.9469668943035, 414.1241274704002, 427.81176282435734, 428.47511798047395, 429.75289208705004, 19168.329108481204, 20629.793702062976, 20715.689264661003, 20722.585548289044, 21350.679739803916, 20109.29617463389, 20127.899360503212, 20174.495637609067, 20564.794352056473, 20718.0, 5943.881122377561, 6150.6924653767355, 6151.046587778879, 6153.876922461571, 6156.261248650168, 5933.88132237355, 5987.940120598803, 6030.577859549833, 6056.969715151418, 6058.697065146747, 5809.941900581003, 5922.170896074553, 5953.3451320354725, 5957.612755170921, 5958.28500579931, 5931.733072011748, 5949.940500594982, 5961.791337303195, 5973.970130149365, 5994.460498555108, 5346.625736198468, 5380.757865896044, 5382.058139825514, 5385.272988146589, 5393.325834270716, 23813.166539171045, 24667.506649867082, 24691.50616987668, 24779.380515487075, 24971.62542561863, 2551.885165167572, 2613.529564678357, 2753.8072334936564, 2760.7791376689815, 2812.409394027257, 14722.926385368004, 15391.153486558234, 15413.306401211972, 15814.683706325925, 15986.60033499172, 2051.7332746742827, 2068.2657656531865, 2081.302763574189, 2131.094284928899, 2171.913123475075, 73792.63103684447, 75350.62324688342, 78593.21406785917, 82495.5875220626, 83609.0, 11066.557337706401, 11307.943460282646, 11315.83026254607, 11667.88332116685, 12252.142350035412, 16218.0, 16325.918370408071, 16389.262483188286, 16392.75410868838, 16417.343306267856, 7772.8056798579355, 7959.880601790977, 8002.1997800219515, 8044.0, 8065.354771618257, 7393.519421237603, 7400.148982867039, 7600.46796724224, 7612.048493938258, 7652.464327497022, 6913.89629155563, 6986.825329366807, 7053.435725141976, 7097.503174777819, 7118.394936430358, 1993.681011038256, 2057.845661575388, 2077.919491820753, 2102.2011635578383, 2135.7185688586724, 490.25726025072044, 492.33534728116757, 984.4437892590806, 1058.539535302146, 1087.1954753482366, 12829.29438880852, 13121.803172952414, 13242.801357979639, 13316.334183290797, 13370.665733356744, 500.35204410288037, 524.1796588339237, 525.8921921006184, 525.9053370393366, 526.0504788856075, 7046.823829404203, 7067.399271062018, 7123.536970096927, 7160.713571457083, 7190.280971902873, 543508.0, 554707.0, 559647.0, 566428.0, 570038.0, 100216.0, 101444.0, 103054.48472757588, 104223.47888260662, 105870.0, 1004.5178314409125, 1045.717656232813, 1079.3362082319318, 1096.8135416979133, 1099.6261271167841, 16577.336906523604, 18761.249550017845, 19226.423207303804, 19245.230190792212, 19292.13185406632, 43526.347104794084, 44648.0, 44975.0, 45618.315725263485, 45739.31391029071, 58706.412935871806, 64732.352676472634, 65677.67161164164, 66811.66594166998, 67655.66172169108, 8893.73318800437, 8993.820123597623, 9105.817883642423, 9145.314101442285, 9169.54152292396, 14074.0, 14090.506832260822, 14123.223222722438, 14367.712645747235, 14478.782818257523, 11826.940865295619, 11907.04743620491, 11910.702232444259, 11973.0, 12192.634220973385, 11887.643370698894, 12041.759164816829, 12172.513099475922, 12186.756264874475, 12395.132340736061, 11822.935098466198, 11837.940810295893, 12096.637100886988, 12190.2076365036, 12220.633380998584, 9262.53687315645, 9342.626294948126, 9359.15767580921, 9463.0, 9491.288153388385, 10646.787064258826, 11430.371329576954, 11580.0, 11753.764924701629, 11923.403829808649], "value": 9409.102907079568, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1521.855423734745, 1542.9382824687013, 1684.6714890596331, 1787.1332403784163, 1790.6329202513487, 8246.67013319468, 8585.484870907734, 8854.291656667467, 9518.524073796309, 9530.809383812328, 8618.138186181395, 8879.644814207424, 8886.55567221638, 8912.688055918044, 8936.15106564877, 2540.796736261095, 2572.768450839424, 2573.652556904822, 2583.147561304774, 2588.0812311629384, 2472.171822439485, 2543.122622695167, 2555.987220063897, 2558.9488210235786, 2559.641650168978, 2510.0587279770084, 2520.3825062859623, 2579.21333993132, 2583.651207087038, 2584.496023275462, 2517.0435234610823, 2523.823332366726, 2529.8229123961332, 2555.3100662820934, 2569.935751606206, 2524.2048754642415, 2525.3434107132093, 2529.051605647882, 2557.731438198981, 2579.8968041278326, 9348.766280843034, 9407.247420206435, 9465.90534094657, 9477.147056764925, 9501.192398646124, 5809.2447981762525, 5881.735321910524, 5917.289925208959, 5924.851878703023, 5934.376890426486, 3648.525691660094, 3761.153740408414, 3778.6032466591164, 3823.063349479395, 3849.191669749356, 1295.0999055656323, 1372.4167228927665, 1378.63466181461, 1390.7635701930594, 1415.3984556563419, 12948.611541653765, 12999.285039322927, 13010.089293749441, 13029.153105048237, 13060.542880999214, 4899.6570240083565, 4966.776495057731, 5131.7177555234075, 5160.664556803796, 5243.68537887728, 10520.053195212467, 10533.736656583493, 10553.472326383804, 10589.682309530726, 10643.201759867887, 5837.591368604242, 5841.1238314253205, 5850.063989761617, 5850.268716410449, 5851.180834683148, 5203.16749320107, 5335.62650614461, 5424.918626220609, 5472.124460086447, 5507.972460137674, 4061.226285077515, 4136.31750761121, 4148.983499042693, 4197.160567886435, 4217.683673724483, 1200.7178313096415, 1215.1493954232074, 1230.9569165079179, 1240.6092080994638, 1242.8694987026413, 864.4424346296604, 924.075924075924, 987.5457289646843, 1007.4710776842217, 1023.4012457503011, 5652.604317697722, 6093.908591371133, 6094.055421409688, 6097.512199024068, 6117.051856962178, 501.4985014985015, 501.7541404711665, 503.05676855895194, 506.3366989244102, 507.652258203132, 4078.0824314529586, 4081.0, 4099.815508302134, 4117.032497363117, 4123.505179378494, 11250.943745281385, 11467.942660286646, 11635.301881887113, 11638.534458621729, 11643.76712465746, 11428.314301141792, 11523.423828808694, 11550.306981581132, 11557.24877882935, 11573.826392604118, 8486.0, 8836.337274704423, 8876.822463550692, 9112.179903808688, 9117.042710515432, 6077.51379889599, 6114.755409783559, 6293.087502312048, 6301.747930082745, 6460.483161347175, 10624.681259562225, 11088.833667495155, 11222.775544489228, 11262.718432039264, 11313.094952403953, 11349.716257093638, 11360.261582997078, 11389.772204556028, 11401.543938242377, 11496.655100347003, 8038.115807261313, 8242.752717418487, 8319.0, 8358.45670031446, 8445.746627601182, 9147.817043658957, 9214.308926830381, 9221.538923053955, 9224.492652903886, 9225.677101301422, 8212.794680133044, 8212.917870821215, 8238.134995825352, 8275.255227029596, 8417.789555261168, 8016.799580010546, 8053.476524025919, 8082.110967793656, 8109.432339736398, 8137.226963438464, 6788.4569234462115, 6836.316368363124, 6883.552569082994, 6903.0335752993615, 6960.825979350557, 5221.503957124067, 5283.524482796567, 5321.255024296524, 5374.785008599612, 5401.48685874857, 5253.894922101613, 5274.97138058075, 5355.3305836770405, 5369.54358879484, 5443.265159203457], "value": 4856.333107046976, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.5053522926985, 355.0874253169355, 356.57033274903745, 356.59526437493446, 357.85864583489524, 5673.602847800656, 5737.3688894221605, 5807.157962095499, 5870.919710800995, 5871.295444546655, 5664.830055098343, 5747.913781293273, 5753.683547404901, 5755.223044888939, 5768.451997060283, 4567.155076310876, 4568.291914753218, 4569.29175977724, 4571.0, 4578.587927086562, 4462.843800466984, 4560.133574620828, 4585.0, 4603.539646035403, 4637.350770892078, 4549.0, 4553.861534616346, 4562.224421848277, 4565.059849563922, 4574.679772415932, 4575.062112266977, 4581.61056310214, 4588.862334129964, 4597.310403439477, 4602.148602508547, 3924.50943632046, 4048.4332193492933, 4050.5139383274195, 4095.385692146172, 4095.7747323897174, 5550.611457197998, 5601.327840659147, 5615.7472913718575, 5692.203091567184, 5692.77228910843, 5228.189630607262, 5240.161574148156, 5252.395974462909, 5271.920921186147, 5283.365996080456, 5773.769049238067, 6025.849353766147, 6038.0, 6045.486133678643, 6045.758169673208, 4446.7998940047455, 4459.152760975404, 4470.485894122185, 4503.774811259424, 4516.638668906479, 6150.7232174552255, 6340.4293613575, 6350.33321501245, 6382.553221274513, 6388.39310265524, 4566.493057291153, 4573.062522183011, 4576.656750743708, 4577.954220457819, 4582.152301824173, 4675.6908065741645, 4692.225782745877, 4703.435587729495, 4707.152712511713, 4712.481627021025, 5110.48895110486, 5286.15421532553, 5302.1781623848365, 5326.573874090141, 5344.599155063386, 5952.285725712942, 6056.424639659225, 6062.151298818169, 6128.406614280319, 6140.232470941132, 1573.9291731872092, 1592.004996876952, 1603.783489228939, 1611.846874546916, 1628.6335574495645, 1027.1833892055834, 1038.631285893512, 1061.0874647802884, 1069.7967386196597, 1073.88187299397, 5285.0, 5296.205569164579, 5310.575153987672, 5319.760610772524, 5346.887979248175, 5469.835904922858, 5483.561315094783, 5500.436899263513, 5520.806771763049, 5540.63985840919, 4947.703137811743, 5235.267062611238, 5252.185911183773, 5271.603025198326, 5298.735063246822, 2944.1568619620602, 2958.556216567489, 2980.7033940236065, 2992.3267264865667, 2992.865321060514, 5632.633878797865, 5674.638086859124, 5711.800086997018, 5721.197822685841, 5748.971255143698, 6191.690415479208, 6411.96794016027, 6442.420182183532, 6446.323136070645, 6452.548321617442, 6537.378948999837, 6543.574667646588, 6545.247296560861, 6550.639714815636, 6581.539292249592, 6128.264608247039, 6293.779717710041, 6311.747530098744, 6351.682415879095, 6356.618602883842, 6257.155284036597, 6266.717997690023, 6292.640259902537, 6304.968475157778, 6369.90445143314, 6401.0, 6417.903731443938, 6448.935510645021, 6565.770198043046, 6617.43751781104, 4381.693281470394, 4390.582894625006, 4391.5827996340295, 4421.6683748719615, 4429.180601588717, 6078.635281883101, 6188.0, 6331.2085989251345, 6350.841228969312, 6359.20509936258, 6099.9390006099375, 6112.602680825732, 6119.0, 6119.296280927751, 6137.570370074052, 6041.2750469943885, 6124.663143527148, 6128.448439640454, 6152.692365381624, 6198.7602479503375, 6043.909341359795, 6051.33435322123, 6076.544259180668, 6091.634501929899, 6099.420555047263, 6182.1345011697495, 6192.903548225887, 6209.59637623553, 6232.189815323979, 6233.0, 5819.817568411846, 5982.816272060753, 5987.131865879336, 6023.6385816851125, 6067.150598916067], "value": 4610.3997026394245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.3361176611645, 795.141247452751, 806.9556174410408, 835.8453686068076, 883.055131009819, 16547.172641367928, 17537.824621753778, 17789.46631601054, 17827.732584011217, 17940.282388704436, 16193.271302791372, 16266.674666506662, 16267.023978561265, 16283.185840707976, 16307.51077467675, 715.2418436457345, 834.7036801935317, 834.8079941613432, 854.0903937306748, 854.0989256334565, 856.9614367353453, 857.0700789643214, 857.8798968144465, 859.9183077607618, 860.5009094725076, 912.9771755706095, 914.1087439746261, 914.6935776514842, 915.1031988651092, 918.1186061381082, 905.5743800413795, 905.7282815155427, 907.5915837872917, 909.7452713240303, 909.822584596004, 667.7195577857311, 667.9298673639247, 670.6311528659218, 670.9060731497594, 671.9361660642231, 15708.214589270605, 15715.371385144581, 15716.764248536281, 15718.921405392899, 15738.842611573853, 15746.76379854303, 16088.356465741357, 16168.757468637863, 17221.655566888592, 17584.472465826046, 14431.35058922351, 14550.27248637564, 14695.559133226021, 14717.705645887023, 14750.336234869457, 4923.0, 4952.034353301107, 4975.701457912537, 4996.25056241563, 5173.3016042834115, 67092.0, 69203.0, 88146.0, 88787.0, 91412.54293728556, 12835.037372197123, 13021.609351719464, 13208.669783255304, 13210.867891321155, 13233.470661173638, 24919.62620560693, 25012.249632511055, 25284.11505597332, 25306.240812775646, 25322.1137260195, 9316.673916412892, 9502.144806967406, 9552.42685438876, 9578.808423831484, 9705.126538611417, 5180.145276029489, 5195.558377537952, 5327.680339179662, 5329.120695085345, 5358.4373640767935, 3778.9244215115546, 3916.7649941003633, 3929.7838618875667, 3932.2332145231976, 3941.6452519273403, 713.169157931015, 718.4037249083353, 722.7470385365081, 735.2390276064248, 741.492077926622, 769.3960241210623, 773.880048592469, 781.6052893288853, 782.5187509681618, 806.467731297341, 10283.845742313872, 10655.0, 10670.146388288918, 10827.24209305341, 10941.28881622692, 826.3430572694602, 829.1832544943226, 834.9791255218547, 838.932885369169, 852.6120615120105, 5007.9499205008215, 5095.26118712792, 5106.872328191751, 5195.6622819516615, 5198.610104242197, 127544.36227818801, 128034.0, 128188.3590582041, 129350.0, 131325.0, 70757.64621176862, 72342.63828680823, 88585.0, 101510.0, 118945.0, 83616.58191709002, 99423.50288248512, 99462.50268748609, 101871.49064254631, 102282.0, 9508.334416590773, 9909.801803964025, 10178.949105254427, 10243.334183278062, 10384.792304154025, 58842.41157588369, 59595.70202148962, 60285.69857150686, 60551.69724151351, 60560.86157289052, 78852.60573697095, 85936.57031714801, 88700.0, 89522.8666996652, 91048.0, 12507.687307817378, 12976.870231297566, 13720.313984300945, 13912.0, 14228.430862765788, 14965.77551336709, 15085.54743357701, 15604.0, 15663.216839158224, 15856.127912964897, 14405.92797036008, 14693.0, 14901.180435076174, 15453.459128930434, 15465.690686186439, 13925.303734813016, 14034.165832185083, 14185.148891066568, 15126.168060756763, 15145.394184232508, 13415.0, 14383.208923509308, 14942.626434339229, 15189.6202594936, 16637.168141593113, 11853.5851245206, 11894.940525297317, 11904.940475297568, 12427.875721242672, 12471.251724896536, 11278.830817537579, 11997.280163190237, 12192.57325993586, 12310.13829031995, 12398.938005309916], "value": 8330.758288206349, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1510.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 107.0, 112.0, 113.0, 112.0, 111.0, 112.0, 112.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 153.0, 177.0, 118.0, 149.0, 139.0, 118.0, 165.0, 118.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 165.0, 163.0, 166.0, 161.0, 160.0, 162.0, 161.0, 161.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 83.0, 182.0, 76.0, 76.0, 75.0, 75.0, 181.0, 76.0], "value": 97.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 142.0, 85.0, 84.0, 85.0, 148.0, 83.0, 83.0, 152.0], "value": 102.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 146.0, 148.0, 146.0, 146.0, 147.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 57.0, 54.0, 63.0, 52.0, 50.0, 51.0, 56.0, 51.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 60.0, 61.0, 62.0, 61.0, 60.0, 61.0, 61.0, 62.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 72.0, 83.0, 74.0, 73.0, 73.0, 73.0, 74.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 136.0, 123.0, 121.0, 128.0, 120.0, 139.0, 123.0, 173.0, 121.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 157.0, 157.0, 157.0, 154.0, 158.0, 156.0, 155.0, 153.0, 158.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 65.0, 61.0, 65.0, 64.0, 62.0, 63.0, 62.0, 62.0], "value": 63.0, "unit": "score"}]}]} 16:22:04 INFO - Return code: 0 16:22:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:22:04 ERROR - # TBPL SUCCESS # 16:22:04 INFO - Running post-action listener: _resource_record_post_action 16:22:04 INFO - [mozharness: 2016-10-25 23:22:04.706286Z] Finished run-tests step (success) 16:22:04 INFO - Running post-run listener: _resource_record_post_run 16:22:04 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1740800; Write bytes: 288636928; Read time: 180; Write time: 194860 16:22:04 INFO - TinderboxPrint: CPU usage
13.2% 16:22:04 INFO - TinderboxPrint: I/O read bytes / time
1,740,800 / 180 16:22:04 INFO - TinderboxPrint: I/O write bytes / time
288,636,928 / 194,860 16:22:04 INFO - TinderboxPrint: CPU idle
8,222.0 (86.8%) 16:22:04 INFO - TinderboxPrint: CPU user
1,195.6 (12.6%) 16:22:04 INFO - TinderboxPrint: Swap in / out
0 / 0 16:22:04 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 70086656; Read time: 0; Write time: 69256 16:22:04 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218550272; Read time: 140; Write time: 125604 16:22:05 INFO - Running post-run listener: _upload_blobber_files 16:22:05 INFO - Blob upload gear active. 16:22:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:22:05 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 16:22:05 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:22:05 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:22:05 INFO - (blobuploader) - INFO - Open directory for files ... 16:22:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:22:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:22:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:06 INFO - (blobuploader) - INFO - Done attempting. 16:22:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:22:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:10 INFO - (blobuploader) - INFO - Done attempting. 16:22:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:22:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:22:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:11 INFO - (blobuploader) - INFO - Done attempting. 16:22:11 INFO - (blobuploader) - INFO - Iteration through files over. 16:22:11 INFO - Return code: 0 16:22:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:22:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:22:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8adb58162dea959e149e15d7564562d6f54203b91ef6b8b39667b28419fce39a40327b94d7865c47bc3b6104fdfb1b632fe169998efed75ff4b09cb1907d8e25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0670987ae26cc4bb9c802c2236c4acfbf7c76173987773520cd21b3825d4bc8f546be8e95062822ab4ddd68b901c399d7ceb28e250012dcf5d5c93a864da31a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1134f1b5f2cfdf4b7aeb5257dd240c2a2795206065a8eb8eb90cc4dc69ed0d2e834ceb7d08305691136b40c15a518ff75bab8de6772e0d053d1b19a39b9064d"} 16:22:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:22:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:22:11 INFO - Contents: 16:22:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8adb58162dea959e149e15d7564562d6f54203b91ef6b8b39667b28419fce39a40327b94d7865c47bc3b6104fdfb1b632fe169998efed75ff4b09cb1907d8e25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0670987ae26cc4bb9c802c2236c4acfbf7c76173987773520cd21b3825d4bc8f546be8e95062822ab4ddd68b901c399d7ceb28e250012dcf5d5c93a864da31a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1134f1b5f2cfdf4b7aeb5257dd240c2a2795206065a8eb8eb90cc4dc69ed0d2e834ceb7d08305691136b40c15a518ff75bab8de6772e0d053d1b19a39b9064d"} 16:22:11 INFO - Running post-run listener: copy_logs_to_upload_dir 16:22:11 INFO - Copying logs to upload dir... 16:22:11 INFO - mkdir: /builds/slave/test/build/upload/logs 16:22:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.885809 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-25 16:22:11.211032) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 16:22:11.214885) ========= 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-1477436054.964565-1374205825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8adb58162dea959e149e15d7564562d6f54203b91ef6b8b39667b28419fce39a40327b94d7865c47bc3b6104fdfb1b632fe169998efed75ff4b09cb1907d8e25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0670987ae26cc4bb9c802c2236c4acfbf7c76173987773520cd21b3825d4bc8f546be8e95062822ab4ddd68b901c399d7ceb28e250012dcf5d5c93a864da31a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1134f1b5f2cfdf4b7aeb5257dd240c2a2795206065a8eb8eb90cc4dc69ed0d2e834ceb7d08305691136b40c15a518ff75bab8de6772e0d053d1b19a39b9064d"} build_url:https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8adb58162dea959e149e15d7564562d6f54203b91ef6b8b39667b28419fce39a40327b94d7865c47bc3b6104fdfb1b632fe169998efed75ff4b09cb1907d8e25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0670987ae26cc4bb9c802c2236c4acfbf7c76173987773520cd21b3825d4bc8f546be8e95062822ab4ddd68b901c399d7ceb28e250012dcf5d5c93a864da31a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1134f1b5f2cfdf4b7aeb5257dd240c2a2795206065a8eb8eb90cc4dc69ed0d2e834ceb7d08305691136b40c15a518ff75bab8de6772e0d053d1b19a39b9064d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 16:22:11.240003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 16:22:11.240340) ========= 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-1477436054.964565-1374205825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 16:22:11.322442) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 16:22:11.322787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 16:22:11.323100) ========= ========= Total master_lag: 0.16 =========