builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1472603068.11 results: success (0) buildid: 20160830142920 builduid: 8885bb9facb64463968fa650c5b59878 revision: 16676fabf4c6da9a46ba08a408b87e37927573b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.108319) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.109006) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.109291) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.144157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.144623) ========= 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=cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 17:24:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.8M=0s 2016-08-30 17:24:28 (66.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139004 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.303655) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.303980) ========= 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=cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023191 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:28.355973) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 17:24:28.356388) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 16676fabf4c6da9a46ba08a408b87e37927573b5 --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 16676fabf4c6da9a46ba08a408b87e37927573b5 --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=cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 17:24:28,864 truncating revision to first 12 chars 2016-08-30 17:24:28,864 Setting DEBUG logging. 2016-08-30 17:24:28,864 attempt 1/10 2016-08-30 17:24:28,864 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/16676fabf4c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 17:24:30,002 unpacking tar archive at: mozilla-aurora-16676fabf4c6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.391905 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 17:24:29.769780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:29.770102) ========= script_repo_revision: 16676fabf4c6da9a46ba08a408b87e37927573b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:29.770501) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:29.770779) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 17:24:29.787853) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-30 17:24:29.788192) ========= /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=cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:24:30 INFO - MultiFileLogger online at 20160830 17:24:30 in /builds/slave/test 17:24:30 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 17:24:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:24:30 INFO - {'append_to_log': False, 17:24:30 INFO - 'base_work_dir': '/builds/slave/test', 17:24:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:24:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:24:30 INFO - 'branch': 'Mozilla-Aurora', 17:24:30 INFO - 'buildbot_json_path': 'buildprops.json', 17:24:30 INFO - 'config_files': ('talos/linux_config.py',), 17:24:30 INFO - 'default_actions': ('clobber', 17:24:30 INFO - 'read-buildbot-config', 17:24:30 INFO - 'download-and-extract', 17:24:30 INFO - 'populate-webroot', 17:24:30 INFO - 'create-virtualenv', 17:24:30 INFO - 'install', 17:24:30 INFO - 'run-tests'), 17:24:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:24:30 INFO - 'download_minidump_stackwalk': True, 17:24:30 INFO - 'download_symbols': 'ondemand', 17:24:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:24:30 INFO - 'tooltool.py': '/tools/tooltool.py', 17:24:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:24:30 INFO - '/tools/misc-python/virtualenv.py')}, 17:24:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:24:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:24:30 INFO - 'installer_path': 'installer.exe', 17:24:30 INFO - 'log_level': 'info', 17:24:30 INFO - 'log_name': 'talos', 17:24:30 INFO - 'log_to_console': True, 17:24:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:24:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:24:30 INFO - 'opt_config_files': (), 17:24:30 INFO - 'pip_index': False, 17:24:30 INFO - 'sps_profile': False, 17:24:30 INFO - 'sps_profile_interval': 0, 17:24:30 INFO - 'suite': 'dromaeojs', 17:24:30 INFO - 'system_bits': '32', 17:24:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:24:30 INFO - 'title': 'talos-linux64-ix-054', 17:24:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:24:30 INFO - 'use_talos_json': True, 17:24:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:24:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:24:30 INFO - 'work_dir': 'build'} 17:24:30 INFO - [mozharness: 2016-08-31 00:24:30.386614Z] Running clobber step. 17:24:30 INFO - Running pre-action listener: _resource_record_pre_action 17:24:30 INFO - Running main action method: clobber 17:24:30 INFO - rmtree: /builds/slave/test/build 17:24:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:24:30 INFO - Running post-action listener: _resource_record_post_action 17:24:30 INFO - [mozharness: 2016-08-31 00:24:30.857135Z] Finished clobber step (success) 17:24:30 INFO - [mozharness: 2016-08-31 00:24:30.857236Z] Running read-buildbot-config step. 17:24:30 INFO - Running pre-action listener: _resource_record_pre_action 17:24:30 INFO - Running main action method: read_buildbot_config 17:24:30 INFO - Using buildbot properties: 17:24:30 INFO - { 17:24:30 INFO - "project": "", 17:24:30 INFO - "product": "firefox", 17:24:30 INFO - "script_repo_revision": "production", 17:24:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:24:30 INFO - "repository": "", 17:24:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:24:30 INFO - "buildid": "20160830142920", 17:24:30 INFO - "pgo_build": "True", 17:24:30 INFO - "basedir": "/builds/slave/test", 17:24:30 INFO - "buildnumber": 709, 17:24:30 INFO - "slavename": "talos-linux64-ix-054", 17:24:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:24:30 INFO - "platform": "ubuntu64_hw", 17:24:30 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:24:30 INFO - "branch": "mozilla-aurora", 17:24:30 INFO - "repo_path": "releases/mozilla-aurora", 17:24:30 INFO - "revision": "16676fabf4c6da9a46ba08a408b87e37927573b5", 17:24:30 INFO - "stage_platform": "linux64-pgo", 17:24:30 INFO - "builduid": "8885bb9facb64463968fa650c5b59878", 17:24:30 INFO - "slavebuilddir": "test" 17:24:30 INFO - } 17:24:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 17:24:30 INFO - Running post-action listener: _resource_record_post_action 17:24:30 INFO - [mozharness: 2016-08-31 00:24:30.859686Z] Finished read-buildbot-config step (success) 17:24:30 INFO - [mozharness: 2016-08-31 00:24:30.859776Z] Running download-and-extract step. 17:24:30 INFO - Running pre-action listener: _resource_record_pre_action 17:24:30 INFO - Running main action method: download_and_extract 17:24:30 INFO - mkdir: /builds/slave/test/build/tests 17:24:30 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')]} 17:24:30 INFO - https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:24:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:24:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:24:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:24:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:24:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:24:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:24:30 INFO - retry: Failed, sleeping 30 seconds before retrying 17:25:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:25:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:25:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:25:00 INFO - retry: Failed, sleeping 60 seconds before retrying 17:26:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:26:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:26:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:26:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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! 17:26:00 INFO - Caught exception: HTTP Error 404: Not Found 17:26:00 INFO - Caught exception: HTTP Error 404: Not Found 17:26:00 INFO - Caught exception: HTTP Error 404: Not Found 17:26:00 INFO - trying https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:26:00 INFO - Downloading https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:26:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:26:01 INFO - Downloaded 1361 bytes. 17:26:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:26:01 INFO - Using the following test package requirements: 17:26:01 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:26:01 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:26:01 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:26:01 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'jsshell-linux-x86_64.zip'], 17:26:01 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:26:01 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:26:01 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:26:01 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:26:01 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:26:01 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:26:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:26:01 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:26:01 INFO - https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:26:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:26:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:26:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:26:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:26:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:26:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:26:01 INFO - retry: Failed, sleeping 30 seconds before retrying 17:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:26:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:26:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:26:31 INFO - retry: Failed, sleeping 60 seconds before retrying 17:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:27:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:27:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:27:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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! 17:27:31 INFO - Caught exception: HTTP Error 404: Not Found 17:27:31 INFO - Caught exception: HTTP Error 404: Not Found 17:27:31 INFO - Caught exception: HTTP Error 404: Not Found 17:27:31 INFO - trying https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:27:31 INFO - Downloading https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:27:32 INFO - Downloaded 21771268 bytes. 17:27:32 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'] 17:27:32 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 17:27:33 INFO - Return code: 0 17:27:33 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 17:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:33 INFO - https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:27:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:27:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:27:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:27:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:27:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:27:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:27:33 INFO - retry: Failed, sleeping 30 seconds before retrying 17:28:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:28:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:28:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:28:03 INFO - retry: Failed, sleeping 60 seconds before retrying 17:29:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:29:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:29:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:29:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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! 17:29:03 INFO - Caught exception: HTTP Error 404: Not Found 17:29:03 INFO - Caught exception: HTTP Error 404: Not Found 17:29:03 INFO - Caught exception: HTTP Error 404: Not Found 17:29:03 INFO - trying https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:29:03 INFO - Downloading https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:29:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:29:04 INFO - Downloaded 21771268 bytes. 17:29:04 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'] 17:29:04 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 17:29:05 INFO - Return code: 0 17:29:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:29:05 INFO - https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:29:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:29:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:29:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:29:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:29:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:29:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:29:05 INFO - retry: Failed, sleeping 30 seconds before retrying 17:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:29:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:29:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:29:35 INFO - retry: Failed, sleeping 60 seconds before retrying 17:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:30:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:30:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:30:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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! 17:30:35 INFO - Caught exception: HTTP Error 404: Not Found 17:30:35 INFO - Caught exception: HTTP Error 404: Not Found 17:30:35 INFO - Caught exception: HTTP Error 404: Not Found 17:30:35 INFO - trying https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:30:35 INFO - Downloading https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:30:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:30:36 INFO - Downloaded 14413740 bytes. 17:30:36 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'] 17:30:36 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 17:30:36 INFO - Return code: 0 17:30:36 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')]} 17:30:36 INFO - https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:30:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:30:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:30:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:30:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:30:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:30:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:30:36 INFO - retry: Failed, sleeping 30 seconds before retrying 17:31:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:31:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:31:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:31:06 INFO - retry: Failed, sleeping 60 seconds before retrying 17:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:32:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:32:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:32:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eQNNgufHSYqjnujZnW5EeQ/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! 17:32:06 INFO - Caught exception: HTTP Error 404: Not Found 17:32:06 INFO - Caught exception: HTTP Error 404: Not Found 17:32:06 INFO - Caught exception: HTTP Error 404: Not Found 17:32:06 INFO - trying https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:32:06 INFO - Downloading https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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 17:32:08 INFO - Downloaded 63893985 bytes. 17:32:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:32:08 INFO - mkdir: /builds/slave/test/properties 17:32:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:32:08 INFO - Writing to file /builds/slave/test/properties/build_url 17:32:08 INFO - Contents: 17:32:08 INFO - build_url:https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:32:08 INFO - Running post-action listener: _resource_record_post_action 17:32:08 INFO - Running post-action listener: set_extra_try_arguments 17:32:08 INFO - [mozharness: 2016-08-31 00:32:08.912507Z] Finished download-and-extract step (success) 17:32:08 INFO - [mozharness: 2016-08-31 00:32:08.912742Z] Running populate-webroot step. 17:32:08 INFO - Running pre-action listener: _resource_record_pre_action 17:32:08 INFO - Running main action method: populate_webroot 17:32:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:32:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:32:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:32:08 INFO - '--tppagecycles', 17:32:08 INFO - '7'], 17:32:08 INFO - 'tests': ['tsvgm']}}, 17:32:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:32:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:32:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:32:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:32:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:32:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['damp', 'tps']}, 17:32:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'tests': ['damp', 'tps']}, 17:32:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['dromaeo_dom']}, 17:32:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:32:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['basic_compositor_video']}, 17:32:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:32:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['a11yr', 17:32:08 INFO - 'ts_paint', 17:32:08 INFO - 'tpaint', 17:32:08 INFO - 'sessionrestore', 17:32:08 INFO - 'sessionrestore_no_auto_restore', 17:32:08 INFO - 'tabpaint']}, 17:32:08 INFO - 'other-e10s': {'tests': ['a11yr', 17:32:08 INFO - 'ts_paint', 17:32:08 INFO - 'tpaint', 17:32:08 INFO - 'sessionrestore', 17:32:08 INFO - 'sessionrestore_no_auto_restore', 17:32:08 INFO - 'tabpaint']}, 17:32:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:32:08 INFO - 'ts_paint', 17:32:08 INFO - 'tpaint', 17:32:08 INFO - 'sessionrestore', 17:32:08 INFO - 'sessionrestore_no_auto_restore', 17:32:08 INFO - 'tabpaint']}, 17:32:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:32:08 INFO - 'ts_paint', 17:32:08 INFO - 'tpaint', 17:32:08 INFO - 'sessionrestore', 17:32:08 INFO - 'sessionrestore_no_auto_restore', 17:32:08 INFO - 'tabpaint']}, 17:32:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['a11yr', 17:32:08 INFO - 'ts_paint', 17:32:08 INFO - 'tpaint', 17:32:08 INFO - 'sessionrestore', 17:32:08 INFO - 'sessionrestore_no_auto_restore', 17:32:08 INFO - 'tabpaint']}, 17:32:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['a11yr', 17:32:08 INFO - 'ts_paint', 17:32:08 INFO - 'tpaint', 17:32:08 INFO - 'sessionrestore', 17:32:08 INFO - 'sessionrestore_no_auto_restore', 17:32:08 INFO - 'tabpaint']}, 17:32:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['tsvgx', 17:32:08 INFO - 'tsvgr_opacity', 17:32:08 INFO - 'tart', 17:32:08 INFO - 'tscrollx', 17:32:08 INFO - 'cart']}, 17:32:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:32:08 INFO - 'tsvgr_opacity', 17:32:08 INFO - 'tart', 17:32:08 INFO - 'tscrollx', 17:32:08 INFO - 'cart']}, 17:32:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'talos_options': ['--disable-e10s'], 17:32:08 INFO - 'tests': ['tp5o']}, 17:32:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'tests': ['tp5o']}, 17:32:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'talos_options': ['--disable-e10s', 17:32:08 INFO - '--xperf_path', 17:32:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:08 INFO - 'tests': ['tp5n']}, 17:32:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:32:08 INFO - 'talos_options': ['--xperf_path', 17:32:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:32:08 INFO - 'tests': ['tp5n']}}, 17:32:08 INFO - 'talos.zip': {'path': '', 17:32:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:32:08 INFO - Running post-action listener: _resource_record_post_action 17:32:08 INFO - [mozharness: 2016-08-31 00:32:08.930279Z] Finished populate-webroot step (success) 17:32:08 INFO - [mozharness: 2016-08-31 00:32:08.930396Z] Running create-virtualenv step. 17:32:08 INFO - Running pre-action listener: _resource_record_pre_action 17:32:08 INFO - Running main action method: create_virtualenv 17:32:08 INFO - Creating virtualenv /builds/slave/test/build/venv 17:32:08 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 17:32:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:32:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:32:09 INFO - Using real prefix '/usr' 17:32:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:32:09 INFO - Installing distribute.............................................................................................................................................................................................done. 17:32:11 INFO - Installing pip.................done. 17:32:11 INFO - Return code: 0 17:32:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:32:11 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')]} 17:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:11 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')]} 17:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:11 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:11 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 17:32:11 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 17:32:11 INFO - Using env: {'DISPLAY': ':0', 17:32:11 INFO - 'HOME': '/home/cltbld', 17:32:11 INFO - 'LANG': 'en_US.UTF-8', 17:32:11 INFO - 'LANGUAGE': 'en_US:en', 17:32:11 INFO - 'LOGNAME': 'cltbld', 17:32:11 INFO - 'MAIL': '/var/mail/cltbld', 17:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:11 INFO - 'MOZ_NO_REMOTE': '1', 17:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:11 INFO - 'NO_EM_RESTART': '1', 17:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:11 INFO - 'PWD': '/builds/slave/test', 17:32:11 INFO - 'SHELL': '/bin/bash', 17:32:11 INFO - 'SHLVL': '1', 17:32:11 INFO - 'TERM': 'linux', 17:32:11 INFO - 'TMOUT': '86400', 17:32:11 INFO - 'USER': 'cltbld', 17:32:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:11 INFO - '_': '/tools/buildbot/bin/python'} 17:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:11 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 17:32:11 INFO - Cleaning up... 17:32:11 INFO - Return code: 0 17:32:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:32:11 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')]} 17:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:11 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')]} 17:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:11 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:11 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 17:32:11 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 17:32:11 INFO - Using env: {'DISPLAY': ':0', 17:32:11 INFO - 'HOME': '/home/cltbld', 17:32:11 INFO - 'LANG': 'en_US.UTF-8', 17:32:11 INFO - 'LANGUAGE': 'en_US:en', 17:32:11 INFO - 'LOGNAME': 'cltbld', 17:32:11 INFO - 'MAIL': '/var/mail/cltbld', 17:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:11 INFO - 'MOZ_NO_REMOTE': '1', 17:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:11 INFO - 'NO_EM_RESTART': '1', 17:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:11 INFO - 'PWD': '/builds/slave/test', 17:32:11 INFO - 'SHELL': '/bin/bash', 17:32:11 INFO - 'SHLVL': '1', 17:32:11 INFO - 'TERM': 'linux', 17:32:11 INFO - 'TMOUT': '86400', 17:32:11 INFO - 'USER': 'cltbld', 17:32:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:11 INFO - '_': '/tools/buildbot/bin/python'} 17:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:11 INFO - Downloading/unpacking psutil>=3.1.1 17:32:11 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 17:32:11 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 17:32:11 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 17:32:11 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 17:32:11 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 17:32:11 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 17:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:32:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:13 INFO - Installing collected packages: psutil 17:32:13 INFO - Running setup.py install for psutil 17:32:13 INFO - building 'psutil._psutil_linux' extension 17:32:13 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 17:32:14 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 17:32:14 INFO - building 'psutil._psutil_posix' extension 17:32:14 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 17:32:14 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 17:32:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:32:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:32:14 INFO - Successfully installed psutil 17:32:14 INFO - Cleaning up... 17:32:14 INFO - Return code: 0 17:32:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:32:14 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')]} 17:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:14 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')]} 17:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:14 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:14 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 17:32:14 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 17:32:14 INFO - Using env: {'DISPLAY': ':0', 17:32:14 INFO - 'HOME': '/home/cltbld', 17:32:14 INFO - 'LANG': 'en_US.UTF-8', 17:32:14 INFO - 'LANGUAGE': 'en_US:en', 17:32:14 INFO - 'LOGNAME': 'cltbld', 17:32:14 INFO - 'MAIL': '/var/mail/cltbld', 17:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:14 INFO - 'MOZ_NO_REMOTE': '1', 17:32:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:14 INFO - 'NO_EM_RESTART': '1', 17:32:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:14 INFO - 'PWD': '/builds/slave/test', 17:32:14 INFO - 'SHELL': '/bin/bash', 17:32:14 INFO - 'SHLVL': '1', 17:32:14 INFO - 'TERM': 'linux', 17:32:14 INFO - 'TMOUT': '86400', 17:32:14 INFO - 'USER': 'cltbld', 17:32:14 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:14 INFO - '_': '/tools/buildbot/bin/python'} 17:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:32:16 INFO - Installing collected packages: mozsystemmonitor 17:32:16 INFO - Running setup.py install for mozsystemmonitor 17:32:16 INFO - Successfully installed mozsystemmonitor 17:32:16 INFO - Cleaning up... 17:32:16 INFO - Return code: 0 17:32:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:32:16 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')]} 17:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:16 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')]} 17:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:16 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:16 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 17:32:16 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 17:32:16 INFO - Using env: {'DISPLAY': ':0', 17:32:16 INFO - 'HOME': '/home/cltbld', 17:32:16 INFO - 'LANG': 'en_US.UTF-8', 17:32:16 INFO - 'LANGUAGE': 'en_US:en', 17:32:16 INFO - 'LOGNAME': 'cltbld', 17:32:16 INFO - 'MAIL': '/var/mail/cltbld', 17:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:16 INFO - 'MOZ_NO_REMOTE': '1', 17:32:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:16 INFO - 'NO_EM_RESTART': '1', 17:32:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:16 INFO - 'PWD': '/builds/slave/test', 17:32:16 INFO - 'SHELL': '/bin/bash', 17:32:16 INFO - 'SHLVL': '1', 17:32:16 INFO - 'TERM': 'linux', 17:32:16 INFO - 'TMOUT': '86400', 17:32:16 INFO - 'USER': 'cltbld', 17:32:16 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:16 INFO - '_': '/tools/buildbot/bin/python'} 17:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:17 INFO - Downloading/unpacking jsonschema==2.5.1 17:32: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 17:32: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 17:32: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 17:32: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 17:32: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 17:32: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 17:32:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:32:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:32: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 17:32: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 17:32: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 17:32: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 17:32: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 17:32: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 17:32:19 INFO - Downloading functools32-3.2.3-2.tar.gz 17:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:32:19 INFO - warning: no files found matching '*.txt' 17:32:19 INFO - no previously-included directories found matching 'build' 17:32:19 INFO - no previously-included directories found matching 'dist' 17:32:19 INFO - no previously-included directories found matching '.git*' 17:32:19 INFO - Installing collected packages: jsonschema, functools32 17:32:19 INFO - Running setup.py install for functools32 17:32:19 INFO - warning: no files found matching '*.txt' 17:32:19 INFO - no previously-included directories found matching 'build' 17:32:19 INFO - no previously-included directories found matching 'dist' 17:32:19 INFO - no previously-included directories found matching '.git*' 17:32:19 INFO - Successfully installed jsonschema functools32 17:32:19 INFO - Cleaning up... 17:32:19 INFO - Return code: 0 17:32:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:32:19 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')]} 17:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:19 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')]} 17:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:19 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 17:32:19 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 17:32:19 INFO - Using env: {'DISPLAY': ':0', 17:32:19 INFO - 'HOME': '/home/cltbld', 17:32:19 INFO - 'LANG': 'en_US.UTF-8', 17:32:19 INFO - 'LANGUAGE': 'en_US:en', 17:32:19 INFO - 'LOGNAME': 'cltbld', 17:32:19 INFO - 'MAIL': '/var/mail/cltbld', 17:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:19 INFO - 'MOZ_NO_REMOTE': '1', 17:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:19 INFO - 'NO_EM_RESTART': '1', 17:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:19 INFO - 'PWD': '/builds/slave/test', 17:32:19 INFO - 'SHELL': '/bin/bash', 17:32:19 INFO - 'SHLVL': '1', 17:32:19 INFO - 'TERM': 'linux', 17:32:19 INFO - 'TMOUT': '86400', 17:32:19 INFO - 'USER': 'cltbld', 17:32:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:19 INFO - '_': '/tools/buildbot/bin/python'} 17:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:32:19 INFO - Cleaning up... 17:32:19 INFO - Return code: 0 17:32:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:32:19 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')]} 17:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:19 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')]} 17:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:19 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 17:32:19 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 17:32:19 INFO - Using env: {'DISPLAY': ':0', 17:32:19 INFO - 'HOME': '/home/cltbld', 17:32:19 INFO - 'LANG': 'en_US.UTF-8', 17:32:19 INFO - 'LANGUAGE': 'en_US:en', 17:32:19 INFO - 'LOGNAME': 'cltbld', 17:32:19 INFO - 'MAIL': '/var/mail/cltbld', 17:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:19 INFO - 'MOZ_NO_REMOTE': '1', 17:32:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:19 INFO - 'NO_EM_RESTART': '1', 17:32:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:19 INFO - 'PWD': '/builds/slave/test', 17:32:19 INFO - 'SHELL': '/bin/bash', 17:32:19 INFO - 'SHLVL': '1', 17:32:19 INFO - 'TERM': 'linux', 17:32:19 INFO - 'TMOUT': '86400', 17:32:19 INFO - 'USER': 'cltbld', 17:32:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:19 INFO - '_': '/tools/buildbot/bin/python'} 17:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:19 INFO - Downloading/unpacking blobuploader==1.2.4 17:32: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 17:32: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 17:32: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 17:32: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 17:32: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 17:32: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 17:32:21 INFO - Downloading blobuploader-1.2.4.tar.gz 17:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:32:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:32:21 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 17:32:21 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 17:32:21 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 17:32:21 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 17:32:21 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 17:32:21 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 17:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:32:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:22 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 17:32:22 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 17:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:22 INFO - Downloading docopt-0.6.1.tar.gz 17:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:32:22 INFO - Installing collected packages: blobuploader, requests, docopt 17:32:22 INFO - Running setup.py install for blobuploader 17:32:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:32:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:32:22 INFO - Running setup.py install for requests 17:32:22 INFO - Running setup.py install for docopt 17:32:23 INFO - Successfully installed blobuploader requests docopt 17:32:23 INFO - Cleaning up... 17:32:23 INFO - Return code: 0 17:32:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:23 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:23 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 17:32:23 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 17:32:23 INFO - Using env: {'DISPLAY': ':0', 17:32:23 INFO - 'HOME': '/home/cltbld', 17:32:23 INFO - 'LANG': 'en_US.UTF-8', 17:32:23 INFO - 'LANGUAGE': 'en_US:en', 17:32:23 INFO - 'LOGNAME': 'cltbld', 17:32:23 INFO - 'MAIL': '/var/mail/cltbld', 17:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:23 INFO - 'MOZ_NO_REMOTE': '1', 17:32:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:23 INFO - 'NO_EM_RESTART': '1', 17:32:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:23 INFO - 'PWD': '/builds/slave/test', 17:32:23 INFO - 'SHELL': '/bin/bash', 17:32:23 INFO - 'SHLVL': '1', 17:32:23 INFO - 'TERM': 'linux', 17:32:23 INFO - 'TMOUT': '86400', 17:32:23 INFO - 'USER': 'cltbld', 17:32:23 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:23 INFO - '_': '/tools/buildbot/bin/python'} 17:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:23 INFO - Running setup.py (path:/tmp/pip-iT57M7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:23 INFO - Running setup.py (path:/tmp/pip-ABn62X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:23 INFO - Running setup.py (path:/tmp/pip-lWYgnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:23 INFO - Running setup.py (path:/tmp/pip-guWhE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:23 INFO - Running setup.py (path:/tmp/pip-Rja9bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:23 INFO - Running setup.py (path:/tmp/pip-x6f9ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:23 INFO - Running setup.py (path:/tmp/pip-T46P8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:23 INFO - Running setup.py (path:/tmp/pip-3xN64_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:23 INFO - Running setup.py (path:/tmp/pip-JYCoIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:23 INFO - Running setup.py (path:/tmp/pip-E1VfSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:24 INFO - Running setup.py (path:/tmp/pip-KyDJdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:24 INFO - Running setup.py (path:/tmp/pip-8u6TRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:32:24 INFO - Running setup.py (path:/tmp/pip-2FZ9Yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:32:24 INFO - Running setup.py (path:/tmp/pip-JfVW7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:24 INFO - Running setup.py (path:/tmp/pip-FdsAvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:32:24 INFO - Running setup.py (path:/tmp/pip-bz6s6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:32:24 INFO - Running setup.py (path:/tmp/pip-eFAZnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:32:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:32:24 INFO - Running setup.py install for manifestparser 17:32:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:32:24 INFO - Running setup.py install for mozcrash 17:32:24 INFO - Running setup.py install for mozdebug 17:32:24 INFO - Running setup.py install for mozdevice 17:32:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:32:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:32:24 INFO - Running setup.py install for mozfile 17:32:25 INFO - Running setup.py install for mozhttpd 17:32:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Running setup.py install for mozinfo 17:32:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Running setup.py install for mozInstall 17:32:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Running setup.py install for mozleak 17:32:25 INFO - Running setup.py install for mozlog 17:32:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Running setup.py install for moznetwork 17:32:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Running setup.py install for mozprocess 17:32:25 INFO - Running setup.py install for mozprofile 17:32:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:32:25 INFO - Running setup.py install for mozrunner 17:32:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:32:26 INFO - Running setup.py install for mozscreenshot 17:32:26 INFO - Running setup.py install for moztest 17:32:26 INFO - Running setup.py install for mozversion 17:32:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:32:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:32:26 INFO - Cleaning up... 17:32:26 INFO - Return code: 0 17:32:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32:26 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')]} 17:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:26 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')]} 17:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:26 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:26 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 17:32:26 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 17:32:26 INFO - Using env: {'DISPLAY': ':0', 17:32:26 INFO - 'HOME': '/home/cltbld', 17:32:26 INFO - 'LANG': 'en_US.UTF-8', 17:32:26 INFO - 'LANGUAGE': 'en_US:en', 17:32:26 INFO - 'LOGNAME': 'cltbld', 17:32:26 INFO - 'MAIL': '/var/mail/cltbld', 17:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:26 INFO - 'MOZ_NO_REMOTE': '1', 17:32:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:26 INFO - 'NO_EM_RESTART': '1', 17:32:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:26 INFO - 'PWD': '/builds/slave/test', 17:32:26 INFO - 'SHELL': '/bin/bash', 17:32:26 INFO - 'SHLVL': '1', 17:32:26 INFO - 'TERM': 'linux', 17:32:26 INFO - 'TMOUT': '86400', 17:32:26 INFO - 'USER': 'cltbld', 17:32:26 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:26 INFO - '_': '/tools/buildbot/bin/python'} 17:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:32:26 INFO - Running setup.py (path:/tmp/pip-XkS_Z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:32:26 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)) 17:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:32:26 INFO - Running setup.py (path:/tmp/pip-dBtB3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:32:26 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)) 17:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:32:26 INFO - Running setup.py (path:/tmp/pip-0IvJkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:32:26 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)) 17:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:32:26 INFO - Running setup.py (path:/tmp/pip-bayiN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:32:26 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)) 17:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:32:26 INFO - Running setup.py (path:/tmp/pip-OKNmuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:32:26 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)) 17:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:32:26 INFO - Running setup.py (path:/tmp/pip-yejVIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:32:27 INFO - Running setup.py (path:/tmp/pip-HrdZBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:32:27 INFO - Running setup.py (path:/tmp/pip-K1BvIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:32:27 INFO - Running setup.py (path:/tmp/pip-xuzMMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:32:27 INFO - Running setup.py (path:/tmp/pip-70A41P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:32:27 INFO - Running setup.py (path:/tmp/pip-Nz_S_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:32:27 INFO - Running setup.py (path:/tmp/pip-VXeuCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:32:27 INFO - Running setup.py (path:/tmp/pip-La7348-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:32:27 INFO - Running setup.py (path:/tmp/pip-2mFkHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:27 INFO - Running setup.py (path:/tmp/pip-dgdSeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:32:27 INFO - Running setup.py (path:/tmp/pip-OwqTa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:32:27 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)) 17:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:32:27 INFO - Running setup.py (path:/tmp/pip-RKoaRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:32:27 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)) 17:32:27 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)) 17:32:27 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)) 17:32:27 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)) 17:32:27 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)) 17:32:27 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)) 17:32:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:27 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 17:32:27 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 17:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:32:29 INFO - Downloading blessings-1.6.tar.gz 17:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:32:29 INFO - Installing collected packages: blessings 17:32:29 INFO - Running setup.py install for blessings 17:32:29 INFO - Successfully installed blessings 17:32:29 INFO - Cleaning up... 17:32:29 INFO - Return code: 0 17:32:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:32:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:32:30 INFO - Reading from file tmpfile_stdout 17:32:30 INFO - Current package versions: 17:32:30 INFO - argparse == 1.2.1 17:32:30 INFO - blessings == 1.6 17:32:30 INFO - blobuploader == 1.2.4 17:32:30 INFO - docopt == 0.6.1 17:32:30 INFO - functools32 == 3.2.3-2 17:32:30 INFO - jsonschema == 2.5.1 17:32:30 INFO - manifestparser == 1.1 17:32:30 INFO - mozInstall == 1.12 17:32:30 INFO - mozcrash == 0.17 17:32:30 INFO - mozdebug == 0.1 17:32:30 INFO - mozdevice == 0.48 17:32:30 INFO - mozfile == 1.2 17:32:30 INFO - mozhttpd == 0.7 17:32:30 INFO - mozinfo == 0.9 17:32:30 INFO - mozleak == 0.1 17:32:30 INFO - mozlog == 3.3 17:32:30 INFO - moznetwork == 0.27 17:32:30 INFO - mozprocess == 0.23 17:32:30 INFO - mozprofile == 0.28 17:32:30 INFO - mozrunner == 6.12 17:32:30 INFO - mozscreenshot == 0.1 17:32:30 INFO - mozsystemmonitor == 0.3 17:32:30 INFO - moztest == 0.7 17:32:30 INFO - mozversion == 1.4 17:32:30 INFO - psutil == 3.1.1 17:32:30 INFO - requests == 1.2.3 17:32:30 INFO - wsgiref == 0.1.2 17:32:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:32:30 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')]} 17:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:30 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')]} 17:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:30 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:30 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 17:32:30 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 17:32:30 INFO - Using env: {'DISPLAY': ':0', 17:32:30 INFO - 'HOME': '/home/cltbld', 17:32:30 INFO - 'LANG': 'en_US.UTF-8', 17:32:30 INFO - 'LANGUAGE': 'en_US:en', 17:32:30 INFO - 'LOGNAME': 'cltbld', 17:32:30 INFO - 'MAIL': '/var/mail/cltbld', 17:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:30 INFO - 'MOZ_NO_REMOTE': '1', 17:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:30 INFO - 'NO_EM_RESTART': '1', 17:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:30 INFO - 'PWD': '/builds/slave/test', 17:32:30 INFO - 'SHELL': '/bin/bash', 17:32:30 INFO - 'SHLVL': '1', 17:32:30 INFO - 'TERM': 'linux', 17:32:30 INFO - 'TMOUT': '86400', 17:32:30 INFO - 'USER': 'cltbld', 17:32:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:30 INFO - '_': '/tools/buildbot/bin/python'} 17:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 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)) 17:32:30 INFO - Cleaning up... 17:32:30 INFO - Return code: 0 17:32:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:32:30 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')]} 17:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:30 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')]} 17:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:32:30 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 0x25a5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27088a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2866180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, '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': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', '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 17:32:30 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 17:32:30 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 17:32:30 INFO - Using env: {'DISPLAY': ':0', 17:32:30 INFO - 'HOME': '/home/cltbld', 17:32:30 INFO - 'LANG': 'en_US.UTF-8', 17:32:30 INFO - 'LANGUAGE': 'en_US:en', 17:32:30 INFO - 'LOGNAME': 'cltbld', 17:32:30 INFO - 'MAIL': '/var/mail/cltbld', 17:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:30 INFO - 'MOZ_NO_REMOTE': '1', 17:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:30 INFO - 'NO_EM_RESTART': '1', 17:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:30 INFO - 'PWD': '/builds/slave/test', 17:32:30 INFO - 'SHELL': '/bin/bash', 17:32:30 INFO - 'SHLVL': '1', 17:32:30 INFO - 'TERM': 'linux', 17:32:30 INFO - 'TMOUT': '86400', 17:32:30 INFO - 'USER': 'cltbld', 17:32:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:30 INFO - '_': '/tools/buildbot/bin/python'} 17:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:32:30 INFO - Cleaning up... 17:32:30 INFO - Return code: 0 17:32:30 INFO - Running post-action listener: _resource_record_post_action 17:32:30 INFO - Running post-action listener: _start_resource_monitoring 17:32:30 INFO - Starting resource monitoring. 17:32:30 INFO - [mozharness: 2016-08-31 00:32:30.669042Z] Finished create-virtualenv step (success) 17:32:30 INFO - [mozharness: 2016-08-31 00:32:30.669518Z] Running install step. 17:32:30 INFO - Running pre-action listener: _resource_record_pre_action 17:32:30 INFO - Running main action method: install 17:32:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:32:30 INFO - Reading from file tmpfile_stdout 17:32:30 INFO - Detecting whether we're running mozinstall >=1.0... 17:32:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:32:30 INFO - Reading from file tmpfile_stdout 17:32:30 INFO - Output received: 17:32:30 INFO - Usage: mozinstall [options] installer 17:32:30 INFO - Options: 17:32:30 INFO - -h, --help show this help message and exit 17:32:30 INFO - -d DEST, --destination=DEST 17:32:30 INFO - Directory to install application into. [default: 17:32:30 INFO - "/builds/slave/test"] 17:32:30 INFO - --app=APP Application being installed. [default: firefox] 17:32:30 INFO - mkdir: /builds/slave/test/build/application 17:32:30 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'] 17:32:30 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 17:32:47 INFO - Reading from file tmpfile_stdout 17:32:47 INFO - Output received: 17:32:47 INFO - /builds/slave/test/build/application/firefox/firefox 17:32:47 INFO - Running post-action listener: _resource_record_post_action 17:32:47 INFO - [mozharness: 2016-08-31 00:32:47.536906Z] Finished install step (success) 17:32:47 INFO - [mozharness: 2016-08-31 00:32:47.537185Z] Running run-tests step. 17:32:47 INFO - Running pre-action listener: _resource_record_pre_action 17:32:47 INFO - Running main action method: run_tests 17:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:32:47 INFO - Python 2.7.3 17:32:47 INFO - Return code: 0 17:32:47 INFO - grabbing minidump binary from tooltool 17:32:47 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')]} 17:32:47 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 0x28643b0>, '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 0x28652b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2865740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:32:47 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 17:32:47 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 17:32:47 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 17:32:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:32:47 INFO - Return code: 0 17:32:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:32:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:32:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:32:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:32:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:32:47 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/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 17:32:47 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/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 17:32:47 INFO - Using env: {'DISPLAY': ':0', 17:32:47 INFO - 'HOME': '/home/cltbld', 17:32:47 INFO - 'LANG': 'en_US.UTF-8', 17:32:47 INFO - 'LANGUAGE': 'en_US:en', 17:32:47 INFO - 'LOGNAME': 'cltbld', 17:32:47 INFO - 'MAIL': '/var/mail/cltbld', 17:32:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:32:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:32:47 INFO - 'MOZ_NO_REMOTE': '1', 17:32:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:32:47 INFO - 'NO_EM_RESTART': '1', 17:32:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:32:47 INFO - 'PWD': '/builds/slave/test', 17:32:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:32:47 INFO - 'SHELL': '/bin/bash', 17:32:47 INFO - 'SHLVL': '1', 17:32:47 INFO - 'TERM': 'linux', 17:32:47 INFO - 'TMOUT': '86400', 17:32:47 INFO - 'USER': 'cltbld', 17:32:47 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795', 17:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:32:47 INFO - '_': '/tools/buildbot/bin/python'} 17:32:47 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/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 17:32:47 INFO - mozversion application_buildid: 20160830142920 17:32:47 INFO - mozversion application_changeset: 16676fabf4c6da9a46ba08a408b87e37927573b5 17:32:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:32:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:32:47 INFO - mozversion application_name: Firefox 17:32:47 INFO - mozversion application_remotingname: firefox-dev 17:32:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:32:47 INFO - mozversion application_vendor: Mozilla 17:32:47 INFO - mozversion application_version: 50.0a2 17:32:47 INFO - mozversion platform_buildid: 20160830142920 17:32:47 INFO - mozversion platform_changeset: 16676fabf4c6da9a46ba08a408b87e37927573b5 17:32:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:32:47 INFO - mozversion platform_version: 50.0a2 17:32:47 INFO - using testdate: 1472603567 17:32:47 INFO - actual date: 1472603567 17:32:47 INFO - starting webserver on 'localhost:40323' 17:32:47 INFO - SUITE-START | Running 2 tests 17:32:47 INFO - TEST-START | dromaeo_css 17:32:47 INFO - operating with platform_type : linux_ 17:32:47 INFO - Initialising browser for dromaeo_css test... 17:32:47 INFO - TEST-INFO | started process 5908 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6VPCnh/profile http://localhost:40323/getInfo.html) 17:32:51 INFO - PROCESS | 5908 | __metrics Screen width/height:1600/1200 17:32:51 INFO - PROCESS | 5908 | colorDepth:24 17:32:51 INFO - PROCESS | 5908 | Browser inner width/height: 1024/697 17:32:51 INFO - PROCESS | 5908 | __metrics 17:32:52 INFO - PROCESS | 5908 | 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" 17:32:52 INFO - PROCESS | 5908 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:32:53 INFO - TEST-INFO | 5908: exit 0 17:32:53 INFO - Browser initialized. 17:32:53 INFO - Running cycle 1/1 for dromaeo_css test... 17:32:53 INFO - TEST-INFO | started process 5996 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6VPCnh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:33:05 INFO - PROCESS | 5996 | RSS: Main: 145272832 17:33:05 INFO - PROCESS | 5996 | 17:33:56 INFO - PROCESS | 5996 | 1472603636174 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] 17:36:05 INFO - PROCESS | 5996 | Cycle 1(1): loaded http://localhost:40323/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40323/tests/dromaeo/cssquery-ext.html) 17:36:06 INFO - PROCESS | 5996 | RSS: Main: 223309824 17:36:06 INFO - PROCESS | 5996 | 17:39:08 INFO - PROCESS | 5996 | Cycle 1(1): loaded http://localhost:40323/tests/dromaeo/cssquery-ext.html (next: http://localhost:40323/tests/dromaeo/cssquery-jquery.html) 17:39:09 INFO - PROCESS | 5996 | RSS: Main: 233353216 17:39:09 INFO - PROCESS | 5996 | 17:42:11 INFO - PROCESS | 5996 | Cycle 1(1): loaded http://localhost:40323/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40323/tests/dromaeo/cssquery-mootools.html) 17:42:11 INFO - PROCESS | 5996 | RSS: Main: 200564736 17:42:11 INFO - PROCESS | 5996 | 17:45:13 INFO - PROCESS | 5996 | Cycle 1(1): loaded http://localhost:40323/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40323/tests/dromaeo/cssquery-prototype.html) 17:45:14 INFO - PROCESS | 5996 | RSS: Main: 203632640 17:45:14 INFO - PROCESS | 5996 | 17:48:17 INFO - PROCESS | 5996 | Cycle 1(1): loaded http://localhost:40323/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40323/tests/dromaeo/cssquery-yui.html) 17:48:17 INFO - PROCESS | 5996 | RSS: Main: 177754112 17:48:17 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | Cycle 1(1): loaded http://localhost:40323/tests/dromaeo/cssquery-yui.html (next: http://localhost:40323/tests/dromaeo/cssquery-dojo.html) 17:51:20 INFO - PROCESS | 5996 | RSS: Main: 264024064 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | __start_tp_report 17:51:20 INFO - PROCESS | 5996 | _x_x_mozilla_page_load 17:51:20 INFO - PROCESS | 5996 | _x_x_mozilla_page_load_details 17:51:20 INFO - PROCESS | 5996 | |i|pagename|runs| 17:51:20 INFO - PROCESS | 5996 | |0;dojo.html;3201.8559164837593;3215.26049008728;3231.8384080795963;3248.3665685191395;3266.2650903546705;5554.1391084381885;5624.77500899964;5645.435456454353;5653.604247702658;5657.9434205657935;5644.350899646542;5720.256366672337;5723.685197314146;5728.541716662668;5747.3677895431465;4987.476314986929;5062.924056139161;5144.048351055048;5175.404828444721;5233.921491177635;5068.670536415139;5118.948810511884;5125.487451254863;5125.769815244334;5128.230765385185;5138.665986710852;5172.431032586413;5190.377154741436;5204.167333226685;5218.530332270095;5289.44460831611;5297.231901374318;5299.682019078868;5301.814436494743;5336.386315573681;4984.601231901439;5095.617828662865;5166.147585648364;5180.041692286939;5184.11869982104;5617.578681598897;5664.461876121762;5698.34469036062;5725.456081672276;5741.224934633813;6113.755449782004;6132.7546898124465;6142.631442113443;6145.446909778141;6152;6056.06131049688;6089.989536100471;6129.816105516842;6136.69316534176;6190.226221722285;5762.740676669518;5779.971100144472;5798.9420105799245;5811.593188476766;5812.88374232513;6081.422264884829;6327.430531252117;6341.175647165839;6354.205724284465;6359.07793369968;5938.703064846741;5967.5524335675;6011.579189456696;6090.847728806815;6097.542684298695;6121.4194740653975;6129.84675383119;6131.816045518642;6132.2947860996555;6137.693115344304;5717.714114294268;6110.80690925375;6120.020512922583;6121.513487658352;6135.263289243823;6028.577999540078;6068;6073.6659483727935;6135.814547850547;6138.1738283688655;4633.096546173464;4675.251959686434;4682.578567928903;4693.624510039189;4700.670953033323;4052.797360131982;4140.627343539036;4158.688098392633;4169.1661667666585;4174.248635245685;5618.213450116987;5752.165935939349;5817.650940943557;5827.650340979555;5932.19915311436;6200.348963358872;6202.472789812917;6208.4722798562625;6211.378862113843;6215.06773983906;5872.677002764889;5883.852903677357;5885.058390657474;5887.35239123696;5916.7337469813965;5074.619403544749;5217.8173763918085;5268.947310526845;5277.445868183862;5311.176767601028;6297.811065668037;6324.083007963912;6344.143540622049;6350.491960643138;6353.61878287295;6074.149619053248;6132.325444201224;6144.078388241799;6145.385461453998;6145.4161854623735;6130.948521380091;6297.937020629735;6320.810375688737;6379.74481020754;6466.062420948842;6095.908561371672;6105.481034111973;6105.511559075352;6111.755529778759;6119.816405507842;6251.812445626639;6254.312025677263;6256.687165641609;6257;6272.717727702173;5694.373618901835;5921.496672782861;5956.195913551788;5975.611585246945;6050.848728781815;5946.108083787466;5961.165436838759;5997.760089596367;6000.759969601167;6008.399160083956;5812.389699081705;5855.3266374365785;5867.823965281048;5870.882582348415;5885.55858310637;5792.362840087502;6018.428249316308;6078.118672792509;6083.6349819011;6148.631082135086;5974.103884417198;5981.282246130492;5989.790357337472;5999.250093738283;6024.277086749618 17:51:20 INFO - PROCESS | 5996 | |1;ext.html;601.2124117406198;624.1449214576031;634.5114262018243;646.6928209100674;647.5078940005594;35826.2834743305;45488.54511454885;47001.529984700144;47023;47169.76415117924;47712.76143619277;48058.75970620142;48122.27816582754;48148.99999999991;48158.518414815844;8349.123342049088;9240.815183696339;9387.155156035955;9391.201747851406;10918.344899306026;10829.891701082966;11317.773644527104;11320.26418282813;11325;11325.320480771139;11224.494897729623;11305.830412543777;11344.943275283653;11351.886481135165;11353.43232838359;11174.441277936112;11219.326840389604;11229.60696375631;11230.15773816967;11232;11024.614138505194;11107.72230694231;11112.44437778116;11113;11116.444177791078;45965.77017114926;46392.76803615994;46412.76793616044;46451.76774116141;46523.30215046777;3836.1560456699763;4370.606645401897;4581.473130589991;4679.836205732783;4701.059788042371;35001.824990874884;36213.27573448516;36366.27267454663;36871;36999.260014799554;4674.088552732219;4692.835750748707;4715.599174070208;4760.523947605247;4971.751412429364;382361;388539;390267;390569;390902;64322;64462.355376446576;64493.38766530874;64631.67684161549;64668.353316467175;41257;42694.573054269684;42820.78589607032;42842.571574284484;42889.14221715548;19444.51388715265;19602.411927642195;19603.803961960482;19616.11727472267;19618.21527138927;13480.258585777876;14642.194679292528;14648.780268295985;14725.926370368294;14793;21121.26075587347;21212;21355.78644213569;21363.25228616991;21439.571208576053;7552.962235188789;7599.316061554487;7727.806804829925;7772.611369431505;7846.725364612211;947.2658689515542;1003.3979612232602;1005.7234260578253;1035.140833108518;1071.0146665068155;30291.24271893176;30401.69598304033;30573.38853222923;30765.692343076284;31145.80484773151;1076.2143635146388;1087.0542627913608;1117.4859564600213;1144.891235332642;1164.714644912002;15560.144192069545;15977.360905563879;16052.197390130446;16183.757243641356;16490.34038638465;668829;1051542;1072313;1073904;1079252;66478.66760666166;66935;67042.32957670458;67162;67225;1339.7655410303157;1384.9446022159;1403.5298175111247;1447.9565613031416;1471.0364711114237;27261.72738272592;33843.492347614316;34301.313973720884;37313.440298395006;37573.4363984535;60462;61174.69412653086;61232;61241;61356.69321653363;539702;540828;543201;543862;545942;27829.721702782714;31669.841650791597;49857.252141217185;50271.748641258026;51143.488565113876;50785;55338.72330638321;55451;55650;55729.16406253991;45238;47909.5209047919;48262.5173748258;48375.27437088376;49002.284920027625;45400.982813240276;47269.527304726515;47754;47996.28005579989;48468.2729759061;47390.76304618455;47465.52534474611;47717.761411192725;48351;48499;38671;41273.380899285934;41868.58131418647;41903.16193676049;42563.57436425596;30644.540331894594;30717;30987.380252394374;31084.37831243317;31305.8434707825 17:51:20 INFO - PROCESS | 5996 | |2;jquery.html;424.9107687385649;427.2373812744251;429.3130990415334;438.3578058144817;442.2327262200082;18959.81040189598;20794;20863.165473381065;21395.251166209186;21510.89244553779;19838.40484785459;20207;20386;20567.280145194884;20638;5789.39211382804;6045.093236014589;6049.48579370754;6056.9091463627865;6089.969550152266;5826.446487583673;5914.2015827863115;5990.041593345066;6015.398460153971;6027.909581356261;5983.222181116449;5988.401159883999;5993.790217342394;5994.340622531518;5994.400559944014;5885.323187833411;5889.116632505137;5897.970510147465;5929.258842644648;5942;5302.840914772524;5329.573634109262;5358.4641535846495;5364.383095943977;5368.892622147535;24929.62605560918;25072.122475713444;25124.623130653054;25137.748622513907;25324.493510129694;2548.370937262854;2620.868956552184;2644.338915271182;2676.464707058596;2687.1669782367335;14610.269486525738;15158.772618410732;15212.695746085126;15477.380904763795;16071.517854464384;2077.5948690005457;2119.738453225467;2146.581416623759;2189.1243502598927;2246.887655617229;65545.34454655487;75502.24497755062;78167.21832781656;79304;88357.55821220853;11563.496745423041;11644.592439264585;11734.41327933617;11740;11839.230450020721;16479.752803707954;16495.257713402927;16498.75251871223;16534.173291335384;16537;8905.975812781484;9053.366264361432;9094.590743416562;9239.353245272901;9262;8990.011098779129;8992.100789920956;9024.729258122266;9196.724098277062;9254.722358329262;7055.576665400093;7076.71693132269;7083.716651333889;7187.083039743353;7266.491345605863;1986.8410527157794;2000.21991423345;2035.707325848097;2053.425040988526;2082.3648787120073;866.5364030243916;966.1594412860856;974.1573538888952;982.5087456271864;1092.4428541443922;20879.373618791462;21698.132074716836;21827.890860545594;21900.780992190194;22038.33884983421;1005.3063386263601;1009.28340877976;1012.099231683802;1079.2984560035882;1079.9244052916379;11675.76648467026;12238;12382.690432739073;12494.062945278958;12570.497180112692;486469;518261;539991;548969;550880;61709.69145154246;75295;91829.54085229531;92148.53925730329;92490;1022.0750221049962;1034.8033873564;1058.264506168221;1066.626680661762;1068.3269540189785;18785.34251301198;18875.244990200787;19617.215311387383;19728.30950916711;19968.800311997275;43479.55730266802;43952;44341;45147;45362;58442.70778646079;58769.11846322394;59044;59911.700441499255;60340.698296508235;8742.694005709769;8832.337574681796;8861.24679402258;9220.631174752934;9297.442153470813;12494.687632809253;13021.609351719464;13249.80125298102;14325.068870523382;15017.24913754286;12080.335581543099;12264.202826816228;12331.876681233432;12485.87514124847;13079.730837037449;11517;12038.879611203776;12121.454534546143;12369.62891113268;12397.504099835905;12577.748445031231;12836.358182091044;12895.548655797002;12923.353832308536;13216.867831321564;8768.298536117223;8966.596503157243;9072.90927090747;9145;9347.485888276211;12659.493620255087;12814.55149069778;12929.870701292866;13015.088943773844;13036.217826930415 17:51:20 INFO - PROCESS | 5996 | |3;mootools.html;1474.896757226994;1576.9053856768594;1751.3957684598809;1905.4855189098953;1947.357372067217;9580.568874400651;10333.58665653375;10335.483225838696;10345.844812327821;10348.37909725417;8601.870971935426;9296.25629949604;9696.515174241295;9717.854232186522;10149.644762433298;2476.170482888235;2542.0467324753217;2542.3135753346587;2542.6694529711112;2543.1226226951762;2448.4980578981267;2596.7792737617324;2599.402137508365;2600.7529284718016;2600.8959641614315;2548.210054882983;2558.0663057983793;2603.1279521360366;2607.4654695787412;2610.099515667092;2500.549901017807;2512.773850353468;2517.7608127227886;2535.6323333116593;2537.4417628121782;2458.987705061481;2460.8769561521854;2463.19946017545;2493.650888875559;2529.152733834168;9887.505624718808;9945.950270248675;9950.253730970206;9953.452560109192;9955.352902061342;5829.358770535237;5840.766369345221;5856.15085812559;5876.55925805562;5888.38171991939;3567.625399333059;3656.634336566349;3734.9439758403646;3737.177820879402;3753.493278407407;1068.1241382066708;1144.2505159120706;1146.7591805720808;1158.6350299655583;1163.2729544034978;14129.646758831112;14401.495947641886;14412.13527188372;14417.639559011004;14420.711585768331;5094.133997220481;5124.103281925648;5256.868578285574;5282.867928301823;5315.441878602768;12142.028637708961;12186;12186.08604354677;12191.207571507824;12192.207506512048;5980.73086711099;6029.698515074228;6035.426634469718;6043.456088952015;6045.637261764308;4788.377510923558;5307.946920530745;5333.466653334635;5416.0792665247;5416.106342453451;4431.778411079433;4478.955210447919;4604.194266003503;4636.559526844944;4656.3946686930485;1422.217780220895;1463.656040830404;1467.8238611366705;1467.9339429725687;1479.3269062576717;1023.3501726403787;1046.2362475393006;1083.6748975307376;1104.9337039777479;1107.9279846809932;6628.5360024798765;6676.899846502306;6831.726730930707;6896.965515172392;7031.08595882532;448.7173080958975;477.1721063954037;479.05625916943563;480.22204029471783;480.6107053286849;4765.475797662254;4878.170710979142;4942.5057494251005;4970.080535101018;5022.146235140035;11850.11124165691;12307.076969227164;12325.938370308091;12651.93674031624;12688.555900543437;12391.814122788166;12393.690157745948;12417.689557761129;12440;12455.75088498225;8727.432716873383;8747.256483198998;8977.865332019894;9031.412958157698;9038.683646072355;6620.2386725527185;6649.434798042221;6754.223264324469;6832.863342733018;6845.657717114024;11073.944630276797;11275.097992160772;11276.548938042386;11379.715507112389;11390.316581005167;11390.25963312382;11460.598879039193;11540.422978851191;11620.593279235187;11659.591914282959;8032.558209298545;8761.123887611187;8832.470051796912;8905.243054340453;9068.365214434927;9928.702138935843;9991.450470224207;10012.749681258027;10014.298999069995;10016.849747253651;8572;8758.518281494324;8882;8912.197902188835;9022;8721.127887211229;8767.605457754544;8812.955935220283;8922.55387230623;8993.325500587613;8116.431849770459;8172.673093076448;8212.794680133044;8235;8240;5681.261436013292;5681.573881958952;5769.798057067983;5787.797427090031;5819.0689489683145;5680.346760122474;5705.4009329019855;5732.168835518743;5752.971235143965;5868 17:51:20 INFO - PROCESS | 5996 | |4;prototype.html;354.1217779905833;356.2500935530707;362.8349101158973;364.18786106981446;367.87676128496935;5530.446955304467;5584.497395234428;5601.683604352974;5637.295338082739;5639.379668236501;5193.844184674456;5340.092184328664;5423.918641220375;5455;5545.805896793613;4302.440682711243;4316.2662347400865;4317.5466576009385;4320.1575692739925;4328.696991210617;4353.390525326441;4365.738055716652;4403.779811009453;4415.624671902892;4424.269995450747;4149.481314835645;4299.097189590189;4302.354646802974;4333.349997500384;4357.433533640622;4277.700560960734;4283.8861895907285;4293.763842988635;4308.719933204332;4316.417283666696;3786.6024067472795;3805.7335986480957;3840.636574016212;3855.2867719471988;3857.228554289153;5202.193659982709;5331.706756128411;5345.599080069011;5426.158945363435;5437.184422336682;4893.95106048942;4909.509049095098;4919.827806026808;4925.187344088221;4938.753062346904;5712.914306285409;5732.398098199671;5737.483626473638;5740.13897915316;5742.885142297131;4126.154138401621;4154.584541545852;4209.895052473763;4219.894502637459;4234.428352172479;5867.8533036674;5887.735051922674;5889.381614930457;5902.439268269508;5922.17089607451;4240.321548552279;4246.40550322955;4261.850835220753;4283.828646854091;4292.570742925683;4076.103257283393;4261.80669412565;4297.656187504992;4338.696291259582;4354.238008348526;4831.130396528659;4849;4864.343313652612;4875.488073752276;4882.731449770296;5734.598578099576;5780.682062486603;5789.465791565239;5804.361520232771;5817.080363480041;1462.531989763287;1471.2937789860928;1478.1722235548127;1488.598078518794;1492.753695640229;949.9123503588353;960.159860122393;969.418348990614;976.2580438866513;978.9574103579807;4915.336429581961;4921.360223170965;4923.990581930696;4934.210526315772;4934.876628084255;5181.766820493011;5184.74850622486;5191.662541934762;5200.6879587224885;5206.453322401169;4718.150732868116;4924.655274130848;4931.112399768004;4945.8021679132435;4966;2764.875580598878;2782.499150152951;2792.357757715696;2795.916122516328;2801.705820888818;5342.5993050521365;5371.221172929903;5372.409035006146;5384.326959130109;5392.742076475405;5159.251908473326;5215.295935048795;5609.775608975677;6003.459688628044;6011.819645410645;6076.939230607726;6087.939120608826;6092.75628974836;6094.2077529920825;6112.174856394418;5646.830595082154;5860.824175274749;5942.851428714317;5955.196048533393;5958.5828991971875;5749.367569567428;5805.680687562224;5862.325832529144;5874.17761513397;5881.117832325185;6053.092036194606;6101.359357267423;6118.632882027093;6160.938390616216;6161.784337548164;4209.326507758867;4269.57259613237;4376.562343765598;4417.845375411845;4425.911481770411;5736.389942576035;5835.803660249554;5866.970665146647;5877.059670452707;5879.176915231957;5701.771929122789;5736.741846616829;5740.225069615549;5757.510611598061;5763.308402991668;5652.180433837153;5688.345840228427;5724.141378793214;5739.454751798572;5753.252077229933;5675.290588676416;5701.458361455655;5722.597963498969;5726.742296596746;5728.283964504437;5622.746976385991;5816.302043754777;5835.2122463466885;5854.941450585439;5876.941230587639;5550.862073274888;5672.319321681424;5681.579605098726;5687.402822703557;5720.256366672473 17:51:20 INFO - PROCESS | 5996 | |5;yui.html;792.8810678398239;814.4054839966824;822.9506229626219;867.6616119713311;870.4559650218613;15873.84126158738;17192.312307507702;17195.484135475956;17218.3112675493;17238.224279907405;15504.224788760544;15647.217639118027;15658.686826263467;15660.295286712097;15660.451884184056;736.3446532586003;852.0584753847003;858.8539948208788;877.2674816528174;886.0165216609558;855.6149732620346;863.3007264116055;863.6286396849353;864.1358641358642;864.4035615425367;910.6994691751706;912.1334732004589;913.5204017890594;914.2868562521244;915.6749353979342;759.5670467833365;894.7673604862717;902.5532361481077;911.5806728904712;918.0452329577232;682.7780971184335;685.4995853027268;685.6057766784119;685.9245482996866;686.1628812848317;15577.844221557865;15587;15615.921920390325;15621.297041633154;15628.453004144914;14456.454217915269;14494.710105797825;14619.488317908823;15117.319720612599;15183.483269691247;12509.311987840665;12723.172993755376;14446.422143114263;14461.78307325391;14816.3332650031;4697.577218050357;4740.5496477834895;4748.240281554935;4826.251930950708;4834.806607735686;68971.65514172448;71528.6423567884;73333;79185.20814791835;81475.18524814735;12169.756604867853;12703.555375561997;12776.808347874603;12795.61613151607;12799.872001280055;23189.3043208704;23222.767772322397;23274.65088023681;23281.417964551023;23352.416189595056;9014.278857691368;9027.14242146995;9313.580888859882;9408.529573521297;9418.246540276761;4923.655344125948;5093.567046800989;5105.668131571436;5111.156659151198;5135.922961155586;3662.8168591570316;3779.60314167014;3785.6025117362833;3805.752626079296;3809.142942837839;691.4261163234503;716.8387112899555;721.6463932672953;724.3299947548602;725.2421219825295;635.1742734445203;646.7186773753432;697.7767114523303;698.1970733656336;709.670003448399;10112.241581881388;10496.947515262375;10992.780144397067;10996.12031037515;11088.675416587537;613.947814435769;685.6400389795398;690.9861802763917;697.8360085379932;701.1165930927068;5003.49965003504;5015.122353588107;5063.974680126649;5070.29015937769;5071.391433027987;69787.30212697909;101291.49354253181;103165;104589.47705261577;134127.32936335256;101334.4933275329;104252;105173;105853;107880.92119078709;89275.55362223147;93259.53370233106;95391;95695.9999999986;97764;9994.300398972002;10334.844977325196;10409.47952602382;10671.679849604525;10772.407517586607;56008.71995639996;56055;56597;56639.433605665065;57823.4217657818;73676.26323736693;81519;81695;82461;82812;12572.434240459384;13664.31678416095;13805.24071176095;13962.720745585235;14023.649408764863;14599.562013139623;15060.924695376452;15198.924005379902;16385.59036024109;16528.75206871922;14063.789043164565;14201.928990354982;14476.855231447971;15345.84654153489;15629.531114066596;13665.726685466434;14280.571582852532;14303.64240893986;14566.19885906243;15381.692366152838;12971.156874803108;13075.150115242479;13278.402471888981;13319.66700832487;13928.791068133783;11430.619722797435;11516.481758320728;11548.769024619292;11591;11887.643370698894;11547.711307217387;11943.992961841766;12021.278723276631;12311.322877241479;12440.75118497643 17:51:20 INFO - PROCESS | 5996 | __end_tp_report 17:51:20 INFO - PROCESS | 5996 | __start_cc_report 17:51:20 INFO - PROCESS | 5996 | _x_x_mozilla_cycle_collect,1005 17:51:20 INFO - PROCESS | 5996 | __end_cc_report 17:51:20 INFO - PROCESS | 5996 | __startTimestamp1472604680502__endTimestamp 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | ------- Summary: start ------- 17:51:20 INFO - PROCESS | 5996 | Number of tests: 6 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | [#0] dojo.html Cycles:165 Average:5684.63 Median:5921.50 stddev:670.19 (11.3%) stddev-sans-first:643.32 17:51:20 INFO - PROCESS | 5996 | Values: 3201.9 3215.3 3231.8 3248.4 3266.3 5554.1 5624.8 5645.4 5653.6 5657.9 5644.4 5720.3 5723.7 5728.5 5747.4 4987.5 5062.9 5144.0 5175.4 5233.9 5068.7 5118.9 5125.5 5125.8 5128.2 5138.7 5172.4 5190.4 5204.2 5218.5 5289.4 5297.2 5299.7 5301.8 5336.4 4984.6 5095.6 5166.1 5180.0 5184.1 5617.6 5664.5 5698.3 5725.5 5741.2 6113.8 6132.8 6142.6 6145.4 6152.0 6056.1 6090.0 6129.8 6136.7 6190.2 5762.7 5780.0 5798.9 5811.6 5812.9 6081.4 6327.4 6341.2 6354.2 6359.1 5938.7 5967.6 6011.6 6090.8 6097.5 6121.4 6129.8 6131.8 6132.3 6137.7 5717.7 6110.8 6120.0 6121.5 6135.3 6028.6 6068.0 6073.7 6135.8 6138.2 4633.1 4675.3 4682.6 4693.6 4700.7 4052.8 4140.6 4158.7 4169.2 4174.2 5618.2 5752.2 5817.7 5827.7 5932.2 6200.3 6202.5 6208.5 6211.4 6215.1 5872.7 5883.9 5885.1 5887.4 5916.7 5074.6 5217.8 5268.9 5277.4 5311.2 6297.8 6324.1 6344.1 6350.5 6353.6 6074.1 6132.3 6144.1 6145.4 6145.4 6130.9 6297.9 6320.8 6379.7 6466.1 6095.9 6105.5 6105.5 6111.8 6119.8 6251.8 6254.3 6256.7 6257.0 6272.7 5694.4 5921.5 5956.2 5975.6 6050.8 5946.1 5961.2 5997.8 6000.8 6008.4 5812.4 5855.3 5867.8 5870.9 5885.6 5792.4 6018.4 6078.1 6083.6 6148.6 5974.1 5981.3 5989.8 5999.3 6024.3 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | [#1] ext.html Cycles:180 Average:79280.24 Median:41570.98 stddev:187890.91 (452.0%) stddev-sans-first:188325.12 17:51:20 INFO - PROCESS | 5996 | Values: 601.2 624.1 634.5 646.7 647.5 35826.3 45488.5 47001.5 47023.0 47169.8 47712.8 48058.8 48122.3 48149.0 48158.5 8349.1 9240.8 9387.2 9391.2 10918.3 10829.9 11317.8 11320.3 11325.0 11325.3 11224.5 11305.8 11344.9 11351.9 11353.4 11174.4 11219.3 11229.6 11230.2 11232.0 11024.6 11107.7 11112.4 11113.0 11116.4 45965.8 46392.8 46412.8 46451.8 46523.3 3836.2 4370.6 4581.5 4679.8 4701.1 35001.8 36213.3 36366.3 36871.0 36999.3 4674.1 4692.8 4715.6 4760.5 4971.8 382361.0 388539.0 390267.0 390569.0 390902.0 64322.0 64462.4 64493.4 64631.7 64668.4 41257.0 42694.6 42820.8 42842.6 42889.1 19444.5 19602.4 19603.8 19616.1 19618.2 13480.3 14642.2 14648.8 14725.9 14793.0 21121.3 21212.0 21355.8 21363.3 21439.6 7553.0 7599.3 7727.8 7772.6 7846.7 947.3 1003.4 1005.7 1035.1 1071.0 30291.2 30401.7 30573.4 30765.7 31145.8 1076.2 1087.1 1117.5 1144.9 1164.7 15560.1 15977.4 16052.2 16183.8 16490.3 668829.0 1051542.0 1072313.0 1073904.0 1079252.0 66478.7 66935.0 67042.3 67162.0 67225.0 1339.8 1384.9 1403.5 1448.0 1471.0 27261.7 33843.5 34301.3 37313.4 37573.4 60462.0 61174.7 61232.0 61241.0 61356.7 539702.0 540828.0 543201.0 543862.0 545942.0 27829.7 31669.8 49857.3 50271.7 51143.5 50785.0 55338.7 55451.0 55650.0 55729.2 45238.0 47909.5 48262.5 48375.3 49002.3 45401.0 47269.5 47754.0 47996.3 48468.3 47390.8 47465.5 47717.8 48351.0 48499.0 38671.0 41273.4 41868.6 41903.2 42563.6 30644.5 30717.0 30987.4 31084.4 31305.8 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | [#2] jquery.html Cycles:180 Average:30627.75 Median:13936.43 stddev:86778.87 (622.7%) stddev-sans-first:86992.68 17:51:20 INFO - PROCESS | 5996 | Values: 424.9 427.2 429.3 438.4 442.2 18959.8 20794.0 20863.2 21395.3 21510.9 19838.4 20207.0 20386.0 20567.3 20638.0 5789.4 6045.1 6049.5 6056.9 6090.0 5826.4 5914.2 5990.0 6015.4 6027.9 5983.2 5988.4 5993.8 5994.3 5994.4 5885.3 5889.1 5898.0 5929.3 5942.0 5302.8 5329.6 5358.5 5364.4 5368.9 24929.6 25072.1 25124.6 25137.7 25324.5 2548.4 2620.9 2644.3 2676.5 2687.2 14610.3 15158.8 15212.7 15477.4 16071.5 2077.6 2119.7 2146.6 2189.1 2246.9 65545.3 75502.2 78167.2 79304.0 88357.6 11563.5 11644.6 11734.4 11740.0 11839.2 16479.8 16495.3 16498.8 16534.2 16537.0 8906.0 9053.4 9094.6 9239.4 9262.0 8990.0 8992.1 9024.7 9196.7 9254.7 7055.6 7076.7 7083.7 7187.1 7266.5 1986.8 2000.2 2035.7 2053.4 2082.4 866.5 966.2 974.2 982.5 1092.4 20879.4 21698.1 21827.9 21900.8 22038.3 1005.3 1009.3 1012.1 1079.3 1079.9 11675.8 12238.0 12382.7 12494.1 12570.5 486469.0 518261.0 539991.0 548969.0 550880.0 61709.7 75295.0 91829.5 92148.5 92490.0 1022.1 1034.8 1058.3 1066.6 1068.3 18785.3 18875.2 19617.2 19728.3 19968.8 43479.6 43952.0 44341.0 45147.0 45362.0 58442.7 58769.1 59044.0 59911.7 60340.7 8742.7 8832.3 8861.2 9220.6 9297.4 12494.7 13021.6 13249.8 14325.1 15017.2 12080.3 12264.2 12331.9 12485.9 13079.7 11517.0 12038.9 12121.5 12369.6 12397.5 12577.7 12836.4 12895.5 12923.4 13216.9 8768.3 8966.6 9072.9 9145.0 9347.5 12659.5 12814.6 12929.9 13015.1 13036.2 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | [#3] mootools.html Cycles:180 Average:6565.77 Median:2526.46 stddev:3825.38 (151.4%) stddev-sans-first:3816.98 17:51:20 INFO - PROCESS | 5996 | Values: 1474.9 1576.9 1751.4 1905.5 1947.4 9580.6 10333.6 10335.5 10345.8 10348.4 8601.9 9296.3 9696.5 9717.9 10149.6 2476.2 2542.0 2542.3 2542.7 2543.1 2448.5 2596.8 2599.4 2600.8 2600.9 2548.2 2558.1 2603.1 2607.5 2610.1 2500.5 2512.8 2517.8 2535.6 2537.4 2459.0 2460.9 2463.2 2493.7 2529.2 9887.5 9946.0 9950.3 9953.5 9955.4 5829.4 5840.8 5856.2 5876.6 5888.4 3567.6 3656.6 3734.9 3737.2 3753.5 1068.1 1144.3 1146.8 1158.6 1163.3 14129.6 14401.5 14412.1 14417.6 14420.7 5094.1 5124.1 5256.9 5282.9 5315.4 12142.0 12186.0 12186.1 12191.2 12192.2 5980.7 6029.7 6035.4 6043.5 6045.6 4788.4 5307.9 5333.5 5416.1 5416.1 4431.8 4479.0 4604.2 4636.6 4656.4 1422.2 1463.7 1467.8 1467.9 1479.3 1023.4 1046.2 1083.7 1104.9 1107.9 6628.5 6676.9 6831.7 6897.0 7031.1 448.7 477.2 479.1 480.2 480.6 4765.5 4878.2 4942.5 4970.1 5022.1 11850.1 12307.1 12325.9 12651.9 12688.6 12391.8 12393.7 12417.7 12440.0 12455.8 8727.4 8747.3 8977.9 9031.4 9038.7 6620.2 6649.4 6754.2 6832.9 6845.7 11073.9 11275.1 11276.5 11379.7 11390.3 11390.3 11460.6 11540.4 11620.6 11659.6 8032.6 8761.1 8832.5 8905.2 9068.4 9928.7 9991.5 10012.7 10014.3 10016.8 8572.0 8758.5 8882.0 8912.2 9022.0 8721.1 8767.6 8813.0 8922.6 8993.3 8116.4 8172.7 8212.8 8235.0 8240.0 5681.3 5681.6 5769.8 5787.8 5819.1 5680.3 5705.4 5732.2 5753.0 5868.0 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | [#4] prototype.html Cycles:180 Average:4768.87 Median:5341.35 stddev:1390.14 (26.0%) stddev-sans-first:1353.97 17:51:20 INFO - PROCESS | 5996 | Values: 354.1 356.3 362.8 364.2 367.9 5530.4 5584.5 5601.7 5637.3 5639.4 5193.8 5340.1 5423.9 5455.0 5545.8 4302.4 4316.3 4317.5 4320.2 4328.7 4353.4 4365.7 4403.8 4415.6 4424.3 4149.5 4299.1 4302.4 4333.3 4357.4 4277.7 4283.9 4293.8 4308.7 4316.4 3786.6 3805.7 3840.6 3855.3 3857.2 5202.2 5331.7 5345.6 5426.2 5437.2 4894.0 4909.5 4919.8 4925.2 4938.8 5712.9 5732.4 5737.5 5740.1 5742.9 4126.2 4154.6 4209.9 4219.9 4234.4 5867.9 5887.7 5889.4 5902.4 5922.2 4240.3 4246.4 4261.9 4283.8 4292.6 4076.1 4261.8 4297.7 4338.7 4354.2 4831.1 4849.0 4864.3 4875.5 4882.7 5734.6 5780.7 5789.5 5804.4 5817.1 1462.5 1471.3 1478.2 1488.6 1492.8 949.9 960.2 969.4 976.3 979.0 4915.3 4921.4 4924.0 4934.2 4934.9 5181.8 5184.7 5191.7 5200.7 5206.5 4718.2 4924.7 4931.1 4945.8 4966.0 2764.9 2782.5 2792.4 2795.9 2801.7 5342.6 5371.2 5372.4 5384.3 5392.7 5159.3 5215.3 5609.8 6003.5 6011.8 6076.9 6087.9 6092.8 6094.2 6112.2 5646.8 5860.8 5942.9 5955.2 5958.6 5749.4 5805.7 5862.3 5874.2 5881.1 6053.1 6101.4 6118.6 6160.9 6161.8 4209.3 4269.6 4376.6 4417.8 4425.9 5736.4 5835.8 5867.0 5877.1 5879.2 5701.8 5736.7 5740.2 5757.5 5763.3 5652.2 5688.3 5724.1 5739.5 5753.3 5675.3 5701.5 5722.6 5726.7 5728.3 5622.7 5816.3 5835.2 5854.9 5876.9 5550.9 5672.3 5681.6 5687.4 5720.3 17:51:20 INFO - PROCESS | 5996 | 17:51:20 INFO - PROCESS | 5996 | [#5] yui.html Cycles:180 Average:21606.59 Median:3795.68 stddev:30342.50 (799.4%) stddev-sans-first:30387.37 17:51:20 INFO - PROCESS | 5996 | Values: 792.9 814.4 823.0 867.7 870.5 15873.8 17192.3 17195.5 17218.3 17238.2 15504.2 15647.2 15658.7 15660.3 15660.5 736.3 852.1 858.9 877.3 886.0 855.6 863.3 863.6 864.1 864.4 910.7 912.1 913.5 914.3 915.7 759.6 894.8 902.6 911.6 918.0 682.8 685.5 685.6 685.9 686.2 15577.8 15587.0 15615.9 15621.3 15628.5 14456.5 14494.7 14619.5 15117.3 15183.5 12509.3 12723.2 14446.4 14461.8 14816.3 4697.6 4740.5 4748.2 4826.3 4834.8 68971.7 71528.6 73333.0 79185.2 81475.2 12169.8 12703.6 12776.8 12795.6 12799.9 23189.3 23222.8 23274.7 23281.4 23352.4 9014.3 9027.1 9313.6 9408.5 9418.2 4923.7 5093.6 5105.7 5111.2 5135.9 3662.8 3779.6 3785.6 3805.8 3809.1 691.4 716.8 721.6 724.3 725.2 635.2 646.7 697.8 698.2 709.7 10112.2 10496.9 10992.8 10996.1 11088.7 613.9 685.6 691.0 697.8 701.1 5003.5 5015.1 5064.0 5070.3 5071.4 69787.3 101291.5 103165.0 104589.5 134127.3 101334.5 104252.0 105173.0 105853.0 107880.9 89275.6 93259.5 95391.0 95696.0 97764.0 9994.3 10334.8 10409.5 10671.7 10772.4 56008.7 56055.0 56597.0 56639.4 57823.4 73676.3 81519.0 81695.0 82461.0 82812.0 12572.4 13664.3 13805.2 13962.7 14023.6 14599.6 15060.9 15198.9 16385.6 16528.8 14063.8 14201.9 14476.9 15345.8 15629.5 13665.7 14280.6 14303.6 14566.2 15381.7 12971.2 13075.2 13278.4 13319.7 13928.8 11430.6 11516.5 11548.8 11591.0 11887.6 11547.7 11944.0 12021.3 12311.3 12440.8 17:51:20 INFO - PROCESS | 5996 | -------- Summary: end -------- 17:51:20 INFO - PROCESS | 5996 | 17:51:21 INFO - TEST-INFO | 5996: exit 0 17:51:21 INFO - TEST-OK | dromaeo_css | took 1113422ms 17:51:21 INFO - TEST-START | kraken 17:51:21 INFO - operating with platform_type : linux_ 17:51:21 INFO - Initialising browser for kraken test... 17:51:21 INFO - TEST-INFO | started process 6202 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkgLLOC/profile http://localhost:40323/getInfo.html) 17:51:25 INFO - PROCESS | 6202 | __metrics Screen width/height:1600/1200 17:51:25 INFO - PROCESS | 6202 | colorDepth:24 17:51:25 INFO - PROCESS | 6202 | Browser inner width/height: 1024/697 17:51:25 INFO - PROCESS | 6202 | __metrics 17:51:26 INFO - PROCESS | 6202 | 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" 17:51:26 INFO - PROCESS | 6202 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:51:27 INFO - TEST-INFO | 6202: exit 0 17:51:27 INFO - Browser initialized. 17:51:27 INFO - Running cycle 1/1 for kraken test... 17:51:27 INFO - TEST-INFO | started process 6290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkgLLOC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:51:38 INFO - PROCESS | 6290 | RSS: Main: 145313792 17:51:38 INFO - PROCESS | 6290 | 17:51:41 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40323/tests/kraken/driver.html?testName=audio-beat-detection) 17:51:42 INFO - PROCESS | 6290 | RSS: Main: 191705088 17:51:42 INFO - PROCESS | 6290 | 17:51:44 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40323/tests/kraken/driver.html?testName=audio-dft) 17:51:45 INFO - PROCESS | 6290 | RSS: Main: 181673984 17:51:45 INFO - PROCESS | 6290 | 17:51:47 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40323/tests/kraken/driver.html?testName=audio-fft) 17:51:48 INFO - PROCESS | 6290 | RSS: Main: 269770752 17:51:48 INFO - PROCESS | 6290 | 17:51:50 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40323/tests/kraken/driver.html?testName=audio-oscillator) 17:51:51 INFO - PROCESS | 6290 | RSS: Main: 175484928 17:51:51 INFO - PROCESS | 6290 | 17:51:53 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40323/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:51:53 INFO - PROCESS | 6290 | RSS: Main: 177115136 17:51:53 INFO - PROCESS | 6290 | 17:51:56 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40323/tests/kraken/driver.html?testName=imaging-darkroom) 17:51:57 INFO - PROCESS | 6290 | RSS: Main: 213336064 17:51:57 INFO - PROCESS | 6290 | 17:52:00 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40323/tests/kraken/driver.html?testName=imaging-desaturate) 17:52:01 INFO - PROCESS | 6290 | RSS: Main: 212172800 17:52:01 INFO - PROCESS | 6290 | 17:52:04 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40323/tests/kraken/driver.html?testName=json-parse-financial) 17:52:04 INFO - PROCESS | 6290 | RSS: Main: 211513344 17:52:04 INFO - PROCESS | 6290 | 17:52:06 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40323/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:52:06 INFO - PROCESS | 6290 | RSS: Main: 176328704 17:52:06 INFO - PROCESS | 6290 | 17:52:08 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:52:09 INFO - PROCESS | 6290 | RSS: Main: 183181312 17:52:09 INFO - PROCESS | 6290 | 17:52:11 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:52:11 INFO - PROCESS | 6290 | RSS: Main: 186695680 17:52:11 INFO - PROCESS | 6290 | 17:52:14 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:52:15 INFO - PROCESS | 6290 | RSS: Main: 182435840 17:52:15 INFO - PROCESS | 6290 | 17:52:17 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:52:18 INFO - PROCESS | 6290 | RSS: Main: 184229888 17:52:18 INFO - PROCESS | 6290 | 17:52:19 INFO - PROCESS | 6290 | Cycle 1(1): loaded http://localhost:40323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40323/tests/kraken/driver.html?testName=ai-astar) 17:52:20 INFO - PROCESS | 6290 | RSS: Main: 235540480 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | __start_tp_report 17:52:20 INFO - PROCESS | 6290 | _x_x_mozilla_page_load 17:52:20 INFO - PROCESS | 6290 | _x_x_mozilla_page_load_details 17:52:20 INFO - PROCESS | 6290 | |i|pagename|runs| 17:52:20 INFO - PROCESS | 6290 | |0;ai-astar;100;110;108;109;108;109;108;109;108;109 17:52:20 INFO - PROCESS | 6290 | |1;audio-beat-detection;145;115;115;168;115;119;145;116;158;116 17:52:20 INFO - PROCESS | 6290 | |2;audio-dft;161;160;160;161;161;163;161;161;160;159 17:52:20 INFO - PROCESS | 6290 | |3;audio-fft;76;75;76;114;76;78;76;74;99;75 17:52:20 INFO - PROCESS | 6290 | |4;audio-oscillator;84;83;135;84;84;83;114;84;84;132 17:52:20 INFO - PROCESS | 6290 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;116;117;117 17:52:20 INFO - PROCESS | 6290 | |6;imaging-darkroom;153;145;145;145;145;145;145;145;145;145 17:52:20 INFO - PROCESS | 6290 | |7;imaging-desaturate;95;94;95;95;95;94;94;94;94;94 17:52:20 INFO - PROCESS | 6290 | |8;json-parse-financial;50;49;50;50;49;58;53;50;49;49 17:52:20 INFO - PROCESS | 6290 | |9;json-stringify-tinderbox;60;61;61;60;60;59;60;60;60;61 17:52:20 INFO - PROCESS | 6290 | |10;stanford-crypto-aes;73;73;72;82;73;77;73;81;77;73 17:52:20 INFO - PROCESS | 6290 | |11;stanford-crypto-ccm;116;121;123;127;118;114;125;129;157;125 17:52:20 INFO - PROCESS | 6290 | |12;stanford-crypto-pbkdf2;157;152;151;151;150;156;151;151;156;150 17:52:20 INFO - PROCESS | 6290 | |13;stanford-crypto-sha256-iterative;62;62;62;63;62;64;63;60;64;61 17:52:20 INFO - PROCESS | 6290 | __end_tp_report 17:52:20 INFO - PROCESS | 6290 | __start_cc_report 17:52:20 INFO - PROCESS | 6290 | _x_x_mozilla_cycle_collect,1592 17:52:20 INFO - PROCESS | 6290 | __end_cc_report 17:52:20 INFO - PROCESS | 6290 | __startTimestamp1472604740144__endTimestamp 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | ------- Summary: start ------- 17:52:20 INFO - PROCESS | 6290 | Number of tests: 14 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#0] ai-astar Cycles:10 Average:107.80 Median:109.00 stddev:2.82 (2.6%) stddev-sans-first:0.71 17:52:20 INFO - PROCESS | 6290 | Values: 100.0 110.0 108.0 109.0 108.0 109.0 108.0 109.0 108.0 109.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:132.00 stddev:20.69 (15.7%) stddev-sans-first:21.33 17:52:20 INFO - PROCESS | 6290 | Values: 145.0 115.0 115.0 168.0 115.0 119.0 145.0 116.0 158.0 116.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 17:52:20 INFO - PROCESS | 6290 | Values: 161.0 160.0 160.0 161.0 161.0 163.0 161.0 161.0 160.0 159.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:13.48 (17.7%) stddev-sans-first:14.13 17:52:20 INFO - PROCESS | 6290 | Values: 76.0 75.0 76.0 114.0 76.0 78.0 76.0 74.0 99.0 75.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:84.00 stddev:21.59 (25.7%) stddev-sans-first:22.40 17:52:20 INFO - PROCESS | 6290 | Values: 84.0 83.0 135.0 84.0 84.0 83.0 114.0 84.0 84.0 132.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 17:52:20 INFO - PROCESS | 6290 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 117.0 117.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:145.00 stddev:2.53 (1.7%) stddev-sans-first:0.00 17:52:20 INFO - PROCESS | 6290 | Values: 153.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 17:52:20 INFO - PROCESS | 6290 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.00 stddev:2.83 (5.7%) stddev-sans-first:2.99 17:52:20 INFO - PROCESS | 6290 | Values: 50.0 49.0 50.0 50.0 49.0 58.0 53.0 50.0 49.0 49.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 17:52:20 INFO - PROCESS | 6290 | Values: 60.0 61.0 61.0 60.0 60.0 59.0 60.0 60.0 60.0 61.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.00 stddev:3.66 (4.9%) stddev-sans-first:3.77 17:52:20 INFO - PROCESS | 6290 | Values: 73.0 73.0 72.0 82.0 73.0 77.0 73.0 81.0 77.0 73.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:125.00 stddev:12.08 (9.7%) stddev-sans-first:12.31 17:52:20 INFO - PROCESS | 6290 | Values: 116.0 121.0 123.0 127.0 118.0 114.0 125.0 129.0 157.0 125.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:151.50 stddev:2.72 (1.8%) stddev-sans-first:2.35 17:52:20 INFO - PROCESS | 6290 | Values: 157.0 152.0 151.0 151.0 150.0 156.0 151.0 151.0 156.0 150.0 17:52:20 INFO - PROCESS | 6290 | 17:52:20 INFO - PROCESS | 6290 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:1.25 (2.0%) stddev-sans-first:1.32 17:52:20 INFO - PROCESS | 6290 | Values: 62.0 62.0 62.0 63.0 62.0 64.0 63.0 60.0 64.0 61.0 17:52:20 INFO - PROCESS | 6290 | -------- Summary: end -------- 17:52:20 INFO - PROCESS | 6290 | 17:52:21 INFO - TEST-INFO | 6290: exit 0 17:52:21 INFO - TEST-OK | kraken | took 59965ms 17:52:21 INFO - SUITE-END | took 1173s 17:52:21 INFO - Completed test suite (00:19:33) 17:52:21 INFO - javascript benchmark 17:52:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3201.8559164837593, 3215.26049008728, 3231.8384080795963, 3248.3665685191395, 3266.2650903546705, 5554.1391084381885, 5624.77500899964, 5645.435456454353, 5653.604247702658, 5657.9434205657935, 5644.350899646542, 5720.256366672337, 5723.685197314146, 5728.541716662668, 5747.3677895431465, 4987.476314986929, 5062.924056139161, 5144.048351055048, 5175.404828444721, 5233.921491177635, 5068.670536415139, 5118.948810511884, 5125.487451254863, 5125.769815244334, 5128.230765385185, 5138.665986710852, 5172.431032586413, 5190.377154741436, 5204.167333226685, 5218.530332270095, 5289.44460831611, 5297.231901374318, 5299.682019078868, 5301.814436494743, 5336.386315573681, 4984.601231901439, 5095.617828662865, 5166.147585648364, 5180.041692286939, 5184.11869982104, 5617.578681598897, 5664.461876121762, 5698.34469036062, 5725.456081672276, 5741.224934633813, 6113.755449782004, 6132.7546898124465, 6142.631442113443, 6145.446909778141, 6152.0, 6056.06131049688, 6089.989536100471, 6129.816105516842, 6136.69316534176, 6190.226221722285, 5762.740676669518, 5779.971100144472, 5798.9420105799245, 5811.593188476766, 5812.88374232513, 6081.422264884829, 6327.430531252117, 6341.175647165839, 6354.205724284465, 6359.07793369968, 5938.703064846741, 5967.5524335675, 6011.579189456696, 6090.847728806815, 6097.542684298695, 6121.4194740653975, 6129.84675383119, 6131.816045518642, 6132.2947860996555, 6137.693115344304, 5717.714114294268, 6110.80690925375, 6120.020512922583, 6121.513487658352, 6135.263289243823, 6028.577999540078, 6068.0, 6073.6659483727935, 6135.814547850547, 6138.1738283688655, 4633.096546173464, 4675.251959686434, 4682.578567928903, 4693.624510039189, 4700.670953033323, 4052.797360131982, 4140.627343539036, 4158.688098392633, 4169.1661667666585, 4174.248635245685, 5618.213450116987, 5752.165935939349, 5817.650940943557, 5827.650340979555, 5932.19915311436, 6200.348963358872, 6202.472789812917, 6208.4722798562625, 6211.378862113843, 6215.06773983906, 5872.677002764889, 5883.852903677357, 5885.058390657474, 5887.35239123696, 5916.7337469813965, 5074.619403544749, 5217.8173763918085, 5268.947310526845, 5277.445868183862, 5311.176767601028, 6297.811065668037, 6324.083007963912, 6344.143540622049, 6350.491960643138, 6353.61878287295, 6074.149619053248, 6132.325444201224, 6144.078388241799, 6145.385461453998, 6145.4161854623735, 6130.948521380091, 6297.937020629735, 6320.810375688737, 6379.74481020754, 6466.062420948842, 6095.908561371672, 6105.481034111973, 6105.511559075352, 6111.755529778759, 6119.816405507842, 6251.812445626639, 6254.312025677263, 6256.687165641609, 6257.0, 6272.717727702173, 5694.373618901835, 5921.496672782861, 5956.195913551788, 5975.611585246945, 6050.848728781815, 5946.108083787466, 5961.165436838759, 5997.760089596367, 6000.759969601167, 6008.399160083956, 5812.389699081705, 5855.3266374365785, 5867.823965281048, 5870.882582348415, 5885.55858310637, 5792.362840087502, 6018.428249316308, 6078.118672792509, 6083.6349819011, 6148.631082135086, 5974.103884417198, 5981.282246130492, 5989.790357337472, 5999.250093738283, 6024.277086749618], "value": 5637.430793476409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.2124117406198, 624.1449214576031, 634.5114262018243, 646.6928209100674, 647.5078940005594, 35826.2834743305, 45488.54511454885, 47001.529984700144, 47023.0, 47169.76415117924, 47712.76143619277, 48058.75970620142, 48122.27816582754, 48148.99999999991, 48158.518414815844, 8349.123342049088, 9240.815183696339, 9387.155156035955, 9391.201747851406, 10918.344899306026, 10829.891701082966, 11317.773644527104, 11320.26418282813, 11325.0, 11325.320480771139, 11224.494897729623, 11305.830412543777, 11344.943275283653, 11351.886481135165, 11353.43232838359, 11174.441277936112, 11219.326840389604, 11229.60696375631, 11230.15773816967, 11232.0, 11024.614138505194, 11107.72230694231, 11112.44437778116, 11113.0, 11116.444177791078, 45965.77017114926, 46392.76803615994, 46412.76793616044, 46451.76774116141, 46523.30215046777, 3836.1560456699763, 4370.606645401897, 4581.473130589991, 4679.836205732783, 4701.059788042371, 35001.824990874884, 36213.27573448516, 36366.27267454663, 36871.0, 36999.260014799554, 4674.088552732219, 4692.835750748707, 4715.599174070208, 4760.523947605247, 4971.751412429364, 382361.0, 388539.0, 390267.0, 390569.0, 390902.0, 64322.0, 64462.355376446576, 64493.38766530874, 64631.67684161549, 64668.353316467175, 41257.0, 42694.573054269684, 42820.78589607032, 42842.571574284484, 42889.14221715548, 19444.51388715265, 19602.411927642195, 19603.803961960482, 19616.11727472267, 19618.21527138927, 13480.258585777876, 14642.194679292528, 14648.780268295985, 14725.926370368294, 14793.0, 21121.26075587347, 21212.0, 21355.78644213569, 21363.25228616991, 21439.571208576053, 7552.962235188789, 7599.316061554487, 7727.806804829925, 7772.611369431505, 7846.725364612211, 947.2658689515542, 1003.3979612232602, 1005.7234260578253, 1035.140833108518, 1071.0146665068155, 30291.24271893176, 30401.69598304033, 30573.38853222923, 30765.692343076284, 31145.80484773151, 1076.2143635146388, 1087.0542627913608, 1117.4859564600213, 1144.891235332642, 1164.714644912002, 15560.144192069545, 15977.360905563879, 16052.197390130446, 16183.757243641356, 16490.34038638465, 668829.0, 1051542.0, 1072313.0, 1073904.0, 1079252.0, 66478.66760666166, 66935.0, 67042.32957670458, 67162.0, 67225.0, 1339.7655410303157, 1384.9446022159, 1403.5298175111247, 1447.9565613031416, 1471.0364711114237, 27261.72738272592, 33843.492347614316, 34301.313973720884, 37313.440298395006, 37573.4363984535, 60462.0, 61174.69412653086, 61232.0, 61241.0, 61356.69321653363, 539702.0, 540828.0, 543201.0, 543862.0, 545942.0, 27829.721702782714, 31669.841650791597, 49857.252141217185, 50271.748641258026, 51143.488565113876, 50785.0, 55338.72330638321, 55451.0, 55650.0, 55729.16406253991, 45238.0, 47909.5209047919, 48262.5173748258, 48375.27437088376, 49002.284920027625, 45400.982813240276, 47269.527304726515, 47754.0, 47996.28005579989, 48468.2729759061, 47390.76304618455, 47465.52534474611, 47717.761411192725, 48351.0, 48499.0, 38671.0, 41273.380899285934, 41868.58131418647, 41903.16193676049, 42563.57436425596, 30644.540331894594, 30717.0, 30987.380252394374, 31084.37831243317, 31305.8434707825], "value": 22708.08812166421, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.9107687385649, 427.2373812744251, 429.3130990415334, 438.3578058144817, 442.2327262200082, 18959.81040189598, 20794.0, 20863.165473381065, 21395.251166209186, 21510.89244553779, 19838.40484785459, 20207.0, 20386.0, 20567.280145194884, 20638.0, 5789.39211382804, 6045.093236014589, 6049.48579370754, 6056.9091463627865, 6089.969550152266, 5826.446487583673, 5914.2015827863115, 5990.041593345066, 6015.398460153971, 6027.909581356261, 5983.222181116449, 5988.401159883999, 5993.790217342394, 5994.340622531518, 5994.400559944014, 5885.323187833411, 5889.116632505137, 5897.970510147465, 5929.258842644648, 5942.0, 5302.840914772524, 5329.573634109262, 5358.4641535846495, 5364.383095943977, 5368.892622147535, 24929.62605560918, 25072.122475713444, 25124.623130653054, 25137.748622513907, 25324.493510129694, 2548.370937262854, 2620.868956552184, 2644.338915271182, 2676.464707058596, 2687.1669782367335, 14610.269486525738, 15158.772618410732, 15212.695746085126, 15477.380904763795, 16071.517854464384, 2077.5948690005457, 2119.738453225467, 2146.581416623759, 2189.1243502598927, 2246.887655617229, 65545.34454655487, 75502.24497755062, 78167.21832781656, 79304.0, 88357.55821220853, 11563.496745423041, 11644.592439264585, 11734.41327933617, 11740.0, 11839.230450020721, 16479.752803707954, 16495.257713402927, 16498.75251871223, 16534.173291335384, 16537.0, 8905.975812781484, 9053.366264361432, 9094.590743416562, 9239.353245272901, 9262.0, 8990.011098779129, 8992.100789920956, 9024.729258122266, 9196.724098277062, 9254.722358329262, 7055.576665400093, 7076.71693132269, 7083.716651333889, 7187.083039743353, 7266.491345605863, 1986.8410527157794, 2000.21991423345, 2035.707325848097, 2053.425040988526, 2082.3648787120073, 866.5364030243916, 966.1594412860856, 974.1573538888952, 982.5087456271864, 1092.4428541443922, 20879.373618791462, 21698.132074716836, 21827.890860545594, 21900.780992190194, 22038.33884983421, 1005.3063386263601, 1009.28340877976, 1012.099231683802, 1079.2984560035882, 1079.9244052916379, 11675.76648467026, 12238.0, 12382.690432739073, 12494.062945278958, 12570.497180112692, 486469.0, 518261.0, 539991.0, 548969.0, 550880.0, 61709.69145154246, 75295.0, 91829.54085229531, 92148.53925730329, 92490.0, 1022.0750221049962, 1034.8033873564, 1058.264506168221, 1066.626680661762, 1068.3269540189785, 18785.34251301198, 18875.244990200787, 19617.215311387383, 19728.30950916711, 19968.800311997275, 43479.55730266802, 43952.0, 44341.0, 45147.0, 45362.0, 58442.70778646079, 58769.11846322394, 59044.0, 59911.700441499255, 60340.698296508235, 8742.694005709769, 8832.337574681796, 8861.24679402258, 9220.631174752934, 9297.442153470813, 12494.687632809253, 13021.609351719464, 13249.80125298102, 14325.068870523382, 15017.24913754286, 12080.335581543099, 12264.202826816228, 12331.876681233432, 12485.87514124847, 13079.730837037449, 11517.0, 12038.879611203776, 12121.454534546143, 12369.62891113268, 12397.504099835905, 12577.748445031231, 12836.358182091044, 12895.548655797002, 12923.353832308536, 13216.867831321564, 8768.298536117223, 8966.596503157243, 9072.90927090747, 9145.0, 9347.485888276211, 12659.493620255087, 12814.55149069778, 12929.870701292866, 13015.088943773844, 13036.217826930415], "value": 9955.557332170527, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.896757226994, 1576.9053856768594, 1751.3957684598809, 1905.4855189098953, 1947.357372067217, 9580.568874400651, 10333.58665653375, 10335.483225838696, 10345.844812327821, 10348.37909725417, 8601.870971935426, 9296.25629949604, 9696.515174241295, 9717.854232186522, 10149.644762433298, 2476.170482888235, 2542.0467324753217, 2542.3135753346587, 2542.6694529711112, 2543.1226226951762, 2448.4980578981267, 2596.7792737617324, 2599.402137508365, 2600.7529284718016, 2600.8959641614315, 2548.210054882983, 2558.0663057983793, 2603.1279521360366, 2607.4654695787412, 2610.099515667092, 2500.549901017807, 2512.773850353468, 2517.7608127227886, 2535.6323333116593, 2537.4417628121782, 2458.987705061481, 2460.8769561521854, 2463.19946017545, 2493.650888875559, 2529.152733834168, 9887.505624718808, 9945.950270248675, 9950.253730970206, 9953.452560109192, 9955.352902061342, 5829.358770535237, 5840.766369345221, 5856.15085812559, 5876.55925805562, 5888.38171991939, 3567.625399333059, 3656.634336566349, 3734.9439758403646, 3737.177820879402, 3753.493278407407, 1068.1241382066708, 1144.2505159120706, 1146.7591805720808, 1158.6350299655583, 1163.2729544034978, 14129.646758831112, 14401.495947641886, 14412.13527188372, 14417.639559011004, 14420.711585768331, 5094.133997220481, 5124.103281925648, 5256.868578285574, 5282.867928301823, 5315.441878602768, 12142.028637708961, 12186.0, 12186.08604354677, 12191.207571507824, 12192.207506512048, 5980.73086711099, 6029.698515074228, 6035.426634469718, 6043.456088952015, 6045.637261764308, 4788.377510923558, 5307.946920530745, 5333.466653334635, 5416.0792665247, 5416.106342453451, 4431.778411079433, 4478.955210447919, 4604.194266003503, 4636.559526844944, 4656.3946686930485, 1422.217780220895, 1463.656040830404, 1467.8238611366705, 1467.9339429725687, 1479.3269062576717, 1023.3501726403787, 1046.2362475393006, 1083.6748975307376, 1104.9337039777479, 1107.9279846809932, 6628.5360024798765, 6676.899846502306, 6831.726730930707, 6896.965515172392, 7031.08595882532, 448.7173080958975, 477.1721063954037, 479.05625916943563, 480.22204029471783, 480.6107053286849, 4765.475797662254, 4878.170710979142, 4942.5057494251005, 4970.080535101018, 5022.146235140035, 11850.11124165691, 12307.076969227164, 12325.938370308091, 12651.93674031624, 12688.555900543437, 12391.814122788166, 12393.690157745948, 12417.689557761129, 12440.0, 12455.75088498225, 8727.432716873383, 8747.256483198998, 8977.865332019894, 9031.412958157698, 9038.683646072355, 6620.2386725527185, 6649.434798042221, 6754.223264324469, 6832.863342733018, 6845.657717114024, 11073.944630276797, 11275.097992160772, 11276.548938042386, 11379.715507112389, 11390.316581005167, 11390.25963312382, 11460.598879039193, 11540.422978851191, 11620.593279235187, 11659.591914282959, 8032.558209298545, 8761.123887611187, 8832.470051796912, 8905.243054340453, 9068.365214434927, 9928.702138935843, 9991.450470224207, 10012.749681258027, 10014.298999069995, 10016.849747253651, 8572.0, 8758.518281494324, 8882.0, 8912.197902188835, 9022.0, 8721.127887211229, 8767.605457754544, 8812.955935220283, 8922.55387230623, 8993.325500587613, 8116.431849770459, 8172.673093076448, 8212.794680133044, 8235.0, 8240.0, 5681.261436013292, 5681.573881958952, 5769.798057067983, 5787.797427090031, 5819.0689489683145, 5680.346760122474, 5705.4009329019855, 5732.168835518743, 5752.971235143965, 5868.0], "value": 5106.757519466933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.1217779905833, 356.2500935530707, 362.8349101158973, 364.18786106981446, 367.87676128496935, 5530.446955304467, 5584.497395234428, 5601.683604352974, 5637.295338082739, 5639.379668236501, 5193.844184674456, 5340.092184328664, 5423.918641220375, 5455.0, 5545.805896793613, 4302.440682711243, 4316.2662347400865, 4317.5466576009385, 4320.1575692739925, 4328.696991210617, 4353.390525326441, 4365.738055716652, 4403.779811009453, 4415.624671902892, 4424.269995450747, 4149.481314835645, 4299.097189590189, 4302.354646802974, 4333.349997500384, 4357.433533640622, 4277.700560960734, 4283.8861895907285, 4293.763842988635, 4308.719933204332, 4316.417283666696, 3786.6024067472795, 3805.7335986480957, 3840.636574016212, 3855.2867719471988, 3857.228554289153, 5202.193659982709, 5331.706756128411, 5345.599080069011, 5426.158945363435, 5437.184422336682, 4893.95106048942, 4909.509049095098, 4919.827806026808, 4925.187344088221, 4938.753062346904, 5712.914306285409, 5732.398098199671, 5737.483626473638, 5740.13897915316, 5742.885142297131, 4126.154138401621, 4154.584541545852, 4209.895052473763, 4219.894502637459, 4234.428352172479, 5867.8533036674, 5887.735051922674, 5889.381614930457, 5902.439268269508, 5922.17089607451, 4240.321548552279, 4246.40550322955, 4261.850835220753, 4283.828646854091, 4292.570742925683, 4076.103257283393, 4261.80669412565, 4297.656187504992, 4338.696291259582, 4354.238008348526, 4831.130396528659, 4849.0, 4864.343313652612, 4875.488073752276, 4882.731449770296, 5734.598578099576, 5780.682062486603, 5789.465791565239, 5804.361520232771, 5817.080363480041, 1462.531989763287, 1471.2937789860928, 1478.1722235548127, 1488.598078518794, 1492.753695640229, 949.9123503588353, 960.159860122393, 969.418348990614, 976.2580438866513, 978.9574103579807, 4915.336429581961, 4921.360223170965, 4923.990581930696, 4934.210526315772, 4934.876628084255, 5181.766820493011, 5184.74850622486, 5191.662541934762, 5200.6879587224885, 5206.453322401169, 4718.150732868116, 4924.655274130848, 4931.112399768004, 4945.8021679132435, 4966.0, 2764.875580598878, 2782.499150152951, 2792.357757715696, 2795.916122516328, 2801.705820888818, 5342.5993050521365, 5371.221172929903, 5372.409035006146, 5384.326959130109, 5392.742076475405, 5159.251908473326, 5215.295935048795, 5609.775608975677, 6003.459688628044, 6011.819645410645, 6076.939230607726, 6087.939120608826, 6092.75628974836, 6094.2077529920825, 6112.174856394418, 5646.830595082154, 5860.824175274749, 5942.851428714317, 5955.196048533393, 5958.5828991971875, 5749.367569567428, 5805.680687562224, 5862.325832529144, 5874.17761513397, 5881.117832325185, 6053.092036194606, 6101.359357267423, 6118.632882027093, 6160.938390616216, 6161.784337548164, 4209.326507758867, 4269.57259613237, 4376.562343765598, 4417.845375411845, 4425.911481770411, 5736.389942576035, 5835.803660249554, 5866.970665146647, 5877.059670452707, 5879.176915231957, 5701.771929122789, 5736.741846616829, 5740.225069615549, 5757.510611598061, 5763.308402991668, 5652.180433837153, 5688.345840228427, 5724.141378793214, 5739.454751798572, 5753.252077229933, 5675.290588676416, 5701.458361455655, 5722.597963498969, 5726.742296596746, 5728.283964504437, 5622.746976385991, 5816.302043754777, 5835.2122463466885, 5854.941450585439, 5876.941230587639, 5550.862073274888, 5672.319321681424, 5681.579605098726, 5687.402822703557, 5720.256366672473], "value": 4331.932459188523, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.8810678398239, 814.4054839966824, 822.9506229626219, 867.6616119713311, 870.4559650218613, 15873.84126158738, 17192.312307507702, 17195.484135475956, 17218.3112675493, 17238.224279907405, 15504.224788760544, 15647.217639118027, 15658.686826263467, 15660.295286712097, 15660.451884184056, 736.3446532586003, 852.0584753847003, 858.8539948208788, 877.2674816528174, 886.0165216609558, 855.6149732620346, 863.3007264116055, 863.6286396849353, 864.1358641358642, 864.4035615425367, 910.6994691751706, 912.1334732004589, 913.5204017890594, 914.2868562521244, 915.6749353979342, 759.5670467833365, 894.7673604862717, 902.5532361481077, 911.5806728904712, 918.0452329577232, 682.7780971184335, 685.4995853027268, 685.6057766784119, 685.9245482996866, 686.1628812848317, 15577.844221557865, 15587.0, 15615.921920390325, 15621.297041633154, 15628.453004144914, 14456.454217915269, 14494.710105797825, 14619.488317908823, 15117.319720612599, 15183.483269691247, 12509.311987840665, 12723.172993755376, 14446.422143114263, 14461.78307325391, 14816.3332650031, 4697.577218050357, 4740.5496477834895, 4748.240281554935, 4826.251930950708, 4834.806607735686, 68971.65514172448, 71528.6423567884, 73333.0, 79185.20814791835, 81475.18524814735, 12169.756604867853, 12703.555375561997, 12776.808347874603, 12795.61613151607, 12799.872001280055, 23189.3043208704, 23222.767772322397, 23274.65088023681, 23281.417964551023, 23352.416189595056, 9014.278857691368, 9027.14242146995, 9313.580888859882, 9408.529573521297, 9418.246540276761, 4923.655344125948, 5093.567046800989, 5105.668131571436, 5111.156659151198, 5135.922961155586, 3662.8168591570316, 3779.60314167014, 3785.6025117362833, 3805.752626079296, 3809.142942837839, 691.4261163234503, 716.8387112899555, 721.6463932672953, 724.3299947548602, 725.2421219825295, 635.1742734445203, 646.7186773753432, 697.7767114523303, 698.1970733656336, 709.670003448399, 10112.241581881388, 10496.947515262375, 10992.780144397067, 10996.12031037515, 11088.675416587537, 613.947814435769, 685.6400389795398, 690.9861802763917, 697.8360085379932, 701.1165930927068, 5003.49965003504, 5015.122353588107, 5063.974680126649, 5070.29015937769, 5071.391433027987, 69787.30212697909, 101291.49354253181, 103165.0, 104589.47705261577, 134127.32936335256, 101334.4933275329, 104252.0, 105173.0, 105853.0, 107880.92119078709, 89275.55362223147, 93259.53370233106, 95391.0, 95695.9999999986, 97764.0, 9994.300398972002, 10334.844977325196, 10409.47952602382, 10671.679849604525, 10772.407517586607, 56008.71995639996, 56055.0, 56597.0, 56639.433605665065, 57823.4217657818, 73676.26323736693, 81519.0, 81695.0, 82461.0, 82812.0, 12572.434240459384, 13664.31678416095, 13805.24071176095, 13962.720745585235, 14023.649408764863, 14599.562013139623, 15060.924695376452, 15198.924005379902, 16385.59036024109, 16528.75206871922, 14063.789043164565, 14201.928990354982, 14476.855231447971, 15345.84654153489, 15629.531114066596, 13665.726685466434, 14280.571582852532, 14303.64240893986, 14566.19885906243, 15381.692366152838, 12971.156874803108, 13075.150115242479, 13278.402471888981, 13319.66700832487, 13928.791068133783, 11430.619722797435, 11516.481758320728, 11548.769024619292, 11591.0, 11887.643370698894, 11547.711307217387, 11943.992961841766, 12021.278723276631, 12311.322877241479, 12440.75118497643], "value": 8033.70381210534, "unit": "score"}], "name": "dromaeo_css", "value": 7807.630425587322, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 108.0, 109.0, 108.0, 109.0, 108.0, 109.0, 108.0, 109.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 115.0, 168.0, 115.0, 119.0, 145.0, 116.0, 158.0, 116.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 163.0, 161.0, 161.0, 160.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 114.0, 76.0, 78.0, 76.0, 74.0, 99.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 135.0, 84.0, 84.0, 83.0, 114.0, 84.0, 84.0, 132.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 50.0, 49.0, 58.0, 53.0, 50.0, 49.0, 49.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 82.0, 73.0, 77.0, 73.0, 81.0, 77.0, 73.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 121.0, 123.0, 127.0, 118.0, 114.0, 125.0, 129.0, 157.0, 125.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 152.0, 151.0, 151.0, 150.0, 156.0, 151.0, 151.0, 156.0, 150.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 63.0, 62.0, 64.0, 63.0, 60.0, 64.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1461.8000000000002, "alertThreshold": 2.0}]} 17:52:21 INFO - Return code: 0 17:52:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:52:21 INFO - # TBPL SUCCESS # 17:52:21 INFO - Running post-action listener: _resource_record_post_action 17:52:21 INFO - [mozharness: 2016-08-31 00:52:21.475164Z] Finished run-tests step (success) 17:52:21 INFO - Running post-run listener: _resource_record_post_run 17:52:21 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 274432; Write bytes: 329723904; Read time: 208; Write time: 173968 17:52:21 INFO - TinderboxPrint: CPU usage
12.9% 17:52:21 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 208 17:52:21 INFO - TinderboxPrint: I/O write bytes / time
329,723,904 / 173,968 17:52:21 INFO - TinderboxPrint: CPU idle
8,267.4 (87.0%) 17:52:21 INFO - TinderboxPrint: CPU user
1,186.1 (12.5%) 17:52:21 INFO - TinderboxPrint: Swap in / out
0 / 0 17:52:21 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77205504; Read time: 0; Write time: 39336 17:52:21 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 274432; Write bytes: 250060800; Read time: 208; Write time: 134396 17:52:22 INFO - Running post-run listener: _upload_blobber_files 17:52:22 INFO - Blob upload gear active. 17:52:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:52:22 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 17:52:22 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'] 17:52:22 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 17:52:22 INFO - (blobuploader) - INFO - Open directory for files ... 17:52:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:52:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:52:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:52:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:52:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:52:23 INFO - (blobuploader) - INFO - Done attempting. 17:52:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:52:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:52:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:52:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:52:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:52:28 INFO - (blobuploader) - INFO - Done attempting. 17:52:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:52:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:52:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:52:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:52:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:52:29 INFO - (blobuploader) - INFO - Done attempting. 17:52:29 INFO - (blobuploader) - INFO - Iteration through files over. 17:52:29 INFO - Return code: 0 17:52:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:52:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:52:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb4cffd4a1b428348e4bbed46f08b9b7d302a3a4483c51150db59f28c64d7b907fd8349485b0be6b0678246aa5453b924008dead73a325a84cccfa3bd593087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27a6f344a80aa0141585f4ff9302ae7620e39a0696a10b7daa067874eaed7fe416c01f143b546a074c613c344d1b4062b42675a3a7832620ce542bc63a61e4c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25860a371476afdef579780305d28c97ff74d90aef779c75be2c8affaa754c4c00e6e00b69b007b4671a1f99781b3641e26984095939ea7c25620051675b6881"} 17:52:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:52:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:52:29 INFO - Contents: 17:52:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb4cffd4a1b428348e4bbed46f08b9b7d302a3a4483c51150db59f28c64d7b907fd8349485b0be6b0678246aa5453b924008dead73a325a84cccfa3bd593087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27a6f344a80aa0141585f4ff9302ae7620e39a0696a10b7daa067874eaed7fe416c01f143b546a074c613c344d1b4062b42675a3a7832620ce542bc63a61e4c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25860a371476afdef579780305d28c97ff74d90aef779c75be2c8affaa754c4c00e6e00b69b007b4671a1f99781b3641e26984095939ea7c25620051675b6881"} 17:52:29 INFO - Running post-run listener: copy_logs_to_upload_dir 17:52:29 INFO - Copying logs to upload dir... 17:52:29 INFO - mkdir: /builds/slave/test/build/upload/logs 17:52:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.321296 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-30 17:52:29.123703) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 17:52:29.127206) ========= 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=cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795 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/3cb4cffd4a1b428348e4bbed46f08b9b7d302a3a4483c51150db59f28c64d7b907fd8349485b0be6b0678246aa5453b924008dead73a325a84cccfa3bd593087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27a6f344a80aa0141585f4ff9302ae7620e39a0696a10b7daa067874eaed7fe416c01f143b546a074c613c344d1b4062b42675a3a7832620ce542bc63a61e4c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25860a371476afdef579780305d28c97ff74d90aef779c75be2c8affaa754c4c00e6e00b69b007b4671a1f99781b3641e26984095939ea7c25620051675b6881"} build_url:https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/eQNNgufHSYqjnujZnW5EeQ/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/3cb4cffd4a1b428348e4bbed46f08b9b7d302a3a4483c51150db59f28c64d7b907fd8349485b0be6b0678246aa5453b924008dead73a325a84cccfa3bd593087", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27a6f344a80aa0141585f4ff9302ae7620e39a0696a10b7daa067874eaed7fe416c01f143b546a074c613c344d1b4062b42675a3a7832620ce542bc63a61e4c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25860a371476afdef579780305d28c97ff74d90aef779c75be2c8affaa754c4c00e6e00b69b007b4671a1f99781b3641e26984095939ea7c25620051675b6881"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 17:52:29.159536) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:52:29.160243) ========= 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=cbe409c6de289db6ac707de3000001d4-1472594733.864199-1307856795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:52:29.243848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 17:52:29.244217) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 17:52:29.244574) ========= ========= Total master_lag: 0.18 =========