builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1462936792.41 results: success (0) buildid: 20160510173021 builduid: b6a075f48b8b4a7e8410db36d3376e88 revision: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.410303) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.410861) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.411183) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.421648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.422088) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 20:19:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 86.1M=0s 2016-05-10 20:19:52 (86.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.531775) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.532134) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009558 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:52.581364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 20:19:52.581761) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 027b0144a1a6c2d62dfb1656e41597acfe6a22ef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 027b0144a1a6c2d62dfb1656e41597acfe6a22ef --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 20:19:53,083 truncating revision to first 12 chars 2016-05-10 20:19:53,083 Setting DEBUG logging. 2016-05-10 20:19:53,083 attempt 1/10 2016-05-10 20:19:53,083 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/027b0144a1a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 20:19:54,062 unpacking tar archive at: fx-team-027b0144a1a6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.234909 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 20:19:53.827351) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:53.827756) ========= script_repo_revision: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:53.828217) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:53.828555) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 20:19:53.834713) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-10 20:19:53.835047) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:19:54 INFO - MultiFileLogger online at 20160510 20:19:54 in /builds/slave/test 20:19:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:19:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:19:54 INFO - {'append_to_log': False, 20:19:54 INFO - 'base_work_dir': '/builds/slave/test', 20:19:54 INFO - 'blob_upload_branch': 'Fx-Team', 20:19:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:19:54 INFO - 'branch': 'Fx-Team', 20:19:54 INFO - 'buildbot_json_path': 'buildprops.json', 20:19:54 INFO - 'config_files': ('talos/linux_config.py',), 20:19:54 INFO - 'default_actions': ('clobber', 20:19:54 INFO - 'read-buildbot-config', 20:19:54 INFO - 'download-and-extract', 20:19:54 INFO - 'populate-webroot', 20:19:54 INFO - 'create-virtualenv', 20:19:54 INFO - 'install', 20:19:54 INFO - 'run-tests'), 20:19:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:19:54 INFO - 'download_minidump_stackwalk': True, 20:19:54 INFO - 'download_symbols': 'ondemand', 20:19:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:19:54 INFO - 'tooltool.py': '/tools/tooltool.py', 20:19:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:19:54 INFO - '/tools/misc-python/virtualenv.py')}, 20:19:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:19:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:19:54 INFO - 'installer_path': 'installer.exe', 20:19:54 INFO - 'log_level': 'info', 20:19:54 INFO - 'log_name': 'talos', 20:19:54 INFO - 'log_to_console': True, 20:19:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:19:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:19:54 INFO - 'opt_config_files': (), 20:19:54 INFO - 'pip_index': False, 20:19:54 INFO - 'sps_profile': False, 20:19:54 INFO - 'sps_profile_interval': 0, 20:19:54 INFO - 'suite': 'dromaeojs', 20:19:54 INFO - 'system_bits': '32', 20:19:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:19:54 INFO - 'title': 'talos-linux64-ix-018', 20:19:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:19:54 INFO - 'use_talos_json': True, 20:19:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:19:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:19:54 INFO - 'work_dir': 'build'} 20:19:54 INFO - ##### 20:19:54 INFO - ##### Running clobber step. 20:19:54 INFO - ##### 20:19:54 INFO - Running pre-action listener: _resource_record_pre_action 20:19:54 INFO - Running main action method: clobber 20:19:54 INFO - rmtree: /builds/slave/test/build 20:19:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:19:54 INFO - Running post-action listener: _resource_record_post_action 20:19:54 INFO - ##### 20:19:54 INFO - ##### Finished clobber step (success) 20:19:54 INFO - ##### 20:19:54 INFO - ##### 20:19:54 INFO - ##### Running read-buildbot-config step. 20:19:54 INFO - ##### 20:19:54 INFO - Running pre-action listener: _resource_record_pre_action 20:19:54 INFO - Running main action method: read_buildbot_config 20:19:54 INFO - Using buildbot properties: 20:19:54 INFO - { 20:19:54 INFO - "project": "", 20:19:54 INFO - "product": "firefox", 20:19:54 INFO - "script_repo_revision": "production", 20:19:54 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:19:54 INFO - "repository": "", 20:19:54 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:19:54 INFO - "buildid": "20160510173021", 20:19:54 INFO - "pgo_build": "True", 20:19:54 INFO - "basedir": "/builds/slave/test", 20:19:54 INFO - "buildnumber": 661, 20:19:54 INFO - "slavename": "talos-linux64-ix-018", 20:19:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:19:54 INFO - "platform": "ubuntu64_hw", 20:19:54 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:19:54 INFO - "branch": "fx-team", 20:19:54 INFO - "repo_path": "integration/fx-team", 20:19:54 INFO - "revision": "027b0144a1a6c2d62dfb1656e41597acfe6a22ef", 20:19:54 INFO - "stage_platform": "linux64-pgo", 20:19:54 INFO - "builduid": "b6a075f48b8b4a7e8410db36d3376e88", 20:19:54 INFO - "slavebuilddir": "test" 20:19:54 INFO - } 20:19:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 20:19:54 INFO - Running post-action listener: _resource_record_post_action 20:19:54 INFO - ##### 20:19:54 INFO - ##### Finished read-buildbot-config step (success) 20:19:54 INFO - ##### 20:19:54 INFO - ##### 20:19:54 INFO - ##### Running download-and-extract step. 20:19:54 INFO - ##### 20:19:54 INFO - Running pre-action listener: _resource_record_pre_action 20:19:54 INFO - Running main action method: download_and_extract 20:19:54 INFO - mkdir: /builds/slave/test/build/tests 20:19: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')]} 20:19:54 INFO - https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:19:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:19:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:19:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:19:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:19:57 INFO - Downloaded 1361 bytes. 20:19:57 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 20:19:57 INFO - Using the following test package requirements: 20:19:57 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:19:57 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:19:57 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:19:57 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'jsshell-linux-x86_64.zip'], 20:19:57 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:19:57 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 20:19:57 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:19:57 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:19:57 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:19:57 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 20:19:57 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:19:57 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:19:57 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')]} 20:19:57 INFO - https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:19:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:19:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:19:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:19:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:19:58 INFO - Downloaded 23903496 bytes. 20:19:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:19:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:19:59 INFO - Return code: 0 20:19:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:59 INFO - https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:19:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:19:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:19:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 20:19:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:20:01 INFO - Downloaded 23903496 bytes. 20:20:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:20:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:20:02 INFO - Return code: 0 20:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:02 INFO - https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:20:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:20:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:20:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 20:20:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:20:03 INFO - Downloaded 10840152 bytes. 20:20:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:20:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 20:20:03 INFO - Return code: 0 20:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:03 INFO - https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:20:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:20:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:20:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:20:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:20:06 INFO - Downloaded 58334754 bytes. 20:20:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:20:06 INFO - mkdir: /builds/slave/test/properties 20:20:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:20:06 INFO - Writing to file /builds/slave/test/properties/build_url 20:20:06 INFO - Contents: 20:20:06 INFO - build_url:https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 20:20:07 INFO - Running post-action listener: _resource_record_post_action 20:20:07 INFO - Running post-action listener: set_extra_try_arguments 20:20:07 INFO - ##### 20:20:07 INFO - ##### Finished download-and-extract step (success) 20:20:07 INFO - ##### 20:20:07 INFO - ##### 20:20:07 INFO - ##### Running populate-webroot step. 20:20:07 INFO - ##### 20:20:07 INFO - Running pre-action listener: _resource_record_pre_action 20:20:07 INFO - Running main action method: populate_webroot 20:20:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:20:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:20:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:20:07 INFO - '--tppagecycles', 20:20:07 INFO - '7'], 20:20:07 INFO - 'tests': ['tsvgm']}}, 20:20:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:20:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:20:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:20:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:20:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:20:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:20:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['damp', 'tps']}, 20:20:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'tests': ['damp', 'tps']}, 20:20:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['dromaeo_dom']}, 20:20:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:20:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['a11yr', 20:20:07 INFO - 'ts_paint', 20:20:07 INFO - 'tpaint', 20:20:07 INFO - 'sessionrestore', 20:20:07 INFO - 'sessionrestore_no_auto_restore', 20:20:07 INFO - 'tabpaint']}, 20:20:07 INFO - 'other-e10s': {'tests': ['a11yr', 20:20:07 INFO - 'ts_paint', 20:20:07 INFO - 'tpaint', 20:20:07 INFO - 'sessionrestore', 20:20:07 INFO - 'sessionrestore_no_auto_restore', 20:20:07 INFO - 'tabpaint']}, 20:20:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:20:07 INFO - 'ts_paint', 20:20:07 INFO - 'tpaint', 20:20:07 INFO - 'sessionrestore', 20:20:07 INFO - 'sessionrestore_no_auto_restore', 20:20:07 INFO - 'tabpaint']}, 20:20:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:20:07 INFO - 'ts_paint', 20:20:07 INFO - 'tpaint', 20:20:07 INFO - 'sessionrestore', 20:20:07 INFO - 'sessionrestore_no_auto_restore', 20:20:07 INFO - 'tabpaint']}, 20:20:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['a11yr', 20:20:07 INFO - 'ts_paint', 20:20:07 INFO - 'tpaint', 20:20:07 INFO - 'sessionrestore', 20:20:07 INFO - 'sessionrestore_no_auto_restore', 20:20:07 INFO - 'tabpaint']}, 20:20:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['a11yr', 20:20:07 INFO - 'ts_paint', 20:20:07 INFO - 'tpaint', 20:20:07 INFO - 'sessionrestore', 20:20:07 INFO - 'sessionrestore_no_auto_restore', 20:20:07 INFO - 'tabpaint']}, 20:20:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['tsvgx', 20:20:07 INFO - 'tsvgr_opacity', 20:20:07 INFO - 'tart', 20:20:07 INFO - 'tscrollx', 20:20:07 INFO - 'cart']}, 20:20:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:20:07 INFO - 'tsvgr_opacity', 20:20:07 INFO - 'tart', 20:20:07 INFO - 'tscrollx', 20:20:07 INFO - 'cart']}, 20:20:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'talos_options': ['--disable-e10s'], 20:20:07 INFO - 'tests': ['tp5o']}, 20:20:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'tests': ['tp5o']}, 20:20:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'talos_options': ['--disable-e10s', 20:20:07 INFO - '--xperf_path', 20:20:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:20:07 INFO - 'tests': ['tp5n']}, 20:20:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:07 INFO - 'talos_options': ['--xperf_path', 20:20:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:20:07 INFO - 'tests': ['tp5n']}}, 20:20:07 INFO - 'talos.zip': {'path': '', 20:20:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:20:07 INFO - Running post-action listener: _resource_record_post_action 20:20:07 INFO - ##### 20:20:07 INFO - ##### Finished populate-webroot step (success) 20:20:07 INFO - ##### 20:20:07 INFO - ##### 20:20:07 INFO - ##### Running create-virtualenv step. 20:20:07 INFO - ##### 20:20:07 INFO - Running pre-action listener: _resource_record_pre_action 20:20:07 INFO - Running main action method: create_virtualenv 20:20:07 INFO - Creating virtualenv /builds/slave/test/build/venv 20:20:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:20:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:20:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:20:07 INFO - Using real prefix '/usr' 20:20:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:20:08 INFO - Installing distribute.............................................................................................................................................................................................done. 20:20:10 INFO - Installing pip.................done. 20:20:10 INFO - Return code: 0 20:20:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:20:10 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')]} 20:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:10 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')]} 20:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:10 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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:10 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 20:20:10 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 20:20:10 INFO - Using env: {'DISPLAY': ':0', 20:20:10 INFO - 'HOME': '/home/cltbld', 20:20:10 INFO - 'LANG': 'en_US.UTF-8', 20:20:10 INFO - 'LANGUAGE': 'en_US:en', 20:20:10 INFO - 'LOGNAME': 'cltbld', 20:20:10 INFO - 'MAIL': '/var/mail/cltbld', 20:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:10 INFO - 'MOZ_NO_REMOTE': '1', 20:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:10 INFO - 'NO_EM_RESTART': '1', 20:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:10 INFO - 'PWD': '/builds/slave/test', 20:20:10 INFO - 'SHELL': '/bin/bash', 20:20:10 INFO - 'SHLVL': '1', 20:20:10 INFO - 'TERM': 'linux', 20:20:10 INFO - 'TMOUT': '86400', 20:20:10 INFO - 'USER': 'cltbld', 20:20:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:10 INFO - '_': '/tools/buildbot/bin/python'} 20:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:10 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 20:20:10 INFO - Cleaning up... 20:20:10 INFO - Return code: 0 20:20:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:20:10 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')]} 20:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:10 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')]} 20:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:10 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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:10 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 20:20:10 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 20:20:10 INFO - Using env: {'DISPLAY': ':0', 20:20:10 INFO - 'HOME': '/home/cltbld', 20:20:10 INFO - 'LANG': 'en_US.UTF-8', 20:20:10 INFO - 'LANGUAGE': 'en_US:en', 20:20:10 INFO - 'LOGNAME': 'cltbld', 20:20:10 INFO - 'MAIL': '/var/mail/cltbld', 20:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:10 INFO - 'MOZ_NO_REMOTE': '1', 20:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:10 INFO - 'NO_EM_RESTART': '1', 20:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:10 INFO - 'PWD': '/builds/slave/test', 20:20:10 INFO - 'SHELL': '/bin/bash', 20:20:10 INFO - 'SHLVL': '1', 20:20:10 INFO - 'TERM': 'linux', 20:20:10 INFO - 'TMOUT': '86400', 20:20:10 INFO - 'USER': 'cltbld', 20:20:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:10 INFO - '_': '/tools/buildbot/bin/python'} 20:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:10 INFO - Downloading/unpacking psutil>=0.7.1 20:20: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 20:20: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 20:20: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 20:20: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 20:20: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 20:20: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 20:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:20:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:20:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:20:12 INFO - Installing collected packages: psutil 20:20:12 INFO - Running setup.py install for psutil 20:20:12 INFO - building 'psutil._psutil_linux' extension 20:20:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:20:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:20:12 INFO - building 'psutil._psutil_posix' extension 20:20:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:20:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:20:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:20:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:20:12 INFO - Successfully installed psutil 20:20:12 INFO - Cleaning up... 20:20:12 INFO - Return code: 0 20:20:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 20:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 20:20:12 INFO - Using env: {'DISPLAY': ':0', 20:20:12 INFO - 'HOME': '/home/cltbld', 20:20:12 INFO - 'LANG': 'en_US.UTF-8', 20:20:12 INFO - 'LANGUAGE': 'en_US:en', 20:20:12 INFO - 'LOGNAME': 'cltbld', 20:20:12 INFO - 'MAIL': '/var/mail/cltbld', 20:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:12 INFO - 'MOZ_NO_REMOTE': '1', 20:20:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:12 INFO - 'NO_EM_RESTART': '1', 20:20:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:12 INFO - 'PWD': '/builds/slave/test', 20:20:12 INFO - 'SHELL': '/bin/bash', 20:20:12 INFO - 'SHLVL': '1', 20:20:12 INFO - 'TERM': 'linux', 20:20:12 INFO - 'TMOUT': '86400', 20:20:12 INFO - 'USER': 'cltbld', 20:20:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:12 INFO - '_': '/tools/buildbot/bin/python'} 20:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:20:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:20:14 INFO - Installing collected packages: mozsystemmonitor 20:20:14 INFO - Running setup.py install for mozsystemmonitor 20:20:14 INFO - Successfully installed mozsystemmonitor 20:20:14 INFO - Cleaning up... 20:20:14 INFO - Return code: 0 20:20:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:20: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')]} 20:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20: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')]} 20:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:20:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:20:14 INFO - Using env: {'DISPLAY': ':0', 20:20:14 INFO - 'HOME': '/home/cltbld', 20:20:14 INFO - 'LANG': 'en_US.UTF-8', 20:20:14 INFO - 'LANGUAGE': 'en_US:en', 20:20:14 INFO - 'LOGNAME': 'cltbld', 20:20:14 INFO - 'MAIL': '/var/mail/cltbld', 20:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:14 INFO - 'MOZ_NO_REMOTE': '1', 20:20:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:14 INFO - 'NO_EM_RESTART': '1', 20:20:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:14 INFO - 'PWD': '/builds/slave/test', 20:20:14 INFO - 'SHELL': '/bin/bash', 20:20:14 INFO - 'SHLVL': '1', 20:20:14 INFO - 'TERM': 'linux', 20:20:14 INFO - 'TMOUT': '86400', 20:20:14 INFO - 'USER': 'cltbld', 20:20:14 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:14 INFO - '_': '/tools/buildbot/bin/python'} 20:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:15 INFO - Downloading/unpacking blobuploader==1.2.4 20:20: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 20:20: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 20:20: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 20:20: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 20:20: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 20:20: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 20:20:16 INFO - Downloading blobuploader-1.2.4.tar.gz 20:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:20:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:20:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:17 INFO - Downloading docopt-0.6.1.tar.gz 20:20:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:20:17 INFO - Installing collected packages: blobuploader, requests, docopt 20:20:17 INFO - Running setup.py install for blobuploader 20:20:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:20:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:20:17 INFO - Running setup.py install for requests 20:20:18 INFO - Running setup.py install for docopt 20:20:18 INFO - Successfully installed blobuploader requests docopt 20:20:18 INFO - Cleaning up... 20:20:18 INFO - Return code: 0 20:20:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:20: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')]} 20:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20: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')]} 20:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:18 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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:18 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 20:20:18 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 20:20:18 INFO - Using env: {'DISPLAY': ':0', 20:20:18 INFO - 'HOME': '/home/cltbld', 20:20:18 INFO - 'LANG': 'en_US.UTF-8', 20:20:18 INFO - 'LANGUAGE': 'en_US:en', 20:20:18 INFO - 'LOGNAME': 'cltbld', 20:20:18 INFO - 'MAIL': '/var/mail/cltbld', 20:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:18 INFO - 'MOZ_NO_REMOTE': '1', 20:20:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:18 INFO - 'NO_EM_RESTART': '1', 20:20:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:18 INFO - 'PWD': '/builds/slave/test', 20:20:18 INFO - 'SHELL': '/bin/bash', 20:20:18 INFO - 'SHLVL': '1', 20:20:18 INFO - 'TERM': 'linux', 20:20:18 INFO - 'TMOUT': '86400', 20:20:18 INFO - 'USER': 'cltbld', 20:20:18 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:18 INFO - '_': '/tools/buildbot/bin/python'} 20:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:20:18 INFO - Running setup.py (path:/tmp/pip-VijmCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:20:18 INFO - Running setup.py (path:/tmp/pip-SvxUcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:20:18 INFO - Running setup.py (path:/tmp/pip-mcJ6U0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:20:18 INFO - Running setup.py (path:/tmp/pip-blKTsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:20:18 INFO - Running setup.py (path:/tmp/pip-JpRIga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:20:18 INFO - Running setup.py (path:/tmp/pip-7Cwymh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:20:18 INFO - Running setup.py (path:/tmp/pip-Y3QhrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:20:18 INFO - Running setup.py (path:/tmp/pip-TMc8Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:20:18 INFO - Running setup.py (path:/tmp/pip-R4vpCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:20:18 INFO - Running setup.py (path:/tmp/pip-cbqlbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:20:19 INFO - Running setup.py (path:/tmp/pip-w6qDu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:20:19 INFO - Running setup.py (path:/tmp/pip-UvQAUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:20:19 INFO - Running setup.py (path:/tmp/pip-rTmc07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:20:19 INFO - Running setup.py (path:/tmp/pip-SaeR4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:19 INFO - Running setup.py (path:/tmp/pip-Vwpwxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:20:19 INFO - Running setup.py (path:/tmp/pip-v0KEB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:20:19 INFO - Running setup.py (path:/tmp/pip-HaFXqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:20:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:20:19 INFO - Running setup.py install for manifestparser 20:20:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:20:19 INFO - Running setup.py install for mozcrash 20:20:19 INFO - Running setup.py install for mozdebug 20:20:19 INFO - Running setup.py install for mozdevice 20:20:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:20:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:20:19 INFO - Running setup.py install for mozfile 20:20:19 INFO - Running setup.py install for mozhttpd 20:20:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:20:19 INFO - Running setup.py install for mozinfo 20:20:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Running setup.py install for mozInstall 20:20:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Running setup.py install for mozleak 20:20:20 INFO - Running setup.py install for mozlog 20:20:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Running setup.py install for moznetwork 20:20:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Running setup.py install for mozprocess 20:20:20 INFO - Running setup.py install for mozprofile 20:20:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Running setup.py install for mozrunner 20:20:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:20:20 INFO - Running setup.py install for mozscreenshot 20:20:20 INFO - Running setup.py install for moztest 20:20:20 INFO - Running setup.py install for mozversion 20:20:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:20:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:20:21 INFO - Cleaning up... 20:20:21 INFO - Return code: 0 20:20:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:21 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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:21 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 20:20:21 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 20:20:21 INFO - Using env: {'DISPLAY': ':0', 20:20:21 INFO - 'HOME': '/home/cltbld', 20:20:21 INFO - 'LANG': 'en_US.UTF-8', 20:20:21 INFO - 'LANGUAGE': 'en_US:en', 20:20:21 INFO - 'LOGNAME': 'cltbld', 20:20:21 INFO - 'MAIL': '/var/mail/cltbld', 20:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:21 INFO - 'MOZ_NO_REMOTE': '1', 20:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:21 INFO - 'NO_EM_RESTART': '1', 20:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:21 INFO - 'PWD': '/builds/slave/test', 20:20:21 INFO - 'SHELL': '/bin/bash', 20:20:21 INFO - 'SHLVL': '1', 20:20:21 INFO - 'TERM': 'linux', 20:20:21 INFO - 'TMOUT': '86400', 20:20:21 INFO - 'USER': 'cltbld', 20:20:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:21 INFO - '_': '/tools/buildbot/bin/python'} 20:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:20:21 INFO - Running setup.py (path:/tmp/pip-8t9NZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:20:21 INFO - Running setup.py (path:/tmp/pip-XVS37o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:20:21 INFO - Running setup.py (path:/tmp/pip-qMubA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:20:21 INFO - Running setup.py (path:/tmp/pip-gZx0xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:20:21 INFO - Running setup.py (path:/tmp/pip-zHmpfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:20:21 INFO - Running setup.py (path:/tmp/pip-KRSX7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:20:21 INFO - Running setup.py (path:/tmp/pip-EE9p3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:20:21 INFO - Running setup.py (path:/tmp/pip-BBrAOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:20:21 INFO - Running setup.py (path:/tmp/pip-5QoAza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:20:21 INFO - Running setup.py (path:/tmp/pip-Kz0TyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:20:21 INFO - Running setup.py (path:/tmp/pip-UWe4nA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:20:21 INFO - Running setup.py (path:/tmp/pip-qvDlcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:20:21 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)) 20:20:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:20:21 INFO - Running setup.py (path:/tmp/pip-IjlZxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:20:22 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)) 20:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:20:22 INFO - Running setup.py (path:/tmp/pip-TzooBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:20:22 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)) 20:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:22 INFO - Running setup.py (path:/tmp/pip-Py0vVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:22 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)) 20:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:20:22 INFO - Running setup.py (path:/tmp/pip-jUDKOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:20:22 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)) 20:20:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:20:22 INFO - Running setup.py (path:/tmp/pip-tuS91A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:20:22 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)) 20:20:22 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)) 20:20:22 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)) 20:20:22 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)) 20:20:22 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)) 20:20:22 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)) 20:20:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:20: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 20:20: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 20:20: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 20:20: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 20:20: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 20:20: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 20:20:24 INFO - Downloading blessings-1.6.tar.gz 20:20:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:20:24 INFO - Installing collected packages: blessings 20:20:24 INFO - Running setup.py install for blessings 20:20:24 INFO - Successfully installed blessings 20:20:24 INFO - Cleaning up... 20:20:24 INFO - Return code: 0 20:20:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:20:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:20:24 INFO - Reading from file tmpfile_stdout 20:20:24 INFO - Current package versions: 20:20:24 INFO - argparse == 1.2.1 20:20:24 INFO - blessings == 1.6 20:20:24 INFO - blobuploader == 1.2.4 20:20:24 INFO - docopt == 0.6.1 20:20:24 INFO - manifestparser == 1.1 20:20:24 INFO - mozInstall == 1.12 20:20:24 INFO - mozcrash == 0.17 20:20:24 INFO - mozdebug == 0.1 20:20:24 INFO - mozdevice == 0.48 20:20:24 INFO - mozfile == 1.2 20:20:24 INFO - mozhttpd == 0.7 20:20:24 INFO - mozinfo == 0.9 20:20:24 INFO - mozleak == 0.1 20:20:24 INFO - mozlog == 3.1 20:20:24 INFO - moznetwork == 0.27 20:20:24 INFO - mozprocess == 0.22 20:20:24 INFO - mozprofile == 0.28 20:20:24 INFO - mozrunner == 6.11 20:20:24 INFO - mozscreenshot == 0.1 20:20:24 INFO - mozsystemmonitor == 0.0 20:20:24 INFO - moztest == 0.7 20:20:24 INFO - mozversion == 1.4 20:20:24 INFO - psutil == 3.1.1 20:20:24 INFO - requests == 1.2.3 20:20:24 INFO - wsgiref == 0.1.2 20:20:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:24 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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:24 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 20:20:24 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 20:20:24 INFO - Using env: {'DISPLAY': ':0', 20:20:24 INFO - 'HOME': '/home/cltbld', 20:20:24 INFO - 'LANG': 'en_US.UTF-8', 20:20:24 INFO - 'LANGUAGE': 'en_US:en', 20:20:24 INFO - 'LOGNAME': 'cltbld', 20:20:24 INFO - 'MAIL': '/var/mail/cltbld', 20:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:24 INFO - 'MOZ_NO_REMOTE': '1', 20:20:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:24 INFO - 'NO_EM_RESTART': '1', 20:20:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:24 INFO - 'PWD': '/builds/slave/test', 20:20:24 INFO - 'SHELL': '/bin/bash', 20:20:24 INFO - 'SHLVL': '1', 20:20:24 INFO - 'TERM': 'linux', 20:20:24 INFO - 'TMOUT': '86400', 20:20:24 INFO - 'USER': 'cltbld', 20:20:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:24 INFO - '_': '/tools/buildbot/bin/python'} 20:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 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)) 20:20:24 INFO - Cleaning up... 20:20:24 INFO - Return code: 0 20:20:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:24 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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:24 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 20:20:24 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 20:20:24 INFO - Using env: {'DISPLAY': ':0', 20:20:24 INFO - 'HOME': '/home/cltbld', 20:20:24 INFO - 'LANG': 'en_US.UTF-8', 20:20:24 INFO - 'LANGUAGE': 'en_US:en', 20:20:24 INFO - 'LOGNAME': 'cltbld', 20:20:24 INFO - 'MAIL': '/var/mail/cltbld', 20:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:24 INFO - 'MOZ_NO_REMOTE': '1', 20:20:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:24 INFO - 'NO_EM_RESTART': '1', 20:20:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:24 INFO - 'PWD': '/builds/slave/test', 20:20:24 INFO - 'SHELL': '/bin/bash', 20:20:24 INFO - 'SHLVL': '1', 20:20:24 INFO - 'TERM': 'linux', 20:20:24 INFO - 'TMOUT': '86400', 20:20:24 INFO - 'USER': 'cltbld', 20:20:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:24 INFO - '_': '/tools/buildbot/bin/python'} 20:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:24 INFO - Downloading/unpacking jsonschema 20:20:24 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 20:20:24 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 20:20:24 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 20:20:24 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 20:20:24 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 20:20:24 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 20:20:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:20:26 INFO - Downloading/unpacking functools32 (from jsonschema) 20:20:26 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 20:20:26 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 20:20:26 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 20:20:26 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 20:20:26 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 20:20:26 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 20:20:26 INFO - Downloading functools32-3.2.3-2.tar.gz 20:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:20:27 INFO - warning: no files found matching '*.txt' 20:20:27 INFO - no previously-included directories found matching 'build' 20:20:27 INFO - no previously-included directories found matching 'dist' 20:20:27 INFO - no previously-included directories found matching '.git*' 20:20:27 INFO - Installing collected packages: jsonschema, functools32 20:20:27 INFO - Running setup.py install for functools32 20:20:27 INFO - warning: no files found matching '*.txt' 20:20:27 INFO - no previously-included directories found matching 'build' 20:20:27 INFO - no previously-included directories found matching 'dist' 20:20:27 INFO - no previously-included directories found matching '.git*' 20:20:27 INFO - Successfully installed jsonschema functools32 20:20:27 INFO - Cleaning up... 20:20:27 INFO - Return code: 0 20:20:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:20:27 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')]} 20:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:27 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')]} 20:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:27 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 0x7f8a3e646f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a8e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b59770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', '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 20:20:27 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 20:20:27 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 20:20:27 INFO - Using env: {'DISPLAY': ':0', 20:20:27 INFO - 'HOME': '/home/cltbld', 20:20:27 INFO - 'LANG': 'en_US.UTF-8', 20:20:27 INFO - 'LANGUAGE': 'en_US:en', 20:20:27 INFO - 'LOGNAME': 'cltbld', 20:20:27 INFO - 'MAIL': '/var/mail/cltbld', 20:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:27 INFO - 'MOZ_NO_REMOTE': '1', 20:20:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:27 INFO - 'NO_EM_RESTART': '1', 20:20:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:27 INFO - 'PWD': '/builds/slave/test', 20:20:27 INFO - 'SHELL': '/bin/bash', 20:20:27 INFO - 'SHLVL': '1', 20:20:27 INFO - 'TERM': 'linux', 20:20:27 INFO - 'TMOUT': '86400', 20:20:27 INFO - 'USER': 'cltbld', 20:20:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:27 INFO - '_': '/tools/buildbot/bin/python'} 20:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:27 INFO - Downloading/unpacking flake8 20:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:20:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:20:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 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 20:20:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:20:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:20:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:20:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:20:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:20:29 INFO - no previously-included directories found matching 'docs/_build' 20:20:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:20:30 INFO - Running setup.py install for pep8 20:20:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:20:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:20:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:20:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:20:30 INFO - no previously-included directories found matching 'docs/_build' 20:20:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:20:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:20:30 INFO - Cleaning up... 20:20:30 INFO - Return code: 0 20:20:30 INFO - Running post-action listener: _resource_record_post_action 20:20:30 INFO - Running post-action listener: _start_resource_monitoring 20:20:30 INFO - Starting resource monitoring. 20:20:30 INFO - ##### 20:20:30 INFO - ##### Finished create-virtualenv step (success) 20:20:30 INFO - ##### 20:20:30 INFO - ##### 20:20:30 INFO - ##### Running install step. 20:20:30 INFO - ##### 20:20:30 INFO - Running pre-action listener: _resource_record_pre_action 20:20:30 INFO - Running main action method: install 20:20:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:20:30 INFO - Reading from file tmpfile_stdout 20:20:30 INFO - Detecting whether we're running mozinstall >=1.0... 20:20:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:20:30 INFO - Reading from file tmpfile_stdout 20:20:30 INFO - Output received: 20:20:30 INFO - Usage: mozinstall [options] installer 20:20:30 INFO - Options: 20:20:30 INFO - -h, --help show this help message and exit 20:20:30 INFO - -d DEST, --destination=DEST 20:20:30 INFO - Directory to install application into. [default: 20:20:30 INFO - "/builds/slave/test"] 20:20:30 INFO - --app=APP Application being installed. [default: firefox] 20:20:30 INFO - mkdir: /builds/slave/test/build/application 20:20:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:20:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:20:47 INFO - Reading from file tmpfile_stdout 20:20:47 INFO - Output received: 20:20:47 INFO - /builds/slave/test/build/application/firefox/firefox 20:20:47 INFO - Running post-action listener: _resource_record_post_action 20:20:47 INFO - ##### 20:20:47 INFO - ##### Finished install step (success) 20:20:47 INFO - ##### 20:20:47 INFO - ##### 20:20:47 INFO - ##### Running run-tests step. 20:20:47 INFO - ##### 20:20:47 INFO - Running pre-action listener: _resource_record_pre_action 20:20:47 INFO - Running main action method: run_tests 20:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:20:47 INFO - Python 2.7.3 20:20:47 INFO - Return code: 0 20:20:47 INFO - grabbing minidump binary from tooltool 20:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:47 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be89c0>, '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 0x2be98c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be9d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:20:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:20:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:20:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:20:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:20:47 INFO - Return code: 0 20:20:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:20:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:20:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:20:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:20:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:20:48 INFO - Return code: 0 20:20:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:20:48 INFO - Using env: {'DISPLAY': ':0', 20:20:48 INFO - 'HOME': '/home/cltbld', 20:20:48 INFO - 'LANG': 'en_US.UTF-8', 20:20:48 INFO - 'LANGUAGE': 'en_US:en', 20:20:48 INFO - 'LOGNAME': 'cltbld', 20:20:48 INFO - 'MAIL': '/var/mail/cltbld', 20:20:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:20:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:48 INFO - 'MOZ_NO_REMOTE': '1', 20:20:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:48 INFO - 'NO_EM_RESTART': '1', 20:20:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:48 INFO - 'PWD': '/builds/slave/test', 20:20:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:20:48 INFO - 'SHELL': '/bin/bash', 20:20:48 INFO - 'SHLVL': '1', 20:20:48 INFO - 'TERM': 'linux', 20:20:48 INFO - 'TMOUT': '86400', 20:20:48 INFO - 'USER': 'cltbld', 20:20:48 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492', 20:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:48 INFO - '_': '/tools/buildbot/bin/python'} 20:20:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:20:48 INFO - mozversion application_buildid: 20160510173021 20:20:48 INFO - mozversion application_changeset: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef 20:20:48 INFO - mozversion application_display_name: Nightly 20:20:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:20:48 INFO - mozversion application_name: Firefox 20:20:48 INFO - mozversion application_remotingname: firefox 20:20:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:20:48 INFO - mozversion application_vendor: Mozilla 20:20:48 INFO - mozversion application_version: 49.0a1 20:20:48 INFO - mozversion platform_buildid: 20160510173021 20:20:48 INFO - mozversion platform_changeset: 027b0144a1a6c2d62dfb1656e41597acfe6a22ef 20:20:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:20:48 INFO - mozversion platform_version: 49.0a1 20:20:48 INFO - using testdate: 1462936848 20:20:48 INFO - actual date: 1462936848 20:20:48 INFO - starting webserver on 'localhost:41615' 20:20:48 INFO - SUITE-START | Running 2 tests 20:20:48 INFO - TEST-START | dromaeo_css 20:20:48 INFO - operating with platform_type : linux_ 20:20:48 INFO - Initialising browser for dromaeo_css test... 20:20:48 INFO - TEST-INFO | started process 3552 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8nkdXN/profile http://localhost:41615/getInfo.html) 20:20:51 INFO - PROCESS | 3552 | __metrics Screen width/height:1600/1200 20:20:51 INFO - PROCESS | 3552 | colorDepth:24 20:20:51 INFO - PROCESS | 3552 | Browser inner width/height: 1024/697 20:20:51 INFO - PROCESS | 3552 | __metrics 20:20:52 ERROR - PROCESS | 3552 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:20:53 INFO - TEST-INFO | 3552: exit 0 20:20:53 INFO - Browser initialized. 20:20:53 INFO - Running cycle 1/1 for dromaeo_css test... 20:20:53 INFO - TEST-INFO | started process 3630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8nkdXN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:21:04 INFO - PROCESS | 3630 | RSS: Main: 151072768 20:21:04 INFO - PROCESS | 3630 | 20:21:56 INFO - PROCESS | 3630 | 1462936916261 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] 20:24:04 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:41615/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41615/tests/dromaeo/cssquery-ext.html) 20:24:05 INFO - PROCESS | 3630 | RSS: Main: 258895872 20:24:05 INFO - PROCESS | 3630 | 20:27:07 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:41615/tests/dromaeo/cssquery-ext.html (next: http://localhost:41615/tests/dromaeo/cssquery-jquery.html) 20:27:07 INFO - PROCESS | 3630 | RSS: Main: 233017344 20:27:07 INFO - PROCESS | 3630 | 20:30:09 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:41615/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41615/tests/dromaeo/cssquery-mootools.html) 20:30:10 INFO - PROCESS | 3630 | RSS: Main: 238632960 20:30:10 INFO - PROCESS | 3630 | 20:33:12 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:41615/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41615/tests/dromaeo/cssquery-prototype.html) 20:33:12 INFO - PROCESS | 3630 | RSS: Main: 232656896 20:33:12 INFO - PROCESS | 3630 | 20:36:15 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:41615/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41615/tests/dromaeo/cssquery-yui.html) 20:36:16 INFO - PROCESS | 3630 | RSS: Main: 214794240 20:36:16 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | Cycle 1(1): loaded http://localhost:41615/tests/dromaeo/cssquery-yui.html (next: http://localhost:41615/tests/dromaeo/cssquery-dojo.html) 20:39:18 INFO - PROCESS | 3630 | RSS: Main: 332718080 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | __start_tp_report 20:39:18 INFO - PROCESS | 3630 | _x_x_mozilla_page_load 20:39:18 INFO - PROCESS | 3630 | _x_x_mozilla_page_load_details 20:39:18 INFO - PROCESS | 3630 | |i|pagename|runs| 20:39:18 INFO - PROCESS | 3630 | |0;dojo.html;3173.539836723675;3187.840607969601;3215.581974343336;3225;3256.9837150814255;5295.285136506571;5398.757055932482;5411.161270003146;5471.452854714526;5485.972570137149;5424.186372044195;5429.53848922841;5456.290682211317;5467.343918729748;5585.218069470277;4859.246816743393;4928.334674818907;4960.156773348522;4983.875403114915;5059.190529515278;4900.754962251891;4925.13810083234;4940.283658869462;4991.450940396553;5010.323606313137;4757.191277482836;4842.539958703917;4874.098291816025;4876.146674331978;4912.6806757561;4892.437369702458;4956.529129732706;4964.726940018298;4971.527704868032;5017.272495488134;4816.54242846929;4839.467658557556;4915.926261106087;4921.261810728383;5026.547610715053;5378.569714422837;5398.541124004464;5423.078076726966;5445.455454454563;5452.945470545284;5805.535557155417;5871.324797648283;5885.882282354329;5887.381824908366;5947.316058653224;5612.186232996192;5619.129034999582;5697.971510142423;5934.97032514839;5955.106733989893;5467.6719396836315;5473.151661492475;5490.752916118784;5510.1459273812625;5517.144842549371;5960.731767070492;5988.9101663475085;5989.64062156272;5990.550708696778;5994.520438364921;5550.972245138749;5604.215409842625;5668.773249070074;5718.914216286759;5724.0841465366175;5800.245968024215;5811.3316968548315;5812.680302583314;5817.825465236049;5844.853878653068;5752.741126649311;5779.3642699303045;5791.015527360359;5793.594448388657;5826.475617194473;5608.495235428831;5631.943680563224;5655.858603534863;5698.974184646803;5753.539716822645;4378.912421751547;4384.232759267115;4384.9914519660015;4392.5607439256455;4420.115976804652;3771.641694039062;3906.648401643866;3916.549596796348;3937.215492151959;3963.4847469828737;5429.67421954684;5467.863303417446;5471.890562188734;5495.972520137454;5512.118061110284;5821.650700957872;5871.148683440877;5872.0898260769645;5874.441928016831;5875.853103672443;5384.488473595002;5409.26974858397;5490.945090549123;5513.117901135799;5517.4482551745305;4851.248056551241;4906.435759887659;4910.140725373117;4912.606991440676;4946.530079642428;6040.848978775478;6102.511799055976;6109.725062372204;6124.785632502841;6133.080037994337;5663.1788390682295;5732.59871808986;5760.654360738369;5893.057110862242;5916.822495325147;6009.819705408845;6015.789447369321;6038.84902877414;6050.576459647782;6082.60463069899;5695.829125126253;5725.713714314351;5727.083666613322;5747.051736463437;5751.022326204639;5634.85912852182;5793;5813.244278243801;5830;5835.824925252249;5735.684537350319;5735.827925162252;5747.9137812932;5762.135679647919;5765.308163020464;5660.066089095253;5701.743421545957;5717.028105222205;5723.141528770718;5726.427357264407;5422.9186562202385;5528.308961379827;5551.111822108443;5582.944170558242;5599.524040456607;5716.742746576331;5743.712814359182;5747.5401967841635;5748.48263656273;5761.740721667451;5455.427180146027;5504.119340905436;5550.583706221969;5551.528120109674;5585.581081418829 20:39:18 INFO - PROCESS | 3630 | |1;ext.html;563.3211979564625;599.200067909341;605.8969975104231;611.0193140010281;617.9011358182689;30415.087547373587;32475.51286730701;32692.83653581729;32801.5079773803;42851.78574107125;39876;44101;44292.3356149658;44302.55697443025;44377.7781111094;8828.073052329493;8946.284297256203;8963.41737787045;8978.326625503081;9009.639614415415;8779.604917778715;8862.512561809099;8869.37914345996;8878.378513504058;8883.244924181452;8896;8912.821743565124;8922.643094276254;8928.642854285821;8961.238294744955;8872;8918.152775486318;8929.33030022751;8935.285177185842;8945.955270223672;8731.432456890281;8786.472811631258;8787.428817126865;8801.20789128975;8805.955970220108;40988;41261.587384126375;41355;41364.58635413668;41369.793151034355;3491.6682915123024;3627.927441451156;3640.872569460056;3825.5600605930395;4020.7587544747116;30797.538036929465;31171.220719481968;31435.214119647193;31575.684243157502;31773.682263177303;4256.638185754215;4259.403683484315;4384.123175364908;4397.450318710161;4451.955480445219;207342.9999999985;215086;217844;218289;218519;54514.72742636262;54919.45080549143;55018.449815502136;55187;55311.44688553063;53872.73063634657;58319.70840145857;58560.801487791745;58873;59389.40610593839;14611.926940365443;14720.705585888436;14726.263686815617;14727.779083313759;14827.851721482863;11090;11166.776664466664;11208.775824483628;11214.271072380268;11218.326900386002;14558.563243102724;16396.098214598074;16799.2440340185;16893.662126757397;16966.660666786596;6265.185525881606;6266.24805023391;6283.183186185766;6314.242290925026;6318.62088274705;953.4517652349926;1053.6786280184617;1060.3002018667642;1113.92506231487;1117.446863802423;31091.067267981998;31271.687283126877;31657.683423165934;31688.524672129937;31721.524177137362;1097.4293367448881;1127.1208457403238;1131.4908291268962;1138.4478527913955;1165.9650210493699;16420.425285114965;16602.75095873563;16705.749413758804;16837.073960932266;16884.07137607419;445947;448322;448482;448632;453226;53788.462115378345;54052.18921716177;54052.729736351066;54161.72919135458;54283.72858135763;1295.3070107492447;1344.5764584156154;1359.0690377091723;1389.340063469864;1408.3099281352268;29856.552151717307;31525.684743152276;32017.519737203496;32357.51463727999;32898.67101329052;46547.301790473844;46605.3009204869;47087.764561178345;47098.76450617725;47227.05545888994;314278;321042;321274;322304;322781;34547;44642.55357446514;45431;45724.771376142904;49521.50478495169;43827.342589861815;44198.55801442073;44846;47086;48101.278480823516;39696;40585.391219132325;40982.79508602438;41236.58763412447;41837.58162418459;39667.206655866146;40904.523115535514;41349.586504134575;41672.791636042835;41924.37113443362;39965.80444542191;40069.1986160281;42666;43062.35406468968;43072.35391469193;34004.65995340015;35972.64027359798;36196.4570531437;36518;36705.44941825928;27750.86124569445;27830.44339113247;28186.2953426166;28384.432311353245;28440 20:39:18 INFO - PROCESS | 3630 | |2;jquery.html;374.94188400797884;380.86669665617035;380.9276237514872;386.9709771767118;396.3856023164097;17272.395466158687;19296.131674074666;19846.7022994655;19946;20063.09716062777;18549.165287562057;19016.858988460666;19018.239270429167;19149.138288777005;20109.29617463389;6300.243970723508;6599.93400065998;6636.634985075819;6643.534952553323;7057;6954.895676564855;7074.080369551951;7103.644817759117;7128.465365097612;7521.661525231378;7461.3284804367595;7488.063992001;7502.662380192877;7507.286807753255;7508.286712762306;7110.2889711029;7389.7413590524075;7408.666610002563;7412.925125856747;7413.703451861919;6844.794656160323;7126.750563730297;7129;7191.316824901626;7217.494775365726;24954.37614059663;25221.873890630613;25864.353391165183;25883.352916177057;25890;2389.2222392888866;2393.8563686178886;2532.632768248612;2688.40855011899;2803.6074949507083;14539.636509087357;15471.42137323082;15849.762253566205;16303.755443668355;16377.672446551001;2019.1923230707835;2097.353577441716;2140.2509121807243;2160.4274867160216;2170.4031391367344;95582.52208738912;96141.51929240309;97497.51251243698;97537;98920.0107998918;17542.298308067533;17794.644107117787;17862.642747144986;17990.190441430168;18099.54751131206;31354.372912542076;31644.208894777355;31690.841545792122;31721.682783171873;31769.36461270808;9294.953525232331;9379.718608441757;9411.10594493522;9501.334906556474;9521.809563808685;7685.346745526693;7697.23027697237;7753.728619498291;7834.921650783419;7895.368370530344;8737.126287371211;9011.188992990532;9062.909370906338;9078.137576930181;9113.77215569616;3516.8593256269974;3564.6078931317534;3606.657367550131;3683.5395575553057;3703.4629978652943;965.7971825916495;1005.5676059294427;1072.871255449351;1086.9130469562417;1100.4222783038808;18683.626327473645;19243.134058967382;19476.513087172934;19564;19642.60714785696;904.7059705595708;973.5278389980995;1014.8934361892043;1036.8807587127426;1081.416035340907;10150.340227885164;10711.544080866488;10727.195460340507;10736.30214036085;10798;468722;480932;486959;490149;490415;55944.88110237772;57649.71175144181;65169;75770;88989.55505222562;1081.1296905990596;1091.2797553614735;1131.9490622921824;1147.3574798113084;1155.295269885386;16245.756313655542;16362.509124726277;16447.84865059482;16524.42164524284;16651.83437159387;37967.240655186186;38228.42657360086;39868.401973969834;39919.401208981304;41276.793616031726;52710;53611.73194134004;53762;53893.19160212522;54928;7513;7773.961130194313;8022.638981245741;8257.587120643824;8380.24577788002;12733.172343797623;13185.53850615224;13332.466701331838;13439.596812095653;13605.86394136046;11249.21255512139;11958.222715523463;12024.098192635412;12028.398580071136;12147.878521215029;10891.346519208873;11841;11896.821547676618;11980.400979951142;12189.08581856382;12343;12354.876451235372;12404.689882753004;12412.75174496487;12910.870891290966;8494.320454363513;8619.741407757778;8654.783630409038;8743.606537705946;8972.730818075468;11029.779404411707;11637.418129093681;11930.701732456411;12012.459439325427;12134.332611706088 20:39:18 INFO - PROCESS | 3630 | |3;mootools.html;1358.606004258765;1510.3580978084312;1515.7650564162552;1536.5313579358299;1542.9970519162543;8315.667373305068;8687.218150366465;8732.91267087329;9024.95487522563;9068.546572671374;8764.649414023432;8974.730758077252;8988;8996.460212387245;9002.819943601124;1948.1915005272795;2217.3347995601257;2258.8531745436576;2297.5634629420356;2299.781520755534;2246.955060898781;2268.965965510519;2275.72691277047;2277.089164334271;2278.4759505313787;2180.7165068541067;2213.822894168463;2216.4569680428317;2216.4902072523328;2217.423469897822;1906.4090132059036;2193.6709493576013;2242.55148970205;2279.111146652815;2291.8166546676225;2205.503761653631;2205.669149627569;2212.822974162063;2216.656418255173;2253;8932.598033088461;9009.963854156791;9016.684416045407;9020;9033.006369299372;4993.101241776479;5198.9480105198845;5219.164933610642;5257.158854583248;5258.8685282867855;3279.6392396836327;3311.7516186286125;3356.345512625039;3462.186386199241;3484.0593039879345;1050.76883085721;1077.8221593437074;1078.1805827571102;1083.6478144602956;1097.286763603656;12109.939450302692;12207.511699532008;12221.877781222163;12256.509739610405;12262.509499620093;4635.466921304026;4641.698289611165;4701.835435759732;4750.572448479654;4757.548032936866;9852;9858.654947076819;9864.112229899343;9864.802703945881;9872;4575.9084818303445;5234.345706786652;5253.789848406097;5281.287026251483;5297.390800058042;4672.486026537078;4813.975930120327;4825.348577942003;4846.612271018381;4869.70781753096;3879.3792993120965;3903.394973779069;3911.530616326059;3962.5244970603712;4017.091881356391;1210.4129497193853;1275.4770544076885;1277.6741930807584;1288.8324517812812;1293.2628401811066;988.2143695761887;990.6928852055886;1007.7682133109425;1022.2282176956498;1034.7723500829804;5931.703414829241;5934.109883517507;5942.465178133903;6034.616017512475;6214.40963108504;451.31174958189024;452.92300308947557;463.1200718634621;466.6989791584477;468.0335108001965;4271.871843844628;4298.699091063658;4302.634276086567;4305.095929854766;4331.523532411432;10402.375857448576;10934;10938.56245750161;10947.616833410792;11027.503762330714;10757.977992090739;10759.784804304027;10844;10856.511456984455;10926.453677316102;7520.6239688015385;7875.527468351917;7916.604169791487;8072.556009419421;8221;5893.705314734332;6135.53983451234;6195.380461953769;6211.378862113753;6211.875762484816;9758.072983066597;9965.302428829913;10167.288289819642;10170.237232207763;10361.792764144524;10209.183265338903;10350.896491035295;10358.792824143324;10359.741006474898;10394.844077338998;6993.650317484004;7354.926450735424;7557.35762460175;7906.486078404885;8061.395395345256;8632.136786321318;8662.393632445921;8696.695615653422;8735.694250701195;8798.164174403422;7236.6019868907715;7601.163871973968;7729.6521656524465;7735.110462296683;7993.240642138988;7550.924490755242;7560.886586701094;7577.772666820004;7729.6521656524465;7773;6729.798106056826;6737.2252190996705;6771.424428923597;6784.185897692309;6800.455963523005;4977.925331120108;5115.3094332266155;5135.435102138837;5204.323437953042;5329.040772660958;5132.538071573576;5179.274901513716;5286.154215325683;5288.180332048539;5358.356997160366 20:39:18 INFO - PROCESS | 3630 | |4;prototype.html;313.1310613048791;319.24976305681645;325.2486755594576;325.3801508127019;329.5370005142775;4801.399825021872;4931.975340123303;5023.095842748304;5088.821891233808;5168.173092305232;5062.949370506294;5098.037255656571;5106.540411362977;5124.769385377657;5129.102407078765;3957.4261732048835;3971.1462035662325;3986.501687289074;3992.700547458945;4008.478897743304;3811.4282857571447;3858.3440815061367;3960.2871483133026;4029.6373326400617;4032.931273212601;3966.742161759491;3986.2027594481074;4004.3192657248337;4005.1589166275116;4006.5392479864895;3966.147278335143;3974.6224108709625;3979.3831956046715;3983.701222408331;4000.9799951000205;3510.4018381159262;3536.328097661436;3541.1678255609913;3581.6955558777536;3581.94627080594;4788.616910647174;4859.198232291667;4867.006790823618;4887.165444042447;4891.065360783537;4508.977455112737;4531.977340113312;4541.772911354452;4554.157480866017;4554.38515800366;5212.791488340462;5231.136862417697;5234.089557311592;5273.707941554306;5279.498447647467;3665.5601327840563;3872.457855900176;3872.9031774205587;3873.7532231305777;3874.767513949144;5257.553107985826;5338.439463856278;5438.308037521413;5506.311711036121;5511.311086114236;3805.6194380561724;3849.2101172954713;3862.2661694278;3873.0123818426123;3873.0898238913833;3888.494495715539;3902.2195560887935;3923.352646813244;3928.9607103929166;3930.744501607386;4429.313456414261;4454.866354009385;4455.799489023002;4457.710248833815;4488.977555112268;5061.367329083864;5272.6309158358545;5291.1798671206025;5313.44208858072;5317.30874986257;1318.637374721959;1342.5837990223063;1343.3081962789172;1343.899207559437;1347.6294019144814;829.5810615639184;857.2756021162108;876.2114097312342;877.793034577456;879.4898958603994;4516.548345165457;4540.772961351985;4561.129936725993;4564.338170965191;4569.177548041318;4741.2414013758325;4743.95256047442;4750.881227969259;4760.785764640599;4775.976120119447;4453.532379100147;4464.955350446519;4488.349189367524;4490.236659767847;4503.256962601135;2686.6507354043847;2867.197184788221;2888.5378339465583;2890.638670166229;2934.5891575179494;5613.831585052455;5632.5212356950115;5638.23883775685;5664.24408433387;5668.206451096849;5382.4886635769535;5417.052015897202;5571.164325351229;5619.971900140555;5624.128260119688;5605.32736071674;5613.214150019082;5620.831375058755;5655.123455864347;5669.886602267931;5455.9454405455435;5463.344398672185;5467.699276539669;5486.862828429321;5495.53287970527;5039.6220283479615;5306.469353064663;5310.362756469249;5338.2526446298325;5392.919106213489;5339.225812257278;5489.58828087887;5567.2762540871045;5588.16177573351;5663.575231857545;3812.6759225465034;3877.0501227199516;3880.767153970771;3892.6885849131436;3913.5890731473924;5436.972815135899;5446.291982042309;5459;5459.508644222039;5465.37148227943;5193.662411943212;5248.186531087687;5274.12976858814;5275.577953763766;5320.973395133;5244.842654720364;5250.687328167958;5252.5535329497425;5260.105782017143;5273.101683393898;4984.800607975641;5161.509656582619;5193.818216362409;5225.869353266046;5239.973800130975;5290.206469029677;5297.629165958346;5305.389880163831;5335.599830012842;5348.893022139458;5080.974595127001;5170.8965820684125;5206.140986737298;5211.9478805211465;5214.191800270964 20:39:18 INFO - PROCESS | 3630 | |5;yui.html;749.4603885202654;761.2425636491691;791.0349373764005;820.4093053001836;848.1687945813103;13153.40809663565;13584.728305433886;13780.104293220933;13969.720605587881;13984.93007534962;13570.796438053412;13601.115927464707;13629.72740545191;13635.250061246628;13656.863431365684;679.9932000679979;789.6288744290204;822.6257053040852;822.6257053040882;835.7450977451874;826.5082276045773;828.8052307707684;832.8792325112855;834.4867906237681;838.0948575538404;871.7036207689384;874.0822136756381;874.4534665833854;874.7506960516254;877.6401675313123;715.4419552748839;867.3971589745098;871.904090550039;896.3725392225468;898.4429653614735;626.214101302862;627.6485168305763;628.779927025542;633.0567454492818;633.5248563577317;14133.15201087938;14148.858511414857;14167.858321416757;14179.078359906573;14251.287435628177;13672.589822305248;14020.929895350459;14056;14196.219207943559;14322.212278324689;11353.318800871892;11635.185537012412;11651.126165537618;11743.58897438594;11766.470508827124;3882.864099756523;3904.219156168749;4000.439938408625;4008.6191811777835;4031.9798401007806;50557.494425055644;50696.23955640668;51086.23370649443;51291.23063154056;53197.46802531964;11486.42567871603;11554.364509951865;11654.417279136009;11656.650300491;11776.175667703179;19778.70331945022;19854.404367868698;19901.805891646258;19935.302264420967;20045.699314510293;7880.290773830383;8015.637341651933;8086.5148091114725;8088.150744171895;8173.346132309401;4495.43807024122;4535.614472769786;4555.5899969002285;4570.245909424974;4583.862484125415;3215.5015972524297;3390.135515443546;3393.830308484615;3407.863685452604;3410.215650400422;539.9622026458189;679.8436359637311;710.8578284343153;710.989335159973;716.4161208614962;744.5123444144094;773.7098588029489;777.1917206105587;785.4658831994299;789.6683392974965;9759;10010.297219750626;10012.299139060196;10104;10140.898591014144;766.2643861892645;794.7258195922398;799.4563696686193;812.9878051829228;819.6188772220946;4418.6023257906945;4439.511653718088;4642.51253618372;4672;4675.228587283128;63402.36597634057;63804.68097659575;64663.353366466676;64989.350106499274;65140.34859651438;58663;58945;59183.704081479314;59258.11112833311;60083;50446.74776626093;51784;52158.739206303726;52320.215196771314;52918.20622690663;8699.782505437415;8748.081451447506;8759.60581773809;8760;8824.426412283181;33335.49996750099;33353.499697505045;33458.33083338271;33865.83067084649;34053.63785448554;37879;39556.80221598873;45076.549234508544;45461.772691136335;47599.28601071056;12470.56353027676;12555.811662824883;12655.367231638196;12828.840694675098;12960.351982400654;13908.304584770518;14034.438622454987;14207.928960355131;14416.639584010065;14892.553223403314;9751.60993560278;12651.430685618985;13128.803067953797;13669.111507751964;13727.45090196381;13062.934685326512;13331.600051998455;13398.933005334911;13507.729845403233;13906.096103753223;12442.315672638091;12544.937275313565;12618.369081545703;13011.739765204833;13111.803322949972;10738.892611073788;10822.134229261797;11050.778984420427;11111.388873611706;11301.547938082385;10994;11061.557537698724;11078.446077696244;11250;11257.380844053328 20:39:18 INFO - PROCESS | 3630 | __end_tp_report 20:39:18 INFO - PROCESS | 3630 | __start_cc_report 20:39:18 INFO - PROCESS | 3630 | _x_x_mozilla_cycle_collect,831 20:39:18 INFO - PROCESS | 3630 | __end_cc_report 20:39:18 INFO - PROCESS | 3630 | __startTimestamp1462937958866__endTimestamp 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | ------- Summary: start ------- 20:39:18 INFO - PROCESS | 3630 | Number of tests: 6 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | [#0] dojo.html Cycles:165 Average:5391.02 Median:5582.94 stddev:620.03 (11.1%) stddev-sans-first:597.03 20:39:18 INFO - PROCESS | 3630 | Values: 3173.5 3187.8 3215.6 3225.0 3257.0 5295.3 5398.8 5411.2 5471.5 5486.0 5424.2 5429.5 5456.3 5467.3 5585.2 4859.2 4928.3 4960.2 4983.9 5059.2 4900.8 4925.1 4940.3 4991.5 5010.3 4757.2 4842.5 4874.1 4876.1 4912.7 4892.4 4956.5 4964.7 4971.5 5017.3 4816.5 4839.5 4915.9 4921.3 5026.5 5378.6 5398.5 5423.1 5445.5 5452.9 5805.5 5871.3 5885.9 5887.4 5947.3 5612.2 5619.1 5698.0 5935.0 5955.1 5467.7 5473.2 5490.8 5510.1 5517.1 5960.7 5988.9 5989.6 5990.6 5994.5 5551.0 5604.2 5668.8 5718.9 5724.1 5800.2 5811.3 5812.7 5817.8 5844.9 5752.7 5779.4 5791.0 5793.6 5826.5 5608.5 5631.9 5655.9 5699.0 5753.5 4378.9 4384.2 4385.0 4392.6 4420.1 3771.6 3906.6 3916.5 3937.2 3963.5 5429.7 5467.9 5471.9 5496.0 5512.1 5821.7 5871.1 5872.1 5874.4 5875.9 5384.5 5409.3 5490.9 5513.1 5517.4 4851.2 4906.4 4910.1 4912.6 4946.5 6040.8 6102.5 6109.7 6124.8 6133.1 5663.2 5732.6 5760.7 5893.1 5916.8 6009.8 6015.8 6038.8 6050.6 6082.6 5695.8 5725.7 5727.1 5747.1 5751.0 5634.9 5793.0 5813.2 5830.0 5835.8 5735.7 5735.8 5747.9 5762.1 5765.3 5660.1 5701.7 5717.0 5723.1 5726.4 5422.9 5528.3 5551.1 5582.9 5599.5 5716.7 5743.7 5747.5 5748.5 5761.7 5455.4 5504.1 5550.6 5551.5 5585.6 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | [#1] ext.html Cycles:180 Average:50308.23 Median:41352.29 stddev:90206.84 (218.1%) stddev-sans-first:90382.58 20:39:18 INFO - PROCESS | 3630 | Values: 563.3 599.2 605.9 611.0 617.9 30415.1 32475.5 32692.8 32801.5 42851.8 39876.0 44101.0 44292.3 44302.6 44377.8 8828.1 8946.3 8963.4 8978.3 9009.6 8779.6 8862.5 8869.4 8878.4 8883.2 8896.0 8912.8 8922.6 8928.6 8961.2 8872.0 8918.2 8929.3 8935.3 8946.0 8731.4 8786.5 8787.4 8801.2 8806.0 40988.0 41261.6 41355.0 41364.6 41369.8 3491.7 3627.9 3640.9 3825.6 4020.8 30797.5 31171.2 31435.2 31575.7 31773.7 4256.6 4259.4 4384.1 4397.5 4452.0 207343.0 215086.0 217844.0 218289.0 218519.0 54514.7 54919.5 55018.4 55187.0 55311.4 53872.7 58319.7 58560.8 58873.0 59389.4 14611.9 14720.7 14726.3 14727.8 14827.9 11090.0 11166.8 11208.8 11214.3 11218.3 14558.6 16396.1 16799.2 16893.7 16966.7 6265.2 6266.2 6283.2 6314.2 6318.6 953.5 1053.7 1060.3 1113.9 1117.4 31091.1 31271.7 31657.7 31688.5 31721.5 1097.4 1127.1 1131.5 1138.4 1166.0 16420.4 16602.8 16705.7 16837.1 16884.1 445947.0 448322.0 448482.0 448632.0 453226.0 53788.5 54052.2 54052.7 54161.7 54283.7 1295.3 1344.6 1359.1 1389.3 1408.3 29856.6 31525.7 32017.5 32357.5 32898.7 46547.3 46605.3 47087.8 47098.8 47227.1 314278.0 321042.0 321274.0 322304.0 322781.0 34547.0 44642.6 45431.0 45724.8 49521.5 43827.3 44198.6 44846.0 47086.0 48101.3 39696.0 40585.4 40982.8 41236.6 41837.6 39667.2 40904.5 41349.6 41672.8 41924.4 39965.8 40069.2 42666.0 43062.4 43072.4 34004.7 35972.6 36196.5 36518.0 36705.4 27750.9 27830.4 28186.3 28384.4 28440.0 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | [#2] jquery.html Cycles:180 Average:29714.78 Median:31745.52 stddev:79430.58 (250.2%) stddev-sans-first:79622.86 20:39:18 INFO - PROCESS | 3630 | Values: 374.9 380.9 380.9 387.0 396.4 17272.4 19296.1 19846.7 19946.0 20063.1 18549.2 19016.9 19018.2 19149.1 20109.3 6300.2 6599.9 6636.6 6643.5 7057.0 6954.9 7074.1 7103.6 7128.5 7521.7 7461.3 7488.1 7502.7 7507.3 7508.3 7110.3 7389.7 7408.7 7412.9 7413.7 6844.8 7126.8 7129.0 7191.3 7217.5 24954.4 25221.9 25864.4 25883.4 25890.0 2389.2 2393.9 2532.6 2688.4 2803.6 14539.6 15471.4 15849.8 16303.8 16377.7 2019.2 2097.4 2140.3 2160.4 2170.4 95582.5 96141.5 97497.5 97537.0 98920.0 17542.3 17794.6 17862.6 17990.2 18099.5 31354.4 31644.2 31690.8 31721.7 31769.4 9295.0 9379.7 9411.1 9501.3 9521.8 7685.3 7697.2 7753.7 7834.9 7895.4 8737.1 9011.2 9062.9 9078.1 9113.8 3516.9 3564.6 3606.7 3683.5 3703.5 965.8 1005.6 1072.9 1086.9 1100.4 18683.6 19243.1 19476.5 19564.0 19642.6 904.7 973.5 1014.9 1036.9 1081.4 10150.3 10711.5 10727.2 10736.3 10798.0 468722.0 480932.0 486959.0 490149.0 490415.0 55944.9 57649.7 65169.0 75770.0 88989.6 1081.1 1091.3 1131.9 1147.4 1155.3 16245.8 16362.5 16447.8 16524.4 16651.8 37967.2 38228.4 39868.4 39919.4 41276.8 52710.0 53611.7 53762.0 53893.2 54928.0 7513.0 7774.0 8022.6 8257.6 8380.2 12733.2 13185.5 13332.5 13439.6 13605.9 11249.2 11958.2 12024.1 12028.4 12147.9 10891.3 11841.0 11896.8 11980.4 12189.1 12343.0 12354.9 12404.7 12412.8 12910.9 8494.3 8619.7 8654.8 8743.6 8972.7 11029.8 11637.4 11930.7 12012.5 12134.3 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | [#3] mootools.html Cycles:180 Average:5764.36 Median:4726.20 stddev:3314.44 (70.1%) stddev-sans-first:3307.20 20:39:18 INFO - PROCESS | 3630 | Values: 1358.6 1510.4 1515.8 1536.5 1543.0 8315.7 8687.2 8732.9 9025.0 9068.5 8764.6 8974.7 8988.0 8996.5 9002.8 1948.2 2217.3 2258.9 2297.6 2299.8 2247.0 2269.0 2275.7 2277.1 2278.5 2180.7 2213.8 2216.5 2216.5 2217.4 1906.4 2193.7 2242.6 2279.1 2291.8 2205.5 2205.7 2212.8 2216.7 2253.0 8932.6 9010.0 9016.7 9020.0 9033.0 4993.1 5198.9 5219.2 5257.2 5258.9 3279.6 3311.8 3356.3 3462.2 3484.1 1050.8 1077.8 1078.2 1083.6 1097.3 12109.9 12207.5 12221.9 12256.5 12262.5 4635.5 4641.7 4701.8 4750.6 4757.5 9852.0 9858.7 9864.1 9864.8 9872.0 4575.9 5234.3 5253.8 5281.3 5297.4 4672.5 4814.0 4825.3 4846.6 4869.7 3879.4 3903.4 3911.5 3962.5 4017.1 1210.4 1275.5 1277.7 1288.8 1293.3 988.2 990.7 1007.8 1022.2 1034.8 5931.7 5934.1 5942.5 6034.6 6214.4 451.3 452.9 463.1 466.7 468.0 4271.9 4298.7 4302.6 4305.1 4331.5 10402.4 10934.0 10938.6 10947.6 11027.5 10758.0 10759.8 10844.0 10856.5 10926.5 7520.6 7875.5 7916.6 8072.6 8221.0 5893.7 6135.5 6195.4 6211.4 6211.9 9758.1 9965.3 10167.3 10170.2 10361.8 10209.2 10350.9 10358.8 10359.7 10394.8 6993.7 7354.9 7557.4 7906.5 8061.4 8632.1 8662.4 8696.7 8735.7 8798.2 7236.6 7601.2 7729.7 7735.1 7993.2 7550.9 7560.9 7577.8 7729.7 7773.0 6729.8 6737.2 6771.4 6784.2 6800.5 4977.9 5115.3 5135.4 5204.3 5329.0 5132.5 5179.3 5286.2 5288.2 5358.4 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | [#4] prototype.html Cycles:180 Average:4407.24 Median:4911.52 stddev:1288.43 (26.2%) stddev-sans-first:1254.86 20:39:18 INFO - PROCESS | 3630 | Values: 313.1 319.2 325.2 325.4 329.5 4801.4 4932.0 5023.1 5088.8 5168.2 5062.9 5098.0 5106.5 5124.8 5129.1 3957.4 3971.1 3986.5 3992.7 4008.5 3811.4 3858.3 3960.3 4029.6 4032.9 3966.7 3986.2 4004.3 4005.2 4006.5 3966.1 3974.6 3979.4 3983.7 4001.0 3510.4 3536.3 3541.2 3581.7 3581.9 4788.6 4859.2 4867.0 4887.2 4891.1 4509.0 4532.0 4541.8 4554.2 4554.4 5212.8 5231.1 5234.1 5273.7 5279.5 3665.6 3872.5 3872.9 3873.8 3874.8 5257.6 5338.4 5438.3 5506.3 5511.3 3805.6 3849.2 3862.3 3873.0 3873.1 3888.5 3902.2 3923.4 3929.0 3930.7 4429.3 4454.9 4455.8 4457.7 4489.0 5061.4 5272.6 5291.2 5313.4 5317.3 1318.6 1342.6 1343.3 1343.9 1347.6 829.6 857.3 876.2 877.8 879.5 4516.5 4540.8 4561.1 4564.3 4569.2 4741.2 4744.0 4750.9 4760.8 4776.0 4453.5 4465.0 4488.3 4490.2 4503.3 2686.7 2867.2 2888.5 2890.6 2934.6 5613.8 5632.5 5638.2 5664.2 5668.2 5382.5 5417.1 5571.2 5620.0 5624.1 5605.3 5613.2 5620.8 5655.1 5669.9 5455.9 5463.3 5467.7 5486.9 5495.5 5039.6 5306.5 5310.4 5338.3 5392.9 5339.2 5489.6 5567.3 5588.2 5663.6 3812.7 3877.1 3880.8 3892.7 3913.6 5437.0 5446.3 5459.0 5459.5 5465.4 5193.7 5248.2 5274.1 5275.6 5321.0 5244.8 5250.7 5252.6 5260.1 5273.1 4984.8 5161.5 5193.8 5225.9 5240.0 5290.2 5297.6 5305.4 5335.6 5348.9 5081.0 5170.9 5206.1 5211.9 5214.2 20:39:18 INFO - PROCESS | 3630 | 20:39:18 INFO - PROCESS | 3630 | [#5] yui.html Cycles:180 Average:15014.29 Median:4429.06 stddev:17326.80 (391.2%) stddev-sans-first:17342.30 20:39:18 INFO - PROCESS | 3630 | Values: 749.5 761.2 791.0 820.4 848.2 13153.4 13584.7 13780.1 13969.7 13984.9 13570.8 13601.1 13629.7 13635.3 13656.9 680.0 789.6 822.6 822.6 835.7 826.5 828.8 832.9 834.5 838.1 871.7 874.1 874.5 874.8 877.6 715.4 867.4 871.9 896.4 898.4 626.2 627.6 628.8 633.1 633.5 14133.2 14148.9 14167.9 14179.1 14251.3 13672.6 14020.9 14056.0 14196.2 14322.2 11353.3 11635.2 11651.1 11743.6 11766.5 3882.9 3904.2 4000.4 4008.6 4032.0 50557.5 50696.2 51086.2 51291.2 53197.5 11486.4 11554.4 11654.4 11656.7 11776.2 19778.7 19854.4 19901.8 19935.3 20045.7 7880.3 8015.6 8086.5 8088.2 8173.3 4495.4 4535.6 4555.6 4570.2 4583.9 3215.5 3390.1 3393.8 3407.9 3410.2 540.0 679.8 710.9 711.0 716.4 744.5 773.7 777.2 785.5 789.7 9759.0 10010.3 10012.3 10104.0 10140.9 766.3 794.7 799.5 813.0 819.6 4418.6 4439.5 4642.5 4672.0 4675.2 63402.4 63804.7 64663.4 64989.4 65140.3 58663.0 58945.0 59183.7 59258.1 60083.0 50446.7 51784.0 52158.7 52320.2 52918.2 8699.8 8748.1 8759.6 8760.0 8824.4 33335.5 33353.5 33458.3 33865.8 34053.6 37879.0 39556.8 45076.5 45461.8 47599.3 12470.6 12555.8 12655.4 12828.8 12960.4 13908.3 14034.4 14207.9 14416.6 14892.6 9751.6 12651.4 13128.8 13669.1 13727.5 13062.9 13331.6 13398.9 13507.7 13906.1 12442.3 12544.9 12618.4 13011.7 13111.8 10738.9 10822.1 11050.8 11111.4 11301.5 10994.0 11061.6 11078.4 11250.0 11257.4 20:39:18 INFO - PROCESS | 3630 | -------- Summary: end -------- 20:39:18 INFO - PROCESS | 3630 | 20:39:19 INFO - TEST-INFO | 3630: exit 0 20:39:19 INFO - TEST-OK | dromaeo_css | took 1111089ms 20:39:19 INFO - TEST-START | kraken 20:39:19 INFO - operating with platform_type : linux_ 20:39:19 INFO - Initialising browser for kraken test... 20:39:19 INFO - TEST-INFO | started process 3819 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNkxhBZ/profile http://localhost:41615/getInfo.html) 20:39:22 INFO - PROCESS | 3819 | __metrics Screen width/height:1600/1200 20:39:22 INFO - PROCESS | 3819 | colorDepth:24 20:39:22 INFO - PROCESS | 3819 | Browser inner width/height: 1024/697 20:39:22 INFO - PROCESS | 3819 | __metrics 20:39:23 ERROR - PROCESS | 3819 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:39:24 INFO - TEST-INFO | 3819: exit 0 20:39:24 INFO - Browser initialized. 20:39:24 INFO - Running cycle 1/1 for kraken test... 20:39:24 INFO - TEST-INFO | started process 3902 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNkxhBZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:39:35 INFO - PROCESS | 3902 | RSS: Main: 150945792 20:39:35 INFO - PROCESS | 3902 | 20:39:38 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41615/tests/kraken/driver.html?testName=audio-beat-detection) 20:39:39 INFO - PROCESS | 3902 | RSS: Main: 207683584 20:39:39 INFO - PROCESS | 3902 | 20:39:42 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41615/tests/kraken/driver.html?testName=audio-dft) 20:39:42 INFO - PROCESS | 3902 | RSS: Main: 196804608 20:39:42 INFO - PROCESS | 3902 | 20:39:45 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41615/tests/kraken/driver.html?testName=audio-fft) 20:39:46 INFO - PROCESS | 3902 | RSS: Main: 264622080 20:39:46 INFO - PROCESS | 3902 | 20:39:48 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41615/tests/kraken/driver.html?testName=audio-oscillator) 20:39:49 INFO - PROCESS | 3902 | RSS: Main: 173535232 20:39:49 INFO - PROCESS | 3902 | 20:39:51 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41615/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:39:52 INFO - PROCESS | 3902 | RSS: Main: 177848320 20:39:52 INFO - PROCESS | 3902 | 20:39:55 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41615/tests/kraken/driver.html?testName=imaging-darkroom) 20:39:55 INFO - PROCESS | 3902 | RSS: Main: 213458944 20:39:55 INFO - PROCESS | 3902 | 20:39:59 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41615/tests/kraken/driver.html?testName=imaging-desaturate) 20:40:00 INFO - PROCESS | 3902 | RSS: Main: 211546112 20:40:00 INFO - PROCESS | 3902 | 20:40:03 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41615/tests/kraken/driver.html?testName=json-parse-financial) 20:40:03 INFO - PROCESS | 3902 | RSS: Main: 212250624 20:40:03 INFO - PROCESS | 3902 | 20:40:05 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41615/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:40:05 INFO - PROCESS | 3902 | RSS: Main: 200486912 20:40:05 INFO - PROCESS | 3902 | 20:40:07 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:40:08 INFO - PROCESS | 3902 | RSS: Main: 204820480 20:40:08 INFO - PROCESS | 3902 | 20:40:10 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:40:10 INFO - PROCESS | 3902 | RSS: Main: 214716416 20:40:10 INFO - PROCESS | 3902 | 20:40:13 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:40:14 INFO - PROCESS | 3902 | RSS: Main: 222359552 20:40:14 INFO - PROCESS | 3902 | 20:40:17 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:40:17 INFO - PROCESS | 3902 | RSS: Main: 217550848 20:40:17 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | Cycle 1(1): loaded http://localhost:41615/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41615/tests/kraken/driver.html?testName=ai-astar) 20:40:19 INFO - PROCESS | 3902 | RSS: Main: 263008256 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | __start_tp_report 20:40:19 INFO - PROCESS | 3902 | _x_x_mozilla_page_load 20:40:19 INFO - PROCESS | 3902 | _x_x_mozilla_page_load_details 20:40:19 INFO - PROCESS | 3902 | |i|pagename|runs| 20:40:19 INFO - PROCESS | 3902 | |0;ai-astar;127;108;113;112;111;112;111;114;116;113 20:40:19 INFO - PROCESS | 3902 | |1;audio-beat-detection;164;140;157;209;137;164;160;133;189;137 20:40:19 INFO - PROCESS | 3902 | |2;audio-dft;163;163;161;163;162;163;162;163;162;167 20:40:19 INFO - PROCESS | 3902 | |3;audio-fft;78;76;76;149;77;77;76;77;131;77 20:40:19 INFO - PROCESS | 3902 | |4;audio-oscillator;93;94;145;93;100;93;119;93;95;117 20:40:19 INFO - PROCESS | 3902 | |5;imaging-gaussian-blur;118;118;119;118;117;119;119;117;118;117 20:40:19 INFO - PROCESS | 3902 | |6;imaging-darkroom;147;147;147;146;147;148;149;146;147;148 20:40:19 INFO - PROCESS | 3902 | |7;imaging-desaturate;96;97;95;96;96;96;96;96;96;96 20:40:19 INFO - PROCESS | 3902 | |8;json-parse-financial;60;60;58;60;59;60;58;112;56;56 20:40:19 INFO - PROCESS | 3902 | |9;json-stringify-tinderbox;60;60;60;59;60;60;61;60;60;61 20:40:19 INFO - PROCESS | 3902 | |10;stanford-crypto-aes;86;82;85;82;84;83;87;83;86;83 20:40:19 INFO - PROCESS | 3902 | |11;stanford-crypto-ccm;146;147;145;135;139;129;129;130;197;154 20:40:19 INFO - PROCESS | 3902 | |12;stanford-crypto-pbkdf2;172;172;171;175;174;172;173;172;173;173 20:40:19 INFO - PROCESS | 3902 | |13;stanford-crypto-sha256-iterative;70;67;68;68;67;67;67;66;66;66 20:40:19 INFO - PROCESS | 3902 | __end_tp_report 20:40:19 INFO - PROCESS | 3902 | __start_cc_report 20:40:19 INFO - PROCESS | 3902 | _x_x_mozilla_cycle_collect,1695 20:40:19 INFO - PROCESS | 3902 | __end_cc_report 20:40:19 INFO - PROCESS | 3902 | __startTimestamp1462938019706__endTimestamp 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | ------- Summary: start ------- 20:40:19 INFO - PROCESS | 3902 | Number of tests: 14 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#0] ai-astar Cycles:10 Average:113.70 Median:113.00 stddev:5.12 (4.5%) stddev-sans-first:2.22 20:40:19 INFO - PROCESS | 3902 | Values: 127.0 108.0 113.0 112.0 111.0 112.0 111.0 114.0 116.0 113.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#1] audio-beat-detection Cycles:10 Average:159.00 Median:162.00 stddev:24.63 (15.2%) stddev-sans-first:26.06 20:40:19 INFO - PROCESS | 3902 | Values: 164.0 140.0 157.0 209.0 137.0 164.0 160.0 133.0 189.0 137.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:1.60 (1.0%) stddev-sans-first:1.69 20:40:19 INFO - PROCESS | 3902 | Values: 163.0 163.0 161.0 163.0 162.0 163.0 162.0 163.0 162.0 167.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#3] audio-fft Cycles:10 Average:89.40 Median:77.00 stddev:27.01 (35.1%) stddev-sans-first:28.33 20:40:19 INFO - PROCESS | 3902 | Values: 78.0 76.0 76.0 149.0 77.0 77.0 76.0 77.0 131.0 77.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#4] audio-oscillator Cycles:10 Average:104.20 Median:93.00 stddev:17.50 (18.8%) stddev-sans-first:18.08 20:40:19 INFO - PROCESS | 3902 | Values: 93.0 94.0 145.0 93.0 100.0 93.0 119.0 93.0 95.0 117.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 20:40:19 INFO - PROCESS | 3902 | Values: 118.0 118.0 119.0 118.0 117.0 119.0 119.0 117.0 118.0 117.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 20:40:19 INFO - PROCESS | 3902 | Values: 147.0 147.0 147.0 146.0 147.0 148.0 149.0 146.0 147.0 148.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 20:40:19 INFO - PROCESS | 3902 | Values: 96.0 97.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:59.50 stddev:16.97 (28.5%) stddev-sans-first:17.94 20:40:19 INFO - PROCESS | 3902 | Values: 60.0 60.0 58.0 60.0 59.0 60.0 58.0 112.0 56.0 56.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 20:40:19 INFO - PROCESS | 3902 | Values: 60.0 60.0 60.0 59.0 60.0 60.0 61.0 60.0 60.0 61.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.50 stddev:1.79 (2.1%) stddev-sans-first:1.76 20:40:19 INFO - PROCESS | 3902 | Values: 86.0 82.0 85.0 82.0 84.0 83.0 87.0 83.0 86.0 83.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:145.50 stddev:20.17 (13.9%) stddev-sans-first:21.40 20:40:19 INFO - PROCESS | 3902 | Values: 146.0 147.0 145.0 135.0 139.0 129.0 129.0 130.0 197.0 154.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:173.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 20:40:19 INFO - PROCESS | 3902 | Values: 172.0 172.0 171.0 175.0 174.0 172.0 173.0 172.0 173.0 173.0 20:40:19 INFO - PROCESS | 3902 | 20:40:19 INFO - PROCESS | 3902 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:0.78 20:40:19 INFO - PROCESS | 3902 | Values: 70.0 67.0 68.0 68.0 67.0 67.0 67.0 66.0 66.0 66.0 20:40:19 INFO - PROCESS | 3902 | -------- Summary: end -------- 20:40:19 INFO - PROCESS | 3902 | 20:40:20 INFO - TEST-INFO | 3902: exit 0 20:40:20 INFO - TEST-OK | kraken | took 61115ms 20:40:20 INFO - SUITE-END | took 1172s 20:40:20 INFO - Completed test suite (00:19:32) 20:40:20 INFO - javascript benchmark 20:40:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.539836723675, 3187.840607969601, 3215.581974343336, 3225.0, 3256.9837150814255, 5295.285136506571, 5398.757055932482, 5411.161270003146, 5471.452854714526, 5485.972570137149, 5424.186372044195, 5429.53848922841, 5456.290682211317, 5467.343918729748, 5585.218069470277, 4859.246816743393, 4928.334674818907, 4960.156773348522, 4983.875403114915, 5059.190529515278, 4900.754962251891, 4925.13810083234, 4940.283658869462, 4991.450940396553, 5010.323606313137, 4757.191277482836, 4842.539958703917, 4874.098291816025, 4876.146674331978, 4912.6806757561, 4892.437369702458, 4956.529129732706, 4964.726940018298, 4971.527704868032, 5017.272495488134, 4816.54242846929, 4839.467658557556, 4915.926261106087, 4921.261810728383, 5026.547610715053, 5378.569714422837, 5398.541124004464, 5423.078076726966, 5445.455454454563, 5452.945470545284, 5805.535557155417, 5871.324797648283, 5885.882282354329, 5887.381824908366, 5947.316058653224, 5612.186232996192, 5619.129034999582, 5697.971510142423, 5934.97032514839, 5955.106733989893, 5467.6719396836315, 5473.151661492475, 5490.752916118784, 5510.1459273812625, 5517.144842549371, 5960.731767070492, 5988.9101663475085, 5989.64062156272, 5990.550708696778, 5994.520438364921, 5550.972245138749, 5604.215409842625, 5668.773249070074, 5718.914216286759, 5724.0841465366175, 5800.245968024215, 5811.3316968548315, 5812.680302583314, 5817.825465236049, 5844.853878653068, 5752.741126649311, 5779.3642699303045, 5791.015527360359, 5793.594448388657, 5826.475617194473, 5608.495235428831, 5631.943680563224, 5655.858603534863, 5698.974184646803, 5753.539716822645, 4378.912421751547, 4384.232759267115, 4384.9914519660015, 4392.5607439256455, 4420.115976804652, 3771.641694039062, 3906.648401643866, 3916.549596796348, 3937.215492151959, 3963.4847469828737, 5429.67421954684, 5467.863303417446, 5471.890562188734, 5495.972520137454, 5512.118061110284, 5821.650700957872, 5871.148683440877, 5872.0898260769645, 5874.441928016831, 5875.853103672443, 5384.488473595002, 5409.26974858397, 5490.945090549123, 5513.117901135799, 5517.4482551745305, 4851.248056551241, 4906.435759887659, 4910.140725373117, 4912.606991440676, 4946.530079642428, 6040.848978775478, 6102.511799055976, 6109.725062372204, 6124.785632502841, 6133.080037994337, 5663.1788390682295, 5732.59871808986, 5760.654360738369, 5893.057110862242, 5916.822495325147, 6009.819705408845, 6015.789447369321, 6038.84902877414, 6050.576459647782, 6082.60463069899, 5695.829125126253, 5725.713714314351, 5727.083666613322, 5747.051736463437, 5751.022326204639, 5634.85912852182, 5793.0, 5813.244278243801, 5830.0, 5835.824925252249, 5735.684537350319, 5735.827925162252, 5747.9137812932, 5762.135679647919, 5765.308163020464, 5660.066089095253, 5701.743421545957, 5717.028105222205, 5723.141528770718, 5726.427357264407, 5422.9186562202385, 5528.308961379827, 5551.111822108443, 5582.944170558242, 5599.524040456607, 5716.742746576331, 5743.712814359182, 5747.5401967841635, 5748.48263656273, 5761.740721667451, 5455.427180146027, 5504.119340905436, 5550.583706221969, 5551.528120109674, 5585.581081418829], "value": 5349.200545548602, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.3211979564625, 599.200067909341, 605.8969975104231, 611.0193140010281, 617.9011358182689, 30415.087547373587, 32475.51286730701, 32692.83653581729, 32801.5079773803, 42851.78574107125, 39876.0, 44101.0, 44292.3356149658, 44302.55697443025, 44377.7781111094, 8828.073052329493, 8946.284297256203, 8963.41737787045, 8978.326625503081, 9009.639614415415, 8779.604917778715, 8862.512561809099, 8869.37914345996, 8878.378513504058, 8883.244924181452, 8896.0, 8912.821743565124, 8922.643094276254, 8928.642854285821, 8961.238294744955, 8872.0, 8918.152775486318, 8929.33030022751, 8935.285177185842, 8945.955270223672, 8731.432456890281, 8786.472811631258, 8787.428817126865, 8801.20789128975, 8805.955970220108, 40988.0, 41261.587384126375, 41355.0, 41364.58635413668, 41369.793151034355, 3491.6682915123024, 3627.927441451156, 3640.872569460056, 3825.5600605930395, 4020.7587544747116, 30797.538036929465, 31171.220719481968, 31435.214119647193, 31575.684243157502, 31773.682263177303, 4256.638185754215, 4259.403683484315, 4384.123175364908, 4397.450318710161, 4451.955480445219, 207342.9999999985, 215086.0, 217844.0, 218289.0, 218519.0, 54514.72742636262, 54919.45080549143, 55018.449815502136, 55187.0, 55311.44688553063, 53872.73063634657, 58319.70840145857, 58560.801487791745, 58873.0, 59389.40610593839, 14611.926940365443, 14720.705585888436, 14726.263686815617, 14727.779083313759, 14827.851721482863, 11090.0, 11166.776664466664, 11208.775824483628, 11214.271072380268, 11218.326900386002, 14558.563243102724, 16396.098214598074, 16799.2440340185, 16893.662126757397, 16966.660666786596, 6265.185525881606, 6266.24805023391, 6283.183186185766, 6314.242290925026, 6318.62088274705, 953.4517652349926, 1053.6786280184617, 1060.3002018667642, 1113.92506231487, 1117.446863802423, 31091.067267981998, 31271.687283126877, 31657.683423165934, 31688.524672129937, 31721.524177137362, 1097.4293367448881, 1127.1208457403238, 1131.4908291268962, 1138.4478527913955, 1165.9650210493699, 16420.425285114965, 16602.75095873563, 16705.749413758804, 16837.073960932266, 16884.07137607419, 445947.0, 448322.0, 448482.0, 448632.0, 453226.0, 53788.462115378345, 54052.18921716177, 54052.729736351066, 54161.72919135458, 54283.72858135763, 1295.3070107492447, 1344.5764584156154, 1359.0690377091723, 1389.340063469864, 1408.3099281352268, 29856.552151717307, 31525.684743152276, 32017.519737203496, 32357.51463727999, 32898.67101329052, 46547.301790473844, 46605.3009204869, 47087.764561178345, 47098.76450617725, 47227.05545888994, 314278.0, 321042.0, 321274.0, 322304.0, 322781.0, 34547.0, 44642.55357446514, 45431.0, 45724.771376142904, 49521.50478495169, 43827.342589861815, 44198.55801442073, 44846.0, 47086.0, 48101.278480823516, 39696.0, 40585.391219132325, 40982.79508602438, 41236.58763412447, 41837.58162418459, 39667.206655866146, 40904.523115535514, 41349.586504134575, 41672.791636042835, 41924.37113443362, 39965.80444542191, 40069.1986160281, 42666.0, 43062.35406468968, 43072.35391469193, 34004.65995340015, 35972.64027359798, 36196.4570531437, 36518.0, 36705.44941825928, 27750.86124569445, 27830.44339113247, 28186.2953426166, 28384.432311353245, 28440.0], "value": 19235.882920420496, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.94188400797884, 380.86669665617035, 380.9276237514872, 386.9709771767118, 396.3856023164097, 17272.395466158687, 19296.131674074666, 19846.7022994655, 19946.0, 20063.09716062777, 18549.165287562057, 19016.858988460666, 19018.239270429167, 19149.138288777005, 20109.29617463389, 6300.243970723508, 6599.93400065998, 6636.634985075819, 6643.534952553323, 7057.0, 6954.895676564855, 7074.080369551951, 7103.644817759117, 7128.465365097612, 7521.661525231378, 7461.3284804367595, 7488.063992001, 7502.662380192877, 7507.286807753255, 7508.286712762306, 7110.2889711029, 7389.7413590524075, 7408.666610002563, 7412.925125856747, 7413.703451861919, 6844.794656160323, 7126.750563730297, 7129.0, 7191.316824901626, 7217.494775365726, 24954.37614059663, 25221.873890630613, 25864.353391165183, 25883.352916177057, 25890.0, 2389.2222392888866, 2393.8563686178886, 2532.632768248612, 2688.40855011899, 2803.6074949507083, 14539.636509087357, 15471.42137323082, 15849.762253566205, 16303.755443668355, 16377.672446551001, 2019.1923230707835, 2097.353577441716, 2140.2509121807243, 2160.4274867160216, 2170.4031391367344, 95582.52208738912, 96141.51929240309, 97497.51251243698, 97537.0, 98920.0107998918, 17542.298308067533, 17794.644107117787, 17862.642747144986, 17990.190441430168, 18099.54751131206, 31354.372912542076, 31644.208894777355, 31690.841545792122, 31721.682783171873, 31769.36461270808, 9294.953525232331, 9379.718608441757, 9411.10594493522, 9501.334906556474, 9521.809563808685, 7685.346745526693, 7697.23027697237, 7753.728619498291, 7834.921650783419, 7895.368370530344, 8737.126287371211, 9011.188992990532, 9062.909370906338, 9078.137576930181, 9113.77215569616, 3516.8593256269974, 3564.6078931317534, 3606.657367550131, 3683.5395575553057, 3703.4629978652943, 965.7971825916495, 1005.5676059294427, 1072.871255449351, 1086.9130469562417, 1100.4222783038808, 18683.626327473645, 19243.134058967382, 19476.513087172934, 19564.0, 19642.60714785696, 904.7059705595708, 973.5278389980995, 1014.8934361892043, 1036.8807587127426, 1081.416035340907, 10150.340227885164, 10711.544080866488, 10727.195460340507, 10736.30214036085, 10798.0, 468722.0, 480932.0, 486959.0, 490149.0, 490415.0, 55944.88110237772, 57649.71175144181, 65169.0, 75770.0, 88989.55505222562, 1081.1296905990596, 1091.2797553614735, 1131.9490622921824, 1147.3574798113084, 1155.295269885386, 16245.756313655542, 16362.509124726277, 16447.84865059482, 16524.42164524284, 16651.83437159387, 37967.240655186186, 38228.42657360086, 39868.401973969834, 39919.401208981304, 41276.793616031726, 52710.0, 53611.73194134004, 53762.0, 53893.19160212522, 54928.0, 7513.0, 7773.961130194313, 8022.638981245741, 8257.587120643824, 8380.24577788002, 12733.172343797623, 13185.53850615224, 13332.466701331838, 13439.596812095653, 13605.86394136046, 11249.21255512139, 11958.222715523463, 12024.098192635412, 12028.398580071136, 12147.878521215029, 10891.346519208873, 11841.0, 11896.821547676618, 11980.400979951142, 12189.08581856382, 12343.0, 12354.876451235372, 12404.689882753004, 12412.75174496487, 12910.870891290966, 8494.320454363513, 8619.741407757778, 8654.783630409038, 8743.606537705946, 8972.730818075468, 11029.779404411707, 11637.418129093681, 11930.701732456411, 12012.459439325427, 12134.332611706088], "value": 10392.269027615672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.606004258765, 1510.3580978084312, 1515.7650564162552, 1536.5313579358299, 1542.9970519162543, 8315.667373305068, 8687.218150366465, 8732.91267087329, 9024.95487522563, 9068.546572671374, 8764.649414023432, 8974.730758077252, 8988.0, 8996.460212387245, 9002.819943601124, 1948.1915005272795, 2217.3347995601257, 2258.8531745436576, 2297.5634629420356, 2299.781520755534, 2246.955060898781, 2268.965965510519, 2275.72691277047, 2277.089164334271, 2278.4759505313787, 2180.7165068541067, 2213.822894168463, 2216.4569680428317, 2216.4902072523328, 2217.423469897822, 1906.4090132059036, 2193.6709493576013, 2242.55148970205, 2279.111146652815, 2291.8166546676225, 2205.503761653631, 2205.669149627569, 2212.822974162063, 2216.656418255173, 2253.0, 8932.598033088461, 9009.963854156791, 9016.684416045407, 9020.0, 9033.006369299372, 4993.101241776479, 5198.9480105198845, 5219.164933610642, 5257.158854583248, 5258.8685282867855, 3279.6392396836327, 3311.7516186286125, 3356.345512625039, 3462.186386199241, 3484.0593039879345, 1050.76883085721, 1077.8221593437074, 1078.1805827571102, 1083.6478144602956, 1097.286763603656, 12109.939450302692, 12207.511699532008, 12221.877781222163, 12256.509739610405, 12262.509499620093, 4635.466921304026, 4641.698289611165, 4701.835435759732, 4750.572448479654, 4757.548032936866, 9852.0, 9858.654947076819, 9864.112229899343, 9864.802703945881, 9872.0, 4575.9084818303445, 5234.345706786652, 5253.789848406097, 5281.287026251483, 5297.390800058042, 4672.486026537078, 4813.975930120327, 4825.348577942003, 4846.612271018381, 4869.70781753096, 3879.3792993120965, 3903.394973779069, 3911.530616326059, 3962.5244970603712, 4017.091881356391, 1210.4129497193853, 1275.4770544076885, 1277.6741930807584, 1288.8324517812812, 1293.2628401811066, 988.2143695761887, 990.6928852055886, 1007.7682133109425, 1022.2282176956498, 1034.7723500829804, 5931.703414829241, 5934.109883517507, 5942.465178133903, 6034.616017512475, 6214.40963108504, 451.31174958189024, 452.92300308947557, 463.1200718634621, 466.6989791584477, 468.0335108001965, 4271.871843844628, 4298.699091063658, 4302.634276086567, 4305.095929854766, 4331.523532411432, 10402.375857448576, 10934.0, 10938.56245750161, 10947.616833410792, 11027.503762330714, 10757.977992090739, 10759.784804304027, 10844.0, 10856.511456984455, 10926.453677316102, 7520.6239688015385, 7875.527468351917, 7916.604169791487, 8072.556009419421, 8221.0, 5893.705314734332, 6135.53983451234, 6195.380461953769, 6211.378862113753, 6211.875762484816, 9758.072983066597, 9965.302428829913, 10167.288289819642, 10170.237232207763, 10361.792764144524, 10209.183265338903, 10350.896491035295, 10358.792824143324, 10359.741006474898, 10394.844077338998, 6993.650317484004, 7354.926450735424, 7557.35762460175, 7906.486078404885, 8061.395395345256, 8632.136786321318, 8662.393632445921, 8696.695615653422, 8735.694250701195, 8798.164174403422, 7236.6019868907715, 7601.163871973968, 7729.6521656524465, 7735.110462296683, 7993.240642138988, 7550.924490755242, 7560.886586701094, 7577.772666820004, 7729.6521656524465, 7773.0, 6729.798106056826, 6737.2252190996705, 6771.424428923597, 6784.185897692309, 6800.455963523005, 4977.925331120108, 5115.3094332266155, 5135.435102138837, 5204.323437953042, 5329.040772660958, 5132.538071573576, 5179.274901513716, 5286.154215325683, 5288.180332048539, 5358.356997160366], "value": 4516.855672586102, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.1310613048791, 319.24976305681645, 325.2486755594576, 325.3801508127019, 329.5370005142775, 4801.399825021872, 4931.975340123303, 5023.095842748304, 5088.821891233808, 5168.173092305232, 5062.949370506294, 5098.037255656571, 5106.540411362977, 5124.769385377657, 5129.102407078765, 3957.4261732048835, 3971.1462035662325, 3986.501687289074, 3992.700547458945, 4008.478897743304, 3811.4282857571447, 3858.3440815061367, 3960.2871483133026, 4029.6373326400617, 4032.931273212601, 3966.742161759491, 3986.2027594481074, 4004.3192657248337, 4005.1589166275116, 4006.5392479864895, 3966.147278335143, 3974.6224108709625, 3979.3831956046715, 3983.701222408331, 4000.9799951000205, 3510.4018381159262, 3536.328097661436, 3541.1678255609913, 3581.6955558777536, 3581.94627080594, 4788.616910647174, 4859.198232291667, 4867.006790823618, 4887.165444042447, 4891.065360783537, 4508.977455112737, 4531.977340113312, 4541.772911354452, 4554.157480866017, 4554.38515800366, 5212.791488340462, 5231.136862417697, 5234.089557311592, 5273.707941554306, 5279.498447647467, 3665.5601327840563, 3872.457855900176, 3872.9031774205587, 3873.7532231305777, 3874.767513949144, 5257.553107985826, 5338.439463856278, 5438.308037521413, 5506.311711036121, 5511.311086114236, 3805.6194380561724, 3849.2101172954713, 3862.2661694278, 3873.0123818426123, 3873.0898238913833, 3888.494495715539, 3902.2195560887935, 3923.352646813244, 3928.9607103929166, 3930.744501607386, 4429.313456414261, 4454.866354009385, 4455.799489023002, 4457.710248833815, 4488.977555112268, 5061.367329083864, 5272.6309158358545, 5291.1798671206025, 5313.44208858072, 5317.30874986257, 1318.637374721959, 1342.5837990223063, 1343.3081962789172, 1343.899207559437, 1347.6294019144814, 829.5810615639184, 857.2756021162108, 876.2114097312342, 877.793034577456, 879.4898958603994, 4516.548345165457, 4540.772961351985, 4561.129936725993, 4564.338170965191, 4569.177548041318, 4741.2414013758325, 4743.95256047442, 4750.881227969259, 4760.785764640599, 4775.976120119447, 4453.532379100147, 4464.955350446519, 4488.349189367524, 4490.236659767847, 4503.256962601135, 2686.6507354043847, 2867.197184788221, 2888.5378339465583, 2890.638670166229, 2934.5891575179494, 5613.831585052455, 5632.5212356950115, 5638.23883775685, 5664.24408433387, 5668.206451096849, 5382.4886635769535, 5417.052015897202, 5571.164325351229, 5619.971900140555, 5624.128260119688, 5605.32736071674, 5613.214150019082, 5620.831375058755, 5655.123455864347, 5669.886602267931, 5455.9454405455435, 5463.344398672185, 5467.699276539669, 5486.862828429321, 5495.53287970527, 5039.6220283479615, 5306.469353064663, 5310.362756469249, 5338.2526446298325, 5392.919106213489, 5339.225812257278, 5489.58828087887, 5567.2762540871045, 5588.16177573351, 5663.575231857545, 3812.6759225465034, 3877.0501227199516, 3880.767153970771, 3892.6885849131436, 3913.5890731473924, 5436.972815135899, 5446.291982042309, 5459.0, 5459.508644222039, 5465.37148227943, 5193.662411943212, 5248.186531087687, 5274.12976858814, 5275.577953763766, 5320.973395133, 5244.842654720364, 5250.687328167958, 5252.5535329497425, 5260.105782017143, 5273.101683393898, 4984.800607975641, 5161.509656582619, 5193.818216362409, 5225.869353266046, 5239.973800130975, 5290.206469029677, 5297.629165958346, 5305.389880163831, 5335.599830012842, 5348.893022139458, 5080.974595127001, 5170.8965820684125, 5206.140986737298, 5211.9478805211465, 5214.191800270964], "value": 3997.464480061305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.4603885202654, 761.2425636491691, 791.0349373764005, 820.4093053001836, 848.1687945813103, 13153.40809663565, 13584.728305433886, 13780.104293220933, 13969.720605587881, 13984.93007534962, 13570.796438053412, 13601.115927464707, 13629.72740545191, 13635.250061246628, 13656.863431365684, 679.9932000679979, 789.6288744290204, 822.6257053040852, 822.6257053040882, 835.7450977451874, 826.5082276045773, 828.8052307707684, 832.8792325112855, 834.4867906237681, 838.0948575538404, 871.7036207689384, 874.0822136756381, 874.4534665833854, 874.7506960516254, 877.6401675313123, 715.4419552748839, 867.3971589745098, 871.904090550039, 896.3725392225468, 898.4429653614735, 626.214101302862, 627.6485168305763, 628.779927025542, 633.0567454492818, 633.5248563577317, 14133.15201087938, 14148.858511414857, 14167.858321416757, 14179.078359906573, 14251.287435628177, 13672.589822305248, 14020.929895350459, 14056.0, 14196.219207943559, 14322.212278324689, 11353.318800871892, 11635.185537012412, 11651.126165537618, 11743.58897438594, 11766.470508827124, 3882.864099756523, 3904.219156168749, 4000.439938408625, 4008.6191811777835, 4031.9798401007806, 50557.494425055644, 50696.23955640668, 51086.23370649443, 51291.23063154056, 53197.46802531964, 11486.42567871603, 11554.364509951865, 11654.417279136009, 11656.650300491, 11776.175667703179, 19778.70331945022, 19854.404367868698, 19901.805891646258, 19935.302264420967, 20045.699314510293, 7880.290773830383, 8015.637341651933, 8086.5148091114725, 8088.150744171895, 8173.346132309401, 4495.43807024122, 4535.614472769786, 4555.5899969002285, 4570.245909424974, 4583.862484125415, 3215.5015972524297, 3390.135515443546, 3393.830308484615, 3407.863685452604, 3410.215650400422, 539.9622026458189, 679.8436359637311, 710.8578284343153, 710.989335159973, 716.4161208614962, 744.5123444144094, 773.7098588029489, 777.1917206105587, 785.4658831994299, 789.6683392974965, 9759.0, 10010.297219750626, 10012.299139060196, 10104.0, 10140.898591014144, 766.2643861892645, 794.7258195922398, 799.4563696686193, 812.9878051829228, 819.6188772220946, 4418.6023257906945, 4439.511653718088, 4642.51253618372, 4672.0, 4675.228587283128, 63402.36597634057, 63804.68097659575, 64663.353366466676, 64989.350106499274, 65140.34859651438, 58663.0, 58945.0, 59183.704081479314, 59258.11112833311, 60083.0, 50446.74776626093, 51784.0, 52158.739206303726, 52320.215196771314, 52918.20622690663, 8699.782505437415, 8748.081451447506, 8759.60581773809, 8760.0, 8824.426412283181, 33335.49996750099, 33353.499697505045, 33458.33083338271, 33865.83067084649, 34053.63785448554, 37879.0, 39556.80221598873, 45076.549234508544, 45461.772691136335, 47599.28601071056, 12470.56353027676, 12555.811662824883, 12655.367231638196, 12828.840694675098, 12960.351982400654, 13908.304584770518, 14034.438622454987, 14207.928960355131, 14416.639584010065, 14892.553223403314, 9751.60993560278, 12651.430685618985, 13128.803067953797, 13669.111507751964, 13727.45090196381, 13062.934685326512, 13331.600051998455, 13398.933005334911, 13507.729845403233, 13906.096103753223, 12442.315672638091, 12544.937275313565, 12618.369081545703, 13011.739765204833, 13111.803322949972, 10738.892611073788, 10822.134229261797, 11050.778984420427, 11111.388873611706, 11301.547938082385, 10994.0, 11061.557537698724, 11078.446077696244, 11250.0, 11257.380844053328], "value": 6878.7838305970945, "unit": "score"}], "name": "dromaeo_css", "value": 7143.023700086001, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 108.0, 113.0, 112.0, 111.0, 112.0, 111.0, 114.0, 116.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 140.0, 157.0, 209.0, 137.0, 164.0, 160.0, 133.0, 189.0, 137.0], "value": 159.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 163.0, 162.0, 163.0, 162.0, 163.0, 162.0, 167.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 76.0, 149.0, 77.0, 77.0, 76.0, 77.0, 131.0, 77.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 145.0, 93.0, 100.0, 93.0, 119.0, 93.0, 95.0, 117.0], "value": 104.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 118.0, 117.0, 119.0, 119.0, 117.0, 118.0, 117.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 149.0, 146.0, 147.0, 148.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 58.0, 60.0, 59.0, 60.0, 58.0, 112.0, 56.0, 56.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 61.0, 60.0, 60.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 85.0, 82.0, 84.0, 83.0, 87.0, 83.0, 86.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 135.0, 139.0, 129.0, 129.0, 130.0, 197.0, 154.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 171.0, 175.0, 174.0, 172.0, 173.0, 172.0, 173.0, 173.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1583.5, "alertThreshold": 2.0}]} 20:40:20 INFO - Return code: 0 20:40:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:40:21 ERROR - # TBPL SUCCESS # 20:40:21 INFO - Running post-action listener: _resource_record_post_action 20:40:21 INFO - ##### 20:40:21 INFO - ##### Finished run-tests step (success) 20:40:21 INFO - ##### 20:40:21 INFO - Running post-run listener: _resource_record_post_run 20:40:22 INFO - Total resource usage - Wall time: 1190s; CPU: 25.0%; Read bytes: 4096; Write bytes: 548700160; Read time: 12; Write time: 517216 20:40:22 INFO - install - Wall time: 17s; CPU: 27.0%; Read bytes: 0; Write bytes: 253874176; Read time: 0; Write time: 284312 20:40:22 INFO - run-tests - Wall time: 1174s; CPU: 25.0%; Read bytes: 4096; Write bytes: 294825984; Read time: 12; Write time: 232904 20:40:22 INFO - Running post-run listener: _upload_blobber_files 20:40:22 INFO - Blob upload gear active. 20:40:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:40:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:40:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:40:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:40:22 INFO - (blobuploader) - INFO - Open directory for files ... 20:40:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:40:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:40:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:40:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:23 INFO - (blobuploader) - INFO - Done attempting. 20:40:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:40:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:40:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:40:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:23 INFO - (blobuploader) - INFO - Done attempting. 20:40:23 INFO - (blobuploader) - INFO - Iteration through files over. 20:40:23 INFO - Return code: 0 20:40:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:40:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:40:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff49847aee01a59faf5521ab3705356d42fe3df07ee985e76c1973455cccaae47d0e1e8fa031177527736bdcc15e6aea15c34cf8823da9f1e5b66854e5927ef0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c84aa6d6a4d92b842c58d53bce95d4e8bfecaf4f53f430effe8a19fbb9e57ec498c107b078317ca59bd2abc6dab27683a2f70a4fd4f9ae72737c83f758af7046"} 20:40:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:40:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:40:23 INFO - Contents: 20:40:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff49847aee01a59faf5521ab3705356d42fe3df07ee985e76c1973455cccaae47d0e1e8fa031177527736bdcc15e6aea15c34cf8823da9f1e5b66854e5927ef0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c84aa6d6a4d92b842c58d53bce95d4e8bfecaf4f53f430effe8a19fbb9e57ec498c107b078317ca59bd2abc6dab27683a2f70a4fd4f9ae72737c83f758af7046"} 20:40:23 INFO - Running post-run listener: copy_logs_to_upload_dir 20:40:23 INFO - Copying logs to upload dir... 20:40:23 INFO - mkdir: /builds/slave/test/build/upload/logs 20:40:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.661089 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-05-10 20:40:23.516120) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 20:40:23.520052) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff49847aee01a59faf5521ab3705356d42fe3df07ee985e76c1973455cccaae47d0e1e8fa031177527736bdcc15e6aea15c34cf8823da9f1e5b66854e5927ef0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c84aa6d6a4d92b842c58d53bce95d4e8bfecaf4f53f430effe8a19fbb9e57ec498c107b078317ca59bd2abc6dab27683a2f70a4fd4f9ae72737c83f758af7046"} build_url:https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005312 build_url: 'https://queue.taskcluster.net/v1/task/f_38WHqwQeaGNcb3MY0cOA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff49847aee01a59faf5521ab3705356d42fe3df07ee985e76c1973455cccaae47d0e1e8fa031177527736bdcc15e6aea15c34cf8823da9f1e5b66854e5927ef0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c84aa6d6a4d92b842c58d53bce95d4e8bfecaf4f53f430effe8a19fbb9e57ec498c107b078317ca59bd2abc6dab27683a2f70a4fd4f9ae72737c83f758af7046"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 20:40:23.534655) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:40:23.535023) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1462936450.835962-646986492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002121 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 20:40:23.620129) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 20:40:23.620953) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 20:40:23.621645) ========= ========= Total master_lag: 0.18 =========