builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1462809605.28 results: success (0) buildid: 20160509081850 builduid: f804fe1375754279a8a1f8625c2bda46 revision: a0aa4227b6b36846a06ad580dd30628316cf18f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.276453) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.276947) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.277231) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.446642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.447102) ========= 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=df17445c3590ff7897617a0e00000225-1462808584.324799-98337328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-09 09:00:05-- 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% 114M=0s 2016-05-09 09:00:05 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106607 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.613478) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.613855) ========= 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=df17445c3590ff7897617a0e00000225-1462808584.324799-98337328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021261 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:05.677822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 09:00:05.678330) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a0aa4227b6b36846a06ad580dd30628316cf18f7 --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 a0aa4227b6b36846a06ad580dd30628316cf18f7 --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=df17445c3590ff7897617a0e00000225-1462808584.324799-98337328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-09 09:00:06,138 truncating revision to first 12 chars 2016-05-09 09:00:06,138 Setting DEBUG logging. 2016-05-09 09:00:06,138 attempt 1/10 2016-05-09 09:00:06,138 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a0aa4227b6b3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 09:00:07,358 unpacking tar archive at: fx-team-a0aa4227b6b3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.526911 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 09:00:07.241284) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:07.241735) ========= script_repo_revision: a0aa4227b6b36846a06ad580dd30628316cf18f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:07.242263) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:07.246662) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 09:00:07.260899) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-09 09:00:07.261307) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=df17445c3590ff7897617a0e00000225-1462808584.324799-98337328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:00:07 INFO - MultiFileLogger online at 20160509 09:00:07 in /builds/slave/test 09:00:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 09:00:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:07 INFO - {'append_to_log': False, 09:00:07 INFO - 'base_work_dir': '/builds/slave/test', 09:00:07 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:00:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:07 INFO - 'branch': 'Fx-Team-Non-PGO', 09:00:07 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:07 INFO - 'config_files': ('talos/linux_config.py',), 09:00:07 INFO - 'default_actions': ('clobber', 09:00:07 INFO - 'read-buildbot-config', 09:00:07 INFO - 'download-and-extract', 09:00:07 INFO - 'populate-webroot', 09:00:07 INFO - 'create-virtualenv', 09:00:07 INFO - 'install', 09:00:07 INFO - 'run-tests'), 09:00:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:07 INFO - 'download_minidump_stackwalk': True, 09:00:07 INFO - 'download_symbols': 'ondemand', 09:00:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:07 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:07 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:07 INFO - 'installer_path': 'installer.exe', 09:00:07 INFO - 'log_level': 'info', 09:00:07 INFO - 'log_name': 'talos', 09:00:07 INFO - 'log_to_console': True, 09:00:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:00:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:00:07 INFO - 'opt_config_files': (), 09:00:07 INFO - 'pip_index': False, 09:00:07 INFO - 'sps_profile': False, 09:00:07 INFO - 'sps_profile_interval': 0, 09:00:07 INFO - 'suite': 'dromaeojs', 09:00:07 INFO - 'system_bits': '32', 09:00:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:07 INFO - 'title': 'talos-linux64-ix-021', 09:00:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:07 INFO - 'use_talos_json': True, 09:00:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:07 INFO - 'work_dir': 'build'} 09:00:07 INFO - ##### 09:00:07 INFO - ##### Running clobber step. 09:00:07 INFO - ##### 09:00:07 INFO - Running pre-action listener: _resource_record_pre_action 09:00:07 INFO - Running main action method: clobber 09:00:07 INFO - rmtree: /builds/slave/test/build 09:00:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:08 INFO - Running post-action listener: _resource_record_post_action 09:00:08 INFO - ##### 09:00:08 INFO - ##### Finished clobber step (success) 09:00:08 INFO - ##### 09:00:08 INFO - ##### 09:00:08 INFO - ##### Running read-buildbot-config step. 09:00:08 INFO - ##### 09:00:08 INFO - Running pre-action listener: _resource_record_pre_action 09:00:08 INFO - Running main action method: read_buildbot_config 09:00:08 INFO - Using buildbot properties: 09:00:08 INFO - { 09:00:08 INFO - "project": "", 09:00:08 INFO - "product": "firefox", 09:00:08 INFO - "script_repo_revision": "production", 09:00:08 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:00:08 INFO - "repository": "", 09:00:08 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:00:08 INFO - "buildid": "20160509081850", 09:00:08 INFO - "pgo_build": "False", 09:00:08 INFO - "basedir": "/builds/slave/test", 09:00:08 INFO - "buildnumber": 1739, 09:00:08 INFO - "slavename": "talos-linux64-ix-021", 09:00:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:00:08 INFO - "platform": "ubuntu64_hw", 09:00:08 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:00:08 INFO - "branch": "fx-team", 09:00:08 INFO - "repo_path": "integration/fx-team", 09:00:08 INFO - "revision": "a0aa4227b6b36846a06ad580dd30628316cf18f7", 09:00:08 INFO - "stage_platform": "linux64", 09:00:08 INFO - "builduid": "f804fe1375754279a8a1f8625c2bda46", 09:00:08 INFO - "slavebuilddir": "test" 09:00:08 INFO - } 09:00:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:00:08 INFO - Running post-action listener: _resource_record_post_action 09:00:08 INFO - ##### 09:00:08 INFO - ##### Finished read-buildbot-config step (success) 09:00:08 INFO - ##### 09:00:08 INFO - ##### 09:00:08 INFO - ##### Running download-and-extract step. 09:00:08 INFO - ##### 09:00:08 INFO - Running pre-action listener: _resource_record_pre_action 09:00:08 INFO - Running main action method: download_and_extract 09:00:08 INFO - mkdir: /builds/slave/test/build/tests 09:00:08 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')]} 09:00:08 INFO - https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:00:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:00:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:00:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:10 INFO - Downloaded 1361 bytes. 09:00:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:00:10 INFO - Using the following test package requirements: 09:00:10 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:00:10 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:00:10 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:00:10 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'jsshell-linux-x86_64.zip'], 09:00:10 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:00:10 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:00:10 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:00:10 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:00:10 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:00:10 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:00:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:00:10 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:00: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')]} 09:00:10 INFO - https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:00:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:00:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:00:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:12 INFO - Downloaded 22322863 bytes. 09:00:12 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'] 09:00:12 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 09:00:13 INFO - Return code: 0 09:00:13 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 09:00:13 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')]} 09:00:13 INFO - https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:00:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:00:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:00:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:23 INFO - Downloaded 22322863 bytes. 09:00:23 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'] 09:00:23 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 09:00:24 INFO - Return code: 0 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:24 INFO - https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:00:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:25 INFO - Downloaded 10840152 bytes. 09:00:25 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'] 09:00:25 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 09:00:26 INFO - Return code: 0 09:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:26 INFO - https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:00:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PNK5tzmqQXCn7dy16TDHgw/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 09:00:28 INFO - Downloaded 54863263 bytes. 09:00:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:28 INFO - mkdir: /builds/slave/test/properties 09:00:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:28 INFO - Contents: 09:00:28 INFO - build_url:https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:29 INFO - Running post-action listener: _resource_record_post_action 09:00:29 INFO - Running post-action listener: set_extra_try_arguments 09:00:29 INFO - ##### 09:00:29 INFO - ##### Finished download-and-extract step (success) 09:00:29 INFO - ##### 09:00:29 INFO - ##### 09:00:29 INFO - ##### Running populate-webroot step. 09:00:29 INFO - ##### 09:00:29 INFO - Running pre-action listener: _resource_record_pre_action 09:00:29 INFO - Running main action method: populate_webroot 09:00:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:29 INFO - '--tppagecycles', 09:00:29 INFO - '7'], 09:00:29 INFO - 'tests': ['tsvgm']}}, 09:00:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['damp', 'tps']}, 09:00:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'tests': ['damp', 'tps']}, 09:00:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['dromaeo_dom']}, 09:00:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tsvgx', 09:00:29 INFO - 'tsvgr_opacity', 09:00:29 INFO - 'tart', 09:00:29 INFO - 'tscrollx', 09:00:29 INFO - 'cart']}, 09:00:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:29 INFO - 'tsvgr_opacity', 09:00:29 INFO - 'tart', 09:00:29 INFO - 'tscrollx', 09:00:29 INFO - 'cart']}, 09:00:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tp5o']}, 09:00:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'tests': ['tp5o']}, 09:00:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s', 09:00:29 INFO - '--xperf_path', 09:00:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:29 INFO - 'tests': ['tp5n']}, 09:00:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:29 INFO - 'talos_options': ['--xperf_path', 09:00:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:29 INFO - 'tests': ['tp5n']}}, 09:00:29 INFO - 'talos.zip': {'path': '', 09:00:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:29 INFO - Running post-action listener: _resource_record_post_action 09:00:29 INFO - ##### 09:00:29 INFO - ##### Finished populate-webroot step (success) 09:00:29 INFO - ##### 09:00:29 INFO - ##### 09:00:29 INFO - ##### Running create-virtualenv step. 09:00:29 INFO - ##### 09:00:29 INFO - Running pre-action listener: _resource_record_pre_action 09:00:29 INFO - Running main action method: create_virtualenv 09:00:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:29 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 09:00:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:29 INFO - Using real prefix '/usr' 09:00:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:29 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:31 INFO - Installing pip.................done. 09:00:31 INFO - Return code: 0 09:00:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:31 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 09:00:31 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 09:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:31 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 09:00:31 INFO - Cleaning up... 09:00:31 INFO - Return code: 0 09:00:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:31 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:31 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 09:00:31 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 09:00:31 INFO - Using env: {'DISPLAY': ':0', 09:00:31 INFO - 'HOME': '/home/cltbld', 09:00:31 INFO - 'LANG': 'en_US.UTF-8', 09:00:31 INFO - 'LANGUAGE': 'en_US:en', 09:00:31 INFO - 'LOGNAME': 'cltbld', 09:00:31 INFO - 'MAIL': '/var/mail/cltbld', 09:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:31 INFO - 'MOZ_NO_REMOTE': '1', 09:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:31 INFO - 'NO_EM_RESTART': '1', 09:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:31 INFO - 'PWD': '/builds/slave/test', 09:00:31 INFO - 'SHELL': '/bin/bash', 09:00:31 INFO - 'SHLVL': '1', 09:00:31 INFO - 'TERM': 'linux', 09:00:31 INFO - 'TMOUT': '86400', 09:00:31 INFO - 'USER': 'cltbld', 09:00:31 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:31 INFO - '_': '/tools/buildbot/bin/python'} 09:00:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:32 INFO - Downloading/unpacking psutil>=0.7.1 09:00:32 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 09:00:32 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 09:00:32 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 09:00:32 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 09:00:32 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 09:00:32 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 09:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:34 INFO - Installing collected packages: psutil 09:00:34 INFO - Running setup.py install for psutil 09:00:34 INFO - building 'psutil._psutil_linux' extension 09:00:34 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 09:00:34 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 09:00:34 INFO - building 'psutil._psutil_posix' extension 09:00:34 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 09:00:34 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 09:00:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:34 INFO - Successfully installed psutil 09:00:34 INFO - Cleaning up... 09:00:34 INFO - Return code: 0 09:00:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:34 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:34 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 09:00:34 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 09:00:34 INFO - Using env: {'DISPLAY': ':0', 09:00:34 INFO - 'HOME': '/home/cltbld', 09:00:34 INFO - 'LANG': 'en_US.UTF-8', 09:00:34 INFO - 'LANGUAGE': 'en_US:en', 09:00:34 INFO - 'LOGNAME': 'cltbld', 09:00:34 INFO - 'MAIL': '/var/mail/cltbld', 09:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:34 INFO - 'MOZ_NO_REMOTE': '1', 09:00:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:34 INFO - 'NO_EM_RESTART': '1', 09:00:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:34 INFO - 'PWD': '/builds/slave/test', 09:00:34 INFO - 'SHELL': '/bin/bash', 09:00:34 INFO - 'SHLVL': '1', 09:00:34 INFO - 'TERM': 'linux', 09:00:34 INFO - 'TMOUT': '86400', 09:00:34 INFO - 'USER': 'cltbld', 09:00:34 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:34 INFO - '_': '/tools/buildbot/bin/python'} 09:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:00:34 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 09:00:34 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 09:00:34 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 09:00:34 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 09:00:34 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 09:00:34 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 09:00:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:00:36 INFO - Installing collected packages: mozsystemmonitor 09:00:36 INFO - Running setup.py install for mozsystemmonitor 09:00:36 INFO - Successfully installed mozsystemmonitor 09:00:36 INFO - Cleaning up... 09:00:36 INFO - Return code: 0 09:00:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:36 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:36 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 09:00:36 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 09:00:36 INFO - Using env: {'DISPLAY': ':0', 09:00:36 INFO - 'HOME': '/home/cltbld', 09:00:36 INFO - 'LANG': 'en_US.UTF-8', 09:00:36 INFO - 'LANGUAGE': 'en_US:en', 09:00:36 INFO - 'LOGNAME': 'cltbld', 09:00:36 INFO - 'MAIL': '/var/mail/cltbld', 09:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:36 INFO - 'MOZ_NO_REMOTE': '1', 09:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:36 INFO - 'NO_EM_RESTART': '1', 09:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:36 INFO - 'PWD': '/builds/slave/test', 09:00:36 INFO - 'SHELL': '/bin/bash', 09:00:36 INFO - 'SHLVL': '1', 09:00:36 INFO - 'TERM': 'linux', 09:00:36 INFO - 'TMOUT': '86400', 09:00:36 INFO - 'USER': 'cltbld', 09:00:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:36 INFO - '_': '/tools/buildbot/bin/python'} 09:00:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:37 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:37 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 09:00:37 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 09:00:37 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 09:00:37 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 09:00:37 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 09:00:37 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 09:00:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 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 09:00:39 INFO - Downloading docopt-0.6.1.tar.gz 09:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:39 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:39 INFO - Running setup.py install for blobuploader 09:00:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:39 INFO - Running setup.py install for requests 09:00:40 INFO - Running setup.py install for docopt 09:00:40 INFO - Successfully installed blobuploader requests docopt 09:00:40 INFO - Cleaning up... 09:00:40 INFO - Return code: 0 09:00:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:40 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')]} 09:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:40 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')]} 09:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:40 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:40 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 09:00:40 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 09:00:40 INFO - Using env: {'DISPLAY': ':0', 09:00:40 INFO - 'HOME': '/home/cltbld', 09:00:40 INFO - 'LANG': 'en_US.UTF-8', 09:00:40 INFO - 'LANGUAGE': 'en_US:en', 09:00:40 INFO - 'LOGNAME': 'cltbld', 09:00:40 INFO - 'MAIL': '/var/mail/cltbld', 09:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:40 INFO - 'MOZ_NO_REMOTE': '1', 09:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:40 INFO - 'NO_EM_RESTART': '1', 09:00:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:40 INFO - 'PWD': '/builds/slave/test', 09:00:40 INFO - 'SHELL': '/bin/bash', 09:00:40 INFO - 'SHLVL': '1', 09:00:40 INFO - 'TERM': 'linux', 09:00:40 INFO - 'TMOUT': '86400', 09:00:40 INFO - 'USER': 'cltbld', 09:00:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:40 INFO - '_': '/tools/buildbot/bin/python'} 09:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:40 INFO - Running setup.py (path:/tmp/pip-HfprB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:40 INFO - Running setup.py (path:/tmp/pip-Nnpwry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:40 INFO - Running setup.py (path:/tmp/pip-Zclfts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:40 INFO - Running setup.py (path:/tmp/pip-iGJvnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:40 INFO - Running setup.py (path:/tmp/pip-fvAPLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:40 INFO - Running setup.py (path:/tmp/pip-PLspKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:40 INFO - Running setup.py (path:/tmp/pip-MH7duZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:41 INFO - Running setup.py (path:/tmp/pip-CoADD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:41 INFO - Running setup.py (path:/tmp/pip-l51r_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:41 INFO - Running setup.py (path:/tmp/pip-emrxCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:41 INFO - Running setup.py (path:/tmp/pip-Gx1yFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:41 INFO - Running setup.py (path:/tmp/pip-TJXbtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:41 INFO - Running setup.py (path:/tmp/pip-8ONLI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:41 INFO - Running setup.py (path:/tmp/pip-DIo6ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:41 INFO - Running setup.py (path:/tmp/pip-3sBG6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:41 INFO - Running setup.py (path:/tmp/pip-KQPMIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:41 INFO - Running setup.py (path:/tmp/pip-rfrrX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:41 INFO - Running setup.py install for manifestparser 09:00:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:41 INFO - Running setup.py install for mozcrash 09:00:41 INFO - Running setup.py install for mozdebug 09:00:42 INFO - Running setup.py install for mozdevice 09:00:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Running setup.py install for mozfile 09:00:42 INFO - Running setup.py install for mozhttpd 09:00:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Running setup.py install for mozinfo 09:00:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Running setup.py install for mozInstall 09:00:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Running setup.py install for mozleak 09:00:42 INFO - Running setup.py install for mozlog 09:00:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Running setup.py install for moznetwork 09:00:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:42 INFO - Running setup.py install for mozprocess 09:00:42 INFO - Running setup.py install for mozprofile 09:00:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:43 INFO - Running setup.py install for mozrunner 09:00:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:43 INFO - Running setup.py install for mozscreenshot 09:00:43 INFO - Running setup.py install for moztest 09:00:43 INFO - Running setup.py install for mozversion 09:00:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:43 INFO - Cleaning up... 09:00:43 INFO - Return code: 0 09:00:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:43 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:43 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 09:00:43 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 09:00:43 INFO - Using env: {'DISPLAY': ':0', 09:00:43 INFO - 'HOME': '/home/cltbld', 09:00:43 INFO - 'LANG': 'en_US.UTF-8', 09:00:43 INFO - 'LANGUAGE': 'en_US:en', 09:00:43 INFO - 'LOGNAME': 'cltbld', 09:00:43 INFO - 'MAIL': '/var/mail/cltbld', 09:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:43 INFO - 'MOZ_NO_REMOTE': '1', 09:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:43 INFO - 'NO_EM_RESTART': '1', 09:00:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:43 INFO - 'PWD': '/builds/slave/test', 09:00:43 INFO - 'SHELL': '/bin/bash', 09:00:43 INFO - 'SHLVL': '1', 09:00:43 INFO - 'TERM': 'linux', 09:00:43 INFO - 'TMOUT': '86400', 09:00:43 INFO - 'USER': 'cltbld', 09:00:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:43 INFO - '_': '/tools/buildbot/bin/python'} 09:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:43 INFO - Running setup.py (path:/tmp/pip-5lzPEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:43 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)) 09:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:43 INFO - Running setup.py (path:/tmp/pip-L9GpmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:43 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)) 09:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:43 INFO - Running setup.py (path:/tmp/pip-9xROSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:43 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)) 09:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:43 INFO - Running setup.py (path:/tmp/pip-jXF8cN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:44 INFO - Running setup.py (path:/tmp/pip-fpTOhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:44 INFO - Running setup.py (path:/tmp/pip-eguWPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:44 INFO - Running setup.py (path:/tmp/pip-iJKU86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:44 INFO - Running setup.py (path:/tmp/pip-psz756-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:44 INFO - Running setup.py (path:/tmp/pip-UvLC_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:44 INFO - Running setup.py (path:/tmp/pip-agdSfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:44 INFO - Running setup.py (path:/tmp/pip-9qhFwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:44 INFO - Running setup.py (path:/tmp/pip-mHHCt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:44 INFO - Running setup.py (path:/tmp/pip-5VicLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:44 INFO - Running setup.py (path:/tmp/pip-SCYUXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:44 INFO - Running setup.py (path:/tmp/pip-_HkYjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:44 INFO - Running setup.py (path:/tmp/pip-qM7dDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:44 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)) 09:00:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:44 INFO - Running setup.py (path:/tmp/pip-ys4kn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:44 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)) 09:00:44 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)) 09:00:44 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)) 09:00:44 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)) 09:00:44 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)) 09:00:44 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)) 09:00:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:46 INFO - Downloading blessings-1.6.tar.gz 09:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:46 INFO - Installing collected packages: blessings 09:00:46 INFO - Running setup.py install for blessings 09:00:47 INFO - Successfully installed blessings 09:00:47 INFO - Cleaning up... 09:00:47 INFO - Return code: 0 09:00:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:47 INFO - Reading from file tmpfile_stdout 09:00:47 INFO - Current package versions: 09:00:47 INFO - argparse == 1.2.1 09:00:47 INFO - blessings == 1.6 09:00:47 INFO - blobuploader == 1.2.4 09:00:47 INFO - docopt == 0.6.1 09:00:47 INFO - manifestparser == 1.1 09:00:47 INFO - mozInstall == 1.12 09:00:47 INFO - mozcrash == 0.17 09:00:47 INFO - mozdebug == 0.1 09:00:47 INFO - mozdevice == 0.48 09:00:47 INFO - mozfile == 1.2 09:00:47 INFO - mozhttpd == 0.7 09:00:47 INFO - mozinfo == 0.9 09:00:47 INFO - mozleak == 0.1 09:00:47 INFO - mozlog == 3.1 09:00:47 INFO - moznetwork == 0.27 09:00:47 INFO - mozprocess == 0.22 09:00:47 INFO - mozprofile == 0.28 09:00:47 INFO - mozrunner == 6.11 09:00:47 INFO - mozscreenshot == 0.1 09:00:47 INFO - mozsystemmonitor == 0.0 09:00:47 INFO - moztest == 0.7 09:00:47 INFO - mozversion == 1.4 09:00:47 INFO - psutil == 3.1.1 09:00:47 INFO - requests == 1.2.3 09:00:47 INFO - wsgiref == 0.1.2 09:00:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:47 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:47 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 09:00:47 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 09:00:47 INFO - Using env: {'DISPLAY': ':0', 09:00:47 INFO - 'HOME': '/home/cltbld', 09:00:47 INFO - 'LANG': 'en_US.UTF-8', 09:00:47 INFO - 'LANGUAGE': 'en_US:en', 09:00:47 INFO - 'LOGNAME': 'cltbld', 09:00:47 INFO - 'MAIL': '/var/mail/cltbld', 09:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:47 INFO - 'MOZ_NO_REMOTE': '1', 09:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:47 INFO - 'NO_EM_RESTART': '1', 09:00:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:47 INFO - 'PWD': '/builds/slave/test', 09:00:47 INFO - 'SHELL': '/bin/bash', 09:00:47 INFO - 'SHLVL': '1', 09:00:47 INFO - 'TERM': 'linux', 09:00:47 INFO - 'TMOUT': '86400', 09:00:47 INFO - 'USER': 'cltbld', 09:00:47 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:47 INFO - '_': '/tools/buildbot/bin/python'} 09:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 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)) 09:00:47 INFO - Cleaning up... 09:00:47 INFO - Return code: 0 09:00:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:47 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:47 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 09:00:47 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 09:00:47 INFO - Using env: {'DISPLAY': ':0', 09:00:47 INFO - 'HOME': '/home/cltbld', 09:00:47 INFO - 'LANG': 'en_US.UTF-8', 09:00:47 INFO - 'LANGUAGE': 'en_US:en', 09:00:47 INFO - 'LOGNAME': 'cltbld', 09:00:47 INFO - 'MAIL': '/var/mail/cltbld', 09:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:47 INFO - 'MOZ_NO_REMOTE': '1', 09:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:47 INFO - 'NO_EM_RESTART': '1', 09:00:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:47 INFO - 'PWD': '/builds/slave/test', 09:00:47 INFO - 'SHELL': '/bin/bash', 09:00:47 INFO - 'SHLVL': '1', 09:00:47 INFO - 'TERM': 'linux', 09:00:47 INFO - 'TMOUT': '86400', 09:00:47 INFO - 'USER': 'cltbld', 09:00:47 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:47 INFO - '_': '/tools/buildbot/bin/python'} 09:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:47 INFO - Downloading/unpacking jsonschema 09:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:49 INFO - Downloading/unpacking functools32 (from jsonschema) 09:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:49 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:49 INFO - warning: no files found matching '*.txt' 09:00:49 INFO - no previously-included directories found matching 'build' 09:00:49 INFO - no previously-included directories found matching 'dist' 09:00:49 INFO - no previously-included directories found matching '.git*' 09:00:49 INFO - Installing collected packages: jsonschema, functools32 09:00:49 INFO - Running setup.py install for functools32 09:00:50 INFO - warning: no files found matching '*.txt' 09:00:50 INFO - no previously-included directories found matching 'build' 09:00:50 INFO - no previously-included directories found matching 'dist' 09:00:50 INFO - no previously-included directories found matching '.git*' 09:00:50 INFO - Successfully installed jsonschema functools32 09:00:50 INFO - Cleaning up... 09:00:50 INFO - Return code: 0 09:00:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:00:50 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')]} 09:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:50 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')]} 09:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:50 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 0x7f37c52a5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a058a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad18e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, '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': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', '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 09:00:50 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 09:00:50 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 09:00:50 INFO - Using env: {'DISPLAY': ':0', 09:00:50 INFO - 'HOME': '/home/cltbld', 09:00:50 INFO - 'LANG': 'en_US.UTF-8', 09:00:50 INFO - 'LANGUAGE': 'en_US:en', 09:00:50 INFO - 'LOGNAME': 'cltbld', 09:00:50 INFO - 'MAIL': '/var/mail/cltbld', 09:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:50 INFO - 'MOZ_NO_REMOTE': '1', 09:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:50 INFO - 'NO_EM_RESTART': '1', 09:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:50 INFO - 'PWD': '/builds/slave/test', 09:00:50 INFO - 'SHELL': '/bin/bash', 09:00:50 INFO - 'SHLVL': '1', 09:00:50 INFO - 'TERM': 'linux', 09:00:50 INFO - 'TMOUT': '86400', 09:00:50 INFO - 'USER': 'cltbld', 09:00:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:50 INFO - '_': '/tools/buildbot/bin/python'} 09:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:50 INFO - Downloading/unpacking flake8 09:00:50 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 09:00:50 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 09:00:50 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 09:00:50 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 09:00:50 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 09:00:50 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 09:00:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:00:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:00:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:00:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:52 INFO - no previously-included directories found matching 'docs/_build' 09:00:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:00:52 INFO - Running setup.py install for pep8 09:00:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:53 INFO - no previously-included directories found matching 'docs/_build' 09:00:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:00:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:00:53 INFO - Cleaning up... 09:00:53 INFO - Return code: 0 09:00:53 INFO - Running post-action listener: _resource_record_post_action 09:00:53 INFO - Running post-action listener: _start_resource_monitoring 09:00:53 INFO - Starting resource monitoring. 09:00:53 INFO - ##### 09:00:53 INFO - ##### Finished create-virtualenv step (success) 09:00:53 INFO - ##### 09:00:53 INFO - ##### 09:00:53 INFO - ##### Running install step. 09:00:53 INFO - ##### 09:00:53 INFO - Running pre-action listener: _resource_record_pre_action 09:00:53 INFO - Running main action method: install 09:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:53 INFO - Reading from file tmpfile_stdout 09:00:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:53 INFO - Reading from file tmpfile_stdout 09:00:53 INFO - Output received: 09:00:53 INFO - Usage: mozinstall [options] installer 09:00:53 INFO - Options: 09:00:53 INFO - -h, --help show this help message and exit 09:00:53 INFO - -d DEST, --destination=DEST 09:00:53 INFO - Directory to install application into. [default: 09:00:53 INFO - "/builds/slave/test"] 09:00:53 INFO - --app=APP Application being installed. [default: firefox] 09:00:53 INFO - mkdir: /builds/slave/test/build/application 09:00:53 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'] 09:00:53 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 09:01:09 INFO - Reading from file tmpfile_stdout 09:01:09 INFO - Output received: 09:01:09 INFO - /builds/slave/test/build/application/firefox/firefox 09:01:09 INFO - Running post-action listener: _resource_record_post_action 09:01:09 INFO - ##### 09:01:09 INFO - ##### Finished install step (success) 09:01:09 INFO - ##### 09:01:09 INFO - ##### 09:01:09 INFO - ##### Running run-tests step. 09:01:09 INFO - ##### 09:01:09 INFO - Running pre-action listener: _resource_record_pre_action 09:01:09 INFO - Running main action method: run_tests 09:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:09 INFO - Python 2.7.3 09:01:09 INFO - Return code: 0 09:01:09 INFO - grabbing minidump binary from tooltool 09:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b60b20>, '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 0x1b61a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b61eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:09 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 09:01:09 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 09:01:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:09 INFO - Return code: 0 09:01:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:01:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:01:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:01:10 INFO - Return code: 0 09:01:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 09:01:10 INFO - Using env: {'DISPLAY': ':0', 09:01:10 INFO - 'HOME': '/home/cltbld', 09:01:10 INFO - 'LANG': 'en_US.UTF-8', 09:01:10 INFO - 'LANGUAGE': 'en_US:en', 09:01:10 INFO - 'LOGNAME': 'cltbld', 09:01:10 INFO - 'MAIL': '/var/mail/cltbld', 09:01:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:10 INFO - 'MOZ_NO_REMOTE': '1', 09:01:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:10 INFO - 'NO_EM_RESTART': '1', 09:01:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:10 INFO - 'PWD': '/builds/slave/test', 09:01:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:10 INFO - 'SHELL': '/bin/bash', 09:01:10 INFO - 'SHLVL': '1', 09:01:10 INFO - 'TERM': 'linux', 09:01:10 INFO - 'TMOUT': '86400', 09:01:10 INFO - 'USER': 'cltbld', 09:01:10 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1462808584.324799-98337328', 09:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:10 INFO - '_': '/tools/buildbot/bin/python'} 09:01:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:01:10 INFO - mozversion application_buildid: 20160509081850 09:01:10 INFO - mozversion application_changeset: a0aa4227b6b36846a06ad580dd30628316cf18f7 09:01:10 INFO - mozversion application_display_name: Nightly 09:01:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:10 INFO - mozversion application_name: Firefox 09:01:10 INFO - mozversion application_remotingname: firefox 09:01:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:01:10 INFO - mozversion application_vendor: Mozilla 09:01:10 INFO - mozversion application_version: 49.0a1 09:01:10 INFO - mozversion platform_buildid: 20160509081850 09:01:10 INFO - mozversion platform_changeset: a0aa4227b6b36846a06ad580dd30628316cf18f7 09:01:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:01:10 INFO - mozversion platform_version: 49.0a1 09:01:10 INFO - using testdate: 1462809670 09:01:10 INFO - actual date: 1462809670 09:01:10 INFO - starting webserver on 'localhost:55657' 09:01:10 INFO - SUITE-START | Running 2 tests 09:01:10 INFO - TEST-START | dromaeo_css 09:01:10 INFO - operating with platform_type : linux_ 09:01:10 INFO - Initialising browser for dromaeo_css test... 09:01:10 INFO - TEST-INFO | started process 29026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVTm5Aq/profile http://localhost:55657/getInfo.html) 09:01:15 INFO - PROCESS | 29026 | __metrics Screen width/height:1600/1200 09:01:15 INFO - PROCESS | 29026 | colorDepth:24 09:01:15 INFO - PROCESS | 29026 | Browser inner width/height: 1024/697 09:01:15 INFO - PROCESS | 29026 | __metrics 09:01:17 ERROR - PROCESS | 29026 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:01:17 INFO - TEST-INFO | 29026: exit 0 09:01:17 INFO - Browser initialized. 09:01:17 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:17 INFO - TEST-INFO | started process 29108 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVTm5Aq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:29 INFO - PROCESS | 29108 | RSS: Main: 154038272 09:01:29 INFO - PROCESS | 29108 | 09:02:21 INFO - PROCESS | 29108 | 1462809741432 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] 09:04:33 INFO - PROCESS | 29108 | Cycle 1(1): loaded http://localhost:55657/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55657/tests/dromaeo/cssquery-ext.html) 09:04:34 INFO - PROCESS | 29108 | RSS: Main: 258691072 09:04:34 INFO - PROCESS | 29108 | 09:07:37 INFO - PROCESS | 29108 | Cycle 1(1): loaded http://localhost:55657/tests/dromaeo/cssquery-ext.html (next: http://localhost:55657/tests/dromaeo/cssquery-jquery.html) 09:07:37 INFO - PROCESS | 29108 | RSS: Main: 234299392 09:07:37 INFO - PROCESS | 29108 | 09:10:39 INFO - PROCESS | 29108 | Cycle 1(1): loaded http://localhost:55657/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55657/tests/dromaeo/cssquery-mootools.html) 09:10:40 INFO - PROCESS | 29108 | RSS: Main: 233684992 09:10:40 INFO - PROCESS | 29108 | 09:13:42 INFO - PROCESS | 29108 | Cycle 1(1): loaded http://localhost:55657/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55657/tests/dromaeo/cssquery-prototype.html) 09:13:43 INFO - PROCESS | 29108 | RSS: Main: 232947712 09:13:43 INFO - PROCESS | 29108 | 09:16:46 INFO - PROCESS | 29108 | Cycle 1(1): loaded http://localhost:55657/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55657/tests/dromaeo/cssquery-yui.html) 09:16:46 INFO - PROCESS | 29108 | RSS: Main: 208076800 09:16:46 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | Cycle 1(1): loaded http://localhost:55657/tests/dromaeo/cssquery-yui.html (next: http://localhost:55657/tests/dromaeo/cssquery-dojo.html) 09:19:49 INFO - PROCESS | 29108 | RSS: Main: 301875200 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | __start_tp_report 09:19:49 INFO - PROCESS | 29108 | _x_x_mozilla_page_load 09:19:49 INFO - PROCESS | 29108 | _x_x_mozilla_page_load_details 09:19:49 INFO - PROCESS | 29108 | |i|pagename|runs| 09:19:49 INFO - PROCESS | 29108 | |0;dojo.html;2637.6702912135984;2648.24525010372;2696.460707858429;2698.338906967792;2707.269037359913;4609.631229501636;4612.86161415158;4690.249560070385;4692.976535117328;4728.101660684471;4671.135839869627;4694.6244300455965;4719.268513380432;4747;4812.205986012304;4347.260965635849;4350.086481838802;4355.390245365651;4400.867973960779;4410.029793445437;4353.62994145498;4469.44131983502;4469.575390337913;4498.50516443191;4501.954980450203;4407.449068866392;4413.0732546165345;4443.111377724452;4450.488193857699;4508.571685689854;4273.55127711592;4292.034292284242;4309.4397728295435;4314.870553883357;4316.892077698051;4261.531231564525;4273.380359847804;4301.118270754488;4315.093830295642;4351.108022855307;4666.370040044584;4690;4710.387649605564;4714.151452738506;4715.056988602259;4918.286848406997;4940.357753492023;5041.747912604392;5043.142665746832;5045.293658887758;5084.186530155157;5096.235564665293;5123.077845987721;5140.357455318085;5143.974280128576;4691.179043667379;4788.473267940523;4791.329213910055;4795.184818580849;4820.6143508519235;5082.237664350377;5115.488451154854;5148.3564554430695;5162.045021671003;5242.659227150223;4937.679050861682;4957.578605818474;4996.3504744383;5022.096022715873;5024.648274620815;4981.9252711209365;5052.469490703424;5053.0904437200925;5053.267276244924;5066.341375621146;4999.600031997505;5052.267421223975;5062.696238225718;5103.489651034867;5119.641625086283;5050.191969284971;5059.494050594911;5064.290999260106;5076.74616269185;5103.336566246364;4354.56454354562;4361.738295702268;4372.1692878353015;4379.693421460467;4424.933625995613;3677.3380791457794;3728.179800443898;3752.512173417438;3797.715171362104;3822.369309063973;4283.20760659279;4284.871453856389;4288.378185163197;4288.742675439484;4333.393324934512;5050.924236136461;5058.114829904752;5059.519345662156;5059.62052846038;5097.821576244888;4204.705670603028;4306.74159550428;4355.346697995326;4358.171947329997;4374.4094547236355;4641.721496710208;4653.441587009513;4661.137689527448;4668.39310889589;4711.905761884812;4617.79219935097;4842.6368022397755;4857.514248575114;4862.635302352267;4910.729909854992;5036.6726162799305;5038.596912247085;5050.090983622983;5088.516590923857;5118.38579370478;4792.065547218398;4806.519348065305;4811.759412029454;4826.889815342421;4863.465018847854;4785.521447855187;4995.892354800937;5029.29589857413;5045.369328833895;5091.516305950929;5163.941392014553;5180.637355385087;5210.791568337224;5240.109181439241;5260.658057226268;5040.672356296829;5046.369203849519;5058.721770302669;5060.544550990428;5112.258722485144;4814.566688998007;4869.829555965524;5051;5058.924116138187;5064.974675126601;4820.807167713252;4851.393575803025;4886.267059940895;4896.951030489649;4904.632152588499;4936;4944.3572335596145;4946.35697359341;4988.55103040728;4997.675151115166;4751.216049351819;4860.635452341159;4901.264810278344;4915.0415668944215;4933.432655244693 09:19:49 INFO - PROCESS | 29108 | |1;ext.html;561.508679905083;590.1560768101618;595.3599880128861;600.9008513595254;606.8846919085373;26854.597181042274;41539.376909346385;43051.78474107633;43155.56844431551;43263;41602;42116.57883421165;42175.78912105435;42181;42236.36645450313;7469.551826890377;8039.115697273295;8190.836183276331;10187;10471.84292235617;10228.284020118595;10280.537375818069;10358.844617330746;10363.119134873545;10368.222383321243;10283.074523292902;10292.330998485075;10341.121004714609;10354.430506322149;10367;7484.101907771075;10120.190384769201;10344.586216551328;10407.739806504822;10772.784544309148;10147.036031577061;10208.79582408355;10214.336068155546;10216.080552750289;10232;38588;38823.61176388257;38897;38923;38981;3322.3355328934067;3382.594088709346;3487.2676737885076;3623.3296840084668;3962.1481381503113;29331.85334073316;29511.409771804658;29941.40117197644;29982;30045.248868778235;2972.868486008009;3071.9232019199476;3087.691230876917;3151.8266495342746;3153.668864769212;241595;242354;242522;242985;243108;54952.17571736427;55359;55384.44615553873;55392;55422.72288638531;57294;57319.713401432724;57435.13847292294;57548.547087174906;57578.712106440034;12640.87359126397;14074.2962851857;14153.5046273382;14248.287585620885;14262;9892.505374731234;10241.283110182365;10249.282550221562;10334.379937203792;10499.317544359592;16067.276972536263;16070.196490175444;16195.757063644054;16221.594460138354;16226.756598651029;5573;5792.536597072224;5848.3274423441;5927.911081333784;5985.521158307325;907.3920473282916;1011.9494025298706;1013.5185786751234;1054.6414219165367;1055.2349546578669;28721.281967950552;28808.855955720086;28974.27564310867;29051.709482905324;29215.685294161813;1016.3749294184023;1045.9372437653765;1066.4774260612244;1073.9248252622397;1085.7231405991422;15315.846841531666;15398.769018464733;15433.691326173413;15596.220188990732;15655.295511702001;367185;368957;369706;370318;371014;49635.751821240665;50534.49465505371;50556;50587;50599.24101138486;1120.2326406411635;1133.1048471707259;1137.7667578146625;1243.421808858885;1246.663400881775;22986.310410687707;27645.723542764314;28171.71828281691;29684;30166.396672066876;47678.28482572833;48485;48543.5145648539;48595.5140448591;48721.756391217816;328594;330846;331747;331798;332903;35536;36847;37479.625203747615;37537.81231093827;37538.37231393776;43135.78432107945;43350.13299734051;43609.781951090044;44628.55371446244;44681.32978005397;37400.43899341457;37888.43167352547;38236.61763382442;38281.4257786139;38346.42480362853;36109.27781444304;36643.81678091593;37208.44187337138;37321.253574928895;37361.81319093496;33722.83138584292;36851.44722829106;37014.44478332881;37068.814655926544;37114.814425927696;32045.35909281848;33283.83358083194;33641.327173456884;34156.65843341634;34194.658053419145;25449.61825572655;25501;25799.742002579733;25935;25963.87018064973 09:19:49 INFO - PROCESS | 29108 | |2;jquery.html;382.8228198290257;383.75247965062533;385.0777388155367;401.71478250442186;403.16142423758583;13061.542846000391;13702.725945481085;15854.365825366986;17645.55886102848;17704.380346687853;15749.921250393762;16173.35306587738;16488.09315487648;17849.107544622748;17852.82147178528;4631.143238500871;5118;5252.658577192489;5281.075811732951;5313.495217954288;5134.25553294772;5142.537171654551;5195;5236.7119808410525;5281.656692314988;5160.406553246368;5216.321878155834;5223.451537588556;5226.320578324812;5230.555402790767;5023.447420783711;5125.666831655931;5130.384353877558;5158.019976204528;5204.401493828221;4409.206342858284;4556.384888040106;4599.356090147382;4599.655025873073;4649.488556258809;19052.237910483564;20239.79760202394;20775.792242077536;20864.791352086435;21076.683849742265;1920.0207893974034;1921.03948025987;1946.698261769428;1980.5840773437596;2101.2225476573612;9556.952215238949;9701.320907536476;9792.363496372713;9942.602295908155;9999.400035997864;1775.1656721340948;1784.8215178482048;1811.5742800441885;1878.3144152384418;1888.5845114075019;64842;68695.31304686939;68792.31207687959;69223.65388173077;69903;10266.691999240034;10271.794564108675;10328.896711032943;10372;10627.096696780705;12352.938235308766;13100.868991309964;14538.200398977946;14588.635284117981;14594.34325455357;7423.331900129015;7563.394928405824;7608.961955190189;7710.498817576951;7710.691572337044;7179.533330333512;7195.748148814766;7199.640017999184;7295.963520182365;7314.561126332437;4508.256137737237;4527.818887244539;4530.29780384041;4537.546245375502;4580.358749775034;1840.0163645707162;1840.1719226348198;1873.1102726204945;1929.9807001930083;1940.3305859478457;845.9873101903477;893.1871996483169;894.8836651235298;1005.9748506287401;1007.4811472091878;16500.25748841281;16936.83063169377;17094.829051709323;17558.29766809313;17580.736288955675;935.3265648732902;935.3639525122982;947.7820101376722;953.2421724728719;968.6028728221395;9452.291078169164;9459.76350591227;9729.416235025921;9775.413475191512;9920.751981200383;407372;408816;410116;412421;414762;71709.28290717026;76062.61968690231;76915.23084769193;80275;80577.19422805814;964.71058682395;972.6741541583507;988.6045581767495;1019.0879163149089;1032.1794173631981;16040.518784436019;16047.919760401122;16054.357825686842;16160.67678646444;16193.59516012109;36817;37069.81465092765;37401.81299093487;37779;37976.620233798414;50830.237546436096;50904.490955089976;52783;52802.207966881295;53722.925541489734;7535.6985720570565;7591.582462964369;7776.961115194388;7789.532628042336;7883.369330453435;10572.259941803999;11662.358570278717;11908.880911190778;12043.036557075238;12138.393080345772;10259.179265658879;10450.268481206547;10455.634052808115;10501.921324723673;10632.468376580986;10369.792604148026;10382.3251488653;10447.425391603534;10556.419396933243;10660.520276587727;10468.581256749645;10633.255672102878;10755.085817705583;10985.890141098806;10988.615398461016;7576;7619.619019048914;7813.296803287732;7855.978722766004;8008.719694810654;10149.188064954638;10564.524596393027;10596;10666.73333166677;10681.412522311348 09:19:49 INFO - PROCESS | 29108 | |3;mootools.html;1251.4743807600807;1443.5380678182983;1460.3501441858375;1614.9031058136513;1761.8590512758988;6207.534434917382;7153.713851445942;7373.483856130067;7381.667824947876;7594.696212151507;7375.151857536385;7506.7372641957545;7512.96243518783;7515.54906705598;7529.134149572801;2222.2555443926303;2253.5605556916407;2253.9436514087197;2256.0862850545554;2256.165218869021;2177.803997640212;2208.8564243324213;2223.4441389652507;2224.2659922225707;2227.498812767131;2176.1621775616422;2180.1715348167672;2183.585118827418;2186.5517568898417;2224.4994876152896;2162.697222388867;2188.3872515695552;2196.5606878624258;2196.648536234195;2199.967000494994;2161.103142195987;2164.7402311722694;2166.523364859728;2167.2522979572022;2167.9132834686593;7314.707411703525;7409.481336306407;7462.365698915544;7470.3650189733935;7472.252774722539;4411.691181617288;4571.542845715435;4576.977115114437;4584.01443689605;4612.285095810154;2954.6454425468864;2974.8215107093642;3001.639803223627;3030.9848450757604;3087.783855130142;967.3083745122201;970.3935040599625;970.9514524273828;986.1716158426956;989.648674720471;9596.280278979035;9709.36889102206;9715.36850104741;9739.464329461876;9795.853062204073;3898.96101038986;3986.980065099656;4049.331860243086;4076.592340765959;4081;7794.766157015299;7895.171007044292;7898.170692077364;7913.089994650573;7915.050193976645;4510.503844577094;4565.5206203348835;4568.885777855514;4569.29175977724;4570.3829982951875;3662.285854258447;3950.3679411294056;4093.385992101149;4242.787860606957;4250.022494826208;3284.392387408337;3336.799792012487;3371.7976921384798;3384.272381438003;3421.4354631486026;975.5902520941222;1034.4207243943415;1040.0483557544762;1040.287403128844;1044.1803184500131;850.9702160424356;851.5188918261163;862.4868203418956;884.1644645809648;888.4447220487195;4723.905521889543;4757.119932812441;4791.209450440639;4968.403791545038;4973.179425394769;400.30946617085294;410.15712710380456;414.4860373137348;430.80613723824405;437.2915876280446;3391.5251864738957;3415.470602056685;3443.793372397614;3477.2697733475748;3546.0957455848593;8460.8730869037;9115.589798459085;9123.270138388913;9128.634854605874;9240.399374040666;8985.101489851093;9130.406523575946;9147;9168;9187.2190863776;6992.860142797115;7044.682989265495;7117.039199708077;7160.140783105957;7183.640817959081;4934.457209706858;5240.449752775904;5304.706029209837;5434.83695489136;5495.670259784426;7999.6400161994015;8131.552764598004;8197.344212463107;8203.0566484855;8328.791780205544;8195.754127376189;8458.661653534036;8683.2185103341;8717.520536370306;8773.473591584525;5892.499137573354;6398.90401643985;6546.509011824037;6610.504212184009;6672.866542669217;7056.6471676417;7290.05229320185;7291.927080729125;7303.926960730325;7304.342609165201;6416.262129855127;6594.802155935329;6604.14146160996;6652.135222421054;6654.234763002317;6358;6542.901856472062;6549;6563.113979612691;6681.231658359351;6244.656543889948;6275.811725648238;6317.96841015792;6320.209973753281;6321.747130114744;4462.732236065846;4487.775611219491;4607.331936869068;4637.466691330405;4642.280446530793;4471.262241730078;4566.109608626286;4583.427071616048;4602.585767280827;4657.045305712389 09:19:49 INFO - PROCESS | 29108 | |4;prototype.html;303.7084398976982;314.69631805307876;315.50623274575304;316.8035817792968;317.8966835778373;4778.784954677039;4779.856604301868;4794.544518270763;4821.252705830598;4825.638077144211;4737.739424331669;4744.217204161318;4756.167670657638;4773.02153058623;4775.617950563956;3656.3966945454035;3697.500837386945;3720.1443667956387;3731.5708693500187;3818.026403267163;3748.6063963283736;3756.9812150939206;3783.659470647655;3793.4878791363226;3810.371288737355;3607.963920360802;3732.8693495727657;3738.663520283174;3749.568799588041;3789.2232092420986;3696.2792255510185;3766.152615661482;3767.7550959187565;3775.8672398280405;3789.829457674411;3361.8823341182942;3373.2339481625077;3379.5775528058994;3381.9830900845586;3385.765394839394;4534.433195850519;4542.568455996675;4555.157295900269;4560.657950653681;4562.498125206224;4184.581541845791;4210.0948296116485;4316.179925814085;4323.3947247385395;4354.695171338008;4957.429895562021;4973.254011898208;4982.377202849644;4993.550580447741;5001.099802035632;3682.558093028854;3692.9446058308877;3700.870469533553;3709.295233905549;3721.1999420124803;4967.230079337708;5196.402413722395;5200.517852412082;5223;5227.764750586232;3731.5708693500596;3759.793211373401;3769.6418840210135;3781.730961423124;3788.1666033472593;3797.518967602637;3825.3114439401174;3839.2897313997;3841.961580384216;3857.9807100964877;4273.615374616237;4274.166537525215;4299.871003869889;4305.978470107692;4326.718763280377;4787.257975013808;4984.925226121611;5035.295058691786;5036.597072234213;5052.469490703496;1289.5234955975357;1295.2940647347218;1297.1503665099376;1304.6151385341157;1314.9474021039243;857.4340934983006;881.7442941547008;919.1819280840058;920.5949382271779;935.691221896786;4238;4251.87244382669;4253.2769429197115;4258.361245813153;4260.467441569804;4914.483979182206;4945.258211268267;4975.253711943237;4988.35151430319;5026.271190677331;4118.423420721101;4226.598473145046;4231.576842315805;4243.745375277494;4250.256205164084;2599.2981894888276;2634.8287361321454;2652.4429869727574;2653.5886937524715;2672.5189465896324;4674.252119660838;4677.95322046782;4679.321498382716;4685.5782979532005;4713;5062.949370506321;5067.923981140286;5086.415062267813;5097.8725531861255;5141.711938187471;4935.6051515877925;4950.43070046942;4952.529509696573;4962.429320628102;4973.477784832613;5169.948300516947;5177.611679124156;5189.780401605695;5206.765695543634;5221.9216711748595;4890.119778439915;5084.466131056186;5119.641625086358;5130.846074617767;5135.35808023997;4879.95120048795;4885.804567817389;4896.36347274852;4897.16748152822;4909.9018019640125;3651.653092956165;3671.908202294964;3675.393560062667;3688.4098544232793;3695.2055308108884;4948.455669876239;5178.456262092577;5201.297824793604;5230.921536177037;5233.345831771028;4980.5019498049905;5011.924821127613;5022.84931452057;5026.953302591449;5038.722870242172;5003.649744517995;5015.523525265094;5032.245163225399;5066.2400639902835;5068.403452912321;4829.9275510868065;4948.752562371795;4974.7512624369365;4990.8253211137435;4998.6500944933505;4778.928316075331;4913.304909806111;5044.697318160922;5050.646454748279;5056.974715126401;4937.8271760488215;4960.206366981266;4960.231164169415;4960.454350021567;4963.6525443218625 09:19:49 INFO - PROCESS | 29108 | |5;yui.html;732.0154392342298;757.6628400361839;770.7032792374938;803.8313643273751;812.8577498937684;10206.795864082724;10684.19868509861;10696.572137114515;10702.250842441032;10714.410707411089;11950.163488555805;11976.580819671313;11976.580819671313;12002.699932501691;12024.33866137362;651.4657980456028;824.4805772363433;841.0958219913603;853.5476197615274;856.0540602634093;850.8681154421074;860.2945584620613;861.418542483824;861.5606040919145;862.068965517243;918.3020904112889;919.4437365393941;919.9770005749876;920.3373571028882;920.4063379120463;701.2426579294375;892.7411050795295;907.945523268606;909.6225066597354;910.7586489580267;676.0163961436148;676.2223443040493;676.3473248315399;676.5331920974512;676.6177109932921;11374.488148033359;11374.658760237206;11412.31526108436;11434.256773309708;11438.19932604718;11010.119190464744;11112.277701949348;11377.374244416555;11422.086233101332;11770.823437648356;10226.181905447545;10351.430671313075;10472.464342313802;10634.840477392845;11019.944900275528;4062.2687916175078;4134.462519872397;4145.751254924714;4156.625903668654;4287.764172970486;39683;40531.39202911959;40604.187916241506;42414.36378454325;42675.57324426747;9781.951090244504;9856.950715246378;9999.25005624581;10066;10111.191104711605;16941.407050753165;16947.576310592332;16950.91524542394;17168.141592920303;17265.91367043182;6245.125682404467;6337.2395312562785;6451.064595633607;6546.18172728409;6866;3527.9118022049656;3583.2654305867236;3627.9470874820727;3643.562772467321;3661.835217415223;2821.9858900705367;2827.2649111231226;2931.1646180838356;2931.721486458783;2945.27840679035;570.7460180219763;571.4942276085611;572.1275055540334;573.4265734265734;575.7150210645674;469.28434137939917;521.5775222070135;583.8073435766187;599.9302406696913;636.4939872801134;7903.407244456689;7990.216875889045;7998.160193179749;8018.358531317481;8054.758357249292;566.3175873072937;580.6864293281665;593.6645795125827;608.5435923057706;620.4632992461488;3575.856965721394;3647.5799901335995;3667.3032123896373;3701.648343407372;3715.9442608360896;47802.5219747805;51900;54217;55011;55635.72182139063;43925.341119883225;45017;46018.30972535415;46287.30569041467;46340.3048954266;42216;42232.15535689269;42309.57690423056;42569.57430425718;43028.78485607615;7996.760097197093;8018.4787988780545;8031.83936321282;8080.313173380094;8226.958865205876;27048.1885543434;27414;28067.43865122727;29140;29334.853325733235;36502.45246321254;37149.814250928575;37388;38143;38301.80849095848;10464.738381540217;10697.14422846186;10791.1367090631;10958.56165753361;11000.834987475355;9919.764416787213;10007.949960250153;10963.780724385628;11046.281991670516;11102.61140860066;11032.779344413228;11220.55117795279;11394.658160255205;11589;11749.706257343634;10584;10739.892601073889;11069;11330.036946859609;11534.077273818242;10413.271071024956;10630.468476575985;10681.46592670379;10798.40608766494;10990.285631433931;9427.905720942703;9565.569549370155;9566.712998609775;9848.261380396641;9913.45475998798;8780.429272097548;8967.192952634294;8982.416142950688;9028.36801423894;9107.681231156877 09:19:49 INFO - PROCESS | 29108 | __end_tp_report 09:19:49 INFO - PROCESS | 29108 | __start_cc_report 09:19:49 INFO - PROCESS | 29108 | _x_x_mozilla_cycle_collect,956 09:19:49 INFO - PROCESS | 29108 | __end_cc_report 09:19:49 INFO - PROCESS | 29108 | __startTimestamp1462810789935__endTimestamp 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | ------- Summary: start ------- 09:19:49 INFO - PROCESS | 29108 | Number of tests: 6 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | [#0] dojo.html Cycles:165 Average:4696.69 Median:4820.81 stddev:492.02 (10.2%) stddev-sans-first:466.26 09:19:49 INFO - PROCESS | 29108 | Values: 2637.7 2648.2 2696.5 2698.3 2707.3 4609.6 4612.9 4690.2 4693.0 4728.1 4671.1 4694.6 4719.3 4747.0 4812.2 4347.3 4350.1 4355.4 4400.9 4410.0 4353.6 4469.4 4469.6 4498.5 4502.0 4407.4 4413.1 4443.1 4450.5 4508.6 4273.6 4292.0 4309.4 4314.9 4316.9 4261.5 4273.4 4301.1 4315.1 4351.1 4666.4 4690.0 4710.4 4714.2 4715.1 4918.3 4940.4 5041.7 5043.1 5045.3 5084.2 5096.2 5123.1 5140.4 5144.0 4691.2 4788.5 4791.3 4795.2 4820.6 5082.2 5115.5 5148.4 5162.0 5242.7 4937.7 4957.6 4996.4 5022.1 5024.6 4981.9 5052.5 5053.1 5053.3 5066.3 4999.6 5052.3 5062.7 5103.5 5119.6 5050.2 5059.5 5064.3 5076.7 5103.3 4354.6 4361.7 4372.2 4379.7 4424.9 3677.3 3728.2 3752.5 3797.7 3822.4 4283.2 4284.9 4288.4 4288.7 4333.4 5050.9 5058.1 5059.5 5059.6 5097.8 4204.7 4306.7 4355.3 4358.2 4374.4 4641.7 4653.4 4661.1 4668.4 4711.9 4617.8 4842.6 4857.5 4862.6 4910.7 5036.7 5038.6 5050.1 5088.5 5118.4 4792.1 4806.5 4811.8 4826.9 4863.5 4785.5 4995.9 5029.3 5045.4 5091.5 5163.9 5180.6 5210.8 5240.1 5260.7 5040.7 5046.4 5058.7 5060.5 5112.3 4814.6 4869.8 5051.0 5058.9 5065.0 4820.8 4851.4 4886.3 4897.0 4904.6 4936.0 4944.4 4946.4 4988.6 4997.7 4751.2 4860.6 4901.3 4915.0 4933.4 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | [#1] ext.html Cycles:180 Average:48182.21 Median:18303.08 stddev:83664.64 (457.1%) stddev-sans-first:83822.94 09:19:49 INFO - PROCESS | 29108 | Values: 561.5 590.2 595.4 600.9 606.9 26854.6 41539.4 43051.8 43155.6 43263.0 41602.0 42116.6 42175.8 42181.0 42236.4 7469.6 8039.1 8190.8 10187.0 10471.8 10228.3 10280.5 10358.8 10363.1 10368.2 10283.1 10292.3 10341.1 10354.4 10367.0 7484.1 10120.2 10344.6 10407.7 10772.8 10147.0 10208.8 10214.3 10216.1 10232.0 38588.0 38823.6 38897.0 38923.0 38981.0 3322.3 3382.6 3487.3 3623.3 3962.1 29331.9 29511.4 29941.4 29982.0 30045.2 2972.9 3071.9 3087.7 3151.8 3153.7 241595.0 242354.0 242522.0 242985.0 243108.0 54952.2 55359.0 55384.4 55392.0 55422.7 57294.0 57319.7 57435.1 57548.5 57578.7 12640.9 14074.3 14153.5 14248.3 14262.0 9892.5 10241.3 10249.3 10334.4 10499.3 16067.3 16070.2 16195.8 16221.6 16226.8 5573.0 5792.5 5848.3 5927.9 5985.5 907.4 1011.9 1013.5 1054.6 1055.2 28721.3 28808.9 28974.3 29051.7 29215.7 1016.4 1045.9 1066.5 1073.9 1085.7 15315.8 15398.8 15433.7 15596.2 15655.3 367185.0 368957.0 369706.0 370318.0 371014.0 49635.8 50534.5 50556.0 50587.0 50599.2 1120.2 1133.1 1137.8 1243.4 1246.7 22986.3 27645.7 28171.7 29684.0 30166.4 47678.3 48485.0 48543.5 48595.5 48721.8 328594.0 330846.0 331747.0 331798.0 332903.0 35536.0 36847.0 37479.6 37537.8 37538.4 43135.8 43350.1 43609.8 44628.6 44681.3 37400.4 37888.4 38236.6 38281.4 38346.4 36109.3 36643.8 37208.4 37321.3 37361.8 33722.8 36851.4 37014.4 37068.8 37114.8 32045.4 33283.8 33641.3 34156.7 34194.7 25449.6 25501.0 25799.7 25935.0 25963.9 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | [#2] jquery.html Cycles:180 Average:24955.12 Median:409466.00 stddev:67677.78 (16.5%) stddev-sans-first:67842.49 09:19:49 INFO - PROCESS | 29108 | Values: 382.8 383.8 385.1 401.7 403.2 13061.5 13702.7 15854.4 17645.6 17704.4 15749.9 16173.4 16488.1 17849.1 17852.8 4631.1 5118.0 5252.7 5281.1 5313.5 5134.3 5142.5 5195.0 5236.7 5281.7 5160.4 5216.3 5223.5 5226.3 5230.6 5023.4 5125.7 5130.4 5158.0 5204.4 4409.2 4556.4 4599.4 4599.7 4649.5 19052.2 20239.8 20775.8 20864.8 21076.7 1920.0 1921.0 1946.7 1980.6 2101.2 9557.0 9701.3 9792.4 9942.6 9999.4 1775.2 1784.8 1811.6 1878.3 1888.6 64842.0 68695.3 68792.3 69223.7 69903.0 10266.7 10271.8 10328.9 10372.0 10627.1 12352.9 13100.9 14538.2 14588.6 14594.3 7423.3 7563.4 7609.0 7710.5 7710.7 7179.5 7195.7 7199.6 7296.0 7314.6 4508.3 4527.8 4530.3 4537.5 4580.4 1840.0 1840.2 1873.1 1930.0 1940.3 846.0 893.2 894.9 1006.0 1007.5 16500.3 16936.8 17094.8 17558.3 17580.7 935.3 935.4 947.8 953.2 968.6 9452.3 9459.8 9729.4 9775.4 9920.8 407372.0 408816.0 410116.0 412421.0 414762.0 71709.3 76062.6 76915.2 80275.0 80577.2 964.7 972.7 988.6 1019.1 1032.2 16040.5 16047.9 16054.4 16160.7 16193.6 36817.0 37069.8 37401.8 37779.0 37976.6 50830.2 50904.5 52783.0 52802.2 53722.9 7535.7 7591.6 7777.0 7789.5 7883.4 10572.3 11662.4 11908.9 12043.0 12138.4 10259.2 10450.3 10455.6 10501.9 10632.5 10369.8 10382.3 10447.4 10556.4 10660.5 10468.6 10633.3 10755.1 10985.9 10988.6 7576.0 7619.6 7813.3 7856.0 8008.7 10149.2 10564.5 10596.0 10666.7 10681.4 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | [#3] mootools.html Cycles:180 Average:4910.54 Median:5272.58 stddev:2667.30 (50.6%) stddev-sans-first:2660.61 09:19:49 INFO - PROCESS | 29108 | Values: 1251.5 1443.5 1460.4 1614.9 1761.9 6207.5 7153.7 7373.5 7381.7 7594.7 7375.2 7506.7 7513.0 7515.5 7529.1 2222.3 2253.6 2253.9 2256.1 2256.2 2177.8 2208.9 2223.4 2224.3 2227.5 2176.2 2180.2 2183.6 2186.6 2224.5 2162.7 2188.4 2196.6 2196.6 2200.0 2161.1 2164.7 2166.5 2167.3 2167.9 7314.7 7409.5 7462.4 7470.4 7472.3 4411.7 4571.5 4577.0 4584.0 4612.3 2954.6 2974.8 3001.6 3031.0 3087.8 967.3 970.4 971.0 986.2 989.6 9596.3 9709.4 9715.4 9739.5 9795.9 3899.0 3987.0 4049.3 4076.6 4081.0 7794.8 7895.2 7898.2 7913.1 7915.1 4510.5 4565.5 4568.9 4569.3 4570.4 3662.3 3950.4 4093.4 4242.8 4250.0 3284.4 3336.8 3371.8 3384.3 3421.4 975.6 1034.4 1040.0 1040.3 1044.2 851.0 851.5 862.5 884.2 888.4 4723.9 4757.1 4791.2 4968.4 4973.2 400.3 410.2 414.5 430.8 437.3 3391.5 3415.5 3443.8 3477.3 3546.1 8460.9 9115.6 9123.3 9128.6 9240.4 8985.1 9130.4 9147.0 9168.0 9187.2 6992.9 7044.7 7117.0 7160.1 7183.6 4934.5 5240.4 5304.7 5434.8 5495.7 7999.6 8131.6 8197.3 8203.1 8328.8 8195.8 8458.7 8683.2 8717.5 8773.5 5892.5 6398.9 6546.5 6610.5 6672.9 7056.6 7290.1 7291.9 7303.9 7304.3 6416.3 6594.8 6604.1 6652.1 6654.2 6358.0 6542.9 6549.0 6563.1 6681.2 6244.7 6275.8 6318.0 6320.2 6321.7 4462.7 4487.8 4607.3 4637.5 4642.3 4471.3 4566.1 4583.4 4602.6 4657.0 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | [#4] prototype.html Cycles:180 Average:4158.85 Median:4740.98 stddev:1188.44 (25.1%) stddev-sans-first:1156.01 09:19:49 INFO - PROCESS | 29108 | Values: 303.7 314.7 315.5 316.8 317.9 4778.8 4779.9 4794.5 4821.3 4825.6 4737.7 4744.2 4756.2 4773.0 4775.6 3656.4 3697.5 3720.1 3731.6 3818.0 3748.6 3757.0 3783.7 3793.5 3810.4 3608.0 3732.9 3738.7 3749.6 3789.2 3696.3 3766.2 3767.8 3775.9 3789.8 3361.9 3373.2 3379.6 3382.0 3385.8 4534.4 4542.6 4555.2 4560.7 4562.5 4184.6 4210.1 4316.2 4323.4 4354.7 4957.4 4973.3 4982.4 4993.6 5001.1 3682.6 3692.9 3700.9 3709.3 3721.2 4967.2 5196.4 5200.5 5223.0 5227.8 3731.6 3759.8 3769.6 3781.7 3788.2 3797.5 3825.3 3839.3 3842.0 3858.0 4273.6 4274.2 4299.9 4306.0 4326.7 4787.3 4984.9 5035.3 5036.6 5052.5 1289.5 1295.3 1297.2 1304.6 1314.9 857.4 881.7 919.2 920.6 935.7 4238.0 4251.9 4253.3 4258.4 4260.5 4914.5 4945.3 4975.3 4988.4 5026.3 4118.4 4226.6 4231.6 4243.7 4250.3 2599.3 2634.8 2652.4 2653.6 2672.5 4674.3 4678.0 4679.3 4685.6 4713.0 5062.9 5067.9 5086.4 5097.9 5141.7 4935.6 4950.4 4952.5 4962.4 4973.5 5169.9 5177.6 5189.8 5206.8 5221.9 4890.1 5084.5 5119.6 5130.8 5135.4 4880.0 4885.8 4896.4 4897.2 4909.9 3651.7 3671.9 3675.4 3688.4 3695.2 4948.5 5178.5 5201.3 5230.9 5233.3 4980.5 5011.9 5022.8 5027.0 5038.7 5003.6 5015.5 5032.2 5066.2 5068.4 4829.9 4948.8 4974.8 4990.8 4998.7 4778.9 4913.3 5044.7 5050.6 5057.0 4937.8 4960.2 4960.2 4960.5 4963.7 09:19:49 INFO - PROCESS | 29108 | 09:19:49 INFO - PROCESS | 29108 | [#5] yui.html Cycles:180 Average:12426.51 Median:42361.97 stddev:14022.90 (33.1%) stddev-sans-first:14034.74 09:19:49 INFO - PROCESS | 29108 | Values: 732.0 757.7 770.7 803.8 812.9 10206.8 10684.2 10696.6 10702.3 10714.4 11950.2 11976.6 11976.6 12002.7 12024.3 651.5 824.5 841.1 853.5 856.1 850.9 860.3 861.4 861.6 862.1 918.3 919.4 920.0 920.3 920.4 701.2 892.7 907.9 909.6 910.8 676.0 676.2 676.3 676.5 676.6 11374.5 11374.7 11412.3 11434.3 11438.2 11010.1 11112.3 11377.4 11422.1 11770.8 10226.2 10351.4 10472.5 10634.8 11019.9 4062.3 4134.5 4145.8 4156.6 4287.8 39683.0 40531.4 40604.2 42414.4 42675.6 9782.0 9857.0 9999.3 10066.0 10111.2 16941.4 16947.6 16950.9 17168.1 17265.9 6245.1 6337.2 6451.1 6546.2 6866.0 3527.9 3583.3 3627.9 3643.6 3661.8 2822.0 2827.3 2931.2 2931.7 2945.3 570.7 571.5 572.1 573.4 575.7 469.3 521.6 583.8 599.9 636.5 7903.4 7990.2 7998.2 8018.4 8054.8 566.3 580.7 593.7 608.5 620.5 3575.9 3647.6 3667.3 3701.6 3715.9 47802.5 51900.0 54217.0 55011.0 55635.7 43925.3 45017.0 46018.3 46287.3 46340.3 42216.0 42232.2 42309.6 42569.6 43028.8 7996.8 8018.5 8031.8 8080.3 8227.0 27048.2 27414.0 28067.4 29140.0 29334.9 36502.5 37149.8 37388.0 38143.0 38301.8 10464.7 10697.1 10791.1 10958.6 11000.8 9919.8 10007.9 10963.8 11046.3 11102.6 11032.8 11220.6 11394.7 11589.0 11749.7 10584.0 10739.9 11069.0 11330.0 11534.1 10413.3 10630.5 10681.5 10798.4 10990.3 9427.9 9565.6 9566.7 9848.3 9913.5 8780.4 8967.2 8982.4 9028.4 9107.7 09:19:49 INFO - PROCESS | 29108 | -------- Summary: end -------- 09:19:49 INFO - PROCESS | 29108 | 09:19:50 INFO - TEST-INFO | 29108: exit 0 09:19:50 INFO - TEST-OK | dromaeo_css | took 1120200ms 09:19:50 INFO - TEST-START | kraken 09:19:50 INFO - operating with platform_type : linux_ 09:19:50 INFO - Initialising browser for kraken test... 09:19:50 INFO - TEST-INFO | started process 29285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYlvd15/profile http://localhost:55657/getInfo.html) 09:19:55 INFO - PROCESS | 29285 | __metrics Screen width/height:1600/1200 09:19:55 INFO - PROCESS | 29285 | colorDepth:24 09:19:55 INFO - PROCESS | 29285 | Browser inner width/height: 1024/697 09:19:55 INFO - PROCESS | 29285 | __metrics 09:19:56 ERROR - PROCESS | 29285 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:19:57 INFO - TEST-INFO | 29285: exit 0 09:19:57 INFO - Browser initialized. 09:19:57 INFO - Running cycle 1/1 for kraken test... 09:19:57 INFO - TEST-INFO | started process 29365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYlvd15/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:09 INFO - PROCESS | 29365 | RSS: Main: 153870336 09:20:09 INFO - PROCESS | 29365 | 09:20:12 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55657/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:13 INFO - PROCESS | 29365 | RSS: Main: 210276352 09:20:13 INFO - PROCESS | 29365 | 09:20:16 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55657/tests/kraken/driver.html?testName=audio-dft) 09:20:17 INFO - PROCESS | 29365 | RSS: Main: 197763072 09:20:17 INFO - PROCESS | 29365 | 09:20:20 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55657/tests/kraken/driver.html?testName=audio-fft) 09:20:20 INFO - PROCESS | 29365 | RSS: Main: 266342400 09:20:20 INFO - PROCESS | 29365 | 09:20:23 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55657/tests/kraken/driver.html?testName=audio-oscillator) 09:20:23 INFO - PROCESS | 29365 | RSS: Main: 176173056 09:20:23 INFO - PROCESS | 29365 | 09:20:26 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55657/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:26 INFO - PROCESS | 29365 | RSS: Main: 179646464 09:20:26 INFO - PROCESS | 29365 | 09:20:30 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55657/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:30 INFO - PROCESS | 29365 | RSS: Main: 215810048 09:20:30 INFO - PROCESS | 29365 | 09:20:34 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55657/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:35 INFO - PROCESS | 29365 | RSS: Main: 214175744 09:20:35 INFO - PROCESS | 29365 | 09:20:38 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55657/tests/kraken/driver.html?testName=json-parse-financial) 09:20:38 INFO - PROCESS | 29365 | RSS: Main: 214851584 09:20:38 INFO - PROCESS | 29365 | 09:20:40 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55657/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:41 INFO - PROCESS | 29365 | RSS: Main: 205385728 09:20:41 INFO - PROCESS | 29365 | 09:20:42 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:43 INFO - PROCESS | 29365 | RSS: Main: 207515648 09:20:43 INFO - PROCESS | 29365 | 09:20:45 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:46 INFO - PROCESS | 29365 | RSS: Main: 216875008 09:20:46 INFO - PROCESS | 29365 | 09:20:49 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:50 INFO - PROCESS | 29365 | RSS: Main: 222875648 09:20:50 INFO - PROCESS | 29365 | 09:20:53 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:53 INFO - PROCESS | 29365 | RSS: Main: 218714112 09:20:53 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | Cycle 1(1): loaded http://localhost:55657/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55657/tests/kraken/driver.html?testName=ai-astar) 09:20:55 INFO - PROCESS | 29365 | RSS: Main: 265052160 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | __start_tp_report 09:20:55 INFO - PROCESS | 29365 | _x_x_mozilla_page_load 09:20:55 INFO - PROCESS | 29365 | _x_x_mozilla_page_load_details 09:20:55 INFO - PROCESS | 29365 | |i|pagename|runs| 09:20:55 INFO - PROCESS | 29365 | |0;ai-astar;115;109;114;114;114;114;113;114;113;114 09:20:55 INFO - PROCESS | 29365 | |1;audio-beat-detection;158;139;154;218;132;159;160;137;193;132 09:20:55 INFO - PROCESS | 29365 | |2;audio-dft;162;161;163;162;162;161;161;160;161;161 09:20:55 INFO - PROCESS | 29365 | |3;audio-fft;75;76;78;159;77;75;77;75;135;75 09:20:55 INFO - PROCESS | 29365 | |4;audio-oscillator;93;94;155;94;92;93;128;93;93;138 09:20:55 INFO - PROCESS | 29365 | |5;imaging-gaussian-blur;116;116;116;117;117;117;116;117;116;116 09:20:55 INFO - PROCESS | 29365 | |6;imaging-darkroom;145;146;146;145;145;146;146;146;146;146 09:20:55 INFO - PROCESS | 29365 | |7;imaging-desaturate;94;95;95;94;96;95;95;95;95;95 09:20:55 INFO - PROCESS | 29365 | |8;json-parse-financial;64;65;65;63;63;67;66;116;63;63 09:20:55 INFO - PROCESS | 29365 | |9;json-stringify-tinderbox;63;64;64;64;64;65;63;64;64;64 09:20:55 INFO - PROCESS | 29365 | |10;stanford-crypto-aes;82;88;82;82;82;83;83;83;83;89 09:20:55 INFO - PROCESS | 29365 | |11;stanford-crypto-ccm;132;141;133;131;131;139;139;130;199;147 09:20:55 INFO - PROCESS | 29365 | |12;stanford-crypto-pbkdf2;179;173;183;174;173;176;171;178;172;172 09:20:55 INFO - PROCESS | 29365 | |13;stanford-crypto-sha256-iterative;67;68;70;67;67;72;68;66;66;67 09:20:55 INFO - PROCESS | 29365 | __end_tp_report 09:20:55 INFO - PROCESS | 29365 | __start_cc_report 09:20:55 INFO - PROCESS | 29365 | _x_x_mozilla_cycle_collect,2082 09:20:55 INFO - PROCESS | 29365 | __end_cc_report 09:20:55 INFO - PROCESS | 29365 | __startTimestamp1462810855714__endTimestamp 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | ------- Summary: start ------- 09:20:55 INFO - PROCESS | 29365 | Number of tests: 14 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:1.65 (1.4%) stddev-sans-first:1.64 09:20:55 INFO - PROCESS | 29365 | Values: 115.0 109.0 114.0 114.0 114.0 114.0 113.0 114.0 113.0 114.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#1] audio-beat-detection Cycles:10 Average:158.20 Median:158.50 stddev:27.89 (17.6%) stddev-sans-first:29.58 09:20:55 INFO - PROCESS | 29365 | Values: 158.0 139.0 154.0 218.0 132.0 159.0 160.0 137.0 193.0 132.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 09:20:55 INFO - PROCESS | 29365 | Values: 162.0 161.0 163.0 162.0 162.0 161.0 161.0 160.0 161.0 161.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#3] audio-fft Cycles:10 Average:90.20 Median:75.50 stddev:30.48 (40.4%) stddev-sans-first:31.83 09:20:55 INFO - PROCESS | 29365 | Values: 75.0 76.0 78.0 159.0 77.0 75.0 77.0 75.0 135.0 75.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#4] audio-oscillator Cycles:10 Average:107.30 Median:93.00 stddev:23.69 (25.5%) stddev-sans-first:24.56 09:20:55 INFO - PROCESS | 29365 | Values: 93.0 94.0 155.0 94.0 92.0 93.0 128.0 93.0 93.0 138.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:20:55 INFO - PROCESS | 29365 | Values: 116.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 09:20:55 INFO - PROCESS | 29365 | Values: 145.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 146.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 09:20:55 INFO - PROCESS | 29365 | Values: 94.0 95.0 95.0 94.0 96.0 95.0 95.0 95.0 95.0 95.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#8] json-parse-financial Cycles:10 Average:69.50 Median:64.50 stddev:16.40 (25.4%) stddev-sans-first:17.27 09:20:55 INFO - PROCESS | 29365 | Values: 64.0 65.0 65.0 63.0 63.0 67.0 66.0 116.0 63.0 63.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 09:20:55 INFO - PROCESS | 29365 | Values: 63.0 64.0 64.0 64.0 64.0 65.0 63.0 64.0 64.0 64.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.00 stddev:2.58 (3.1%) stddev-sans-first:2.67 09:20:55 INFO - PROCESS | 29365 | Values: 82.0 88.0 82.0 82.0 82.0 83.0 83.0 83.0 83.0 89.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:139.00 stddev:20.71 (14.9%) stddev-sans-first:21.63 09:20:55 INFO - PROCESS | 29365 | Values: 132.0 141.0 133.0 131.0 131.0 139.0 139.0 130.0 199.0 147.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:175.00 stddev:3.84 (2.2%) stddev-sans-first:3.81 09:20:55 INFO - PROCESS | 29365 | Values: 179.0 173.0 183.0 174.0 173.0 176.0 171.0 178.0 172.0 172.0 09:20:55 INFO - PROCESS | 29365 | 09:20:55 INFO - PROCESS | 29365 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:1.87 (2.8%) stddev-sans-first:1.96 09:20:55 INFO - PROCESS | 29365 | Values: 67.0 68.0 70.0 67.0 67.0 72.0 68.0 66.0 66.0 67.0 09:20:55 INFO - PROCESS | 29365 | -------- Summary: end -------- 09:20:55 INFO - PROCESS | 29365 | 09:20:56 INFO - TEST-INFO | 29365: exit 0 09:20:56 INFO - TEST-OK | kraken | took 66094ms 09:20:56 INFO - SUITE-END | took 1186s 09:20:56 INFO - Completed test suite (00:19:46) 09:20:56 INFO - javascript benchmark 09:20:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2637.6702912135984, 2648.24525010372, 2696.460707858429, 2698.338906967792, 2707.269037359913, 4609.631229501636, 4612.86161415158, 4690.249560070385, 4692.976535117328, 4728.101660684471, 4671.135839869627, 4694.6244300455965, 4719.268513380432, 4747.0, 4812.205986012304, 4347.260965635849, 4350.086481838802, 4355.390245365651, 4400.867973960779, 4410.029793445437, 4353.62994145498, 4469.44131983502, 4469.575390337913, 4498.50516443191, 4501.954980450203, 4407.449068866392, 4413.0732546165345, 4443.111377724452, 4450.488193857699, 4508.571685689854, 4273.55127711592, 4292.034292284242, 4309.4397728295435, 4314.870553883357, 4316.892077698051, 4261.531231564525, 4273.380359847804, 4301.118270754488, 4315.093830295642, 4351.108022855307, 4666.370040044584, 4690.0, 4710.387649605564, 4714.151452738506, 4715.056988602259, 4918.286848406997, 4940.357753492023, 5041.747912604392, 5043.142665746832, 5045.293658887758, 5084.186530155157, 5096.235564665293, 5123.077845987721, 5140.357455318085, 5143.974280128576, 4691.179043667379, 4788.473267940523, 4791.329213910055, 4795.184818580849, 4820.6143508519235, 5082.237664350377, 5115.488451154854, 5148.3564554430695, 5162.045021671003, 5242.659227150223, 4937.679050861682, 4957.578605818474, 4996.3504744383, 5022.096022715873, 5024.648274620815, 4981.9252711209365, 5052.469490703424, 5053.0904437200925, 5053.267276244924, 5066.341375621146, 4999.600031997505, 5052.267421223975, 5062.696238225718, 5103.489651034867, 5119.641625086283, 5050.191969284971, 5059.494050594911, 5064.290999260106, 5076.74616269185, 5103.336566246364, 4354.56454354562, 4361.738295702268, 4372.1692878353015, 4379.693421460467, 4424.933625995613, 3677.3380791457794, 3728.179800443898, 3752.512173417438, 3797.715171362104, 3822.369309063973, 4283.20760659279, 4284.871453856389, 4288.378185163197, 4288.742675439484, 4333.393324934512, 5050.924236136461, 5058.114829904752, 5059.519345662156, 5059.62052846038, 5097.821576244888, 4204.705670603028, 4306.74159550428, 4355.346697995326, 4358.171947329997, 4374.4094547236355, 4641.721496710208, 4653.441587009513, 4661.137689527448, 4668.39310889589, 4711.905761884812, 4617.79219935097, 4842.6368022397755, 4857.514248575114, 4862.635302352267, 4910.729909854992, 5036.6726162799305, 5038.596912247085, 5050.090983622983, 5088.516590923857, 5118.38579370478, 4792.065547218398, 4806.519348065305, 4811.759412029454, 4826.889815342421, 4863.465018847854, 4785.521447855187, 4995.892354800937, 5029.29589857413, 5045.369328833895, 5091.516305950929, 5163.941392014553, 5180.637355385087, 5210.791568337224, 5240.109181439241, 5260.658057226268, 5040.672356296829, 5046.369203849519, 5058.721770302669, 5060.544550990428, 5112.258722485144, 4814.566688998007, 4869.829555965524, 5051.0, 5058.924116138187, 5064.974675126601, 4820.807167713252, 4851.393575803025, 4886.267059940895, 4896.951030489649, 4904.632152588499, 4936.0, 4944.3572335596145, 4946.35697359341, 4988.55103040728, 4997.675151115166, 4751.216049351819, 4860.635452341159, 4901.264810278344, 4915.0415668944215, 4933.432655244693], "value": 4665.493413961583, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.508679905083, 590.1560768101618, 595.3599880128861, 600.9008513595254, 606.8846919085373, 26854.597181042274, 41539.376909346385, 43051.78474107633, 43155.56844431551, 43263.0, 41602.0, 42116.57883421165, 42175.78912105435, 42181.0, 42236.36645450313, 7469.551826890377, 8039.115697273295, 8190.836183276331, 10187.0, 10471.84292235617, 10228.284020118595, 10280.537375818069, 10358.844617330746, 10363.119134873545, 10368.222383321243, 10283.074523292902, 10292.330998485075, 10341.121004714609, 10354.430506322149, 10367.0, 7484.101907771075, 10120.190384769201, 10344.586216551328, 10407.739806504822, 10772.784544309148, 10147.036031577061, 10208.79582408355, 10214.336068155546, 10216.080552750289, 10232.0, 38588.0, 38823.61176388257, 38897.0, 38923.0, 38981.0, 3322.3355328934067, 3382.594088709346, 3487.2676737885076, 3623.3296840084668, 3962.1481381503113, 29331.85334073316, 29511.409771804658, 29941.40117197644, 29982.0, 30045.248868778235, 2972.868486008009, 3071.9232019199476, 3087.691230876917, 3151.8266495342746, 3153.668864769212, 241595.0, 242354.0, 242522.0, 242985.0, 243108.0, 54952.17571736427, 55359.0, 55384.44615553873, 55392.0, 55422.72288638531, 57294.0, 57319.713401432724, 57435.13847292294, 57548.547087174906, 57578.712106440034, 12640.87359126397, 14074.2962851857, 14153.5046273382, 14248.287585620885, 14262.0, 9892.505374731234, 10241.283110182365, 10249.282550221562, 10334.379937203792, 10499.317544359592, 16067.276972536263, 16070.196490175444, 16195.757063644054, 16221.594460138354, 16226.756598651029, 5573.0, 5792.536597072224, 5848.3274423441, 5927.911081333784, 5985.521158307325, 907.3920473282916, 1011.9494025298706, 1013.5185786751234, 1054.6414219165367, 1055.2349546578669, 28721.281967950552, 28808.855955720086, 28974.27564310867, 29051.709482905324, 29215.685294161813, 1016.3749294184023, 1045.9372437653765, 1066.4774260612244, 1073.9248252622397, 1085.7231405991422, 15315.846841531666, 15398.769018464733, 15433.691326173413, 15596.220188990732, 15655.295511702001, 367185.0, 368957.0, 369706.0, 370318.0, 371014.0, 49635.751821240665, 50534.49465505371, 50556.0, 50587.0, 50599.24101138486, 1120.2326406411635, 1133.1048471707259, 1137.7667578146625, 1243.421808858885, 1246.663400881775, 22986.310410687707, 27645.723542764314, 28171.71828281691, 29684.0, 30166.396672066876, 47678.28482572833, 48485.0, 48543.5145648539, 48595.5140448591, 48721.756391217816, 328594.0, 330846.0, 331747.0, 331798.0, 332903.0, 35536.0, 36847.0, 37479.625203747615, 37537.81231093827, 37538.37231393776, 43135.78432107945, 43350.13299734051, 43609.781951090044, 44628.55371446244, 44681.32978005397, 37400.43899341457, 37888.43167352547, 38236.61763382442, 38281.4257786139, 38346.42480362853, 36109.27781444304, 36643.81678091593, 37208.44187337138, 37321.253574928895, 37361.81319093496, 33722.83138584292, 36851.44722829106, 37014.44478332881, 37068.814655926544, 37114.814425927696, 32045.35909281848, 33283.83358083194, 33641.327173456884, 34156.65843341634, 34194.658053419145, 25449.61825572655, 25501.0, 25799.742002579733, 25935.0, 25963.87018064973], "value": 18489.545697386748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.8228198290257, 383.75247965062533, 385.0777388155367, 401.71478250442186, 403.16142423758583, 13061.542846000391, 13702.725945481085, 15854.365825366986, 17645.55886102848, 17704.380346687853, 15749.921250393762, 16173.35306587738, 16488.09315487648, 17849.107544622748, 17852.82147178528, 4631.143238500871, 5118.0, 5252.658577192489, 5281.075811732951, 5313.495217954288, 5134.25553294772, 5142.537171654551, 5195.0, 5236.7119808410525, 5281.656692314988, 5160.406553246368, 5216.321878155834, 5223.451537588556, 5226.320578324812, 5230.555402790767, 5023.447420783711, 5125.666831655931, 5130.384353877558, 5158.019976204528, 5204.401493828221, 4409.206342858284, 4556.384888040106, 4599.356090147382, 4599.655025873073, 4649.488556258809, 19052.237910483564, 20239.79760202394, 20775.792242077536, 20864.791352086435, 21076.683849742265, 1920.0207893974034, 1921.03948025987, 1946.698261769428, 1980.5840773437596, 2101.2225476573612, 9556.952215238949, 9701.320907536476, 9792.363496372713, 9942.602295908155, 9999.400035997864, 1775.1656721340948, 1784.8215178482048, 1811.5742800441885, 1878.3144152384418, 1888.5845114075019, 64842.0, 68695.31304686939, 68792.31207687959, 69223.65388173077, 69903.0, 10266.691999240034, 10271.794564108675, 10328.896711032943, 10372.0, 10627.096696780705, 12352.938235308766, 13100.868991309964, 14538.200398977946, 14588.635284117981, 14594.34325455357, 7423.331900129015, 7563.394928405824, 7608.961955190189, 7710.498817576951, 7710.691572337044, 7179.533330333512, 7195.748148814766, 7199.640017999184, 7295.963520182365, 7314.561126332437, 4508.256137737237, 4527.818887244539, 4530.29780384041, 4537.546245375502, 4580.358749775034, 1840.0163645707162, 1840.1719226348198, 1873.1102726204945, 1929.9807001930083, 1940.3305859478457, 845.9873101903477, 893.1871996483169, 894.8836651235298, 1005.9748506287401, 1007.4811472091878, 16500.25748841281, 16936.83063169377, 17094.829051709323, 17558.29766809313, 17580.736288955675, 935.3265648732902, 935.3639525122982, 947.7820101376722, 953.2421724728719, 968.6028728221395, 9452.291078169164, 9459.76350591227, 9729.416235025921, 9775.413475191512, 9920.751981200383, 407372.0, 408816.0, 410116.0, 412421.0, 414762.0, 71709.28290717026, 76062.61968690231, 76915.23084769193, 80275.0, 80577.19422805814, 964.71058682395, 972.6741541583507, 988.6045581767495, 1019.0879163149089, 1032.1794173631981, 16040.518784436019, 16047.919760401122, 16054.357825686842, 16160.67678646444, 16193.59516012109, 36817.0, 37069.81465092765, 37401.81299093487, 37779.0, 37976.620233798414, 50830.237546436096, 50904.490955089976, 52783.0, 52802.207966881295, 53722.925541489734, 7535.6985720570565, 7591.582462964369, 7776.961115194388, 7789.532628042336, 7883.369330453435, 10572.259941803999, 11662.358570278717, 11908.880911190778, 12043.036557075238, 12138.393080345772, 10259.179265658879, 10450.268481206547, 10455.634052808115, 10501.921324723673, 10632.468376580986, 10369.792604148026, 10382.3251488653, 10447.425391603534, 10556.419396933243, 10660.520276587727, 10468.581256749645, 10633.255672102878, 10755.085817705583, 10985.890141098806, 10988.615398461016, 7576.0, 7619.619019048914, 7813.296803287732, 7855.978722766004, 8008.719694810654, 10149.188064954638, 10564.524596393027, 10596.0, 10666.73333166677, 10681.412522311348], "value": 8332.00437357762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1251.4743807600807, 1443.5380678182983, 1460.3501441858375, 1614.9031058136513, 1761.8590512758988, 6207.534434917382, 7153.713851445942, 7373.483856130067, 7381.667824947876, 7594.696212151507, 7375.151857536385, 7506.7372641957545, 7512.96243518783, 7515.54906705598, 7529.134149572801, 2222.2555443926303, 2253.5605556916407, 2253.9436514087197, 2256.0862850545554, 2256.165218869021, 2177.803997640212, 2208.8564243324213, 2223.4441389652507, 2224.2659922225707, 2227.498812767131, 2176.1621775616422, 2180.1715348167672, 2183.585118827418, 2186.5517568898417, 2224.4994876152896, 2162.697222388867, 2188.3872515695552, 2196.5606878624258, 2196.648536234195, 2199.967000494994, 2161.103142195987, 2164.7402311722694, 2166.523364859728, 2167.2522979572022, 2167.9132834686593, 7314.707411703525, 7409.481336306407, 7462.365698915544, 7470.3650189733935, 7472.252774722539, 4411.691181617288, 4571.542845715435, 4576.977115114437, 4584.01443689605, 4612.285095810154, 2954.6454425468864, 2974.8215107093642, 3001.639803223627, 3030.9848450757604, 3087.783855130142, 967.3083745122201, 970.3935040599625, 970.9514524273828, 986.1716158426956, 989.648674720471, 9596.280278979035, 9709.36889102206, 9715.36850104741, 9739.464329461876, 9795.853062204073, 3898.96101038986, 3986.980065099656, 4049.331860243086, 4076.592340765959, 4081.0, 7794.766157015299, 7895.171007044292, 7898.170692077364, 7913.089994650573, 7915.050193976645, 4510.503844577094, 4565.5206203348835, 4568.885777855514, 4569.29175977724, 4570.3829982951875, 3662.285854258447, 3950.3679411294056, 4093.385992101149, 4242.787860606957, 4250.022494826208, 3284.392387408337, 3336.799792012487, 3371.7976921384798, 3384.272381438003, 3421.4354631486026, 975.5902520941222, 1034.4207243943415, 1040.0483557544762, 1040.287403128844, 1044.1803184500131, 850.9702160424356, 851.5188918261163, 862.4868203418956, 884.1644645809648, 888.4447220487195, 4723.905521889543, 4757.119932812441, 4791.209450440639, 4968.403791545038, 4973.179425394769, 400.30946617085294, 410.15712710380456, 414.4860373137348, 430.80613723824405, 437.2915876280446, 3391.5251864738957, 3415.470602056685, 3443.793372397614, 3477.2697733475748, 3546.0957455848593, 8460.8730869037, 9115.589798459085, 9123.270138388913, 9128.634854605874, 9240.399374040666, 8985.101489851093, 9130.406523575946, 9147.0, 9168.0, 9187.2190863776, 6992.860142797115, 7044.682989265495, 7117.039199708077, 7160.140783105957, 7183.640817959081, 4934.457209706858, 5240.449752775904, 5304.706029209837, 5434.83695489136, 5495.670259784426, 7999.6400161994015, 8131.552764598004, 8197.344212463107, 8203.0566484855, 8328.791780205544, 8195.754127376189, 8458.661653534036, 8683.2185103341, 8717.520536370306, 8773.473591584525, 5892.499137573354, 6398.90401643985, 6546.509011824037, 6610.504212184009, 6672.866542669217, 7056.6471676417, 7290.05229320185, 7291.927080729125, 7303.926960730325, 7304.342609165201, 6416.262129855127, 6594.802155935329, 6604.14146160996, 6652.135222421054, 6654.234763002317, 6358.0, 6542.901856472062, 6549.0, 6563.113979612691, 6681.231658359351, 6244.656543889948, 6275.811725648238, 6317.96841015792, 6320.209973753281, 6321.747130114744, 4462.732236065846, 4487.775611219491, 4607.331936869068, 4637.466691330405, 4642.280446530793, 4471.262241730078, 4566.109608626286, 4583.427071616048, 4602.585767280827, 4657.045305712389], "value": 3942.6258766993815, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.7084398976982, 314.69631805307876, 315.50623274575304, 316.8035817792968, 317.8966835778373, 4778.784954677039, 4779.856604301868, 4794.544518270763, 4821.252705830598, 4825.638077144211, 4737.739424331669, 4744.217204161318, 4756.167670657638, 4773.02153058623, 4775.617950563956, 3656.3966945454035, 3697.500837386945, 3720.1443667956387, 3731.5708693500187, 3818.026403267163, 3748.6063963283736, 3756.9812150939206, 3783.659470647655, 3793.4878791363226, 3810.371288737355, 3607.963920360802, 3732.8693495727657, 3738.663520283174, 3749.568799588041, 3789.2232092420986, 3696.2792255510185, 3766.152615661482, 3767.7550959187565, 3775.8672398280405, 3789.829457674411, 3361.8823341182942, 3373.2339481625077, 3379.5775528058994, 3381.9830900845586, 3385.765394839394, 4534.433195850519, 4542.568455996675, 4555.157295900269, 4560.657950653681, 4562.498125206224, 4184.581541845791, 4210.0948296116485, 4316.179925814085, 4323.3947247385395, 4354.695171338008, 4957.429895562021, 4973.254011898208, 4982.377202849644, 4993.550580447741, 5001.099802035632, 3682.558093028854, 3692.9446058308877, 3700.870469533553, 3709.295233905549, 3721.1999420124803, 4967.230079337708, 5196.402413722395, 5200.517852412082, 5223.0, 5227.764750586232, 3731.5708693500596, 3759.793211373401, 3769.6418840210135, 3781.730961423124, 3788.1666033472593, 3797.518967602637, 3825.3114439401174, 3839.2897313997, 3841.961580384216, 3857.9807100964877, 4273.615374616237, 4274.166537525215, 4299.871003869889, 4305.978470107692, 4326.718763280377, 4787.257975013808, 4984.925226121611, 5035.295058691786, 5036.597072234213, 5052.469490703496, 1289.5234955975357, 1295.2940647347218, 1297.1503665099376, 1304.6151385341157, 1314.9474021039243, 857.4340934983006, 881.7442941547008, 919.1819280840058, 920.5949382271779, 935.691221896786, 4238.0, 4251.87244382669, 4253.2769429197115, 4258.361245813153, 4260.467441569804, 4914.483979182206, 4945.258211268267, 4975.253711943237, 4988.35151430319, 5026.271190677331, 4118.423420721101, 4226.598473145046, 4231.576842315805, 4243.745375277494, 4250.256205164084, 2599.2981894888276, 2634.8287361321454, 2652.4429869727574, 2653.5886937524715, 2672.5189465896324, 4674.252119660838, 4677.95322046782, 4679.321498382716, 4685.5782979532005, 4713.0, 5062.949370506321, 5067.923981140286, 5086.415062267813, 5097.8725531861255, 5141.711938187471, 4935.6051515877925, 4950.43070046942, 4952.529509696573, 4962.429320628102, 4973.477784832613, 5169.948300516947, 5177.611679124156, 5189.780401605695, 5206.765695543634, 5221.9216711748595, 4890.119778439915, 5084.466131056186, 5119.641625086358, 5130.846074617767, 5135.35808023997, 4879.95120048795, 4885.804567817389, 4896.36347274852, 4897.16748152822, 4909.9018019640125, 3651.653092956165, 3671.908202294964, 3675.393560062667, 3688.4098544232793, 3695.2055308108884, 4948.455669876239, 5178.456262092577, 5201.297824793604, 5230.921536177037, 5233.345831771028, 4980.5019498049905, 5011.924821127613, 5022.84931452057, 5026.953302591449, 5038.722870242172, 5003.649744517995, 5015.523525265094, 5032.245163225399, 5066.2400639902835, 5068.403452912321, 4829.9275510868065, 4948.752562371795, 4974.7512624369365, 4990.8253211137435, 4998.6500944933505, 4778.928316075331, 4913.304909806111, 5044.697318160922, 5050.646454748279, 5056.974715126401, 4937.8271760488215, 4960.206366981266, 4960.231164169415, 4960.454350021567, 4963.6525443218625], "value": 3789.231866268874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.0154392342298, 757.6628400361839, 770.7032792374938, 803.8313643273751, 812.8577498937684, 10206.795864082724, 10684.19868509861, 10696.572137114515, 10702.250842441032, 10714.410707411089, 11950.163488555805, 11976.580819671313, 11976.580819671313, 12002.699932501691, 12024.33866137362, 651.4657980456028, 824.4805772363433, 841.0958219913603, 853.5476197615274, 856.0540602634093, 850.8681154421074, 860.2945584620613, 861.418542483824, 861.5606040919145, 862.068965517243, 918.3020904112889, 919.4437365393941, 919.9770005749876, 920.3373571028882, 920.4063379120463, 701.2426579294375, 892.7411050795295, 907.945523268606, 909.6225066597354, 910.7586489580267, 676.0163961436148, 676.2223443040493, 676.3473248315399, 676.5331920974512, 676.6177109932921, 11374.488148033359, 11374.658760237206, 11412.31526108436, 11434.256773309708, 11438.19932604718, 11010.119190464744, 11112.277701949348, 11377.374244416555, 11422.086233101332, 11770.823437648356, 10226.181905447545, 10351.430671313075, 10472.464342313802, 10634.840477392845, 11019.944900275528, 4062.2687916175078, 4134.462519872397, 4145.751254924714, 4156.625903668654, 4287.764172970486, 39683.0, 40531.39202911959, 40604.187916241506, 42414.36378454325, 42675.57324426747, 9781.951090244504, 9856.950715246378, 9999.25005624581, 10066.0, 10111.191104711605, 16941.407050753165, 16947.576310592332, 16950.91524542394, 17168.141592920303, 17265.91367043182, 6245.125682404467, 6337.2395312562785, 6451.064595633607, 6546.18172728409, 6866.0, 3527.9118022049656, 3583.2654305867236, 3627.9470874820727, 3643.562772467321, 3661.835217415223, 2821.9858900705367, 2827.2649111231226, 2931.1646180838356, 2931.721486458783, 2945.27840679035, 570.7460180219763, 571.4942276085611, 572.1275055540334, 573.4265734265734, 575.7150210645674, 469.28434137939917, 521.5775222070135, 583.8073435766187, 599.9302406696913, 636.4939872801134, 7903.407244456689, 7990.216875889045, 7998.160193179749, 8018.358531317481, 8054.758357249292, 566.3175873072937, 580.6864293281665, 593.6645795125827, 608.5435923057706, 620.4632992461488, 3575.856965721394, 3647.5799901335995, 3667.3032123896373, 3701.648343407372, 3715.9442608360896, 47802.5219747805, 51900.0, 54217.0, 55011.0, 55635.72182139063, 43925.341119883225, 45017.0, 46018.30972535415, 46287.30569041467, 46340.3048954266, 42216.0, 42232.15535689269, 42309.57690423056, 42569.57430425718, 43028.78485607615, 7996.760097197093, 8018.4787988780545, 8031.83936321282, 8080.313173380094, 8226.958865205876, 27048.1885543434, 27414.0, 28067.43865122727, 29140.0, 29334.853325733235, 36502.45246321254, 37149.814250928575, 37388.0, 38143.0, 38301.80849095848, 10464.738381540217, 10697.14422846186, 10791.1367090631, 10958.56165753361, 11000.834987475355, 9919.764416787213, 10007.949960250153, 10963.780724385628, 11046.281991670516, 11102.61140860066, 11032.779344413228, 11220.55117795279, 11394.658160255205, 11589.0, 11749.706257343634, 10584.0, 10739.892601073889, 11069.0, 11330.036946859609, 11534.077273818242, 10413.271071024956, 10630.468476575985, 10681.46592670379, 10798.40608766494, 10990.285631433931, 9427.905720942703, 9565.569549370155, 9566.712998609775, 9848.261380396641, 9913.45475998798, 8780.429272097548, 8967.192952634294, 8982.416142950688, 9028.36801423894, 9107.681231156877], "value": 5923.396812457585, "unit": "score"}], "name": "dromaeo_css", "value": 6318.139412724483, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 109.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 139.0, 154.0, 218.0, 132.0, 159.0, 160.0, 137.0, 193.0, 132.0], "value": 158.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 162.0, 162.0, 161.0, 161.0, 160.0, 161.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 78.0, 159.0, 77.0, 75.0, 77.0, 75.0, 135.0, 75.0], "value": 90.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 155.0, 94.0, 92.0, 93.0, 128.0, 93.0, 93.0, 138.0], "value": 107.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 63.0, 63.0, 67.0, 66.0, 116.0, 63.0, 63.0], "value": 69.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 64.0, 65.0, 63.0, 64.0, 64.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 82.0, 82.0, 82.0, 83.0, 83.0, 83.0, 83.0, 89.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 141.0, 133.0, 131.0, 131.0, 139.0, 139.0, 130.0, 199.0, 147.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 173.0, 183.0, 174.0, 173.0, 176.0, 171.0, 178.0, 172.0, 172.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 67.0, 67.0, 72.0, 68.0, 66.0, 66.0, 67.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1589.7, "alertThreshold": 2.0}]} 09:20:56 INFO - Return code: 0 09:20:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:20:57 ERROR - # TBPL SUCCESS # 09:20:57 INFO - Running post-action listener: _resource_record_post_action 09:20:57 INFO - ##### 09:20:57 INFO - ##### Finished run-tests step (success) 09:20:57 INFO - ##### 09:20:57 INFO - Running post-run listener: _resource_record_post_run 09:20:58 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 527085568; Read time: 28; Write time: 1244396 09:20:58 INFO - install - Wall time: 16s; CPU: 18.0%; Read bytes: 0; Write bytes: 235491328; Read time: 0; Write time: 750180 09:20:58 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291594240; Read time: 28; Write time: 494216 09:20:58 INFO - Running post-run listener: _upload_blobber_files 09:20:58 INFO - Blob upload gear active. 09:20:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:58 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 09:20:58 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:58 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:59 INFO - (blobuploader) - INFO - Done attempting. 09:20:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:21:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:00 INFO - (blobuploader) - INFO - Done attempting. 09:21:00 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:00 INFO - Return code: 0 09:21:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/63e2bdbfd514215a4abecfc978533c721215a2be14350117f2f79d8060b7cee6c2b7730eb8cc1154d875b9e640c96dbe1dc103510ccda184c8de02c75607eae2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/11e8d12c57c8d695374ae665756d478db5d2d8ce07d249cbde5788706ce410162ac6c2c550599142456cea3e907179bacef04933435142c8ffea97806525011e"} 09:21:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:00 INFO - Contents: 09:21:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/63e2bdbfd514215a4abecfc978533c721215a2be14350117f2f79d8060b7cee6c2b7730eb8cc1154d875b9e640c96dbe1dc103510ccda184c8de02c75607eae2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/11e8d12c57c8d695374ae665756d478db5d2d8ce07d249cbde5788706ce410162ac6c2c550599142456cea3e907179bacef04933435142c8ffea97806525011e"} 09:21:00 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:00 INFO - Copying logs to upload dir... 09:21:00 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.618606 ========= master_lag: 0.50 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-09 09:21:00.377288) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 09:21:00.381893) ========= 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=df17445c3590ff7897617a0e00000225-1462808584.324799-98337328 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-Non-PGO/sha512/63e2bdbfd514215a4abecfc978533c721215a2be14350117f2f79d8060b7cee6c2b7730eb8cc1154d875b9e640c96dbe1dc103510ccda184c8de02c75607eae2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/11e8d12c57c8d695374ae665756d478db5d2d8ce07d249cbde5788706ce410162ac6c2c550599142456cea3e907179bacef04933435142c8ffea97806525011e"} build_url:https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011254 build_url: 'https://queue.taskcluster.net/v1/task/PNK5tzmqQXCn7dy16TDHgw/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-Non-PGO/sha512/63e2bdbfd514215a4abecfc978533c721215a2be14350117f2f79d8060b7cee6c2b7730eb8cc1154d875b9e640c96dbe1dc103510ccda184c8de02c75607eae2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/11e8d12c57c8d695374ae665756d478db5d2d8ce07d249cbde5788706ce410162ac6c2c550599142456cea3e907179bacef04933435142c8ffea97806525011e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 09:21:00.406386) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 09:21:00.409804) ========= 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=df17445c3590ff7897617a0e00000225-1462808584.324799-98337328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 09:21:00.459274) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 09:21:00.462624) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 09:21:00.683754) ========= ========= Total master_lag: 0.69 =========