builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1464411573.05 results: success (0) buildid: 20160527191736 builduid: 62e462e558b34ffd8b45ed8aa5932282 revision: 138121c338c75865135a001fc51f7465e36f4740 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.053610) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.054077) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.054392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.109496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.109804) ========= 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=db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 21:59:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.0M=0.001s 2016-05-27 21:59:33 (20.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.319652) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.319982) ========= 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=db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020584 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.372399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:33.373122) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 138121c338c75865135a001fc51f7465e36f4740 --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 138121c338c75865135a001fc51f7465e36f4740 --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=db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 21:59:33,465 truncating revision to first 12 chars 2016-05-27 21:59:33,466 Setting DEBUG logging. 2016-05-27 21:59:33,466 attempt 1/10 2016-05-27 21:59:33,466 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/138121c338c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 21:59:33,779 unpacking tar archive at: mozilla-aurora-138121c338c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644977 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:34.037083) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:34.037841) ========= script_repo_revision: 138121c338c75865135a001fc51f7465e36f4740 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:34.038296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:34.038637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 21:59:34.050626) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-27 21:59:34.051211) ========= /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=db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:59:34 INFO - MultiFileLogger online at 20160527 21:59:34 in /builds/slave/test 21:59:34 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 21:59:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:59:34 INFO - {'append_to_log': False, 21:59:34 INFO - 'base_work_dir': '/builds/slave/test', 21:59:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:59:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:59:34 INFO - 'branch': 'Mozilla-Aurora', 21:59:34 INFO - 'buildbot_json_path': 'buildprops.json', 21:59:34 INFO - 'config_files': ('talos/linux_config.py',), 21:59:34 INFO - 'default_actions': ('clobber', 21:59:34 INFO - 'read-buildbot-config', 21:59:34 INFO - 'download-and-extract', 21:59:34 INFO - 'populate-webroot', 21:59:34 INFO - 'create-virtualenv', 21:59:34 INFO - 'install', 21:59:34 INFO - 'run-tests'), 21:59:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:59:34 INFO - 'download_minidump_stackwalk': True, 21:59:34 INFO - 'download_symbols': 'ondemand', 21:59:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:59:34 INFO - 'tooltool.py': '/tools/tooltool.py', 21:59:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:59:34 INFO - '/tools/misc-python/virtualenv.py')}, 21:59:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:59:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:59:34 INFO - 'installer_path': 'installer.exe', 21:59:34 INFO - 'log_level': 'info', 21:59:34 INFO - 'log_name': 'talos', 21:59:34 INFO - 'log_to_console': True, 21:59:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:59:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:59:34 INFO - 'opt_config_files': (), 21:59:34 INFO - 'pip_index': False, 21:59:34 INFO - 'sps_profile': False, 21:59:34 INFO - 'sps_profile_interval': 0, 21:59:34 INFO - 'suite': 'dromaeojs', 21:59:34 INFO - 'system_bits': '32', 21:59:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:59:34 INFO - 'title': 'talos-linux64-ix-031', 21:59:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:59:34 INFO - 'use_talos_json': True, 21:59:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:59:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:59:34 INFO - 'work_dir': 'build'} 21:59:34 INFO - ##### 21:59:34 INFO - ##### Running clobber step. 21:59:34 INFO - ##### 21:59:34 INFO - Running pre-action listener: _resource_record_pre_action 21:59:34 INFO - Running main action method: clobber 21:59:34 INFO - rmtree: /builds/slave/test/build 21:59:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:59:34 INFO - Running post-action listener: _resource_record_post_action 21:59:34 INFO - ##### 21:59:34 INFO - ##### Running read-buildbot-config step. 21:59:34 INFO - ##### 21:59:34 INFO - Running pre-action listener: _resource_record_pre_action 21:59:34 INFO - Running main action method: read_buildbot_config 21:59:34 INFO - Using buildbot properties: 21:59:34 INFO - { 21:59:34 INFO - "project": "", 21:59:34 INFO - "product": "firefox", 21:59:34 INFO - "script_repo_revision": "production", 21:59:34 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 21:59:34 INFO - "repository": "", 21:59:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 21:59:34 INFO - "buildid": "20160527191736", 21:59:34 INFO - "pgo_build": "True", 21:59:34 INFO - "basedir": "/builds/slave/test", 21:59:34 INFO - "buildnumber": 349, 21:59:34 INFO - "slavename": "talos-linux64-ix-031", 21:59:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:59:34 INFO - "platform": "ubuntu64_hw", 21:59:34 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:59:34 INFO - "branch": "mozilla-aurora", 21:59:34 INFO - "repo_path": "releases/mozilla-aurora", 21:59:34 INFO - "revision": "138121c338c75865135a001fc51f7465e36f4740", 21:59:34 INFO - "stage_platform": "linux64-pgo", 21:59:34 INFO - "builduid": "62e462e558b34ffd8b45ed8aa5932282", 21:59:34 INFO - "slavebuilddir": "test" 21:59:34 INFO - } 21:59:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 21:59:34 INFO - Running post-action listener: _resource_record_post_action 21:59:34 INFO - ##### 21:59:34 INFO - ##### Running download-and-extract step. 21:59:34 INFO - ##### 21:59:34 INFO - Running pre-action listener: _resource_record_pre_action 21:59:34 INFO - Running main action method: download_and_extract 21:59:34 INFO - mkdir: /builds/slave/test/build/tests 21:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:59:34 INFO - https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:59:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:59:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:59:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:59:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:59:37 INFO - Downloaded 1361 bytes. 21:59:37 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:59:37 INFO - Using the following test package requirements: 21:59:37 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 21:59:37 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:59:37 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:59:37 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'jsshell-linux-x86_64.zip'], 21:59:37 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:59:37 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 21:59:37 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:59:37 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:59:37 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:59:37 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:59:37 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:59:37 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:59:37 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')]} 21:59:37 INFO - https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:59:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:59:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:59:41 INFO - Downloaded 24135241 bytes. 21:59:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:59:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:59:42 INFO - Return code: 0 21:59:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:59:42 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')]} 21:59:42 INFO - https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:59:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:59:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:59:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:59:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:59:53 INFO - Downloaded 24135241 bytes. 21:59:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:59:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:59:54 INFO - Return code: 0 21:59:54 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')]} 21:59:54 INFO - https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:59:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:59:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:59:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:59:55 INFO - Downloaded 10840145 bytes. 21:59:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:59:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:59:56 INFO - Return code: 0 21:59:56 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')]} 21:59:56 INFO - https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:59:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:59:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:59:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:59:57 INFO - Downloaded 62752348 bytes. 21:59:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:59:57 INFO - mkdir: /builds/slave/test/properties 21:59:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:59:57 INFO - Writing to file /builds/slave/test/properties/build_url 21:59:57 INFO - Contents: 21:59:57 INFO - build_url:https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:59:58 INFO - Running post-action listener: _resource_record_post_action 21:59:58 INFO - Running post-action listener: set_extra_try_arguments 21:59:58 INFO - ##### 21:59:58 INFO - ##### Running populate-webroot step. 21:59:58 INFO - ##### 21:59:58 INFO - Running pre-action listener: _resource_record_pre_action 21:59:58 INFO - Running main action method: populate_webroot 21:59:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:59:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:59:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:59:58 INFO - '--tppagecycles', 21:59:58 INFO - '7'], 21:59:58 INFO - 'tests': ['tsvgm']}}, 21:59:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:59:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:59:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:59:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:59:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:59:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['damp', 'tps']}, 21:59:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'tests': ['damp', 'tps']}, 21:59:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['dromaeo_dom']}, 21:59:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:59:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other-e10s': {'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['a11yr', 21:59:58 INFO - 'ts_paint', 21:59:58 INFO - 'tpaint', 21:59:58 INFO - 'sessionrestore', 21:59:58 INFO - 'sessionrestore_no_auto_restore', 21:59:58 INFO - 'tabpaint']}, 21:59:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tsvgx', 21:59:58 INFO - 'tsvgr_opacity', 21:59:58 INFO - 'tart', 21:59:58 INFO - 'tscrollx', 21:59:58 INFO - 'cart']}, 21:59:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:59:58 INFO - 'tsvgr_opacity', 21:59:58 INFO - 'tart', 21:59:58 INFO - 'tscrollx', 21:59:58 INFO - 'cart']}, 21:59:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s'], 21:59:58 INFO - 'tests': ['tp5o']}, 21:59:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'tests': ['tp5o']}, 21:59:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--disable-e10s', 21:59:58 INFO - '--xperf_path', 21:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:58 INFO - 'tests': ['tp5n']}, 21:59:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:59:58 INFO - 'talos_options': ['--xperf_path', 21:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:59:58 INFO - 'tests': ['tp5n']}}, 21:59:58 INFO - 'talos.zip': {'path': '', 21:59:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:59:58 INFO - Running post-action listener: _resource_record_post_action 21:59:58 INFO - ##### 21:59:58 INFO - ##### Running create-virtualenv step. 21:59:58 INFO - ##### 21:59:58 INFO - Running pre-action listener: _resource_record_pre_action 21:59:58 INFO - Running main action method: create_virtualenv 21:59:58 INFO - Creating virtualenv /builds/slave/test/build/venv 21:59:58 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 21:59:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:59:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:59:58 INFO - Using real prefix '/usr' 21:59:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:59:59 INFO - Installing distribute.............................................................................................................................................................................................done. 22:00:01 INFO - Installing pip.................done. 22:00:01 INFO - Return code: 0 22:00:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:00:01 INFO - Using env: {'DISPLAY': ':0', 22:00:01 INFO - 'HOME': '/home/cltbld', 22:00:01 INFO - 'LANG': 'en_US.UTF-8', 22:00:01 INFO - 'LANGUAGE': 'en_US:en', 22:00:01 INFO - 'LOGNAME': 'cltbld', 22:00:01 INFO - 'MAIL': '/var/mail/cltbld', 22:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:01 INFO - 'MOZ_NO_REMOTE': '1', 22:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:01 INFO - 'NO_EM_RESTART': '1', 22:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:01 INFO - 'PWD': '/builds/slave/test', 22:00:01 INFO - 'SHELL': '/bin/bash', 22:00:01 INFO - 'SHLVL': '1', 22:00:01 INFO - 'TERM': 'linux', 22:00:01 INFO - 'TMOUT': '86400', 22:00:01 INFO - 'USER': 'cltbld', 22:00:01 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:01 INFO - '_': '/tools/buildbot/bin/python'} 22:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:01 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 22:00:01 INFO - Cleaning up... 22:00:01 INFO - Return code: 0 22:00:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:00:01 INFO - Using env: {'DISPLAY': ':0', 22:00:01 INFO - 'HOME': '/home/cltbld', 22:00:01 INFO - 'LANG': 'en_US.UTF-8', 22:00:01 INFO - 'LANGUAGE': 'en_US:en', 22:00:01 INFO - 'LOGNAME': 'cltbld', 22:00:01 INFO - 'MAIL': '/var/mail/cltbld', 22:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:01 INFO - 'MOZ_NO_REMOTE': '1', 22:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:01 INFO - 'NO_EM_RESTART': '1', 22:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:01 INFO - 'PWD': '/builds/slave/test', 22:00:01 INFO - 'SHELL': '/bin/bash', 22:00:01 INFO - 'SHLVL': '1', 22:00:01 INFO - 'TERM': 'linux', 22:00:01 INFO - 'TMOUT': '86400', 22:00:01 INFO - 'USER': 'cltbld', 22:00:01 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:01 INFO - '_': '/tools/buildbot/bin/python'} 22:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:01 INFO - Downloading/unpacking psutil>=0.7.1 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:04 INFO - Installing collected packages: psutil 22:00:04 INFO - Running setup.py install for psutil 22:00:04 INFO - building 'psutil._psutil_linux' extension 22:00:04 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 22:00:04 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 22:00:04 INFO - building 'psutil._psutil_posix' extension 22:00:04 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 22:00:04 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 22:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:00:04 INFO - Successfully installed psutil 22:00:04 INFO - Cleaning up... 22:00:05 INFO - Return code: 0 22:00:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:05 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:05 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.0.0'] in /builds/slave/test/build 22:00:05 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.0.0 22:00:05 INFO - Using env: {'DISPLAY': ':0', 22:00:05 INFO - 'HOME': '/home/cltbld', 22:00:05 INFO - 'LANG': 'en_US.UTF-8', 22:00:05 INFO - 'LANGUAGE': 'en_US:en', 22:00:05 INFO - 'LOGNAME': 'cltbld', 22:00:05 INFO - 'MAIL': '/var/mail/cltbld', 22:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:05 INFO - 'MOZ_NO_REMOTE': '1', 22:00:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:05 INFO - 'NO_EM_RESTART': '1', 22:00:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:05 INFO - 'PWD': '/builds/slave/test', 22:00:05 INFO - 'SHELL': '/bin/bash', 22:00:05 INFO - 'SHLVL': '1', 22:00:05 INFO - 'TERM': 'linux', 22:00:05 INFO - 'TMOUT': '86400', 22:00:05 INFO - 'USER': 'cltbld', 22:00:05 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:05 INFO - '_': '/tools/buildbot/bin/python'} 22:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:00:07 INFO - Installing collected packages: mozsystemmonitor 22:00:07 INFO - Running setup.py install for mozsystemmonitor 22:00:07 INFO - Successfully installed mozsystemmonitor 22:00:07 INFO - Cleaning up... 22:00:07 INFO - Return code: 0 22:00:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:00:07 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')]} 22:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:07 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')]} 22:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:07 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 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:07 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 22:00:07 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 22:00:07 INFO - Using env: {'DISPLAY': ':0', 22:00:07 INFO - 'HOME': '/home/cltbld', 22:00:07 INFO - 'LANG': 'en_US.UTF-8', 22:00:07 INFO - 'LANGUAGE': 'en_US:en', 22:00:07 INFO - 'LOGNAME': 'cltbld', 22:00:07 INFO - 'MAIL': '/var/mail/cltbld', 22:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:07 INFO - 'MOZ_NO_REMOTE': '1', 22:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:07 INFO - 'NO_EM_RESTART': '1', 22:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:07 INFO - 'PWD': '/builds/slave/test', 22:00:07 INFO - 'SHELL': '/bin/bash', 22:00:07 INFO - 'SHLVL': '1', 22:00:07 INFO - 'TERM': 'linux', 22:00:07 INFO - 'TMOUT': '86400', 22:00:07 INFO - 'USER': 'cltbld', 22:00:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:07 INFO - '_': '/tools/buildbot/bin/python'} 22:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:07 INFO - Downloading/unpacking blobuploader==1.2.4 22:00:07 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 22:00:07 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 22:00:07 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 22:00:07 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 22:00:07 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 22:00:07 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 22:00:09 INFO - Downloading blobuploader-1.2.4.tar.gz 22:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:00:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:00:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:10 INFO - Downloading docopt-0.6.1.tar.gz 22:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:00:10 INFO - Installing collected packages: blobuploader, requests, docopt 22:00:10 INFO - Running setup.py install for blobuploader 22:00:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:00:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:00:10 INFO - Running setup.py install for requests 22:00:10 INFO - Running setup.py install for docopt 22:00:10 INFO - Successfully installed blobuploader requests docopt 22:00:10 INFO - Cleaning up... 22:00:11 INFO - Return code: 0 22:00:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:11 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 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:11 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 22:00:11 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 22:00:11 INFO - Using env: {'DISPLAY': ':0', 22:00:11 INFO - 'HOME': '/home/cltbld', 22:00:11 INFO - 'LANG': 'en_US.UTF-8', 22:00:11 INFO - 'LANGUAGE': 'en_US:en', 22:00:11 INFO - 'LOGNAME': 'cltbld', 22:00:11 INFO - 'MAIL': '/var/mail/cltbld', 22:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:11 INFO - 'MOZ_NO_REMOTE': '1', 22:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:11 INFO - 'NO_EM_RESTART': '1', 22:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:11 INFO - 'PWD': '/builds/slave/test', 22:00:11 INFO - 'SHELL': '/bin/bash', 22:00:11 INFO - 'SHLVL': '1', 22:00:11 INFO - 'TERM': 'linux', 22:00:11 INFO - 'TMOUT': '86400', 22:00:11 INFO - 'USER': 'cltbld', 22:00:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:11 INFO - '_': '/tools/buildbot/bin/python'} 22:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:11 INFO - Running setup.py (path:/tmp/pip-LYIY6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:11 INFO - Running setup.py (path:/tmp/pip-wdRCeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:11 INFO - Running setup.py (path:/tmp/pip-s1zXLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:11 INFO - Running setup.py (path:/tmp/pip-DNUqut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:11 INFO - Running setup.py (path:/tmp/pip-jEKwDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:11 INFO - Running setup.py (path:/tmp/pip-MaP8zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:11 INFO - Running setup.py (path:/tmp/pip-raronW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:11 INFO - Running setup.py (path:/tmp/pip-7c0oNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:11 INFO - Running setup.py (path:/tmp/pip-3vxw7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:11 INFO - Running setup.py (path:/tmp/pip-gUQ6NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:11 INFO - Running setup.py (path:/tmp/pip-ihHRGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:12 INFO - Running setup.py (path:/tmp/pip-mUEnN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:12 INFO - Running setup.py (path:/tmp/pip-5w2ZZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:12 INFO - Running setup.py (path:/tmp/pip-C8vYXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:12 INFO - Running setup.py (path:/tmp/pip-ptoZsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:12 INFO - Running setup.py (path:/tmp/pip-oQsKDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:12 INFO - Running setup.py (path:/tmp/pip-pwSKpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:00:12 INFO - Running setup.py install for manifestparser 22:00:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozcrash 22:00:12 INFO - Running setup.py install for mozdebug 22:00:12 INFO - Running setup.py install for mozdevice 22:00:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:00:12 INFO - Running setup.py install for mozfile 22:00:12 INFO - Running setup.py install for mozhttpd 22:00:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for mozinfo 22:00:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for mozInstall 22:00:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for mozleak 22:00:13 INFO - Running setup.py install for mozlog 22:00:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for moznetwork 22:00:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for mozprocess 22:00:13 INFO - Running setup.py install for mozprofile 22:00:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for mozrunner 22:00:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:00:13 INFO - Running setup.py install for mozscreenshot 22:00:14 INFO - Running setup.py install for moztest 22:00:14 INFO - Running setup.py install for mozversion 22:00:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:00:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:00:14 INFO - Cleaning up... 22:00:14 INFO - Return code: 0 22:00:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00: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')]} 22:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00: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')]} 22:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:14 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 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:14 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 22:00:14 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 22:00:14 INFO - Using env: {'DISPLAY': ':0', 22:00:14 INFO - 'HOME': '/home/cltbld', 22:00:14 INFO - 'LANG': 'en_US.UTF-8', 22:00:14 INFO - 'LANGUAGE': 'en_US:en', 22:00:14 INFO - 'LOGNAME': 'cltbld', 22:00:14 INFO - 'MAIL': '/var/mail/cltbld', 22:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:14 INFO - 'MOZ_NO_REMOTE': '1', 22:00:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:14 INFO - 'NO_EM_RESTART': '1', 22:00:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:14 INFO - 'PWD': '/builds/slave/test', 22:00:14 INFO - 'SHELL': '/bin/bash', 22:00:14 INFO - 'SHLVL': '1', 22:00:14 INFO - 'TERM': 'linux', 22:00:14 INFO - 'TMOUT': '86400', 22:00:14 INFO - 'USER': 'cltbld', 22:00:14 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:14 INFO - '_': '/tools/buildbot/bin/python'} 22:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:00:14 INFO - Running setup.py (path:/tmp/pip-fPrZQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:00:14 INFO - Running setup.py (path:/tmp/pip-J6D17h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:00:14 INFO - Running setup.py (path:/tmp/pip-E36XSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:00:14 INFO - Running setup.py (path:/tmp/pip-DUZJG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:00:14 INFO - Running setup.py (path:/tmp/pip-7FaGbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:00:14 INFO - Running setup.py (path:/tmp/pip-Ny7Ptg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:00:14 INFO - Running setup.py (path:/tmp/pip-D6vudK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:00:14 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)) 22:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:00:14 INFO - Running setup.py (path:/tmp/pip-owiP3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:00:15 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:00:15 INFO - Running setup.py (path:/tmp/pip-bCq0BS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:00:15 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:00:15 INFO - Running setup.py (path:/tmp/pip-y5qt6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:00:15 INFO - Running setup.py (path:/tmp/pip-BP_iov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:00:15 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:00:15 INFO - Running setup.py (path:/tmp/pip-oiXSyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:00:15 INFO - Running setup.py (path:/tmp/pip-y1BwrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:00:15 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:00:15 INFO - Running setup.py (path:/tmp/pip-_JbxAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:15 INFO - Running setup.py (path:/tmp/pip-oA0zbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:00:15 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:00:15 INFO - Running setup.py (path:/tmp/pip-VeMrCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:00:15 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)) 22:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:00:15 INFO - Running setup.py (path:/tmp/pip-MZ6Tk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:00:15 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)) 22:00:15 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)) 22:00:15 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)) 22:00:15 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)) 22:00:15 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)) 22:00:15 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)) 22:00:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:17 INFO - Downloading blessings-1.6.tar.gz 22:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:00:17 INFO - Installing collected packages: blessings 22:00:17 INFO - Running setup.py install for blessings 22:00:17 INFO - Successfully installed blessings 22:00:17 INFO - Cleaning up... 22:00:17 INFO - Return code: 0 22:00:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:00:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:18 INFO - Reading from file tmpfile_stdout 22:00:18 INFO - Current package versions: 22:00:18 INFO - argparse == 1.2.1 22:00:18 INFO - blessings == 1.6 22:00:18 INFO - blobuploader == 1.2.4 22:00:18 INFO - docopt == 0.6.1 22:00:18 INFO - manifestparser == 1.1 22:00:18 INFO - mozInstall == 1.12 22:00:18 INFO - mozcrash == 0.17 22:00:18 INFO - mozdebug == 0.1 22:00:18 INFO - mozdevice == 0.48 22:00:18 INFO - mozfile == 1.2 22:00:18 INFO - mozhttpd == 0.7 22:00:18 INFO - mozinfo == 0.9 22:00:18 INFO - mozleak == 0.1 22:00:18 INFO - mozlog == 3.1 22:00:18 INFO - moznetwork == 0.27 22:00:18 INFO - mozprocess == 0.22 22:00:18 INFO - mozprofile == 0.28 22:00:18 INFO - mozrunner == 6.11 22:00:18 INFO - mozscreenshot == 0.1 22:00:18 INFO - mozsystemmonitor == 0.0 22:00:18 INFO - moztest == 0.7 22:00:18 INFO - mozversion == 1.4 22:00:18 INFO - psutil == 3.1.1 22:00:18 INFO - requests == 1.2.3 22:00:18 INFO - wsgiref == 0.1.2 22:00:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:00:18 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')]} 22:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:18 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')]} 22:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:18 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 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:18 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 22:00:18 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 22:00:18 INFO - Using env: {'DISPLAY': ':0', 22:00:18 INFO - 'HOME': '/home/cltbld', 22:00:18 INFO - 'LANG': 'en_US.UTF-8', 22:00:18 INFO - 'LANGUAGE': 'en_US:en', 22:00:18 INFO - 'LOGNAME': 'cltbld', 22:00:18 INFO - 'MAIL': '/var/mail/cltbld', 22:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:18 INFO - 'MOZ_NO_REMOTE': '1', 22:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:18 INFO - 'NO_EM_RESTART': '1', 22:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:18 INFO - 'PWD': '/builds/slave/test', 22:00:18 INFO - 'SHELL': '/bin/bash', 22:00:18 INFO - 'SHLVL': '1', 22:00:18 INFO - 'TERM': 'linux', 22:00:18 INFO - 'TMOUT': '86400', 22:00:18 INFO - 'USER': 'cltbld', 22:00:18 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:18 INFO - '_': '/tools/buildbot/bin/python'} 22:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 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)) 22:00:18 INFO - Cleaning up... 22:00:18 INFO - Return code: 0 22:00:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:00:18 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')]} 22:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:18 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')]} 22:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:18 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 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:18 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 22:00:18 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 22:00:18 INFO - Using env: {'DISPLAY': ':0', 22:00:18 INFO - 'HOME': '/home/cltbld', 22:00:18 INFO - 'LANG': 'en_US.UTF-8', 22:00:18 INFO - 'LANGUAGE': 'en_US:en', 22:00:18 INFO - 'LOGNAME': 'cltbld', 22:00:18 INFO - 'MAIL': '/var/mail/cltbld', 22:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:18 INFO - 'MOZ_NO_REMOTE': '1', 22:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:18 INFO - 'NO_EM_RESTART': '1', 22:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:18 INFO - 'PWD': '/builds/slave/test', 22:00:18 INFO - 'SHELL': '/bin/bash', 22:00:18 INFO - 'SHLVL': '1', 22:00:18 INFO - 'TERM': 'linux', 22:00:18 INFO - 'TMOUT': '86400', 22:00:18 INFO - 'USER': 'cltbld', 22:00:18 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:18 INFO - '_': '/tools/buildbot/bin/python'} 22:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:18 INFO - Downloading/unpacking jsonschema 22:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:00:20 INFO - Downloading/unpacking functools32 (from jsonschema) 22:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:00:20 INFO - Downloading functools32-3.2.3-2.tar.gz 22:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:00:20 INFO - warning: no files found matching '*.txt' 22:00:20 INFO - no previously-included directories found matching 'build' 22:00:20 INFO - no previously-included directories found matching 'dist' 22:00:20 INFO - no previously-included directories found matching '.git*' 22:00:20 INFO - Installing collected packages: jsonschema, functools32 22:00:20 INFO - Running setup.py install for functools32 22:00:20 INFO - warning: no files found matching '*.txt' 22:00:20 INFO - no previously-included directories found matching 'build' 22:00:20 INFO - no previously-included directories found matching 'dist' 22:00:20 INFO - no previously-included directories found matching '.git*' 22:00:20 INFO - Successfully installed jsonschema functools32 22:00:20 INFO - Cleaning up... 22:00:20 INFO - Return code: 0 22:00:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:00:20 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96768b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ee7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, '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': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', '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 22:00:20 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', 'flake8'] in /builds/slave/test/build 22:00:20 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 flake8 22:00:20 INFO - Using env: {'DISPLAY': ':0', 22:00:20 INFO - 'HOME': '/home/cltbld', 22:00:20 INFO - 'LANG': 'en_US.UTF-8', 22:00:20 INFO - 'LANGUAGE': 'en_US:en', 22:00:20 INFO - 'LOGNAME': 'cltbld', 22:00:20 INFO - 'MAIL': '/var/mail/cltbld', 22:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:20 INFO - 'MOZ_NO_REMOTE': '1', 22:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:20 INFO - 'NO_EM_RESTART': '1', 22:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:20 INFO - 'PWD': '/builds/slave/test', 22:00:20 INFO - 'SHELL': '/bin/bash', 22:00:20 INFO - 'SHLVL': '1', 22:00:20 INFO - 'TERM': 'linux', 22:00:20 INFO - 'TMOUT': '86400', 22:00:20 INFO - 'USER': 'cltbld', 22:00:20 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:20 INFO - '_': '/tools/buildbot/bin/python'} 22:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:00:21 INFO - Downloading/unpacking flake8 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:00:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00: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 22:00:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:00:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 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 22:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:00:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:00:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:00:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:00:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:00:23 INFO - no previously-included directories found matching 'docs/_build' 22:00:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:00:23 INFO - Running setup.py install for pep8 22:00:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:00:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:00:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:00:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:00:23 INFO - no previously-included directories found matching 'docs/_build' 22:00:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:00:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:00:23 INFO - Cleaning up... 22:00:23 INFO - Return code: 0 22:00:23 INFO - Running post-action listener: _resource_record_post_action 22:00:23 INFO - Running post-action listener: _start_resource_monitoring 22:00:23 INFO - Starting resource monitoring. 22:00:23 INFO - ##### 22:00:23 INFO - ##### Running install step. 22:00:23 INFO - ##### 22:00:23 INFO - Running pre-action listener: _resource_record_pre_action 22:00:23 INFO - Running main action method: install 22:00:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:00:24 INFO - Reading from file tmpfile_stdout 22:00:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:00:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:00:24 INFO - Reading from file tmpfile_stdout 22:00:24 INFO - Output received: 22:00:24 INFO - Usage: mozinstall [options] installer 22:00:24 INFO - Options: 22:00:24 INFO - -h, --help show this help message and exit 22:00:24 INFO - -d DEST, --destination=DEST 22:00:24 INFO - Directory to install application into. [default: 22:00:24 INFO - "/builds/slave/test"] 22:00:24 INFO - --app=APP Application being installed. [default: firefox] 22:00:24 INFO - mkdir: /builds/slave/test/build/application 22:00:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:00:39 INFO - Reading from file tmpfile_stdout 22:00:39 INFO - Output received: 22:00:39 INFO - /builds/slave/test/build/application/firefox/firefox 22:00:39 INFO - Running post-action listener: _resource_record_post_action 22:00:39 INFO - ##### 22:00:39 INFO - ##### Running run-tests step. 22:00:39 INFO - ##### 22:00:39 INFO - Running pre-action listener: _resource_record_pre_action 22:00:39 INFO - Running main action method: run_tests 22:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:00:39 INFO - Python 2.7.3 22:00:39 INFO - Return code: 0 22:00:39 INFO - grabbing minidump binary from tooltool 22:00:39 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')]} 22:00:39 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257d640>, '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 0x257e540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257e9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:00:39 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 22:00:39 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 22:00:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:00:39 INFO - Return code: 0 22:00:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:00:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:00:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:00:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:00:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:00:40 INFO - Return code: 0 22:00:40 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/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 22:00:40 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/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 22:00:40 INFO - Using env: {'DISPLAY': ':0', 22:00:40 INFO - 'HOME': '/home/cltbld', 22:00:40 INFO - 'LANG': 'en_US.UTF-8', 22:00:40 INFO - 'LANGUAGE': 'en_US:en', 22:00:40 INFO - 'LOGNAME': 'cltbld', 22:00:40 INFO - 'MAIL': '/var/mail/cltbld', 22:00:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:00:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:00:40 INFO - 'MOZ_NO_REMOTE': '1', 22:00:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:00:40 INFO - 'NO_EM_RESTART': '1', 22:00:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:00:40 INFO - 'PWD': '/builds/slave/test', 22:00:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:00:40 INFO - 'SHELL': '/bin/bash', 22:00:40 INFO - 'SHLVL': '1', 22:00:40 INFO - 'TERM': 'linux', 22:00:40 INFO - 'TMOUT': '86400', 22:00:40 INFO - 'USER': 'cltbld', 22:00:40 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432', 22:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:00:40 INFO - '_': '/tools/buildbot/bin/python'} 22:00:40 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/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 22:00:40 INFO - mozversion application_buildid: 20160527191736 22:00:40 INFO - mozversion application_changeset: 138121c338c75865135a001fc51f7465e36f4740 22:00:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:00:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:00:40 INFO - mozversion application_name: Firefox 22:00:40 INFO - mozversion application_remotingname: firefox-dev 22:00:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:00:40 INFO - mozversion application_vendor: Mozilla 22:00:40 INFO - mozversion application_version: 48.0a2 22:00:40 INFO - mozversion platform_buildid: 20160527191736 22:00:40 INFO - mozversion platform_changeset: 138121c338c75865135a001fc51f7465e36f4740 22:00:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:00:40 INFO - mozversion platform_version: 48.0a2 22:00:40 INFO - using testdate: 1464411640 22:00:40 INFO - actual date: 1464411640 22:00:40 INFO - starting webserver on 'localhost:45763' 22:00:40 INFO - SUITE-START | Running 2 tests 22:00:40 INFO - TEST-START | dromaeo_css 22:00:40 INFO - operating with platform_type : linux_ 22:00:40 INFO - Initialising browser for dromaeo_css test... 22:00:40 INFO - TEST-INFO | started process 4001 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbmlQPe/profile http://localhost:45763/getInfo.html) 22:00:44 INFO - PROCESS | 4001 | __metrics Screen width/height:1600/1200 22:00:44 INFO - PROCESS | 4001 | colorDepth:24 22:00:44 INFO - PROCESS | 4001 | Browser inner width/height: 1024/697 22:00:44 INFO - PROCESS | 4001 | __metrics 22:00:45 ERROR - PROCESS | 4001 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:00:46 INFO - TEST-INFO | 4001: exit 0 22:00:46 INFO - Browser initialized. 22:00:46 INFO - Running cycle 1/1 for dromaeo_css test... 22:00:46 INFO - TEST-INFO | started process 4091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbmlQPe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:58 INFO - PROCESS | 4091 | RSS: Main: 141266944 22:00:58 INFO - PROCESS | 4091 | 22:01:49 INFO - PROCESS | 4091 | 1464411709991 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] 22:03:56 INFO - PROCESS | 4091 | Cycle 1(1): loaded http://localhost:45763/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45763/tests/dromaeo/cssquery-ext.html) 22:03:57 INFO - PROCESS | 4091 | RSS: Main: 248836096 22:03:57 INFO - PROCESS | 4091 | 22:06:59 INFO - PROCESS | 4091 | Cycle 1(1): loaded http://localhost:45763/tests/dromaeo/cssquery-ext.html (next: http://localhost:45763/tests/dromaeo/cssquery-jquery.html) 22:07:00 INFO - PROCESS | 4091 | RSS: Main: 214794240 22:07:00 INFO - PROCESS | 4091 | 22:10:02 INFO - PROCESS | 4091 | Cycle 1(1): loaded http://localhost:45763/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45763/tests/dromaeo/cssquery-mootools.html) 22:10:02 INFO - PROCESS | 4091 | RSS: Main: 217432064 22:10:02 INFO - PROCESS | 4091 | 22:13:04 INFO - PROCESS | 4091 | Cycle 1(1): loaded http://localhost:45763/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45763/tests/dromaeo/cssquery-prototype.html) 22:13:05 INFO - PROCESS | 4091 | RSS: Main: 220188672 22:13:05 INFO - PROCESS | 4091 | 22:16:07 INFO - PROCESS | 4091 | Cycle 1(1): loaded http://localhost:45763/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45763/tests/dromaeo/cssquery-yui.html) 22:16:08 INFO - PROCESS | 4091 | RSS: Main: 182747136 22:16:08 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | Cycle 1(1): loaded http://localhost:45763/tests/dromaeo/cssquery-yui.html (next: http://localhost:45763/tests/dromaeo/cssquery-dojo.html) 22:19:10 INFO - PROCESS | 4091 | RSS: Main: 256577536 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | __start_tp_report 22:19:10 INFO - PROCESS | 4091 | _x_x_mozilla_page_load 22:19:10 INFO - PROCESS | 4091 | _x_x_mozilla_page_load_details 22:19:10 INFO - PROCESS | 4091 | |i|pagename|runs| 22:19:10 INFO - PROCESS | 4091 | |0;dojo.html;3266.47736362182;3316.038348878825;3331.866725330987;3357.060023193507;3378.4763361678934;5482.205080263359;5491.039068163071;5502.119660854264;5506.559475241981;5520.033994051045;5482.479164479368;5513.917291240635;5515;5523.806666766664;5560.221568980346;4888.046830867982;4968.056069346831;4971.502849715036;5052.141136006888;5091.312672789181;4960.925586116211;4967.552920237178;4978.850634480971;5054.696718196901;5066.164082926312;5038.2190760432195;5126.487351264862;5127.154019586764;5133.614978876581;5134.050200712862;4950.554450099508;4993.650444468889;5004.549590536851;5012.348394708701;5015.949840501585;4861.854144375639;4881.682690625098;4919.163742163805;4957.380327459067;4969;5367.194920761878;5370.409254981949;5371.865703357408;5382.354117505885;5423.132298832168;5814.912776308359;5839.562032847553;5842.649441033552;5843.883122337572;5844.503217226541;5800.738966746464;5886.323072846634;5892.086726557391;5973.402659734035;5977.880442391128;5450.155225939986;5497.890042199174;5504.559635229212;5504.972475137639;5520.613557051008;5862.091375836752;5918.763249469973;5966.552508561876;5974.790882319098;5981.342052374235;5725.341585717696;5779.942200578024;5781.682007489629;5816.534677225812;5824.388439213822;5845.824625261249;5845.912311315334;5848.8537786554825;5854.2096816930025;5855.238818953594;5706.80026199081;5814.215080964101;5819.592628516048;5827.88344233113;5850;5883.441073098049;5885.176075349455;5891.823245302648;5898.174255604219;5928.170056192136;4474.731516108979;4505.6846020778885;4506.59440650343;4532.864014079583;4534.727916324965;3973.304671682502;3979.2041591681777;4030.133521292936;4048.7570745755347;4091.6317531422314;5488.725563721798;5500.889982200333;5531.3915469298345;5532.640378375393;5538.390777014525;5873.882522349529;5876.324222714357;5883.676397798162;5889.293284805851;5890.587658863838;5433.646812957145;5497.505224529811;5524.4199359067525;5566.749496272678;5572.777088916398;5037.093323201786;5061.493850614909;5073.594112470993;5074.517920797518;5078.492150784966;5872.119182122716;5941.9331420643975;5960.0761881908375;5969.850753731192;5982.282126144891;5727.799527016535;5747.3103227612955;5838.854028649318;5847.678377689268;5877.706114694333;5918.082697181943;5927;5928.851778705567;5930.881382372415;5931.466168044724;5742.913856292075;5754.769809207585;5760.625559338797;5770.653760774367;5772.682502462237;5773.3072031356505;5818.970905145447;5889.116632505158;5890.911636325544;5921.792737254175;5715.628484148517;5720.113382425564;5733.827985160452;5743.942560574508;5761.366249712444;5606.579506537108;5720.971395142998;5730.169125476866;5737.827865164052;5740.454656807764;5618.4662457066515;5655.519280861173;5661.094224923992;5671.6029877909805;5697.23087383198;5713.228714123707;5725.427457254241;5749.1663708762835;5755.913661295168;5760.395158508296;5518.227448157341;5576.358718747396;5635.633683810539;5651;5675 22:19:10 INFO - PROCESS | 4091 | |1;ext.html;665.0821865825161;674.5817593092283;680.4828330468843;731.5829976913161;739.2311995524657;33272.83363583185;34101.63593456262;38350;39017.80491097548;39143;42222.57777422225;42602;42664.36003459951;42807.78596107015;42814.785926070326;8773.385862989593;8948.597313120892;8954.32842536809;8955.865662015074;8957.417767845101;8809.38334316598;8935.910640893606;8941.59762810675;8941.821163576724;8947.865782013276;8913.465192088463;9175.311851611123;9186.402883812565;9205.493697846616;9212.401193922373;9044.276457883387;9100.180983711498;9110.271178305753;9112.225460835836;9120.908790912072;9012.233960113399;9142.908570914273;9145.176934075898;9145.268378529701;9151.771205719844;42107.57892421067;42214;42355.1528969422;42671.35992960108;42750.358744618854;3699.722520810923;3734.271816995687;3768.2275133597545;3969.28552860485;4310.547392523771;31994.200144996328;32047;32074.198145046328;32326.515102273486;32525.186870328194;4377.5403582624;4432.246518091933;4460.776961151962;4514.548545145492;4556.088782243564;280915;281975;282976;284025;284248;60337.09494357588;60651.393486065455;60658.0901286481;60661.69669151714;60695.26871290802;69770.65114674394;71483.64258178676;71496;71513.2848671517;71550.64224678843;18992.05039748007;19221.519462013282;19262.22951081941;19269.132889020028;19276.421707348803;14737.11577305365;14872.702545949236;14879.47921822731;14898.851011489964;14908.25458727081;20188;20404.387868363974;20549.79450205479;20609.484762880747;20614.58770824605;7544.887431519073;7575.659095340723;7588.203238659971;7622.771316860503;7781.649825757854;1178.2459226095307;1181.710480932177;1197.9760404791855;1216.5377156680404;1239.6528971887885;33335.333293334;33776.49335259973;33789.662103379145;34393.65606343905;34467.482987755204;1243.7077286837587;1282.3139620303093;1288.7100402409383;1302.3293004102704;1370.616227456314;12984.935075324564;17757.644847102987;17877.28490860377;17919.28322867071;18091.638167236844;486122;494511;495405;495586;495627;61711;62504.68747656233;62520;62616;62620;1330.8935285177163;1433.1902475101533;1568.9607759806097;1631.2903886809277;1661.6178278995899;31139.26200478583;32135.357292854478;32825.377468350365;33250.16874578155;33924.3215135691;48948;49251.26123108228;49302.75348623234;49365;49400;287024;288455;288948;289048;290081;29554.408911821214;30421.695783041887;30520.542191867586;30830.53754193644;30854;49625.255621166434;50451;50564.24153637772;50867;51620.22569661383;41009.76970690884;41645.84688895406;42134.78932605317;42411.15177696491;42626.78686606547;41261;41670.37494437647;41784;42476.36285455659;42633.14733705246;42810.1437971245;42910.141797164506;44273.77863110664;44297.77851110724;44601.77699111614;36700.81649591735;37081.62918370782;37604.24791504209;37738.69071069823;38214.6178538211;28603.570946435404;28762.712372876842;31292.374152517277;31447.213819653778;32265.193370165933 22:19:10 INFO - PROCESS | 4091 | |2;jquery.html;429.81563171586936;431.25177816488394;436.8405531980828;441.1772045135822;448.8249582662762;18555.443336699886;18572.90713546432;18924.432267031978;19233.519162020955;19361.128749206284;18661;18762.718559221623;19469.610607787872;19566.021698915032;19574.217031318767;5745.827625171241;5791.797287094932;6001.60989535681;6028.849278768022;6032.607880487754;5962.25471816023;5979.192808970777;6003.6697981611;6031.909521357183;6067.484263837579;6007;6008.579399442041;6105.572609917308;6379.5853269537565;6397.392247736458;5985.311689155759;6122.387761223887;6401.615903045832;6407.070974708665;6444.258910225314;5933.643981361089;5962.940370596282;5968.820935371946;5981.970090149522;5988.790392336291;20689.27587534444;21481.24815631461;21678.45803854916;21695.240666576596;22220.333389998326;2337.625979843234;2497.475530138673;2635.8813853376646;2774.3202915285683;2867.899623513188;10061.39631622105;10752.892471075267;10768.138548916068;13100.01749868754;14674.413023479048;2019.6061767955255;2029.918803247883;2053.3121404329568;2074.95850082999;2081.105124796337;71835.64082179609;79974.600126999;80370.19629803686;81540;88079;11042.343648452796;11567.826482602768;11819.763604727857;11845.526178952918;12005.759884802255;16519.33922643105;16596.751048734277;16660.58348541296;16685.24916378766;16717.414890478776;8677.566121694017;8858.911410885808;8872.112788721077;8898.555072246361;8938.46369217849;8952.283817294601;8973.641054357882;8992.145746154105;9003.36976411658;9071.319651026199;4637.281221410687;4794.760261986887;4906.460289368167;5081.466446023188;5115.514026167508;2067.7105205271273;2070.2960993262363;2112.7359080114984;2119.8940052997596;2144.8498605097807;997.5959736306808;1051.679237832453;1072.158355690779;1073.2862646340097;1166.2302880099094;19221.80778192228;19520.511987200432;19736.90131549333;19876.50308742264;19917.502062448555;1051.7055224537141;1059.2796898109204;1061.9787604248027;1073.7691396349821;1085.5386460754148;10891.965263299973;11035.724106897393;11336.886631133748;11381.601643942422;11403.942980285046;517772;526524;535362;538186;539209;89375;94814;94889;95133;95183.52408238054;1088.102315589626;1090.8636420447444;1095.1293721491593;1118.608487029533;1122.242486321746;16223.756643650591;16866;17431.128443578025;17575.472735817944;17704.380346687805;36030.45954310635;36459.45310820287;36936;38118.809405952794;38169.80915095407;54021.45978540164;57794.4220557789;58088.70955645195;58407;58510.4148958522;8823.955880220557;9121.77195570116;9242.491662958602;9309.20871725911;9489.288303377136;9150.40522366044;9330.533473326172;12175.208611440228;13032.283224422748;14115.153090814583;10778.407187604756;11169.944150279196;11564.190506664452;12016.519339226683;12603.74792504163;11758.3532905691;11928.105392095455;12102;12156.939215303866;12449.25304481734;11547.942260288644;12491.93754031224;12656.11407201487;12660.746785064062;12823.358832058173;9206.815863682823;9236.72289831306;9374.296927730584;9387.671431499864;9389.953050234704;9633.614655413705;12198.51205951752;12345.197562158432;12778.105532612628;12803.487860485477 22:19:10 INFO - PROCESS | 4091 | |3;mootools.html;1413.3639862062075;1565.5068653374187;1591.3873158833849;1778.9822101778987;1850.8889466632004;8375.204355586216;8566.614502347395;8577.95711021444;8590.52752098635;8626.568671566429;8422.620982055807;8561.058283588816;8623.396362254645;8630;8657.047724750273;2453.1781853079196;2475.170817776047;2475.4553998120386;2476.8885400157037;2477.665515155458;2445.8165637577163;2506.761857623532;2508.4481414088964;2509.5357358888573;2517.999999999991;2430.2101816909576;2435.926922192328;2475.8885850136785;2477.046337160188;2479.78921791647;2456.3736247256925;2476.05909754294;2480.553500369933;2483.3791552111793;2505.9749402506013;2431.4043059450373;2461.384653836541;2475.282168171246;2500.5374005808985;2501.874906254698;8415.368847336413;8561;8745.47527148373;8800.075992020873;8801.251893589053;5379.81170659029;5499.917501237484;5510.779568817243;5515.834524964217;5516.117421212627;3588.5873124590744;3589.46158076288;3597.5143355646915;3637.654422829827;3658.2317713280245;1097;1105.4804242006244;1134.5858761552056;1153.532819208222;1197.8143387774908;11943.40282985856;12294.508219671203;12337.568185113478;12368.072394570443;12375.071869609725;4555.2483840166005;4690.624750019991;4726.550977657117;4736.881577960509;4764.166270902648;9890.406575605486;9922.107010369102;9924.900750992541;9929.156021738088;9932.751681208028;5452.727363631802;5461.672299662033;5464.453554644583;5464.726763661801;5467.043267428184;4455.220336441174;4782.33047373368;4959.20652695567;4989.251612258117;5072.960043191137;3889.9027524311555;3909.0618251619976;3962.6829853611644;3997.160596274815;4008.498937632796;1216.9148159628742;1221.8900298973135;1229.692576855786;1239.3865036806837;1249.7500499900057;977.9364341317792;985.5367977050774;999.4003597841237;1000.3597697473623;1006.7634105985088;5653;5660.8867822643315;5755.85610359736;5773.624714393551;5881.852953676193;982.886967998675;997.9301448898652;999.4253304350028;1012.7620009297809;1021.3718563083798;3907.4138879168354;3950.6444420001762;3961.92076158481;3965.2443940162616;3986.22268657609;10626.893731062744;10972.451377431096;11111.222214444912;11134.27627204229;11151.553937842395;10653.254272200871;10684.198685098494;10763.08513776322;10838.512266948006;10860.728481788019;8361.707340243183;8403.70587029451;8407.74776756686;8462.280706139923;8491.830163396697;5459.208414779914;5632.549396048388;5661.094224923992;5759.596828221985;5790.594658373873;9227.677031303872;9836.901630983599;9841.655542055994;9900.653477128266;9920;9455.24358051368;9945.552450139616;9992.250581206585;9996;10010;6577.408033277028;6733.898991515229;7091.290870912868;7385.150707668687;7507.286807753255;8115.513069215866;8176.100628930694;8201.58992050407;8203.097659257357;8226.876596850932;7319.853602927805;7464.925350746423;7475.738349157753;7577.431692622965;7625.428092892944;7099.36105750485;7278.6724597392185;7295.52579082358;7377.372923301579;7385.7045718170675;6948.791536253921;6990.475714321345;6998.6850591724515;7119.466040046914;7231.385332246818;4946.35697359341;5069.214271787879;5095.541401273903;5140.3831540215415;5159.509846564571;5137.92293115611;5192.039472697563;5192.610554208525;5270.314859068297;5283.471652834686 22:19:10 INFO - PROCESS | 4091 | |4;prototype.html;361.416312655062;368.4087040300318;368.6221622836593;373.44729799896146;376.77017019618046;5706.828795136143;5869.677167755772;5895.823125306237;5929.703514824263;5936.554758393122;5865.677387743673;5886.676232807194;5903.26209223847;5907.85230369241;5983.192269043678;4606.470255920579;4623.375844261015;4635.721856688592;4659.114768194049;4663.8367657132;4427.77861106945;4496.617787488068;4587.357769912215;4634.768261586924;4652.371929789469;4575.153596584626;4599.540045995407;4608.23964045932;4615;4628.236341003747;4585.747783871886;4592.816287348502;4593.173228818795;4611.239145540982;4624.653151013654;4106.7125301228925;4108.322126849066;4110.403991421257;4118.5057793064725;4119.485064366954;5730.656160630376;5737.139429085629;5761.683107429089;5765.308163020464;5774.595778295521;5378.596605254622;5388.114160043953;5394.056040192951;5398.757055932492;5404.135338345885;6019.578629495937;6238.875222495525;6240.687965601747;6247.375262473762;6248.343923888017;4349.499807522112;4352.869413917588;4363.3454981752675;4364.672649551297;4373.497047839507;6459.870802583969;6532.052852336385;6541.084248205208;6549.508786841006;6555.278919318871;4543.5002149763495;4561.201789686858;4565.0641618468135;4571.131485017902;4577.107464044546;4618.861434156981;4620.976895115503;4623.884402889955;4627.190241707755;4631.861044168681;5184.533391994739;5291.814786482455;5296.841094767086;5313.946860531345;5318.867028324245;6227.19046523949;6240.594361366497;6266.561340706198;6313.242410910629;6317.052442133625;1573.3077445923757;1629.6985057764352;1666.1419369024961;1673.623434727201;1674.0792564089702;1130.084631448513;1138.1407037686495;1147.6614398752388;1158.1719070864372;1162.7674465107013;5953.672548009901;5970.0746384310505;6004.609700369462;6018.699065046818;6018.969905150534;6402.903956440658;6407.13503677007;6415.647139407378;6420.26166990802;6426.068220108152;5998.820035398945;5998.910016349758;6000.189974353494;6015.639061656227;6016.2479690038745;3422.691957723817;3442.8622855085514;3443.7589368743947;3447.6035255945385;3453.3783918894337;6340.587861788969;6476.060971159155;6491.993740970157;6552.9344706553275;6642.800715978528;6610.3720146586;6634.535582509275;6649.035889795952;6652.933470665232;6655.234648015444;6554.737810487527;6569.310222426672;6570.868582628416;6574.539782215199;6617.4706023518;6529.542931994714;6533.803985880431;6536.575122617015;6537.182852143482;6545.21457425112;6302.842428939313;6424.807255782334;6436;6443.838904027437;6446.7098980546925;6582.572132811543;6596.901046484402;6610.2398224204835;6613.570117942319;6614.966925165343;4495.685302028826;4524.321351797257;4527.637788976808;4527.728336299832;4536.115457485758;6209.875802484015;6328.6202827830475;6515.348465153446;6520.119783829312;6523.869522609426;6285.025820997753;6302.747890084345;6307.747690092345;6319.58922670025;6370.617762934239;6333.873322533432;6336.7148478319505;6338.746450141943;6343.5876668016435;6345.619262844244;6228.439440450381;6231.127642130196;6234.9688251558455;6238.376162383907;6241.656708881055;6207.255129384503;6341.651209183539;6378.266499352634;6379.202599675041;6384.6169229846355;6180.598261113013;6193.597416167935;6201.472874805692;6214.1921550198185;6220.937790622036 22:19:10 INFO - PROCESS | 4091 | |5;yui.html;832.816780308332;848.0967769325672;908.7637214324275;938.6292414496272;940.5720397219266;14791.482298119568;14965.401383944629;14973.026753261043;15073;15139.848601513982;13115.803262951064;13808.516701915434;13906;13940;13963.441462341494;860.7848037990502;890.9420887642315;893.852514335134;897.2373482539854;897.2866571075978;869.8260347930408;872.8952525696893;874.1520724896872;874.7725591346232;875.1248751248751;908.1871724806294;913.1598928985317;913.3469569257979;916.3585490156884;916.7616419730884;910.1353713971753;913.4382354851751;913.6482454255093;913.7761248494095;915.4598786715836;672.663668165917;675.8209074595236;677.2448719677524;678.091357580844;678.4199509419425;13596.864031359659;13629.863701363058;13635.931820340835;13714.65713357164;13743;12823.294718790463;12872.871271287355;12989.87010129896;13054.086213965027;13121.73756524864;11053.28153670017;11074.335539867554;11188.888111118866;11250.831237531444;11465.426728663617;3847.6537111660086;3885.358915778893;3938.7833669148185;3993.820278087493;4004.83980640774;42051.7897410511;51020.74489627528;53526;53858.46141538573;54194.45805541934;11926;12269.331618265544;12396.628101156979;12596.118271721074;12605.117641764988;21322.893385533283;21439.78560214409;21680.566388672138;21681.45796355072;21741.782582173975;8999.415038022638;9003.189712925738;9127.452352858849;9237.538123093818;9402.905970940203;4947.1837146871085;4994.375703037121;5029.723365214948;5049.091163590516;5167.638265321391;3764.3600587900123;3824.4454554089502;3829.4255861620422;3837.6929845612285;3839.040239940015;687.1067612104245;692.6329045605837;694.5797792335666;704.968276427562;705.1643802094534;659.4954859532462;758.878579427289;778.8052986753312;796.8167321516083;823.4853216739538;9597.856032159523;9851.802963940825;9969.850452243221;10098.192144628412;10138.797224055477;656.5207398599049;730.4120183252427;756.7994481462417;798.3972101063775;820.4011071917533;4467.7319360838455;4584.144090206084;4598.655100867449;4601.378813860153;4740.5733483986605;62769.37230627635;64507.03239451412;65081.34918650847;65317.67341163264;65426.34573654298;47845.5215447848;54672;54714.17928731072;55932;56541.43458565362;47955.760221198674;49991.50008499868;50687.746561266955;52309.7384513075;52615;9062.592183351897;9116.1339672731;9891.802163956825;10007.849882251918;10057.396556206651;37167.25665486729;38263;38597.80701096477;39686;40485;49235;49292.75353623209;49516.75241623769;49656.50343496519;50635;12650.332300395748;12746.42641081172;12805.551805686757;13060.542880999119;13135;13698.315084245947;13765.242911639956;13997.44010239579;14273.286335682966;14348.282585870873;13442.370559948955;13482.19106853592;13967.371468284155;13974.161550307013;14225.359858806585;13277.66805829862;13694.178349299074;13851.515196968461;13869.722605547631;13918.4432622698;13163.144395614254;13198;13330.733385332045;13493.595192144252;14002.929985350009;10687.358758474516;10700.83948740754;10831.837522437012;11289;11376.374299413612;11153.553857845594;11312.406576980567;11497.310161390344;11563.71090722705;11643.76712465729 22:19:10 INFO - PROCESS | 4091 | __end_tp_report 22:19:10 INFO - PROCESS | 4091 | __start_cc_report 22:19:10 INFO - PROCESS | 4091 | _x_x_mozilla_cycle_collect,816 22:19:10 INFO - PROCESS | 4091 | __end_cc_report 22:19:10 INFO - PROCESS | 4091 | __startTimestamp1464412750735__endTimestamp 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | ------- Summary: start ------- 22:19:10 INFO - PROCESS | 4091 | Number of tests: 6 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | [#0] dojo.html Cycles:165 Average:5440.88 Median:5661.09 stddev:583.89 (10.3%) stddev-sans-first:560.21 22:19:10 INFO - PROCESS | 4091 | Values: 3266.5 3316.0 3331.9 3357.1 3378.5 5482.2 5491.0 5502.1 5506.6 5520.0 5482.5 5513.9 5515.0 5523.8 5560.2 4888.0 4968.1 4971.5 5052.1 5091.3 4960.9 4967.6 4978.9 5054.7 5066.2 5038.2 5126.5 5127.2 5133.6 5134.1 4950.6 4993.7 5004.5 5012.3 5015.9 4861.9 4881.7 4919.2 4957.4 4969.0 5367.2 5370.4 5371.9 5382.4 5423.1 5814.9 5839.6 5842.6 5843.9 5844.5 5800.7 5886.3 5892.1 5973.4 5977.9 5450.2 5497.9 5504.6 5505.0 5520.6 5862.1 5918.8 5966.6 5974.8 5981.3 5725.3 5779.9 5781.7 5816.5 5824.4 5845.8 5845.9 5848.9 5854.2 5855.2 5706.8 5814.2 5819.6 5827.9 5850.0 5883.4 5885.2 5891.8 5898.2 5928.2 4474.7 4505.7 4506.6 4532.9 4534.7 3973.3 3979.2 4030.1 4048.8 4091.6 5488.7 5500.9 5531.4 5532.6 5538.4 5873.9 5876.3 5883.7 5889.3 5890.6 5433.6 5497.5 5524.4 5566.7 5572.8 5037.1 5061.5 5073.6 5074.5 5078.5 5872.1 5941.9 5960.1 5969.9 5982.3 5727.8 5747.3 5838.9 5847.7 5877.7 5918.1 5927.0 5928.9 5930.9 5931.5 5742.9 5754.8 5760.6 5770.7 5772.7 5773.3 5819.0 5889.1 5890.9 5921.8 5715.6 5720.1 5733.8 5743.9 5761.4 5606.6 5721.0 5730.2 5737.8 5740.5 5618.5 5655.5 5661.1 5671.6 5697.2 5713.2 5725.4 5749.2 5755.9 5760.4 5518.2 5576.4 5635.6 5651.0 5675.0 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | [#1] ext.html Cycles:180 Average:53974.91 Median:42174.39 stddev:97214.92 (230.5%) stddev-sans-first:97405.23 22:19:10 INFO - PROCESS | 4091 | Values: 665.1 674.6 680.5 731.6 739.2 33272.8 34101.6 38350.0 39017.8 39143.0 42222.6 42602.0 42664.4 42807.8 42814.8 8773.4 8948.6 8954.3 8955.9 8957.4 8809.4 8935.9 8941.6 8941.8 8947.9 8913.5 9175.3 9186.4 9205.5 9212.4 9044.3 9100.2 9110.3 9112.2 9120.9 9012.2 9142.9 9145.2 9145.3 9151.8 42107.6 42214.0 42355.2 42671.4 42750.4 3699.7 3734.3 3768.2 3969.3 4310.5 31994.2 32047.0 32074.2 32326.5 32525.2 4377.5 4432.2 4460.8 4514.5 4556.1 280915.0 281975.0 282976.0 284025.0 284248.0 60337.1 60651.4 60658.1 60661.7 60695.3 69770.7 71483.6 71496.0 71513.3 71550.6 18992.1 19221.5 19262.2 19269.1 19276.4 14737.1 14872.7 14879.5 14898.9 14908.3 20188.0 20404.4 20549.8 20609.5 20614.6 7544.9 7575.7 7588.2 7622.8 7781.6 1178.2 1181.7 1198.0 1216.5 1239.7 33335.3 33776.5 33789.7 34393.7 34467.5 1243.7 1282.3 1288.7 1302.3 1370.6 12984.9 17757.6 17877.3 17919.3 18091.6 486122.0 494511.0 495405.0 495586.0 495627.0 61711.0 62504.7 62520.0 62616.0 62620.0 1330.9 1433.2 1569.0 1631.3 1661.6 31139.3 32135.4 32825.4 33250.2 33924.3 48948.0 49251.3 49302.8 49365.0 49400.0 287024.0 288455.0 288948.0 289048.0 290081.0 29554.4 30421.7 30520.5 30830.5 30854.0 49625.3 50451.0 50564.2 50867.0 51620.2 41009.8 41645.8 42134.8 42411.2 42626.8 41261.0 41670.4 41784.0 42476.4 42633.1 42810.1 42910.1 44273.8 44297.8 44601.8 36700.8 37081.6 37604.2 37738.7 38214.6 28603.6 28762.7 31292.4 31447.2 32265.2 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | [#2] jquery.html Cycles:180 Average:30271.22 Median:12278.98 stddev:87370.73 (711.5%) stddev-sans-first:87587.09 22:19:10 INFO - PROCESS | 4091 | Values: 429.8 431.3 436.8 441.2 448.8 18555.4 18572.9 18924.4 19233.5 19361.1 18661.0 18762.7 19469.6 19566.0 19574.2 5745.8 5791.8 6001.6 6028.8 6032.6 5962.3 5979.2 6003.7 6031.9 6067.5 6007.0 6008.6 6105.6 6379.6 6397.4 5985.3 6122.4 6401.6 6407.1 6444.3 5933.6 5962.9 5968.8 5982.0 5988.8 20689.3 21481.2 21678.5 21695.2 22220.3 2337.6 2497.5 2635.9 2774.3 2867.9 10061.4 10752.9 10768.1 13100.0 14674.4 2019.6 2029.9 2053.3 2075.0 2081.1 71835.6 79974.6 80370.2 81540.0 88079.0 11042.3 11567.8 11819.8 11845.5 12005.8 16519.3 16596.8 16660.6 16685.2 16717.4 8677.6 8858.9 8872.1 8898.6 8938.5 8952.3 8973.6 8992.1 9003.4 9071.3 4637.3 4794.8 4906.5 5081.5 5115.5 2067.7 2070.3 2112.7 2119.9 2144.8 997.6 1051.7 1072.2 1073.3 1166.2 19221.8 19520.5 19736.9 19876.5 19917.5 1051.7 1059.3 1062.0 1073.8 1085.5 10892.0 11035.7 11336.9 11381.6 11403.9 517772.0 526524.0 535362.0 538186.0 539209.0 89375.0 94814.0 94889.0 95133.0 95183.5 1088.1 1090.9 1095.1 1118.6 1122.2 16223.8 16866.0 17431.1 17575.5 17704.4 36030.5 36459.5 36936.0 38118.8 38169.8 54021.5 57794.4 58088.7 58407.0 58510.4 8824.0 9121.8 9242.5 9309.2 9489.3 9150.4 9330.5 12175.2 13032.3 14115.2 10778.4 11169.9 11564.2 12016.5 12603.7 11758.4 11928.1 12102.0 12156.9 12449.3 11547.9 12491.9 12656.1 12660.7 12823.4 9206.8 9236.7 9374.3 9387.7 9390.0 9633.6 12198.5 12345.2 12778.1 12803.5 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | [#3] mootools.html Cycles:180 Average:5749.27 Median:5175.77 stddev:3185.88 (61.6%) stddev-sans-first:3178.15 22:19:10 INFO - PROCESS | 4091 | Values: 1413.4 1565.5 1591.4 1779.0 1850.9 8375.2 8566.6 8578.0 8590.5 8626.6 8422.6 8561.1 8623.4 8630.0 8657.0 2453.2 2475.2 2475.5 2476.9 2477.7 2445.8 2506.8 2508.4 2509.5 2518.0 2430.2 2435.9 2475.9 2477.0 2479.8 2456.4 2476.1 2480.6 2483.4 2506.0 2431.4 2461.4 2475.3 2500.5 2501.9 8415.4 8561.0 8745.5 8800.1 8801.3 5379.8 5499.9 5510.8 5515.8 5516.1 3588.6 3589.5 3597.5 3637.7 3658.2 1097.0 1105.5 1134.6 1153.5 1197.8 11943.4 12294.5 12337.6 12368.1 12375.1 4555.2 4690.6 4726.6 4736.9 4764.2 9890.4 9922.1 9924.9 9929.2 9932.8 5452.7 5461.7 5464.5 5464.7 5467.0 4455.2 4782.3 4959.2 4989.3 5073.0 3889.9 3909.1 3962.7 3997.2 4008.5 1216.9 1221.9 1229.7 1239.4 1249.8 977.9 985.5 999.4 1000.4 1006.8 5653.0 5660.9 5755.9 5773.6 5881.9 982.9 997.9 999.4 1012.8 1021.4 3907.4 3950.6 3961.9 3965.2 3986.2 10626.9 10972.5 11111.2 11134.3 11151.6 10653.3 10684.2 10763.1 10838.5 10860.7 8361.7 8403.7 8407.7 8462.3 8491.8 5459.2 5632.5 5661.1 5759.6 5790.6 9227.7 9836.9 9841.7 9900.7 9920.0 9455.2 9945.6 9992.3 9996.0 10010.0 6577.4 6733.9 7091.3 7385.2 7507.3 8115.5 8176.1 8201.6 8203.1 8226.9 7319.9 7464.9 7475.7 7577.4 7625.4 7099.4 7278.7 7295.5 7377.4 7385.7 6948.8 6990.5 6998.7 7119.5 7231.4 4946.4 5069.2 5095.5 5140.4 5159.5 5137.9 5192.0 5192.6 5270.3 5283.5 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | [#4] prototype.html Cycles:180 Average:5275.83 Median:5976.63 stddev:1551.12 (26.0%) stddev-sans-first:1510.98 22:19:10 INFO - PROCESS | 4091 | Values: 361.4 368.4 368.6 373.4 376.8 5706.8 5869.7 5895.8 5929.7 5936.6 5865.7 5886.7 5903.3 5907.9 5983.2 4606.5 4623.4 4635.7 4659.1 4663.8 4427.8 4496.6 4587.4 4634.8 4652.4 4575.2 4599.5 4608.2 4615.0 4628.2 4585.7 4592.8 4593.2 4611.2 4624.7 4106.7 4108.3 4110.4 4118.5 4119.5 5730.7 5737.1 5761.7 5765.3 5774.6 5378.6 5388.1 5394.1 5398.8 5404.1 6019.6 6238.9 6240.7 6247.4 6248.3 4349.5 4352.9 4363.3 4364.7 4373.5 6459.9 6532.1 6541.1 6549.5 6555.3 4543.5 4561.2 4565.1 4571.1 4577.1 4618.9 4621.0 4623.9 4627.2 4631.9 5184.5 5291.8 5296.8 5313.9 5318.9 6227.2 6240.6 6266.6 6313.2 6317.1 1573.3 1629.7 1666.1 1673.6 1674.1 1130.1 1138.1 1147.7 1158.2 1162.8 5953.7 5970.1 6004.6 6018.7 6019.0 6402.9 6407.1 6415.6 6420.3 6426.1 5998.8 5998.9 6000.2 6015.6 6016.2 3422.7 3442.9 3443.8 3447.6 3453.4 6340.6 6476.1 6492.0 6552.9 6642.8 6610.4 6634.5 6649.0 6652.9 6655.2 6554.7 6569.3 6570.9 6574.5 6617.5 6529.5 6533.8 6536.6 6537.2 6545.2 6302.8 6424.8 6436.0 6443.8 6446.7 6582.6 6596.9 6610.2 6613.6 6615.0 4495.7 4524.3 4527.6 4527.7 4536.1 6209.9 6328.6 6515.3 6520.1 6523.9 6285.0 6302.7 6307.7 6319.6 6370.6 6333.9 6336.7 6338.7 6343.6 6345.6 6228.4 6231.1 6235.0 6238.4 6241.7 6207.3 6341.7 6378.3 6379.2 6384.6 6180.6 6193.6 6201.5 6214.2 6220.9 22:19:10 INFO - PROCESS | 4091 | 22:19:10 INFO - PROCESS | 4091 | [#5] yui.html Cycles:180 Average:15360.54 Median:4525.94 stddev:17379.29 (384.0%) stddev-sans-first:17393.80 22:19:10 INFO - PROCESS | 4091 | Values: 832.8 848.1 908.8 938.6 940.6 14791.5 14965.4 14973.0 15073.0 15139.8 13115.8 13808.5 13906.0 13940.0 13963.4 860.8 890.9 893.9 897.2 897.3 869.8 872.9 874.2 874.8 875.1 908.2 913.2 913.3 916.4 916.8 910.1 913.4 913.6 913.8 915.5 672.7 675.8 677.2 678.1 678.4 13596.9 13629.9 13635.9 13714.7 13743.0 12823.3 12872.9 12989.9 13054.1 13121.7 11053.3 11074.3 11188.9 11250.8 11465.4 3847.7 3885.4 3938.8 3993.8 4004.8 42051.8 51020.7 53526.0 53858.5 54194.5 11926.0 12269.3 12396.6 12596.1 12605.1 21322.9 21439.8 21680.6 21681.5 21741.8 8999.4 9003.2 9127.5 9237.5 9402.9 4947.2 4994.4 5029.7 5049.1 5167.6 3764.4 3824.4 3829.4 3837.7 3839.0 687.1 692.6 694.6 705.0 705.2 659.5 758.9 778.8 796.8 823.5 9597.9 9851.8 9969.9 10098.2 10138.8 656.5 730.4 756.8 798.4 820.4 4467.7 4584.1 4598.7 4601.4 4740.6 62769.4 64507.0 65081.3 65317.7 65426.3 47845.5 54672.0 54714.2 55932.0 56541.4 47955.8 49991.5 50687.7 52309.7 52615.0 9062.6 9116.1 9891.8 10007.8 10057.4 37167.3 38263.0 38597.8 39686.0 40485.0 49235.0 49292.8 49516.8 49656.5 50635.0 12650.3 12746.4 12805.6 13060.5 13135.0 13698.3 13765.2 13997.4 14273.3 14348.3 13442.4 13482.2 13967.4 13974.2 14225.4 13277.7 13694.2 13851.5 13869.7 13918.4 13163.1 13198.0 13330.7 13493.6 14002.9 10687.4 10700.8 10831.8 11289.0 11376.4 11153.6 11312.4 11497.3 11563.7 11643.8 22:19:10 INFO - PROCESS | 4091 | -------- Summary: end -------- 22:19:10 INFO - PROCESS | 4091 | 22:19:11 INFO - TEST-INFO | 4091: exit 0 22:19:11 INFO - TEST-OK | dromaeo_css | took 1110513ms 22:19:11 INFO - TEST-START | kraken 22:19:11 INFO - operating with platform_type : linux_ 22:19:11 INFO - Initialising browser for kraken test... 22:19:11 INFO - TEST-INFO | started process 4292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8TGW3D/profile http://localhost:45763/getInfo.html) 22:19:15 INFO - PROCESS | 4292 | __metrics Screen width/height:1600/1200 22:19:15 INFO - PROCESS | 4292 | colorDepth:24 22:19:15 INFO - PROCESS | 4292 | Browser inner width/height: 1024/697 22:19:15 INFO - PROCESS | 4292 | __metrics 22:19:16 ERROR - PROCESS | 4292 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:19:17 INFO - TEST-INFO | 4292: exit 0 22:19:17 INFO - Browser initialized. 22:19:17 INFO - Running cycle 1/1 for kraken test... 22:19:17 INFO - TEST-INFO | started process 4384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8TGW3D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:19:28 INFO - PROCESS | 4384 | RSS: Main: 141803520 22:19:28 INFO - PROCESS | 4384 | 22:19:31 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45763/tests/kraken/driver.html?testName=audio-beat-detection) 22:19:32 INFO - PROCESS | 4384 | RSS: Main: 196448256 22:19:32 INFO - PROCESS | 4384 | 22:19:34 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45763/tests/kraken/driver.html?testName=audio-dft) 22:19:35 INFO - PROCESS | 4384 | RSS: Main: 181473280 22:19:35 INFO - PROCESS | 4384 | 22:19:38 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45763/tests/kraken/driver.html?testName=audio-fft) 22:19:38 INFO - PROCESS | 4384 | RSS: Main: 272314368 22:19:38 INFO - PROCESS | 4384 | 22:19:40 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45763/tests/kraken/driver.html?testName=audio-oscillator) 22:19:41 INFO - PROCESS | 4384 | RSS: Main: 178573312 22:19:41 INFO - PROCESS | 4384 | 22:19:43 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45763/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:19:44 INFO - PROCESS | 4384 | RSS: Main: 177000448 22:19:44 INFO - PROCESS | 4384 | 22:19:47 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45763/tests/kraken/driver.html?testName=imaging-darkroom) 22:19:47 INFO - PROCESS | 4384 | RSS: Main: 211689472 22:19:47 INFO - PROCESS | 4384 | 22:19:51 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45763/tests/kraken/driver.html?testName=imaging-desaturate) 22:19:51 INFO - PROCESS | 4384 | RSS: Main: 212164608 22:19:51 INFO - PROCESS | 4384 | 22:19:54 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45763/tests/kraken/driver.html?testName=json-parse-financial) 22:19:55 INFO - PROCESS | 4384 | RSS: Main: 211083264 22:19:55 INFO - PROCESS | 4384 | 22:19:56 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45763/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:19:57 INFO - PROCESS | 4384 | RSS: Main: 190820352 22:19:57 INFO - PROCESS | 4384 | 22:19:58 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:19:59 INFO - PROCESS | 4384 | RSS: Main: 194199552 22:19:59 INFO - PROCESS | 4384 | 22:20:01 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:20:02 INFO - PROCESS | 4384 | RSS: Main: 207245312 22:20:02 INFO - PROCESS | 4384 | 22:20:04 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:20:05 INFO - PROCESS | 4384 | RSS: Main: 214126592 22:20:05 INFO - PROCESS | 4384 | 22:20:07 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:20:08 INFO - PROCESS | 4384 | RSS: Main: 210345984 22:20:08 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | Cycle 1(1): loaded http://localhost:45763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45763/tests/kraken/driver.html?testName=ai-astar) 22:20:10 INFO - PROCESS | 4384 | RSS: Main: 274550784 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | __start_tp_report 22:20:10 INFO - PROCESS | 4384 | _x_x_mozilla_page_load 22:20:10 INFO - PROCESS | 4384 | _x_x_mozilla_page_load_details 22:20:10 INFO - PROCESS | 4384 | |i|pagename|runs| 22:20:10 INFO - PROCESS | 4384 | |0;ai-astar;111;113;112;109;109;111;106;111;110;109 22:20:10 INFO - PROCESS | 4384 | |1;audio-beat-detection;159;135;156;184;126;167;159;128;198;125 22:20:10 INFO - PROCESS | 4384 | |2;audio-dft;161;160;160;160;161;160;160;159;167;161 22:20:10 INFO - PROCESS | 4384 | |3;audio-fft;77;77;76;172;77;73;75;75;167;76 22:20:10 INFO - PROCESS | 4384 | |4;audio-oscillator;92;92;143;92;92;92;148;92;92;145 22:20:10 INFO - PROCESS | 4384 | |5;imaging-gaussian-blur;117;118;116;117;116;116;117;117;117;117 22:20:10 INFO - PROCESS | 4384 | |6;imaging-darkroom;145;145;145;145;145;144;144;145;145;145 22:20:10 INFO - PROCESS | 4384 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;94;94 22:20:10 INFO - PROCESS | 4384 | |8;json-parse-financial;54;56;54;55;58;56;56;75;55;52 22:20:10 INFO - PROCESS | 4384 | |9;json-stringify-tinderbox;66;57;58;58;57;57;56;57;58;57 22:20:10 INFO - PROCESS | 4384 | |10;stanford-crypto-aes;72;71;73;72;73;76;74;80;73;73 22:20:10 INFO - PROCESS | 4384 | |11;stanford-crypto-ccm;114;120;111;116;113;112;114;113;135;125 22:20:10 INFO - PROCESS | 4384 | |12;stanford-crypto-pbkdf2;159;169;159;161;158;155;156;154;155;156 22:20:10 INFO - PROCESS | 4384 | |13;stanford-crypto-sha256-iterative;68;66;66;69;66;69;66;67;65;65 22:20:10 INFO - PROCESS | 4384 | __end_tp_report 22:20:10 INFO - PROCESS | 4384 | __start_cc_report 22:20:10 INFO - PROCESS | 4384 | _x_x_mozilla_cycle_collect,1571 22:20:10 INFO - PROCESS | 4384 | __end_cc_report 22:20:10 INFO - PROCESS | 4384 | __startTimestamp1464412810336__endTimestamp 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | ------- Summary: start ------- 22:20:10 INFO - PROCESS | 4384 | Number of tests: 14 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:1.97 (1.8%) stddev-sans-first:2.06 22:20:10 INFO - PROCESS | 4384 | Values: 111.0 113.0 112.0 109.0 109.0 111.0 106.0 111.0 110.0 109.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#1] audio-beat-detection Cycles:10 Average:153.70 Median:159.00 stddev:25.21 (15.9%) stddev-sans-first:26.67 22:20:10 INFO - PROCESS | 4384 | Values: 159.0 135.0 156.0 184.0 126.0 167.0 159.0 128.0 198.0 125.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#2] audio-dft Cycles:10 Average:160.90 Median:160.50 stddev:2.23 (1.4%) stddev-sans-first:2.37 22:20:10 INFO - PROCESS | 4384 | Values: 161.0 160.0 160.0 160.0 161.0 160.0 160.0 159.0 167.0 161.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#3] audio-fft Cycles:10 Average:94.50 Median:76.00 stddev:39.56 (52.1%) stddev-sans-first:41.46 22:20:10 INFO - PROCESS | 4384 | Values: 77.0 77.0 76.0 172.0 77.0 73.0 75.0 75.0 167.0 76.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#4] audio-oscillator Cycles:10 Average:108.00 Median:92.00 stddev:25.79 (28.0%) stddev-sans-first:26.70 22:20:10 INFO - PROCESS | 4384 | Values: 92.0 92.0 143.0 92.0 92.0 92.0 148.0 92.0 92.0 145.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 22:20:10 INFO - PROCESS | 4384 | Values: 117.0 118.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#6] imaging-darkroom Cycles:10 Average:144.80 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 22:20:10 INFO - PROCESS | 4384 | Values: 145.0 145.0 145.0 145.0 145.0 144.0 144.0 145.0 145.0 145.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 22:20:10 INFO - PROCESS | 4384 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 94.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:56.00 stddev:6.49 (11.6%) stddev-sans-first:6.78 22:20:10 INFO - PROCESS | 4384 | Values: 54.0 56.0 54.0 55.0 58.0 56.0 56.0 75.0 55.0 52.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:57.50 stddev:2.85 (4.9%) stddev-sans-first:0.67 22:20:10 INFO - PROCESS | 4384 | Values: 66.0 57.0 58.0 58.0 57.0 57.0 56.0 57.0 58.0 57.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:73.00 stddev:2.58 (3.5%) stddev-sans-first:2.67 22:20:10 INFO - PROCESS | 4384 | Values: 72.0 71.0 73.0 72.0 73.0 76.0 74.0 80.0 73.0 73.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#11] stanford-crypto-ccm Cycles:10 Average:117.30 Median:115.00 stddev:7.51 (6.5%) stddev-sans-first:7.87 22:20:10 INFO - PROCESS | 4384 | Values: 114.0 120.0 111.0 116.0 113.0 112.0 114.0 113.0 135.0 125.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.20 Median:158.50 stddev:4.39 (2.8%) stddev-sans-first:4.65 22:20:10 INFO - PROCESS | 4384 | Values: 159.0 169.0 159.0 161.0 158.0 155.0 156.0 154.0 155.0 156.0 22:20:10 INFO - PROCESS | 4384 | 22:20:10 INFO - PROCESS | 4384 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:1.49 (2.2%) stddev-sans-first:1.51 22:20:10 INFO - PROCESS | 4384 | Values: 68.0 66.0 66.0 69.0 66.0 69.0 66.0 67.0 65.0 65.0 22:20:10 INFO - PROCESS | 4384 | -------- Summary: end -------- 22:20:10 INFO - PROCESS | 4384 | 22:20:11 INFO - TEST-INFO | 4384: exit 0 22:20:11 INFO - TEST-OK | kraken | took 59856ms 22:20:11 INFO - SUITE-END | took 1170s 22:20:11 INFO - Completed test suite (00:19:30) 22:20:11 INFO - javascript benchmark 22:20:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3266.47736362182, 3316.038348878825, 3331.866725330987, 3357.060023193507, 3378.4763361678934, 5482.205080263359, 5491.039068163071, 5502.119660854264, 5506.559475241981, 5520.033994051045, 5482.479164479368, 5513.917291240635, 5515.0, 5523.806666766664, 5560.221568980346, 4888.046830867982, 4968.056069346831, 4971.502849715036, 5052.141136006888, 5091.312672789181, 4960.925586116211, 4967.552920237178, 4978.850634480971, 5054.696718196901, 5066.164082926312, 5038.2190760432195, 5126.487351264862, 5127.154019586764, 5133.614978876581, 5134.050200712862, 4950.554450099508, 4993.650444468889, 5004.549590536851, 5012.348394708701, 5015.949840501585, 4861.854144375639, 4881.682690625098, 4919.163742163805, 4957.380327459067, 4969.0, 5367.194920761878, 5370.409254981949, 5371.865703357408, 5382.354117505885, 5423.132298832168, 5814.912776308359, 5839.562032847553, 5842.649441033552, 5843.883122337572, 5844.503217226541, 5800.738966746464, 5886.323072846634, 5892.086726557391, 5973.402659734035, 5977.880442391128, 5450.155225939986, 5497.890042199174, 5504.559635229212, 5504.972475137639, 5520.613557051008, 5862.091375836752, 5918.763249469973, 5966.552508561876, 5974.790882319098, 5981.342052374235, 5725.341585717696, 5779.942200578024, 5781.682007489629, 5816.534677225812, 5824.388439213822, 5845.824625261249, 5845.912311315334, 5848.8537786554825, 5854.2096816930025, 5855.238818953594, 5706.80026199081, 5814.215080964101, 5819.592628516048, 5827.88344233113, 5850.0, 5883.441073098049, 5885.176075349455, 5891.823245302648, 5898.174255604219, 5928.170056192136, 4474.731516108979, 4505.6846020778885, 4506.59440650343, 4532.864014079583, 4534.727916324965, 3973.304671682502, 3979.2041591681777, 4030.133521292936, 4048.7570745755347, 4091.6317531422314, 5488.725563721798, 5500.889982200333, 5531.3915469298345, 5532.640378375393, 5538.390777014525, 5873.882522349529, 5876.324222714357, 5883.676397798162, 5889.293284805851, 5890.587658863838, 5433.646812957145, 5497.505224529811, 5524.4199359067525, 5566.749496272678, 5572.777088916398, 5037.093323201786, 5061.493850614909, 5073.594112470993, 5074.517920797518, 5078.492150784966, 5872.119182122716, 5941.9331420643975, 5960.0761881908375, 5969.850753731192, 5982.282126144891, 5727.799527016535, 5747.3103227612955, 5838.854028649318, 5847.678377689268, 5877.706114694333, 5918.082697181943, 5927.0, 5928.851778705567, 5930.881382372415, 5931.466168044724, 5742.913856292075, 5754.769809207585, 5760.625559338797, 5770.653760774367, 5772.682502462237, 5773.3072031356505, 5818.970905145447, 5889.116632505158, 5890.911636325544, 5921.792737254175, 5715.628484148517, 5720.113382425564, 5733.827985160452, 5743.942560574508, 5761.366249712444, 5606.579506537108, 5720.971395142998, 5730.169125476866, 5737.827865164052, 5740.454656807764, 5618.4662457066515, 5655.519280861173, 5661.094224923992, 5671.6029877909805, 5697.23087383198, 5713.228714123707, 5725.427457254241, 5749.1663708762835, 5755.913661295168, 5760.395158508296, 5518.227448157341, 5576.358718747396, 5635.633683810539, 5651.0, 5675.0], "value": 5404.077026003945, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.0821865825161, 674.5817593092283, 680.4828330468843, 731.5829976913161, 739.2311995524657, 33272.83363583185, 34101.63593456262, 38350.0, 39017.80491097548, 39143.0, 42222.57777422225, 42602.0, 42664.36003459951, 42807.78596107015, 42814.785926070326, 8773.385862989593, 8948.597313120892, 8954.32842536809, 8955.865662015074, 8957.417767845101, 8809.38334316598, 8935.910640893606, 8941.59762810675, 8941.821163576724, 8947.865782013276, 8913.465192088463, 9175.311851611123, 9186.402883812565, 9205.493697846616, 9212.401193922373, 9044.276457883387, 9100.180983711498, 9110.271178305753, 9112.225460835836, 9120.908790912072, 9012.233960113399, 9142.908570914273, 9145.176934075898, 9145.268378529701, 9151.771205719844, 42107.57892421067, 42214.0, 42355.1528969422, 42671.35992960108, 42750.358744618854, 3699.722520810923, 3734.271816995687, 3768.2275133597545, 3969.28552860485, 4310.547392523771, 31994.200144996328, 32047.0, 32074.198145046328, 32326.515102273486, 32525.186870328194, 4377.5403582624, 4432.246518091933, 4460.776961151962, 4514.548545145492, 4556.088782243564, 280915.0, 281975.0, 282976.0, 284025.0, 284248.0, 60337.09494357588, 60651.393486065455, 60658.0901286481, 60661.69669151714, 60695.26871290802, 69770.65114674394, 71483.64258178676, 71496.0, 71513.2848671517, 71550.64224678843, 18992.05039748007, 19221.519462013282, 19262.22951081941, 19269.132889020028, 19276.421707348803, 14737.11577305365, 14872.702545949236, 14879.47921822731, 14898.851011489964, 14908.25458727081, 20188.0, 20404.387868363974, 20549.79450205479, 20609.484762880747, 20614.58770824605, 7544.887431519073, 7575.659095340723, 7588.203238659971, 7622.771316860503, 7781.649825757854, 1178.2459226095307, 1181.710480932177, 1197.9760404791855, 1216.5377156680404, 1239.6528971887885, 33335.333293334, 33776.49335259973, 33789.662103379145, 34393.65606343905, 34467.482987755204, 1243.7077286837587, 1282.3139620303093, 1288.7100402409383, 1302.3293004102704, 1370.616227456314, 12984.935075324564, 17757.644847102987, 17877.28490860377, 17919.28322867071, 18091.638167236844, 486122.0, 494511.0, 495405.0, 495586.0, 495627.0, 61711.0, 62504.68747656233, 62520.0, 62616.0, 62620.0, 1330.8935285177163, 1433.1902475101533, 1568.9607759806097, 1631.2903886809277, 1661.6178278995899, 31139.26200478583, 32135.357292854478, 32825.377468350365, 33250.16874578155, 33924.3215135691, 48948.0, 49251.26123108228, 49302.75348623234, 49365.0, 49400.0, 287024.0, 288455.0, 288948.0, 289048.0, 290081.0, 29554.408911821214, 30421.695783041887, 30520.542191867586, 30830.53754193644, 30854.0, 49625.255621166434, 50451.0, 50564.24153637772, 50867.0, 51620.22569661383, 41009.76970690884, 41645.84688895406, 42134.78932605317, 42411.15177696491, 42626.78686606547, 41261.0, 41670.37494437647, 41784.0, 42476.36285455659, 42633.14733705246, 42810.1437971245, 42910.141797164506, 44273.77863110664, 44297.77851110724, 44601.77699111614, 36700.81649591735, 37081.62918370782, 37604.24791504209, 37738.69071069823, 38214.6178538211, 28603.570946435404, 28762.712372876842, 31292.374152517277, 31447.213819653778, 32265.193370165933], "value": 20674.666001503247, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.81563171586936, 431.25177816488394, 436.8405531980828, 441.1772045135822, 448.8249582662762, 18555.443336699886, 18572.90713546432, 18924.432267031978, 19233.519162020955, 19361.128749206284, 18661.0, 18762.718559221623, 19469.610607787872, 19566.021698915032, 19574.217031318767, 5745.827625171241, 5791.797287094932, 6001.60989535681, 6028.849278768022, 6032.607880487754, 5962.25471816023, 5979.192808970777, 6003.6697981611, 6031.909521357183, 6067.484263837579, 6007.0, 6008.579399442041, 6105.572609917308, 6379.5853269537565, 6397.392247736458, 5985.311689155759, 6122.387761223887, 6401.615903045832, 6407.070974708665, 6444.258910225314, 5933.643981361089, 5962.940370596282, 5968.820935371946, 5981.970090149522, 5988.790392336291, 20689.27587534444, 21481.24815631461, 21678.45803854916, 21695.240666576596, 22220.333389998326, 2337.625979843234, 2497.475530138673, 2635.8813853376646, 2774.3202915285683, 2867.899623513188, 10061.39631622105, 10752.892471075267, 10768.138548916068, 13100.01749868754, 14674.413023479048, 2019.6061767955255, 2029.918803247883, 2053.3121404329568, 2074.95850082999, 2081.105124796337, 71835.64082179609, 79974.600126999, 80370.19629803686, 81540.0, 88079.0, 11042.343648452796, 11567.826482602768, 11819.763604727857, 11845.526178952918, 12005.759884802255, 16519.33922643105, 16596.751048734277, 16660.58348541296, 16685.24916378766, 16717.414890478776, 8677.566121694017, 8858.911410885808, 8872.112788721077, 8898.555072246361, 8938.46369217849, 8952.283817294601, 8973.641054357882, 8992.145746154105, 9003.36976411658, 9071.319651026199, 4637.281221410687, 4794.760261986887, 4906.460289368167, 5081.466446023188, 5115.514026167508, 2067.7105205271273, 2070.2960993262363, 2112.7359080114984, 2119.8940052997596, 2144.8498605097807, 997.5959736306808, 1051.679237832453, 1072.158355690779, 1073.2862646340097, 1166.2302880099094, 19221.80778192228, 19520.511987200432, 19736.90131549333, 19876.50308742264, 19917.502062448555, 1051.7055224537141, 1059.2796898109204, 1061.9787604248027, 1073.7691396349821, 1085.5386460754148, 10891.965263299973, 11035.724106897393, 11336.886631133748, 11381.601643942422, 11403.942980285046, 517772.0, 526524.0, 535362.0, 538186.0, 539209.0, 89375.0, 94814.0, 94889.0, 95133.0, 95183.52408238054, 1088.102315589626, 1090.8636420447444, 1095.1293721491593, 1118.608487029533, 1122.242486321746, 16223.756643650591, 16866.0, 17431.128443578025, 17575.472735817944, 17704.380346687805, 36030.45954310635, 36459.45310820287, 36936.0, 38118.809405952794, 38169.80915095407, 54021.45978540164, 57794.4220557789, 58088.70955645195, 58407.0, 58510.4148958522, 8823.955880220557, 9121.77195570116, 9242.491662958602, 9309.20871725911, 9489.288303377136, 9150.40522366044, 9330.533473326172, 12175.208611440228, 13032.283224422748, 14115.153090814583, 10778.407187604756, 11169.944150279196, 11564.190506664452, 12016.519339226683, 12603.74792504163, 11758.3532905691, 11928.105392095455, 12102.0, 12156.939215303866, 12449.25304481734, 11547.942260288644, 12491.93754031224, 12656.11407201487, 12660.746785064062, 12823.358832058173, 9206.815863682823, 9236.72289831306, 9374.296927730584, 9387.671431499864, 9389.953050234704, 9633.614655413705, 12198.51205951752, 12345.197562158432, 12778.105532612628, 12803.487860485477], "value": 9614.874611008732, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.3639862062075, 1565.5068653374187, 1591.3873158833849, 1778.9822101778987, 1850.8889466632004, 8375.204355586216, 8566.614502347395, 8577.95711021444, 8590.52752098635, 8626.568671566429, 8422.620982055807, 8561.058283588816, 8623.396362254645, 8630.0, 8657.047724750273, 2453.1781853079196, 2475.170817776047, 2475.4553998120386, 2476.8885400157037, 2477.665515155458, 2445.8165637577163, 2506.761857623532, 2508.4481414088964, 2509.5357358888573, 2517.999999999991, 2430.2101816909576, 2435.926922192328, 2475.8885850136785, 2477.046337160188, 2479.78921791647, 2456.3736247256925, 2476.05909754294, 2480.553500369933, 2483.3791552111793, 2505.9749402506013, 2431.4043059450373, 2461.384653836541, 2475.282168171246, 2500.5374005808985, 2501.874906254698, 8415.368847336413, 8561.0, 8745.47527148373, 8800.075992020873, 8801.251893589053, 5379.81170659029, 5499.917501237484, 5510.779568817243, 5515.834524964217, 5516.117421212627, 3588.5873124590744, 3589.46158076288, 3597.5143355646915, 3637.654422829827, 3658.2317713280245, 1097.0, 1105.4804242006244, 1134.5858761552056, 1153.532819208222, 1197.8143387774908, 11943.40282985856, 12294.508219671203, 12337.568185113478, 12368.072394570443, 12375.071869609725, 4555.2483840166005, 4690.624750019991, 4726.550977657117, 4736.881577960509, 4764.166270902648, 9890.406575605486, 9922.107010369102, 9924.900750992541, 9929.156021738088, 9932.751681208028, 5452.727363631802, 5461.672299662033, 5464.453554644583, 5464.726763661801, 5467.043267428184, 4455.220336441174, 4782.33047373368, 4959.20652695567, 4989.251612258117, 5072.960043191137, 3889.9027524311555, 3909.0618251619976, 3962.6829853611644, 3997.160596274815, 4008.498937632796, 1216.9148159628742, 1221.8900298973135, 1229.692576855786, 1239.3865036806837, 1249.7500499900057, 977.9364341317792, 985.5367977050774, 999.4003597841237, 1000.3597697473623, 1006.7634105985088, 5653.0, 5660.8867822643315, 5755.85610359736, 5773.624714393551, 5881.852953676193, 982.886967998675, 997.9301448898652, 999.4253304350028, 1012.7620009297809, 1021.3718563083798, 3907.4138879168354, 3950.6444420001762, 3961.92076158481, 3965.2443940162616, 3986.22268657609, 10626.893731062744, 10972.451377431096, 11111.222214444912, 11134.27627204229, 11151.553937842395, 10653.254272200871, 10684.198685098494, 10763.08513776322, 10838.512266948006, 10860.728481788019, 8361.707340243183, 8403.70587029451, 8407.74776756686, 8462.280706139923, 8491.830163396697, 5459.208414779914, 5632.549396048388, 5661.094224923992, 5759.596828221985, 5790.594658373873, 9227.677031303872, 9836.901630983599, 9841.655542055994, 9900.653477128266, 9920.0, 9455.24358051368, 9945.552450139616, 9992.250581206585, 9996.0, 10010.0, 6577.408033277028, 6733.898991515229, 7091.290870912868, 7385.150707668687, 7507.286807753255, 8115.513069215866, 8176.100628930694, 8201.58992050407, 8203.097659257357, 8226.876596850932, 7319.853602927805, 7464.925350746423, 7475.738349157753, 7577.431692622965, 7625.428092892944, 7099.36105750485, 7278.6724597392185, 7295.52579082358, 7377.372923301579, 7385.7045718170675, 6948.791536253921, 6990.475714321345, 6998.6850591724515, 7119.466040046914, 7231.385332246818, 4946.35697359341, 5069.214271787879, 5095.541401273903, 5140.3831540215415, 5159.509846564571, 5137.92293115611, 5192.039472697563, 5192.610554208525, 5270.314859068297, 5283.471652834686], "value": 4662.445636498992, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.416312655062, 368.4087040300318, 368.6221622836593, 373.44729799896146, 376.77017019618046, 5706.828795136143, 5869.677167755772, 5895.823125306237, 5929.703514824263, 5936.554758393122, 5865.677387743673, 5886.676232807194, 5903.26209223847, 5907.85230369241, 5983.192269043678, 4606.470255920579, 4623.375844261015, 4635.721856688592, 4659.114768194049, 4663.8367657132, 4427.77861106945, 4496.617787488068, 4587.357769912215, 4634.768261586924, 4652.371929789469, 4575.153596584626, 4599.540045995407, 4608.23964045932, 4615.0, 4628.236341003747, 4585.747783871886, 4592.816287348502, 4593.173228818795, 4611.239145540982, 4624.653151013654, 4106.7125301228925, 4108.322126849066, 4110.403991421257, 4118.5057793064725, 4119.485064366954, 5730.656160630376, 5737.139429085629, 5761.683107429089, 5765.308163020464, 5774.595778295521, 5378.596605254622, 5388.114160043953, 5394.056040192951, 5398.757055932492, 5404.135338345885, 6019.578629495937, 6238.875222495525, 6240.687965601747, 6247.375262473762, 6248.343923888017, 4349.499807522112, 4352.869413917588, 4363.3454981752675, 4364.672649551297, 4373.497047839507, 6459.870802583969, 6532.052852336385, 6541.084248205208, 6549.508786841006, 6555.278919318871, 4543.5002149763495, 4561.201789686858, 4565.0641618468135, 4571.131485017902, 4577.107464044546, 4618.861434156981, 4620.976895115503, 4623.884402889955, 4627.190241707755, 4631.861044168681, 5184.533391994739, 5291.814786482455, 5296.841094767086, 5313.946860531345, 5318.867028324245, 6227.19046523949, 6240.594361366497, 6266.561340706198, 6313.242410910629, 6317.052442133625, 1573.3077445923757, 1629.6985057764352, 1666.1419369024961, 1673.623434727201, 1674.0792564089702, 1130.084631448513, 1138.1407037686495, 1147.6614398752388, 1158.1719070864372, 1162.7674465107013, 5953.672548009901, 5970.0746384310505, 6004.609700369462, 6018.699065046818, 6018.969905150534, 6402.903956440658, 6407.13503677007, 6415.647139407378, 6420.26166990802, 6426.068220108152, 5998.820035398945, 5998.910016349758, 6000.189974353494, 6015.639061656227, 6016.2479690038745, 3422.691957723817, 3442.8622855085514, 3443.7589368743947, 3447.6035255945385, 3453.3783918894337, 6340.587861788969, 6476.060971159155, 6491.993740970157, 6552.9344706553275, 6642.800715978528, 6610.3720146586, 6634.535582509275, 6649.035889795952, 6652.933470665232, 6655.234648015444, 6554.737810487527, 6569.310222426672, 6570.868582628416, 6574.539782215199, 6617.4706023518, 6529.542931994714, 6533.803985880431, 6536.575122617015, 6537.182852143482, 6545.21457425112, 6302.842428939313, 6424.807255782334, 6436.0, 6443.838904027437, 6446.7098980546925, 6582.572132811543, 6596.901046484402, 6610.2398224204835, 6613.570117942319, 6614.966925165343, 4495.685302028826, 4524.321351797257, 4527.637788976808, 4527.728336299832, 4536.115457485758, 6209.875802484015, 6328.6202827830475, 6515.348465153446, 6520.119783829312, 6523.869522609426, 6285.025820997753, 6302.747890084345, 6307.747690092345, 6319.58922670025, 6370.617762934239, 6333.873322533432, 6336.7148478319505, 6338.746450141943, 6343.5876668016435, 6345.619262844244, 6228.439440450381, 6231.127642130196, 6234.9688251558455, 6238.376162383907, 6241.656708881055, 6207.255129384503, 6341.651209183539, 6378.266499352634, 6379.202599675041, 6384.6169229846355, 6180.598261113013, 6193.597416167935, 6201.472874805692, 6214.1921550198185, 6220.937790622036], "value": 4787.30375309704, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.816780308332, 848.0967769325672, 908.7637214324275, 938.6292414496272, 940.5720397219266, 14791.482298119568, 14965.401383944629, 14973.026753261043, 15073.0, 15139.848601513982, 13115.803262951064, 13808.516701915434, 13906.0, 13940.0, 13963.441462341494, 860.7848037990502, 890.9420887642315, 893.852514335134, 897.2373482539854, 897.2866571075978, 869.8260347930408, 872.8952525696893, 874.1520724896872, 874.7725591346232, 875.1248751248751, 908.1871724806294, 913.1598928985317, 913.3469569257979, 916.3585490156884, 916.7616419730884, 910.1353713971753, 913.4382354851751, 913.6482454255093, 913.7761248494095, 915.4598786715836, 672.663668165917, 675.8209074595236, 677.2448719677524, 678.091357580844, 678.4199509419425, 13596.864031359659, 13629.863701363058, 13635.931820340835, 13714.65713357164, 13743.0, 12823.294718790463, 12872.871271287355, 12989.87010129896, 13054.086213965027, 13121.73756524864, 11053.28153670017, 11074.335539867554, 11188.888111118866, 11250.831237531444, 11465.426728663617, 3847.6537111660086, 3885.358915778893, 3938.7833669148185, 3993.820278087493, 4004.83980640774, 42051.7897410511, 51020.74489627528, 53526.0, 53858.46141538573, 54194.45805541934, 11926.0, 12269.331618265544, 12396.628101156979, 12596.118271721074, 12605.117641764988, 21322.893385533283, 21439.78560214409, 21680.566388672138, 21681.45796355072, 21741.782582173975, 8999.415038022638, 9003.189712925738, 9127.452352858849, 9237.538123093818, 9402.905970940203, 4947.1837146871085, 4994.375703037121, 5029.723365214948, 5049.091163590516, 5167.638265321391, 3764.3600587900123, 3824.4454554089502, 3829.4255861620422, 3837.6929845612285, 3839.040239940015, 687.1067612104245, 692.6329045605837, 694.5797792335666, 704.968276427562, 705.1643802094534, 659.4954859532462, 758.878579427289, 778.8052986753312, 796.8167321516083, 823.4853216739538, 9597.856032159523, 9851.802963940825, 9969.850452243221, 10098.192144628412, 10138.797224055477, 656.5207398599049, 730.4120183252427, 756.7994481462417, 798.3972101063775, 820.4011071917533, 4467.7319360838455, 4584.144090206084, 4598.655100867449, 4601.378813860153, 4740.5733483986605, 62769.37230627635, 64507.03239451412, 65081.34918650847, 65317.67341163264, 65426.34573654298, 47845.5215447848, 54672.0, 54714.17928731072, 55932.0, 56541.43458565362, 47955.760221198674, 49991.50008499868, 50687.746561266955, 52309.7384513075, 52615.0, 9062.592183351897, 9116.1339672731, 9891.802163956825, 10007.849882251918, 10057.396556206651, 37167.25665486729, 38263.0, 38597.80701096477, 39686.0, 40485.0, 49235.0, 49292.75353623209, 49516.75241623769, 49656.50343496519, 50635.0, 12650.332300395748, 12746.42641081172, 12805.551805686757, 13060.542880999119, 13135.0, 13698.315084245947, 13765.242911639956, 13997.44010239579, 14273.286335682966, 14348.282585870873, 13442.370559948955, 13482.19106853592, 13967.371468284155, 13974.161550307013, 14225.359858806585, 13277.66805829862, 13694.178349299074, 13851.515196968461, 13869.722605547631, 13918.4432622698, 13163.144395614254, 13198.0, 13330.733385332045, 13493.595192144252, 14002.929985350009, 10687.358758474516, 10700.83948740754, 10831.837522437012, 11289.0, 11376.374299413612, 11153.553857845594, 11312.406576980567, 11497.310161390344, 11563.71090722705, 11643.76712465729], "value": 7134.6548892730025, "unit": "score"}], "name": "dromaeo_css", "value": 7450.81571064905, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 112.0, 109.0, 109.0, 111.0, 106.0, 111.0, 110.0, 109.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 135.0, 156.0, 184.0, 126.0, 167.0, 159.0, 128.0, 198.0, 125.0], "value": 153.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 160.0, 160.0, 159.0, 167.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 172.0, 77.0, 73.0, 75.0, 75.0, 167.0, 76.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 143.0, 92.0, 92.0, 92.0, 148.0, 92.0, 92.0, 145.0], "value": 108.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 144.0, 144.0, 145.0, 145.0, 145.0], "value": 144.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 54.0, 55.0, 58.0, 56.0, 56.0, 75.0, 55.0, 52.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 57.0, 58.0, 58.0, 57.0, 57.0, 56.0, 57.0, 58.0, 57.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 73.0, 72.0, 73.0, 76.0, 74.0, 80.0, 73.0, 73.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 111.0, 116.0, 113.0, 112.0, 114.0, 113.0, 135.0, 125.0], "value": 117.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 169.0, 159.0, 161.0, 158.0, 155.0, 156.0, 154.0, 155.0, 156.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 69.0, 66.0, 69.0, 66.0, 67.0, 65.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1514.5, "alertThreshold": 2.0}]} 22:20:11 INFO - Return code: 0 22:20:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:20:11 ERROR - # TBPL SUCCESS # 22:20:11 INFO - Running post-action listener: _resource_record_post_action 22:20:11 INFO - Running post-run listener: _resource_record_post_run 22:20:12 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 688128; Write bytes: 260788224; Read time: 68; Write time: 173800 22:20:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 13930496; Read time: 0; Write time: 3156 22:20:12 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 688128; Write bytes: 246857728; Read time: 68; Write time: 170644 22:20:12 INFO - Running post-run listener: _upload_blobber_files 22:20:12 INFO - Blob upload gear active. 22:20:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:20:12 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 22:20:12 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'] 22:20:12 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 22:20:13 INFO - (blobuploader) - INFO - Open directory for files ... 22:20:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:20:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:13 INFO - (blobuploader) - INFO - Done attempting. 22:20:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:20:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:20:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:20:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:20:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:20:14 INFO - (blobuploader) - INFO - Done attempting. 22:20:14 INFO - (blobuploader) - INFO - Iteration through files over. 22:20:14 INFO - Return code: 0 22:20:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:20:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:20:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbc042a1dff24b598af7e008be764f091ab1101dc71975b5832d61c10b326c1a8cc4a98ed1e0d9f5a2508a8416e5ff6231987ef5e536f1ddad416beca0729f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89222a2f606187750f07f118b14226083830d81fba1ee79a885f2a6f44835834dc9caae26e9989b3f6e0f8e3f8ab070a8c0d048af7d259bbf42754b6a15249bc"} 22:20:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:20:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:20:14 INFO - Contents: 22:20:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbc042a1dff24b598af7e008be764f091ab1101dc71975b5832d61c10b326c1a8cc4a98ed1e0d9f5a2508a8416e5ff6231987ef5e536f1ddad416beca0729f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89222a2f606187750f07f118b14226083830d81fba1ee79a885f2a6f44835834dc9caae26e9989b3f6e0f8e3f8ab070a8c0d048af7d259bbf42754b6a15249bc"} 22:20:14 INFO - Running post-run listener: copy_logs_to_upload_dir 22:20:14 INFO - Copying logs to upload dir... 22:20:14 INFO - mkdir: /builds/slave/test/build/upload/logs 22:20:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.542952 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-05-27 22:20:14.619685) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 22:20:14.622857) ========= 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=db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432 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/fbc042a1dff24b598af7e008be764f091ab1101dc71975b5832d61c10b326c1a8cc4a98ed1e0d9f5a2508a8416e5ff6231987ef5e536f1ddad416beca0729f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89222a2f606187750f07f118b14226083830d81fba1ee79a885f2a6f44835834dc9caae26e9989b3f6e0f8e3f8ab070a8c0d048af7d259bbf42754b6a15249bc"} build_url:https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011370 build_url: 'https://queue.taskcluster.net/v1/task/JwrWW5_rQiufoczwKFTNWg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbc042a1dff24b598af7e008be764f091ab1101dc71975b5832d61c10b326c1a8cc4a98ed1e0d9f5a2508a8416e5ff6231987ef5e536f1ddad416beca0729f94", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89222a2f606187750f07f118b14226083830d81fba1ee79a885f2a6f44835834dc9caae26e9989b3f6e0f8e3f8ab070a8c0d048af7d259bbf42754b6a15249bc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 22:20:14.649508) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:20:14.649843) ========= 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=db0483c52d987ecb441a263b000001ad-1464402213.59548-717891432 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004375 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 22:20:14.732869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 22:20:14.733225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 22:20:14.733563) ========= ========= Total master_lag: 0.19 =========