builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1472737945.54 results: success (0) buildid: 20160830104522 builduid: 5655a65e53c8465d859cdd3cc9117707 revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.540132) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.540638) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.540912) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.077039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.077327) ========= 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=62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-09-01 06:52:26 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126491 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.232130) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.232435) ========= 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=62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019752 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.376998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.377295) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bac8c738419eb9ff1fe0947120e879707d19c97b --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-aurora --rev bac8c738419eb9ff1fe0947120e879707d19c97b --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=62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:26,953 truncating revision to first 12 chars 2016-09-01 06:52:26,953 Setting DEBUG logging. 2016-09-01 06:52:26,953 attempt 1/10 2016-09-01 06:52:26,953 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bac8c738419e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:27,351 unpacking tar archive at: mozilla-aurora-bac8c738419e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.736306 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.159611) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.159885) ========= script_repo_revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.160252) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.160508) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.172807) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-01 06:52:27.173063) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:27 INFO - MultiFileLogger online at 20160901 06:52:27 in /builds/slave/test 06:52:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:52:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:27 INFO - {'append_to_log': False, 06:52:27 INFO - 'base_work_dir': '/builds/slave/test', 06:52:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:27 INFO - 'branch': 'Mozilla-Aurora', 06:52:27 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:27 INFO - 'config_files': ('talos/linux_config.py',), 06:52:27 INFO - 'default_actions': ('clobber', 06:52:27 INFO - 'read-buildbot-config', 06:52:27 INFO - 'download-and-extract', 06:52:27 INFO - 'populate-webroot', 06:52:27 INFO - 'create-virtualenv', 06:52:27 INFO - 'install', 06:52:27 INFO - 'run-tests'), 06:52:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:27 INFO - 'download_minidump_stackwalk': True, 06:52:27 INFO - 'download_symbols': 'ondemand', 06:52:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:27 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:27 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:27 INFO - 'installer_path': 'installer.exe', 06:52:27 INFO - 'log_level': 'info', 06:52:27 INFO - 'log_name': 'talos', 06:52:27 INFO - 'log_to_console': True, 06:52:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:27 INFO - 'opt_config_files': (), 06:52:27 INFO - 'pip_index': False, 06:52:27 INFO - 'sps_profile': False, 06:52:27 INFO - 'sps_profile_interval': 0, 06:52:27 INFO - 'suite': 'dromaeojs', 06:52:27 INFO - 'system_bits': '32', 06:52:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:27 INFO - 'title': 'talos-linux64-ix-018', 06:52:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:27 INFO - 'use_talos_json': True, 06:52:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:27 INFO - 'work_dir': 'build'} 06:52:27 INFO - [mozharness: 2016-09-01 13:52:27.810716Z] Running clobber step. 06:52:27 INFO - Running pre-action listener: _resource_record_pre_action 06:52:27 INFO - Running main action method: clobber 06:52:27 INFO - rmtree: /builds/slave/test/build 06:52:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:28 INFO - Running post-action listener: _resource_record_post_action 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.269870Z] Finished clobber step (success) 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.269969Z] Running read-buildbot-config step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: read_buildbot_config 06:52:28 INFO - Using buildbot properties: 06:52:28 INFO - { 06:52:28 INFO - "project": "", 06:52:28 INFO - "product": "firefox", 06:52:28 INFO - "script_repo_revision": "production", 06:52:28 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:52:28 INFO - "repository": "", 06:52:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:52:28 INFO - "buildid": "20160830104522", 06:52:28 INFO - "pgo_build": "True", 06:52:28 INFO - "basedir": "/builds/slave/test", 06:52:28 INFO - "buildnumber": 722, 06:52:28 INFO - "slavename": "talos-linux64-ix-018", 06:52:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:28 INFO - "platform": "ubuntu64_hw", 06:52:28 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:28 INFO - "branch": "mozilla-aurora", 06:52:28 INFO - "repo_path": "releases/mozilla-aurora", 06:52:28 INFO - "revision": "bac8c738419eb9ff1fe0947120e879707d19c97b", 06:52:28 INFO - "stage_platform": "linux64-pgo", 06:52:28 INFO - "builduid": "5655a65e53c8465d859cdd3cc9117707", 06:52:28 INFO - "slavebuilddir": "test" 06:52:28 INFO - } 06:52:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:28 INFO - Running post-action listener: _resource_record_post_action 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.272382Z] Finished read-buildbot-config step (success) 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.272470Z] Running download-and-extract step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: download_and_extract 06:52:28 INFO - mkdir: /builds/slave/test/build/tests 06:52:28 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')]} 06:52:28 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:52:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:28 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 06:52:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:58 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - Caught exception: HTTP Error 404: Not Found 06:53:58 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:53:58 INFO - Downloaded 1361 bytes. 06:53:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:58 INFO - Using the following test package requirements: 06:53:58 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:58 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:58 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'jsshell-linux-x86_64.zip'], 06:53:58 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:58 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:58 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:58 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:58 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:58 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:58 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:53:58 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:58 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:54:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:28 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:55:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:29 INFO - Downloaded 21769513 bytes. 06:55:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:55:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:55:31 INFO - Return code: 0 06:55:31 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:55:31 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')]} 06:55:31 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:55:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:31 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 06:56:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:01 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 06:57:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - Caught exception: HTTP Error 404: Not Found 06:57:01 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:57:01 INFO - Downloaded 21769513 bytes. 06:57:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:57:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 06:57:03 INFO - Return code: 0 06:57: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')]} 06:57:03 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:57:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:03 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 06:57:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:33 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 06:58:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - Caught exception: HTTP Error 404: Not Found 06:58:33 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:58:34 INFO - Downloaded 14413723 bytes. 06:58:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:58:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 06:58:34 INFO - Return code: 0 06:58:34 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')]} 06:58:34 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:34 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 06:59:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:04 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 07:00:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - Caught exception: HTTP Error 404: Not Found 07:00:04 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:00:06 INFO - Downloaded 63889067 bytes. 07:00:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - mkdir: /builds/slave/test/properties 07:00:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:06 INFO - Contents: 07:00:06 INFO - build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:07 INFO - Running post-action listener: _resource_record_post_action 07:00:07 INFO - Running post-action listener: set_extra_try_arguments 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.165024Z] Finished download-and-extract step (success) 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.165258Z] Running populate-webroot step. 07:00:07 INFO - Running pre-action listener: _resource_record_pre_action 07:00:07 INFO - Running main action method: populate_webroot 07:00:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:07 INFO - '--tppagecycles', 07:00:07 INFO - '7'], 07:00:07 INFO - 'tests': ['tsvgm']}}, 07:00:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['damp', 'tps']}, 07:00:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'tests': ['damp', 'tps']}, 07:00:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['dromaeo_dom']}, 07:00:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['basic_compositor_video']}, 07:00:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tsvgx', 07:00:07 INFO - 'tsvgr_opacity', 07:00:07 INFO - 'tart', 07:00:07 INFO - 'tscrollx', 07:00:07 INFO - 'cart']}, 07:00:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:07 INFO - 'tsvgr_opacity', 07:00:07 INFO - 'tart', 07:00:07 INFO - 'tscrollx', 07:00:07 INFO - 'cart']}, 07:00:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tp5o']}, 07:00:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'tests': ['tp5o']}, 07:00:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s', 07:00:07 INFO - '--xperf_path', 07:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:07 INFO - 'tests': ['tp5n']}, 07:00:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--xperf_path', 07:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:07 INFO - 'tests': ['tp5n']}}, 07:00:07 INFO - 'talos.zip': {'path': '', 07:00:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:07 INFO - Running post-action listener: _resource_record_post_action 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.181165Z] Finished populate-webroot step (success) 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.181253Z] Running create-virtualenv step. 07:00:07 INFO - Running pre-action listener: _resource_record_pre_action 07:00:07 INFO - Running main action method: create_virtualenv 07:00:07 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:07 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 07:00:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:07 INFO - Using real prefix '/usr' 07:00:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:07 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:09 INFO - Installing pip.................done. 07:00:09 INFO - Return code: 0 07:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:09 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 07:00:09 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 07:00:09 INFO - Using env: {'DISPLAY': ':0', 07:00:09 INFO - 'HOME': '/home/cltbld', 07:00:09 INFO - 'LANG': 'en_US.UTF-8', 07:00:09 INFO - 'LANGUAGE': 'en_US:en', 07:00:09 INFO - 'LOGNAME': 'cltbld', 07:00:09 INFO - 'MAIL': '/var/mail/cltbld', 07:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:09 INFO - 'MOZ_NO_REMOTE': '1', 07:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:09 INFO - 'NO_EM_RESTART': '1', 07:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:09 INFO - 'PWD': '/builds/slave/test', 07:00:09 INFO - 'SHELL': '/bin/bash', 07:00:09 INFO - 'SHLVL': '1', 07:00:09 INFO - 'TERM': 'linux', 07:00:09 INFO - 'TMOUT': '86400', 07:00:09 INFO - 'USER': 'cltbld', 07:00:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:09 INFO - '_': '/tools/buildbot/bin/python'} 07:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:09 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 07:00:09 INFO - Cleaning up... 07:00:09 INFO - Return code: 0 07:00:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:09 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:09 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 07:00:09 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 07:00:09 INFO - Using env: {'DISPLAY': ':0', 07:00:09 INFO - 'HOME': '/home/cltbld', 07:00:09 INFO - 'LANG': 'en_US.UTF-8', 07:00:09 INFO - 'LANGUAGE': 'en_US:en', 07:00:09 INFO - 'LOGNAME': 'cltbld', 07:00:09 INFO - 'MAIL': '/var/mail/cltbld', 07:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:09 INFO - 'MOZ_NO_REMOTE': '1', 07:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:09 INFO - 'NO_EM_RESTART': '1', 07:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:09 INFO - 'PWD': '/builds/slave/test', 07:00:09 INFO - 'SHELL': '/bin/bash', 07:00:09 INFO - 'SHLVL': '1', 07:00:09 INFO - 'TERM': 'linux', 07:00:09 INFO - 'TMOUT': '86400', 07:00:09 INFO - 'USER': 'cltbld', 07:00:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:09 INFO - '_': '/tools/buildbot/bin/python'} 07:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:10 INFO - Downloading/unpacking psutil>=3.1.1 07:00:10 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 07:00:10 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 07:00:10 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 07:00:10 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 07:00:10 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 07:00:10 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 07:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:12 INFO - Installing collected packages: psutil 07:00:12 INFO - Running setup.py install for psutil 07:00:12 INFO - building 'psutil._psutil_linux' extension 07:00:12 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 07:00:12 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 07:00:12 INFO - building 'psutil._psutil_posix' extension 07:00:12 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 07:00:12 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 07:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:12 INFO - Successfully installed psutil 07:00:12 INFO - Cleaning up... 07:00:12 INFO - Return code: 0 07:00:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:12 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')]} 07:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:12 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')]} 07:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:12 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:12 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 07:00:12 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 07:00:12 INFO - Using env: {'DISPLAY': ':0', 07:00:12 INFO - 'HOME': '/home/cltbld', 07:00:12 INFO - 'LANG': 'en_US.UTF-8', 07:00:12 INFO - 'LANGUAGE': 'en_US:en', 07:00:12 INFO - 'LOGNAME': 'cltbld', 07:00:12 INFO - 'MAIL': '/var/mail/cltbld', 07:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:12 INFO - 'MOZ_NO_REMOTE': '1', 07:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:12 INFO - 'NO_EM_RESTART': '1', 07:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:12 INFO - 'PWD': '/builds/slave/test', 07:00:12 INFO - 'SHELL': '/bin/bash', 07:00:12 INFO - 'SHLVL': '1', 07:00:12 INFO - 'TERM': 'linux', 07:00:12 INFO - 'TMOUT': '86400', 07:00:12 INFO - 'USER': 'cltbld', 07:00:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:12 INFO - '_': '/tools/buildbot/bin/python'} 07:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:12 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 07:00:12 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 07:00:12 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 07:00:12 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 07:00:12 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 07:00:12 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 07:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:14 INFO - Installing collected packages: mozsystemmonitor 07:00:14 INFO - Running setup.py install for mozsystemmonitor 07:00:14 INFO - Successfully installed mozsystemmonitor 07:00:14 INFO - Cleaning up... 07:00:15 INFO - Return code: 0 07:00:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00:15 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')]} 07:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 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')]} 07:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:15 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 07:00:15 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 07:00:15 INFO - Using env: {'DISPLAY': ':0', 07:00:15 INFO - 'HOME': '/home/cltbld', 07:00:15 INFO - 'LANG': 'en_US.UTF-8', 07:00:15 INFO - 'LANGUAGE': 'en_US:en', 07:00:15 INFO - 'LOGNAME': 'cltbld', 07:00:15 INFO - 'MAIL': '/var/mail/cltbld', 07:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:15 INFO - 'MOZ_NO_REMOTE': '1', 07:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:15 INFO - 'NO_EM_RESTART': '1', 07:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:15 INFO - 'PWD': '/builds/slave/test', 07:00:15 INFO - 'SHELL': '/bin/bash', 07:00:15 INFO - 'SHLVL': '1', 07:00:15 INFO - 'TERM': 'linux', 07:00:15 INFO - 'TMOUT': '86400', 07:00:15 INFO - 'USER': 'cltbld', 07:00:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:15 INFO - '_': '/tools/buildbot/bin/python'} 07:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:15 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:15 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 07:00:15 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 07:00:15 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 07:00:15 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 07:00:15 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 07:00:15 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 07:00:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 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 07:00:17 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:17 INFO - warning: no files found matching '*.txt' 07:00:17 INFO - no previously-included directories found matching 'build' 07:00:17 INFO - no previously-included directories found matching 'dist' 07:00:17 INFO - no previously-included directories found matching '.git*' 07:00:17 INFO - Installing collected packages: jsonschema, functools32 07:00:17 INFO - Running setup.py install for functools32 07:00:17 INFO - warning: no files found matching '*.txt' 07:00:17 INFO - no previously-included directories found matching 'build' 07:00:17 INFO - no previously-included directories found matching 'dist' 07:00:17 INFO - no previously-included directories found matching '.git*' 07:00:17 INFO - Successfully installed jsonschema functools32 07:00:17 INFO - Cleaning up... 07:00:17 INFO - Return code: 0 07:00:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:00:17 INFO - Using env: {'DISPLAY': ':0', 07:00:17 INFO - 'HOME': '/home/cltbld', 07:00:17 INFO - 'LANG': 'en_US.UTF-8', 07:00:17 INFO - 'LANGUAGE': 'en_US:en', 07:00:17 INFO - 'LOGNAME': 'cltbld', 07:00:17 INFO - 'MAIL': '/var/mail/cltbld', 07:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:17 INFO - 'MOZ_NO_REMOTE': '1', 07:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:17 INFO - 'NO_EM_RESTART': '1', 07:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:17 INFO - 'PWD': '/builds/slave/test', 07:00:17 INFO - 'SHELL': '/bin/bash', 07:00:17 INFO - 'SHLVL': '1', 07:00:17 INFO - 'TERM': 'linux', 07:00:17 INFO - 'TMOUT': '86400', 07:00:17 INFO - 'USER': 'cltbld', 07:00:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:17 INFO - '_': '/tools/buildbot/bin/python'} 07:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:17 INFO - Cleaning up... 07:00:17 INFO - Return code: 0 07:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:00:17 INFO - Using env: {'DISPLAY': ':0', 07:00:17 INFO - 'HOME': '/home/cltbld', 07:00:17 INFO - 'LANG': 'en_US.UTF-8', 07:00:17 INFO - 'LANGUAGE': 'en_US:en', 07:00:17 INFO - 'LOGNAME': 'cltbld', 07:00:17 INFO - 'MAIL': '/var/mail/cltbld', 07:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:17 INFO - 'MOZ_NO_REMOTE': '1', 07:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:17 INFO - 'NO_EM_RESTART': '1', 07:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:17 INFO - 'PWD': '/builds/slave/test', 07:00:17 INFO - 'SHELL': '/bin/bash', 07:00:17 INFO - 'SHLVL': '1', 07:00:17 INFO - 'TERM': 'linux', 07:00:17 INFO - 'TMOUT': '86400', 07:00:17 INFO - 'USER': 'cltbld', 07:00:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:17 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:00:18 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 07:00:18 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 07:00:18 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 07:00:18 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 07:00:18 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 07:00:18 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 07:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 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 07:00:20 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 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - Downloading docopt-0.6.1.tar.gz 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:20 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:20 INFO - Running setup.py install for blobuploader 07:00:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:20 INFO - Running setup.py install for requests 07:00:21 INFO - Running setup.py install for docopt 07:00:21 INFO - Successfully installed blobuploader requests docopt 07:00:21 INFO - Cleaning up... 07:00:21 INFO - Return code: 0 07:00:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:21 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')]} 07:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:21 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')]} 07:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:21 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:21 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 07:00:21 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 07:00:21 INFO - Using env: {'DISPLAY': ':0', 07:00:21 INFO - 'HOME': '/home/cltbld', 07:00:21 INFO - 'LANG': 'en_US.UTF-8', 07:00:21 INFO - 'LANGUAGE': 'en_US:en', 07:00:21 INFO - 'LOGNAME': 'cltbld', 07:00:21 INFO - 'MAIL': '/var/mail/cltbld', 07:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:21 INFO - 'MOZ_NO_REMOTE': '1', 07:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:21 INFO - 'NO_EM_RESTART': '1', 07:00:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:21 INFO - 'PWD': '/builds/slave/test', 07:00:21 INFO - 'SHELL': '/bin/bash', 07:00:21 INFO - 'SHLVL': '1', 07:00:21 INFO - 'TERM': 'linux', 07:00:21 INFO - 'TMOUT': '86400', 07:00:21 INFO - 'USER': 'cltbld', 07:00:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:21 INFO - '_': '/tools/buildbot/bin/python'} 07:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:21 INFO - Running setup.py (path:/tmp/pip-VNsOzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:21 INFO - Running setup.py (path:/tmp/pip-AnJXMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:21 INFO - Running setup.py (path:/tmp/pip-nbZQbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:21 INFO - Running setup.py (path:/tmp/pip-tF34gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:21 INFO - Running setup.py (path:/tmp/pip-JPk9se-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:21 INFO - Running setup.py (path:/tmp/pip-K41oIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:21 INFO - Running setup.py (path:/tmp/pip-gWIe9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:22 INFO - Running setup.py (path:/tmp/pip-NSbOBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:22 INFO - Running setup.py (path:/tmp/pip-F3y_HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:22 INFO - Running setup.py (path:/tmp/pip-Rtqjpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:22 INFO - Running setup.py (path:/tmp/pip-NGVXxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:22 INFO - Running setup.py (path:/tmp/pip-6ycMg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:22 INFO - Running setup.py (path:/tmp/pip-ULCV1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:22 INFO - Running setup.py (path:/tmp/pip-zyR3Bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:22 INFO - Running setup.py (path:/tmp/pip-aDqjyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:22 INFO - Running setup.py (path:/tmp/pip-T4H951-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:22 INFO - Running setup.py (path:/tmp/pip-bt0SNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:22 INFO - Running setup.py install for manifestparser 07:00:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozcrash 07:00:22 INFO - Running setup.py install for mozdebug 07:00:23 INFO - Running setup.py install for mozdevice 07:00:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozfile 07:00:23 INFO - Running setup.py install for mozhttpd 07:00:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozinfo 07:00:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozInstall 07:00:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozleak 07:00:23 INFO - Running setup.py install for mozlog 07:00:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for moznetwork 07:00:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozprocess 07:00:23 INFO - Running setup.py install for mozprofile 07:00:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozrunner 07:00:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozscreenshot 07:00:24 INFO - Running setup.py install for moztest 07:00:24 INFO - Running setup.py install for mozversion 07:00:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:24 INFO - Cleaning up... 07:00:24 INFO - Return code: 0 07:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:24 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')]} 07:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:24 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')]} 07:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:24 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:24 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 07:00:24 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 07:00:24 INFO - Using env: {'DISPLAY': ':0', 07:00:24 INFO - 'HOME': '/home/cltbld', 07:00:24 INFO - 'LANG': 'en_US.UTF-8', 07:00:24 INFO - 'LANGUAGE': 'en_US:en', 07:00:24 INFO - 'LOGNAME': 'cltbld', 07:00:24 INFO - 'MAIL': '/var/mail/cltbld', 07:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:24 INFO - 'MOZ_NO_REMOTE': '1', 07:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:24 INFO - 'NO_EM_RESTART': '1', 07:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:24 INFO - 'PWD': '/builds/slave/test', 07:00:24 INFO - 'SHELL': '/bin/bash', 07:00:24 INFO - 'SHLVL': '1', 07:00:24 INFO - 'TERM': 'linux', 07:00:24 INFO - 'TMOUT': '86400', 07:00:24 INFO - 'USER': 'cltbld', 07:00:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:24 INFO - '_': '/tools/buildbot/bin/python'} 07:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:24 INFO - Running setup.py (path:/tmp/pip-LIMs9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:24 INFO - Running setup.py (path:/tmp/pip-iAq6su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:24 INFO - Running setup.py (path:/tmp/pip-a8Rwod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:24 INFO - Running setup.py (path:/tmp/pip-euc_Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:25 INFO - Running setup.py (path:/tmp/pip-GL8PIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:25 INFO - Running setup.py (path:/tmp/pip-xU48ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:25 INFO - Running setup.py (path:/tmp/pip-g_9oUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:25 INFO - Running setup.py (path:/tmp/pip-gSAMzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:25 INFO - Running setup.py (path:/tmp/pip-aTsz_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:25 INFO - Running setup.py (path:/tmp/pip-mmgAMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:25 INFO - Running setup.py (path:/tmp/pip-5kFz_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:25 INFO - Running setup.py (path:/tmp/pip-Caoq3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:25 INFO - Running setup.py (path:/tmp/pip-U5eL5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:25 INFO - Running setup.py (path:/tmp/pip-xOb_4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:25 INFO - Running setup.py (path:/tmp/pip-71f6mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:25 INFO - Running setup.py (path:/tmp/pip-MGgYiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:25 INFO - Running setup.py (path:/tmp/pip-13lwcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:25 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)) 07:00:25 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)) 07:00:25 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)) 07:00:25 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)) 07:00:25 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)) 07:00:25 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)) 07:00:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:25 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 07:00:25 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 07:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:27 INFO - Downloading blessings-1.6.tar.gz 07:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:27 INFO - Installing collected packages: blessings 07:00:27 INFO - Running setup.py install for blessings 07:00:28 INFO - Successfully installed blessings 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:28 INFO - Reading from file tmpfile_stdout 07:00:28 INFO - Current package versions: 07:00:28 INFO - argparse == 1.2.1 07:00:28 INFO - blessings == 1.6 07:00:28 INFO - blobuploader == 1.2.4 07:00:28 INFO - docopt == 0.6.1 07:00:28 INFO - functools32 == 3.2.3-2 07:00:28 INFO - jsonschema == 2.5.1 07:00:28 INFO - manifestparser == 1.1 07:00:28 INFO - mozInstall == 1.12 07:00:28 INFO - mozcrash == 0.17 07:00:28 INFO - mozdebug == 0.1 07:00:28 INFO - mozdevice == 0.48 07:00:28 INFO - mozfile == 1.2 07:00:28 INFO - mozhttpd == 0.7 07:00:28 INFO - mozinfo == 0.9 07:00:28 INFO - mozleak == 0.1 07:00:28 INFO - mozlog == 3.3 07:00:28 INFO - moznetwork == 0.27 07:00:28 INFO - mozprocess == 0.23 07:00:28 INFO - mozprofile == 0.28 07:00:28 INFO - mozrunner == 6.12 07:00:28 INFO - mozscreenshot == 0.1 07:00:28 INFO - mozsystemmonitor == 0.3 07:00:28 INFO - moztest == 0.7 07:00:28 INFO - mozversion == 1.4 07:00:28 INFO - psutil == 3.1.1 07:00:28 INFO - requests == 1.2.3 07:00:28 INFO - wsgiref == 0.1.2 07:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:28 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')]} 07:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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')]} 07:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:28 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 07:00:28 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 07:00:28 INFO - Using env: {'DISPLAY': ':0', 07:00:28 INFO - 'HOME': '/home/cltbld', 07:00:28 INFO - 'LANG': 'en_US.UTF-8', 07:00:28 INFO - 'LANGUAGE': 'en_US:en', 07:00:28 INFO - 'LOGNAME': 'cltbld', 07:00:28 INFO - 'MAIL': '/var/mail/cltbld', 07:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:28 INFO - 'MOZ_NO_REMOTE': '1', 07:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:28 INFO - 'NO_EM_RESTART': '1', 07:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:28 INFO - 'PWD': '/builds/slave/test', 07:00:28 INFO - 'SHELL': '/bin/bash', 07:00:28 INFO - 'SHLVL': '1', 07:00:28 INFO - 'TERM': 'linux', 07:00:28 INFO - 'TMOUT': '86400', 07:00:28 INFO - 'USER': 'cltbld', 07:00:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:28 INFO - '_': '/tools/buildbot/bin/python'} 07:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00:28 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')]} 07:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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')]} 07:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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 0x14eb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17ac180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, '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': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', '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 07:00:28 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 07:00:28 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 07:00:28 INFO - Using env: {'DISPLAY': ':0', 07:00:28 INFO - 'HOME': '/home/cltbld', 07:00:28 INFO - 'LANG': 'en_US.UTF-8', 07:00:28 INFO - 'LANGUAGE': 'en_US:en', 07:00:28 INFO - 'LOGNAME': 'cltbld', 07:00:28 INFO - 'MAIL': '/var/mail/cltbld', 07:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:28 INFO - 'MOZ_NO_REMOTE': '1', 07:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:28 INFO - 'NO_EM_RESTART': '1', 07:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:28 INFO - 'PWD': '/builds/slave/test', 07:00:28 INFO - 'SHELL': '/bin/bash', 07:00:28 INFO - 'SHLVL': '1', 07:00:28 INFO - 'TERM': 'linux', 07:00:28 INFO - 'TMOUT': '86400', 07:00:28 INFO - 'USER': 'cltbld', 07:00:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:28 INFO - '_': '/tools/buildbot/bin/python'} 07:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Running post-action listener: _resource_record_post_action 07:00:28 INFO - Running post-action listener: _start_resource_monitoring 07:00:28 INFO - Starting resource monitoring. 07:00:28 INFO - [mozharness: 2016-09-01 14:00:28.816630Z] Finished create-virtualenv step (success) 07:00:28 INFO - [mozharness: 2016-09-01 14:00:28.817065Z] Running install step. 07:00:28 INFO - Running pre-action listener: _resource_record_pre_action 07:00:28 INFO - Running main action method: install 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Output received: 07:00:29 INFO - Usage: mozinstall [options] installer 07:00:29 INFO - Options: 07:00:29 INFO - -h, --help show this help message and exit 07:00:29 INFO - -d DEST, --destination=DEST 07:00:29 INFO - Directory to install application into. [default: 07:00:29 INFO - "/builds/slave/test"] 07:00:29 INFO - --app=APP Application being installed. [default: firefox] 07:00:29 INFO - mkdir: /builds/slave/test/build/application 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:00:45 INFO - Reading from file tmpfile_stdout 07:00:45 INFO - Output received: 07:00:45 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.774883Z] Finished install step (success) 07:00:45 INFO - [mozharness: 2016-09-01 14:00:45.775159Z] Running run-tests step. 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: run_tests 07:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:45 INFO - Python 2.7.3 07:00:45 INFO - Return code: 0 07:00:45 INFO - grabbing minidump binary from tooltool 07:00:45 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')]} 07:00:45 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 0x17aa3b0>, '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 0x17ab2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17ab740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:45 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 07:00:45 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 07:00:45 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 07:00:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:45 INFO - Return code: 0 07:00:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:00:45 INFO - Using env: {'DISPLAY': ':0', 07:00:45 INFO - 'HOME': '/home/cltbld', 07:00:45 INFO - 'LANG': 'en_US.UTF-8', 07:00:45 INFO - 'LANGUAGE': 'en_US:en', 07:00:45 INFO - 'LOGNAME': 'cltbld', 07:00:45 INFO - 'MAIL': '/var/mail/cltbld', 07:00:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:45 INFO - 'MOZ_NO_REMOTE': '1', 07:00:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:45 INFO - 'NO_EM_RESTART': '1', 07:00:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:45 INFO - 'PWD': '/builds/slave/test', 07:00:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:45 INFO - 'SHELL': '/bin/bash', 07:00:45 INFO - 'SHLVL': '1', 07:00:45 INFO - 'TERM': 'linux', 07:00:45 INFO - 'TMOUT': '86400', 07:00:45 INFO - 'USER': 'cltbld', 07:00:45 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161', 07:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:45 INFO - '_': '/tools/buildbot/bin/python'} 07:00:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:00:46 INFO - mozversion application_buildid: 20160830104522 07:00:46 INFO - mozversion application_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:46 INFO - mozversion application_name: Firefox 07:00:46 INFO - mozversion application_remotingname: firefox-dev 07:00:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion application_vendor: Mozilla 07:00:46 INFO - mozversion application_version: 50.0a2 07:00:46 INFO - mozversion platform_buildid: 20160830104522 07:00:46 INFO - mozversion platform_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion platform_version: 50.0a2 07:00:46 INFO - using testdate: 1472738446 07:00:46 INFO - actual date: 1472738446 07:00:46 INFO - starting webserver on 'localhost:55909' 07:00:46 INFO - SUITE-START | Running 2 tests 07:00:46 INFO - TEST-START | dromaeo_css 07:00:46 INFO - operating with platform_type : linux_ 07:00:46 INFO - Initialising browser for dromaeo_css test... 07:00:46 INFO - TEST-INFO | started process 3841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjcisDC/profile http://localhost:55909/getInfo.html) 07:00:50 INFO - PROCESS | 3841 | __metrics Screen width/height:1600/1200 07:00:50 INFO - PROCESS | 3841 | colorDepth:24 07:00:50 INFO - PROCESS | 3841 | Browser inner width/height: 1024/697 07:00:50 INFO - PROCESS | 3841 | __metrics 07:00:50 INFO - PROCESS | 3841 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:00:50 INFO - PROCESS | 3841 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:00:51 INFO - TEST-INFO | 3841: exit 0 07:00:51 INFO - Browser initialized. 07:00:51 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:51 INFO - TEST-INFO | started process 3931 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjcisDC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:03 INFO - PROCESS | 3931 | RSS: Main: 145555456 07:01:03 INFO - PROCESS | 3931 | 07:01:54 INFO - PROCESS | 3931 | 1472738514304 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] 07:04:03 INFO - PROCESS | 3931 | Cycle 1(1): loaded http://localhost:55909/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55909/tests/dromaeo/cssquery-ext.html) 07:04:04 INFO - PROCESS | 3931 | RSS: Main: 228786176 07:04:04 INFO - PROCESS | 3931 | 07:07:06 INFO - PROCESS | 3931 | Cycle 1(1): loaded http://localhost:55909/tests/dromaeo/cssquery-ext.html (next: http://localhost:55909/tests/dromaeo/cssquery-jquery.html) 07:07:07 INFO - PROCESS | 3931 | RSS: Main: 235765760 07:07:07 INFO - PROCESS | 3931 | 07:10:09 INFO - PROCESS | 3931 | Cycle 1(1): loaded http://localhost:55909/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55909/tests/dromaeo/cssquery-mootools.html) 07:10:09 INFO - PROCESS | 3931 | RSS: Main: 177143808 07:10:09 INFO - PROCESS | 3931 | 07:13:11 INFO - PROCESS | 3931 | Cycle 1(1): loaded http://localhost:55909/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55909/tests/dromaeo/cssquery-prototype.html) 07:13:12 INFO - PROCESS | 3931 | RSS: Main: 175099904 07:13:12 INFO - PROCESS | 3931 | 07:16:15 INFO - PROCESS | 3931 | Cycle 1(1): loaded http://localhost:55909/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55909/tests/dromaeo/cssquery-yui.html) 07:16:15 INFO - PROCESS | 3931 | RSS: Main: 177500160 07:16:15 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | Cycle 1(1): loaded http://localhost:55909/tests/dromaeo/cssquery-yui.html (next: http://localhost:55909/tests/dromaeo/cssquery-dojo.html) 07:19:18 INFO - PROCESS | 3931 | RSS: Main: 266534912 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | __start_tp_report 07:19:18 INFO - PROCESS | 3931 | _x_x_mozilla_page_load 07:19:18 INFO - PROCESS | 3931 | _x_x_mozilla_page_load_details 07:19:18 INFO - PROCESS | 3931 | |i|pagename|runs| 07:19:18 INFO - PROCESS | 3931 | |0;dojo.html;3227.9677203227966;3242.5136229565546;3246.123546642406;3273.819939903304;3287.1946373138585;5619.719014049297;5691.430856914311;5700.429957004298;5707.857303567408;5708.372079071299;5677.37548869624;5709.543236541077;5716.28546431696;5726.799562015331;5737.684427356494;5152.92270615941;5200.08998425276;5211.140161873286;5271.947280527184;5288.920666189991;5207.713575753332;5313.309269794921;5353.250544923714;5366.4633536646215;5368.543673787733;5195.142801437758;5260.631755777097;5277.47225277473;5278.155495120782;5368.248445217672;5374.462553744614;5387.353517577916;5391.757370918318;5395.487428694268;5399.541039011689;5136.897262054738;5143.459936706629;5205.427402985669;5265.210218467222;5266.1837415200325;5614.073677843151;5622.775088996435;5660.405657405995;5716.742746576372;5760.279965004374;6179.227596550431;6186.721597528122;6194.81415557534;6197.349278325756;6200.503959683215;5989.520838332923;6122.846928826815;6135.753292002322;6148.907766383508;6227.595206311576;5789.392113828029;5801;5813.883722325572;5824.359320474744;5830.125481177857;6383.553151279366;6389.169407976933;6400.519961002943;6401.103845461639;6405.167328247391;5878.882422351529;5949.940500594938;5971.940280597139;5984.790532331434;6109;6144.969275153596;6146.938530614637;6155;6155.261368635793;6156.29202641702;6114.0828875669;6129.938700613026;6137.754489820447;6140.662263575457;6143.84640383994;5977.820665380045;6070.878582428327;6093.634381937098;6102.267727872595;6157.599756015845;4679.251319788886;4713.528647135259;4714.316424118484;4739.502352252964;4754.762261886892;4192.496900371975;4205.3902184183125;4212.389203565527;4228.365745138192;4233.7671428071135;5626.915596266059;5695;5707.942920570741;5729.0260655688635;5833.066709326487;6140.662263575547;6145.4161854623735;6146.200993870768;6147.84630384244;6151.169592105098;5691.174779656926;5809.186713860063;5810.82567522975;5817.534597232211;5837.124431335249;5256.027634887558;5303.761330740049;5306.946930530646;5342.1452567589;5377.300950876361;6401.583897046677;6413.518986076063;6417.294097649255;6418.711157997901;6426.164598602245;5944.048952167633;6128.816135515942;6130.448259656652;6192.102145189013;6201;6312.621242725451;6345.524085693499;6371.362863713592;6475.287718351072;6485.675716214076;6100.206973093647;6106.969465152646;6107.175531303217;6110.969445152746;6114.877702446015;6005.879882402415;6203.410675985774;6206.193194884817;6210;6210.5652604317265;5960.5827592068135;6015.39846015395;6016.067509536029;6067.393260674074;6111.572189946839;6017.759289628366;6055.182550355646;6069.0592958091565;6070;6070.575059745775;5842.444967728059;6010.519158467399;6054.212952316171;6071.241094863142;6074.939250607437;5975.671338076448;6039.214902062879;6045.516358691206;6107.303096926935;6118.583064974548;5949.672767997802;5956.612820166675;5958.136070269873;5965.492933100735;5982.551308651781 07:19:18 INFO - PROCESS | 3931 | |1;ext.html;605.9454649081582;611.3947192279643;641.7112299465242;642.9067785171151;667.896476046213;36296.45555316669;47960;49042.509574904194;49092.50907490924;49099.75450122753;48857.51142488574;49311.26033109497;49322.50677493233;49358.75320623401;49412.75293623536;8388.958055209716;9086.500242486678;9337.766555836091;9337.766555836124;10941.726456838604;10695.679129626124;11104.44477776112;11105.777884442346;11110.944445277762;11113.055390291835;11151.832722509169;11215.214934954556;11216.49525771338;11221.663350099469;11221.887781122166;11129.332240065582;11145.944270278678;11186.105111591054;11187.105031597453;11190.832137517944;10988.725281868017;11039.503222355013;11065.723356916142;11074.114070874311;11077.058450031756;45537;45952;45976.3103553447;46018.539814602096;46020;3903.2974064668347;3923.509561304837;4017.658499027586;4125.66994640428;4130.504339479252;35200.27020583329;35204.29591408183;35341.46987795185;35405.29189416197;35454.468182977274;4577.885552861172;4616.169089563878;4620.861374158781;4642.466116396624;4689.742064186468;358137;358573;358767;360998;361233;65462.345376546575;65808;65910.34089659138;66039;66083;40062.1987560253;41184.794076030026;41231.58768412277;43215.13569728588;69245.88393522304;19532.51168720765;19830.603387932162;19845.702314465296;19845.801541984685;19846.90076549608;13134.474621015244;14989.100653960797;15455.690886182438;15656.765148522782;15718.921405393128;21298.78701212968;21325.786742132692;21410.678839817414;21483;21503;7463.067116610424;7503.174650788409;7617.314441700273;7749.612519374122;7761.534307941542;1009.7425156585024;1059.897706385366;1098.9120870330355;1114.169943392178;1169.6257197696825;35587.110322241635;36172.27655446929;36492;36812.079698007765;36841.263174736356;1136.2841409911703;1141.8915203055697;1172.6892373521023;1185.1940680337452;1229.624964385853;18199.63600727978;18942.62114757697;19004.80995190058;19160.425187244404;19203.519912002033;576574;583021;585886;586726;588457;65572.34427655757;66265.6686716573;66287.66856165689;66381.33618663848;66391.66804165948;1349.6828245362333;1381.040177076922;1383.3083458270864;1384.536180379584;1447.7466443372368;26357.472850542497;33555.496667549516;34055.31889362248;35786;35837.82081089578;60071;60581;60609.69695151672;60683.69658151681;60684.393156069644;368361;368612;370206;370856;371687;28127.156185314474;30685.53971690382;31765.36469270648;32010.039698809072;32208.35583288368;54043;54134.45865541294;54437.45562544324;54541.72729136488;54958;45119.32321015253;45704.31443528416;46392.76803615961;46777.53222467732;48396.758016211104;42373.576264238196;45123;45203.32195017012;45999.77000114978;46628.30057549071;44463;44934.775326124465;45370.773146135376;45632;46034.76982615065;39148;40598.1880362397;41128.58871411248;41133.35466581303;41185;30514.389712205186;30791.84604076965;30912;30994.845025774728;31089.53365699471 07:19:18 INFO - PROCESS | 3931 | |2;jquery.html;414.29156142995475;415.7463946992334;416.7207970659659;425.11151692961715;428.86705121412365;19351.322703705373;20257.898710506444;20953.68569471459;21092.789072109274;21261.681074783854;20272.695909561367;20440.28459003935;20763.273285435014;20892.89553552234;21080.578388432223;5782.971085144568;6099.359567245442;6101.572889897709;6101.694915254242;6106.816795496142;5775.5957083004205;5985.700714964256;5986.730597123118;6016.7292471838655;6022.578419510636;5987.730552125143;5989.490893274055;5991.071383935497;5998;5998.760049598032;5775.913361299563;5956.0172571525645;5972.970135149319;5974.731137098797;5987.371326010793;5289.47105289468;5339.439358867302;5382.838514844561;5388.110961691316;5390.056740070511;24543.38641533958;24788.752112478825;25002.49995000108;25113.497730045296;25286.49427011468;2724.7956403269636;2774.209350335165;3079.276370053058;3176.444122278592;3213;15235.771463427947;15255.38978440861;15298.617534561614;15653.686926261526;15838.287277072559;1980.12874335292;2035.2062695548748;2045.887476188809;2080.38628604562;2122.4587730128874;78831.605841971;80349.99999999942;82280;85397.57301213517;85653;11421.657350279505;11568.305901645928;11665.183437159318;11901.464434100486;11919.284842909454;16213.513594592181;16218.75671864923;16242.675146497004;16254.51236462908;16272.186390680656;8638;8674.522901240367;8714.128587141362;8782;9014.143656352637;8515.701950431705;8550;8567.528785916835;8763.211310982042;8768.210861022411;4961.82633607822;4975.726335051607;4986.227134794112;4991.550760431579;5076.974615126975;1953.89253591051;1963.9803601964084;2010.3615553323993;2078.1791192478886;2082.1671331467232;907.3421769217265;970.3062310448064;1025.8512515685184;1061.3366645846345;1081.7620123572801;20168.19327226924;20338.898305508377;21143.365699029055;21202.575948480942;21346.78653213479;1014.1075853249093;1041.7083216699336;1067.7170549804152;1067.8558394616782;1120.0815331428314;10634.946825265824;11639.6508104757;11675.766484670428;11684;11739;505079;514562;516359;518761;519755;59108;91653;95673.52163239139;97937;101148;1031.8916513765946;1043.4939054558533;1063.691529456449;1066.4720963123305;1067.8932106789414;17743.55641108931;18060;18583.44249672512;18718.6256274871;18719.251229951195;44586.77706611446;45321.77339113283;45369.319460207465;45620.315695265264;47439.05121897474;61636;63652;64555.677221613594;64995.67502162459;65220.347796521426;7579.696812127453;8835.734927952171;9113.589888455168;9188.540572971191;9228.307876909124;13124.146930449871;13655.86344136546;13695.383707733376;13769.862301376857;14186;11884.405779711153;12167.087468439726;12310.753784924073;12363.745109278185;12552.74894502123;12025.4588543514;12174;12464.937675311565;12477.188982716094;12486.562970295996;8774.517401542977;11582.88417115818;12055.336956467478;12143.332116733665;12586.307753073668;8791.208791208823;8907.643694252158;8961.283097352327;8974.01285858542;9341.20599749029;11248.381339026431;11985.520579176735;12043.217190882564;12340.691482713004;12449 07:19:18 INFO - PROCESS | 3931 | |3;mootools.html;1424.287856071964;1507.9924600376996;1562.9843701562984;1691.5179173935423;1861.7952025277218;9169.312301577384;9693.903060969396;9703.07820757028;9984.900150998488;9997.15024222942;9723.416595004304;9836.901630983688;9854.507274636275;9856.901430985688;9866.35868668536;2522.4198434360064;2544.2494464133033;2550.961735573968;2551.8851651675627;2552.068494999321;2451.816113791464;2530.772230499255;2533.695956485215;2534.733852945433;2537.5305568469894;2476.938076548083;2496.3010357100043;2504.1485894795765;2504.26124293333;2507.084914006393;2426.053839002799;2426.3085020769086;2437.6587277781123;2470.5800013997664;2473.6784218051716;2427.174760581393;2450.6691596634405;2451.1788550835313;2461.692288463942;2473.567125752986;9564.713058608253;9737.56180971858;9797.80404391908;9800.90199098014;9808.264380171515;5657.490825825655;5835.883282334372;5841.85395365115;5853.970730146365;5869.50109240715;3414.163529935182;3428.5714285714284;3466.9133271668156;3485.721142308609;3493.8951831445097;1022.2333250062453;1032.4063663393579;1047.9318844275174;1048.6801525534709;1057.3867157048894;13506.932465337708;13529.45882164722;13608.591742247749;13618.046736728335;13632.59102226925;4716.858494245178;4796.184648609745;4849.078675051729;4899.902001959941;4972.602191824645;10885.891141088647;10898.182636302308;10911.945440272748;10919.290246133976;10994.450277486254;5543.861403464945;5559.6664200148125;5563.13771365439;5565.526930210896;5565.972170139124;4631.120087183492;4810.230363141881;5202.895942081137;5210;5288.735563221823;4192.350185721218;4209.2423363794815;4212.978935105366;4213.494380674278;4246.554111818276;1246.650937737435;1257.9245245285313;1263.5767018048873;1271.0340141492532;1272.7072773262016;1044.4412239451856;1053.6259627832162;1061.601899287767;1072.892710728921;1073.3291692692067;6379.585326953733;6424.518161137933;6582.341765823379;6694.765683201064;6921.792346229621;478.4450037955954;484.6074679509608;1043.7286305560608;1121.9439028048564;1160.0893298760602;4367.563243675607;4408.823647054082;4509.053098849278;4595.770211489479;4688.249880019181;11355.545778168946;11886.465109070112;12134.75730485403;12143.635690929286;12344.259344439362;11423.771524569462;11928.940355298168;11938.64184074479;11942.820857686967;11998.460069296903;8820.338474614302;8829.646814127364;9067.954660226656;9138.13187747163;9145.77135571616;6591.077249185022;6733.831654208684;6736.79789606293;6748.460123190232;6831.146106736658;10983.835242471529;11252.66242012741;11258.718532036764;11278.830817537579;11325;10994.890051099386;11365.204435689753;11423.885761142614;11426.714332141762;11476.942615286871;8579.227869491775;8582.055973842997;8668.566571671518;8782.385233033627;8784.209421152127;9210.723678289662;9333.48665823359;9354.672586459714;9360;9367.437953722798;8281.29608983243;8368.958155209428;8433.746987590383;8481.40630155883;8516.531590762568;8421.07368189511;8472.957635211784;8562.443441176303;8612.698555550525;8752;7533.6233188339265;7538.924610753822;7569.924300757142;7632.274933881273;7640.121386040454;5303.628745987899;5365.785368585213;5372.140457526777;5633.521150702236;5808.7095645218415;5600;5697.857553561194;5737.426257374229;5886.676232807065;5984.8803023940145 07:19:18 INFO - PROCESS | 3931 | |4;prototype.html;347.06812209218253;350.5372907761755;355.1069061310802;357.11257525050246;357.91410061585225;5459.535939445147;5466.781328746845;5484.341878974524;5491.835244942648;5529.640573362729;5383.67697938124;5384.434634363394;5391.16436952273;5401.351837779472;5417.918731219025;4047.817848196838;4235.618794308527;4274.487061552602;4293.29522400887;4308.590683885034;4243.554426785189;4262.531121576624;4275.764832934187;4278.117628243578;4286.721363111404;4022.1955608878193;4235.555266696999;4236.343366778139;4241.660667146621;4243.745375277478;4094.324436467979;4223.669544093601;4229.936550951738;4232.978835105836;4244.5967633075115;3734.7945862977526;3745.4756334113245;3745.8501659933568;3750.081230098616;3752.155764952891;5072.289879416885;5289.127293996525;5290.841274761725;5299.523042926116;5302.443243459458;4590.150822097922;4804.7837847296605;4810.5670489656095;4811.182099043171;4816.759162041919;5616.433564686413;5620.241267428885;5622.381538030813;5645.887082258332;5648.124540696199;3945.1320709443876;4070.287699652579;4078.796060197008;4089.488813898263;4092.5498195198506;5845.561582881301;5868.207791948075;5870.706464676792;5883.411658834168;5896.852578685567;4168.916621667549;4178.373244013423;4196;4211.7262377945335;4214.545981636331;4087.2234275487563;4232.111256636079;4249.851255206053;4252.425922500484;4266.978665106654;4812.302216178635;4834.244480645998;4837.467878533358;4838.443578988461;4844.8304309349005;5711.571632127524;5714.542836573064;5725.656460612293;5726.742296596663;5733.541316694655;1437.1018113678952;1463.1806188534454;1469.4636457692866;1473.1676602719429;1476.9483068092566;930.2557953637063;945.7210123013728;947.4646824544111;956.712986104166;957.2916042128775;4898.24077268024;4924.581410580069;4934.8896498287595;4939.234418665113;4940.431850337257;4834.95165048352;5145.423930368358;5156.587473002151;5163.457836927144;5189.44316704987;4887.802488390367;4941.209406495015;4955.231939049453;5265.236540701653;5350.411454739976;3075.5232938894505;3085.228692826793;3085.3520760640517;3101.42623614632;3115.3613509230554;6066.45401913821;6073.271207455045;6073.878522429527;6093.756249750049;6103.23709536308;5692.31692196939;5960.4635582797755;5996.910046349309;5997.940020599738;5998.760049598054;6143.017117261216;6143.96928015357;6145.385461453819;6153.138560601519;6177.752889884534;5662.688552129673;5914.911276330772;5944.673042982505;5956;5964.373740757158;5847.8245652630485;5849.678267695318;5861.413858614275;5886.470217680429;5895.793647222326;5819.243498345358;6098.725557349841;6103.337997201644;6174.048608890404;6177.227846519185;4163.354680024601;4191.685623578305;4226.218149642326;4233.830646774218;4235.534091249899;5891.675957822361;5912.67480288571;5917.911231331447;5969;5978.970105149447;5582.637128586629;5737.196792448977;5784;5824.563157763269;5830.941690583039;5701.230333904869;5729.455701708331;5729.598928075162;5751.827445176651;5756.121939030485;5714.885597308485;5718.199452076796;5739.8565035874435;5741.224934633771;5751.338596061507;5580.493266818017;5756.280464941882;5803.593748437569;5803.680797556175;5823.534117270524;5655.830325090254;5655.971720141373;5658.632188907708;5683.431656834449;5683.630564013326 07:19:18 INFO - PROCESS | 3931 | |5;yui.html;858.6093327535972;888.4624801994793;899.7314678108183;905.194377004466;951.0584521323891;15732.764008539882;17200.483985480423;17260.136993150354;17267.39564115256;17297.30810767568;15953.84046159538;16433.83566164338;16728.58178545534;16760.41338553151;16769.748453773173;878.3149143667918;904.8280826642949;907.9273658107336;908.3550679017906;908.9590968406437;745.9104907411119;867.839449701804;893.7676204186894;899.6356475627381;899.95050272235;950.5199874063622;951.980960380792;953.1231267234126;953.2040745977083;953.7949340891707;943.2218419803621;944.5230158769812;944.655200851691;944.7118628818208;946.5787724462617;561.5872333834628;669.5380187670492;687.4912564702134;689.9103116594861;693.5873155472486;14351;14387.71224575513;14402.279886005657;14426.639334016734;14505.492307769178;14863.925680371636;15391.0765354078;15951.920240398724;15965.600859978478;16084.19579021056;14241.715165696629;14771.482998095014;14790.630234244123;14878.776818347733;15513.224338783128;4700.106979673851;4758.738269395182;4764.547368000069;4782.545658162469;4901.1177987962155;85497.57251213703;86085.56957215237;86729;87182.56408717915;88242;12403.379831008413;12487.93756031214;12539.623811285675;12671.429785659668;12948.09363344557;23537.529249414914;23544.411389715395;23776.524469510514;23874.164404245766;23961.52076958486;8844.911550884408;8852.336074794417;8942.821143577092;8948.328875334377;8958.10418958099;5020.523050310215;5138.922916156261;5209.505097015777;5227.895442091213;5273.736313184326;3594.5506811648543;3723.4600982857874;3731.7014638829373;3749.362608356586;3759.3984962405884;645.8385403649088;657.4477438951209;660.6168422315046;669.1668872254074;678.091357580844;685.7325642999234;713.6645776485044;738.4203400330822;766.4136935244543;803.5018288661067;10069.446180459998;10244.199512799016;10397.376157430579;10467.011267859083;10657.786844263072;654.3816093791417;659.544914009332;683.4498228925663;718.7448455798221;740.5519660605256;4544.977275113603;4751.881202969884;4800.609852684927;4846.7818948147415;4857.514248575114;126812;128119;131652;132303;135895;112059;112376;113030.43484782524;115432.42283788527;116094.4195279035;81827.5908620465;82899.58550207331;97091;100501.49749251206;101203;10178.949105254722;10526.78946421052;10609.522571484456;10862.674119776419;11274.605388811353;53109.20336195037;54015.45984540104;55102.72448637892;55526.444735552126;55704;70490;78363.60818195873;79930;81676.18323816686;84414.57792710997;13654.453821847015;13944.30278486092;14008.929955350159;14261.42954281817;14602.342894569558;16249.431269905497;16561.751573726164;17537.386191483238;17556.56108597244;17738.645227095643;14434.711305774035;14503.85496145025;14616.269186540843;15370;15447.691046179238;14705.970582059581;15206.847931520542;15213.847861521243;16358.427455039016;16405.179741012664;14263.500777472738;14417.7837332438;14443.85556144425;15048.6990260192;15603.765943510629;11767.88232117668;11901.706119334038;12418.627441176779;12476.937615312228;12478.688032799253;12109.515619375126;12195.268283902995;12284.324362160167;12322.32227227511;12576.434060467118 07:19:18 INFO - PROCESS | 3931 | __end_tp_report 07:19:18 INFO - PROCESS | 3931 | __start_cc_report 07:19:18 INFO - PROCESS | 3931 | _x_x_mozilla_cycle_collect,1091 07:19:18 INFO - PROCESS | 3931 | __end_cc_report 07:19:18 INFO - PROCESS | 3931 | __startTimestamp1472739558652__endTimestamp 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | ------- Summary: start ------- 07:19:18 INFO - PROCESS | 3931 | Number of tests: 6 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | [#0] dojo.html Cycles:165 Average:5722.82 Median:5960.58 stddev:655.03 (11.0%) stddev-sans-first:627.11 07:19:18 INFO - PROCESS | 3931 | Values: 3228.0 3242.5 3246.1 3273.8 3287.2 5619.7 5691.4 5700.4 5707.9 5708.4 5677.4 5709.5 5716.3 5726.8 5737.7 5152.9 5200.1 5211.1 5271.9 5288.9 5207.7 5313.3 5353.3 5366.5 5368.5 5195.1 5260.6 5277.5 5278.2 5368.2 5374.5 5387.4 5391.8 5395.5 5399.5 5136.9 5143.5 5205.4 5265.2 5266.2 5614.1 5622.8 5660.4 5716.7 5760.3 6179.2 6186.7 6194.8 6197.3 6200.5 5989.5 6122.8 6135.8 6148.9 6227.6 5789.4 5801.0 5813.9 5824.4 5830.1 6383.6 6389.2 6400.5 6401.1 6405.2 5878.9 5949.9 5971.9 5984.8 6109.0 6145.0 6146.9 6155.0 6155.3 6156.3 6114.1 6129.9 6137.8 6140.7 6143.8 5977.8 6070.9 6093.6 6102.3 6157.6 4679.3 4713.5 4714.3 4739.5 4754.8 4192.5 4205.4 4212.4 4228.4 4233.8 5626.9 5695.0 5707.9 5729.0 5833.1 6140.7 6145.4 6146.2 6147.8 6151.2 5691.2 5809.2 5810.8 5817.5 5837.1 5256.0 5303.8 5306.9 5342.1 5377.3 6401.6 6413.5 6417.3 6418.7 6426.2 5944.0 6128.8 6130.4 6192.1 6201.0 6312.6 6345.5 6371.4 6475.3 6485.7 6100.2 6107.0 6107.2 6111.0 6114.9 6005.9 6203.4 6206.2 6210.0 6210.6 5960.6 6015.4 6016.1 6067.4 6111.6 6017.8 6055.2 6069.1 6070.0 6070.6 5842.4 6010.5 6054.2 6071.2 6074.9 5975.7 6039.2 6045.5 6107.3 6118.6 5949.7 5956.6 5958.1 5965.5 5982.6 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | [#1] ext.html Cycles:180 Average:62296.03 Median:371271.50 stddev:119079.02 (32.1%) stddev-sans-first:119322.98 07:19:18 INFO - PROCESS | 3931 | Values: 605.9 611.4 641.7 642.9 667.9 36296.5 47960.0 49042.5 49092.5 49099.8 48857.5 49311.3 49322.5 49358.8 49412.8 8389.0 9086.5 9337.8 9337.8 10941.7 10695.7 11104.4 11105.8 11110.9 11113.1 11151.8 11215.2 11216.5 11221.7 11221.9 11129.3 11145.9 11186.1 11187.1 11190.8 10988.7 11039.5 11065.7 11074.1 11077.1 45537.0 45952.0 45976.3 46018.5 46020.0 3903.3 3923.5 4017.7 4125.7 4130.5 35200.3 35204.3 35341.5 35405.3 35454.5 4577.9 4616.2 4620.9 4642.5 4689.7 358137.0 358573.0 358767.0 360998.0 361233.0 65462.3 65808.0 65910.3 66039.0 66083.0 40062.2 41184.8 41231.6 43215.1 69245.9 19532.5 19830.6 19845.7 19845.8 19846.9 13134.5 14989.1 15455.7 15656.8 15718.9 21298.8 21325.8 21410.7 21483.0 21503.0 7463.1 7503.2 7617.3 7749.6 7761.5 1009.7 1059.9 1098.9 1114.2 1169.6 35587.1 36172.3 36492.0 36812.1 36841.3 1136.3 1141.9 1172.7 1185.2 1229.6 18199.6 18942.6 19004.8 19160.4 19203.5 576574.0 583021.0 585886.0 586726.0 588457.0 65572.3 66265.7 66287.7 66381.3 66391.7 1349.7 1381.0 1383.3 1384.5 1447.7 26357.5 33555.5 34055.3 35786.0 35837.8 60071.0 60581.0 60609.7 60683.7 60684.4 368361.0 368612.0 370206.0 370856.0 371687.0 28127.2 30685.5 31765.4 32010.0 32208.4 54043.0 54134.5 54437.5 54541.7 54958.0 45119.3 45704.3 46392.8 46777.5 48396.8 42373.6 45123.0 45203.3 45999.8 46628.3 44463.0 44934.8 45370.8 45632.0 46034.8 39148.0 40598.2 41128.6 41133.4 41185.0 30514.4 30791.8 30912.0 30994.8 31089.5 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | [#2] jquery.html Cycles:180 Average:30507.26 Median:25058.00 stddev:84749.65 (338.2%) stddev-sans-first:84957.27 07:19:18 INFO - PROCESS | 3931 | Values: 414.3 415.7 416.7 425.1 428.9 19351.3 20257.9 20953.7 21092.8 21261.7 20272.7 20440.3 20763.3 20892.9 21080.6 5783.0 6099.4 6101.6 6101.7 6106.8 5775.6 5985.7 5986.7 6016.7 6022.6 5987.7 5989.5 5991.1 5998.0 5998.8 5775.9 5956.0 5973.0 5974.7 5987.4 5289.5 5339.4 5382.8 5388.1 5390.1 24543.4 24788.8 25002.5 25113.5 25286.5 2724.8 2774.2 3079.3 3176.4 3213.0 15235.8 15255.4 15298.6 15653.7 15838.3 1980.1 2035.2 2045.9 2080.4 2122.5 78831.6 80350.0 82280.0 85397.6 85653.0 11421.7 11568.3 11665.2 11901.5 11919.3 16213.5 16218.8 16242.7 16254.5 16272.2 8638.0 8674.5 8714.1 8782.0 9014.1 8515.7 8550.0 8567.5 8763.2 8768.2 4961.8 4975.7 4986.2 4991.6 5077.0 1953.9 1964.0 2010.4 2078.2 2082.2 907.3 970.3 1025.9 1061.3 1081.8 20168.2 20338.9 21143.4 21202.6 21346.8 1014.1 1041.7 1067.7 1067.9 1120.1 10634.9 11639.7 11675.8 11684.0 11739.0 505079.0 514562.0 516359.0 518761.0 519755.0 59108.0 91653.0 95673.5 97937.0 101148.0 1031.9 1043.5 1063.7 1066.5 1067.9 17743.6 18060.0 18583.4 18718.6 18719.3 44586.8 45321.8 45369.3 45620.3 47439.1 61636.0 63652.0 64555.7 64995.7 65220.3 7579.7 8835.7 9113.6 9188.5 9228.3 13124.1 13655.9 13695.4 13769.9 14186.0 11884.4 12167.1 12310.8 12363.7 12552.7 12025.5 12174.0 12464.9 12477.2 12486.6 8774.5 11582.9 12055.3 12143.3 12586.3 8791.2 8907.6 8961.3 8974.0 9341.2 11248.4 11985.5 12043.2 12340.7 12449.0 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | [#3] mootools.html Cycles:180 Average:6334.95 Median:4552.41 stddev:3672.00 (80.7%) stddev-sans-first:3663.76 07:19:18 INFO - PROCESS | 3931 | Values: 1424.3 1508.0 1563.0 1691.5 1861.8 9169.3 9693.9 9703.1 9984.9 9997.2 9723.4 9836.9 9854.5 9856.9 9866.4 2522.4 2544.2 2551.0 2551.9 2552.1 2451.8 2530.8 2533.7 2534.7 2537.5 2476.9 2496.3 2504.1 2504.3 2507.1 2426.1 2426.3 2437.7 2470.6 2473.7 2427.2 2450.7 2451.2 2461.7 2473.6 9564.7 9737.6 9797.8 9800.9 9808.3 5657.5 5835.9 5841.9 5854.0 5869.5 3414.2 3428.6 3466.9 3485.7 3493.9 1022.2 1032.4 1047.9 1048.7 1057.4 13506.9 13529.5 13608.6 13618.0 13632.6 4716.9 4796.2 4849.1 4899.9 4972.6 10885.9 10898.2 10911.9 10919.3 10994.5 5543.9 5559.7 5563.1 5565.5 5566.0 4631.1 4810.2 5202.9 5210.0 5288.7 4192.4 4209.2 4213.0 4213.5 4246.6 1246.7 1257.9 1263.6 1271.0 1272.7 1044.4 1053.6 1061.6 1072.9 1073.3 6379.6 6424.5 6582.3 6694.8 6921.8 478.4 484.6 1043.7 1121.9 1160.1 4367.6 4408.8 4509.1 4595.8 4688.2 11355.5 11886.5 12134.8 12143.6 12344.3 11423.8 11928.9 11938.6 11942.8 11998.5 8820.3 8829.6 9068.0 9138.1 9145.8 6591.1 6733.8 6736.8 6748.5 6831.1 10983.8 11252.7 11258.7 11278.8 11325.0 10994.9 11365.2 11423.9 11426.7 11476.9 8579.2 8582.1 8668.6 8782.4 8784.2 9210.7 9333.5 9354.7 9360.0 9367.4 8281.3 8369.0 8433.7 8481.4 8516.5 8421.1 8473.0 8562.4 8612.7 8752.0 7533.6 7538.9 7569.9 7632.3 7640.1 5303.6 5365.8 5372.1 5633.5 5808.7 5600.0 5697.9 5737.4 5886.7 5984.9 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | [#4] prototype.html Cycles:180 Average:4772.67 Median:5384.06 stddev:1408.67 (26.2%) stddev-sans-first:1372.90 07:19:18 INFO - PROCESS | 3931 | Values: 347.1 350.5 355.1 357.1 357.9 5459.5 5466.8 5484.3 5491.8 5529.6 5383.7 5384.4 5391.2 5401.4 5417.9 4047.8 4235.6 4274.5 4293.3 4308.6 4243.6 4262.5 4275.8 4278.1 4286.7 4022.2 4235.6 4236.3 4241.7 4243.7 4094.3 4223.7 4229.9 4233.0 4244.6 3734.8 3745.5 3745.9 3750.1 3752.2 5072.3 5289.1 5290.8 5299.5 5302.4 4590.2 4804.8 4810.6 4811.2 4816.8 5616.4 5620.2 5622.4 5645.9 5648.1 3945.1 4070.3 4078.8 4089.5 4092.5 5845.6 5868.2 5870.7 5883.4 5896.9 4168.9 4178.4 4196.0 4211.7 4214.5 4087.2 4232.1 4249.9 4252.4 4267.0 4812.3 4834.2 4837.5 4838.4 4844.8 5711.6 5714.5 5725.7 5726.7 5733.5 1437.1 1463.2 1469.5 1473.2 1476.9 930.3 945.7 947.5 956.7 957.3 4898.2 4924.6 4934.9 4939.2 4940.4 4835.0 5145.4 5156.6 5163.5 5189.4 4887.8 4941.2 4955.2 5265.2 5350.4 3075.5 3085.2 3085.4 3101.4 3115.4 6066.5 6073.3 6073.9 6093.8 6103.2 5692.3 5960.5 5996.9 5997.9 5998.8 6143.0 6144.0 6145.4 6153.1 6177.8 5662.7 5914.9 5944.7 5956.0 5964.4 5847.8 5849.7 5861.4 5886.5 5895.8 5819.2 6098.7 6103.3 6174.0 6177.2 4163.4 4191.7 4226.2 4233.8 4235.5 5891.7 5912.7 5917.9 5969.0 5979.0 5582.6 5737.2 5784.0 5824.6 5830.9 5701.2 5729.5 5729.6 5751.8 5756.1 5714.9 5718.2 5739.9 5741.2 5751.3 5580.5 5756.3 5803.6 5803.7 5823.5 5655.8 5656.0 5658.6 5683.4 5683.6 07:19:18 INFO - PROCESS | 3931 | 07:19:18 INFO - PROCESS | 3931 | [#5] yui.html Cycles:180 Average:23034.90 Median:23917.84 stddev:33543.72 (140.2%) stddev-sans-first:33596.49 07:19:18 INFO - PROCESS | 3931 | Values: 858.6 888.5 899.7 905.2 951.1 15732.8 17200.5 17260.1 17267.4 17297.3 15953.8 16433.8 16728.6 16760.4 16769.7 878.3 904.8 907.9 908.4 909.0 745.9 867.8 893.8 899.6 900.0 950.5 952.0 953.1 953.2 953.8 943.2 944.5 944.7 944.7 946.6 561.6 669.5 687.5 689.9 693.6 14351.0 14387.7 14402.3 14426.6 14505.5 14863.9 15391.1 15951.9 15965.6 16084.2 14241.7 14771.5 14790.6 14878.8 15513.2 4700.1 4758.7 4764.5 4782.5 4901.1 85497.6 86085.6 86729.0 87182.6 88242.0 12403.4 12487.9 12539.6 12671.4 12948.1 23537.5 23544.4 23776.5 23874.2 23961.5 8844.9 8852.3 8942.8 8948.3 8958.1 5020.5 5138.9 5209.5 5227.9 5273.7 3594.6 3723.5 3731.7 3749.4 3759.4 645.8 657.4 660.6 669.2 678.1 685.7 713.7 738.4 766.4 803.5 10069.4 10244.2 10397.4 10467.0 10657.8 654.4 659.5 683.4 718.7 740.6 4545.0 4751.9 4800.6 4846.8 4857.5 126812.0 128119.0 131652.0 132303.0 135895.0 112059.0 112376.0 113030.4 115432.4 116094.4 81827.6 82899.6 97091.0 100501.5 101203.0 10178.9 10526.8 10609.5 10862.7 11274.6 53109.2 54015.5 55102.7 55526.4 55704.0 70490.0 78363.6 79930.0 81676.2 84414.6 13654.5 13944.3 14008.9 14261.4 14602.3 16249.4 16561.8 17537.4 17556.6 17738.6 14434.7 14503.9 14616.3 15370.0 15447.7 14706.0 15206.8 15213.8 16358.4 16405.2 14263.5 14417.8 14443.9 15048.7 15603.8 11767.9 11901.7 12418.6 12476.9 12478.7 12109.5 12195.3 12284.3 12322.3 12576.4 07:19:18 INFO - PROCESS | 3931 | -------- Summary: end -------- 07:19:18 INFO - PROCESS | 3931 | 07:19:19 INFO - TEST-INFO | 3931: exit 0 07:19:19 INFO - TEST-OK | dromaeo_css | took 1113318ms 07:19:19 INFO - TEST-START | kraken 07:19:19 INFO - operating with platform_type : linux_ 07:19:19 INFO - Initialising browser for kraken test... 07:19:19 INFO - TEST-INFO | started process 4133 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoOAyGC/profile http://localhost:55909/getInfo.html) 07:19:23 INFO - PROCESS | 4133 | __metrics Screen width/height:1600/1200 07:19:23 INFO - PROCESS | 4133 | colorDepth:24 07:19:23 INFO - PROCESS | 4133 | Browser inner width/height: 1024/697 07:19:23 INFO - PROCESS | 4133 | __metrics 07:19:23 INFO - PROCESS | 4133 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:19:23 INFO - PROCESS | 4133 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:24 INFO - TEST-INFO | 4133: exit 0 07:19:24 INFO - Browser initialized. 07:19:24 INFO - Running cycle 1/1 for kraken test... 07:19:24 INFO - TEST-INFO | started process 4217 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoOAyGC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:36 INFO - PROCESS | 4217 | RSS: Main: 145379328 07:19:36 INFO - PROCESS | 4217 | 07:19:39 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55909/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:39 INFO - PROCESS | 4217 | RSS: Main: 191676416 07:19:39 INFO - PROCESS | 4217 | 07:19:42 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55909/tests/kraken/driver.html?testName=audio-dft) 07:19:43 INFO - PROCESS | 4217 | RSS: Main: 181510144 07:19:43 INFO - PROCESS | 4217 | 07:19:45 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55909/tests/kraken/driver.html?testName=audio-fft) 07:19:46 INFO - PROCESS | 4217 | RSS: Main: 269340672 07:19:46 INFO - PROCESS | 4217 | 07:19:48 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55909/tests/kraken/driver.html?testName=audio-oscillator) 07:19:49 INFO - PROCESS | 4217 | RSS: Main: 176070656 07:19:49 INFO - PROCESS | 4217 | 07:19:51 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55909/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:51 INFO - PROCESS | 4217 | RSS: Main: 176959488 07:19:51 INFO - PROCESS | 4217 | 07:19:54 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55909/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:55 INFO - PROCESS | 4217 | RSS: Main: 213250048 07:19:55 INFO - PROCESS | 4217 | 07:19:58 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55909/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:59 INFO - PROCESS | 4217 | RSS: Main: 211902464 07:19:59 INFO - PROCESS | 4217 | 07:20:02 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55909/tests/kraken/driver.html?testName=json-parse-financial) 07:20:02 INFO - PROCESS | 4217 | RSS: Main: 212205568 07:20:02 INFO - PROCESS | 4217 | 07:20:04 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55909/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:04 INFO - PROCESS | 4217 | RSS: Main: 175955968 07:20:04 INFO - PROCESS | 4217 | 07:20:06 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:07 INFO - PROCESS | 4217 | RSS: Main: 179818496 07:20:07 INFO - PROCESS | 4217 | 07:20:09 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:09 INFO - PROCESS | 4217 | RSS: Main: 186044416 07:20:09 INFO - PROCESS | 4217 | 07:20:12 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:13 INFO - PROCESS | 4217 | RSS: Main: 181424128 07:20:13 INFO - PROCESS | 4217 | 07:20:15 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:16 INFO - PROCESS | 4217 | RSS: Main: 183939072 07:20:16 INFO - PROCESS | 4217 | 07:20:17 INFO - PROCESS | 4217 | Cycle 1(1): loaded http://localhost:55909/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55909/tests/kraken/driver.html?testName=ai-astar) 07:20:18 INFO - PROCESS | 4217 | RSS: Main: 235708416 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | __start_tp_report 07:20:18 INFO - PROCESS | 4217 | _x_x_mozilla_page_load 07:20:18 INFO - PROCESS | 4217 | _x_x_mozilla_page_load_details 07:20:18 INFO - PROCESS | 4217 | |i|pagename|runs| 07:20:18 INFO - PROCESS | 4217 | |0;ai-astar;101;111;111;110;109;111;108;109;113;108 07:20:18 INFO - PROCESS | 4217 | |1;audio-beat-detection;143;118;116;190;117;117;138;120;168;115 07:20:18 INFO - PROCESS | 4217 | |2;audio-dft;161;161;159;162;160;160;161;160;160;160 07:20:18 INFO - PROCESS | 4217 | |3;audio-fft;75;76;75;112;75;76;86;75;102;75 07:20:18 INFO - PROCESS | 4217 | |4;audio-oscillator;83;83;123;83;82;84;116;83;83;119 07:20:18 INFO - PROCESS | 4217 | |5;imaging-gaussian-blur;117;116;116;116;116;117;117;117;116;116 07:20:18 INFO - PROCESS | 4217 | |6;imaging-darkroom;145;145;145;146;146;145;145;146;145;146 07:20:18 INFO - PROCESS | 4217 | |7;imaging-desaturate;94;94;95;94;94;95;94;95;95;95 07:20:18 INFO - PROCESS | 4217 | |8;json-parse-financial;50;50;51;50;50;52;50;49;49;50 07:20:18 INFO - PROCESS | 4217 | |9;json-stringify-tinderbox;62;61;59;60;60;60;60;61;60;61 07:20:18 INFO - PROCESS | 4217 | |10;stanford-crypto-aes;73;71;73;73;73;72;76;78;76;72 07:20:18 INFO - PROCESS | 4217 | |11;stanford-crypto-ccm;125;117;125;118;115;115;118;116;162;129 07:20:18 INFO - PROCESS | 4217 | |12;stanford-crypto-pbkdf2;153;152;154;154;157;155;154;150;153;151 07:20:18 INFO - PROCESS | 4217 | |13;stanford-crypto-sha256-iterative;62;63;63;60;60;64;62;61;60;59 07:20:18 INFO - PROCESS | 4217 | __end_tp_report 07:20:18 INFO - PROCESS | 4217 | __start_cc_report 07:20:18 INFO - PROCESS | 4217 | _x_x_mozilla_cycle_collect,1625 07:20:18 INFO - PROCESS | 4217 | __end_cc_report 07:20:18 INFO - PROCESS | 4217 | __startTimestamp1472739618146__endTimestamp 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | ------- Summary: start ------- 07:20:18 INFO - PROCESS | 4217 | Number of tests: 14 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.50 stddev:3.25 (2.9%) stddev-sans-first:1.66 07:20:18 INFO - PROCESS | 4217 | Values: 101.0 111.0 111.0 110.0 109.0 111.0 108.0 109.0 113.0 108.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:129.00 stddev:26.04 (20.2%) stddev-sans-first:27.43 07:20:18 INFO - PROCESS | 4217 | Values: 143.0 118.0 116.0 190.0 117.0 117.0 138.0 120.0 168.0 115.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 07:20:18 INFO - PROCESS | 4217 | Values: 161.0 161.0 159.0 162.0 160.0 160.0 161.0 160.0 160.0 160.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#3] audio-fft Cycles:10 Average:82.70 Median:75.00 stddev:13.45 (17.9%) stddev-sans-first:13.97 07:20:18 INFO - PROCESS | 4217 | Values: 75.0 76.0 75.0 112.0 75.0 76.0 86.0 75.0 102.0 75.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:83.00 stddev:17.63 (21.2%) stddev-sans-first:18.26 07:20:18 INFO - PROCESS | 4217 | Values: 83.0 83.0 123.0 83.0 82.0 84.0 116.0 83.0 83.0 119.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 07:20:18 INFO - PROCESS | 4217 | Values: 117.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:20:18 INFO - PROCESS | 4217 | Values: 145.0 145.0 145.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 07:20:18 INFO - PROCESS | 4217 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.88 (1.8%) stddev-sans-first:0.93 07:20:18 INFO - PROCESS | 4217 | Values: 50.0 50.0 51.0 50.0 50.0 52.0 50.0 49.0 49.0 50.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.84 (1.4%) stddev-sans-first:0.67 07:20:18 INFO - PROCESS | 4217 | Values: 62.0 61.0 59.0 60.0 60.0 60.0 60.0 61.0 60.0 61.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:73.00 stddev:2.21 (3.0%) stddev-sans-first:2.33 07:20:18 INFO - PROCESS | 4217 | Values: 73.0 71.0 73.0 73.0 73.0 72.0 76.0 78.0 76.0 72.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:121.50 stddev:14.21 (11.7%) stddev-sans-first:15.07 07:20:18 INFO - PROCESS | 4217 | Values: 125.0 117.0 125.0 118.0 115.0 115.0 118.0 116.0 162.0 129.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:154.00 stddev:2.00 (1.3%) stddev-sans-first:2.12 07:20:18 INFO - PROCESS | 4217 | Values: 153.0 152.0 154.0 154.0 157.0 155.0 154.0 150.0 153.0 151.0 07:20:18 INFO - PROCESS | 4217 | 07:20:18 INFO - PROCESS | 4217 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:62.00 stddev:1.65 (2.7%) stddev-sans-first:1.73 07:20:18 INFO - PROCESS | 4217 | Values: 62.0 63.0 63.0 60.0 60.0 64.0 62.0 61.0 60.0 59.0 07:20:18 INFO - PROCESS | 4217 | -------- Summary: end -------- 07:20:18 INFO - PROCESS | 4217 | 07:20:19 INFO - TEST-INFO | 4217: exit 0 07:20:19 INFO - TEST-OK | kraken | took 59915ms 07:20:19 INFO - SUITE-END | took 1173s 07:20:19 INFO - Completed test suite (00:19:33) 07:20:19 INFO - javascript benchmark 07:20:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3227.9677203227966, 3242.5136229565546, 3246.123546642406, 3273.819939903304, 3287.1946373138585, 5619.719014049297, 5691.430856914311, 5700.429957004298, 5707.857303567408, 5708.372079071299, 5677.37548869624, 5709.543236541077, 5716.28546431696, 5726.799562015331, 5737.684427356494, 5152.92270615941, 5200.08998425276, 5211.140161873286, 5271.947280527184, 5288.920666189991, 5207.713575753332, 5313.309269794921, 5353.250544923714, 5366.4633536646215, 5368.543673787733, 5195.142801437758, 5260.631755777097, 5277.47225277473, 5278.155495120782, 5368.248445217672, 5374.462553744614, 5387.353517577916, 5391.757370918318, 5395.487428694268, 5399.541039011689, 5136.897262054738, 5143.459936706629, 5205.427402985669, 5265.210218467222, 5266.1837415200325, 5614.073677843151, 5622.775088996435, 5660.405657405995, 5716.742746576372, 5760.279965004374, 6179.227596550431, 6186.721597528122, 6194.81415557534, 6197.349278325756, 6200.503959683215, 5989.520838332923, 6122.846928826815, 6135.753292002322, 6148.907766383508, 6227.595206311576, 5789.392113828029, 5801.0, 5813.883722325572, 5824.359320474744, 5830.125481177857, 6383.553151279366, 6389.169407976933, 6400.519961002943, 6401.103845461639, 6405.167328247391, 5878.882422351529, 5949.940500594938, 5971.940280597139, 5984.790532331434, 6109.0, 6144.969275153596, 6146.938530614637, 6155.0, 6155.261368635793, 6156.29202641702, 6114.0828875669, 6129.938700613026, 6137.754489820447, 6140.662263575457, 6143.84640383994, 5977.820665380045, 6070.878582428327, 6093.634381937098, 6102.267727872595, 6157.599756015845, 4679.251319788886, 4713.528647135259, 4714.316424118484, 4739.502352252964, 4754.762261886892, 4192.496900371975, 4205.3902184183125, 4212.389203565527, 4228.365745138192, 4233.7671428071135, 5626.915596266059, 5695.0, 5707.942920570741, 5729.0260655688635, 5833.066709326487, 6140.662263575547, 6145.4161854623735, 6146.200993870768, 6147.84630384244, 6151.169592105098, 5691.174779656926, 5809.186713860063, 5810.82567522975, 5817.534597232211, 5837.124431335249, 5256.027634887558, 5303.761330740049, 5306.946930530646, 5342.1452567589, 5377.300950876361, 6401.583897046677, 6413.518986076063, 6417.294097649255, 6418.711157997901, 6426.164598602245, 5944.048952167633, 6128.816135515942, 6130.448259656652, 6192.102145189013, 6201.0, 6312.621242725451, 6345.524085693499, 6371.362863713592, 6475.287718351072, 6485.675716214076, 6100.206973093647, 6106.969465152646, 6107.175531303217, 6110.969445152746, 6114.877702446015, 6005.879882402415, 6203.410675985774, 6206.193194884817, 6210.0, 6210.5652604317265, 5960.5827592068135, 6015.39846015395, 6016.067509536029, 6067.393260674074, 6111.572189946839, 6017.759289628366, 6055.182550355646, 6069.0592958091565, 6070.0, 6070.575059745775, 5842.444967728059, 6010.519158467399, 6054.212952316171, 6071.241094863142, 6074.939250607437, 5975.671338076448, 6039.214902062879, 6045.516358691206, 6107.303096926935, 6118.583064974548, 5949.672767997802, 5956.612820166675, 5958.136070269873, 5965.492933100735, 5982.551308651781], "value": 5677.658407771973, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.9454649081582, 611.3947192279643, 641.7112299465242, 642.9067785171151, 667.896476046213, 36296.45555316669, 47960.0, 49042.509574904194, 49092.50907490924, 49099.75450122753, 48857.51142488574, 49311.26033109497, 49322.50677493233, 49358.75320623401, 49412.75293623536, 8388.958055209716, 9086.500242486678, 9337.766555836091, 9337.766555836124, 10941.726456838604, 10695.679129626124, 11104.44477776112, 11105.777884442346, 11110.944445277762, 11113.055390291835, 11151.832722509169, 11215.214934954556, 11216.49525771338, 11221.663350099469, 11221.887781122166, 11129.332240065582, 11145.944270278678, 11186.105111591054, 11187.105031597453, 11190.832137517944, 10988.725281868017, 11039.503222355013, 11065.723356916142, 11074.114070874311, 11077.058450031756, 45537.0, 45952.0, 45976.3103553447, 46018.539814602096, 46020.0, 3903.2974064668347, 3923.509561304837, 4017.658499027586, 4125.66994640428, 4130.504339479252, 35200.27020583329, 35204.29591408183, 35341.46987795185, 35405.29189416197, 35454.468182977274, 4577.885552861172, 4616.169089563878, 4620.861374158781, 4642.466116396624, 4689.742064186468, 358137.0, 358573.0, 358767.0, 360998.0, 361233.0, 65462.345376546575, 65808.0, 65910.34089659138, 66039.0, 66083.0, 40062.1987560253, 41184.794076030026, 41231.58768412277, 43215.13569728588, 69245.88393522304, 19532.51168720765, 19830.603387932162, 19845.702314465296, 19845.801541984685, 19846.90076549608, 13134.474621015244, 14989.100653960797, 15455.690886182438, 15656.765148522782, 15718.921405393128, 21298.78701212968, 21325.786742132692, 21410.678839817414, 21483.0, 21503.0, 7463.067116610424, 7503.174650788409, 7617.314441700273, 7749.612519374122, 7761.534307941542, 1009.7425156585024, 1059.897706385366, 1098.9120870330355, 1114.169943392178, 1169.6257197696825, 35587.110322241635, 36172.27655446929, 36492.0, 36812.079698007765, 36841.263174736356, 1136.2841409911703, 1141.8915203055697, 1172.6892373521023, 1185.1940680337452, 1229.624964385853, 18199.63600727978, 18942.62114757697, 19004.80995190058, 19160.425187244404, 19203.519912002033, 576574.0, 583021.0, 585886.0, 586726.0, 588457.0, 65572.34427655757, 66265.6686716573, 66287.66856165689, 66381.33618663848, 66391.66804165948, 1349.6828245362333, 1381.040177076922, 1383.3083458270864, 1384.536180379584, 1447.7466443372368, 26357.472850542497, 33555.496667549516, 34055.31889362248, 35786.0, 35837.82081089578, 60071.0, 60581.0, 60609.69695151672, 60683.69658151681, 60684.393156069644, 368361.0, 368612.0, 370206.0, 370856.0, 371687.0, 28127.156185314474, 30685.53971690382, 31765.36469270648, 32010.039698809072, 32208.35583288368, 54043.0, 54134.45865541294, 54437.45562544324, 54541.72729136488, 54958.0, 45119.32321015253, 45704.31443528416, 46392.76803615961, 46777.53222467732, 48396.758016211104, 42373.576264238196, 45123.0, 45203.32195017012, 45999.77000114978, 46628.30057549071, 44463.0, 44934.775326124465, 45370.773146135376, 45632.0, 46034.76982615065, 39148.0, 40598.1880362397, 41128.58871411248, 41133.35466581303, 41185.0, 30514.389712205186, 30791.84604076965, 30912.0, 30994.845025774728, 31089.53365699471], "value": 22068.627891492197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.29156142995475, 415.7463946992334, 416.7207970659659, 425.11151692961715, 428.86705121412365, 19351.322703705373, 20257.898710506444, 20953.68569471459, 21092.789072109274, 21261.681074783854, 20272.695909561367, 20440.28459003935, 20763.273285435014, 20892.89553552234, 21080.578388432223, 5782.971085144568, 6099.359567245442, 6101.572889897709, 6101.694915254242, 6106.816795496142, 5775.5957083004205, 5985.700714964256, 5986.730597123118, 6016.7292471838655, 6022.578419510636, 5987.730552125143, 5989.490893274055, 5991.071383935497, 5998.0, 5998.760049598032, 5775.913361299563, 5956.0172571525645, 5972.970135149319, 5974.731137098797, 5987.371326010793, 5289.47105289468, 5339.439358867302, 5382.838514844561, 5388.110961691316, 5390.056740070511, 24543.38641533958, 24788.752112478825, 25002.49995000108, 25113.497730045296, 25286.49427011468, 2724.7956403269636, 2774.209350335165, 3079.276370053058, 3176.444122278592, 3213.0, 15235.771463427947, 15255.38978440861, 15298.617534561614, 15653.686926261526, 15838.287277072559, 1980.12874335292, 2035.2062695548748, 2045.887476188809, 2080.38628604562, 2122.4587730128874, 78831.605841971, 80349.99999999942, 82280.0, 85397.57301213517, 85653.0, 11421.657350279505, 11568.305901645928, 11665.183437159318, 11901.464434100486, 11919.284842909454, 16213.513594592181, 16218.75671864923, 16242.675146497004, 16254.51236462908, 16272.186390680656, 8638.0, 8674.522901240367, 8714.128587141362, 8782.0, 9014.143656352637, 8515.701950431705, 8550.0, 8567.528785916835, 8763.211310982042, 8768.210861022411, 4961.82633607822, 4975.726335051607, 4986.227134794112, 4991.550760431579, 5076.974615126975, 1953.89253591051, 1963.9803601964084, 2010.3615553323993, 2078.1791192478886, 2082.1671331467232, 907.3421769217265, 970.3062310448064, 1025.8512515685184, 1061.3366645846345, 1081.7620123572801, 20168.19327226924, 20338.898305508377, 21143.365699029055, 21202.575948480942, 21346.78653213479, 1014.1075853249093, 1041.7083216699336, 1067.7170549804152, 1067.8558394616782, 1120.0815331428314, 10634.946825265824, 11639.6508104757, 11675.766484670428, 11684.0, 11739.0, 505079.0, 514562.0, 516359.0, 518761.0, 519755.0, 59108.0, 91653.0, 95673.52163239139, 97937.0, 101148.0, 1031.8916513765946, 1043.4939054558533, 1063.691529456449, 1066.4720963123305, 1067.8932106789414, 17743.55641108931, 18060.0, 18583.44249672512, 18718.6256274871, 18719.251229951195, 44586.77706611446, 45321.77339113283, 45369.319460207465, 45620.315695265264, 47439.05121897474, 61636.0, 63652.0, 64555.677221613594, 64995.67502162459, 65220.347796521426, 7579.696812127453, 8835.734927952171, 9113.589888455168, 9188.540572971191, 9228.307876909124, 13124.146930449871, 13655.86344136546, 13695.383707733376, 13769.862301376857, 14186.0, 11884.405779711153, 12167.087468439726, 12310.753784924073, 12363.745109278185, 12552.74894502123, 12025.4588543514, 12174.0, 12464.937675311565, 12477.188982716094, 12486.562970295996, 8774.517401542977, 11582.88417115818, 12055.336956467478, 12143.332116733665, 12586.307753073668, 8791.208791208823, 8907.643694252158, 8961.283097352327, 8974.01285858542, 9341.20599749029, 11248.381339026431, 11985.520579176735, 12043.217190882564, 12340.691482713004, 12449.0], "value": 9821.148729089444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.287856071964, 1507.9924600376996, 1562.9843701562984, 1691.5179173935423, 1861.7952025277218, 9169.312301577384, 9693.903060969396, 9703.07820757028, 9984.900150998488, 9997.15024222942, 9723.416595004304, 9836.901630983688, 9854.507274636275, 9856.901430985688, 9866.35868668536, 2522.4198434360064, 2544.2494464133033, 2550.961735573968, 2551.8851651675627, 2552.068494999321, 2451.816113791464, 2530.772230499255, 2533.695956485215, 2534.733852945433, 2537.5305568469894, 2476.938076548083, 2496.3010357100043, 2504.1485894795765, 2504.26124293333, 2507.084914006393, 2426.053839002799, 2426.3085020769086, 2437.6587277781123, 2470.5800013997664, 2473.6784218051716, 2427.174760581393, 2450.6691596634405, 2451.1788550835313, 2461.692288463942, 2473.567125752986, 9564.713058608253, 9737.56180971858, 9797.80404391908, 9800.90199098014, 9808.264380171515, 5657.490825825655, 5835.883282334372, 5841.85395365115, 5853.970730146365, 5869.50109240715, 3414.163529935182, 3428.5714285714284, 3466.9133271668156, 3485.721142308609, 3493.8951831445097, 1022.2333250062453, 1032.4063663393579, 1047.9318844275174, 1048.6801525534709, 1057.3867157048894, 13506.932465337708, 13529.45882164722, 13608.591742247749, 13618.046736728335, 13632.59102226925, 4716.858494245178, 4796.184648609745, 4849.078675051729, 4899.902001959941, 4972.602191824645, 10885.891141088647, 10898.182636302308, 10911.945440272748, 10919.290246133976, 10994.450277486254, 5543.861403464945, 5559.6664200148125, 5563.13771365439, 5565.526930210896, 5565.972170139124, 4631.120087183492, 4810.230363141881, 5202.895942081137, 5210.0, 5288.735563221823, 4192.350185721218, 4209.2423363794815, 4212.978935105366, 4213.494380674278, 4246.554111818276, 1246.650937737435, 1257.9245245285313, 1263.5767018048873, 1271.0340141492532, 1272.7072773262016, 1044.4412239451856, 1053.6259627832162, 1061.601899287767, 1072.892710728921, 1073.3291692692067, 6379.585326953733, 6424.518161137933, 6582.341765823379, 6694.765683201064, 6921.792346229621, 478.4450037955954, 484.6074679509608, 1043.7286305560608, 1121.9439028048564, 1160.0893298760602, 4367.563243675607, 4408.823647054082, 4509.053098849278, 4595.770211489479, 4688.249880019181, 11355.545778168946, 11886.465109070112, 12134.75730485403, 12143.635690929286, 12344.259344439362, 11423.771524569462, 11928.940355298168, 11938.64184074479, 11942.820857686967, 11998.460069296903, 8820.338474614302, 8829.646814127364, 9067.954660226656, 9138.13187747163, 9145.77135571616, 6591.077249185022, 6733.831654208684, 6736.79789606293, 6748.460123190232, 6831.146106736658, 10983.835242471529, 11252.66242012741, 11258.718532036764, 11278.830817537579, 11325.0, 10994.890051099386, 11365.204435689753, 11423.885761142614, 11426.714332141762, 11476.942615286871, 8579.227869491775, 8582.055973842997, 8668.566571671518, 8782.385233033627, 8784.209421152127, 9210.723678289662, 9333.48665823359, 9354.672586459714, 9360.0, 9367.437953722798, 8281.29608983243, 8368.958155209428, 8433.746987590383, 8481.40630155883, 8516.531590762568, 8421.07368189511, 8472.957635211784, 8562.443441176303, 8612.698555550525, 8752.0, 7533.6233188339265, 7538.924610753822, 7569.924300757142, 7632.274933881273, 7640.121386040454, 5303.628745987899, 5365.785368585213, 5372.140457526777, 5633.521150702236, 5808.7095645218415, 5600.0, 5697.857553561194, 5737.426257374229, 5886.676232807065, 5984.8803023940145], "value": 4988.970148027555, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.06812209218253, 350.5372907761755, 355.1069061310802, 357.11257525050246, 357.91410061585225, 5459.535939445147, 5466.781328746845, 5484.341878974524, 5491.835244942648, 5529.640573362729, 5383.67697938124, 5384.434634363394, 5391.16436952273, 5401.351837779472, 5417.918731219025, 4047.817848196838, 4235.618794308527, 4274.487061552602, 4293.29522400887, 4308.590683885034, 4243.554426785189, 4262.531121576624, 4275.764832934187, 4278.117628243578, 4286.721363111404, 4022.1955608878193, 4235.555266696999, 4236.343366778139, 4241.660667146621, 4243.745375277478, 4094.324436467979, 4223.669544093601, 4229.936550951738, 4232.978835105836, 4244.5967633075115, 3734.7945862977526, 3745.4756334113245, 3745.8501659933568, 3750.081230098616, 3752.155764952891, 5072.289879416885, 5289.127293996525, 5290.841274761725, 5299.523042926116, 5302.443243459458, 4590.150822097922, 4804.7837847296605, 4810.5670489656095, 4811.182099043171, 4816.759162041919, 5616.433564686413, 5620.241267428885, 5622.381538030813, 5645.887082258332, 5648.124540696199, 3945.1320709443876, 4070.287699652579, 4078.796060197008, 4089.488813898263, 4092.5498195198506, 5845.561582881301, 5868.207791948075, 5870.706464676792, 5883.411658834168, 5896.852578685567, 4168.916621667549, 4178.373244013423, 4196.0, 4211.7262377945335, 4214.545981636331, 4087.2234275487563, 4232.111256636079, 4249.851255206053, 4252.425922500484, 4266.978665106654, 4812.302216178635, 4834.244480645998, 4837.467878533358, 4838.443578988461, 4844.8304309349005, 5711.571632127524, 5714.542836573064, 5725.656460612293, 5726.742296596663, 5733.541316694655, 1437.1018113678952, 1463.1806188534454, 1469.4636457692866, 1473.1676602719429, 1476.9483068092566, 930.2557953637063, 945.7210123013728, 947.4646824544111, 956.712986104166, 957.2916042128775, 4898.24077268024, 4924.581410580069, 4934.8896498287595, 4939.234418665113, 4940.431850337257, 4834.95165048352, 5145.423930368358, 5156.587473002151, 5163.457836927144, 5189.44316704987, 4887.802488390367, 4941.209406495015, 4955.231939049453, 5265.236540701653, 5350.411454739976, 3075.5232938894505, 3085.228692826793, 3085.3520760640517, 3101.42623614632, 3115.3613509230554, 6066.45401913821, 6073.271207455045, 6073.878522429527, 6093.756249750049, 6103.23709536308, 5692.31692196939, 5960.4635582797755, 5996.910046349309, 5997.940020599738, 5998.760049598054, 6143.017117261216, 6143.96928015357, 6145.385461453819, 6153.138560601519, 6177.752889884534, 5662.688552129673, 5914.911276330772, 5944.673042982505, 5956.0, 5964.373740757158, 5847.8245652630485, 5849.678267695318, 5861.413858614275, 5886.470217680429, 5895.793647222326, 5819.243498345358, 6098.725557349841, 6103.337997201644, 6174.048608890404, 6177.227846519185, 4163.354680024601, 4191.685623578305, 4226.218149642326, 4233.830646774218, 4235.534091249899, 5891.675957822361, 5912.67480288571, 5917.911231331447, 5969.0, 5978.970105149447, 5582.637128586629, 5737.196792448977, 5784.0, 5824.563157763269, 5830.941690583039, 5701.230333904869, 5729.455701708331, 5729.598928075162, 5751.827445176651, 5756.121939030485, 5714.885597308485, 5718.199452076796, 5739.8565035874435, 5741.224934633771, 5751.338596061507, 5580.493266818017, 5756.280464941882, 5803.593748437569, 5803.680797556175, 5823.534117270524, 5655.830325090254, 5655.971720141373, 5658.632188907708, 5683.431656834449, 5683.630564013326], "value": 4328.330786948604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [858.6093327535972, 888.4624801994793, 899.7314678108183, 905.194377004466, 951.0584521323891, 15732.764008539882, 17200.483985480423, 17260.136993150354, 17267.39564115256, 17297.30810767568, 15953.84046159538, 16433.83566164338, 16728.58178545534, 16760.41338553151, 16769.748453773173, 878.3149143667918, 904.8280826642949, 907.9273658107336, 908.3550679017906, 908.9590968406437, 745.9104907411119, 867.839449701804, 893.7676204186894, 899.6356475627381, 899.95050272235, 950.5199874063622, 951.980960380792, 953.1231267234126, 953.2040745977083, 953.7949340891707, 943.2218419803621, 944.5230158769812, 944.655200851691, 944.7118628818208, 946.5787724462617, 561.5872333834628, 669.5380187670492, 687.4912564702134, 689.9103116594861, 693.5873155472486, 14351.0, 14387.71224575513, 14402.279886005657, 14426.639334016734, 14505.492307769178, 14863.925680371636, 15391.0765354078, 15951.920240398724, 15965.600859978478, 16084.19579021056, 14241.715165696629, 14771.482998095014, 14790.630234244123, 14878.776818347733, 15513.224338783128, 4700.106979673851, 4758.738269395182, 4764.547368000069, 4782.545658162469, 4901.1177987962155, 85497.57251213703, 86085.56957215237, 86729.0, 87182.56408717915, 88242.0, 12403.379831008413, 12487.93756031214, 12539.623811285675, 12671.429785659668, 12948.09363344557, 23537.529249414914, 23544.411389715395, 23776.524469510514, 23874.164404245766, 23961.52076958486, 8844.911550884408, 8852.336074794417, 8942.821143577092, 8948.328875334377, 8958.10418958099, 5020.523050310215, 5138.922916156261, 5209.505097015777, 5227.895442091213, 5273.736313184326, 3594.5506811648543, 3723.4600982857874, 3731.7014638829373, 3749.362608356586, 3759.3984962405884, 645.8385403649088, 657.4477438951209, 660.6168422315046, 669.1668872254074, 678.091357580844, 685.7325642999234, 713.6645776485044, 738.4203400330822, 766.4136935244543, 803.5018288661067, 10069.446180459998, 10244.199512799016, 10397.376157430579, 10467.011267859083, 10657.786844263072, 654.3816093791417, 659.544914009332, 683.4498228925663, 718.7448455798221, 740.5519660605256, 4544.977275113603, 4751.881202969884, 4800.609852684927, 4846.7818948147415, 4857.514248575114, 126812.0, 128119.0, 131652.0, 132303.0, 135895.0, 112059.0, 112376.0, 113030.43484782524, 115432.42283788527, 116094.4195279035, 81827.5908620465, 82899.58550207331, 97091.0, 100501.49749251206, 101203.0, 10178.949105254722, 10526.78946421052, 10609.522571484456, 10862.674119776419, 11274.605388811353, 53109.20336195037, 54015.45984540104, 55102.72448637892, 55526.444735552126, 55704.0, 70490.0, 78363.60818195873, 79930.0, 81676.18323816686, 84414.57792710997, 13654.453821847015, 13944.30278486092, 14008.929955350159, 14261.42954281817, 14602.342894569558, 16249.431269905497, 16561.751573726164, 17537.386191483238, 17556.56108597244, 17738.645227095643, 14434.711305774035, 14503.85496145025, 14616.269186540843, 15370.0, 15447.691046179238, 14705.970582059581, 15206.847931520542, 15213.847861521243, 16358.427455039016, 16405.179741012664, 14263.500777472738, 14417.7837332438, 14443.85556144425, 15048.6990260192, 15603.765943510629, 11767.88232117668, 11901.706119334038, 12418.627441176779, 12476.937615312228, 12478.688032799253, 12109.515619375126, 12195.268283902995, 12284.324362160167, 12322.32227227511, 12576.434060467118], "value": 8277.479303643553, "unit": "score"}], "name": "dromaeo_css", "value": 7769.567865011545, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 111.0, 110.0, 109.0, 111.0, 108.0, 109.0, 113.0, 108.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 116.0, 190.0, 117.0, 117.0, 138.0, 120.0, 168.0, 115.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 159.0, 162.0, 160.0, 160.0, 161.0, 160.0, 160.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 112.0, 75.0, 76.0, 86.0, 75.0, 102.0, 75.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 123.0, 83.0, 82.0, 84.0, 116.0, 83.0, 83.0, 119.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 50.0, 52.0, 50.0, 49.0, 49.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 59.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 73.0, 73.0, 73.0, 72.0, 76.0, 78.0, 76.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 117.0, 125.0, 118.0, 115.0, 115.0, 118.0, 116.0, 162.0, 129.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 154.0, 154.0, 157.0, 155.0, 154.0, 150.0, 153.0, 151.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 60.0, 60.0, 64.0, 62.0, 61.0, 60.0, 59.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1459.5, "alertThreshold": 2.0}]} 07:20:19 INFO - Return code: 0 07:20:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:19 INFO - # TBPL SUCCESS # 07:20:19 INFO - Running post-action listener: _resource_record_post_action 07:20:19 INFO - [mozharness: 2016-09-01 14:20:19.449801Z] Finished run-tests step (success) 07:20:19 INFO - Running post-run listener: _resource_record_post_run 07:20:19 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 329854976; Read time: 12; Write time: 285576 07:20:19 INFO - TinderboxPrint: CPU usage
12.9% 07:20:19 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 12 07:20:19 INFO - TinderboxPrint: I/O write bytes / time
329,854,976 / 285,576 07:20:19 INFO - TinderboxPrint: CPU idle
8,269.1 (87.1%) 07:20:19 INFO - TinderboxPrint: CPU user
1,182.5 (12.4%) 07:20:19 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:19 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78704640; Read time: 0; Write time: 109224 07:20:19 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 250535936; Read time: 12; Write time: 176348 07:20:20 INFO - Running post-run listener: _upload_blobber_files 07:20:20 INFO - Blob upload gear active. 07:20:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:20 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 07:20:20 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:20:20 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:20:20 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:20:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:20:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:21 INFO - (blobuploader) - INFO - Done attempting. 07:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:20:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:22 INFO - (blobuploader) - INFO - Done attempting. 07:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:33 INFO - (blobuploader) - INFO - Done attempting. 07:20:33 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:33 INFO - Return code: 0 07:20:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06a12244a1db9707fb45f15ec83ae8aec724dde0b37cba7b79ddac14599e75354100b78e4237521a17fceec4744b139045af77a4f5dc8cd9f1b1d4ab3c2e457b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8cf5e85a5c36da36aff02141f88de90f6fdf0ac98002f9f4fe2d1fa64a010fb0de323b232d69d51ba1317bd1478c2a8575967401af76b49d9ac38f057e7d1025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f18e11a687d465a22ba1b2933f1bb256824860e94eac9b5ca5b16025af9cc048de15417880cb3085829eb2e3104b65a15198d06dc6877ed66f9590f151838764"} 07:20:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:33 INFO - Contents: 07:20:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06a12244a1db9707fb45f15ec83ae8aec724dde0b37cba7b79ddac14599e75354100b78e4237521a17fceec4744b139045af77a4f5dc8cd9f1b1d4ab3c2e457b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8cf5e85a5c36da36aff02141f88de90f6fdf0ac98002f9f4fe2d1fa64a010fb0de323b232d69d51ba1317bd1478c2a8575967401af76b49d9ac38f057e7d1025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f18e11a687d465a22ba1b2933f1bb256824860e94eac9b5ca5b16025af9cc048de15417880cb3085829eb2e3104b65a15198d06dc6877ed66f9590f151838764"} 07:20:33 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:33 INFO - Copying logs to upload dir... 07:20:33 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1685.544029 ========= master_lag: 0.69 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-01 07:20:33.404180) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:33.410074) ========= 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=62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06a12244a1db9707fb45f15ec83ae8aec724dde0b37cba7b79ddac14599e75354100b78e4237521a17fceec4744b139045af77a4f5dc8cd9f1b1d4ab3c2e457b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8cf5e85a5c36da36aff02141f88de90f6fdf0ac98002f9f4fe2d1fa64a010fb0de323b232d69d51ba1317bd1478c2a8575967401af76b49d9ac38f057e7d1025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f18e11a687d465a22ba1b2933f1bb256824860e94eac9b5ca5b16025af9cc048de15417880cb3085829eb2e3104b65a15198d06dc6877ed66f9590f151838764"} build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06a12244a1db9707fb45f15ec83ae8aec724dde0b37cba7b79ddac14599e75354100b78e4237521a17fceec4744b139045af77a4f5dc8cd9f1b1d4ab3c2e457b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8cf5e85a5c36da36aff02141f88de90f6fdf0ac98002f9f4fe2d1fa64a010fb0de323b232d69d51ba1317bd1478c2a8575967401af76b49d9ac38f057e7d1025", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f18e11a687d465a22ba1b2933f1bb256824860e94eac9b5ca5b16025af9cc048de15417880cb3085829eb2e3104b65a15198d06dc6877ed66f9590f151838764"}' ========= master_lag: 2.49 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:35.907529) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-09-01 07:20:35.907874) ========= 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=62c568580acbfdaac3eec7be000001dc-1472737044.783644-475754161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 10.51 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2016-09-01 07:20:46.422055) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:46.422368) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:46.858814) ========= ========= Total master_lag: 13.88 =========