builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-049 starttime: 1476313741.22 results: success (0) buildid: 20161012130846 builduid: a639665ad4fd43218e6018ede79d7dae revision: 1cd295569b2bd7b40cab83c179525fd549c73051 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.224361) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.224904) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.225338) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.240674) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.241106) ========= 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=db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-12 16:09:01-- 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% 224M=0s 2016-10-12 16:09:01 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105611 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.360897) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.361249) ========= 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=db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015845 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.410880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:01.411278) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1cd295569b2bd7b40cab83c179525fd549c73051 --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 1cd295569b2bd7b40cab83c179525fd549c73051 --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=db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-12 16:09:01,815 truncating revision to first 12 chars 2016-10-12 16:09:01,815 Setting DEBUG logging. 2016-10-12 16:09:01,815 attempt 1/10 2016-10-12 16:09:01,815 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1cd295569b2b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 16:09:02,138 unpacking tar archive at: mozilla-beta-1cd295569b2b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.682186 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:02.107442) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:02.107820) ========= script_repo_revision: 1cd295569b2bd7b40cab83c179525fd549c73051 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:02.108248) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:02.108547) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:02.118050) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-12 16:09:02.118360) ========= /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=db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:09:02 INFO - MultiFileLogger online at 20161012 16:09:02 in /builds/slave/test 16:09:02 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 16:09:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:02 INFO - {'append_to_log': False, 16:09:02 INFO - 'base_work_dir': '/builds/slave/test', 16:09:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:09:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:02 INFO - 'branch': 'Mozilla-Beta', 16:09:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:02 INFO - 'config_files': ('talos/linux_config.py',), 16:09:02 INFO - 'default_actions': ('clobber', 16:09:02 INFO - 'read-buildbot-config', 16:09:02 INFO - 'download-and-extract', 16:09:02 INFO - 'populate-webroot', 16:09:02 INFO - 'create-virtualenv', 16:09:02 INFO - 'install', 16:09:02 INFO - 'run-tests'), 16:09:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:02 INFO - 'download_minidump_stackwalk': True, 16:09:02 INFO - 'download_symbols': 'ondemand', 16:09:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:02 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:02 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:02 INFO - 'installer_path': 'installer.exe', 16:09:02 INFO - 'log_level': 'info', 16:09:02 INFO - 'log_name': 'talos', 16:09:02 INFO - 'log_to_console': True, 16:09:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:09:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:09:02 INFO - 'opt_config_files': (), 16:09:02 INFO - 'pip_index': False, 16:09:02 INFO - 'sps_profile': False, 16:09:02 INFO - 'sps_profile_interval': 0, 16:09:02 INFO - 'suite': 'dromaeojs-e10s', 16:09:02 INFO - 'system_bits': '32', 16:09:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:02 INFO - 'title': 'talos-linux64-ix-049', 16:09:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:02 INFO - 'use_talos_json': True, 16:09:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:02 INFO - 'work_dir': 'build'} 16:09:02 INFO - [mozharness: 2016-10-12 23:09:02.610822Z] Running clobber step. 16:09:02 INFO - Running pre-action listener: _resource_record_pre_action 16:09:02 INFO - Running main action method: clobber 16:09:02 INFO - rmtree: /builds/slave/test/build 16:09:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:03 INFO - Running post-action listener: _resource_record_post_action 16:09:03 INFO - [mozharness: 2016-10-12 23:09:03.114102Z] Finished clobber step (success) 16:09:03 INFO - [mozharness: 2016-10-12 23:09:03.114204Z] Running read-buildbot-config step. 16:09:03 INFO - Running pre-action listener: _resource_record_pre_action 16:09:03 INFO - Running main action method: read_buildbot_config 16:09:03 INFO - Using buildbot properties: 16:09:03 INFO - { 16:09:03 INFO - "project": "", 16:09:03 INFO - "product": "firefox", 16:09:03 INFO - "script_repo_revision": "production", 16:09:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 16:09:03 INFO - "repository": "", 16:09:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 16:09:03 INFO - "buildid": "20161012130846", 16:09:03 INFO - "pgo_build": "True", 16:09:03 INFO - "basedir": "/builds/slave/test", 16:09:03 INFO - "buildnumber": 282, 16:09:03 INFO - "slavename": "talos-linux64-ix-049", 16:09:03 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:09:03 INFO - "platform": "ubuntu64_hw", 16:09:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:09:03 INFO - "branch": "mozilla-beta", 16:09:03 INFO - "repo_path": "releases/mozilla-beta", 16:09:03 INFO - "revision": "1cd295569b2bd7b40cab83c179525fd549c73051", 16:09:03 INFO - "stage_platform": "linux64-pgo", 16:09:03 INFO - "builduid": "a639665ad4fd43218e6018ede79d7dae", 16:09:03 INFO - "slavebuilddir": "test" 16:09:03 INFO - } 16:09:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:09:03 INFO - Running post-action listener: _resource_record_post_action 16:09:03 INFO - [mozharness: 2016-10-12 23:09:03.116673Z] Finished read-buildbot-config step (success) 16:09:03 INFO - [mozharness: 2016-10-12 23:09:03.116764Z] Running download-and-extract step. 16:09:03 INFO - Running pre-action listener: _resource_record_pre_action 16:09:03 INFO - Running main action method: download_and_extract 16:09:03 INFO - mkdir: /builds/slave/test/build/tests 16:09: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:09:03 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:09:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:09:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:09:03 INFO - retry: Failed, sleeping 30 seconds before retrying 16:09:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:09:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:09:33 INFO - retry: Failed, sleeping 60 seconds before retrying 16:10:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:10:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:10:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:10:33 INFO - Caught exception: HTTP Error 404: Not Found 16:10:33 INFO - Caught exception: HTTP Error 404: Not Found 16:10:33 INFO - Caught exception: HTTP Error 404: Not Found 16:10:33 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:10:33 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:33 INFO - Downloaded 1327 bytes. 16:10:33 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:10:33 INFO - Using the following test package requirements: 16:10:33 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:10:33 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:10:33 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:10:33 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'jsshell-linux-x86_64.zip'], 16:10:33 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:10:33 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:10:33 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:10:33 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:10:33 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:10:33 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:33 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:10:33 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:10:33 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:10:33 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:10:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:10:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:10:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:10:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:10:33 INFO - retry: Failed, sleeping 30 seconds before retrying 16:11:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:11:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:11:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:11:03 INFO - retry: Failed, sleeping 60 seconds before retrying 16:12:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:12:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:12:03 INFO - Caught exception: HTTP Error 404: Not Found 16:12:03 INFO - Caught exception: HTTP Error 404: Not Found 16:12:03 INFO - Caught exception: HTTP Error 404: Not Found 16:12:03 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:03 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:04 INFO - Downloaded 21774215 bytes. 16:12:04 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'] 16:12:04 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 16:12:05 INFO - Return code: 0 16:12:05 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 16:12:05 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:12:05 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:12:05 INFO - retry: Failed, sleeping 30 seconds before retrying 16:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:12:35 INFO - retry: Failed, sleeping 60 seconds before retrying 16:13:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:13:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:13:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:13:36 INFO - Caught exception: HTTP Error 404: Not Found 16:13:36 INFO - Caught exception: HTTP Error 404: Not Found 16:13:36 INFO - Caught exception: HTTP Error 404: Not Found 16:13:36 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:13:36 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:36 INFO - Downloaded 21774215 bytes. 16:13:36 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'] 16:13:36 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 16:13:38 INFO - Return code: 0 16:13:38 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:13:38 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:13:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:13:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:13:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:13:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:13:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:13:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:13:38 INFO - retry: Failed, sleeping 30 seconds before retrying 16:14:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:14:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:14:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:14:08 INFO - retry: Failed, sleeping 60 seconds before retrying 16:15:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:15:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:15:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:15:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:15:08 INFO - Caught exception: HTTP Error 404: Not Found 16:15:08 INFO - Caught exception: HTTP Error 404: Not Found 16:15:08 INFO - Caught exception: HTTP Error 404: Not Found 16:15:08 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:15:08 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:15:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:15:09 INFO - Downloaded 14413740 bytes. 16:15:09 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:15:09 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:15:09 INFO - Return code: 0 16:15: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:15:09 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:15:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:15:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:15:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:15:09 INFO - retry: Failed, sleeping 30 seconds before retrying 16:15:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:15:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:15:39 INFO - retry: Failed, sleeping 60 seconds before retrying 16:16:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:16:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:16:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:16:39 INFO - Caught exception: HTTP Error 404: Not Found 16:16:39 INFO - Caught exception: HTTP Error 404: Not Found 16:16:39 INFO - Caught exception: HTTP Error 404: Not Found 16:16:39 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:39 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:16:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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:16:40 INFO - Downloaded 56792851 bytes. 16:16:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:40 INFO - mkdir: /builds/slave/test/properties 16:16:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:40 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:40 INFO - Contents: 16:16:40 INFO - build_url:https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:41 INFO - Running post-action listener: _resource_record_post_action 16:16:41 INFO - Running post-action listener: set_extra_try_arguments 16:16:41 INFO - [mozharness: 2016-10-12 23:16:41.326275Z] Finished download-and-extract step (success) 16:16:41 INFO - [mozharness: 2016-10-12 23:16:41.326508Z] Running populate-webroot step. 16:16:41 INFO - Running pre-action listener: _resource_record_pre_action 16:16:41 INFO - Running main action method: populate_webroot 16:16:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:41 INFO - '--tppagecycles', 16:16:41 INFO - '7'], 16:16:41 INFO - 'tests': ['tsvgm']}}, 16:16:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['damp', 'tps']}, 16:16:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'tests': ['damp', 'tps']}, 16:16:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['dromaeo_dom']}, 16:16:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['basic_compositor_video']}, 16:16:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:16:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['a11yr', 16:16:41 INFO - 'ts_paint', 16:16:41 INFO - 'tpaint', 16:16:41 INFO - 'sessionrestore', 16:16:41 INFO - 'sessionrestore_no_auto_restore', 16:16:41 INFO - 'tabpaint']}, 16:16:41 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:41 INFO - 'ts_paint', 16:16:41 INFO - 'tpaint', 16:16:41 INFO - 'sessionrestore', 16:16:41 INFO - 'sessionrestore_no_auto_restore', 16:16:41 INFO - 'tabpaint']}, 16:16:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:41 INFO - 'ts_paint', 16:16:41 INFO - 'tpaint', 16:16:41 INFO - 'sessionrestore', 16:16:41 INFO - 'sessionrestore_no_auto_restore', 16:16:41 INFO - 'tabpaint']}, 16:16:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:41 INFO - 'ts_paint', 16:16:41 INFO - 'tpaint', 16:16:41 INFO - 'sessionrestore', 16:16:41 INFO - 'sessionrestore_no_auto_restore', 16:16:41 INFO - 'tabpaint']}, 16:16:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['a11yr', 16:16:41 INFO - 'ts_paint', 16:16:41 INFO - 'tpaint', 16:16:41 INFO - 'sessionrestore', 16:16:41 INFO - 'sessionrestore_no_auto_restore', 16:16:41 INFO - 'tabpaint']}, 16:16:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['a11yr', 16:16:41 INFO - 'ts_paint', 16:16:41 INFO - 'tpaint', 16:16:41 INFO - 'sessionrestore', 16:16:41 INFO - 'sessionrestore_no_auto_restore', 16:16:41 INFO - 'tabpaint']}, 16:16:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['tsvgx', 16:16:41 INFO - 'tsvgr_opacity', 16:16:41 INFO - 'tart', 16:16:41 INFO - 'tscrollx', 16:16:41 INFO - 'cart']}, 16:16:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:41 INFO - 'tsvgr_opacity', 16:16:41 INFO - 'tart', 16:16:41 INFO - 'tscrollx', 16:16:41 INFO - 'cart']}, 16:16:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'talos_options': ['--disable-e10s'], 16:16:41 INFO - 'tests': ['tp5o']}, 16:16:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'tests': ['tp5o']}, 16:16:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'talos_options': ['--disable-e10s', 16:16:41 INFO - '--xperf_path', 16:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:41 INFO - 'tests': ['tp5n']}, 16:16:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:41 INFO - 'talos_options': ['--xperf_path', 16:16:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:41 INFO - 'tests': ['tp5n']}}, 16:16:41 INFO - 'talos.zip': {'path': '', 16:16:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:41 INFO - Running post-action listener: _resource_record_post_action 16:16:41 INFO - [mozharness: 2016-10-12 23:16:41.344623Z] Finished populate-webroot step (success) 16:16:41 INFO - [mozharness: 2016-10-12 23:16:41.344714Z] Running create-virtualenv step. 16:16:41 INFO - Running pre-action listener: _resource_record_pre_action 16:16:41 INFO - Running main action method: create_virtualenv 16:16:41 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:41 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:16:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:41 INFO - Using real prefix '/usr' 16:16:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:41 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:43 INFO - Installing pip.................done. 16:16:43 INFO - Return code: 0 16:16:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16:43 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:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:43 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:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:43 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:43 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:16:43 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:16:43 INFO - Using env: {'DISPLAY': ':0', 16:16:43 INFO - 'HOME': '/home/cltbld', 16:16:43 INFO - 'LANG': 'en_US.UTF-8', 16:16:43 INFO - 'LANGUAGE': 'en_US:en', 16:16:43 INFO - 'LOGNAME': 'cltbld', 16:16:43 INFO - 'MAIL': '/var/mail/cltbld', 16:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:43 INFO - 'MOZ_NO_REMOTE': '1', 16:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:43 INFO - 'NO_EM_RESTART': '1', 16:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:43 INFO - 'PWD': '/builds/slave/test', 16:16:43 INFO - 'SHELL': '/bin/bash', 16:16:43 INFO - 'SHLVL': '1', 16:16:43 INFO - 'TERM': 'linux', 16:16:43 INFO - 'TMOUT': '86400', 16:16:43 INFO - 'USER': 'cltbld', 16:16:43 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:43 INFO - '_': '/tools/buildbot/bin/python'} 16:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:44 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:16:44 INFO - Cleaning up... 16:16:44 INFO - Return code: 0 16:16:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:16:44 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:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:44 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:16:44 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:16:44 INFO - Using env: {'DISPLAY': ':0', 16:16:44 INFO - 'HOME': '/home/cltbld', 16:16:44 INFO - 'LANG': 'en_US.UTF-8', 16:16:44 INFO - 'LANGUAGE': 'en_US:en', 16:16:44 INFO - 'LOGNAME': 'cltbld', 16:16:44 INFO - 'MAIL': '/var/mail/cltbld', 16:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:44 INFO - 'MOZ_NO_REMOTE': '1', 16:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:44 INFO - 'NO_EM_RESTART': '1', 16:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:44 INFO - 'PWD': '/builds/slave/test', 16:16:44 INFO - 'SHELL': '/bin/bash', 16:16:44 INFO - 'SHLVL': '1', 16:16:44 INFO - 'TERM': 'linux', 16:16:44 INFO - 'TMOUT': '86400', 16:16:44 INFO - 'USER': 'cltbld', 16:16:44 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:44 INFO - '_': '/tools/buildbot/bin/python'} 16:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:44 INFO - Downloading/unpacking psutil>=3.1.1 16:16:44 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:16:44 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:16:44 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:16:44 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:16:44 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:16:44 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:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:46 INFO - Installing collected packages: psutil 16:16:46 INFO - Running setup.py install for psutil 16:16:46 INFO - building 'psutil._psutil_linux' extension 16:16:46 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:16:46 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:16:46 INFO - building 'psutil._psutil_posix' extension 16:16:46 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:16:46 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:16:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:46 INFO - Successfully installed psutil 16:16:46 INFO - Cleaning up... 16:16:46 INFO - Return code: 0 16:16:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:16:46 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:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:46 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:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:46 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:46 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:16:46 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:16:46 INFO - Using env: {'DISPLAY': ':0', 16:16:46 INFO - 'HOME': '/home/cltbld', 16:16:46 INFO - 'LANG': 'en_US.UTF-8', 16:16:46 INFO - 'LANGUAGE': 'en_US:en', 16:16:46 INFO - 'LOGNAME': 'cltbld', 16:16:46 INFO - 'MAIL': '/var/mail/cltbld', 16:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:46 INFO - 'MOZ_NO_REMOTE': '1', 16:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:46 INFO - 'NO_EM_RESTART': '1', 16:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:46 INFO - 'PWD': '/builds/slave/test', 16:16:46 INFO - 'SHELL': '/bin/bash', 16:16:46 INFO - 'SHLVL': '1', 16:16:46 INFO - 'TERM': 'linux', 16:16:46 INFO - 'TMOUT': '86400', 16:16:46 INFO - 'USER': 'cltbld', 16:16:46 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:46 INFO - '_': '/tools/buildbot/bin/python'} 16:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:16:47 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:16:47 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:16:47 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:16:47 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:16:47 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:16:47 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:16:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:16:49 INFO - Installing collected packages: mozsystemmonitor 16:16:49 INFO - Running setup.py install for mozsystemmonitor 16:16:49 INFO - Successfully installed mozsystemmonitor 16:16:49 INFO - Cleaning up... 16:16:49 INFO - Return code: 0 16:16:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:16:49 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:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:49 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:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:49 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:49 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:16:49 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:16:49 INFO - Using env: {'DISPLAY': ':0', 16:16:49 INFO - 'HOME': '/home/cltbld', 16:16:49 INFO - 'LANG': 'en_US.UTF-8', 16:16:49 INFO - 'LANGUAGE': 'en_US:en', 16:16:49 INFO - 'LOGNAME': 'cltbld', 16:16:49 INFO - 'MAIL': '/var/mail/cltbld', 16:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:49 INFO - 'MOZ_NO_REMOTE': '1', 16:16:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:49 INFO - 'NO_EM_RESTART': '1', 16:16:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:49 INFO - 'PWD': '/builds/slave/test', 16:16:49 INFO - 'SHELL': '/bin/bash', 16:16:49 INFO - 'SHLVL': '1', 16:16:49 INFO - 'TERM': 'linux', 16:16:49 INFO - 'TMOUT': '86400', 16:16:49 INFO - 'USER': 'cltbld', 16:16:49 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:49 INFO - '_': '/tools/buildbot/bin/python'} 16:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:49 INFO - Downloading/unpacking jsonschema==2.5.1 16:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:16:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:16:51 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:16:51 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:16:51 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:16:51 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:16:51 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:16:51 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:16:51 INFO - Downloading functools32-3.2.3-2.tar.gz 16:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:16:51 INFO - warning: no files found matching '*.txt' 16:16:51 INFO - no previously-included directories found matching 'build' 16:16:51 INFO - no previously-included directories found matching 'dist' 16:16:51 INFO - no previously-included directories found matching '.git*' 16:16:51 INFO - Installing collected packages: jsonschema, functools32 16:16:51 INFO - Running setup.py install for functools32 16:16:51 INFO - warning: no files found matching '*.txt' 16:16:51 INFO - no previously-included directories found matching 'build' 16:16:51 INFO - no previously-included directories found matching 'dist' 16:16:51 INFO - no previously-included directories found matching '.git*' 16:16:51 INFO - Successfully installed jsonschema functools32 16:16:51 INFO - Cleaning up... 16:16:51 INFO - Return code: 0 16:16:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:16:51 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:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:51 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:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:51 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:51 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:16:51 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:16:51 INFO - Using env: {'DISPLAY': ':0', 16:16:51 INFO - 'HOME': '/home/cltbld', 16:16:51 INFO - 'LANG': 'en_US.UTF-8', 16:16:51 INFO - 'LANGUAGE': 'en_US:en', 16:16:51 INFO - 'LOGNAME': 'cltbld', 16:16:51 INFO - 'MAIL': '/var/mail/cltbld', 16:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:51 INFO - 'MOZ_NO_REMOTE': '1', 16:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:51 INFO - 'NO_EM_RESTART': '1', 16:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:51 INFO - 'PWD': '/builds/slave/test', 16:16:51 INFO - 'SHELL': '/bin/bash', 16:16:51 INFO - 'SHLVL': '1', 16:16:51 INFO - 'TERM': 'linux', 16:16:51 INFO - 'TMOUT': '86400', 16:16:51 INFO - 'USER': 'cltbld', 16:16:51 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:51 INFO - '_': '/tools/buildbot/bin/python'} 16:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:16:52 INFO - Cleaning up... 16:16:52 INFO - Return code: 0 16:16:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:52 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:16:52 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:16:52 INFO - Using env: {'DISPLAY': ':0', 16:16:52 INFO - 'HOME': '/home/cltbld', 16:16:52 INFO - 'LANG': 'en_US.UTF-8', 16:16:52 INFO - 'LANGUAGE': 'en_US:en', 16:16:52 INFO - 'LOGNAME': 'cltbld', 16:16:52 INFO - 'MAIL': '/var/mail/cltbld', 16:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:52 INFO - 'MOZ_NO_REMOTE': '1', 16:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:52 INFO - 'NO_EM_RESTART': '1', 16:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:52 INFO - 'PWD': '/builds/slave/test', 16:16:52 INFO - 'SHELL': '/bin/bash', 16:16:52 INFO - 'SHLVL': '1', 16:16:52 INFO - 'TERM': 'linux', 16:16:52 INFO - 'TMOUT': '86400', 16:16:52 INFO - 'USER': 'cltbld', 16:16:52 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:52 INFO - '_': '/tools/buildbot/bin/python'} 16:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:52 INFO - Downloading/unpacking blobuploader==1.2.4 16:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:52 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:16:52 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:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:54 INFO - Downloading blobuploader-1.2.4.tar.gz 16:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:16:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:16:54 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:16:54 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:16:54 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:16:54 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:16:54 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:16:54 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:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:16:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:16:54 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:16:54 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:16:54 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:16:54 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:16:54 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:16:54 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:16:55 INFO - Downloading docopt-0.6.1.tar.gz 16:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:16:55 INFO - Installing collected packages: blobuploader, requests, docopt 16:16:55 INFO - Running setup.py install for blobuploader 16:16:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:16:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:16:55 INFO - Running setup.py install for requests 16:16:55 INFO - Running setup.py install for docopt 16:16:55 INFO - Successfully installed blobuploader requests docopt 16:16:55 INFO - Cleaning up... 16:16:55 INFO - Return code: 0 16:16:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:55 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:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:55 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:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:55 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:55 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:16:55 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:16:55 INFO - Using env: {'DISPLAY': ':0', 16:16:55 INFO - 'HOME': '/home/cltbld', 16:16:55 INFO - 'LANG': 'en_US.UTF-8', 16:16:55 INFO - 'LANGUAGE': 'en_US:en', 16:16:55 INFO - 'LOGNAME': 'cltbld', 16:16:55 INFO - 'MAIL': '/var/mail/cltbld', 16:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:55 INFO - 'MOZ_NO_REMOTE': '1', 16:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:55 INFO - 'NO_EM_RESTART': '1', 16:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:55 INFO - 'PWD': '/builds/slave/test', 16:16:55 INFO - 'SHELL': '/bin/bash', 16:16:55 INFO - 'SHLVL': '1', 16:16:55 INFO - 'TERM': 'linux', 16:16:55 INFO - 'TMOUT': '86400', 16:16:55 INFO - 'USER': 'cltbld', 16:16:55 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:55 INFO - '_': '/tools/buildbot/bin/python'} 16:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:55 INFO - Running setup.py (path:/tmp/pip-H5qHWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:56 INFO - Running setup.py (path:/tmp/pip-StgieN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:56 INFO - Running setup.py (path:/tmp/pip-Aper4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:56 INFO - Running setup.py (path:/tmp/pip-SZPdfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:56 INFO - Running setup.py (path:/tmp/pip-wnn1hH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:56 INFO - Running setup.py (path:/tmp/pip-K2d_ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:56 INFO - Running setup.py (path:/tmp/pip-8fOBKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:56 INFO - Running setup.py (path:/tmp/pip-kPT2O_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:56 INFO - Running setup.py (path:/tmp/pip-wZEzxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:56 INFO - Running setup.py (path:/tmp/pip-ntgvY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:56 INFO - Running setup.py (path:/tmp/pip-V7WgY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:56 INFO - Running setup.py (path:/tmp/pip-4ZmPvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:56 INFO - Running setup.py (path:/tmp/pip-Y8kpNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:56 INFO - Running setup.py (path:/tmp/pip-j6lt7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:57 INFO - Running setup.py (path:/tmp/pip-53jk1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:57 INFO - Running setup.py (path:/tmp/pip-MxgsqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:57 INFO - Running setup.py (path:/tmp/pip-K5dnTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:16:57 INFO - Running setup.py install for manifestparser 16:16:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:16:57 INFO - Running setup.py install for mozcrash 16:16:57 INFO - Running setup.py install for mozdebug 16:16:57 INFO - Running setup.py install for mozdevice 16:16:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:16:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:16:57 INFO - Running setup.py install for mozfile 16:16:57 INFO - Running setup.py install for mozhttpd 16:16:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:16:57 INFO - Running setup.py install for mozinfo 16:16:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:16:57 INFO - Running setup.py install for mozInstall 16:16:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Running setup.py install for mozleak 16:16:58 INFO - Running setup.py install for mozlog 16:16:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Running setup.py install for moznetwork 16:16:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Running setup.py install for mozprocess 16:16:58 INFO - Running setup.py install for mozprofile 16:16:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Running setup.py install for mozrunner 16:16:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:16:58 INFO - Running setup.py install for mozscreenshot 16:16:58 INFO - Running setup.py install for moztest 16:16:58 INFO - Running setup.py install for mozversion 16:16:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:16:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:16:59 INFO - Cleaning up... 16:16:59 INFO - Return code: 0 16:16:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:59 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:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:59 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:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:59 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:16:59 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:16:59 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:16:59 INFO - Using env: {'DISPLAY': ':0', 16:16:59 INFO - 'HOME': '/home/cltbld', 16:16:59 INFO - 'LANG': 'en_US.UTF-8', 16:16:59 INFO - 'LANGUAGE': 'en_US:en', 16:16:59 INFO - 'LOGNAME': 'cltbld', 16:16:59 INFO - 'MAIL': '/var/mail/cltbld', 16:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:59 INFO - 'MOZ_NO_REMOTE': '1', 16:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:59 INFO - 'NO_EM_RESTART': '1', 16:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:59 INFO - 'PWD': '/builds/slave/test', 16:16:59 INFO - 'SHELL': '/bin/bash', 16:16:59 INFO - 'SHLVL': '1', 16:16:59 INFO - 'TERM': 'linux', 16:16:59 INFO - 'TMOUT': '86400', 16:16:59 INFO - 'USER': 'cltbld', 16:16:59 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:59 INFO - '_': '/tools/buildbot/bin/python'} 16:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:59 INFO - Running setup.py (path:/tmp/pip-7LFFA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:59 INFO - Running setup.py (path:/tmp/pip-BcYo8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:59 INFO - Running setup.py (path:/tmp/pip-mkKAPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:59 INFO - Running setup.py (path:/tmp/pip-zSgjrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:59 INFO - Running setup.py (path:/tmp/pip-fryKKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:59 INFO - Running setup.py (path:/tmp/pip-9mJ3mU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:59 INFO - Running setup.py (path:/tmp/pip-FuO2wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:59 INFO - Running setup.py (path:/tmp/pip-AMWjFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:59 INFO - Running setup.py (path:/tmp/pip-iiRrJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:59 INFO - Running setup.py (path:/tmp/pip-DnXGS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:59 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:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:59 INFO - Running setup.py (path:/tmp/pip-tYlwgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:00 INFO - Running setup.py (path:/tmp/pip-AzfFZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:00 INFO - Running setup.py (path:/tmp/pip-ak0hcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:00 INFO - Running setup.py (path:/tmp/pip-OHpkZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:00 INFO - Running setup.py (path:/tmp/pip-Q0wQmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:00 INFO - Running setup.py (path:/tmp/pip-J6NiSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:00 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:17:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:00 INFO - Running setup.py (path:/tmp/pip-rFrPsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:00 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:17:02 INFO - Downloading blessings-1.6.tar.gz 16:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:17:02 INFO - Installing collected packages: blessings 16:17:02 INFO - Running setup.py install for blessings 16:17:02 INFO - Successfully installed blessings 16:17:02 INFO - Cleaning up... 16:17:02 INFO - Return code: 0 16:17:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:02 INFO - Reading from file tmpfile_stdout 16:17:02 INFO - Current package versions: 16:17:02 INFO - argparse == 1.2.1 16:17:02 INFO - blessings == 1.6 16:17:02 INFO - blobuploader == 1.2.4 16:17:02 INFO - docopt == 0.6.1 16:17:02 INFO - functools32 == 3.2.3-2 16:17:02 INFO - jsonschema == 2.5.1 16:17:02 INFO - manifestparser == 1.1 16:17:02 INFO - mozInstall == 1.12 16:17:02 INFO - mozcrash == 0.17 16:17:02 INFO - mozdebug == 0.1 16:17:02 INFO - mozdevice == 0.48 16:17:02 INFO - mozfile == 1.2 16:17:02 INFO - mozhttpd == 0.7 16:17:02 INFO - mozinfo == 0.9 16:17:02 INFO - mozleak == 0.1 16:17:02 INFO - mozlog == 3.3 16:17:02 INFO - moznetwork == 0.27 16:17:02 INFO - mozprocess == 0.23 16:17:02 INFO - mozprofile == 0.28 16:17:02 INFO - mozrunner == 6.12 16:17:02 INFO - mozscreenshot == 0.1 16:17:02 INFO - mozsystemmonitor == 0.3 16:17:02 INFO - moztest == 0.7 16:17:02 INFO - mozversion == 1.4 16:17:02 INFO - psutil == 3.1.1 16:17:02 INFO - requests == 1.2.3 16:17:02 INFO - wsgiref == 0.1.2 16:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:02 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:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:02 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:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:02 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 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:17:02 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:17:02 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:17:02 INFO - Using env: {'DISPLAY': ':0', 16:17:02 INFO - 'HOME': '/home/cltbld', 16:17:02 INFO - 'LANG': 'en_US.UTF-8', 16:17:02 INFO - 'LANGUAGE': 'en_US:en', 16:17:02 INFO - 'LOGNAME': 'cltbld', 16:17:02 INFO - 'MAIL': '/var/mail/cltbld', 16:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:02 INFO - 'MOZ_NO_REMOTE': '1', 16:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:02 INFO - 'NO_EM_RESTART': '1', 16:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:02 INFO - 'PWD': '/builds/slave/test', 16:17:02 INFO - 'SHELL': '/bin/bash', 16:17:02 INFO - 'SHLVL': '1', 16:17:02 INFO - 'TERM': 'linux', 16:17:02 INFO - 'TMOUT': '86400', 16:17:02 INFO - 'USER': 'cltbld', 16:17:02 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:02 INFO - '_': '/tools/buildbot/bin/python'} 16:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 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:17:03 INFO - Cleaning up... 16:17:03 INFO - Return code: 0 16:17:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:17: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:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2982030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ae58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bb1400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, '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': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', '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:17: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'] in /builds/slave/test/build 16:17: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 16:17:03 INFO - Using env: {'DISPLAY': ':0', 16:17:03 INFO - 'HOME': '/home/cltbld', 16:17:03 INFO - 'LANG': 'en_US.UTF-8', 16:17:03 INFO - 'LANGUAGE': 'en_US:en', 16:17:03 INFO - 'LOGNAME': 'cltbld', 16:17:03 INFO - 'MAIL': '/var/mail/cltbld', 16:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:03 INFO - 'MOZ_NO_REMOTE': '1', 16:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:03 INFO - 'NO_EM_RESTART': '1', 16:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:03 INFO - 'PWD': '/builds/slave/test', 16:17:03 INFO - 'SHELL': '/bin/bash', 16:17:03 INFO - 'SHLVL': '1', 16:17:03 INFO - 'TERM': 'linux', 16:17:03 INFO - 'TMOUT': '86400', 16:17:03 INFO - 'USER': 'cltbld', 16:17:03 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:03 INFO - '_': '/tools/buildbot/bin/python'} 16:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:17:03 INFO - Cleaning up... 16:17:03 INFO - Return code: 0 16:17:03 INFO - Running post-action listener: _resource_record_post_action 16:17:03 INFO - Running post-action listener: _start_resource_monitoring 16:17:03 INFO - Starting resource monitoring. 16:17:03 INFO - [mozharness: 2016-10-12 23:17:03.345661Z] Finished create-virtualenv step (success) 16:17:03 INFO - [mozharness: 2016-10-12 23:17:03.346141Z] Running install step. 16:17:03 INFO - Running pre-action listener: _resource_record_pre_action 16:17:03 INFO - Running main action method: install 16:17:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:03 INFO - Reading from file tmpfile_stdout 16:17:03 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:03 INFO - Reading from file tmpfile_stdout 16:17:03 INFO - Output received: 16:17:03 INFO - Usage: mozinstall [options] installer 16:17:03 INFO - Options: 16:17:03 INFO - -h, --help show this help message and exit 16:17:03 INFO - -d DEST, --destination=DEST 16:17:03 INFO - Directory to install application into. [default: 16:17:03 INFO - "/builds/slave/test"] 16:17:03 INFO - --app=APP Application being installed. [default: firefox] 16:17:03 INFO - mkdir: /builds/slave/test/build/application 16:17:03 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:17:03 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:17:17 INFO - Reading from file tmpfile_stdout 16:17:17 INFO - Output received: 16:17:17 INFO - /builds/slave/test/build/application/firefox/firefox 16:17:17 INFO - Running post-action listener: _resource_record_post_action 16:17:17 INFO - [mozharness: 2016-10-12 23:17:17.662869Z] Finished install step (success) 16:17:17 INFO - [mozharness: 2016-10-12 23:17:17.663210Z] Running run-tests step. 16:17:17 INFO - Running pre-action listener: _resource_record_pre_action 16:17:17 INFO - Running main action method: run_tests 16:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:17:17 INFO - Python 2.7.3 16:17:17 INFO - Return code: 0 16:17:17 INFO - grabbing minidump binary from tooltool 16:17: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:17:17 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 0x2c40570>, '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 0x2c41470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c41900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:17:17 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:17:17 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:17:17 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:17:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:17:17 INFO - Return code: 0 16:17:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:17:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:17:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:17:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:17:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:17:17 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/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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:17:17 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/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --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:17:17 INFO - Using env: {'DISPLAY': ':0', 16:17:17 INFO - 'HOME': '/home/cltbld', 16:17:17 INFO - 'LANG': 'en_US.UTF-8', 16:17:17 INFO - 'LANGUAGE': 'en_US:en', 16:17:17 INFO - 'LOGNAME': 'cltbld', 16:17:17 INFO - 'MAIL': '/var/mail/cltbld', 16:17:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:17:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:17 INFO - 'MOZ_NO_REMOTE': '1', 16:17:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:17:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:17 INFO - 'NO_EM_RESTART': '1', 16:17:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:17 INFO - 'PWD': '/builds/slave/test', 16:17:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:17:17 INFO - 'SHELL': '/bin/bash', 16:17:17 INFO - 'SHLVL': '1', 16:17:17 INFO - 'TERM': 'linux', 16:17:17 INFO - 'TMOUT': '86400', 16:17:17 INFO - 'USER': 'cltbld', 16:17:17 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270', 16:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:17 INFO - '_': '/tools/buildbot/bin/python'} 16:17:17 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/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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:17:17 INFO - mozversion application_buildid: 20161012130846 16:17:17 INFO - mozversion application_changeset: 1cd295569b2bd7b40cab83c179525fd549c73051 16:17:17 INFO - mozversion application_display_name: Firefox 16:17:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:17:17 INFO - mozversion application_name: Firefox 16:17:17 INFO - mozversion application_remotingname: firefox 16:17:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:17:17 INFO - mozversion application_vendor: Mozilla 16:17:17 INFO - mozversion application_version: 50.0 16:17:17 INFO - mozversion platform_buildid: 20161012130846 16:17:17 INFO - mozversion platform_changeset: 1cd295569b2bd7b40cab83c179525fd549c73051 16:17:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:17:17 INFO - mozversion platform_version: 50.0 16:17:17 INFO - using testdate: 1476314237 16:17:17 INFO - actual date: 1476314237 16:17:17 INFO - starting webserver on 'localhost:55490' 16:17:17 INFO - SUITE-START | Running 2 tests 16:17:17 INFO - TEST-START | dromaeo_css 16:17:17 INFO - operating with platform_type : linux_ 16:17:17 INFO - Initialising browser for dromaeo_css test... 16:17:17 INFO - TEST-INFO | started process 17630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjr5R6x/profile http://localhost:55490/getInfo.html) 16:17:22 INFO - PROCESS | 17630 | __metrics Screen width/height:1600/1200 16:17:22 INFO - PROCESS | 17630 | colorDepth:24 16:17:22 INFO - PROCESS | 17630 | Browser inner width/height: 1024/697 16:17:22 INFO - PROCESS | 17630 | __metrics 16:17:23 ERROR - PROCESS | 17630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:17:23 INFO - TEST-INFO | 17630: exit 0 16:17:23 INFO - Browser initialized. 16:17:23 INFO - Running cycle 1/1 for dromaeo_css test... 16:17:23 INFO - TEST-INFO | started process 17754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjr5R6x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:35 INFO - PROCESS | 17754 | RSS: Main: 149938176 16:17:35 INFO - PROCESS | 17754 | 16:18:25 INFO - PROCESS | 17754 | 1476314305294 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:20:33 INFO - PROCESS | 17754 | Cycle 1(1): loaded http://localhost:55490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55490/tests/dromaeo/cssquery-ext.html) 16:20:34 INFO - PROCESS | 17754 | RSS: Main: 158744576 16:20:34 INFO - PROCESS | 17754 | 16:23:36 INFO - PROCESS | 17754 | Cycle 1(1): loaded http://localhost:55490/tests/dromaeo/cssquery-ext.html (next: http://localhost:55490/tests/dromaeo/cssquery-jquery.html) 16:23:37 INFO - PROCESS | 17754 | RSS: Main: 155406336 16:23:37 INFO - PROCESS | 17754 | 16:26:39 INFO - PROCESS | 17754 | Cycle 1(1): loaded http://localhost:55490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55490/tests/dromaeo/cssquery-mootools.html) 16:26:39 INFO - PROCESS | 17754 | RSS: Main: 154853376 16:26:39 INFO - PROCESS | 17754 | 16:29:41 INFO - PROCESS | 17754 | Cycle 1(1): loaded http://localhost:55490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55490/tests/dromaeo/cssquery-prototype.html) 16:29:42 INFO - PROCESS | 17754 | RSS: Main: 156545024 16:29:42 INFO - PROCESS | 17754 | 16:32:44 INFO - PROCESS | 17754 | Cycle 1(1): loaded http://localhost:55490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55490/tests/dromaeo/cssquery-yui.html) 16:32:45 INFO - PROCESS | 17754 | RSS: Main: 157335552 16:32:45 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | Cycle 1(1): loaded http://localhost:55490/tests/dromaeo/cssquery-yui.html (next: http://localhost:55490/tests/dromaeo/cssquery-dojo.html) 16:35:47 INFO - PROCESS | 17754 | RSS: Main: 157274112 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | __start_tp_report 16:35:47 INFO - PROCESS | 17754 | _x_x_mozilla_page_load 16:35:47 INFO - PROCESS | 17754 | _x_x_mozilla_page_load_details 16:35:47 INFO - PROCESS | 17754 | |i|pagename|runs| 16:35:47 INFO - PROCESS | 17754 | |0;dojo.html;3280.1307653471827;3297.7526685498588;3298.851551680174;3313.9892332838504;3331.2005118771503;5755.223044888939;5758.049921762909;5801.274840644919;5811;5811.360750317462;5793.102069179274;5794.478496935275;5859.150423188635;5861.0915308127205;5872.031114866042;5244.763985620647;5312.1766126250495;5313.787448502075;5334.066538355792;5338.786448542073;5358.4641535846495;5364.544013758835;5367.329083864517;5376.435474275204;5407.351117865842;5134.409542902558;5230.503102205304;5356.758945847446;5368.812091576796;5370.973145134269;5312.442193569677;5406.54044406226;5421.186821976716;5429.864253393657;5469.617126801126;5279.76241069149;5356.437574054746;5370.9731451342495;5388.326459192601;5400.243965844785;5639.718014099279;5694.117411801178;5859.619124756877;5906.911396329058;6026.487748541379;6090;6190.473809726179;6202.286737025254;6202.534809889231;6210.534209934228;6226.128342032119;6230;6238.9376106238815;6244.843878903019;6247.562670613059;5630.324361076656;5797.594168408255;5822.796202132948;5836.6789826559525;5865.560082993792;6336.619802811846;6462.870742585122;6477.222733272038;6478.125453063776;6479.416852483299;6143.416375444325;6171.6297022178815;6189.84525386869;6190.2571691397325;6191.473724733358;5960.284765828128;6150.04674275488;6235.781747638811;6296.024116261987;6307.873842523124;6129.2032035835055;6252.937470625327;6255.0617407388345;6265.530085243625;6267.96866015667;6257.2491301044165;6262.342454042351;6272.3100458949475;6273.811785646438;6284.780032698925;4585.72485650862;4679.485256621769;4762.880927976828;4777.235642297216;4790.353302304214;4148.191102734998;4212.599803018769;4216.325387937915;4260.169266992969;4272.9359059614135;5967.104934259895;5977.910331345033;5978.880422391528;5985.072313791369;5985.581009329305;6148.9692551536955;6172.166757487771;6238.594491358047;6326.4622507087415;6339.302676705559;5934.940650593525;5964.224650795456;5976.910346344721;5995.850103747354;6004.219451471281;5195.532402083831;5198.428172900978;5204.219367094966;5210.895782084337;5212.218167274863;6169.352218017044;6416.615003099736;6416.711247993851;6471.158749362553;6473.741050358027;6328.114064031131;6332.430081292706;6336.461400780985;6339.587926784744;6355.777547785805;6457.5479716419395;6520.250171230179;6541.476681865534;6543.378379053982;6548.050532672831;5835.824925252249;6056.939430605814;6124.663143527148;6184.412480814315;6195.256569211723;6281.96859015702;6292.402221788922;6321.020241862519;6321.52588555851;6323.557350985387;6152.876942461216;6156.753729850756;6158.476529494864;6160.630362178284;6161.229846269216;5937.228160339128;6039.63762174271;6043.12374705674;6121.602095863755;6135.355787642234;6023.096535519707;6045.244344456943;6078.3921607838865;6100.54245931548;6102;6073.908891366721;6098.054801505774;6121.479674227562;6135.785247516315;6163.96918015407;5821.76712931478;5854.941450585439;5944.019236825875;5947.9702601486715;5973.104034394875 16:35:47 INFO - PROCESS | 17754 | |1;ext.html;590.6367583935878;622.620201676977;628.7139351595025;653.1900443450123;681.5093132944278;41963.58036419635;46623;46822.76588617052;46903.53096469034;46926.53073469264;48292.517074829244;48818.75590622051;48897.26654100182;48918;48948.51051489484;9148.130927561888;9306.906930930672;9315.860262096074;9353;9367.578458969328;9264.305177111744;9337.53312334384;9348.719538413825;9354.672586459475;9371.531423428836;9381.95309023454;9406.811863762721;9415.905840941605;9416.482093484856;9418.434893906353;9345.158935695787;9348.953255233715;9366.484843333614;9368.953155234249;9402.059794020612;9250.259979201648;9303.488308143049;9307.860382094274;9311.5809788559;9344.112309330603;44602.77698611518;44611.10777784426;45056;45072.098558028985;45088.774556127006;3898.9220215595815;3992.401139829049;4081.0205550667924;4191.036061705825;4656.837010704643;35377.64622353769;35529.28941421183;35617.82191089054;35970.460443093376;36124.63875361239;4750.691205071659;4794.089123066641;4847.466778654345;4895.290182923492;4981.252812078181;274281;274712.6264368685;274930;274942;276306;64993.350066499675;65962.67018664876;65986.67006665032;66093;66110.66944665246;40470.59529404668;41365;41393.58606413897;44216.55783442189;69729.30270697227;18041.909790450965;18168.364107256446;18223.544411389823;18232.544186395447;18242.817571824377;13903;13913.791293130611;13987.860121398859;13997.65005874841;13998.30008499571;21146.365609031753;21326.573468530853;21417.78582214189;21523.78476215218;21604.675929861063;7347.485676002629;7498.925010749823;7563.470557060953;7572.712638851409;7617.085949686073;987.782687808681;1049.4437947887632;1059.7986382587283;1097.8033943002165;1141.4349896801136;34396.48405273923;34724.82637586796;34888.47667284993;34928;35000.64999349974;1121.4841173060483;1160.7446361800392;1178.7878181927165;1198.9820152697716;1203.7171264752776;18184.818151818312;18270.26918923228;18316.908415457838;18479.907600461913;18586.535336616424;674990;678474;682259;684243;686007;64523.35476645173;65535.34464655388;65890;65943;66014;1443.0980637101811;1444.8771854392285;1456.8543145685558;1463.1148155366063;1481.2445652717192;32140.910840795274;33582.66417335795;34202.31595368029;34215.31569368549;34353;58079.70960145172;58482.707586461795;58677.70661146667;58713.41286587079;58740.70629646824;506037;506232;507872;510125;511538;31448.52827207548;32208;32222.033338999867;32323.838380807945;32383.19042023874;53991.46008539864;54091.72954135204;54129;54439;54518.72740636271;45896.770516147204;47937.760311198224;47941;48409.75795121002;48818.26772598343;47005.529944701484;47689;47853.521464786296;47943;48754.512454874995;44806.55193448024;45266.547334526236;45311.77344113258;46221.07557848757;48466.27300590565;40328.59671403366;40489.59510404858;40817.79591102026;40853.79573102116;41150.382744259456;30437.239069022566;30707.692923071376;30997.225069373446;31409.214769630027;31678.524822127227 16:35:47 INFO - PROCESS | 17754 | |2;jquery.html;396.3776870313608;407.19375636240244;410.65094669530885;415.30436518830953;417.91014931789675;19054.33309834156;20763.688544671804;20905.581888362223;20931.37205883824;21462;13400.061995660308;13442.126261793;13507;15790.368385264605;20711.792882071175;5415.404305526399;5509.586780991422;5522.475364840334;5536.944630553683;5582.385937546866;5393.1370980643105;5449.509544141027;5456.836294911159;5472.616916815805;5498.725063746817;5288.444713305105;5413.64811287267;5541.085720856054;5956.374580669032;5982.850428739294;5798.101294299369;5952.613080149819;5958.285005799288;5969.074793407029;5973.581849270553;5307.469253074701;5384.973075134639;5392.460753924576;5424.484673955968;5429.348478182604;14760.040597361245;14778.630534236623;14956.102633842005;21699.589526680717;25165.37086572832;2305.4927915858652;2367.786899179082;2369.881505924714;2400.5354354131;2560.6799150106235;10341.224408169417;10492.632757853438;10882.23824332297;10998.670039898816;12727.42726577297;1977.7428934238458;2041.2141325589607;2049.11887888208;2073.7200477935435;2109.778473260301;85840;88234.5588272061;89249.55375223147;90118.09881901163;92966.5351673244;11950;11960.64118076459;12157.209781364183;12216.694582635328;12226.266424014588;17105.23026463812;17179.742303865452;17198.74201886973;17224.224909879085;17262.741058884127;8671.046184919654;8684.56577171139;8723.563821808884;8840.602172902234;8897.154770296811;7980.082290536547;7991.920080799234;8013.879791803128;8178.182181781894;8242.835143297101;5036.370453693288;5152.6908385496245;5166.767495462713;5190.870228244248;5255.211718242294;2140.186729042985;2142.6678864775986;2160.6542953127423;2237.8321625878125;2262.016023029987;839.886615306938;989.2432289298692;993.2103977338033;996.1731762636994;1034.022848408247;21046.47383815417;21243.256486022914;21281.467963301042;21370.893145534483;21383.25158619441;933.6405483888616;943.7074506902882;956.3449037409258;965.3387429610599;994.880614326271;11195.72010699739;11454;11488.804712135263;11682.299062056303;11792.351420671774;527257;543684;551498;572117;582415;65377;66079;95444.52277738706;98631;104778.47610761898;1072.131573425528;1076.978460430787;1105.7677887643524;1126.887311268883;1128.2722643894642;18351;18666.440006799818;18800.247990080243;19212.231510739974;19347.51631209231;35219;43411.56588434075;43847;44905;46252.07495850132;60346.39653603408;60965.39034609597;61428.69285653543;61873;62115;9119.772005699911;9323.533823308942;9331.626734930527;9427.905720942703;9463.574139163891;11077.83383249268;13918.4432622698;14005.089669171472;14558.27208639585;14651.12093274407;12482.563110291096;12518.186317889307;12643.87356126427;12676.809847852106;12946.287954162612;11794.46924888365;11971.580994665144;12124.21192622512;12151.878481215075;12622.87377126217;12413.875861241633;12427.31649759272;12670.809937850756;12761.425735841724;12866.67833304175;9238.353315267866;9287.48918809472;9297.814043719223;9308.44149351041;9455.574499147417;11311.151663625096;11837.348945808064;11949.342786146846;11987.400629968291;12125.090618203847 16:35:47 INFO - PROCESS | 17754 | |3;mootools.html;1542.9459968901087;1554.4947891935121;1714.7342161964891;1846.6768315544775;1869.2896699254281;8189.140140285273;9159.81680366394;9191.632334706612;9853.359531630438;9892.356996795212;9210.953945230265;9577.856332155006;9599.280053995943;9606.231501479882;9661.372010819292;2425.0784701813286;2474.505098980202;2479;2479.801615870726;2484.3664865459373;2383.094424118832;2459.938501537467;2471.789897858684;2472.4931389065287;2475.4801491686676;2423.9515209695796;2443.389152711822;2483.8758062096913;2485.8384205026705;2489.626556016594;2362.870042147681;2365.5741966446117;2366.6095094309417;2403.903843846244;2426.8786560671897;2343.4610039690797;2362.9054837806466;2370.8696021718797;2375.10933399975;2377.976220237793;9678.370905891165;9741.853872191923;9783.951080244624;9786.266030047775;9793.755156121155;5446.210299506589;5579.637323573955;5609.495145436889;5610.831675049755;5615.2700148980375;3463.445848664227;3503.8423270952867;3554.4668299755244;3586.659267369596;3587.1390866192187;1074.258761454594;1082.826747720361;1086.1256688365863;1093.4314156638504;1093.8851420600802;13696.794548081787;13785.724285514334;13796.517121900786;13808.861911380858;13815.309234538334;4425.933610995838;4601.148787474328;4677.95322046782;4712.246040633482;4735.337052812609;10593.258471906893;10676.893231067745;10684.732881677864;10717.624883129052;10742.838857417146;5508.394076651565;5528.778848846082;5531.585131115183;5537.390887002426;5560.138178582326;5039.44566097729;5186.299849520342;5223.085959956992;5224.50367215114;5268.736563171826;4000.159966407029;4063.593640635972;4105.261053010427;4130.215259100762;4169.979150104291;1200.3698058519346;1205.9819102713466;1208.8549374074992;1215.8723334049973;1226.576830993306;1009.258195226508;1017.9287449878437;1025.3847691385108;1064.0583083970741;1083.3066837224183;6470.611763294218;6543.607383557001;6683.6324002180345;6734.8989765153565;6847.417969472551;466.8599420174002;467.28505386758314;468.377058512178;472.9385179926588;473.75128057769945;4367;4509.413776209138;4609.124266389375;4687.6249900007915;4788.61691064714;11964.700882477833;12032.81950770739;12238.326892021025;12312;12329.630111096682;12007.819882701766;12245.877541224474;12246.265224086404;12251.938740306241;12256.387180640932;8903.109689031045;9048.909510904807;9068.365214435058;9074.137956894085;9117.225035871892;6509.869802603826;6570.08018877348;6592;6645.169353830771;6696.096027036482;11374;11400.657980260605;11414.657560273205;11458.255213411101;11460.82808757886;11303.208775385645;11350.772984540097;11399.829002564802;11429.314241145557;11598;8121.715739949073;8163;8327.500349978778;8596.097409772134;8661.523616200917;9571.138597526256;9589.952050239704;9590.040995900354;9597.376170548892;9637.662681806103;8469.153084691481;8548.401611887108;8624.741257762276;8735.257503112307;8787.077356877691;8424.62089205975;8496.405251632326;8528.957355213184;8538.146185381413;8591.6992905248;6984.650767461708;6985.336393042653;7018.719251229893;7024.789256322319;7030.683619237249;5461.208124821958;5684.943150568607;5718.428157184248;5807.390224026416;5872.706364681663;5582.33012038558;5661.631993920382;5731.885362292647;5858.560607954335;5889.911651325319 16:35:47 INFO - PROCESS | 17754 | |4;prototype.html;355.9889912648079;356.53472218754524;357.3531907247881;361.2377882667571;363.62546577025654;5740.770369185233;5819.534437245015;5851.180834683148;5860.736266867991;5865.384134665852;5657.915131273035;5745.683987380692;5746.908087463379;5757.395473475288;5760.510356619692;4478.843240486583;4499.865004049884;4502.189605870942;4505.5719706627815;4523.625096824161;4488.506264310923;4506.5493450655;4512.16524942885;4512.932306015412;4516.909661806778;4480.820767169309;4480.932786008196;4482.565579014717;4489.685721999462;4492.640588752908;4334.32817913224;4429.844955426561;4458.509563947996;4475.664325175625;4480.574345437194;3963.7225394222414;3964.682825373963;3965.5836137205465;3968.940465893014;3970.8014599269923;5616.1014237722175;5627.0434026215235;5628.662280263156;5633.690147041911;5642.21009058728;4984.327115839352;5142.640015198937;5164.2770012198325;5172.353455818023;5174.793008279702;5936.050231962865;5993.782300548782;6001.279846418414;6013.97762380392;6014.338422773491;4242.193983143193;4372.781360931941;4391.824327026915;4394.846180383671;4395.78021098947;6067;6260.217472815898;6295.748170073191;6312.52656050798;6323.93676063238;4260.360945858146;4270.829166833292;4277.871663850089;4283.935740963888;4284.335927931175;4326;4328.091100868853;4328.567143285709;4338.501072376717;4350.2387082260475;4886.560209581155;4978.8257410990445;5032.169692000852;5037.665018770029;5051.747412629427;6056.788012419544;6073.908891366633;6079.939200608026;6086.69566521681;6106.938930610637;1546.4200924653255;1556.6108472881779;1564.4368027510088;1566.224718764219;1567.6204939653032;940.586142097475;988.7923536057508;991.3011327014582;993.90782403042;1001.9649312274181;5129.563987061067;5134.485326828594;5163.432022477525;5233.555147812479;5239.659422137625;5467.20725494801;5471.8084867029465;5497.752601132958;5504.284443022461;5504.80733174337;5011.1481048221885;5143.3570803649545;5196.350456192976;5199.766010529535;5230.607704422184;2929.560565915087;2934.3544420227518;2938.706968933662;2943.7792165587666;2959.5560665899857;5568.888622227614;5583.916241256303;5597.972010140004;5609.719514024282;5620.44269857214;5273.734303767145;5517;5540.335159780852;5549.031268271456;5579.6652200868075;5601.8599535011135;5630.774769009276;5635.549156067505;5651;5697.9430205698245;5155.793768249228;5446.754896029767;5469.72651367438;5495.21405543208;5499.725013749377;5421.6957792937055;5421.810236641699;5434.956424402348;5437.945620543744;5455.918161227664;6212.720427580679;6527.543071985106;6534;6534.313897040743;6546.639934803631;4435.379046933496;4451.132029254264;4466.977665111654;4479.395281636937;4482.372467854569;6183.196184495987;6263.217097862767;6311.684415779101;6316.747330106744;6340.682965851781;6143.877122457615;6155.569110162246;6159.81520554384;6164.969175154096;6167.907481387693;5972.731227094704;6197.628142311476;6198.846772662237;6208.534359922934;6210.968945155245;6107.816765497042;6110.388961104032;6123.265208174869;6125.938740612715;6142.969285153546;6143.416375444325;6153.72308246139;6175.84560385976;6195.380461953949;6201.906971395523;5779.219805326227;5987.730552125077;6030.216071910623;6048.243969503812;6056.757729690763 16:35:47 INFO - PROCESS | 17754 | |5;yui.html;763.9465237433378;783.3928705253428;829.4732844643652;883.4964070479828;903.7108125399875;16966.406175783835;17581.82418175818;18019.279228830863;18030.819691803077;18035.27858885643;16240.918795406007;16422.26099825508;16425.83574164258;16436.671266574693;16474.505764827045;724.4675163754636;863.9308855291594;875.1423604867218;881.7619242804438;895.7044175422095;870.5777697816554;873.628707799186;876.7588913048899;877.5612193903048;878.6836738774036;926.7914719188196;927.1099744245532;931.12009151352;931.2224292715557;931.9627214911395;918.7335672654922;919.4758987377164;919.7470695558708;921.5392303848076;924.3298608508844;680.2007641021779;680.5236334565824;681.2063945503483;682.2290811383106;682.9897551536731;15934;15991.36034558628;15991.760123598038;16028.19859007045;16037.518874433787;13014;13145.60563183106;13323.333833308296;13452.529161479399;14639.341229644691;14292.499762508261;14348.282585870666;14391.280435978264;14547.3453694584;15046.623834404227;4719.740414277213;4749.501302363236;4756.429228492569;4825.227963525819;4848.248521479177;69840.30159698389;71882.64058679673;73845;80243;90063.54968225182;11127.499262533205;11333.150013749002;11468.827967580493;11515.30908145514;11556.711082223012;19775.208991640462;19782;19845.404637860887;19869.60260794805;19924.50188745264;8646.092160323076;8705.085965973482;8892;8982.146696063994;9444.480553569481;4569.405977222939;4737.8104875805275;4794.9280760788615;4874.5856602188505;4891.192953162688;3943.4084887266567;4111.239420707119;4115.938260926088;4118.402831589403;4151.12826306473;678.7658257901016;690.2166041542785;693.930606939302;694.4583225084442;699.4020112803581;784.9803754906173;790.6363072986491;796.9003874515686;810.1250649298743;822.3873214455269;10440.42577658236;10493.69138789465;10528.420936848393;11345.546178152947;11355.261907975955;721;722.5556282886092;737.3806002957434;743.4684200796456;763.3702195688579;4956.033573453215;5010.123228435009;5134.139406077705;5156.149235376196;5219.582433405319;136172;139396;141103;141483;142099;116948.41525792316;118463.40768296103;119355.40322298332;120769.39615301868;125360.37319813343;81779;105515;106761.4661926701;106796.46601767097;110794.44602776934;9694.660686875926;9891.653792117242;10088.546015429176;10094.747631309276;10106.444145572064;60328;60972.390276096674;61635.691821540604;62002.37997620147;62349.68825155845;82291.58854205691;86154.13845861712;90822;92170;94711;12598.370081495705;12610;12661.05042121826;12853.293068881301;12860.356982150668;13975.161490310615;14278.143311400934;14480.493182738554;14523.927380363031;14958.925205374338;13215.273159976294;13395.665108372368;13445.058845880694;13776.517821876187;13942.651433713832;12834.614961551168;13195.340232988505;13519.526816561372;13541.04787929219;14135.858641413453;12958.416871240628;12966.286854223108;13136.474541018635;13152.145110567782;13654;10056.69829905104;10562.735931601464;10682.359058456206;10849.511771970438;11071.391073490713;10564.418956957441;11015.173861960546;11053.447327633747;11329;11348.659540213806 16:35:47 INFO - PROCESS | 17754 | __end_tp_report 16:35:47 INFO - PROCESS | 17754 | __start_cc_report 16:35:47 INFO - PROCESS | 17754 | _x_x_mozilla_cycle_collect,345 16:35:47 INFO - PROCESS | 17754 | __end_cc_report 16:35:47 INFO - PROCESS | 17754 | __startTimestamp1476315347474__endTimestamp 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | ------- Summary: start ------- 16:35:47 INFO - PROCESS | 17754 | Number of tests: 6 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | [#0] dojo.html Cycles:165 Average:5797.40 Median:6023.10 stddev:676.01 (11.2%) stddev-sans-first:648.60 16:35:47 INFO - PROCESS | 17754 | Values: 3280.1 3297.8 3298.9 3314.0 3331.2 5755.2 5758.0 5801.3 5811.0 5811.4 5793.1 5794.5 5859.2 5861.1 5872.0 5244.8 5312.2 5313.8 5334.1 5338.8 5358.5 5364.5 5367.3 5376.4 5407.4 5134.4 5230.5 5356.8 5368.8 5371.0 5312.4 5406.5 5421.2 5429.9 5469.6 5279.8 5356.4 5371.0 5388.3 5400.2 5639.7 5694.1 5859.6 5906.9 6026.5 6090.0 6190.5 6202.3 6202.5 6210.5 6226.1 6230.0 6238.9 6244.8 6247.6 5630.3 5797.6 5822.8 5836.7 5865.6 6336.6 6462.9 6477.2 6478.1 6479.4 6143.4 6171.6 6189.8 6190.3 6191.5 5960.3 6150.0 6235.8 6296.0 6307.9 6129.2 6252.9 6255.1 6265.5 6268.0 6257.2 6262.3 6272.3 6273.8 6284.8 4585.7 4679.5 4762.9 4777.2 4790.4 4148.2 4212.6 4216.3 4260.2 4272.9 5967.1 5977.9 5978.9 5985.1 5985.6 6149.0 6172.2 6238.6 6326.5 6339.3 5934.9 5964.2 5976.9 5995.9 6004.2 5195.5 5198.4 5204.2 5210.9 5212.2 6169.4 6416.6 6416.7 6471.2 6473.7 6328.1 6332.4 6336.5 6339.6 6355.8 6457.5 6520.3 6541.5 6543.4 6548.1 5835.8 6056.9 6124.7 6184.4 6195.3 6282.0 6292.4 6321.0 6321.5 6323.6 6152.9 6156.8 6158.5 6160.6 6161.2 5937.2 6039.6 6043.1 6121.6 6135.4 6023.1 6045.2 6078.4 6100.5 6102.0 6073.9 6098.1 6121.5 6135.8 6164.0 5821.8 5854.9 5944.0 5948.0 5973.1 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | [#1] ext.html Cycles:180 Average:66249.41 Median:46863.15 stddev:137802.91 (294.1%) stddev-sans-first:138101.30 16:35:47 INFO - PROCESS | 17754 | Values: 590.6 622.6 628.7 653.2 681.5 41963.6 46623.0 46822.8 46903.5 46926.5 48292.5 48818.8 48897.3 48918.0 48948.5 9148.1 9306.9 9315.9 9353.0 9367.6 9264.3 9337.5 9348.7 9354.7 9371.5 9382.0 9406.8 9415.9 9416.5 9418.4 9345.2 9349.0 9366.5 9369.0 9402.1 9250.3 9303.5 9307.9 9311.6 9344.1 44602.8 44611.1 45056.0 45072.1 45088.8 3898.9 3992.4 4081.0 4191.0 4656.8 35377.6 35529.3 35617.8 35970.5 36124.6 4750.7 4794.1 4847.5 4895.3 4981.3 274281.0 274712.6 274930.0 274942.0 276306.0 64993.4 65962.7 65986.7 66093.0 66110.7 40470.6 41365.0 41393.6 44216.6 69729.3 18041.9 18168.4 18223.5 18232.5 18242.8 13903.0 13913.8 13987.9 13997.7 13998.3 21146.4 21326.6 21417.8 21523.8 21604.7 7347.5 7498.9 7563.5 7572.7 7617.1 987.8 1049.4 1059.8 1097.8 1141.4 34396.5 34724.8 34888.5 34928.0 35000.6 1121.5 1160.7 1178.8 1199.0 1203.7 18184.8 18270.3 18316.9 18479.9 18586.5 674990.0 678474.0 682259.0 684243.0 686007.0 64523.4 65535.3 65890.0 65943.0 66014.0 1443.1 1444.9 1456.9 1463.1 1481.2 32140.9 33582.7 34202.3 34215.3 34353.0 58079.7 58482.7 58677.7 58713.4 58740.7 506037.0 506232.0 507872.0 510125.0 511538.0 31448.5 32208.0 32222.0 32323.8 32383.2 53991.5 54091.7 54129.0 54439.0 54518.7 45896.8 47937.8 47941.0 48409.8 48818.3 47005.5 47689.0 47853.5 47943.0 48754.5 44806.6 45266.5 45311.8 46221.1 48466.3 40328.6 40489.6 40817.8 40853.8 41150.4 30437.2 30707.7 30997.2 31409.2 31678.5 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | [#2] jquery.html Cycles:180 Average:31185.60 Median:13863.03 stddev:91382.41 (659.2%) stddev-sans-first:91609.51 16:35:47 INFO - PROCESS | 17754 | Values: 396.4 407.2 410.7 415.3 417.9 19054.3 20763.7 20905.6 20931.4 21462.0 13400.1 13442.1 13507.0 15790.4 20711.8 5415.4 5509.6 5522.5 5536.9 5582.4 5393.1 5449.5 5456.8 5472.6 5498.7 5288.4 5413.6 5541.1 5956.4 5982.9 5798.1 5952.6 5958.3 5969.1 5973.6 5307.5 5385.0 5392.5 5424.5 5429.3 14760.0 14778.6 14956.1 21699.6 25165.4 2305.5 2367.8 2369.9 2400.5 2560.7 10341.2 10492.6 10882.2 10998.7 12727.4 1977.7 2041.2 2049.1 2073.7 2109.8 85840.0 88234.6 89249.6 90118.1 92966.5 11950.0 11960.6 12157.2 12216.7 12226.3 17105.2 17179.7 17198.7 17224.2 17262.7 8671.0 8684.6 8723.6 8840.6 8897.2 7980.1 7991.9 8013.9 8178.2 8242.8 5036.4 5152.7 5166.8 5190.9 5255.2 2140.2 2142.7 2160.7 2237.8 2262.0 839.9 989.2 993.2 996.2 1034.0 21046.5 21243.3 21281.5 21370.9 21383.3 933.6 943.7 956.3 965.3 994.9 11195.7 11454.0 11488.8 11682.3 11792.4 527257.0 543684.0 551498.0 572117.0 582415.0 65377.0 66079.0 95444.5 98631.0 104778.5 1072.1 1077.0 1105.8 1126.9 1128.3 18351.0 18666.4 18800.2 19212.2 19347.5 35219.0 43411.6 43847.0 44905.0 46252.1 60346.4 60965.4 61428.7 61873.0 62115.0 9119.8 9323.5 9331.6 9427.9 9463.6 11077.8 13918.4 14005.1 14558.3 14651.1 12482.6 12518.2 12643.9 12676.8 12946.3 11794.5 11971.6 12124.2 12151.9 12622.9 12413.9 12427.3 12670.8 12761.4 12866.7 9238.4 9287.5 9297.8 9308.4 9455.6 11311.2 11837.3 11949.3 11987.4 12125.1 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | [#3] mootools.html Cycles:180 Average:6303.01 Median:467.07 stddev:3716.67 (795.7%) stddev-sans-first:3709.88 16:35:47 INFO - PROCESS | 17754 | Values: 1542.9 1554.5 1714.7 1846.7 1869.3 8189.1 9159.8 9191.6 9853.4 9892.4 9211.0 9577.9 9599.3 9606.2 9661.4 2425.1 2474.5 2479.0 2479.8 2484.4 2383.1 2459.9 2471.8 2472.5 2475.5 2424.0 2443.4 2483.9 2485.8 2489.6 2362.9 2365.6 2366.6 2403.9 2426.9 2343.5 2362.9 2370.9 2375.1 2378.0 9678.4 9741.9 9784.0 9786.3 9793.8 5446.2 5579.6 5609.5 5610.8 5615.3 3463.4 3503.8 3554.5 3586.7 3587.1 1074.3 1082.8 1086.1 1093.4 1093.9 13696.8 13785.7 13796.5 13808.9 13815.3 4425.9 4601.1 4678.0 4712.2 4735.3 10593.3 10676.9 10684.7 10717.6 10742.8 5508.4 5528.8 5531.6 5537.4 5560.1 5039.4 5186.3 5223.1 5224.5 5268.7 4000.2 4063.6 4105.3 4130.2 4170.0 1200.4 1206.0 1208.9 1215.9 1226.6 1009.3 1017.9 1025.4 1064.1 1083.3 6470.6 6543.6 6683.6 6734.9 6847.4 466.9 467.3 468.4 472.9 473.8 4367.0 4509.4 4609.1 4687.6 4788.6 11964.7 12032.8 12238.3 12312.0 12329.6 12007.8 12245.9 12246.3 12251.9 12256.4 8903.1 9048.9 9068.4 9074.1 9117.2 6509.9 6570.1 6592.0 6645.2 6696.1 11374.0 11400.7 11414.7 11458.3 11460.8 11303.2 11350.8 11399.8 11429.3 11598.0 8121.7 8163.0 8327.5 8596.1 8661.5 9571.1 9590.0 9590.0 9597.4 9637.7 8469.2 8548.4 8624.7 8735.3 8787.1 8424.6 8496.4 8529.0 8538.1 8591.7 6984.7 6985.3 7018.7 7024.8 7030.7 5461.2 5684.9 5718.4 5807.4 5872.7 5582.3 5661.6 5731.9 5858.6 5889.9 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | [#4] prototype.html Cycles:180 Average:4932.38 Median:5470.77 stddev:1443.64 (26.4%) stddev-sans-first:1406.23 16:35:47 INFO - PROCESS | 17754 | Values: 356.0 356.5 357.4 361.2 363.6 5740.8 5819.5 5851.2 5860.7 5865.4 5657.9 5745.7 5746.9 5757.4 5760.5 4478.8 4499.9 4502.2 4505.6 4523.6 4488.5 4506.5 4512.2 4512.9 4516.9 4480.8 4480.9 4482.6 4489.7 4492.6 4334.3 4429.8 4458.5 4475.7 4480.6 3963.7 3964.7 3965.6 3968.9 3970.8 5616.1 5627.0 5628.7 5633.7 5642.2 4984.3 5142.6 5164.3 5172.4 5174.8 5936.1 5993.8 6001.3 6014.0 6014.3 4242.2 4372.8 4391.8 4394.8 4395.8 6067.0 6260.2 6295.7 6312.5 6323.9 4260.4 4270.8 4277.9 4283.9 4284.3 4326.0 4328.1 4328.6 4338.5 4350.2 4886.6 4978.8 5032.2 5037.7 5051.7 6056.8 6073.9 6079.9 6086.7 6106.9 1546.4 1556.6 1564.4 1566.2 1567.6 940.6 988.8 991.3 993.9 1002.0 5129.6 5134.5 5163.4 5233.6 5239.7 5467.2 5471.8 5497.8 5504.3 5504.8 5011.1 5143.4 5196.4 5199.8 5230.6 2929.6 2934.4 2938.7 2943.8 2959.6 5568.9 5583.9 5598.0 5609.7 5620.4 5273.7 5517.0 5540.3 5549.0 5579.7 5601.9 5630.8 5635.5 5651.0 5697.9 5155.8 5446.8 5469.7 5495.2 5499.7 5421.7 5421.8 5435.0 5437.9 5455.9 6212.7 6527.5 6534.0 6534.3 6546.6 4435.4 4451.1 4467.0 4479.4 4482.4 6183.2 6263.2 6311.7 6316.7 6340.7 6143.9 6155.6 6159.8 6165.0 6167.9 5972.7 6197.6 6198.8 6208.5 6211.0 6107.8 6110.4 6123.3 6125.9 6143.0 6143.4 6153.7 6175.8 6195.4 6201.9 5779.2 5987.7 6030.2 6048.2 6056.8 16:35:47 INFO - PROCESS | 17754 | 16:35:47 INFO - PROCESS | 17754 | [#5] yui.html Cycles:180 Average:23462.83 Median:19897.05 stddev:35804.32 (179.9%) stddev-sans-first:35864.20 16:35:47 INFO - PROCESS | 17754 | Values: 763.9 783.4 829.5 883.5 903.7 16966.4 17581.8 18019.3 18030.8 18035.3 16240.9 16422.3 16425.8 16436.7 16474.5 724.5 863.9 875.1 881.8 895.7 870.6 873.6 876.8 877.6 878.7 926.8 927.1 931.1 931.2 932.0 918.7 919.5 919.7 921.5 924.3 680.2 680.5 681.2 682.2 683.0 15934.0 15991.4 15991.8 16028.2 16037.5 13014.0 13145.6 13323.3 13452.5 14639.3 14292.5 14348.3 14391.3 14547.3 15046.6 4719.7 4749.5 4756.4 4825.2 4848.2 69840.3 71882.6 73845.0 80243.0 90063.5 11127.5 11333.2 11468.8 11515.3 11556.7 19775.2 19782.0 19845.4 19869.6 19924.5 8646.1 8705.1 8892.0 8982.1 9444.5 4569.4 4737.8 4794.9 4874.6 4891.2 3943.4 4111.2 4115.9 4118.4 4151.1 678.8 690.2 693.9 694.5 699.4 785.0 790.6 796.9 810.1 822.4 10440.4 10493.7 10528.4 11345.5 11355.3 721.0 722.6 737.4 743.5 763.4 4956.0 5010.1 5134.1 5156.1 5219.6 136172.0 139396.0 141103.0 141483.0 142099.0 116948.4 118463.4 119355.4 120769.4 125360.4 81779.0 105515.0 106761.5 106796.5 110794.4 9694.7 9891.7 10088.5 10094.7 10106.4 60328.0 60972.4 61635.7 62002.4 62349.7 82291.6 86154.1 90822.0 92170.0 94711.0 12598.4 12610.0 12661.1 12853.3 12860.4 13975.2 14278.1 14480.5 14523.9 14958.9 13215.3 13395.7 13445.1 13776.5 13942.7 12834.6 13195.3 13519.5 13541.0 14135.9 12958.4 12966.3 13136.5 13152.1 13654.0 10056.7 10562.7 10682.4 10849.5 11071.4 10564.4 11015.2 11053.4 11329.0 11348.7 16:35:47 INFO - PROCESS | 17754 | -------- Summary: end -------- 16:35:47 INFO - PROCESS | 17754 | 16:35:48 INFO - TEST-INFO | 17754: exit 0 16:35:48 INFO - TEST-OK | dromaeo_css | took 1110184ms 16:35:48 INFO - TEST-START | kraken 16:35:48 INFO - operating with platform_type : linux_ 16:35:48 INFO - Initialising browser for kraken test... 16:35:48 INFO - TEST-INFO | started process 17994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6UWA5j/profile http://localhost:55490/getInfo.html) 16:35:52 INFO - PROCESS | 17994 | __metrics Screen width/height:1600/1200 16:35:52 INFO - PROCESS | 17994 | colorDepth:24 16:35:52 INFO - PROCESS | 17994 | Browser inner width/height: 1024/697 16:35:52 INFO - PROCESS | 17994 | __metrics 16:35:53 ERROR - PROCESS | 17994 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 16:35:54 INFO - TEST-INFO | 17994: exit 0 16:35:54 INFO - Browser initialized. 16:35:54 INFO - Running cycle 1/1 for kraken test... 16:35:54 INFO - TEST-INFO | started process 18119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6UWA5j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:36:05 INFO - PROCESS | 18119 | RSS: Main: 150515712 16:36:05 INFO - PROCESS | 18119 | 16:36:08 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55490/tests/kraken/driver.html?testName=audio-beat-detection) 16:36:09 INFO - PROCESS | 18119 | RSS: Main: 156790784 16:36:09 INFO - PROCESS | 18119 | 16:36:11 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55490/tests/kraken/driver.html?testName=audio-dft) 16:36:12 INFO - PROCESS | 18119 | RSS: Main: 155684864 16:36:12 INFO - PROCESS | 18119 | 16:36:15 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55490/tests/kraken/driver.html?testName=audio-fft) 16:36:15 INFO - PROCESS | 18119 | RSS: Main: 156315648 16:36:15 INFO - PROCESS | 18119 | 16:36:17 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55490/tests/kraken/driver.html?testName=audio-oscillator) 16:36:18 INFO - PROCESS | 18119 | RSS: Main: 156315648 16:36:18 INFO - PROCESS | 18119 | 16:36:20 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:36:21 INFO - PROCESS | 18119 | RSS: Main: 156446720 16:36:21 INFO - PROCESS | 18119 | 16:36:24 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55490/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:25 INFO - PROCESS | 18119 | RSS: Main: 155992064 16:36:25 INFO - PROCESS | 18119 | 16:36:28 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55490/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:29 INFO - PROCESS | 18119 | RSS: Main: 156422144 16:36:29 INFO - PROCESS | 18119 | 16:36:31 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55490/tests/kraken/driver.html?testName=json-parse-financial) 16:36:32 INFO - PROCESS | 18119 | RSS: Main: 156106752 16:36:32 INFO - PROCESS | 18119 | 16:36:34 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:34 INFO - PROCESS | 18119 | RSS: Main: 155516928 16:36:34 INFO - PROCESS | 18119 | 16:36:36 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:36:37 INFO - PROCESS | 18119 | RSS: Main: 156364800 16:36:37 INFO - PROCESS | 18119 | 16:36:39 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:39 INFO - PROCESS | 18119 | RSS: Main: 156278784 16:36:39 INFO - PROCESS | 18119 | 16:36:42 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:42 INFO - PROCESS | 18119 | RSS: Main: 157294592 16:36:42 INFO - PROCESS | 18119 | 16:36:45 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:46 INFO - PROCESS | 18119 | RSS: Main: 156876800 16:36:46 INFO - PROCESS | 18119 | 16:36:47 INFO - PROCESS | 18119 | Cycle 1(1): loaded http://localhost:55490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55490/tests/kraken/driver.html?testName=ai-astar) 16:36:48 INFO - PROCESS | 18119 | RSS: Main: 158822400 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | __start_tp_report 16:36:48 INFO - PROCESS | 18119 | _x_x_mozilla_page_load 16:36:48 INFO - PROCESS | 18119 | _x_x_mozilla_page_load_details 16:36:48 INFO - PROCESS | 18119 | |i|pagename|runs| 16:36:48 INFO - PROCESS | 18119 | |0;ai-astar;100;111;106;111;111;112;111;112;111;112 16:36:48 INFO - PROCESS | 18119 | |1;audio-beat-detection;143;116;145;183;118;139;148;120;169;118 16:36:48 INFO - PROCESS | 18119 | |2;audio-dft;164;162;162;163;164;162;162;162;164;161 16:36:48 INFO - PROCESS | 18119 | |3;audio-fft;75;76;75;174;76;75;75;75;174;76 16:36:48 INFO - PROCESS | 18119 | |4;audio-oscillator;84;83;158;85;85;85;142;83;83;150 16:36:48 INFO - PROCESS | 18119 | |5;imaging-gaussian-blur;117;117;118;117;117;117;118;118;117;118 16:36:48 INFO - PROCESS | 18119 | |6;imaging-darkroom;147;148;147;147;147;148;148;155;148;147 16:36:48 INFO - PROCESS | 18119 | |7;imaging-desaturate;95;95;95;95;94;95;94;95;95;96 16:36:48 INFO - PROCESS | 18119 | |8;json-parse-financial;51;51;50;51;51;52;57;51;51;57 16:36:48 INFO - PROCESS | 18119 | |9;json-stringify-tinderbox;60;59;60;61;61;60;60;60;63;60 16:36:48 INFO - PROCESS | 18119 | |10;stanford-crypto-aes;73;82;72;74;75;73;73;72;74;71 16:36:48 INFO - PROCESS | 18119 | |11;stanford-crypto-ccm;139;128;120;118;121;131;119;145;163;123 16:36:48 INFO - PROCESS | 18119 | |12;stanford-crypto-pbkdf2;155;154;154;153;155;156;153;152;149;152 16:36:48 INFO - PROCESS | 18119 | |13;stanford-crypto-sha256-iterative;60;62;62;62;63;62;61;61;61;61 16:36:48 INFO - PROCESS | 18119 | __end_tp_report 16:36:48 INFO - PROCESS | 18119 | __start_cc_report 16:36:48 INFO - PROCESS | 18119 | _x_x_mozilla_cycle_collect,884 16:36:48 INFO - PROCESS | 18119 | __end_cc_report 16:36:48 INFO - PROCESS | 18119 | __startTimestamp1476315408105__endTimestamp 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | ------- Summary: start ------- 16:36:48 INFO - PROCESS | 18119 | Number of tests: 14 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.83 (3.5%) stddev-sans-first:1.86 16:36:48 INFO - PROCESS | 18119 | Values: 100.0 111.0 106.0 111.0 111.0 112.0 111.0 112.0 111.0 112.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:144.00 stddev:22.93 (15.9%) stddev-sans-first:24.30 16:36:48 INFO - PROCESS | 18119 | Values: 143.0 116.0 145.0 183.0 118.0 139.0 148.0 120.0 169.0 118.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#2] audio-dft Cycles:10 Average:162.60 Median:162.50 stddev:1.07 (0.7%) stddev-sans-first:1.01 16:36:48 INFO - PROCESS | 18119 | Values: 164.0 162.0 162.0 163.0 164.0 162.0 162.0 162.0 164.0 161.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#3] audio-fft Cycles:10 Average:95.10 Median:75.00 stddev:41.59 (55.4%) stddev-sans-first:43.47 16:36:48 INFO - PROCESS | 18119 | Values: 75.0 76.0 75.0 174.0 76.0 75.0 75.0 75.0 174.0 76.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:83.50 stddev:32.11 (38.5%) stddev-sans-first:33.25 16:36:48 INFO - PROCESS | 18119 | Values: 84.0 83.0 158.0 85.0 85.0 85.0 142.0 83.0 83.0 150.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:36:48 INFO - PROCESS | 18119 | Values: 117.0 117.0 118.0 117.0 117.0 117.0 118.0 118.0 117.0 118.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:2.44 (1.6%) stddev-sans-first:2.55 16:36:48 INFO - PROCESS | 18119 | Values: 147.0 148.0 147.0 147.0 147.0 148.0 148.0 155.0 148.0 147.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:36:48 INFO - PROCESS | 18119 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 96.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:51.00 stddev:2.57 (5.0%) stddev-sans-first:2.69 16:36:48 INFO - PROCESS | 18119 | Values: 51.0 51.0 50.0 51.0 51.0 52.0 57.0 51.0 51.0 57.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.00 stddev:1.07 (1.8%) stddev-sans-first:1.13 16:36:48 INFO - PROCESS | 18119 | Values: 60.0 59.0 60.0 61.0 61.0 60.0 60.0 60.0 63.0 60.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.50 stddev:3.07 (4.2%) stddev-sans-first:3.24 16:36:48 INFO - PROCESS | 18119 | Values: 73.0 82.0 72.0 74.0 75.0 73.0 73.0 72.0 74.0 71.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#11] stanford-crypto-ccm Cycles:10 Average:130.70 Median:129.50 stddev:14.49 (11.2%) stddev-sans-first:15.06 16:36:48 INFO - PROCESS | 18119 | Values: 139.0 128.0 120.0 118.0 121.0 131.0 119.0 145.0 163.0 123.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:154.00 stddev:2.00 (1.3%) stddev-sans-first:2.03 16:36:48 INFO - PROCESS | 18119 | Values: 155.0 154.0 154.0 153.0 155.0 156.0 153.0 152.0 149.0 152.0 16:36:48 INFO - PROCESS | 18119 | 16:36:48 INFO - PROCESS | 18119 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:0.85 (1.4%) stddev-sans-first:0.71 16:36:48 INFO - PROCESS | 18119 | Values: 60.0 62.0 62.0 62.0 63.0 62.0 61.0 61.0 61.0 61.0 16:36:48 INFO - PROCESS | 18119 | -------- Summary: end -------- 16:36:48 INFO - PROCESS | 18119 | 16:36:49 INFO - TEST-INFO | 18119: exit 0 16:36:49 INFO - TEST-OK | kraken | took 60910ms 16:36:49 INFO - SUITE-END | took 1171s 16:36:49 INFO - Completed test suite (00:19:31) 16:36:49 INFO - javascript benchmark 16:36:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7714.843468652653, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.1307653471827, 3297.7526685498588, 3298.851551680174, 3313.9892332838504, 3331.2005118771503, 5755.223044888939, 5758.049921762909, 5801.274840644919, 5811.0, 5811.360750317462, 5793.102069179274, 5794.478496935275, 5859.150423188635, 5861.0915308127205, 5872.031114866042, 5244.763985620647, 5312.1766126250495, 5313.787448502075, 5334.066538355792, 5338.786448542073, 5358.4641535846495, 5364.544013758835, 5367.329083864517, 5376.435474275204, 5407.351117865842, 5134.409542902558, 5230.503102205304, 5356.758945847446, 5368.812091576796, 5370.973145134269, 5312.442193569677, 5406.54044406226, 5421.186821976716, 5429.864253393657, 5469.617126801126, 5279.76241069149, 5356.437574054746, 5370.9731451342495, 5388.326459192601, 5400.243965844785, 5639.718014099279, 5694.117411801178, 5859.619124756877, 5906.911396329058, 6026.487748541379, 6090.0, 6190.473809726179, 6202.286737025254, 6202.534809889231, 6210.534209934228, 6226.128342032119, 6230.0, 6238.9376106238815, 6244.843878903019, 6247.562670613059, 5630.324361076656, 5797.594168408255, 5822.796202132948, 5836.6789826559525, 5865.560082993792, 6336.619802811846, 6462.870742585122, 6477.222733272038, 6478.125453063776, 6479.416852483299, 6143.416375444325, 6171.6297022178815, 6189.84525386869, 6190.2571691397325, 6191.473724733358, 5960.284765828128, 6150.04674275488, 6235.781747638811, 6296.024116261987, 6307.873842523124, 6129.2032035835055, 6252.937470625327, 6255.0617407388345, 6265.530085243625, 6267.96866015667, 6257.2491301044165, 6262.342454042351, 6272.3100458949475, 6273.811785646438, 6284.780032698925, 4585.72485650862, 4679.485256621769, 4762.880927976828, 4777.235642297216, 4790.353302304214, 4148.191102734998, 4212.599803018769, 4216.325387937915, 4260.169266992969, 4272.9359059614135, 5967.104934259895, 5977.910331345033, 5978.880422391528, 5985.072313791369, 5985.581009329305, 6148.9692551536955, 6172.166757487771, 6238.594491358047, 6326.4622507087415, 6339.302676705559, 5934.940650593525, 5964.224650795456, 5976.910346344721, 5995.850103747354, 6004.219451471281, 5195.532402083831, 5198.428172900978, 5204.219367094966, 5210.895782084337, 5212.218167274863, 6169.352218017044, 6416.615003099736, 6416.711247993851, 6471.158749362553, 6473.741050358027, 6328.114064031131, 6332.430081292706, 6336.461400780985, 6339.587926784744, 6355.777547785805, 6457.5479716419395, 6520.250171230179, 6541.476681865534, 6543.378379053982, 6548.050532672831, 5835.824925252249, 6056.939430605814, 6124.663143527148, 6184.412480814315, 6195.256569211723, 6281.96859015702, 6292.402221788922, 6321.020241862519, 6321.52588555851, 6323.557350985387, 6152.876942461216, 6156.753729850756, 6158.476529494864, 6160.630362178284, 6161.229846269216, 5937.228160339128, 6039.63762174271, 6043.12374705674, 6121.602095863755, 6135.355787642234, 6023.096535519707, 6045.244344456943, 6078.3921607838865, 6100.54245931548, 6102.0, 6073.908891366721, 6098.054801505774, 6121.479674227562, 6135.785247516315, 6163.96918015407, 5821.76712931478, 5854.941450585439, 5944.019236825875, 5947.9702601486715, 5973.104034394875], "value": 5750.167690419323, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.6367583935878, 622.620201676977, 628.7139351595025, 653.1900443450123, 681.5093132944278, 41963.58036419635, 46623.0, 46822.76588617052, 46903.53096469034, 46926.53073469264, 48292.517074829244, 48818.75590622051, 48897.26654100182, 48918.0, 48948.51051489484, 9148.130927561888, 9306.906930930672, 9315.860262096074, 9353.0, 9367.578458969328, 9264.305177111744, 9337.53312334384, 9348.719538413825, 9354.672586459475, 9371.531423428836, 9381.95309023454, 9406.811863762721, 9415.905840941605, 9416.482093484856, 9418.434893906353, 9345.158935695787, 9348.953255233715, 9366.484843333614, 9368.953155234249, 9402.059794020612, 9250.259979201648, 9303.488308143049, 9307.860382094274, 9311.5809788559, 9344.112309330603, 44602.77698611518, 44611.10777784426, 45056.0, 45072.098558028985, 45088.774556127006, 3898.9220215595815, 3992.401139829049, 4081.0205550667924, 4191.036061705825, 4656.837010704643, 35377.64622353769, 35529.28941421183, 35617.82191089054, 35970.460443093376, 36124.63875361239, 4750.691205071659, 4794.089123066641, 4847.466778654345, 4895.290182923492, 4981.252812078181, 274281.0, 274712.6264368685, 274930.0, 274942.0, 276306.0, 64993.350066499675, 65962.67018664876, 65986.67006665032, 66093.0, 66110.66944665246, 40470.59529404668, 41365.0, 41393.58606413897, 44216.55783442189, 69729.30270697227, 18041.909790450965, 18168.364107256446, 18223.544411389823, 18232.544186395447, 18242.817571824377, 13903.0, 13913.791293130611, 13987.860121398859, 13997.65005874841, 13998.30008499571, 21146.365609031753, 21326.573468530853, 21417.78582214189, 21523.78476215218, 21604.675929861063, 7347.485676002629, 7498.925010749823, 7563.470557060953, 7572.712638851409, 7617.085949686073, 987.782687808681, 1049.4437947887632, 1059.7986382587283, 1097.8033943002165, 1141.4349896801136, 34396.48405273923, 34724.82637586796, 34888.47667284993, 34928.0, 35000.64999349974, 1121.4841173060483, 1160.7446361800392, 1178.7878181927165, 1198.9820152697716, 1203.7171264752776, 18184.818151818312, 18270.26918923228, 18316.908415457838, 18479.907600461913, 18586.535336616424, 674990.0, 678474.0, 682259.0, 684243.0, 686007.0, 64523.35476645173, 65535.34464655388, 65890.0, 65943.0, 66014.0, 1443.0980637101811, 1444.8771854392285, 1456.8543145685558, 1463.1148155366063, 1481.2445652717192, 32140.910840795274, 33582.66417335795, 34202.31595368029, 34215.31569368549, 34353.0, 58079.70960145172, 58482.707586461795, 58677.70661146667, 58713.41286587079, 58740.70629646824, 506037.0, 506232.0, 507872.0, 510125.0, 511538.0, 31448.52827207548, 32208.0, 32222.033338999867, 32323.838380807945, 32383.19042023874, 53991.46008539864, 54091.72954135204, 54129.0, 54439.0, 54518.72740636271, 45896.770516147204, 47937.760311198224, 47941.0, 48409.75795121002, 48818.26772598343, 47005.529944701484, 47689.0, 47853.521464786296, 47943.0, 48754.512454874995, 44806.55193448024, 45266.547334526236, 45311.77344113258, 46221.07557848757, 48466.27300590565, 40328.59671403366, 40489.59510404858, 40817.79591102026, 40853.79573102116, 41150.382744259456, 30437.239069022566, 30707.692923071376, 30997.225069373446, 31409.214769630027, 31678.524822127227], "value": 21745.651056880786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.3776870313608, 407.19375636240244, 410.65094669530885, 415.30436518830953, 417.91014931789675, 19054.33309834156, 20763.688544671804, 20905.581888362223, 20931.37205883824, 21462.0, 13400.061995660308, 13442.126261793, 13507.0, 15790.368385264605, 20711.792882071175, 5415.404305526399, 5509.586780991422, 5522.475364840334, 5536.944630553683, 5582.385937546866, 5393.1370980643105, 5449.509544141027, 5456.836294911159, 5472.616916815805, 5498.725063746817, 5288.444713305105, 5413.64811287267, 5541.085720856054, 5956.374580669032, 5982.850428739294, 5798.101294299369, 5952.613080149819, 5958.285005799288, 5969.074793407029, 5973.581849270553, 5307.469253074701, 5384.973075134639, 5392.460753924576, 5424.484673955968, 5429.348478182604, 14760.040597361245, 14778.630534236623, 14956.102633842005, 21699.589526680717, 25165.37086572832, 2305.4927915858652, 2367.786899179082, 2369.881505924714, 2400.5354354131, 2560.6799150106235, 10341.224408169417, 10492.632757853438, 10882.23824332297, 10998.670039898816, 12727.42726577297, 1977.7428934238458, 2041.2141325589607, 2049.11887888208, 2073.7200477935435, 2109.778473260301, 85840.0, 88234.5588272061, 89249.55375223147, 90118.09881901163, 92966.5351673244, 11950.0, 11960.64118076459, 12157.209781364183, 12216.694582635328, 12226.266424014588, 17105.23026463812, 17179.742303865452, 17198.74201886973, 17224.224909879085, 17262.741058884127, 8671.046184919654, 8684.56577171139, 8723.563821808884, 8840.602172902234, 8897.154770296811, 7980.082290536547, 7991.920080799234, 8013.879791803128, 8178.182181781894, 8242.835143297101, 5036.370453693288, 5152.6908385496245, 5166.767495462713, 5190.870228244248, 5255.211718242294, 2140.186729042985, 2142.6678864775986, 2160.6542953127423, 2237.8321625878125, 2262.016023029987, 839.886615306938, 989.2432289298692, 993.2103977338033, 996.1731762636994, 1034.022848408247, 21046.47383815417, 21243.256486022914, 21281.467963301042, 21370.893145534483, 21383.25158619441, 933.6405483888616, 943.7074506902882, 956.3449037409258, 965.3387429610599, 994.880614326271, 11195.72010699739, 11454.0, 11488.804712135263, 11682.299062056303, 11792.351420671774, 527257.0, 543684.0, 551498.0, 572117.0, 582415.0, 65377.0, 66079.0, 95444.52277738706, 98631.0, 104778.47610761898, 1072.131573425528, 1076.978460430787, 1105.7677887643524, 1126.887311268883, 1128.2722643894642, 18351.0, 18666.440006799818, 18800.247990080243, 19212.231510739974, 19347.51631209231, 35219.0, 43411.56588434075, 43847.0, 44905.0, 46252.07495850132, 60346.39653603408, 60965.39034609597, 61428.69285653543, 61873.0, 62115.0, 9119.772005699911, 9323.533823308942, 9331.626734930527, 9427.905720942703, 9463.574139163891, 11077.83383249268, 13918.4432622698, 14005.089669171472, 14558.27208639585, 14651.12093274407, 12482.563110291096, 12518.186317889307, 12643.87356126427, 12676.809847852106, 12946.287954162612, 11794.46924888365, 11971.580994665144, 12124.21192622512, 12151.878481215075, 12622.87377126217, 12413.875861241633, 12427.31649759272, 12670.809937850756, 12761.425735841724, 12866.67833304175, 9238.353315267866, 9287.48918809472, 9297.814043719223, 9308.44149351041, 9455.574499147417, 11311.151663625096, 11837.348945808064, 11949.342786146846, 11987.400629968291, 12125.090618203847], "value": 9519.007266532357, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1542.9459968901087, 1554.4947891935121, 1714.7342161964891, 1846.6768315544775, 1869.2896699254281, 8189.140140285273, 9159.81680366394, 9191.632334706612, 9853.359531630438, 9892.356996795212, 9210.953945230265, 9577.856332155006, 9599.280053995943, 9606.231501479882, 9661.372010819292, 2425.0784701813286, 2474.505098980202, 2479.0, 2479.801615870726, 2484.3664865459373, 2383.094424118832, 2459.938501537467, 2471.789897858684, 2472.4931389065287, 2475.4801491686676, 2423.9515209695796, 2443.389152711822, 2483.8758062096913, 2485.8384205026705, 2489.626556016594, 2362.870042147681, 2365.5741966446117, 2366.6095094309417, 2403.903843846244, 2426.8786560671897, 2343.4610039690797, 2362.9054837806466, 2370.8696021718797, 2375.10933399975, 2377.976220237793, 9678.370905891165, 9741.853872191923, 9783.951080244624, 9786.266030047775, 9793.755156121155, 5446.210299506589, 5579.637323573955, 5609.495145436889, 5610.831675049755, 5615.2700148980375, 3463.445848664227, 3503.8423270952867, 3554.4668299755244, 3586.659267369596, 3587.1390866192187, 1074.258761454594, 1082.826747720361, 1086.1256688365863, 1093.4314156638504, 1093.8851420600802, 13696.794548081787, 13785.724285514334, 13796.517121900786, 13808.861911380858, 13815.309234538334, 4425.933610995838, 4601.148787474328, 4677.95322046782, 4712.246040633482, 4735.337052812609, 10593.258471906893, 10676.893231067745, 10684.732881677864, 10717.624883129052, 10742.838857417146, 5508.394076651565, 5528.778848846082, 5531.585131115183, 5537.390887002426, 5560.138178582326, 5039.44566097729, 5186.299849520342, 5223.085959956992, 5224.50367215114, 5268.736563171826, 4000.159966407029, 4063.593640635972, 4105.261053010427, 4130.215259100762, 4169.979150104291, 1200.3698058519346, 1205.9819102713466, 1208.8549374074992, 1215.8723334049973, 1226.576830993306, 1009.258195226508, 1017.9287449878437, 1025.3847691385108, 1064.0583083970741, 1083.3066837224183, 6470.611763294218, 6543.607383557001, 6683.6324002180345, 6734.8989765153565, 6847.417969472551, 466.8599420174002, 467.28505386758314, 468.377058512178, 472.9385179926588, 473.75128057769945, 4367.0, 4509.413776209138, 4609.124266389375, 4687.6249900007915, 4788.61691064714, 11964.700882477833, 12032.81950770739, 12238.326892021025, 12312.0, 12329.630111096682, 12007.819882701766, 12245.877541224474, 12246.265224086404, 12251.938740306241, 12256.387180640932, 8903.109689031045, 9048.909510904807, 9068.365214435058, 9074.137956894085, 9117.225035871892, 6509.869802603826, 6570.08018877348, 6592.0, 6645.169353830771, 6696.096027036482, 11374.0, 11400.657980260605, 11414.657560273205, 11458.255213411101, 11460.82808757886, 11303.208775385645, 11350.772984540097, 11399.829002564802, 11429.314241145557, 11598.0, 8121.715739949073, 8163.0, 8327.500349978778, 8596.097409772134, 8661.523616200917, 9571.138597526256, 9589.952050239704, 9590.040995900354, 9597.376170548892, 9637.662681806103, 8469.153084691481, 8548.401611887108, 8624.741257762276, 8735.257503112307, 8787.077356877691, 8424.62089205975, 8496.405251632326, 8528.957355213184, 8538.146185381413, 8591.6992905248, 6984.650767461708, 6985.336393042653, 7018.719251229893, 7024.789256322319, 7030.683619237249, 5461.208124821958, 5684.943150568607, 5718.428157184248, 5807.390224026416, 5872.706364681663, 5582.33012038558, 5661.631993920382, 5731.885362292647, 5858.560607954335, 5889.911651325319], "value": 4879.704831400756, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9889912648079, 356.53472218754524, 357.3531907247881, 361.2377882667571, 363.62546577025654, 5740.770369185233, 5819.534437245015, 5851.180834683148, 5860.736266867991, 5865.384134665852, 5657.915131273035, 5745.683987380692, 5746.908087463379, 5757.395473475288, 5760.510356619692, 4478.843240486583, 4499.865004049884, 4502.189605870942, 4505.5719706627815, 4523.625096824161, 4488.506264310923, 4506.5493450655, 4512.16524942885, 4512.932306015412, 4516.909661806778, 4480.820767169309, 4480.932786008196, 4482.565579014717, 4489.685721999462, 4492.640588752908, 4334.32817913224, 4429.844955426561, 4458.509563947996, 4475.664325175625, 4480.574345437194, 3963.7225394222414, 3964.682825373963, 3965.5836137205465, 3968.940465893014, 3970.8014599269923, 5616.1014237722175, 5627.0434026215235, 5628.662280263156, 5633.690147041911, 5642.21009058728, 4984.327115839352, 5142.640015198937, 5164.2770012198325, 5172.353455818023, 5174.793008279702, 5936.050231962865, 5993.782300548782, 6001.279846418414, 6013.97762380392, 6014.338422773491, 4242.193983143193, 4372.781360931941, 4391.824327026915, 4394.846180383671, 4395.78021098947, 6067.0, 6260.217472815898, 6295.748170073191, 6312.52656050798, 6323.93676063238, 4260.360945858146, 4270.829166833292, 4277.871663850089, 4283.935740963888, 4284.335927931175, 4326.0, 4328.091100868853, 4328.567143285709, 4338.501072376717, 4350.2387082260475, 4886.560209581155, 4978.8257410990445, 5032.169692000852, 5037.665018770029, 5051.747412629427, 6056.788012419544, 6073.908891366633, 6079.939200608026, 6086.69566521681, 6106.938930610637, 1546.4200924653255, 1556.6108472881779, 1564.4368027510088, 1566.224718764219, 1567.6204939653032, 940.586142097475, 988.7923536057508, 991.3011327014582, 993.90782403042, 1001.9649312274181, 5129.563987061067, 5134.485326828594, 5163.432022477525, 5233.555147812479, 5239.659422137625, 5467.20725494801, 5471.8084867029465, 5497.752601132958, 5504.284443022461, 5504.80733174337, 5011.1481048221885, 5143.3570803649545, 5196.350456192976, 5199.766010529535, 5230.607704422184, 2929.560565915087, 2934.3544420227518, 2938.706968933662, 2943.7792165587666, 2959.5560665899857, 5568.888622227614, 5583.916241256303, 5597.972010140004, 5609.719514024282, 5620.44269857214, 5273.734303767145, 5517.0, 5540.335159780852, 5549.031268271456, 5579.6652200868075, 5601.8599535011135, 5630.774769009276, 5635.549156067505, 5651.0, 5697.9430205698245, 5155.793768249228, 5446.754896029767, 5469.72651367438, 5495.21405543208, 5499.725013749377, 5421.6957792937055, 5421.810236641699, 5434.956424402348, 5437.945620543744, 5455.918161227664, 6212.720427580679, 6527.543071985106, 6534.0, 6534.313897040743, 6546.639934803631, 4435.379046933496, 4451.132029254264, 4466.977665111654, 4479.395281636937, 4482.372467854569, 6183.196184495987, 6263.217097862767, 6311.684415779101, 6316.747330106744, 6340.682965851781, 6143.877122457615, 6155.569110162246, 6159.81520554384, 6164.969175154096, 6167.907481387693, 5972.731227094704, 6197.628142311476, 6198.846772662237, 6208.534359922934, 6210.968945155245, 6107.816765497042, 6110.388961104032, 6123.265208174869, 6125.938740612715, 6142.969285153546, 6143.416375444325, 6153.72308246139, 6175.84560385976, 6195.380461953949, 6201.906971395523, 5779.219805326227, 5987.730552125077, 6030.216071910623, 6048.243969503812, 6056.757729690763], "value": 4475.122331415491, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.9465237433378, 783.3928705253428, 829.4732844643652, 883.4964070479828, 903.7108125399875, 16966.406175783835, 17581.82418175818, 18019.279228830863, 18030.819691803077, 18035.27858885643, 16240.918795406007, 16422.26099825508, 16425.83574164258, 16436.671266574693, 16474.505764827045, 724.4675163754636, 863.9308855291594, 875.1423604867218, 881.7619242804438, 895.7044175422095, 870.5777697816554, 873.628707799186, 876.7588913048899, 877.5612193903048, 878.6836738774036, 926.7914719188196, 927.1099744245532, 931.12009151352, 931.2224292715557, 931.9627214911395, 918.7335672654922, 919.4758987377164, 919.7470695558708, 921.5392303848076, 924.3298608508844, 680.2007641021779, 680.5236334565824, 681.2063945503483, 682.2290811383106, 682.9897551536731, 15934.0, 15991.36034558628, 15991.760123598038, 16028.19859007045, 16037.518874433787, 13014.0, 13145.60563183106, 13323.333833308296, 13452.529161479399, 14639.341229644691, 14292.499762508261, 14348.282585870666, 14391.280435978264, 14547.3453694584, 15046.623834404227, 4719.740414277213, 4749.501302363236, 4756.429228492569, 4825.227963525819, 4848.248521479177, 69840.30159698389, 71882.64058679673, 73845.0, 80243.0, 90063.54968225182, 11127.499262533205, 11333.150013749002, 11468.827967580493, 11515.30908145514, 11556.711082223012, 19775.208991640462, 19782.0, 19845.404637860887, 19869.60260794805, 19924.50188745264, 8646.092160323076, 8705.085965973482, 8892.0, 8982.146696063994, 9444.480553569481, 4569.405977222939, 4737.8104875805275, 4794.9280760788615, 4874.5856602188505, 4891.192953162688, 3943.4084887266567, 4111.239420707119, 4115.938260926088, 4118.402831589403, 4151.12826306473, 678.7658257901016, 690.2166041542785, 693.930606939302, 694.4583225084442, 699.4020112803581, 784.9803754906173, 790.6363072986491, 796.9003874515686, 810.1250649298743, 822.3873214455269, 10440.42577658236, 10493.69138789465, 10528.420936848393, 11345.546178152947, 11355.261907975955, 721.0, 722.5556282886092, 737.3806002957434, 743.4684200796456, 763.3702195688579, 4956.033573453215, 5010.123228435009, 5134.139406077705, 5156.149235376196, 5219.582433405319, 136172.0, 139396.0, 141103.0, 141483.0, 142099.0, 116948.41525792316, 118463.40768296103, 119355.40322298332, 120769.39615301868, 125360.37319813343, 81779.0, 105515.0, 106761.4661926701, 106796.46601767097, 110794.44602776934, 9694.660686875926, 9891.653792117242, 10088.546015429176, 10094.747631309276, 10106.444145572064, 60328.0, 60972.390276096674, 61635.691821540604, 62002.37997620147, 62349.68825155845, 82291.58854205691, 86154.13845861712, 90822.0, 92170.0, 94711.0, 12598.370081495705, 12610.0, 12661.05042121826, 12853.293068881301, 12860.356982150668, 13975.161490310615, 14278.143311400934, 14480.493182738554, 14523.927380363031, 14958.925205374338, 13215.273159976294, 13395.665108372368, 13445.058845880694, 13776.517821876187, 13942.651433713832, 12834.614961551168, 13195.340232988505, 13519.526816561372, 13541.04787929219, 14135.858641413453, 12958.416871240628, 12966.286854223108, 13136.474541018635, 13152.145110567782, 13654.0, 10056.69829905104, 10562.735931601464, 10682.359058456206, 10849.511771970438, 11071.391073490713, 10564.418956957441, 11015.173861960546, 11053.447327633747, 11329.0, 11348.659540213806], "value": 8111.043364929046, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1503.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 106.0, 111.0, 111.0, 112.0, 111.0, 112.0, 111.0, 112.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 145.0, 183.0, 118.0, 139.0, 148.0, 120.0, 169.0, 118.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 163.0, 164.0, 162.0, 162.0, 162.0, 164.0, 161.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 174.0, 76.0, 75.0, 75.0, 75.0, 174.0, 76.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 158.0, 85.0, 85.0, 85.0, 142.0, 83.0, 83.0, 150.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 147.0, 148.0, 148.0, 155.0, 148.0, 147.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 96.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 51.0, 51.0, 52.0, 57.0, 51.0, 51.0, 57.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 63.0, 60.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 72.0, 74.0, 75.0, 73.0, 73.0, 72.0, 74.0, 71.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 128.0, 120.0, 118.0, 121.0, 131.0, 119.0, 145.0, 163.0, 123.0], "value": 130.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 154.0, 153.0, 155.0, 156.0, 153.0, 152.0, 149.0, 152.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 62.0, 62.0, 63.0, 62.0, 61.0, 61.0, 61.0, 61.0], "value": 61.5, "unit": "score"}]}]} 16:36:49 INFO - Return code: 0 16:36:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:36:49 ERROR - # TBPL SUCCESS # 16:36:49 INFO - Running post-action listener: _resource_record_post_action 16:36:49 INFO - [mozharness: 2016-10-12 23:36:49.404812Z] Finished run-tests step (success) 16:36:49 INFO - Running post-run listener: _resource_record_post_run 16:36:49 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1871872; Write bytes: 288927744; Read time: 552; Write time: 150652 16:36:49 INFO - TinderboxPrint: CPU usage
13.2% 16:36:49 INFO - TinderboxPrint: I/O read bytes / time
1,871,872 / 552 16:36:49 INFO - TinderboxPrint: I/O write bytes / time
288,927,744 / 150,652 16:36:49 INFO - TinderboxPrint: CPU idle
8,212.2 (86.8%) 16:36:49 INFO - TinderboxPrint: CPU user
1,196.9 (12.6%) 16:36:49 INFO - TinderboxPrint: Swap in / out
0 / 0 16:36:49 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70107136; Read time: 0; Write time: 32884 16:36:49 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218820608; Read time: 128; Write time: 117768 16:36:50 INFO - Running post-run listener: _upload_blobber_files 16:36:50 INFO - Blob upload gear active. 16:36:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:50 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:36:50 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:36:50 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:36:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:36:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:36:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:51 INFO - (blobuploader) - INFO - Done attempting. 16:36:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:36:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:36:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:52 INFO - (blobuploader) - INFO - Done attempting. 16:36:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:36:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:36:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:55 INFO - (blobuploader) - INFO - Done attempting. 16:36:55 INFO - (blobuploader) - INFO - Iteration through files over. 16:36:55 INFO - Return code: 0 16:36:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:36:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:36:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a33d5b8b37f4709c55a234232856d9bb70496211d8e174f94e82531956f20645d3950d46d2739cbc7da0512ebdb258b27319cc011880b8dbfb9db9ee10a2e8e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b341b6f74adde6101aaf42cb48793378ff28ee78839baf5c1351e0f3893e32c2fcd62e59cb59438983f1875d7931ad2f9ce14d73eb18bfed74ad7bc1a67706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6200757181bc94ceb3a95b35a3245959f67222b745a2d02ba99b35f594b881909fc6c6a2620dd6ee31982ebf6301d98cc43e161dfb207b2f854918a4d7b908d9"} 16:36:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:36:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:36:55 INFO - Contents: 16:36:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a33d5b8b37f4709c55a234232856d9bb70496211d8e174f94e82531956f20645d3950d46d2739cbc7da0512ebdb258b27319cc011880b8dbfb9db9ee10a2e8e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b341b6f74adde6101aaf42cb48793378ff28ee78839baf5c1351e0f3893e32c2fcd62e59cb59438983f1875d7931ad2f9ce14d73eb18bfed74ad7bc1a67706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6200757181bc94ceb3a95b35a3245959f67222b745a2d02ba99b35f594b881909fc6c6a2620dd6ee31982ebf6301d98cc43e161dfb207b2f854918a4d7b908d9"} 16:36:55 INFO - Running post-run listener: copy_logs_to_upload_dir 16:36:55 INFO - Copying logs to upload dir... 16:36:55 INFO - mkdir: /builds/slave/test/build/upload/logs 16:36:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.279828 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-12 16:36:55.419586) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 16:36:55.423346) ========= 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=db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270 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/a33d5b8b37f4709c55a234232856d9bb70496211d8e174f94e82531956f20645d3950d46d2739cbc7da0512ebdb258b27319cc011880b8dbfb9db9ee10a2e8e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b341b6f74adde6101aaf42cb48793378ff28ee78839baf5c1351e0f3893e32c2fcd62e59cb59438983f1875d7931ad2f9ce14d73eb18bfed74ad7bc1a67706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6200757181bc94ceb3a95b35a3245959f67222b745a2d02ba99b35f594b881909fc6c6a2620dd6ee31982ebf6301d98cc43e161dfb207b2f854918a4d7b908d9"} build_url:https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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/a33d5b8b37f4709c55a234232856d9bb70496211d8e174f94e82531956f20645d3950d46d2739cbc7da0512ebdb258b27319cc011880b8dbfb9db9ee10a2e8e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82b341b6f74adde6101aaf42cb48793378ff28ee78839baf5c1351e0f3893e32c2fcd62e59cb59438983f1875d7931ad2f9ce14d73eb18bfed74ad7bc1a67706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6200757181bc94ceb3a95b35a3245959f67222b745a2d02ba99b35f594b881909fc6c6a2620dd6ee31982ebf6301d98cc43e161dfb207b2f854918a4d7b908d9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 16:36:55.448991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:36:55.449388) ========= 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=db7e7c15f3ca076d53731445000001ba-1476313740.848731-1780228270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004583 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:36:55.531951) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 16:36:55.532346) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 16:36:55.532718) ========= ========= Total master_lag: 0.18 =========