builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1461600929.95 results: success (0) buildid: 20160425083752 builduid: 08d4e1319d244485b787c038d1ac9858 revision: 352d365eb4eda3aab7c3680b0e80ce17e178b5ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:29.947868) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:29.949014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:29.949287) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:29.969319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:29.969571) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 09:15:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-04-25 09:15:30 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120015 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.102480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.102804) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015620 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.152252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.152582) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 352d365eb4eda3aab7c3680b0e80ce17e178b5ee --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 352d365eb4eda3aab7c3680b0e80ce17e178b5ee --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=5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 09:15:30,623 truncating revision to first 12 chars 2016-04-25 09:15:30,623 Setting DEBUG logging. 2016-04-25 09:15:30,623 attempt 1/10 2016-04-25 09:15:30,623 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/352d365eb4ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 09:15:30,875 unpacking tar archive at: fx-team-352d365eb4ed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541473 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.706695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.707022) ========= script_repo_revision: 352d365eb4eda3aab7c3680b0e80ce17e178b5ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.707414) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.707672) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 09:15:30.714766) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-04-25 09:15:30.715044) ========= /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=5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:31 INFO - MultiFileLogger online at 20160425 09:15:31 in /builds/slave/test 09:15:31 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:15:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:31 INFO - {'append_to_log': False, 09:15:31 INFO - 'base_work_dir': '/builds/slave/test', 09:15:31 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:15:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:31 INFO - 'branch': 'Fx-Team-Non-PGO', 09:15:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:31 INFO - 'config_files': ('talos/linux_config.py',), 09:15:31 INFO - 'default_actions': ('clobber', 09:15:31 INFO - 'read-buildbot-config', 09:15:31 INFO - 'download-and-extract', 09:15:31 INFO - 'populate-webroot', 09:15:31 INFO - 'create-virtualenv', 09:15:31 INFO - 'install', 09:15:31 INFO - 'run-tests'), 09:15:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:31 INFO - 'download_minidump_stackwalk': True, 09:15:31 INFO - 'download_symbols': 'ondemand', 09:15:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:31 INFO - 'installer_path': 'installer.exe', 09:15:31 INFO - 'log_level': 'info', 09:15:31 INFO - 'log_name': 'talos', 09:15:31 INFO - 'log_to_console': True, 09:15:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:31 INFO - 'opt_config_files': (), 09:15:31 INFO - 'pip_index': False, 09:15:31 INFO - 'sps_profile': False, 09:15:31 INFO - 'sps_profile_interval': 0, 09:15:31 INFO - 'suite': 'dromaeojs', 09:15:31 INFO - 'system_bits': '32', 09:15:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:31 INFO - 'title': 'talos-linux64-ix-011', 09:15:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:31 INFO - 'use_talos_json': True, 09:15:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:31 INFO - 'work_dir': 'build'} 09:15:31 INFO - ##### 09:15:31 INFO - ##### Running clobber step. 09:15:31 INFO - ##### 09:15:31 INFO - Running pre-action listener: _resource_record_pre_action 09:15:31 INFO - Running main action method: clobber 09:15:31 INFO - rmtree: /builds/slave/test/build 09:15:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:31 INFO - Running post-action listener: _resource_record_post_action 09:15:31 INFO - ##### 09:15:31 INFO - ##### Running read-buildbot-config step. 09:15:31 INFO - ##### 09:15:31 INFO - Running pre-action listener: _resource_record_pre_action 09:15:31 INFO - Running main action method: read_buildbot_config 09:15:31 INFO - Using buildbot properties: 09:15:31 INFO - { 09:15:31 INFO - "project": "", 09:15:31 INFO - "product": "firefox", 09:15:31 INFO - "script_repo_revision": "production", 09:15:31 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:15:31 INFO - "repository": "", 09:15:31 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:15:31 INFO - "buildid": "20160425083752", 09:15:31 INFO - "pgo_build": "False", 09:15:31 INFO - "basedir": "/builds/slave/test", 09:15:31 INFO - "buildnumber": 2883, 09:15:31 INFO - "slavename": "talos-linux64-ix-011", 09:15:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:15:31 INFO - "platform": "ubuntu64_hw", 09:15:31 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:15:31 INFO - "branch": "fx-team", 09:15:31 INFO - "repo_path": "integration/fx-team", 09:15:31 INFO - "revision": "352d365eb4eda3aab7c3680b0e80ce17e178b5ee", 09:15:31 INFO - "stage_platform": "linux64", 09:15:31 INFO - "builduid": "08d4e1319d244485b787c038d1ac9858", 09:15:31 INFO - "slavebuilddir": "test" 09:15:31 INFO - } 09:15:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 09:15:31 INFO - Running post-action listener: _resource_record_post_action 09:15:31 INFO - ##### 09:15:31 INFO - ##### Running download-and-extract step. 09:15:31 INFO - ##### 09:15:31 INFO - Running pre-action listener: _resource_record_pre_action 09:15:31 INFO - Running main action method: download_and_extract 09:15:31 INFO - mkdir: /builds/slave/test/build/tests 09:15: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:15:31 INFO - https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:15:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:15:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:15:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:15:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:15:33 INFO - Downloaded 1361 bytes. 09:15:33 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:15:33 INFO - Using the following test package requirements: 09:15:33 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:33 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:15:33 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:15:33 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'jsshell-linux-x86_64.zip'], 09:15:33 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:15:33 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:33 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:15:33 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:15:33 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:15:33 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:33 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:15:33 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:33 INFO - https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:15:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:15:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:15:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:34 INFO - Downloaded 22288221 bytes. 09:15:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:15:35 INFO - Return code: 0 09:15:35 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:15:35 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:15:35 INFO - https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:15:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:15:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:15:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:15:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:39 INFO - Downloaded 22288221 bytes. 09:15:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:15:41 INFO - Return code: 0 09:15:41 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:15:41 INFO - https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:15:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:15:42 INFO - Downloaded 10840145 bytes. 09:15:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:15:42 INFO - Return code: 0 09:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:42 INFO - https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:15:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:15:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:15:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:15:43 INFO - Downloaded 54817295 bytes. 09:15:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:15:43 INFO - mkdir: /builds/slave/test/properties 09:15:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:43 INFO - Contents: 09:15:43 INFO - build_url:https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:15:44 INFO - Running post-action listener: _resource_record_post_action 09:15:44 INFO - Running post-action listener: set_extra_try_arguments 09:15:44 INFO - ##### 09:15:44 INFO - ##### Running populate-webroot step. 09:15:44 INFO - ##### 09:15:44 INFO - Running pre-action listener: _resource_record_pre_action 09:15:44 INFO - Running main action method: populate_webroot 09:15:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:44 INFO - '--tppagecycles', 09:15:44 INFO - '7'], 09:15:44 INFO - 'tests': ['tsvgm']}}, 09:15:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['damp', 'tps']}, 09:15:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'tests': ['damp', 'tps']}, 09:15:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['dromaeo_dom']}, 09:15:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['a11yr', 09:15:44 INFO - 'ts_paint', 09:15:44 INFO - 'tpaint', 09:15:44 INFO - 'sessionrestore', 09:15:44 INFO - 'sessionrestore_no_auto_restore', 09:15:44 INFO - 'tabpaint']}, 09:15:44 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:44 INFO - 'ts_paint', 09:15:44 INFO - 'tpaint', 09:15:44 INFO - 'sessionrestore', 09:15:44 INFO - 'sessionrestore_no_auto_restore', 09:15:44 INFO - 'tabpaint']}, 09:15:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:44 INFO - 'ts_paint', 09:15:44 INFO - 'tpaint', 09:15:44 INFO - 'sessionrestore', 09:15:44 INFO - 'sessionrestore_no_auto_restore', 09:15:44 INFO - 'tabpaint']}, 09:15:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:44 INFO - 'ts_paint', 09:15:44 INFO - 'tpaint', 09:15:44 INFO - 'sessionrestore', 09:15:44 INFO - 'sessionrestore_no_auto_restore', 09:15:44 INFO - 'tabpaint']}, 09:15:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['a11yr', 09:15:44 INFO - 'ts_paint', 09:15:44 INFO - 'tpaint', 09:15:44 INFO - 'sessionrestore', 09:15:44 INFO - 'sessionrestore_no_auto_restore', 09:15:44 INFO - 'tabpaint']}, 09:15:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['a11yr', 09:15:44 INFO - 'ts_paint', 09:15:44 INFO - 'tpaint', 09:15:44 INFO - 'sessionrestore', 09:15:44 INFO - 'sessionrestore_no_auto_restore', 09:15:44 INFO - 'tabpaint']}, 09:15:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['tsvgx', 09:15:44 INFO - 'tsvgr_opacity', 09:15:44 INFO - 'tart', 09:15:44 INFO - 'tscrollx', 09:15:44 INFO - 'cart']}, 09:15:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:44 INFO - 'tsvgr_opacity', 09:15:44 INFO - 'tart', 09:15:44 INFO - 'tscrollx', 09:15:44 INFO - 'cart']}, 09:15:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'talos_options': ['--disable-e10s'], 09:15:44 INFO - 'tests': ['tp5o']}, 09:15:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'tests': ['tp5o']}, 09:15:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'talos_options': ['--disable-e10s', 09:15:44 INFO - '--xperf_path', 09:15:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:44 INFO - 'tests': ['tp5n']}, 09:15:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:44 INFO - 'talos_options': ['--xperf_path', 09:15:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:44 INFO - 'tests': ['tp5n']}}, 09:15:44 INFO - 'talos.zip': {'path': '', 09:15:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:44 INFO - Running post-action listener: _resource_record_post_action 09:15:44 INFO - ##### 09:15:44 INFO - ##### Running create-virtualenv step. 09:15:44 INFO - ##### 09:15:44 INFO - Running pre-action listener: _resource_record_pre_action 09:15:44 INFO - Running main action method: create_virtualenv 09:15:44 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:44 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:15:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:44 INFO - Using real prefix '/usr' 09:15:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:47 INFO - Installing pip.................done. 09:15:47 INFO - Return code: 0 09:15:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15: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:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:15: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', 'pip>=1.5'] in /builds/slave/test/build 09:15: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 pip>=1.5 09:15:47 INFO - Using env: {'DISPLAY': ':0', 09:15:47 INFO - 'HOME': '/home/cltbld', 09:15:47 INFO - 'LANG': 'en_US.UTF-8', 09:15:47 INFO - 'LANGUAGE': 'en_US:en', 09:15:47 INFO - 'LOGNAME': 'cltbld', 09:15:47 INFO - 'MAIL': '/var/mail/cltbld', 09:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:47 INFO - 'MOZ_NO_REMOTE': '1', 09:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:47 INFO - 'NO_EM_RESTART': '1', 09:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:47 INFO - 'PWD': '/builds/slave/test', 09:15:47 INFO - 'SHELL': '/bin/bash', 09:15:47 INFO - 'SHLVL': '1', 09:15:47 INFO - 'TERM': 'linux', 09:15:47 INFO - 'TMOUT': '86400', 09:15:47 INFO - 'USER': 'cltbld', 09:15:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:47 INFO - '_': '/tools/buildbot/bin/python'} 09:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:47 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:15:47 INFO - Cleaning up... 09:15:47 INFO - Return code: 0 09:15:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:15: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:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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', '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 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:15: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', 'psutil>=0.7.1'] in /builds/slave/test/build 09:15: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 psutil>=0.7.1 09:15:47 INFO - Using env: {'DISPLAY': ':0', 09:15:47 INFO - 'HOME': '/home/cltbld', 09:15:47 INFO - 'LANG': 'en_US.UTF-8', 09:15:47 INFO - 'LANGUAGE': 'en_US:en', 09:15:47 INFO - 'LOGNAME': 'cltbld', 09:15:47 INFO - 'MAIL': '/var/mail/cltbld', 09:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:47 INFO - 'MOZ_NO_REMOTE': '1', 09:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:47 INFO - 'NO_EM_RESTART': '1', 09:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:47 INFO - 'PWD': '/builds/slave/test', 09:15:47 INFO - 'SHELL': '/bin/bash', 09:15:47 INFO - 'SHLVL': '1', 09:15:47 INFO - 'TERM': 'linux', 09:15:47 INFO - 'TMOUT': '86400', 09:15:47 INFO - 'USER': 'cltbld', 09:15:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:47 INFO - '_': '/tools/buildbot/bin/python'} 09:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:47 INFO - Downloading/unpacking psutil>=0.7.1 09:15: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:15: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:15: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:15: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:15: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:15: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:15:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:49 INFO - Installing collected packages: psutil 09:15:49 INFO - Running setup.py install for psutil 09:15:49 INFO - building 'psutil._psutil_linux' extension 09:15:49 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:15:49 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:15:50 INFO - building 'psutil._psutil_posix' extension 09:15:50 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:15:50 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:15:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:50 INFO - Successfully installed psutil 09:15:50 INFO - Cleaning up... 09:15:50 INFO - Return code: 0 09:15:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:15: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:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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', '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 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:15: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:15: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 mozsystemmonitor==0.0.0 09:15:50 INFO - Using env: {'DISPLAY': ':0', 09:15:50 INFO - 'HOME': '/home/cltbld', 09:15:50 INFO - 'LANG': 'en_US.UTF-8', 09:15:50 INFO - 'LANGUAGE': 'en_US:en', 09:15:50 INFO - 'LOGNAME': 'cltbld', 09:15:50 INFO - 'MAIL': '/var/mail/cltbld', 09:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:50 INFO - 'MOZ_NO_REMOTE': '1', 09:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:50 INFO - 'NO_EM_RESTART': '1', 09:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:50 INFO - 'PWD': '/builds/slave/test', 09:15:50 INFO - 'SHELL': '/bin/bash', 09:15:50 INFO - 'SHLVL': '1', 09:15:50 INFO - 'TERM': 'linux', 09:15:50 INFO - 'TMOUT': '86400', 09:15:50 INFO - 'USER': 'cltbld', 09:15:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:50 INFO - '_': '/tools/buildbot/bin/python'} 09:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:15: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:15: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:15: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:15: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:15: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:15: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:15:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:52 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:15:52 INFO - Installing collected packages: mozsystemmonitor 09:15:52 INFO - Running setup.py install for mozsystemmonitor 09:15:52 INFO - Successfully installed mozsystemmonitor 09:15:52 INFO - Cleaning up... 09:15:52 INFO - Return code: 0 09:15:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15:52 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:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:52 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:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:52 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 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:15:52 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:15:52 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:15:52 INFO - Using env: {'DISPLAY': ':0', 09:15:52 INFO - 'HOME': '/home/cltbld', 09:15:52 INFO - 'LANG': 'en_US.UTF-8', 09:15:52 INFO - 'LANGUAGE': 'en_US:en', 09:15:52 INFO - 'LOGNAME': 'cltbld', 09:15:52 INFO - 'MAIL': '/var/mail/cltbld', 09:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:52 INFO - 'MOZ_NO_REMOTE': '1', 09:15:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:52 INFO - 'NO_EM_RESTART': '1', 09:15:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:52 INFO - 'PWD': '/builds/slave/test', 09:15:52 INFO - 'SHELL': '/bin/bash', 09:15:52 INFO - 'SHLVL': '1', 09:15:52 INFO - 'TERM': 'linux', 09:15:52 INFO - 'TMOUT': '86400', 09:15:52 INFO - 'USER': 'cltbld', 09:15:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:52 INFO - '_': '/tools/buildbot/bin/python'} 09:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:15: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:15: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:15: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:15: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:15: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:15: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:15:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:15:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:15:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:15:54 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:15:54 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:15:54 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:15:54 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:15:54 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:15:54 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:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:15:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:15:55 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:15:55 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:15:55 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:15:55 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:15:55 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:15:55 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:15:55 INFO - Downloading docopt-0.6.1.tar.gz 09:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:15:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:15:55 INFO - Running setup.py install for blobuploader 09:15:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:15:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:15:55 INFO - Running setup.py install for requests 09:15:55 INFO - Running setup.py install for docopt 09:15:56 INFO - Successfully installed blobuploader requests docopt 09:15:56 INFO - Cleaning up... 09:15:56 INFO - Return code: 0 09:15:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:56 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 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:15:56 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:15:56 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:15:56 INFO - Using env: {'DISPLAY': ':0', 09:15:56 INFO - 'HOME': '/home/cltbld', 09:15:56 INFO - 'LANG': 'en_US.UTF-8', 09:15:56 INFO - 'LANGUAGE': 'en_US:en', 09:15:56 INFO - 'LOGNAME': 'cltbld', 09:15:56 INFO - 'MAIL': '/var/mail/cltbld', 09:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:56 INFO - 'MOZ_NO_REMOTE': '1', 09:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:56 INFO - 'NO_EM_RESTART': '1', 09:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:56 INFO - 'PWD': '/builds/slave/test', 09:15:56 INFO - 'SHELL': '/bin/bash', 09:15:56 INFO - 'SHLVL': '1', 09:15:56 INFO - 'TERM': 'linux', 09:15:56 INFO - 'TMOUT': '86400', 09:15:56 INFO - 'USER': 'cltbld', 09:15:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:56 INFO - '_': '/tools/buildbot/bin/python'} 09:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:56 INFO - Running setup.py (path:/tmp/pip-niY7tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:56 INFO - Running setup.py (path:/tmp/pip-9hKo9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:56 INFO - Running setup.py (path:/tmp/pip-n9G1Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:56 INFO - Running setup.py (path:/tmp/pip-0dPm0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:56 INFO - Running setup.py (path:/tmp/pip-lRvzoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:56 INFO - Running setup.py (path:/tmp/pip-cYaCNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:56 INFO - Running setup.py (path:/tmp/pip-hmz0H6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:56 INFO - Running setup.py (path:/tmp/pip-OopgfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:56 INFO - Running setup.py (path:/tmp/pip-RdXxN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:56 INFO - Running setup.py (path:/tmp/pip-1jRKrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:57 INFO - Running setup.py (path:/tmp/pip-pRmCP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:57 INFO - Running setup.py (path:/tmp/pip-vtpfIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:57 INFO - Running setup.py (path:/tmp/pip-TvPcjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:57 INFO - Running setup.py (path:/tmp/pip-kKaRCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:57 INFO - Running setup.py (path:/tmp/pip-pxqLBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:57 INFO - Running setup.py (path:/tmp/pip-WxLOR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:57 INFO - Running setup.py (path:/tmp/pip-wXq4cT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:15:57 INFO - Running setup.py install for manifestparser 09:15:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:15:57 INFO - Running setup.py install for mozcrash 09:15:57 INFO - Running setup.py install for mozdebug 09:15:57 INFO - Running setup.py install for mozdevice 09:15:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:15:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:15:57 INFO - Running setup.py install for mozfile 09:15:58 INFO - Running setup.py install for mozhttpd 09:15:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Running setup.py install for mozinfo 09:15:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Running setup.py install for mozInstall 09:15:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Running setup.py install for mozleak 09:15:58 INFO - Running setup.py install for mozlog 09:15:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Running setup.py install for moznetwork 09:15:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Running setup.py install for mozprocess 09:15:58 INFO - Running setup.py install for mozprofile 09:15:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:15:58 INFO - Running setup.py install for mozrunner 09:15:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:15:59 INFO - Running setup.py install for mozscreenshot 09:15:59 INFO - Running setup.py install for moztest 09:15:59 INFO - Running setup.py install for mozversion 09:15:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:15:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:15:59 INFO - Cleaning up... 09:15:59 INFO - Return code: 0 09:15:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:59 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 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:15:59 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:15:59 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:15:59 INFO - Using env: {'DISPLAY': ':0', 09:15:59 INFO - 'HOME': '/home/cltbld', 09:15:59 INFO - 'LANG': 'en_US.UTF-8', 09:15:59 INFO - 'LANGUAGE': 'en_US:en', 09:15:59 INFO - 'LOGNAME': 'cltbld', 09:15:59 INFO - 'MAIL': '/var/mail/cltbld', 09:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:59 INFO - 'MOZ_NO_REMOTE': '1', 09:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:59 INFO - 'NO_EM_RESTART': '1', 09:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:59 INFO - 'PWD': '/builds/slave/test', 09:15:59 INFO - 'SHELL': '/bin/bash', 09:15:59 INFO - 'SHLVL': '1', 09:15:59 INFO - 'TERM': 'linux', 09:15:59 INFO - 'TMOUT': '86400', 09:15:59 INFO - 'USER': 'cltbld', 09:15:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:59 INFO - '_': '/tools/buildbot/bin/python'} 09:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:59 INFO - Running setup.py (path:/tmp/pip-mUYzQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:59 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:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:59 INFO - Running setup.py (path:/tmp/pip-cAeMFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:59 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:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:59 INFO - Running setup.py (path:/tmp/pip-pTBCpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:59 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:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:59 INFO - Running setup.py (path:/tmp/pip-z0JU_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:59 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:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:59 INFO - Running setup.py (path:/tmp/pip-s0uQL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:59 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:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:59 INFO - Running setup.py (path:/tmp/pip-WEa0na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:59 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:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:59 INFO - Running setup.py (path:/tmp/pip-nX9OnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:00 INFO - Running setup.py (path:/tmp/pip-povoCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:00 INFO - Running setup.py (path:/tmp/pip-PHNQat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:00 INFO - Running setup.py (path:/tmp/pip-e8MIq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:00 INFO - Running setup.py (path:/tmp/pip-GrKoAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:00 INFO - Running setup.py (path:/tmp/pip-M8CNNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:00 INFO - Running setup.py (path:/tmp/pip-FNn1tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:00 INFO - Running setup.py (path:/tmp/pip-0oIjj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:00 INFO - Running setup.py (path:/tmp/pip-9iTSa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:00 INFO - Running setup.py (path:/tmp/pip-7okLug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:00 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:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:00 INFO - Running setup.py (path:/tmp/pip-nQhNZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:00 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:16:02 INFO - Downloading blessings-1.6.tar.gz 09:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:02 INFO - Installing collected packages: blessings 09:16:02 INFO - Running setup.py install for blessings 09:16:02 INFO - Successfully installed blessings 09:16:02 INFO - Cleaning up... 09:16:02 INFO - Return code: 0 09:16:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:03 INFO - Reading from file tmpfile_stdout 09:16:03 INFO - Current package versions: 09:16:03 INFO - argparse == 1.2.1 09:16:03 INFO - blessings == 1.6 09:16:03 INFO - blobuploader == 1.2.4 09:16:03 INFO - docopt == 0.6.1 09:16:03 INFO - manifestparser == 1.1 09:16:03 INFO - mozInstall == 1.12 09:16:03 INFO - mozcrash == 0.17 09:16:03 INFO - mozdebug == 0.1 09:16:03 INFO - mozdevice == 0.48 09:16:03 INFO - mozfile == 1.2 09:16:03 INFO - mozhttpd == 0.7 09:16:03 INFO - mozinfo == 0.9 09:16:03 INFO - mozleak == 0.1 09:16:03 INFO - mozlog == 3.1 09:16:03 INFO - moznetwork == 0.27 09:16:03 INFO - mozprocess == 0.22 09:16:03 INFO - mozprofile == 0.28 09:16:03 INFO - mozrunner == 6.11 09:16:03 INFO - mozscreenshot == 0.1 09:16:03 INFO - mozsystemmonitor == 0.0 09:16:03 INFO - moztest == 0.7 09:16:03 INFO - mozversion == 1.4 09:16:03 INFO - psutil == 3.1.1 09:16:03 INFO - requests == 1.2.3 09:16:03 INFO - wsgiref == 0.1.2 09:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:16:03 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:16:03 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:16:03 INFO - Using env: {'DISPLAY': ':0', 09:16:03 INFO - 'HOME': '/home/cltbld', 09:16:03 INFO - 'LANG': 'en_US.UTF-8', 09:16:03 INFO - 'LANGUAGE': 'en_US:en', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MAIL': '/var/mail/cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SHLVL': '1', 09:16:03 INFO - 'TERM': 'linux', 09:16:03 INFO - 'TMOUT': '86400', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - '_': '/tools/buildbot/bin/python'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 INFO - Cleaning up... 09:16:03 INFO - Return code: 0 09:16:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:16:03 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:16:03 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:16:03 INFO - Using env: {'DISPLAY': ':0', 09:16:03 INFO - 'HOME': '/home/cltbld', 09:16:03 INFO - 'LANG': 'en_US.UTF-8', 09:16:03 INFO - 'LANGUAGE': 'en_US:en', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MAIL': '/var/mail/cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SHLVL': '1', 09:16:03 INFO - 'TERM': 'linux', 09:16:03 INFO - 'TMOUT': '86400', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - '_': '/tools/buildbot/bin/python'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 INFO - Downloading/unpacking jsonschema 09:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:03 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:16:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:05 INFO - Downloading/unpacking functools32 (from jsonschema) 09:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:05 INFO - warning: no files found matching '*.txt' 09:16:05 INFO - no previously-included directories found matching 'build' 09:16:05 INFO - no previously-included directories found matching 'dist' 09:16:05 INFO - no previously-included directories found matching '.git*' 09:16:05 INFO - Installing collected packages: jsonschema, functools32 09:16:05 INFO - Running setup.py install for functools32 09:16:05 INFO - warning: no files found matching '*.txt' 09:16:05 INFO - no previously-included directories found matching 'build' 09:16:05 INFO - no previously-included directories found matching 'dist' 09:16:05 INFO - no previously-included directories found matching '.git*' 09:16:05 INFO - Successfully installed jsonschema functools32 09:16:05 INFO - Cleaning up... 09:16:05 INFO - Return code: 0 09:16:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f40aa4a8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13b67d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', '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:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:16:05 INFO - Using env: {'DISPLAY': ':0', 09:16:05 INFO - 'HOME': '/home/cltbld', 09:16:05 INFO - 'LANG': 'en_US.UTF-8', 09:16:05 INFO - 'LANGUAGE': 'en_US:en', 09:16:05 INFO - 'LOGNAME': 'cltbld', 09:16:05 INFO - 'MAIL': '/var/mail/cltbld', 09:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:05 INFO - 'MOZ_NO_REMOTE': '1', 09:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:05 INFO - 'NO_EM_RESTART': '1', 09:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:05 INFO - 'PWD': '/builds/slave/test', 09:16:05 INFO - 'SHELL': '/bin/bash', 09:16:05 INFO - 'SHLVL': '1', 09:16:05 INFO - 'TERM': 'linux', 09:16:05 INFO - 'TMOUT': '86400', 09:16:05 INFO - 'USER': 'cltbld', 09:16:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:05 INFO - '_': '/tools/buildbot/bin/python'} 09:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:06 INFO - Downloading/unpacking flake8 09:16:06 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:16:06 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:16:06 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:16:06 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:16:06 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:16:06 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:16:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:16:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:16:08 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:16:08 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:16:08 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:16:08 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:16:08 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:16:08 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:16:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:16:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:16:08 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:16:08 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:16:08 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:16:08 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:16:08 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:16:08 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:16:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:16:08 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:16:08 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:16:08 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:16:08 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:16:08 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:16:08 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:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:16:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:16:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:16:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:16:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:16:08 INFO - no previously-included directories found matching 'docs/_build' 09:16:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:16:08 INFO - Running setup.py install for pep8 09:16:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:16:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:16:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:16:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:16:09 INFO - no previously-included directories found matching 'docs/_build' 09:16:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:16:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:16:09 INFO - Cleaning up... 09:16:09 INFO - Return code: 0 09:16:09 INFO - Running post-action listener: _resource_record_post_action 09:16:09 INFO - Running post-action listener: _start_resource_monitoring 09:16:09 INFO - Starting resource monitoring. 09:16:09 INFO - ##### 09:16:09 INFO - ##### Running install step. 09:16:09 INFO - ##### 09:16:09 INFO - Running pre-action listener: _resource_record_pre_action 09:16:09 INFO - Running main action method: install 09:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:09 INFO - Reading from file tmpfile_stdout 09:16:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:09 INFO - Reading from file tmpfile_stdout 09:16:09 INFO - Output received: 09:16:09 INFO - Usage: mozinstall [options] installer 09:16:09 INFO - Options: 09:16:09 INFO - -h, --help show this help message and exit 09:16:09 INFO - -d DEST, --destination=DEST 09:16:09 INFO - Directory to install application into. [default: 09:16:09 INFO - "/builds/slave/test"] 09:16:09 INFO - --app=APP Application being installed. [default: firefox] 09:16:09 INFO - mkdir: /builds/slave/test/build/application 09:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:16:25 INFO - Reading from file tmpfile_stdout 09:16:25 INFO - Output received: 09:16:25 INFO - /builds/slave/test/build/application/firefox/firefox 09:16:25 INFO - Running post-action listener: _resource_record_post_action 09:16:25 INFO - ##### 09:16:25 INFO - ##### Running run-tests step. 09:16:25 INFO - ##### 09:16:25 INFO - Running pre-action listener: _resource_record_pre_action 09:16:25 INFO - Running main action method: run_tests 09:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:25 INFO - Python 2.7.3 09:16:25 INFO - Return code: 0 09:16:25 INFO - grabbing minidump binary from tooltool 09:16:25 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:16:25 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 0x1445640>, '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 0x1446540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14469d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:25 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:16:25 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:16:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:25 INFO - Return code: 0 09:16:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:16:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:16:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:16:26 INFO - Return code: 0 09:16:26 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/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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:16:26 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/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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:16:26 INFO - Using env: {'DISPLAY': ':0', 09:16:26 INFO - 'HOME': '/home/cltbld', 09:16:26 INFO - 'LANG': 'en_US.UTF-8', 09:16:26 INFO - 'LANGUAGE': 'en_US:en', 09:16:26 INFO - 'LOGNAME': 'cltbld', 09:16:26 INFO - 'MAIL': '/var/mail/cltbld', 09:16:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:26 INFO - 'MOZ_NO_REMOTE': '1', 09:16:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:26 INFO - 'NO_EM_RESTART': '1', 09:16:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:26 INFO - 'PWD': '/builds/slave/test', 09:16:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:26 INFO - 'SHELL': '/bin/bash', 09:16:26 INFO - 'SHLVL': '1', 09:16:26 INFO - 'TERM': 'linux', 09:16:26 INFO - 'TMOUT': '86400', 09:16:26 INFO - 'USER': 'cltbld', 09:16:26 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889', 09:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:26 INFO - '_': '/tools/buildbot/bin/python'} 09:16:26 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/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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:16:26 INFO - mozversion application_buildid: 20160425083752 09:16:26 INFO - mozversion application_changeset: 352d365eb4eda3aab7c3680b0e80ce17e178b5ee 09:16:26 INFO - mozversion application_display_name: Nightly 09:16:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:26 INFO - mozversion application_name: Firefox 09:16:26 INFO - mozversion application_remotingname: firefox 09:16:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:16:26 INFO - mozversion application_vendor: Mozilla 09:16:26 INFO - mozversion application_version: 48.0a1 09:16:26 INFO - mozversion platform_buildid: 20160425083752 09:16:26 INFO - mozversion platform_changeset: 352d365eb4eda3aab7c3680b0e80ce17e178b5ee 09:16:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:16:26 INFO - mozversion platform_version: 48.0a1 09:16:26 INFO - using testdate: 1461600986 09:16:26 INFO - actual date: 1461600986 09:16:26 INFO - starting webserver on 'localhost:34235' 09:16:26 INFO - SUITE-START | Running 2 tests 09:16:26 INFO - TEST-START | dromaeo_css 09:16:26 INFO - operating with platform_type : linux_ 09:16:26 INFO - Initialising browser for dromaeo_css test... 09:16:26 INFO - TEST-INFO | started process 13937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzv3e9A/profile http://localhost:34235/getInfo.html) 09:16:32 INFO - PROCESS | 13937 | __metrics Screen width/height:1600/1200 09:16:32 INFO - PROCESS | 13937 | colorDepth:24 09:16:32 INFO - PROCESS | 13937 | Browser inner width/height: 1024/697 09:16:32 INFO - PROCESS | 13937 | __metrics 09:16:33 ERROR - PROCESS | 13937 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:16:34 INFO - TEST-INFO | 13937: exit 0 09:16:34 INFO - Browser initialized. 09:16:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:34 INFO - TEST-INFO | started process 14019 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzv3e9A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:46 INFO - PROCESS | 14019 | RSS: Main: 156155904 09:16:46 INFO - PROCESS | 14019 | 09:17:38 INFO - PROCESS | 14019 | 1461601058141 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:19:49 INFO - PROCESS | 14019 | Cycle 1(1): loaded http://localhost:34235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34235/tests/dromaeo/cssquery-ext.html) 09:19:50 INFO - PROCESS | 14019 | RSS: Main: 266366976 09:19:50 INFO - PROCESS | 14019 | 09:22:53 INFO - PROCESS | 14019 | Cycle 1(1): loaded http://localhost:34235/tests/dromaeo/cssquery-ext.html (next: http://localhost:34235/tests/dromaeo/cssquery-jquery.html) 09:22:53 INFO - PROCESS | 14019 | RSS: Main: 243097600 09:22:53 INFO - PROCESS | 14019 | 09:25:56 INFO - PROCESS | 14019 | Cycle 1(1): loaded http://localhost:34235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34235/tests/dromaeo/cssquery-mootools.html) 09:25:56 INFO - PROCESS | 14019 | RSS: Main: 238530560 09:25:56 INFO - PROCESS | 14019 | 09:28:58 INFO - PROCESS | 14019 | Cycle 1(1): loaded http://localhost:34235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34235/tests/dromaeo/cssquery-prototype.html) 09:28:59 INFO - PROCESS | 14019 | RSS: Main: 231501824 09:28:59 INFO - PROCESS | 14019 | 09:32:02 INFO - PROCESS | 14019 | Cycle 1(1): loaded http://localhost:34235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34235/tests/dromaeo/cssquery-yui.html) 09:32:02 INFO - PROCESS | 14019 | RSS: Main: 213422080 09:32:02 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | Cycle 1(1): loaded http://localhost:34235/tests/dromaeo/cssquery-yui.html (next: http://localhost:34235/tests/dromaeo/cssquery-dojo.html) 09:35:06 INFO - PROCESS | 14019 | RSS: Main: 295641088 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | __start_tp_report 09:35:06 INFO - PROCESS | 14019 | _x_x_mozilla_page_load 09:35:06 INFO - PROCESS | 14019 | _x_x_mozilla_page_load_details 09:35:06 INFO - PROCESS | 14019 | |i|pagename|runs| 09:35:06 INFO - PROCESS | 14019 | |0;dojo.html;2632.5393056215157;2680.128958088622;2697.2852194168536;2704.959425608616;2729.9181024569257;4633.49031606523;4720.221163508021;4723.598494128003;4730.834420795269;4762.190427627299;4654.557817007388;4723.85828425147;4779.784909679064;4783.139034973703;4799.232122860343;4266.36004599311;4278.101598664276;4297.978510107445;4319.092990471989;4384.342348647697;4332.0469496710675;4385.802638881258;4395.033092719596;4423.889402764925;4458.9331160032625;4292.957070429318;4372.737635741833;4401.955980440202;4417.558244175573;4419.447569053868;4268.786560671985;4298.849540266107;4311.310190369557;4321.935170972407;4324.956750432519;4316.330968699856;4349.586789255016;4352.347147927804;4383.364412160219;4388.934165987513;4548.135854187693;4645.744484053375;4646.53534646536;4648.767561621906;4662.347271381976;5015.27278544609;5056.949430505685;5058.215976523645;5095.108356037716;5098.464661210556;5041.395032596075;5043.445221025684;5050.772715227824;5057.544820966131;5118.820841270575;4517.638588912879;4659.4175728034;4709.152352576569;4712.175369310357;4716.575508204278;5009.148444764399;5045.949540504622;5069.239614057847;5078.7460626968505;5128.9230661540105;4840.419149702058;4849.733264670477;4852.369192005017;4854.63590230727;4896.853094407174;4938.407391113089;4955.529224723646;4966.453690094087;5001.874953126201;5035.295058691713;4866.221404575251;4889.242167464049;4894.559489645949;4929.704217746947;4933.6793108448555;4849.4908034656555;4851.927221091686;4855.684380515255;4864.027194561102;4909.631777616693;4118.382242663564;4172.603602657743;4178.331466965271;4179.393987871802;4260.978695106504;3716.6655001050035;3730.626937306248;3731.794751288651;3769.509963704701;3769.792661403594;4403.559644035571;4411.558844115627;4412.558744125626;4433.933490997638;4459.710118842265;5003.54968052877;5046.823361182341;5056.544910957958;5076.82231121909;5140.897182056338;4329.523752387235;4331.285337919208;4335.804888780012;4363.47638283408;4392.978035109868;4470.642348612103;4471.3020988759445;4471.642268618502;4479.641628669699;4513.887152821206;4626.467956185014;4708.176069187947;4754.072955773591;4769.451513075902;4789.712617242976;4917.655764096622;4932.950670493393;4942.209246520683;4952.603791696728;5004.574611158092;4684.508126646653;4686.109639168547;4691.061787642417;4733.644976626698;4747.382840230748;4893.9021219576125;4904.166291730343;4945.1346014448045;4998.250262460515;5012.997400519984;4913.336699545515;4923.335349727741;4926.5073492650445;4927.679700819435;4973.229149481836;4768.713877167381;4781.402324709411;4782.354382158364;4784.712917224978;4839;4793.616510679207;4857.53853383928;4880.194767863405;4927.260910863399;4929.926051109164;4735.479097299225;4753.548412900769;4759.119562880878;4763.499832517536;4813.15769740301;4843;4847.636427268039;4850.854474365775;4858.319835223142;4859.343988561499;4835.685680430761;4901;4905.264210368473;4941.258811178352;4955 09:35:06 INFO - PROCESS | 14019 | |1;ext.html;573.5726883471814;607.4047433515155;608.4645511949481;617.8424501752053;626.592714735422;33233.66766332336;34410.13974650635;34556.48165277517;34657.826710866444;34672.99999999997;36909.07727306812;37092.36345456818;37485.437718434245;37566.4365034524;37664.05839854005;7700.730474433396;7856.646450909694;7898.5260884346835;7906.565138917357;7914.327282180992;7734.651940662684;7900.446968712193;7902.130765615778;8090.474119182234;8093.959530202341;8083.555404452753;8151.755447336589;8173.386995975326;8174.877376839352;8178.263956243937;8037.758867233963;8110.310623596943;8111;8111.675532978674;8115.026196856356;7869.606519674051;7968.800779980489;7969.920300796975;7982.401319901031;7982.840343193162;33926.49110263348;34174.48738268928;34214.48678269828;34339.6566034339;34359.484607730905;2762.0608992942293;3023.38020705757;3044.451998640244;3466.9306613867834;3579.158897659048;28359;28405.289867753265;28559.714402855912;28646.85676571604;29286.41427171466;3360.7311415086733;3399.201187720884;3403.5235067090625;3409.2670075933793;3500.7374446916583;166593;167645;167736.16131919262;167887;168584;29243.268918276786;30090.54864177039;30146.24634384114;47696.799416075686;48446.757766211646;31695.52456713151;31881.521777173362;32047;32067.839660801546;32104.357912841613;12602.180858244184;12752.043596730096;12769.936150319189;12775.297358645177;12780.552680656132;10549.156067514581;10552.894471055191;10561;10567.894321056845;10580.312279701795;8012.038555373392;8040.83918321642;8084.8383032339025;8143.42995990275;8153.388495862834;3181.2206009527813;3231.935361292808;3237.9352412951607;3263.738900887923;3286.76992610515;855.2730179347504;885.5306687455659;923.3167456082585;1044.8693913260843;1067.690369792767;27306.863465682545;27861.860690696416;28395.290117747223;28619.57070643942;28740.99406520762;780.9414293927978;924.3760461688333;961.1781926452921;1032.808930347888;1117.3351855645878;13713.862861371259;14165.150090994573;14270.571882843531;14457;14537.273136343141;328323;334521;334729;335236;335314;47332.763336183096;48211.03577928422;48218.5178148221;48281;48319.27521087186;1003.2575893838507;1192.6779769462196;1193.7791508570942;1202.3747651221315;1317.952227978759;24602.384940376633;25500.48999019972;25822.225333240032;26397;27744.72255277502;38061.2387752249;38301.61698383092;38389.616103838605;38428.80785596054;38726.6127338723;184735;184854;185075;185467;185486;21248.78751212468;22546.210882619685;23308.76691233066;23460.64809027832;23530.529389411775;37842.43236351402;38257.426138607385;38565.00002497251;38927.80536097301;39434.2113157741;33226.667733322356;33281;33472;33886.66113338835;33980.32039359248;33199.83400082984;33230.510796366034;33440.83279583684;33552.83223583964;33745.3250934975;32571.837140814143;33133.33733325272;33367;33433.33133337368;33477.66522334745;28933.56599650965;30775.2306192347;30832.53751193689;30913.8454307727;30929.381412371178;23507.529849403258;23628.52742945166;24057.51884962326;24210.636840447056;24324.391890202885 09:35:06 INFO - PROCESS | 14019 | |2;jquery.html;379.04291663549543;383.5378369065277;390.3422732695408;396.9702272329576;398.22743876878855;15967.840321596781;16570.917145414256;16875.831241687578;16896.49310520685;16936.830631693694;15591.1424871632;15740.84259157408;16488.75266870995;16508;16547.91726041368;5031.195008798593;5172.19830926207;5232.92150617741;5235.345581802275;5238.266642670029;3637.7817330960097;3822.923541529168;5651.717414129298;5923.8222853314255;5967.16459695643;5847.8538036549;5888.97055514724;5892.14563888236;5894.764209431618;5926.88146237075;5809.651420914737;5907.172995780594;5960.433758792908;5972.4027597240365;5975.551833612475;3277.36091462165;5009.799608015675;5257.86855328616;5268;5275.657082289639;18964.24143034277;19078.14148363327;21652.458688532755;21743;22040.44898877525;1890.2816929566814;1967.0951362373326;2052.958940821175;2257.683924250606;2488.4525404411;13572.982026348063;14191.858081419157;14443.0612010219;14656.193909334983;14809.259537023107;1791.5431564950984;1817.954551136219;1856.2482194711029;1945.9318923837598;1957.2366776956999;79080.20919790785;79487;80368.59815700885;80419.5979020107;82655;15872.76190857115;16051.919740401223;16094.436694715629;16207.513774586781;16373;28629.28426789347;28682.856585716938;28978.13065608035;28988.42023159525;29021.41957160845;8579.099194584478;8863.157999989991;8974;9044.909550904407;9201.757762702084;6973.860522789516;7021.403180729695;7472.065991751031;7497.335481472302;7574.469787114959;8126.471779334324;8340.541270230196;8369.121242269595;8523.573821309034;8552.914470855212;2847.135126492151;3240.8448381826292;3241.8541165647603;3328.4008878401637;3445.173158441956;846.7502086884384;856.3106699107154;857.1856736100605;909.2816674826945;986.8223719730518;15507.767383489258;15839.604009899615;15864.206789660471;16137.838621613868;16675.24961376741;863.0765081363007;890.6214858685033;903.7316335996695;934.3646320501988;951.4196340232453;8961.462312261285;9313.161815436508;9371.203447707021;9375.062493750707;9475.47884866339;348525;349449;353914;354850;361245;73582.63208683922;77214.61392693112;77494.22505774983;77779.61110194413;79533;909.027340745396;921.7649499377616;944.4946953379908;954.0650162840456;957.0286159548065;11290.379029153475;14178;14463.13221206731;14604.977651564714;14825.25873706332;24577.50844983126;26311;28255.576166357932;33470.163245919044;34076;43978;44655.55344446644;44890.551094488634;44945.55054449545;44981.775091124335;7780.260875216845;7847;7908.723194688158;7913.406494513049;7937.2459616336355;9638.132568068908;10686.8931310689;11195.496202671062;11743.588974385855;11862.940685296518;10059.69820905374;10109.949450252996;10259.384436933808;10306.948465257627;10363.94818025905;10175.89824101779;10227.897721022695;10410.115140213167;10472.266941314036;10486.947565262124;10116.08955194036;10125.64560240388;10128.34165779222;10191.490425478845;10374.22193335518;7650.349720273839;7775.766726998199;7873.0945941215205;7886.487378320391;7933.127355990721;9582.185514231369;9868.062534059252;9879.407235565888;10000.249981251582;10137.391756494633 09:35:06 INFO - PROCESS | 14019 | |3;mootools.html;1265.3926115464583;1450.7388670039393;1467.3323637744825;1580.7628855671649;1733.1854028606551;6919.515633905628;7235.782926512206;7236.601986890732;7245.710171593137;7252.637368131585;6998.510104292702;7210.531315464491;7212.170600380958;7226.6025368604705;7233;1943.9611207775836;2003.1786967343392;2141.06863514371;2159.762426133124;2191.9890400547974;2158.438805910459;2169.360038788553;2173.576152650234;2173.9782602174014;2175.5213852952324;2134.7651758306574;2154.429076294783;2187.453136715821;2191.9890400547974;2198.1976578548793;1876.9155388007573;2076.678114892194;2111.5460176062143;2133.296012315932;2134.9146034158616;2129.701841742142;2130.4886827161527;2149.5593403352277;2150.7204063471804;2154.429076294783;7044.612546309951;7078.964605176941;7135.89296160558;7145.178304494998;7147.070880785516;4424.491183513905;4604.815807367701;4607.631389488833;4612.700174488681;4616.330632058366;2825.095969289828;2833.5891295762203;2899.5650652402096;2911.8252904825777;2914.3879785244912;859.8323326951247;862.969796057135;876.4390789894472;885.9557022148866;891.6433426629335;9802.852957205576;9854.556544955494;9865.852012219822;9883.654072107442;9885.555150018266;4048.291548978917;4155.937660935088;4236.872893813191;4243.554426785205;4243.8090285937205;8341;8380.790480237922;8407.8318433631;8424.073351931283;8436.915630843736;4528.773561321921;4584.060267645192;4596.17268891596;4598.517155698671;4601.470830854428;3757.5490941087246;4197.055662475918;4275.38006988991;4298.935515967263;4312.439382880206;3407.7391365195035;3463.290025544757;3483.3555792178527;3522.8238588070494;3539.3983022886173;1202.3567391445536;1203.2419575667448;1204.241327963395;1206.2099324942176;1222.4498975461079;734.5115498193749;742.3356096293787;811.7929927868474;817.8200795824908;845.0535400351646;4955.108080545537;4961.112654259783;5015.398152221684;5028.974855125701;5105.0810854045885;799.490871484826;813.4265342933218;819.5451524403894;847.4110493207285;879.6701237036111;3305.1406634275263;3451.0854623524788;3457.083872773717;3478.843452044664;3513.94729079064;8685.739427817176;8992.685256016008;9012.369134160677;9111.908880911105;9127.863082053775;8981.955090224506;9000.23498002664;9010.14403631654;9019.188273055457;9048;6922.653867306614;7058.188308344606;7085.397741192057;7238.27617238272;7279.818004549928;4907.55831975124;4970.9254361183885;4979;5016.974915125401;5073.518015788494;8669.523176225368;8733.257673097603;8744.344174186834;8822.95588522079;8874.423162494417;8873.378863479753;9054.502002389932;9074.682386116718;9076.092390761136;9205.263578913804;5408.45915408456;5645.830625081254;5849.473547380756;6288.371162883675;6407.199100112486;6635.336466353326;6654.800355989328;6672.03255527936;6703.363180497845;6734.259231484631;5829.854253643693;6051.697415129314;6132.63204207749;6136.588584625651;6138.693065346804;6022.45797878193;6212.409821066992;6227.034809604518;6292.612997807138;6436.967815160894;5560.5551555876245;5565.55475561946;5582.27430434041;5605.159226116115;5628.971855140698;4093.7339072960162;4096.8156432959995;4098.8360465581045;4201.558836322142;4218.894527636834;4178.916421671489;4207.495100587949;4227;4290.63529599975;4329.740215587075 09:35:06 INFO - PROCESS | 14019 | |4;prototype.html;308.41401337458825;310.44895310823284;310.6768960281307;315.0202869077171;315.55664291670195;4669.953300466995;4716.575508204261;4746.572808447239;4755.595774359179;4790.4191616766475;4656.813727450906;4705.3177289293035;4716.363290955728;4719.1033703596295;4720.244760838266;3533.8056406897545;3575.3564358415474;3665.2303016366463;3682.907927301812;3736.4395340698843;3701.870434534792;3708.6662200401956;3718.0518967663206;3730.6269373062614;3731.5708693500324;3530.8411121499466;3698.8520459181736;3701.0932321581117;3709;3722.516072910518;3592.443171308438;3710.165212827092;3718.772804974352;3762.172322089136;3766.7363284569956;3303;3308.354870800195;3315.950260746091;3329.916752081193;3347.6317605063423;4428;4490.440448944103;4503.144402563536;4534.206513860061;4537.387452693877;4067.6542493888055;4273.658107351435;4285.807138678767;4287.49937521868;4291.227579035773;4900.460949295574;4921.901561968741;4992.326035985132;4993.177490216081;5001.474845141244;3660.4875317455576;3662.633736626343;3674.3569875271987;3680.907977300562;3686.299603075407;5032.917922646649;5158.252053452228;5233.555147812441;5252.842414727526;5253.212018197263;3768.8680896168503;3782.962170378316;3783.4892289541112;3791.7724936503346;3804.8668296609485;3794.354959656865;3810.6189381062227;3845.7692538447222;3845.769253844778;3854.2291541691775;4238.936415953764;4281.314989601648;4286.164197981426;4291.570842915746;4293.656507479394;4790.233562629963;4959.057779021974;4974.353334066621;4975.4527002029745;4996;1367.7948307753718;1371.149887070023;1374.3334482776045;1376.6833628265356;1382.4124746982493;898.3172788680519;940.0176815228103;957.8419560772534;960.3853533738413;970.8932017478072;4660.161170989254;4668.229742092584;4676.018036212434;4683.765811709469;4710.811567537328;5397.62360598045;5417.59368047398;5424.538914192259;5435.103207970719;5436.646617969819;4530.864074077783;4653.185692503798;4674.9715062686155;4693.577578017995;4701.967231135966;2863.1267463423856;2876.3528206153865;2886.62885129566;2890.4363649088646;2895.102518219359;5076.923846142311;5095.592352611782;5100.464451232567;5106.693598384109;5117.641765076483;5332.9733351333;5509.5041446270025;5514.035043867388;5560.332760068818;5561.805336813192;5125.2568377585785;5137.818301790609;5141.38303403594;5151.793928242903;5163.251328557263;5260.894782104413;5324.9467505326;5422.945770542244;5431.646942948696;5455.236266922713;5242.501962313574;5265.262863199232;5266;5278.630495865253;5295.822506496072;5059.949400505948;5094.2103973884105;5098.872528186825;5117;5143.537081662668;3862.8454861806335;3906.160175562268;3907.882763517099;3912.5891781362548;3922.9215415692097;4859.465458799458;5086.338775959101;5125.641205115606;5161.896762064813;5187.429382767968;4904.411470623548;4928.383952005999;4943.653944224014;4968.8571628524915;4992.6255530834605;4822.565969062801;4973.875653108701;5003.949960500348;5014.623903207201;5041.168207245763;4663.836765713184;4862.854114376574;4897.191963326154;4915.582175515121;4929.9787087294035;4823.95176048235;4904.901901961869;4912.852614421573;4920.8769780755765;4944.15949288629;4862.927056094232;4895.118878601886;4922.507749225048;4927.384076990376;4941.6293777966075 09:35:06 INFO - PROCESS | 14019 | |5;yui.html;610.5909040942568;671.83540032692;749.2806905370844;770.3259647808168;772.8686123359032;11879.940600297008;12272.570460033878;12322.445489952963;12350.938245308784;12359.443825027884;11954.880451195508;12048.57829975951;13011.804822927663;13307.800382994263;13351.265680387574;813.66639677732;819.3527113580265;821.6713314674148;821.765796747927;822.1901427094338;643.6427782580666;785.3677789379548;813.9226773456542;814.5642081486405;818.2635627934876;847.0724556610502;849.1254008371387;849.9702510412137;850;850.2815121222557;845.344857735254;845.5814371885895;847.4110493207223;847.7033038436559;848.6647774129216;497.1746900145741;606.1362558354327;622.6855438003824;622.8100429369041;624.1823211592782;12723.23660580368;12736.36318184087;12773.361331933365;12786.808197877039;12786.808197877039;11356.84219998206;11673.241239319503;11780.882191178065;11998.400079995965;12091.879081209163;9487.098725621056;9609.375390599589;9625.229981601455;9719.951400243024;10370.170386369073;3278.4754439289836;3299.472084466498;3312.801231926092;3337.332533493311;3339.916502087443;42062;42779;42898;43254.134917301475;43579.346309805376;9506.714798555917;9665;9667.854982175273;9682.854757178648;9718.319717619699;16448;16533.173341332884;16626.334946602;16626.50120496387;16672.58318542022;6436.195475565554;6604.273529911802;6645.966770166118;6801.591904485746;6869.0383346331555;3440.088376580208;3506.526618906415;3528.241428092921;3533.982330088333;3634.800085995296;2754.5317296059716;2783.665960084803;2787.637607111103;2797.1468702045663;2798.846063466488;494.48820470812535;498.0686116961306;500.7145926821677;512.597610875461;514.1516497778621;576.6453631016979;593.3924414285024;595.425414475025;610.225014231923;611.1352436302582;6921.8961715574305;6973.163220413583;7144.9642751785905;7155.713771449084;7315.122185337687;548.2625868207255;569.4305694305694;576.2969177603318;581.528961541153;582.3419535924378;3247.4966380211104;3411.675890790371;3416.1630400551526;3445.7415693823064;3482.233908540117;46867.76566117216;48119.75940120277;48315;48735.268970965466;49356;44311.335329970076;44623.77688111539;45094.32358514625;45395.31907021398;46026.30960535595;40343.59656403457;40401.595984039785;40954.590454095676;41121.215598045594;41526.79236603858;6612.173478315211;6719.361660642231;6748.831279218059;6820.727170913108;7589.392848572212;28359.149225523266;28707.995220167195;29744;29823.10530684083;30427.087187384415;37407;37498.8125059373;37662.058448539006;37947.81026094852;38107.23785524329;9835.508224588884;10230.032012210324;10428.53071611764;10560.788784224425;10599.364038157735;11312.830307545559;11512.82730759056;11692.649220523397;12288.385580721108;12441.813372799234;9718.659846905324;9822.459764713009;9851.45816980073;9852.359596626196;10154.289199756242;10948.39783811866;11479.8278025828;11496.080313574726;11808.763824723286;11834.171607987702;10323.48382580883;10598.788024239626;10634.787304254027;10790.568377265135;11012.063974561932;8443.719283280545;8577.313814894918;8773.736787896374;8975.192232699088;9040.276777857887;8276.58617069132;8328.167183281623;8487;8594.269487093667;8671.696490622799 09:35:06 INFO - PROCESS | 14019 | __end_tp_report 09:35:06 INFO - PROCESS | 14019 | __start_cc_report 09:35:06 INFO - PROCESS | 14019 | _x_x_mozilla_cycle_collect,1022 09:35:06 INFO - PROCESS | 14019 | __end_cc_report 09:35:06 INFO - PROCESS | 14019 | __startTimestamp1461602106345__endTimestamp 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | ------- Summary: start ------- 09:35:06 INFO - PROCESS | 14019 | Number of tests: 6 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | [#0] dojo.html Cycles:165 Average:4627.57 Median:4762.19 stddev:460.86 (9.7%) stddev-sans-first:434.89 09:35:06 INFO - PROCESS | 14019 | Values: 2632.5 2680.1 2697.3 2705.0 2729.9 4633.5 4720.2 4723.6 4730.8 4762.2 4654.6 4723.9 4779.8 4783.1 4799.2 4266.4 4278.1 4298.0 4319.1 4384.3 4332.0 4385.8 4395.0 4423.9 4458.9 4293.0 4372.7 4402.0 4417.6 4419.4 4268.8 4298.8 4311.3 4321.9 4325.0 4316.3 4349.6 4352.3 4383.4 4388.9 4548.1 4645.7 4646.5 4648.8 4662.3 5015.3 5056.9 5058.2 5095.1 5098.5 5041.4 5043.4 5050.8 5057.5 5118.8 4517.6 4659.4 4709.2 4712.2 4716.6 5009.1 5045.9 5069.2 5078.7 5128.9 4840.4 4849.7 4852.4 4854.6 4896.9 4938.4 4955.5 4966.5 5001.9 5035.3 4866.2 4889.2 4894.6 4929.7 4933.7 4849.5 4851.9 4855.7 4864.0 4909.6 4118.4 4172.6 4178.3 4179.4 4261.0 3716.7 3730.6 3731.8 3769.5 3769.8 4403.6 4411.6 4412.6 4433.9 4459.7 5003.5 5046.8 5056.5 5076.8 5140.9 4329.5 4331.3 4335.8 4363.5 4393.0 4470.6 4471.3 4471.6 4479.6 4513.9 4626.5 4708.2 4754.1 4769.5 4789.7 4917.7 4933.0 4942.2 4952.6 5004.6 4684.5 4686.1 4691.1 4733.6 4747.4 4893.9 4904.2 4945.1 4998.3 5013.0 4913.3 4923.3 4926.5 4927.7 4973.2 4768.7 4781.4 4782.4 4784.7 4839.0 4793.6 4857.5 4880.2 4927.3 4929.9 4735.5 4753.5 4759.1 4763.5 4813.2 4843.0 4847.6 4850.9 4858.3 4859.3 4835.7 4901.0 4905.3 4941.3 4955.0 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | [#1] ext.html Cycles:180 Average:37385.55 Median:183980.92 stddev:63299.83 (34.4%) stddev-sans-first:63417.06 09:35:06 INFO - PROCESS | 14019 | Values: 573.6 607.4 608.5 617.8 626.6 33233.7 34410.1 34556.5 34657.8 34673.0 36909.1 37092.4 37485.4 37566.4 37664.1 7700.7 7856.6 7898.5 7906.6 7914.3 7734.7 7900.4 7902.1 8090.5 8094.0 8083.6 8151.8 8173.4 8174.9 8178.3 8037.8 8110.3 8111.0 8111.7 8115.0 7869.6 7968.8 7969.9 7982.4 7982.8 33926.5 34174.5 34214.5 34339.7 34359.5 2762.1 3023.4 3044.5 3466.9 3579.2 28359.0 28405.3 28559.7 28646.9 29286.4 3360.7 3399.2 3403.5 3409.3 3500.7 166593.0 167645.0 167736.2 167887.0 168584.0 29243.3 30090.5 30146.2 47696.8 48446.8 31695.5 31881.5 32047.0 32067.8 32104.4 12602.2 12752.0 12769.9 12775.3 12780.6 10549.2 10552.9 10561.0 10567.9 10580.3 8012.0 8040.8 8084.8 8143.4 8153.4 3181.2 3231.9 3237.9 3263.7 3286.8 855.3 885.5 923.3 1044.9 1067.7 27306.9 27861.9 28395.3 28619.6 28741.0 780.9 924.4 961.2 1032.8 1117.3 13713.9 14165.2 14270.6 14457.0 14537.3 328323.0 334521.0 334729.0 335236.0 335314.0 47332.8 48211.0 48218.5 48281.0 48319.3 1003.3 1192.7 1193.8 1202.4 1318.0 24602.4 25500.5 25822.2 26397.0 27744.7 38061.2 38301.6 38389.6 38428.8 38726.6 184735.0 184854.0 185075.0 185467.0 185486.0 21248.8 22546.2 23308.8 23460.6 23530.5 37842.4 38257.4 38565.0 38927.8 39434.2 33226.7 33281.0 33472.0 33886.7 33980.3 33199.8 33230.5 33440.8 33552.8 33745.3 32571.8 33133.3 33367.0 33433.3 33477.7 28933.6 30775.2 30832.5 30913.8 30929.4 23507.5 23628.5 24057.5 24210.6 24324.4 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | [#2] jquery.html Cycles:180 Average:24064.79 Median:386.94 stddev:58693.44 (15168.6%) stddev-sans-first:58831.15 09:35:06 INFO - PROCESS | 14019 | Values: 379.0 383.5 390.3 397.0 398.2 15967.8 16570.9 16875.8 16896.5 16936.8 15591.1 15740.8 16488.8 16508.0 16547.9 5031.2 5172.2 5232.9 5235.3 5238.3 3637.8 3822.9 5651.7 5923.8 5967.2 5847.9 5889.0 5892.1 5894.8 5926.9 5809.7 5907.2 5960.4 5972.4 5975.6 3277.4 5009.8 5257.9 5268.0 5275.7 18964.2 19078.1 21652.5 21743.0 22040.4 1890.3 1967.1 2053.0 2257.7 2488.5 13573.0 14191.9 14443.1 14656.2 14809.3 1791.5 1818.0 1856.2 1945.9 1957.2 79080.2 79487.0 80368.6 80419.6 82655.0 15872.8 16051.9 16094.4 16207.5 16373.0 28629.3 28682.9 28978.1 28988.4 29021.4 8579.1 8863.2 8974.0 9044.9 9201.8 6973.9 7021.4 7472.1 7497.3 7574.5 8126.5 8340.5 8369.1 8523.6 8552.9 2847.1 3240.8 3241.9 3328.4 3445.2 846.8 856.3 857.2 909.3 986.8 15507.8 15839.6 15864.2 16137.8 16675.2 863.1 890.6 903.7 934.4 951.4 8961.5 9313.2 9371.2 9375.1 9475.5 348525.0 349449.0 353914.0 354850.0 361245.0 73582.6 77214.6 77494.2 77779.6 79533.0 909.0 921.8 944.5 954.1 957.0 11290.4 14178.0 14463.1 14605.0 14825.3 24577.5 26311.0 28255.6 33470.2 34076.0 43978.0 44655.6 44890.6 44945.6 44981.8 7780.3 7847.0 7908.7 7913.4 7937.2 9638.1 10686.9 11195.5 11743.6 11862.9 10059.7 10109.9 10259.4 10306.9 10363.9 10175.9 10227.9 10410.1 10472.3 10486.9 10116.1 10125.6 10128.3 10191.5 10374.2 7650.3 7775.8 7873.1 7886.5 7933.1 9582.2 9868.1 9879.4 10000.2 10137.4 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | [#3] mootools.html Cycles:180 Average:4852.61 Median:5484.51 stddev:2667.44 (48.6%) stddev-sans-first:2661.30 09:35:06 INFO - PROCESS | 14019 | Values: 1265.4 1450.7 1467.3 1580.8 1733.2 6919.5 7235.8 7236.6 7245.7 7252.6 6998.5 7210.5 7212.2 7226.6 7233.0 1944.0 2003.2 2141.1 2159.8 2192.0 2158.4 2169.4 2173.6 2174.0 2175.5 2134.8 2154.4 2187.5 2192.0 2198.2 1876.9 2076.7 2111.5 2133.3 2134.9 2129.7 2130.5 2149.6 2150.7 2154.4 7044.6 7079.0 7135.9 7145.2 7147.1 4424.5 4604.8 4607.6 4612.7 4616.3 2825.1 2833.6 2899.6 2911.8 2914.4 859.8 863.0 876.4 886.0 891.6 9802.9 9854.6 9865.9 9883.7 9885.6 4048.3 4155.9 4236.9 4243.6 4243.8 8341.0 8380.8 8407.8 8424.1 8436.9 4528.8 4584.1 4596.2 4598.5 4601.5 3757.5 4197.1 4275.4 4298.9 4312.4 3407.7 3463.3 3483.4 3522.8 3539.4 1202.4 1203.2 1204.2 1206.2 1222.4 734.5 742.3 811.8 817.8 845.1 4955.1 4961.1 5015.4 5029.0 5105.1 799.5 813.4 819.5 847.4 879.7 3305.1 3451.1 3457.1 3478.8 3513.9 8685.7 8992.7 9012.4 9111.9 9127.9 8982.0 9000.2 9010.1 9019.2 9048.0 6922.7 7058.2 7085.4 7238.3 7279.8 4907.6 4970.9 4979.0 5017.0 5073.5 8669.5 8733.3 8744.3 8823.0 8874.4 8873.4 9054.5 9074.7 9076.1 9205.3 5408.5 5645.8 5849.5 6288.4 6407.2 6635.3 6654.8 6672.0 6703.4 6734.3 5829.9 6051.7 6132.6 6136.6 6138.7 6022.5 6212.4 6227.0 6292.6 6437.0 5560.6 5565.6 5582.3 5605.2 5629.0 4093.7 4096.8 4098.8 4201.6 4218.9 4178.9 4207.5 4227.0 4290.6 4329.7 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | [#4] prototype.html Cycles:180 Average:4228.42 Median:4717.84 stddev:1213.95 (25.7%) stddev-sans-first:1181.16 09:35:06 INFO - PROCESS | 14019 | Values: 308.4 310.4 310.7 315.0 315.6 4670.0 4716.6 4746.6 4755.6 4790.4 4656.8 4705.3 4716.4 4719.1 4720.2 3533.8 3575.4 3665.2 3682.9 3736.4 3701.9 3708.7 3718.1 3730.6 3731.6 3530.8 3698.9 3701.1 3709.0 3722.5 3592.4 3710.2 3718.8 3762.2 3766.7 3303.0 3308.4 3316.0 3329.9 3347.6 4428.0 4490.4 4503.1 4534.2 4537.4 4067.7 4273.7 4285.8 4287.5 4291.2 4900.5 4921.9 4992.3 4993.2 5001.5 3660.5 3662.6 3674.4 3680.9 3686.3 5032.9 5158.3 5233.6 5252.8 5253.2 3768.9 3783.0 3783.5 3791.8 3804.9 3794.4 3810.6 3845.8 3845.8 3854.2 4238.9 4281.3 4286.2 4291.6 4293.7 4790.2 4959.1 4974.4 4975.5 4996.0 1367.8 1371.1 1374.3 1376.7 1382.4 898.3 940.0 957.8 960.4 970.9 4660.2 4668.2 4676.0 4683.8 4710.8 5397.6 5417.6 5424.5 5435.1 5436.6 4530.9 4653.2 4675.0 4693.6 4702.0 2863.1 2876.4 2886.6 2890.4 2895.1 5076.9 5095.6 5100.5 5106.7 5117.6 5333.0 5509.5 5514.0 5560.3 5561.8 5125.3 5137.8 5141.4 5151.8 5163.3 5260.9 5324.9 5422.9 5431.6 5455.2 5242.5 5265.3 5266.0 5278.6 5295.8 5059.9 5094.2 5098.9 5117.0 5143.5 3862.8 3906.2 3907.9 3912.6 3922.9 4859.5 5086.3 5125.6 5161.9 5187.4 4904.4 4928.4 4943.7 4968.9 4992.6 4822.6 4973.9 5003.9 5014.6 5041.2 4663.8 4862.9 4897.2 4915.6 4930.0 4824.0 4904.9 4912.9 4920.9 4944.2 4862.9 4895.1 4922.5 4927.4 4941.6 09:35:06 INFO - PROCESS | 14019 | 09:35:06 INFO - PROCESS | 14019 | [#5] yui.html Cycles:180 Average:12256.87 Median:46447.04 stddev:13747.24 (29.6%) stddev-sans-first:13757.98 09:35:06 INFO - PROCESS | 14019 | Values: 610.6 671.8 749.3 770.3 772.9 11879.9 12272.6 12322.4 12350.9 12359.4 11954.9 12048.6 13011.8 13307.8 13351.3 813.7 819.4 821.7 821.8 822.2 643.6 785.4 813.9 814.6 818.3 847.1 849.1 850.0 850.0 850.3 845.3 845.6 847.4 847.7 848.7 497.2 606.1 622.7 622.8 624.2 12723.2 12736.4 12773.4 12786.8 12786.8 11356.8 11673.2 11780.9 11998.4 12091.9 9487.1 9609.4 9625.2 9720.0 10370.2 3278.5 3299.5 3312.8 3337.3 3339.9 42062.0 42779.0 42898.0 43254.1 43579.3 9506.7 9665.0 9667.9 9682.9 9718.3 16448.0 16533.2 16626.3 16626.5 16672.6 6436.2 6604.3 6646.0 6801.6 6869.0 3440.1 3506.5 3528.2 3534.0 3634.8 2754.5 2783.7 2787.6 2797.1 2798.8 494.5 498.1 500.7 512.6 514.2 576.6 593.4 595.4 610.2 611.1 6921.9 6973.2 7145.0 7155.7 7315.1 548.3 569.4 576.3 581.5 582.3 3247.5 3411.7 3416.2 3445.7 3482.2 46867.8 48119.8 48315.0 48735.3 49356.0 44311.3 44623.8 45094.3 45395.3 46026.3 40343.6 40401.6 40954.6 41121.2 41526.8 6612.2 6719.4 6748.8 6820.7 7589.4 28359.1 28708.0 29744.0 29823.1 30427.1 37407.0 37498.8 37662.1 37947.8 38107.2 9835.5 10230.0 10428.5 10560.8 10599.4 11312.8 11512.8 11692.6 12288.4 12441.8 9718.7 9822.5 9851.5 9852.4 10154.3 10948.4 11479.8 11496.1 11808.8 11834.2 10323.5 10598.8 10634.8 10790.6 11012.1 8443.7 8577.3 8773.7 8975.2 9040.3 8276.6 8328.2 8487.0 8594.3 8671.7 09:35:06 INFO - PROCESS | 14019 | -------- Summary: end -------- 09:35:06 INFO - PROCESS | 14019 | 09:35:07 INFO - TEST-INFO | 14019: exit 0 09:35:07 INFO - TEST-OK | dromaeo_css | took 1120778ms 09:35:07 INFO - TEST-START | kraken 09:35:07 INFO - operating with platform_type : linux_ 09:35:07 INFO - Initialising browser for kraken test... 09:35:07 INFO - TEST-INFO | started process 14195 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph4DT7U/profile http://localhost:34235/getInfo.html) 09:35:12 INFO - PROCESS | 14195 | __metrics Screen width/height:1600/1200 09:35:12 INFO - PROCESS | 14195 | colorDepth:24 09:35:12 INFO - PROCESS | 14195 | Browser inner width/height: 1024/697 09:35:12 INFO - PROCESS | 14195 | __metrics 09:35:13 ERROR - PROCESS | 14195 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:35:14 INFO - TEST-INFO | 14195: exit 0 09:35:14 INFO - Browser initialized. 09:35:14 INFO - Running cycle 1/1 for kraken test... 09:35:14 INFO - TEST-INFO | started process 14278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph4DT7U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:26 INFO - PROCESS | 14278 | RSS: Main: 155996160 09:35:26 INFO - PROCESS | 14278 | 09:35:29 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34235/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:30 INFO - PROCESS | 14278 | RSS: Main: 210628608 09:35:30 INFO - PROCESS | 14278 | 09:35:33 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34235/tests/kraken/driver.html?testName=audio-dft) 09:35:33 INFO - PROCESS | 14278 | RSS: Main: 196517888 09:35:33 INFO - PROCESS | 14278 | 09:35:36 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34235/tests/kraken/driver.html?testName=audio-fft) 09:35:37 INFO - PROCESS | 14278 | RSS: Main: 267620352 09:35:37 INFO - PROCESS | 14278 | 09:35:39 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34235/tests/kraken/driver.html?testName=audio-oscillator) 09:35:40 INFO - PROCESS | 14278 | RSS: Main: 175525888 09:35:40 INFO - PROCESS | 14278 | 09:35:42 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:43 INFO - PROCESS | 14278 | RSS: Main: 179130368 09:35:43 INFO - PROCESS | 14278 | 09:35:46 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34235/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:47 INFO - PROCESS | 14278 | RSS: Main: 215740416 09:35:47 INFO - PROCESS | 14278 | 09:35:50 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34235/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:51 INFO - PROCESS | 14278 | RSS: Main: 216158208 09:35:51 INFO - PROCESS | 14278 | 09:35:54 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34235/tests/kraken/driver.html?testName=json-parse-financial) 09:35:54 INFO - PROCESS | 14278 | RSS: Main: 216444928 09:35:54 INFO - PROCESS | 14278 | 09:35:56 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:56 INFO - PROCESS | 14278 | RSS: Main: 203403264 09:35:56 INFO - PROCESS | 14278 | 09:35:58 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:59 INFO - PROCESS | 14278 | RSS: Main: 204484608 09:35:59 INFO - PROCESS | 14278 | 09:36:01 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:02 INFO - PROCESS | 14278 | RSS: Main: 216944640 09:36:02 INFO - PROCESS | 14278 | 09:36:04 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:05 INFO - PROCESS | 14278 | RSS: Main: 224952320 09:36:05 INFO - PROCESS | 14278 | 09:36:08 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:09 INFO - PROCESS | 14278 | RSS: Main: 221429760 09:36:09 INFO - PROCESS | 14278 | 09:36:10 INFO - PROCESS | 14278 | Cycle 1(1): loaded http://localhost:34235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34235/tests/kraken/driver.html?testName=ai-astar) 09:36:11 INFO - PROCESS | 14278 | RSS: Main: 285327360 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | __start_tp_report 09:36:11 INFO - PROCESS | 14278 | _x_x_mozilla_page_load 09:36:11 INFO - PROCESS | 14278 | _x_x_mozilla_page_load_details 09:36:11 INFO - PROCESS | 14278 | |i|pagename|runs| 09:36:11 INFO - PROCESS | 14278 | |0;ai-astar;115;116;109;114;112;114;102;112;112;113 09:36:11 INFO - PROCESS | 14278 | |1;audio-beat-detection;163;140;156;182;135;154;152;132;205;133 09:36:11 INFO - PROCESS | 14278 | |2;audio-dft;160;161;159;160;162;160;162;162;161;162 09:36:11 INFO - PROCESS | 14278 | |3;audio-fft;75;77;108;111;78;74;78;124;106;77 09:36:11 INFO - PROCESS | 14278 | |4;audio-oscillator;95;96;154;94;94;95;135;93;94;140 09:36:11 INFO - PROCESS | 14278 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;116;117;117 09:36:11 INFO - PROCESS | 14278 | |6;imaging-darkroom;147;145;145;146;146;145;145;145;145;145 09:36:11 INFO - PROCESS | 14278 | |7;imaging-desaturate;95;95;95;95;94;95;94;94;95;95 09:36:11 INFO - PROCESS | 14278 | |8;json-parse-financial;65;65;65;65;66;67;65;84;64;64 09:36:11 INFO - PROCESS | 14278 | |9;json-stringify-tinderbox;63;64;63;63;64;63;65;65;68;64 09:36:11 INFO - PROCESS | 14278 | |10;stanford-crypto-aes;78;78;78;78;81;81;79;79;80;79 09:36:11 INFO - PROCESS | 14278 | |11;stanford-crypto-ccm;131;131;129;131;129;127;128;131;162;130 09:36:11 INFO - PROCESS | 14278 | |12;stanford-crypto-pbkdf2;174;173;176;175;175;174;179;173;178;179 09:36:11 INFO - PROCESS | 14278 | |13;stanford-crypto-sha256-iterative;72;73;73;73;73;74;85;72;73;73 09:36:11 INFO - PROCESS | 14278 | __end_tp_report 09:36:11 INFO - PROCESS | 14278 | __start_cc_report 09:36:11 INFO - PROCESS | 14278 | _x_x_mozilla_cycle_collect,1907 09:36:11 INFO - PROCESS | 14278 | __end_cc_report 09:36:11 INFO - PROCESS | 14278 | __startTimestamp1461602171204__endTimestamp 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | ------- Summary: start ------- 09:36:11 INFO - PROCESS | 14278 | Number of tests: 14 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.50 stddev:3.98 (3.5%) stddev-sans-first:4.07 09:36:11 INFO - PROCESS | 14278 | Values: 115.0 116.0 109.0 114.0 112.0 114.0 102.0 112.0 112.0 113.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#1] audio-beat-detection Cycles:10 Average:155.20 Median:155.00 stddev:23.43 (15.1%) stddev-sans-first:24.68 09:36:11 INFO - PROCESS | 14278 | Values: 163.0 140.0 156.0 182.0 135.0 154.0 152.0 132.0 205.0 133.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.50 stddev:1.10 (0.7%) stddev-sans-first:1.12 09:36:11 INFO - PROCESS | 14278 | Values: 160.0 161.0 159.0 160.0 162.0 160.0 162.0 162.0 161.0 162.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#3] audio-fft Cycles:10 Average:90.80 Median:76.00 stddev:19.08 (25.1%) stddev-sans-first:19.37 09:36:11 INFO - PROCESS | 14278 | Values: 75.0 77.0 108.0 111.0 78.0 74.0 78.0 124.0 106.0 77.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:94.00 stddev:23.93 (25.5%) stddev-sans-first:24.84 09:36:11 INFO - PROCESS | 14278 | Values: 95.0 96.0 154.0 94.0 94.0 95.0 135.0 93.0 94.0 140.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:36:11 INFO - PROCESS | 14278 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.44 09:36:11 INFO - PROCESS | 14278 | Values: 147.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:36:11 INFO - PROCESS | 14278 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 95.0 95.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:65.00 stddev:6.04 (9.3%) stddev-sans-first:6.36 09:36:11 INFO - PROCESS | 14278 | Values: 65.0 65.0 65.0 65.0 66.0 67.0 65.0 84.0 64.0 64.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:1.55 (2.4%) stddev-sans-first:1.58 09:36:11 INFO - PROCESS | 14278 | Values: 63.0 64.0 63.0 63.0 64.0 63.0 65.0 65.0 68.0 64.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:1.20 (1.5%) stddev-sans-first:1.20 09:36:11 INFO - PROCESS | 14278 | Values: 78.0 78.0 78.0 78.0 81.0 81.0 79.0 79.0 80.0 79.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#11] stanford-crypto-ccm Cycles:10 Average:132.90 Median:131.00 stddev:10.32 (7.9%) stddev-sans-first:10.93 09:36:11 INFO - PROCESS | 14278 | Values: 131.0 131.0 129.0 131.0 129.0 127.0 128.0 131.0 162.0 130.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:175.50 stddev:2.32 (1.3%) stddev-sans-first:2.39 09:36:11 INFO - PROCESS | 14278 | Values: 174.0 173.0 176.0 175.0 175.0 174.0 179.0 173.0 178.0 179.0 09:36:11 INFO - PROCESS | 14278 | 09:36:11 INFO - PROCESS | 14278 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:73.00 stddev:3.87 (5.3%) stddev-sans-first:4.03 09:36:11 INFO - PROCESS | 14278 | Values: 72.0 73.0 73.0 73.0 73.0 74.0 85.0 72.0 73.0 73.0 09:36:11 INFO - PROCESS | 14278 | -------- Summary: end -------- 09:36:11 INFO - PROCESS | 14278 | 09:36:12 INFO - TEST-INFO | 14278: exit 0 09:36:12 INFO - TEST-OK | kraken | took 65564ms 09:36:12 INFO - SUITE-END | took 1186s 09:36:12 INFO - Completed test suite (00:19:46) 09:36:12 INFO - javascript benchmark 09:36:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2632.5393056215157, 2680.128958088622, 2697.2852194168536, 2704.959425608616, 2729.9181024569257, 4633.49031606523, 4720.221163508021, 4723.598494128003, 4730.834420795269, 4762.190427627299, 4654.557817007388, 4723.85828425147, 4779.784909679064, 4783.139034973703, 4799.232122860343, 4266.36004599311, 4278.101598664276, 4297.978510107445, 4319.092990471989, 4384.342348647697, 4332.0469496710675, 4385.802638881258, 4395.033092719596, 4423.889402764925, 4458.9331160032625, 4292.957070429318, 4372.737635741833, 4401.955980440202, 4417.558244175573, 4419.447569053868, 4268.786560671985, 4298.849540266107, 4311.310190369557, 4321.935170972407, 4324.956750432519, 4316.330968699856, 4349.586789255016, 4352.347147927804, 4383.364412160219, 4388.934165987513, 4548.135854187693, 4645.744484053375, 4646.53534646536, 4648.767561621906, 4662.347271381976, 5015.27278544609, 5056.949430505685, 5058.215976523645, 5095.108356037716, 5098.464661210556, 5041.395032596075, 5043.445221025684, 5050.772715227824, 5057.544820966131, 5118.820841270575, 4517.638588912879, 4659.4175728034, 4709.152352576569, 4712.175369310357, 4716.575508204278, 5009.148444764399, 5045.949540504622, 5069.239614057847, 5078.7460626968505, 5128.9230661540105, 4840.419149702058, 4849.733264670477, 4852.369192005017, 4854.63590230727, 4896.853094407174, 4938.407391113089, 4955.529224723646, 4966.453690094087, 5001.874953126201, 5035.295058691713, 4866.221404575251, 4889.242167464049, 4894.559489645949, 4929.704217746947, 4933.6793108448555, 4849.4908034656555, 4851.927221091686, 4855.684380515255, 4864.027194561102, 4909.631777616693, 4118.382242663564, 4172.603602657743, 4178.331466965271, 4179.393987871802, 4260.978695106504, 3716.6655001050035, 3730.626937306248, 3731.794751288651, 3769.509963704701, 3769.792661403594, 4403.559644035571, 4411.558844115627, 4412.558744125626, 4433.933490997638, 4459.710118842265, 5003.54968052877, 5046.823361182341, 5056.544910957958, 5076.82231121909, 5140.897182056338, 4329.523752387235, 4331.285337919208, 4335.804888780012, 4363.47638283408, 4392.978035109868, 4470.642348612103, 4471.3020988759445, 4471.642268618502, 4479.641628669699, 4513.887152821206, 4626.467956185014, 4708.176069187947, 4754.072955773591, 4769.451513075902, 4789.712617242976, 4917.655764096622, 4932.950670493393, 4942.209246520683, 4952.603791696728, 5004.574611158092, 4684.508126646653, 4686.109639168547, 4691.061787642417, 4733.644976626698, 4747.382840230748, 4893.9021219576125, 4904.166291730343, 4945.1346014448045, 4998.250262460515, 5012.997400519984, 4913.336699545515, 4923.335349727741, 4926.5073492650445, 4927.679700819435, 4973.229149481836, 4768.713877167381, 4781.402324709411, 4782.354382158364, 4784.712917224978, 4839.0, 4793.616510679207, 4857.53853383928, 4880.194767863405, 4927.260910863399, 4929.926051109164, 4735.479097299225, 4753.548412900769, 4759.119562880878, 4763.499832517536, 4813.15769740301, 4843.0, 4847.636427268039, 4850.854474365775, 4858.319835223142, 4859.343988561499, 4835.685680430761, 4901.0, 4905.264210368473, 4941.258811178352, 4955.0], "value": 4599.683150801577, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.5726883471814, 607.4047433515155, 608.4645511949481, 617.8424501752053, 626.592714735422, 33233.66766332336, 34410.13974650635, 34556.48165277517, 34657.826710866444, 34672.99999999997, 36909.07727306812, 37092.36345456818, 37485.437718434245, 37566.4365034524, 37664.05839854005, 7700.730474433396, 7856.646450909694, 7898.5260884346835, 7906.565138917357, 7914.327282180992, 7734.651940662684, 7900.446968712193, 7902.130765615778, 8090.474119182234, 8093.959530202341, 8083.555404452753, 8151.755447336589, 8173.386995975326, 8174.877376839352, 8178.263956243937, 8037.758867233963, 8110.310623596943, 8111.0, 8111.675532978674, 8115.026196856356, 7869.606519674051, 7968.800779980489, 7969.920300796975, 7982.401319901031, 7982.840343193162, 33926.49110263348, 34174.48738268928, 34214.48678269828, 34339.6566034339, 34359.484607730905, 2762.0608992942293, 3023.38020705757, 3044.451998640244, 3466.9306613867834, 3579.158897659048, 28359.0, 28405.289867753265, 28559.714402855912, 28646.85676571604, 29286.41427171466, 3360.7311415086733, 3399.201187720884, 3403.5235067090625, 3409.2670075933793, 3500.7374446916583, 166593.0, 167645.0, 167736.16131919262, 167887.0, 168584.0, 29243.268918276786, 30090.54864177039, 30146.24634384114, 47696.799416075686, 48446.757766211646, 31695.52456713151, 31881.521777173362, 32047.0, 32067.839660801546, 32104.357912841613, 12602.180858244184, 12752.043596730096, 12769.936150319189, 12775.297358645177, 12780.552680656132, 10549.156067514581, 10552.894471055191, 10561.0, 10567.894321056845, 10580.312279701795, 8012.038555373392, 8040.83918321642, 8084.8383032339025, 8143.42995990275, 8153.388495862834, 3181.2206009527813, 3231.935361292808, 3237.9352412951607, 3263.738900887923, 3286.76992610515, 855.2730179347504, 885.5306687455659, 923.3167456082585, 1044.8693913260843, 1067.690369792767, 27306.863465682545, 27861.860690696416, 28395.290117747223, 28619.57070643942, 28740.99406520762, 780.9414293927978, 924.3760461688333, 961.1781926452921, 1032.808930347888, 1117.3351855645878, 13713.862861371259, 14165.150090994573, 14270.571882843531, 14457.0, 14537.273136343141, 328323.0, 334521.0, 334729.0, 335236.0, 335314.0, 47332.763336183096, 48211.03577928422, 48218.5178148221, 48281.0, 48319.27521087186, 1003.2575893838507, 1192.6779769462196, 1193.7791508570942, 1202.3747651221315, 1317.952227978759, 24602.384940376633, 25500.48999019972, 25822.225333240032, 26397.0, 27744.72255277502, 38061.2387752249, 38301.61698383092, 38389.616103838605, 38428.80785596054, 38726.6127338723, 184735.0, 184854.0, 185075.0, 185467.0, 185486.0, 21248.78751212468, 22546.210882619685, 23308.76691233066, 23460.64809027832, 23530.529389411775, 37842.43236351402, 38257.426138607385, 38565.00002497251, 38927.80536097301, 39434.2113157741, 33226.667733322356, 33281.0, 33472.0, 33886.66113338835, 33980.32039359248, 33199.83400082984, 33230.510796366034, 33440.83279583684, 33552.83223583964, 33745.3250934975, 32571.837140814143, 33133.33733325272, 33367.0, 33433.33133337368, 33477.66522334745, 28933.56599650965, 30775.2306192347, 30832.53751193689, 30913.8454307727, 30929.381412371178, 23507.529849403258, 23628.52742945166, 24057.51884962326, 24210.636840447056, 24324.391890202885], "value": 15420.370811820318, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.04291663549543, 383.5378369065277, 390.3422732695408, 396.9702272329576, 398.22743876878855, 15967.840321596781, 16570.917145414256, 16875.831241687578, 16896.49310520685, 16936.830631693694, 15591.1424871632, 15740.84259157408, 16488.75266870995, 16508.0, 16547.91726041368, 5031.195008798593, 5172.19830926207, 5232.92150617741, 5235.345581802275, 5238.266642670029, 3637.7817330960097, 3822.923541529168, 5651.717414129298, 5923.8222853314255, 5967.16459695643, 5847.8538036549, 5888.97055514724, 5892.14563888236, 5894.764209431618, 5926.88146237075, 5809.651420914737, 5907.172995780594, 5960.433758792908, 5972.4027597240365, 5975.551833612475, 3277.36091462165, 5009.799608015675, 5257.86855328616, 5268.0, 5275.657082289639, 18964.24143034277, 19078.14148363327, 21652.458688532755, 21743.0, 22040.44898877525, 1890.2816929566814, 1967.0951362373326, 2052.958940821175, 2257.683924250606, 2488.4525404411, 13572.982026348063, 14191.858081419157, 14443.0612010219, 14656.193909334983, 14809.259537023107, 1791.5431564950984, 1817.954551136219, 1856.2482194711029, 1945.9318923837598, 1957.2366776956999, 79080.20919790785, 79487.0, 80368.59815700885, 80419.5979020107, 82655.0, 15872.76190857115, 16051.919740401223, 16094.436694715629, 16207.513774586781, 16373.0, 28629.28426789347, 28682.856585716938, 28978.13065608035, 28988.42023159525, 29021.41957160845, 8579.099194584478, 8863.157999989991, 8974.0, 9044.909550904407, 9201.757762702084, 6973.860522789516, 7021.403180729695, 7472.065991751031, 7497.335481472302, 7574.469787114959, 8126.471779334324, 8340.541270230196, 8369.121242269595, 8523.573821309034, 8552.914470855212, 2847.135126492151, 3240.8448381826292, 3241.8541165647603, 3328.4008878401637, 3445.173158441956, 846.7502086884384, 856.3106699107154, 857.1856736100605, 909.2816674826945, 986.8223719730518, 15507.767383489258, 15839.604009899615, 15864.206789660471, 16137.838621613868, 16675.24961376741, 863.0765081363007, 890.6214858685033, 903.7316335996695, 934.3646320501988, 951.4196340232453, 8961.462312261285, 9313.161815436508, 9371.203447707021, 9375.062493750707, 9475.47884866339, 348525.0, 349449.0, 353914.0, 354850.0, 361245.0, 73582.63208683922, 77214.61392693112, 77494.22505774983, 77779.61110194413, 79533.0, 909.027340745396, 921.7649499377616, 944.4946953379908, 954.0650162840456, 957.0286159548065, 11290.379029153475, 14178.0, 14463.13221206731, 14604.977651564714, 14825.25873706332, 24577.50844983126, 26311.0, 28255.576166357932, 33470.163245919044, 34076.0, 43978.0, 44655.55344446644, 44890.551094488634, 44945.55054449545, 44981.775091124335, 7780.260875216845, 7847.0, 7908.723194688158, 7913.406494513049, 7937.2459616336355, 9638.132568068908, 10686.8931310689, 11195.496202671062, 11743.588974385855, 11862.940685296518, 10059.69820905374, 10109.949450252996, 10259.384436933808, 10306.948465257627, 10363.94818025905, 10175.89824101779, 10227.897721022695, 10410.115140213167, 10472.266941314036, 10486.947565262124, 10116.08955194036, 10125.64560240388, 10128.34165779222, 10191.490425478845, 10374.22193335518, 7650.349720273839, 7775.766726998199, 7873.0945941215205, 7886.487378320391, 7933.127355990721, 9582.185514231369, 9868.062534059252, 9879.407235565888, 10000.249981251582, 10137.391756494633], "value": 8891.94506315734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1265.3926115464583, 1450.7388670039393, 1467.3323637744825, 1580.7628855671649, 1733.1854028606551, 6919.515633905628, 7235.782926512206, 7236.601986890732, 7245.710171593137, 7252.637368131585, 6998.510104292702, 7210.531315464491, 7212.170600380958, 7226.6025368604705, 7233.0, 1943.9611207775836, 2003.1786967343392, 2141.06863514371, 2159.762426133124, 2191.9890400547974, 2158.438805910459, 2169.360038788553, 2173.576152650234, 2173.9782602174014, 2175.5213852952324, 2134.7651758306574, 2154.429076294783, 2187.453136715821, 2191.9890400547974, 2198.1976578548793, 1876.9155388007573, 2076.678114892194, 2111.5460176062143, 2133.296012315932, 2134.9146034158616, 2129.701841742142, 2130.4886827161527, 2149.5593403352277, 2150.7204063471804, 2154.429076294783, 7044.612546309951, 7078.964605176941, 7135.89296160558, 7145.178304494998, 7147.070880785516, 4424.491183513905, 4604.815807367701, 4607.631389488833, 4612.700174488681, 4616.330632058366, 2825.095969289828, 2833.5891295762203, 2899.5650652402096, 2911.8252904825777, 2914.3879785244912, 859.8323326951247, 862.969796057135, 876.4390789894472, 885.9557022148866, 891.6433426629335, 9802.852957205576, 9854.556544955494, 9865.852012219822, 9883.654072107442, 9885.555150018266, 4048.291548978917, 4155.937660935088, 4236.872893813191, 4243.554426785205, 4243.8090285937205, 8341.0, 8380.790480237922, 8407.8318433631, 8424.073351931283, 8436.915630843736, 4528.773561321921, 4584.060267645192, 4596.17268891596, 4598.517155698671, 4601.470830854428, 3757.5490941087246, 4197.055662475918, 4275.38006988991, 4298.935515967263, 4312.439382880206, 3407.7391365195035, 3463.290025544757, 3483.3555792178527, 3522.8238588070494, 3539.3983022886173, 1202.3567391445536, 1203.2419575667448, 1204.241327963395, 1206.2099324942176, 1222.4498975461079, 734.5115498193749, 742.3356096293787, 811.7929927868474, 817.8200795824908, 845.0535400351646, 4955.108080545537, 4961.112654259783, 5015.398152221684, 5028.974855125701, 5105.0810854045885, 799.490871484826, 813.4265342933218, 819.5451524403894, 847.4110493207285, 879.6701237036111, 3305.1406634275263, 3451.0854623524788, 3457.083872773717, 3478.843452044664, 3513.94729079064, 8685.739427817176, 8992.685256016008, 9012.369134160677, 9111.908880911105, 9127.863082053775, 8981.955090224506, 9000.23498002664, 9010.14403631654, 9019.188273055457, 9048.0, 6922.653867306614, 7058.188308344606, 7085.397741192057, 7238.27617238272, 7279.818004549928, 4907.55831975124, 4970.9254361183885, 4979.0, 5016.974915125401, 5073.518015788494, 8669.523176225368, 8733.257673097603, 8744.344174186834, 8822.95588522079, 8874.423162494417, 8873.378863479753, 9054.502002389932, 9074.682386116718, 9076.092390761136, 9205.263578913804, 5408.45915408456, 5645.830625081254, 5849.473547380756, 6288.371162883675, 6407.199100112486, 6635.336466353326, 6654.800355989328, 6672.03255527936, 6703.363180497845, 6734.259231484631, 5829.854253643693, 6051.697415129314, 6132.63204207749, 6136.588584625651, 6138.693065346804, 6022.45797878193, 6212.409821066992, 6227.034809604518, 6292.612997807138, 6436.967815160894, 5560.5551555876245, 5565.55475561946, 5582.27430434041, 5605.159226116115, 5628.971855140698, 4093.7339072960162, 4096.8156432959995, 4098.8360465581045, 4201.558836322142, 4218.894527636834, 4178.916421671489, 4207.495100587949, 4227.0, 4290.63529599975, 4329.740215587075], "value": 3947.3865303731504, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.41401337458825, 310.44895310823284, 310.6768960281307, 315.0202869077171, 315.55664291670195, 4669.953300466995, 4716.575508204261, 4746.572808447239, 4755.595774359179, 4790.4191616766475, 4656.813727450906, 4705.3177289293035, 4716.363290955728, 4719.1033703596295, 4720.244760838266, 3533.8056406897545, 3575.3564358415474, 3665.2303016366463, 3682.907927301812, 3736.4395340698843, 3701.870434534792, 3708.6662200401956, 3718.0518967663206, 3730.6269373062614, 3731.5708693500324, 3530.8411121499466, 3698.8520459181736, 3701.0932321581117, 3709.0, 3722.516072910518, 3592.443171308438, 3710.165212827092, 3718.772804974352, 3762.172322089136, 3766.7363284569956, 3303.0, 3308.354870800195, 3315.950260746091, 3329.916752081193, 3347.6317605063423, 4428.0, 4490.440448944103, 4503.144402563536, 4534.206513860061, 4537.387452693877, 4067.6542493888055, 4273.658107351435, 4285.807138678767, 4287.49937521868, 4291.227579035773, 4900.460949295574, 4921.901561968741, 4992.326035985132, 4993.177490216081, 5001.474845141244, 3660.4875317455576, 3662.633736626343, 3674.3569875271987, 3680.907977300562, 3686.299603075407, 5032.917922646649, 5158.252053452228, 5233.555147812441, 5252.842414727526, 5253.212018197263, 3768.8680896168503, 3782.962170378316, 3783.4892289541112, 3791.7724936503346, 3804.8668296609485, 3794.354959656865, 3810.6189381062227, 3845.7692538447222, 3845.769253844778, 3854.2291541691775, 4238.936415953764, 4281.314989601648, 4286.164197981426, 4291.570842915746, 4293.656507479394, 4790.233562629963, 4959.057779021974, 4974.353334066621, 4975.4527002029745, 4996.0, 1367.7948307753718, 1371.149887070023, 1374.3334482776045, 1376.6833628265356, 1382.4124746982493, 898.3172788680519, 940.0176815228103, 957.8419560772534, 960.3853533738413, 970.8932017478072, 4660.161170989254, 4668.229742092584, 4676.018036212434, 4683.765811709469, 4710.811567537328, 5397.62360598045, 5417.59368047398, 5424.538914192259, 5435.103207970719, 5436.646617969819, 4530.864074077783, 4653.185692503798, 4674.9715062686155, 4693.577578017995, 4701.967231135966, 2863.1267463423856, 2876.3528206153865, 2886.62885129566, 2890.4363649088646, 2895.102518219359, 5076.923846142311, 5095.592352611782, 5100.464451232567, 5106.693598384109, 5117.641765076483, 5332.9733351333, 5509.5041446270025, 5514.035043867388, 5560.332760068818, 5561.805336813192, 5125.2568377585785, 5137.818301790609, 5141.38303403594, 5151.793928242903, 5163.251328557263, 5260.894782104413, 5324.9467505326, 5422.945770542244, 5431.646942948696, 5455.236266922713, 5242.501962313574, 5265.262863199232, 5266.0, 5278.630495865253, 5295.822506496072, 5059.949400505948, 5094.2103973884105, 5098.872528186825, 5117.0, 5143.537081662668, 3862.8454861806335, 3906.160175562268, 3907.882763517099, 3912.5891781362548, 3922.9215415692097, 4859.465458799458, 5086.338775959101, 5125.641205115606, 5161.896762064813, 5187.429382767968, 4904.411470623548, 4928.383952005999, 4943.653944224014, 4968.8571628524915, 4992.6255530834605, 4822.565969062801, 4973.875653108701, 5003.949960500348, 5014.623903207201, 5041.168207245763, 4663.836765713184, 4862.854114376574, 4897.191963326154, 4915.582175515121, 4929.9787087294035, 4823.95176048235, 4904.901901961869, 4912.852614421573, 4920.8769780755765, 4944.15949288629, 4862.927056094232, 4895.118878601886, 4922.507749225048, 4927.384076990376, 4941.6293777966075], "value": 3854.0914356959966, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.5909040942568, 671.83540032692, 749.2806905370844, 770.3259647808168, 772.8686123359032, 11879.940600297008, 12272.570460033878, 12322.445489952963, 12350.938245308784, 12359.443825027884, 11954.880451195508, 12048.57829975951, 13011.804822927663, 13307.800382994263, 13351.265680387574, 813.66639677732, 819.3527113580265, 821.6713314674148, 821.765796747927, 822.1901427094338, 643.6427782580666, 785.3677789379548, 813.9226773456542, 814.5642081486405, 818.2635627934876, 847.0724556610502, 849.1254008371387, 849.9702510412137, 850.0, 850.2815121222557, 845.344857735254, 845.5814371885895, 847.4110493207223, 847.7033038436559, 848.6647774129216, 497.1746900145741, 606.1362558354327, 622.6855438003824, 622.8100429369041, 624.1823211592782, 12723.23660580368, 12736.36318184087, 12773.361331933365, 12786.808197877039, 12786.808197877039, 11356.84219998206, 11673.241239319503, 11780.882191178065, 11998.400079995965, 12091.879081209163, 9487.098725621056, 9609.375390599589, 9625.229981601455, 9719.951400243024, 10370.170386369073, 3278.4754439289836, 3299.472084466498, 3312.801231926092, 3337.332533493311, 3339.916502087443, 42062.0, 42779.0, 42898.0, 43254.134917301475, 43579.346309805376, 9506.714798555917, 9665.0, 9667.854982175273, 9682.854757178648, 9718.319717619699, 16448.0, 16533.173341332884, 16626.334946602, 16626.50120496387, 16672.58318542022, 6436.195475565554, 6604.273529911802, 6645.966770166118, 6801.591904485746, 6869.0383346331555, 3440.088376580208, 3506.526618906415, 3528.241428092921, 3533.982330088333, 3634.800085995296, 2754.5317296059716, 2783.665960084803, 2787.637607111103, 2797.1468702045663, 2798.846063466488, 494.48820470812535, 498.0686116961306, 500.7145926821677, 512.597610875461, 514.1516497778621, 576.6453631016979, 593.3924414285024, 595.425414475025, 610.225014231923, 611.1352436302582, 6921.8961715574305, 6973.163220413583, 7144.9642751785905, 7155.713771449084, 7315.122185337687, 548.2625868207255, 569.4305694305694, 576.2969177603318, 581.528961541153, 582.3419535924378, 3247.4966380211104, 3411.675890790371, 3416.1630400551526, 3445.7415693823064, 3482.233908540117, 46867.76566117216, 48119.75940120277, 48315.0, 48735.268970965466, 49356.0, 44311.335329970076, 44623.77688111539, 45094.32358514625, 45395.31907021398, 46026.30960535595, 40343.59656403457, 40401.595984039785, 40954.590454095676, 41121.215598045594, 41526.79236603858, 6612.173478315211, 6719.361660642231, 6748.831279218059, 6820.727170913108, 7589.392848572212, 28359.149225523266, 28707.995220167195, 29744.0, 29823.10530684083, 30427.087187384415, 37407.0, 37498.8125059373, 37662.058448539006, 37947.81026094852, 38107.23785524329, 9835.508224588884, 10230.032012210324, 10428.53071611764, 10560.788784224425, 10599.364038157735, 11312.830307545559, 11512.82730759056, 11692.649220523397, 12288.385580721108, 12441.813372799234, 9718.659846905324, 9822.459764713009, 9851.45816980073, 9852.359596626196, 10154.289199756242, 10948.39783811866, 11479.8278025828, 11496.080313574726, 11808.763824723286, 11834.171607987702, 10323.48382580883, 10598.788024239626, 10634.787304254027, 10790.568377265135, 11012.063974561932, 8443.719283280545, 8577.313814894918, 8773.736787896374, 8975.192232699088, 9040.276777857887, 8276.58617069132, 8328.167183281623, 8487.0, 8594.269487093667, 8671.696490622799], "value": 5739.748619878162, "unit": "score"}], "name": "dromaeo_css", "value": 6168.296333496732, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 109.0, 114.0, 112.0, 114.0, 102.0, 112.0, 112.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 140.0, 156.0, 182.0, 135.0, 154.0, 152.0, 132.0, 205.0, 133.0], "value": 155.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 160.0, 162.0, 160.0, 162.0, 162.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 108.0, 111.0, 78.0, 74.0, 78.0, 124.0, 106.0, 77.0], "value": 90.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 154.0, 94.0, 94.0, 95.0, 135.0, 93.0, 94.0, 140.0], "value": 109.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 67.0, 65.0, 84.0, 64.0, 64.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 64.0, 63.0, 65.0, 65.0, 68.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 81.0, 81.0, 79.0, 79.0, 80.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 129.0, 131.0, 129.0, 127.0, 128.0, 131.0, 162.0, 130.0], "value": 132.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 176.0, 175.0, 175.0, 174.0, 179.0, 173.0, 178.0, 179.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 73.0, 73.0, 74.0, 85.0, 72.0, 73.0, 73.0], "value": 74.1, "unit": "score"}], "name": "kraken", "value": 1577.5999999999997, "alertThreshold": 2.0}]} 09:36:12 INFO - Return code: 0 09:36:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:36:13 ERROR - # TBPL SUCCESS # 09:36:13 INFO - Running post-action listener: _resource_record_post_action 09:36:13 INFO - Running post-run listener: _resource_record_post_run 09:36:14 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536346624; Read time: 32; Write time: 973068 09:36:14 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 234659840; Read time: 0; Write time: 438620 09:36:14 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289738752; Read time: 32; Write time: 531268 09:36:14 INFO - Running post-run listener: _upload_blobber_files 09:36:14 INFO - Blob upload gear active. 09:36:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:14 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:36:14 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:36:14 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:36:14 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:15 INFO - (blobuploader) - INFO - Done attempting. 09:36:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:16 INFO - (blobuploader) - INFO - Done attempting. 09:36:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:16 INFO - Return code: 0 09:36:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a1caf9cac2d02ae9fb53aaa0d9bf8829357cfceb157b402bcce1230a8acf462f74d5b38ed0404373e6ec61dedb39d5cf8bef45fe4556d00ae7991fe6d4c3f9a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fc11080e55b5ff7df4c810a068dd656b54948f9c65d1dade3efb114d1427a90442da37505fe3f2480252dd11e085b486de32e5dd17882570640edfadb3c02e53"} 09:36:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:16 INFO - Contents: 09:36:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a1caf9cac2d02ae9fb53aaa0d9bf8829357cfceb157b402bcce1230a8acf462f74d5b38ed0404373e6ec61dedb39d5cf8bef45fe4556d00ae7991fe6d4c3f9a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fc11080e55b5ff7df4c810a068dd656b54948f9c65d1dade3efb114d1427a90442da37505fe3f2480252dd11e085b486de32e5dd17882570640edfadb3c02e53"} 09:36:16 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:16 INFO - Copying logs to upload dir... 09:36:16 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.238918 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-04-25 09:36:15.969779) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 09:36:15.973214) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889 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/a1caf9cac2d02ae9fb53aaa0d9bf8829357cfceb157b402bcce1230a8acf462f74d5b38ed0404373e6ec61dedb39d5cf8bef45fe4556d00ae7991fe6d4c3f9a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fc11080e55b5ff7df4c810a068dd656b54948f9c65d1dade3efb114d1427a90442da37505fe3f2480252dd11e085b486de32e5dd17882570640edfadb3c02e53"} build_url:https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/eWhr-ArVTQyWHUnTBeeZZg/artifacts/public/build/firefox-48.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/a1caf9cac2d02ae9fb53aaa0d9bf8829357cfceb157b402bcce1230a8acf462f74d5b38ed0404373e6ec61dedb39d5cf8bef45fe4556d00ae7991fe6d4c3f9a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fc11080e55b5ff7df4c810a068dd656b54948f9c65d1dade3efb114d1427a90442da37505fe3f2480252dd11e085b486de32e5dd17882570640edfadb3c02e53"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 09:36:15.997822) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:36:15.998141) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461600925.64587-253028889 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004382 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:36:16.081643) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 09:36:16.082020) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 09:36:16.082362) ========= ========= Total master_lag: 0.17 =========