builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-062 starttime: 1461859181.19 results: success (0) buildid: 20160428073712 builduid: 13f3b14acfca46618e0c13fc413cc1df revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.194306) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.194750) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.195056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.211584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.211890) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-28 08:59:41-- 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% 27.5M=0s 2016-04-28 08:59:41 (27.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118068 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.343069) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.343392) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017367 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:41.438464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 08:59:41.438796) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 --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 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 --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=a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-28 08:59:41,768 truncating revision to first 12 chars 2016-04-28 08:59:41,769 Setting DEBUG logging. 2016-04-28 08:59:41,769 attempt 1/10 2016-04-28 08:59:41,769 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/45e67d6b5a61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 08:59:42,824 unpacking tar archive at: fx-team-45e67d6b5a61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.368306 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 08:59:42.822343) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.822640) ========= script_repo_revision: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.823082) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.823353) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 08:59:42.832191) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-04-28 08:59:42.832469) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:59:43 INFO - MultiFileLogger online at 20160428 08:59:43 in /builds/slave/test 08:59:43 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 08:59:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:43 INFO - {'append_to_log': False, 08:59:43 INFO - 'base_work_dir': '/builds/slave/test', 08:59:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:59:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:43 INFO - 'branch': 'Fx-Team-Non-PGO', 08:59:43 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:43 INFO - 'config_files': ('talos/linux_config.py',), 08:59:43 INFO - 'default_actions': ('clobber', 08:59:43 INFO - 'read-buildbot-config', 08:59:43 INFO - 'download-and-extract', 08:59:43 INFO - 'populate-webroot', 08:59:43 INFO - 'create-virtualenv', 08:59:43 INFO - 'install', 08:59:43 INFO - 'run-tests'), 08:59:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:43 INFO - 'download_minidump_stackwalk': True, 08:59:43 INFO - 'download_symbols': 'ondemand', 08:59:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:43 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:43 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:43 INFO - 'installer_path': 'installer.exe', 08:59:43 INFO - 'log_level': 'info', 08:59:43 INFO - 'log_name': 'talos', 08:59:43 INFO - 'log_to_console': True, 08:59:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:59:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:59:43 INFO - 'opt_config_files': (), 08:59:43 INFO - 'pip_index': False, 08:59:43 INFO - 'sps_profile': False, 08:59:43 INFO - 'sps_profile_interval': 0, 08:59:43 INFO - 'suite': 'dromaeojs', 08:59:43 INFO - 'system_bits': '32', 08:59:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:43 INFO - 'title': 'talos-linux64-ix-062', 08:59:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:43 INFO - 'use_talos_json': True, 08:59:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:43 INFO - 'work_dir': 'build'} 08:59:43 INFO - ##### 08:59:43 INFO - ##### Running clobber step. 08:59:43 INFO - ##### 08:59:43 INFO - Running pre-action listener: _resource_record_pre_action 08:59:43 INFO - Running main action method: clobber 08:59:43 INFO - rmtree: /builds/slave/test/build 08:59:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - ##### 08:59:44 INFO - ##### Running read-buildbot-config step. 08:59:44 INFO - ##### 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: read_buildbot_config 08:59:44 INFO - Using buildbot properties: 08:59:44 INFO - { 08:59:44 INFO - "project": "", 08:59:44 INFO - "product": "firefox", 08:59:44 INFO - "script_repo_revision": "production", 08:59:44 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:59:44 INFO - "repository": "", 08:59:44 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 08:59:44 INFO - "buildid": "20160428073712", 08:59:44 INFO - "pgo_build": "False", 08:59:44 INFO - "basedir": "/builds/slave/test", 08:59:44 INFO - "buildnumber": 1616, 08:59:44 INFO - "slavename": "talos-linux64-ix-062", 08:59:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:59:44 INFO - "platform": "ubuntu64_hw", 08:59:44 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:59:44 INFO - "branch": "fx-team", 08:59:44 INFO - "repo_path": "integration/fx-team", 08:59:44 INFO - "revision": "45e67d6b5a6137898898c23ae1fe60ddfcd59ed1", 08:59:44 INFO - "stage_platform": "linux64", 08:59:44 INFO - "builduid": "13f3b14acfca46618e0c13fc413cc1df", 08:59:44 INFO - "slavebuilddir": "test" 08:59:44 INFO - } 08:59:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - ##### 08:59:44 INFO - ##### Running download-and-extract step. 08:59:44 INFO - ##### 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: download_and_extract 08:59:44 INFO - mkdir: /builds/slave/test/build/tests 08:59:44 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')]} 08:59:44 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:59:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:59:54 INFO - Downloaded 1361 bytes. 08:59:54 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:54 INFO - Using the following test package requirements: 08:59:54 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:54 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:59:54 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:59:54 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'jsshell-linux-x86_64.zip'], 08:59:54 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:59:54 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:54 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:59:54 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:59:54 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:59:54 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:54 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:59:54 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:54 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:59:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:59:59 INFO - Downloaded 22300839 bytes. 08:59:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:59:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:00:01 INFO - Return code: 0 09:00:01 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:00:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:01 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:00:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:00:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:00:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:00:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:00:04 INFO - Downloaded 22300839 bytes. 09:00:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:00:05 INFO - Return code: 0 09:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:05 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:00:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:00:06 INFO - Downloaded 10840145 bytes. 09:00:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:00:06 INFO - Return code: 0 09:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:06 INFO - https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:00:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:00:08 INFO - Downloaded 54848449 bytes. 09:00:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:08 INFO - mkdir: /builds/slave/test/properties 09:00:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:08 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:08 INFO - Contents: 09:00:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:09 INFO - Running post-action listener: _resource_record_post_action 09:00:09 INFO - Running post-action listener: set_extra_try_arguments 09:00:09 INFO - ##### 09:00:09 INFO - ##### Running populate-webroot step. 09:00:09 INFO - ##### 09:00:09 INFO - Running pre-action listener: _resource_record_pre_action 09:00:09 INFO - Running main action method: populate_webroot 09:00:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:09 INFO - '--tppagecycles', 09:00:09 INFO - '7'], 09:00:09 INFO - 'tests': ['tsvgm']}}, 09:00:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['damp', 'tps']}, 09:00:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'tests': ['damp', 'tps']}, 09:00:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['dromaeo_dom']}, 09:00:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['a11yr', 09:00:09 INFO - 'ts_paint', 09:00:09 INFO - 'tpaint', 09:00:09 INFO - 'sessionrestore', 09:00:09 INFO - 'sessionrestore_no_auto_restore', 09:00:09 INFO - 'tabpaint']}, 09:00:09 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:09 INFO - 'ts_paint', 09:00:09 INFO - 'tpaint', 09:00:09 INFO - 'sessionrestore', 09:00:09 INFO - 'sessionrestore_no_auto_restore', 09:00:09 INFO - 'tabpaint']}, 09:00:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:09 INFO - 'ts_paint', 09:00:09 INFO - 'tpaint', 09:00:09 INFO - 'sessionrestore', 09:00:09 INFO - 'sessionrestore_no_auto_restore', 09:00:09 INFO - 'tabpaint']}, 09:00:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:09 INFO - 'ts_paint', 09:00:09 INFO - 'tpaint', 09:00:09 INFO - 'sessionrestore', 09:00:09 INFO - 'sessionrestore_no_auto_restore', 09:00:09 INFO - 'tabpaint']}, 09:00:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['a11yr', 09:00:09 INFO - 'ts_paint', 09:00:09 INFO - 'tpaint', 09:00:09 INFO - 'sessionrestore', 09:00:09 INFO - 'sessionrestore_no_auto_restore', 09:00:09 INFO - 'tabpaint']}, 09:00:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['a11yr', 09:00:09 INFO - 'ts_paint', 09:00:09 INFO - 'tpaint', 09:00:09 INFO - 'sessionrestore', 09:00:09 INFO - 'sessionrestore_no_auto_restore', 09:00:09 INFO - 'tabpaint']}, 09:00:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['tsvgx', 09:00:09 INFO - 'tsvgr_opacity', 09:00:09 INFO - 'tart', 09:00:09 INFO - 'tscrollx', 09:00:09 INFO - 'cart']}, 09:00:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:09 INFO - 'tsvgr_opacity', 09:00:09 INFO - 'tart', 09:00:09 INFO - 'tscrollx', 09:00:09 INFO - 'cart']}, 09:00:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'talos_options': ['--disable-e10s'], 09:00:09 INFO - 'tests': ['tp5o']}, 09:00:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'tests': ['tp5o']}, 09:00:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'talos_options': ['--disable-e10s', 09:00:09 INFO - '--xperf_path', 09:00:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:09 INFO - 'tests': ['tp5n']}, 09:00:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:09 INFO - 'talos_options': ['--xperf_path', 09:00:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:09 INFO - 'tests': ['tp5n']}}, 09:00:09 INFO - 'talos.zip': {'path': '', 09:00:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:09 INFO - Running post-action listener: _resource_record_post_action 09:00:09 INFO - ##### 09:00:09 INFO - ##### Running create-virtualenv step. 09:00:09 INFO - ##### 09:00:09 INFO - Running pre-action listener: _resource_record_pre_action 09:00:09 INFO - Running main action method: create_virtualenv 09:00:09 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:00:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:09 INFO - Using real prefix '/usr' 09:00:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:11 INFO - Installing pip.................done. 09:00:12 INFO - Return code: 0 09:00:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:00:12 INFO - Using env: {'DISPLAY': ':0', 09:00:12 INFO - 'HOME': '/home/cltbld', 09:00:12 INFO - 'LANG': 'en_US.UTF-8', 09:00:12 INFO - 'LANGUAGE': 'en_US:en', 09:00:12 INFO - 'LOGNAME': 'cltbld', 09:00:12 INFO - 'MAIL': '/var/mail/cltbld', 09:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:12 INFO - 'MOZ_NO_REMOTE': '1', 09:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:12 INFO - 'NO_EM_RESTART': '1', 09:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:12 INFO - 'PWD': '/builds/slave/test', 09:00:12 INFO - 'SHELL': '/bin/bash', 09:00:12 INFO - 'SHLVL': '1', 09:00:12 INFO - 'TERM': 'linux', 09:00:12 INFO - 'TMOUT': '86400', 09:00:12 INFO - 'USER': 'cltbld', 09:00:12 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:12 INFO - '_': '/tools/buildbot/bin/python'} 09:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:00:12 INFO - Cleaning up... 09:00:12 INFO - Return code: 0 09:00:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:00:12 INFO - Using env: {'DISPLAY': ':0', 09:00:12 INFO - 'HOME': '/home/cltbld', 09:00:12 INFO - 'LANG': 'en_US.UTF-8', 09:00:12 INFO - 'LANGUAGE': 'en_US:en', 09:00:12 INFO - 'LOGNAME': 'cltbld', 09:00:12 INFO - 'MAIL': '/var/mail/cltbld', 09:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:12 INFO - 'MOZ_NO_REMOTE': '1', 09:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:12 INFO - 'NO_EM_RESTART': '1', 09:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:12 INFO - 'PWD': '/builds/slave/test', 09:00:12 INFO - 'SHELL': '/bin/bash', 09:00:12 INFO - 'SHLVL': '1', 09:00:12 INFO - 'TERM': 'linux', 09:00:12 INFO - 'TMOUT': '86400', 09:00:12 INFO - 'USER': 'cltbld', 09:00:12 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:12 INFO - '_': '/tools/buildbot/bin/python'} 09:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:12 INFO - Downloading/unpacking psutil>=0.7.1 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:14 INFO - Installing collected packages: psutil 09:00:14 INFO - Running setup.py install for psutil 09:00:14 INFO - building 'psutil._psutil_linux' extension 09:00:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:00:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:00:14 INFO - building 'psutil._psutil_posix' extension 09:00:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:00:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:00:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:14 INFO - Successfully installed psutil 09:00:14 INFO - Cleaning up... 09:00:15 INFO - Return code: 0 09:00:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:15 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 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:00:15 INFO - Using env: {'DISPLAY': ':0', 09:00:15 INFO - 'HOME': '/home/cltbld', 09:00:15 INFO - 'LANG': 'en_US.UTF-8', 09:00:15 INFO - 'LANGUAGE': 'en_US:en', 09:00:15 INFO - 'LOGNAME': 'cltbld', 09:00:15 INFO - 'MAIL': '/var/mail/cltbld', 09:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:15 INFO - 'MOZ_NO_REMOTE': '1', 09:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:15 INFO - 'NO_EM_RESTART': '1', 09:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:15 INFO - 'PWD': '/builds/slave/test', 09:00:15 INFO - 'SHELL': '/bin/bash', 09:00:15 INFO - 'SHLVL': '1', 09:00:15 INFO - 'TERM': 'linux', 09:00:15 INFO - 'TMOUT': '86400', 09:00:15 INFO - 'USER': 'cltbld', 09:00:15 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:15 INFO - '_': '/tools/buildbot/bin/python'} 09:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:00:17 INFO - Installing collected packages: mozsystemmonitor 09:00:17 INFO - Running setup.py install for mozsystemmonitor 09:00:17 INFO - Successfully installed mozsystemmonitor 09:00:17 INFO - Cleaning up... 09:00:17 INFO - Return code: 0 09:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 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 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:00:17 INFO - Using env: {'DISPLAY': ':0', 09:00:17 INFO - 'HOME': '/home/cltbld', 09:00:17 INFO - 'LANG': 'en_US.UTF-8', 09:00:17 INFO - 'LANGUAGE': 'en_US:en', 09:00:17 INFO - 'LOGNAME': 'cltbld', 09:00:17 INFO - 'MAIL': '/var/mail/cltbld', 09:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:17 INFO - 'MOZ_NO_REMOTE': '1', 09:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:17 INFO - 'NO_EM_RESTART': '1', 09:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:17 INFO - 'PWD': '/builds/slave/test', 09:00:17 INFO - 'SHELL': '/bin/bash', 09:00:17 INFO - 'SHLVL': '1', 09:00:17 INFO - 'TERM': 'linux', 09:00:17 INFO - 'TMOUT': '86400', 09:00:17 INFO - 'USER': 'cltbld', 09:00:17 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:17 INFO - '_': '/tools/buildbot/bin/python'} 09:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:20 INFO - Downloading docopt-0.6.1.tar.gz 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:20 INFO - Running setup.py install for blobuploader 09:00:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:20 INFO - Running setup.py install for requests 09:00:20 INFO - Running setup.py install for docopt 09:00:20 INFO - Successfully installed blobuploader requests docopt 09:00:20 INFO - Cleaning up... 09:00:20 INFO - Return code: 0 09:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:20 INFO - Using env: {'DISPLAY': ':0', 09:00:20 INFO - 'HOME': '/home/cltbld', 09:00:20 INFO - 'LANG': 'en_US.UTF-8', 09:00:20 INFO - 'LANGUAGE': 'en_US:en', 09:00:20 INFO - 'LOGNAME': 'cltbld', 09:00:20 INFO - 'MAIL': '/var/mail/cltbld', 09:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:20 INFO - 'MOZ_NO_REMOTE': '1', 09:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:20 INFO - 'NO_EM_RESTART': '1', 09:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:20 INFO - 'PWD': '/builds/slave/test', 09:00:20 INFO - 'SHELL': '/bin/bash', 09:00:20 INFO - 'SHLVL': '1', 09:00:20 INFO - 'TERM': 'linux', 09:00:20 INFO - 'TMOUT': '86400', 09:00:20 INFO - 'USER': 'cltbld', 09:00:20 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:20 INFO - '_': '/tools/buildbot/bin/python'} 09:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:20 INFO - Running setup.py (path:/tmp/pip-DZfiuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:21 INFO - Running setup.py (path:/tmp/pip-1JwkkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:21 INFO - Running setup.py (path:/tmp/pip-ZmGcKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:21 INFO - Running setup.py (path:/tmp/pip-ifH4vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:21 INFO - Running setup.py (path:/tmp/pip-DMRHX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:21 INFO - Running setup.py (path:/tmp/pip-e5owtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:21 INFO - Running setup.py (path:/tmp/pip-w_UzPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:21 INFO - Running setup.py (path:/tmp/pip-pnbCIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:21 INFO - Running setup.py (path:/tmp/pip-pXlmX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:21 INFO - Running setup.py (path:/tmp/pip-VdksVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:21 INFO - Running setup.py (path:/tmp/pip-mq0T21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:21 INFO - Running setup.py (path:/tmp/pip-G7lBS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:21 INFO - Running setup.py (path:/tmp/pip-XqV19j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:21 INFO - Running setup.py (path:/tmp/pip-qZBydH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:22 INFO - Running setup.py (path:/tmp/pip-l3VXXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:22 INFO - Running setup.py (path:/tmp/pip-vcF4SV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:22 INFO - Running setup.py (path:/tmp/pip-rtRZGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:22 INFO - Running setup.py install for manifestparser 09:00:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozcrash 09:00:22 INFO - Running setup.py install for mozdebug 09:00:22 INFO - Running setup.py install for mozdevice 09:00:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozfile 09:00:22 INFO - Running setup.py install for mozhttpd 09:00:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozinfo 09:00:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:22 INFO - Running setup.py install for mozInstall 09:00:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozleak 09:00:23 INFO - Running setup.py install for mozlog 09:00:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for moznetwork 09:00:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozprocess 09:00:23 INFO - Running setup.py install for mozprofile 09:00:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozrunner 09:00:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:23 INFO - Running setup.py install for mozscreenshot 09:00:23 INFO - Running setup.py install for moztest 09:00:23 INFO - Running setup.py install for mozversion 09:00:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:24 INFO - Cleaning up... 09:00:24 INFO - Return code: 0 09:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:24 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 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:24 INFO - Using env: {'DISPLAY': ':0', 09:00:24 INFO - 'HOME': '/home/cltbld', 09:00:24 INFO - 'LANG': 'en_US.UTF-8', 09:00:24 INFO - 'LANGUAGE': 'en_US:en', 09:00:24 INFO - 'LOGNAME': 'cltbld', 09:00:24 INFO - 'MAIL': '/var/mail/cltbld', 09:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:24 INFO - 'MOZ_NO_REMOTE': '1', 09:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:24 INFO - 'NO_EM_RESTART': '1', 09:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:24 INFO - 'PWD': '/builds/slave/test', 09:00:24 INFO - 'SHELL': '/bin/bash', 09:00:24 INFO - 'SHLVL': '1', 09:00:24 INFO - 'TERM': 'linux', 09:00:24 INFO - 'TMOUT': '86400', 09:00:24 INFO - 'USER': 'cltbld', 09:00:24 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:24 INFO - '_': '/tools/buildbot/bin/python'} 09:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:24 INFO - Running setup.py (path:/tmp/pip-LF_eC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:24 INFO - Running setup.py (path:/tmp/pip-5UMC6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:24 INFO - Running setup.py (path:/tmp/pip-qFdzCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:24 INFO - Running setup.py (path:/tmp/pip-WSqAPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:24 INFO - Running setup.py (path:/tmp/pip-9gWUb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:24 INFO - Running setup.py (path:/tmp/pip-mehSsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:24 INFO - Running setup.py (path:/tmp/pip-otFZBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:24 INFO - Running setup.py (path:/tmp/pip-srzygc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:24 INFO - Running setup.py (path:/tmp/pip-40jbPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:24 INFO - Running setup.py (path:/tmp/pip-j0Ef8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:25 INFO - Running setup.py (path:/tmp/pip-IZVWlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:25 INFO - Running setup.py (path:/tmp/pip-pP0WoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:25 INFO - Running setup.py (path:/tmp/pip-7jO1NG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:25 INFO - Running setup.py (path:/tmp/pip-rGICZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:25 INFO - Running setup.py (path:/tmp/pip-WKQQBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:25 INFO - Running setup.py (path:/tmp/pip-4gNVOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:25 INFO - Running setup.py (path:/tmp/pip-hFEoIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:27 INFO - Downloading blessings-1.6.tar.gz 09:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:27 INFO - Installing collected packages: blessings 09:00:27 INFO - Running setup.py install for blessings 09:00:27 INFO - Successfully installed blessings 09:00:27 INFO - Cleaning up... 09:00:27 INFO - Return code: 0 09:00:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:27 INFO - Reading from file tmpfile_stdout 09:00:27 INFO - Current package versions: 09:00:27 INFO - argparse == 1.2.1 09:00:27 INFO - blessings == 1.6 09:00:27 INFO - blobuploader == 1.2.4 09:00:27 INFO - docopt == 0.6.1 09:00:27 INFO - manifestparser == 1.1 09:00:27 INFO - mozInstall == 1.12 09:00:27 INFO - mozcrash == 0.17 09:00:27 INFO - mozdebug == 0.1 09:00:27 INFO - mozdevice == 0.48 09:00:27 INFO - mozfile == 1.2 09:00:27 INFO - mozhttpd == 0.7 09:00:27 INFO - mozinfo == 0.9 09:00:27 INFO - mozleak == 0.1 09:00:27 INFO - mozlog == 3.1 09:00:27 INFO - moznetwork == 0.27 09:00:27 INFO - mozprocess == 0.22 09:00:27 INFO - mozprofile == 0.28 09:00:27 INFO - mozrunner == 6.11 09:00:27 INFO - mozscreenshot == 0.1 09:00:27 INFO - mozsystemmonitor == 0.0 09:00:27 INFO - moztest == 0.7 09:00:27 INFO - mozversion == 1.4 09:00:27 INFO - psutil == 3.1.1 09:00:27 INFO - requests == 1.2.3 09:00:27 INFO - wsgiref == 0.1.2 09:00:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:27 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 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:27 INFO - Using env: {'DISPLAY': ':0', 09:00:27 INFO - 'HOME': '/home/cltbld', 09:00:27 INFO - 'LANG': 'en_US.UTF-8', 09:00:27 INFO - 'LANGUAGE': 'en_US:en', 09:00:27 INFO - 'LOGNAME': 'cltbld', 09:00:27 INFO - 'MAIL': '/var/mail/cltbld', 09:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:27 INFO - 'MOZ_NO_REMOTE': '1', 09:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:27 INFO - 'NO_EM_RESTART': '1', 09:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:27 INFO - 'PWD': '/builds/slave/test', 09:00:27 INFO - 'SHELL': '/bin/bash', 09:00:27 INFO - 'SHLVL': '1', 09:00:27 INFO - 'TERM': 'linux', 09:00:27 INFO - 'TMOUT': '86400', 09:00:27 INFO - 'USER': 'cltbld', 09:00:27 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:27 INFO - '_': '/tools/buildbot/bin/python'} 09:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:28 INFO - Cleaning up... 09:00:28 INFO - Return code: 0 09:00:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:28 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 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:00:28 INFO - Using env: {'DISPLAY': ':0', 09:00:28 INFO - 'HOME': '/home/cltbld', 09:00:28 INFO - 'LANG': 'en_US.UTF-8', 09:00:28 INFO - 'LANGUAGE': 'en_US:en', 09:00:28 INFO - 'LOGNAME': 'cltbld', 09:00:28 INFO - 'MAIL': '/var/mail/cltbld', 09:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:28 INFO - 'MOZ_NO_REMOTE': '1', 09:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:28 INFO - 'NO_EM_RESTART': '1', 09:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:28 INFO - 'PWD': '/builds/slave/test', 09:00:28 INFO - 'SHELL': '/bin/bash', 09:00:28 INFO - 'SHLVL': '1', 09:00:28 INFO - 'TERM': 'linux', 09:00:28 INFO - 'TMOUT': '86400', 09:00:28 INFO - 'USER': 'cltbld', 09:00:28 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:28 INFO - '_': '/tools/buildbot/bin/python'} 09:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:28 INFO - Downloading/unpacking jsonschema 09:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:30 INFO - Downloading/unpacking functools32 (from jsonschema) 09:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:30 INFO - warning: no files found matching '*.txt' 09:00:30 INFO - no previously-included directories found matching 'build' 09:00:30 INFO - no previously-included directories found matching 'dist' 09:00:30 INFO - no previously-included directories found matching '.git*' 09:00:30 INFO - Installing collected packages: jsonschema, functools32 09:00:30 INFO - Running setup.py install for functools32 09:00:30 INFO - warning: no files found matching '*.txt' 09:00:30 INFO - no previously-included directories found matching 'build' 09:00:30 INFO - no previously-included directories found matching 'dist' 09:00:30 INFO - no previously-included directories found matching '.git*' 09:00:30 INFO - Successfully installed jsonschema functools32 09:00:30 INFO - Cleaning up... 09:00:30 INFO - Return code: 0 09:00:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f40f0729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a05a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:00:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:00:30 INFO - Using env: {'DISPLAY': ':0', 09:00:30 INFO - 'HOME': '/home/cltbld', 09:00:30 INFO - 'LANG': 'en_US.UTF-8', 09:00:30 INFO - 'LANGUAGE': 'en_US:en', 09:00:30 INFO - 'LOGNAME': 'cltbld', 09:00:30 INFO - 'MAIL': '/var/mail/cltbld', 09:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:30 INFO - 'MOZ_NO_REMOTE': '1', 09:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:30 INFO - 'NO_EM_RESTART': '1', 09:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:30 INFO - 'PWD': '/builds/slave/test', 09:00:30 INFO - 'SHELL': '/bin/bash', 09:00:30 INFO - 'SHLVL': '1', 09:00:30 INFO - 'TERM': 'linux', 09:00:30 INFO - 'TMOUT': '86400', 09:00:30 INFO - 'USER': 'cltbld', 09:00:30 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:30 INFO - '_': '/tools/buildbot/bin/python'} 09:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:30 INFO - Downloading/unpacking flake8 09:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:00:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:00:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:00:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:33 INFO - no previously-included directories found matching 'docs/_build' 09:00:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:00:33 INFO - Running setup.py install for pep8 09:00:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:33 INFO - no previously-included directories found matching 'docs/_build' 09:00:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:00:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:00:33 INFO - Cleaning up... 09:00:33 INFO - Return code: 0 09:00:33 INFO - Running post-action listener: _resource_record_post_action 09:00:33 INFO - Running post-action listener: _start_resource_monitoring 09:00:33 INFO - Starting resource monitoring. 09:00:33 INFO - ##### 09:00:33 INFO - ##### Running install step. 09:00:33 INFO - ##### 09:00:33 INFO - Running pre-action listener: _resource_record_pre_action 09:00:33 INFO - Running main action method: install 09:00:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:34 INFO - Reading from file tmpfile_stdout 09:00:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:34 INFO - Reading from file tmpfile_stdout 09:00:34 INFO - Output received: 09:00:34 INFO - Usage: mozinstall [options] installer 09:00:34 INFO - Options: 09:00:34 INFO - -h, --help show this help message and exit 09:00:34 INFO - -d DEST, --destination=DEST 09:00:34 INFO - Directory to install application into. [default: 09:00:34 INFO - "/builds/slave/test"] 09:00:34 INFO - --app=APP Application being installed. [default: firefox] 09:00:34 INFO - mkdir: /builds/slave/test/build/application 09:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:00:50 INFO - Reading from file tmpfile_stdout 09:00:50 INFO - Output received: 09:00:50 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:50 INFO - Running post-action listener: _resource_record_post_action 09:00:50 INFO - ##### 09:00:50 INFO - ##### Running run-tests step. 09:00:50 INFO - ##### 09:00:50 INFO - Running pre-action listener: _resource_record_pre_action 09:00:50 INFO - Running main action method: run_tests 09:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:50 INFO - Python 2.7.3 09:00:50 INFO - Return code: 0 09:00:50 INFO - grabbing minidump binary from tooltool 09:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:50 INFO - 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 0x1a2f410>, '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 0x1a30310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a307a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:50 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:00:50 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:00:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:50 INFO - Return code: 0 09:00:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:00:51 INFO - Return code: 0 09:00:51 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/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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:00:51 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/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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:00:51 INFO - Using env: {'DISPLAY': ':0', 09:00:51 INFO - 'HOME': '/home/cltbld', 09:00:51 INFO - 'LANG': 'en_US.UTF-8', 09:00:51 INFO - 'LANGUAGE': 'en_US:en', 09:00:51 INFO - 'LOGNAME': 'cltbld', 09:00:51 INFO - 'MAIL': '/var/mail/cltbld', 09:00:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:51 INFO - 'MOZ_NO_REMOTE': '1', 09:00:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:51 INFO - 'NO_EM_RESTART': '1', 09:00:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:51 INFO - 'PWD': '/builds/slave/test', 09:00:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:51 INFO - 'SHELL': '/bin/bash', 09:00:51 INFO - 'SHLVL': '1', 09:00:51 INFO - 'TERM': 'linux', 09:00:51 INFO - 'TMOUT': '86400', 09:00:51 INFO - 'USER': 'cltbld', 09:00:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613', 09:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:51 INFO - '_': '/tools/buildbot/bin/python'} 09:00:51 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/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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:00:51 INFO - mozversion application_buildid: 20160428073712 09:00:51 INFO - mozversion application_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 09:00:51 INFO - mozversion application_display_name: Nightly 09:00:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:51 INFO - mozversion application_name: Firefox 09:00:51 INFO - mozversion application_remotingname: firefox 09:00:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:00:51 INFO - mozversion application_vendor: Mozilla 09:00:51 INFO - mozversion application_version: 49.0a1 09:00:51 INFO - mozversion platform_buildid: 20160428073712 09:00:51 INFO - mozversion platform_changeset: 45e67d6b5a6137898898c23ae1fe60ddfcd59ed1 09:00:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:00:51 INFO - mozversion platform_version: 49.0a1 09:00:51 INFO - using testdate: 1461859251 09:00:51 INFO - actual date: 1461859251 09:00:51 INFO - starting webserver on 'localhost:57163' 09:00:51 INFO - SUITE-START | Running 2 tests 09:00:51 INFO - TEST-START | dromaeo_css 09:00:51 INFO - operating with platform_type : linux_ 09:00:51 INFO - Initialising browser for dromaeo_css test... 09:00:51 INFO - TEST-INFO | started process 10521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqxXlX9/profile http://localhost:57163/getInfo.html) 09:00:55 INFO - PROCESS | 10521 | __metrics Screen width/height:1600/1200 09:00:55 INFO - PROCESS | 10521 | colorDepth:24 09:00:55 INFO - PROCESS | 10521 | Browser inner width/height: 1024/697 09:00:55 INFO - PROCESS | 10521 | __metrics 09:00:56 ERROR - PROCESS | 10521 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:00:57 INFO - TEST-INFO | 10521: exit 0 09:00:57 INFO - Browser initialized. 09:00:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:57 INFO - TEST-INFO | started process 10603 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqxXlX9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:09 INFO - PROCESS | 10603 | RSS: Main: 156033024 09:01:09 INFO - PROCESS | 10603 | 09:02:00 INFO - PROCESS | 10603 | 1461859320258 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:04:13 INFO - PROCESS | 10603 | Cycle 1(1): loaded http://localhost:57163/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57163/tests/dromaeo/cssquery-ext.html) 09:04:14 INFO - PROCESS | 10603 | RSS: Main: 264142848 09:04:14 INFO - PROCESS | 10603 | 09:07:17 INFO - PROCESS | 10603 | Cycle 1(1): loaded http://localhost:57163/tests/dromaeo/cssquery-ext.html (next: http://localhost:57163/tests/dromaeo/cssquery-jquery.html) 09:07:17 INFO - PROCESS | 10603 | RSS: Main: 235827200 09:07:17 INFO - PROCESS | 10603 | 09:10:19 INFO - PROCESS | 10603 | Cycle 1(1): loaded http://localhost:57163/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57163/tests/dromaeo/cssquery-mootools.html) 09:10:20 INFO - PROCESS | 10603 | RSS: Main: 230338560 09:10:20 INFO - PROCESS | 10603 | 09:13:22 INFO - PROCESS | 10603 | Cycle 1(1): loaded http://localhost:57163/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57163/tests/dromaeo/cssquery-prototype.html) 09:13:23 INFO - PROCESS | 10603 | RSS: Main: 233046016 09:13:23 INFO - PROCESS | 10603 | 09:16:26 INFO - PROCESS | 10603 | Cycle 1(1): loaded http://localhost:57163/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57163/tests/dromaeo/cssquery-yui.html) 09:16:26 INFO - PROCESS | 10603 | RSS: Main: 221962240 09:16:26 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | Cycle 1(1): loaded http://localhost:57163/tests/dromaeo/cssquery-yui.html (next: http://localhost:57163/tests/dromaeo/cssquery-dojo.html) 09:19:29 INFO - PROCESS | 10603 | RSS: Main: 265814016 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | __start_tp_report 09:19:29 INFO - PROCESS | 10603 | _x_x_mozilla_page_load 09:19:29 INFO - PROCESS | 10603 | _x_x_mozilla_page_load_details 09:19:29 INFO - PROCESS | 10603 | |i|pagename|runs| 09:19:29 INFO - PROCESS | 10603 | |0;dojo.html;2592.948141037179;2624.6719160104985;2676.264027392466;2676.3844315807364;2677.370817857803;4534.206513860077;4604.148232576976;4615.607673347765;4634.721916685001;4662.067586482704;4602.355670206174;4622.907541849161;4674.883127921804;4718.268668356411;4720.150372932871;4170.228507726065;4192.308269135604;4235.3858690490015;4237.491501019882;4391.253486907218;4313.676474264443;4343.609075183233;4344.304911214206;4387.254166791653;4399.142167277366;4187.539370669224;4256.4041034254915;4267.189234045552;4273.87178384649;4285.078708077747;4238.936415953764;4279.165562715271;4310.396544483775;4348.934765978513;4359.847405340798;4208.726432781859;4223.239816832969;4227.704060715751;4257.042165512766;4270.743755374688;4399.098184872094;4434.822607095713;4457.353683715875;4469.7541635210055;4478.081993191388;4919.680220785674;4979.029089327582;5019.4227663818765;5028.421731500887;5029;4938.1111399947995;4941.357623508957;4949.7772600233075;4974.353334066548;4980.950190498085;4498.797554110073;4627.236505976543;4632.420947381611;4634.72191668501;4640.32715256286;5048.697078175322;5054.519820617035;5065.544101030925;5111.514406131486;5118;4831.855044348676;4836.830710925101;4842.031593681278;4846.345743324606;4894.339264199359;4797.352357431771;4863.465018847924;4911.852644420673;4944.9258261126115;4998.825041123543;4839.830605928775;4874.53691899269;4899.436564795023;4901.142300097468;4936.580390666762;4872.244802055687;4872.537108974641;4876.731779752076;4878.829240976549;4919.606431485473;4093.63157315843;4171.165766846643;4181.853635122817;4212.473440819897;4260.4248426462655;3643.125649844071;3680.6319368063514;3709.1839795245005;3715.294094122108;3755.4930084438797;4245.5542168072525;4274.829006839692;4293.957060429356;4300.225959327288;4337.848175313912;4810.7835147418455;4825.758712064383;4985.127602669519;4992.276119962585;5045.520675535819;4242.066745316026;4248.468941382327;4274.166537525152;4275.551067137968;4292.613664770186;4441.800118994653;4442.622377097919;4443.200223959719;4452.109578084332;4489.551044895485;4614.561616646548;4674.813007479663;4678.0643871226575;4711.19909615373;4780.665353425227;4909.0672772173175;4986.825461108844;4992.80028798844;4997.225430058346;5015.423226328873;4637.3739545162325;4642.2108241598335;4696.624270058455;4698.436187657503;4752.572268495852;4705.623550116051;4834.709917404967;4883.853484395474;4896.338994235733;4921.680090794087;4893.412790465167;4906.55840974314;4908.435529914105;4918.773683766137;4953.876153096201;4676.462206846256;4695.014047050157;4707.105649926503;4755.358026666356;4762.52374762521;4587.77061146948;4756.16767065769;4793.472718001087;4816.229403295456;4837.685550439211;4711.057788442256;4716.3868697069165;4719.929201062056;4734.124187025412;4793.520647935178;4681.438227412732;4818.975905120452;4834.371531700997;4844.297576851266;4877.80488780459;4666.34671146033;4718.457377401505;4719.3156992235245;4722.7874745635845;4730.834420795256 09:19:29 INFO - PROCESS | 10603 | |1;ext.html;568.3123420661;588.7409539802487;601.7472661482177;610.5024405109833;613.5306490534739;27092;34266.65733342666;34894.651053489455;34925;35052.29895402094;37971.430428543594;38776.612233877655;38888.80555597225;38893.61106388935;38937;7658.731944381948;7873.291403773659;7876;7878.330341920943;7885.172056933997;7569.545827250355;7641.2740789624895;7685.6157192140445;7709.498882572643;7836.608169591497;7847.803804904866;7904.762857114296;7905.367570594367;7906;7910.683572657087;7807.984961954966;7840.2159784021715;7845.686172553091;7855.842883142305;7858.371330293563;7860.68557257709;7977.122516523178;8010.198980101944;8012.8397432051615;8027.959860200662;38493.61506384928;39033.80483097566;39097.609023909965;39110.21779564393;39175.41236881449;2929.8535073246253;3134.388794185135;3233.3048394595276;3289.259916518788;3399.9830000850084;29065.564016539767;29342.266443338875;29422.852885735647;30190.69809301923;30412.239694007607;3121.82829944353;3152.668969758165;3159.08386567895;3188.3942051010235;3204.2630195055267;244745;245527;245642;245658;246617;30489.237769055508;54743.45256547463;54798;54823;55269;32231.35537289288;32319.838400807846;32377.35245295081;32408.35183296321;32418.83790581032;12552.686182845502;12750.80873786894;12755.42600582976;12758.170718903428;12765.872341276654;9667.9033209667;9688.079632434907;9725.124738773544;9739.318247722591;9787.902120978699;7721.691132354643;7756.495827771177;7822.413319000984;8078.23256790604;8223.383246256555;2999.5200767877036;3024.1834704629628;3028.78798484104;3041.528563072727;3099.57419586988;872.6378552900602;904.3579058868146;918.2653876898454;986.2947992185622;1051.5005372448027;27757.306067348072;28594;29012;29182.854085729436;29298.560521592193;1007.2596641468515;1011.4639241202027;1024.779672370444;1036.6060896859144;1036.9740756480996;15208.619784505476;15243.847561524464;15360.308786104652;15679.294431750599;15753.68492630141;363994;369775;370379;371040;372598;48751;49051.509484904695;49066.754666227156;49091;49874.25188622173;1137.181229514748;1150.3902931446346;1165.9708507287385;1225.7671042501895;1234.2779474007627;25990;28206.913010620527;28417;28648.57027144553;29186.708132919248;40270.59729402669;41009;41017.79491102525;41043;41044.179116418105;231985;234473;234756;236859;237033;21186.894065529574;23993.28020159398;24188;24512.877435612707;25184.992600295784;37526.43710344292;37540.81229593835;37928.620713793614;37977.4303385455;38665.80667096646;31944.68055319417;31983.200419989687;32543.837280813445;33017.17457063593;33472.330553389285;32000.19999499938;32121;32954.34091318208;33115.17212069718;33253.33973282083;30971.535426968163;31187.53218701676;35359;35591.28817423585;35730.64269357273;29696;30373.544396834506;31559.52660710045;31887;32001.519977200827;24368.390790230387;24460.021599136548;24850.130245441323;25072;25118.62322065207 09:19:29 INFO - PROCESS | 10603 | |2;jquery.html;382.4853579823897;384.62114816905347;389.5247797686821;396.11665984853755;400.569387907999;12871.292078935654;13398.59804205873;15770.369185232592;16892;16975.151242437874;15389.769153462707;16172.83827161728;16252;16866.578335541617;17341.21964511597;5024.371953505803;5236.31927849379;5240.790368385279;5257.1851363038595;5285.656432331905;5973.313068997056;6006.519478441714;6019.247594050744;6030.246219222598;6031.909521357183;5871.3541510433815;5961.940380596204;5971.4625683688455;5985.521158307325;6003.579749417542;5843.64938103713;6016.338202797689;6028.336882942873;6049.516038716892;6054.727537260834;5170.819021334235;5260.842174734764;5339.733013349356;5340.038793017256;5349.331333583302;11622.244554104042;12329.5068197272;12380.071494637938;20623;20830.47923801902;1887.726279689451;1889.2726300374454;1895.668258054835;1942.1163370666313;2102.9158833646634;8829.514376709278;9366.20387267083;9513.191378732816;11406.429678516124;13165.539206127836;1775.9822401775946;1853.7868145163209;1874.6719324118226;1881.9623607527774;1891.4514790710814;67412.32587674109;69063;70773.2922670777;70799;71545.64227178883;9963.202943764627;9983.151432128205;10170.033846784543;10347.482625868677;10470.685879423629;12681.682957926125;13211.471541138248;14528.20094894791;14559.3448294827;14577.344019519147;5739.770409183586;5876.529877609866;6296.464800491918;7042.260562640849;7150.356467917913;6233.657148856856;6554.934450655528;6694.899576506356;6718.630475323806;6736.393724564715;4287.862212964505;4416.447944006999;4424.247877860771;4553.5218802025975;4564.2240819060835;1812.9818701813076;1864.7016477363827;1865.39374703222;1892.5079479335473;1926.9421917342502;808.8382323535199;852.8720691896141;855.3157474020759;862.8015556422058;979.9314048016571;15920.840791591936;16189;16292.837071629368;16640.001599903804;17124.914375428292;875.9036505984336;902.3863772634671;902.8239493298875;956.9090936361034;958.0850287974968;9201.263898888074;9381.812363752822;9444.197243234265;9464.763380915532;9626.229901607854;385479;390755;393124;406242;410660;47211.29183062257;55166.44833551613;79260;82080;85825.14174858296;902.9232515236279;930.223263574909;974.6052848596188;985.4678473624306;986.3637953520083;12072.3360215189;15125.36818395593;15919.442819501724;16275.674486510441;16300.673986519965;35072.29855402929;35370.29259414849;36026;36177.638223617425;37709.05727356838;45437.772811135736;48936.510634893195;48944.2658360132;49181.75409122931;49252.26121608107;7707.653155608124;7765.572893490912;7882.84234315299;7902.841943161219;8040.356771458386;6688.398044176048;9585.808283834423;10028.097471227624;11254.718632034264;11434.428278586203;9756;9964.402135871871;10066.899331006889;10117.645882394081;10320.48397580103;9920.255980801325;9975.501224938578;10020.849687254551;10067.798644027225;10397.792044158923;8849.51327676984;8881.955590222007;9207.217386522221;9379.953100234456;9767.413955162712;7350.963245183953;7541.35898448638;7649.464537482323;7701.576413297323;7810.609469526615;5210.1663733803925;6218.253809542884;9087.182153606207;9719.951400242953;10221.846672300071 09:19:29 INFO - PROCESS | 10603 | |3;mootools.html;1302.0625149892073;1334.4728832111318;1392.8328600567932;1392.937317820698;1537.8231503377108;6748.19021717394;7624.085109786826;7871.291583757461;7891.881621775671;7916.247956444143;7548.471606987513;7693.961530192341;7713;7714.537127772338;7716.92283077169;1875.0999520230298;1943.1353047893692;1945.6886898096307;2201.2515744646776;2237.6643503474447;2169.4359466538654;2222.688823564702;2243.3942835434505;2249.134083377895;2253.481699209183;2169;2198.6372248578964;2204.019211450905;2206.0844749428966;2235.228846048119;1858.3124244029661;1919.510524816177;2176.6082105221053;2178.4771654802894;2180;1899.857510686704;1919.4145785535545;2076.2317942361274;2147.226998280618;2153.052656831005;7538.095428548554;7607.543547387119;7612.200718924489;7630.38956883448;7633.389328853678;4315.244832154361;4492.68551201416;4506.5493450655;4534.2971839364955;4534.36518887356;2749.5463248563965;2804.1587523742796;2810.5643625237917;2812.2828678686806;2872.1240021793346;903.7966457547;905.3526728389168;919.1176470588176;947.673052796784;964.243069190689;10026.197904167722;10039.196864250842;10050.798984020279;10052.346597471142;10066.899331006669;4078.7348822326453;4175.248455278079;4265.637420819203;4274.658027357804;4309.5044069932355;8445.366597505212;8516.8722469163;8529.445586036887;8551.529665868436;8560.61477233526;4575.2450845610765;4639.30410438437;4644.233701439225;4645.187092258841;4646.697964632288;3691.1325838427947;4065.329220678555;4073.816678249486;4133.1940271647345;4266.253405653998;3495.877644282438;3552.0942159749097;3610.656987586175;3625.2568223514118;3631.763935344247;1186.0511590727383;1193.8268951002055;1196.629044996054;1200.3038237822043;1200.903927685783;744.3784439992667;757.1671161722062;818.7993941484256;850.1073872433919;871.7384784564605;4937.580305674058;5308.30991971049;5331.920021199685;5359.517643412111;5405.648632838853;406.86776797540915;413.57195302861993;417.38644193036185;418.8575884199387;423.25084600257986;3487.4245749451566;3527.5590551181103;3548.1129717570607;3620.6560376764164;3623.8912832615065;9144.314176436794;9565.091316324939;9579.90420095804;9684.612615495302;9695.030496950249;9679;9750.75623109428;9782.706518804447;9826.213902887896;9847.064528869745;6624.105745724362;7032.824179395454;7066.823329416703;7103.680334384861;7110.537815042006;5043.5460808527405;5056.84829455117;5078.7206703631655;5167.870803229949;5204.063268611686;8183.590820459072;8289.95855020721;8460.407771455939;8659;8664.870026949728;8734.69428569997;8746.169113934166;8756.562171891508;8890.288776897703;8964.417312874642;6132.8773424532155;6546.738130474918;6744.62904540255;6806.965965170142;6929;7191;7209.819754506179;7231.023811785341;7264.527805692613;7269.091363579552;6608.074869518176;6726.865462690621;6730.3606157416925;6816.829579260359;6843.589384636938;6716.731330746716;6738.427233684995;6760.864782704417;6789.558678686068;6857.27998560144;6338.049292606146;6338.080978258219;6395.552311338161;6419.775307864203;6420.967895160495;4524.502304746541;4567.634589232787;4597.310403439511;4646.558576935186;4678.906421871611;4448.733076015449;4622.399088118657;4634.0036892068365;4638.5593368629925;4735.526447355237 09:19:29 INFO - PROCESS | 10603 | |4;prototype.html;300.6167136900452;311.93449375631116;317.05517557678104;317.0741435009772;317.2655302973616;4613.215753321935;4672.345871577973;4682.648801339896;4730.787114579844;4755.096531658983;4354.564543545644;4454.420925279717;4533.50394369858;4644.744539050359;4657.813687452506;3715.6098609646006;3723.73933824631;3734.477173195755;3742.9064273393246;3752.737308388414;3732.122951106501;3750.6061863504347;3751.643593858593;3752.906177345561;3764.1906989997146;3532.364174448598;3588.1747198144444;3729.8881033569037;3734.346489364364;3746.8875933722034;3699.6855267302312;3706.314331848603;3711.628837116267;3713.2016616427463;3730;3329.3174899145615;3330.383878982396;3334.3498017886523;3341.298327351259;3351.731861451078;4318.676099292566;4459.9331010034875;4472.955270447286;4483.6861419700635;4511.015153332523;4243.6605071594195;4270.508891477489;4284.614384705392;4286.464191976003;4300.677449191323;4897.975510122426;4915.139850526144;4929.950700492985;4929.975350123263;4932.556069953722;3643.9635603644156;3648.5804132524568;3661.9633803662155;3670.908227294339;3674.5223120994365;4977.477364876672;5160.380754309507;5171.120909445403;5177.938522602857;5179.611529135293;3691.464737613031;3711.777293362407;3727.8881633551036;3729.9440508392395;3730.235301763132;3768.6231376862092;3770.1140232045445;3775.301569209705;3781.262653782486;3784.6215378462484;3972.6424621784176;4185.707000509935;4234.851780187679;4236.830526778956;4256.170046840898;4858.8785280367565;4874.975625121922;4888.6577939543895;4913.508649135058;4932.4081110267;1268.8287081243914;1289.8516670582815;1306.5427100514935;1316.4076165725462;1318.967025824362;907.328576853124;916.7715261549556;917.3074328881652;917.6788124156492;923.3951761596165;4597.7241365518175;4605.125026245003;4631.282151266559;4644.512326205768;4645.558671926162;5429.375621803543;5432.75552600134;5441.265429167091;5444.638840289927;5445.918311225335;4417.249067658506;4566.88582785433;4570.81716731327;4578.702384345007;4615.23848564983;2844.416894536615;2867.6702179249655;2869.2253091665134;2869.368738877444;2870.6124673168856;4959.528844759742;5051.242313652908;5069.923951140662;5091.363579552556;5108.438071812172;5458.235846981426;5462.6995515247045;5476.7535460904355;5493.752781124779;5505;5001.624878134227;5015.247712843103;5015.27278544609;5030.37120359955;5064.620153488503;5218.504242096995;5341.813036543703;5358.651687640447;5371.865703357447;5381.67709937405;5211.270422140828;5240.318758561362;5250.396204436539;5252.842414727565;5262.526372626482;5038.01758657073;5057.14028615129;5061.924071138862;5065.746712664426;5083.491650835035;3511.2626348467106;3693.501377314028;3793.393057110849;3843.308204523101;3848.634379733921;4987.177115775857;5035.949640503694;5040.6471546992825;5046.545810877039;5049.838537136553;4870.659053866196;4897.534734200245;4905.950940490692;4918.327409241451;4934;4652;4885.706857588414;4903.141950158779;4934.62990275738;4962.776675049704;4562.8859278516975;4690.3433519306645;4863.513648635108;4873.829415970424;4878.609711223165;4984.22744474607;4985.601151907911;4990.126727822689;4999.425066117297;5002.099622067917;4864.902701945871;4865.781039853145;4872.782883787939;4874.829380971649;4877.414710234794 09:19:29 INFO - PROCESS | 10603 | |5;yui.html;596.9134475501052;687.6046273392799;711.5268346549544;714.2286330762777;728.9088863892014;9998.550065247064;11180.16148788841;11456.083513318936;11461.828072578917;11478.885211147886;11064.723381915455;11082.390468524229;11141.832872506919;11168.888311116887;11249.212555121145;632.9019002054689;768.8616049111158;776.54572075336;799.5762246009596;801.6272433318505;785.7485604606528;790.8102055506665;792.302773559267;795.026093036032;795.1849354411745;826.3595713322194;827.0406328658758;827.0571548434789;827.5696637748367;827.9254867061964;653.5490511547041;811.9391045671568;829.340674164035;832.4464231286188;837.8868852704868;613.3406587918006;613.4233820208989;614.4500671898626;615.9599626024338;616.907463880417;11329.943350283278;11334.659960201207;11385.601503947404;11400.486978086006;11409;11204.327740335606;11244.26912250701;11639.76720465586;11643.301401915913;12067;9215.308851836138;9230.261579073658;9467.384619999746;9536.761580960461;9593.808123837554;3164.525321201815;3185.633652129988;3225.403300389412;3230.127865476308;3268.297316077055;45247.54752452499;45767.313490297674;46644.300335495;46673.29990050186;46766.53233467656;8633.179847914438;8679.132086791396;8716;8737.126287371339;8903.688370906988;16573.751393729104;16592.419265325654;16603.833961660228;16614.335426583042;16635.168241588115;6078.422549857756;6091.543134264948;6478.611283322922;6493.5454518184215;6629.801105966829;3484.8780292689635;3536.133647256387;3545.5745310562897;3697.4084146536425;3766.9434958475645;2642.4715056988293;2720.7551320381262;2721.5509440942024;2723.591461280784;2743.8490883001227;511.8387707872008;518.7898900945123;521.7938914128898;528.5322489596734;529.5498825997871;450.23460117799874;495.1112752609075;521.1895502493659;522.2218893848138;566.9518090962232;7291.489595728248;7385.778426647209;7392.223816499298;7511.436642251853;7653.11989121247;558.1794761700294;562.4122791682702;566.5297802823646;572.6764378126345;579.2643342954424;3496.650334966534;3527.2063785648024;3596.2268112355446;3677.724170687209;3694.408894576855;46014.309785353245;51675.483245167816;53617.46382536203;54080;54148.729256353465;47341.5265847344;48484.7575762126;48711;49242.26136607954;50172.49827501751;42914.356284655754;43781.56218437839;43881.78059109684;44674.55325446769;44843.77578112089;7707.614619269127;7753.728619498516;7914.643841027283;7923.128455869734;8089.635966381645;29623.703762962094;29864;30329.69670303269;30698.386032279675;30788.38423231478;35604.287914242086;37883.242335152594;37922.810385947894;38835.61164388433;39242.01894952649;9294.581743821409;9455.858162127435;9552.617895284111;9664.565094570902;9704.951475242578;7272.550389585105;9665.226781857575;10561.260711750103;10699.839502407625;11099.445027748741;9572.234221262142;9787.755306117404;9795.706128816148;9945.453000085065;9976.950115249378;9833.008476047264;9891.653792117242;10073.647422340184;10088.092071713581;10212.234082443994;9459.432434053979;9548.952255238679;9592.856107158537;9613.615455381987;10074.294799363974;8296.294814940798;8301.543415112226;8498.235158835734;8663.826723465621;8726.956365218133;8163.959180204061;8266.793330166794;8369.204925532065;8502.659893604434;8538.615762290588 09:19:29 INFO - PROCESS | 10603 | __end_tp_report 09:19:29 INFO - PROCESS | 10603 | __start_cc_report 09:19:29 INFO - PROCESS | 10603 | _x_x_mozilla_cycle_collect,1026 09:19:29 INFO - PROCESS | 10603 | __end_cc_report 09:19:29 INFO - PROCESS | 10603 | __startTimestamp1461860369940__endTimestamp 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | ------- Summary: start ------- 09:19:29 INFO - PROCESS | 10603 | Number of tests: 6 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | [#0] dojo.html Cycles:165 Average:4564.50 Median:4695.01 stddev:461.11 (9.8%) stddev-sans-first:435.81 09:19:29 INFO - PROCESS | 10603 | Values: 2592.9 2624.7 2676.3 2676.4 2677.4 4534.2 4604.1 4615.6 4634.7 4662.1 4602.4 4622.9 4674.9 4718.3 4720.2 4170.2 4192.3 4235.4 4237.5 4391.3 4313.7 4343.6 4344.3 4387.3 4399.1 4187.5 4256.4 4267.2 4273.9 4285.1 4238.9 4279.2 4310.4 4348.9 4359.8 4208.7 4223.2 4227.7 4257.0 4270.7 4399.1 4434.8 4457.4 4469.8 4478.1 4919.7 4979.0 5019.4 5028.4 5029.0 4938.1 4941.4 4949.8 4974.4 4981.0 4498.8 4627.2 4632.4 4634.7 4640.3 5048.7 5054.5 5065.5 5111.5 5118.0 4831.9 4836.8 4842.0 4846.3 4894.3 4797.4 4863.5 4911.9 4944.9 4998.8 4839.8 4874.5 4899.4 4901.1 4936.6 4872.2 4872.5 4876.7 4878.8 4919.6 4093.6 4171.2 4181.9 4212.5 4260.4 3643.1 3680.6 3709.2 3715.3 3755.5 4245.6 4274.8 4294.0 4300.2 4337.8 4810.8 4825.8 4985.1 4992.3 5045.5 4242.1 4248.5 4274.2 4275.6 4292.6 4441.8 4442.6 4443.2 4452.1 4489.6 4614.6 4674.8 4678.1 4711.2 4780.7 4909.1 4986.8 4992.8 4997.2 5015.4 4637.4 4642.2 4696.6 4698.4 4752.6 4705.6 4834.7 4883.9 4896.3 4921.7 4893.4 4906.6 4908.4 4918.8 4953.9 4676.5 4695.0 4707.1 4755.4 4762.5 4587.8 4756.2 4793.5 4816.2 4837.7 4711.1 4716.4 4719.9 4734.1 4793.5 4681.4 4819.0 4834.4 4844.3 4877.8 4666.3 4718.5 4719.3 4722.8 4730.8 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | [#1] ext.html Cycles:180 Average:42637.94 Median:33066.17 stddev:76377.46 (231.0%) stddev-sans-first:76526.40 09:19:29 INFO - PROCESS | 10603 | Values: 568.3 588.7 601.7 610.5 613.5 27092.0 34266.7 34894.7 34925.0 35052.3 37971.4 38776.6 38888.8 38893.6 38937.0 7658.7 7873.3 7876.0 7878.3 7885.2 7569.5 7641.3 7685.6 7709.5 7836.6 7847.8 7904.8 7905.4 7906.0 7910.7 7808.0 7840.2 7845.7 7855.8 7858.4 7860.7 7977.1 8010.2 8012.8 8028.0 38493.6 39033.8 39097.6 39110.2 39175.4 2929.9 3134.4 3233.3 3289.3 3400.0 29065.6 29342.3 29422.9 30190.7 30412.2 3121.8 3152.7 3159.1 3188.4 3204.3 244745.0 245527.0 245642.0 245658.0 246617.0 30489.2 54743.5 54798.0 54823.0 55269.0 32231.4 32319.8 32377.4 32408.4 32418.8 12552.7 12750.8 12755.4 12758.2 12765.9 9667.9 9688.1 9725.1 9739.3 9787.9 7721.7 7756.5 7822.4 8078.2 8223.4 2999.5 3024.2 3028.8 3041.5 3099.6 872.6 904.4 918.3 986.3 1051.5 27757.3 28594.0 29012.0 29182.9 29298.6 1007.3 1011.5 1024.8 1036.6 1037.0 15208.6 15243.8 15360.3 15679.3 15753.7 363994.0 369775.0 370379.0 371040.0 372598.0 48751.0 49051.5 49066.8 49091.0 49874.3 1137.2 1150.4 1166.0 1225.8 1234.3 25990.0 28206.9 28417.0 28648.6 29186.7 40270.6 41009.0 41017.8 41043.0 41044.2 231985.0 234473.0 234756.0 236859.0 237033.0 21186.9 23993.3 24188.0 24512.9 25185.0 37526.4 37540.8 37928.6 37977.4 38665.8 31944.7 31983.2 32543.8 33017.2 33472.3 32000.2 32121.0 32954.3 33115.2 33253.3 30971.5 31187.5 35359.0 35591.3 35730.6 29696.0 30373.5 31559.5 31887.0 32001.5 24368.4 24460.0 24850.1 25072.0 25118.6 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | [#2] jquery.html Cycles:180 Average:23934.21 Median:5248.99 stddev:65494.03 (1247.7%) stddev-sans-first:65653.88 09:19:29 INFO - PROCESS | 10603 | Values: 382.5 384.6 389.5 396.1 400.6 12871.3 13398.6 15770.4 16892.0 16975.2 15389.8 16172.8 16252.0 16866.6 17341.2 5024.4 5236.3 5240.8 5257.2 5285.7 5973.3 6006.5 6019.2 6030.2 6031.9 5871.4 5961.9 5971.5 5985.5 6003.6 5843.6 6016.3 6028.3 6049.5 6054.7 5170.8 5260.8 5339.7 5340.0 5349.3 11622.2 12329.5 12380.1 20623.0 20830.5 1887.7 1889.3 1895.7 1942.1 2102.9 8829.5 9366.2 9513.2 11406.4 13165.5 1776.0 1853.8 1874.7 1882.0 1891.5 67412.3 69063.0 70773.3 70799.0 71545.6 9963.2 9983.2 10170.0 10347.5 10470.7 12681.7 13211.5 14528.2 14559.3 14577.3 5739.8 5876.5 6296.5 7042.3 7150.4 6233.7 6554.9 6694.9 6718.6 6736.4 4287.9 4416.4 4424.2 4553.5 4564.2 1813.0 1864.7 1865.4 1892.5 1926.9 808.8 852.9 855.3 862.8 979.9 15920.8 16189.0 16292.8 16640.0 17124.9 875.9 902.4 902.8 956.9 958.1 9201.3 9381.8 9444.2 9464.8 9626.2 385479.0 390755.0 393124.0 406242.0 410660.0 47211.3 55166.4 79260.0 82080.0 85825.1 902.9 930.2 974.6 985.5 986.4 12072.3 15125.4 15919.4 16275.7 16300.7 35072.3 35370.3 36026.0 36177.6 37709.1 45437.8 48936.5 48944.3 49181.8 49252.3 7707.7 7765.6 7882.8 7902.8 8040.4 6688.4 9585.8 10028.1 11254.7 11434.4 9756.0 9964.4 10066.9 10117.6 10320.5 9920.3 9975.5 10020.8 10067.8 10397.8 8849.5 8882.0 9207.2 9380.0 9767.4 7351.0 7541.4 7649.5 7701.6 7810.6 5210.2 6218.3 9087.2 9720.0 10221.8 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | [#3] mootools.html Cycles:180 Average:5028.66 Median:4990.56 stddev:2817.89 (56.5%) stddev-sans-first:2811.88 09:19:29 INFO - PROCESS | 10603 | Values: 1302.1 1334.5 1392.8 1392.9 1537.8 6748.2 7624.1 7871.3 7891.9 7916.2 7548.5 7694.0 7713.0 7714.5 7716.9 1875.1 1943.1 1945.7 2201.3 2237.7 2169.4 2222.7 2243.4 2249.1 2253.5 2169.0 2198.6 2204.0 2206.1 2235.2 1858.3 1919.5 2176.6 2178.5 2180.0 1899.9 1919.4 2076.2 2147.2 2153.1 7538.1 7607.5 7612.2 7630.4 7633.4 4315.2 4492.7 4506.5 4534.3 4534.4 2749.5 2804.2 2810.6 2812.3 2872.1 903.8 905.4 919.1 947.7 964.2 10026.2 10039.2 10050.8 10052.3 10066.9 4078.7 4175.2 4265.6 4274.7 4309.5 8445.4 8516.9 8529.4 8551.5 8560.6 4575.2 4639.3 4644.2 4645.2 4646.7 3691.1 4065.3 4073.8 4133.2 4266.3 3495.9 3552.1 3610.7 3625.3 3631.8 1186.1 1193.8 1196.6 1200.3 1200.9 744.4 757.2 818.8 850.1 871.7 4937.6 5308.3 5331.9 5359.5 5405.6 406.9 413.6 417.4 418.9 423.3 3487.4 3527.6 3548.1 3620.7 3623.9 9144.3 9565.1 9579.9 9684.6 9695.0 9679.0 9750.8 9782.7 9826.2 9847.1 6624.1 7032.8 7066.8 7103.7 7110.5 5043.5 5056.8 5078.7 5167.9 5204.1 8183.6 8290.0 8460.4 8659.0 8664.9 8734.7 8746.2 8756.6 8890.3 8964.4 6132.9 6546.7 6744.6 6807.0 6929.0 7191.0 7209.8 7231.0 7264.5 7269.1 6608.1 6726.9 6730.4 6816.8 6843.6 6716.7 6738.4 6760.9 6789.6 6857.3 6338.0 6338.1 6395.6 6419.8 6421.0 4524.5 4567.6 4597.3 4646.6 4678.9 4448.7 4622.4 4634.0 4638.6 4735.5 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | [#4] prototype.html Cycles:180 Average:4189.26 Median:4648.78 stddev:1206.70 (26.0%) stddev-sans-first:1174.26 09:19:29 INFO - PROCESS | 10603 | Values: 300.6 311.9 317.1 317.1 317.3 4613.2 4672.3 4682.6 4730.8 4755.1 4354.6 4454.4 4533.5 4644.7 4657.8 3715.6 3723.7 3734.5 3742.9 3752.7 3732.1 3750.6 3751.6 3752.9 3764.2 3532.4 3588.2 3729.9 3734.3 3746.9 3699.7 3706.3 3711.6 3713.2 3730.0 3329.3 3330.4 3334.3 3341.3 3351.7 4318.7 4459.9 4473.0 4483.7 4511.0 4243.7 4270.5 4284.6 4286.5 4300.7 4898.0 4915.1 4930.0 4930.0 4932.6 3644.0 3648.6 3662.0 3670.9 3674.5 4977.5 5160.4 5171.1 5177.9 5179.6 3691.5 3711.8 3727.9 3729.9 3730.2 3768.6 3770.1 3775.3 3781.3 3784.6 3972.6 4185.7 4234.9 4236.8 4256.2 4858.9 4875.0 4888.7 4913.5 4932.4 1268.8 1289.9 1306.5 1316.4 1319.0 907.3 916.8 917.3 917.7 923.4 4597.7 4605.1 4631.3 4644.5 4645.6 5429.4 5432.8 5441.3 5444.6 5445.9 4417.2 4566.9 4570.8 4578.7 4615.2 2844.4 2867.7 2869.2 2869.4 2870.6 4959.5 5051.2 5069.9 5091.4 5108.4 5458.2 5462.7 5476.8 5493.8 5505.0 5001.6 5015.2 5015.3 5030.4 5064.6 5218.5 5341.8 5358.7 5371.9 5381.7 5211.3 5240.3 5250.4 5252.8 5262.5 5038.0 5057.1 5061.9 5065.7 5083.5 3511.3 3693.5 3793.4 3843.3 3848.6 4987.2 5035.9 5040.6 5046.5 5049.8 4870.7 4897.5 4906.0 4918.3 4934.0 4652.0 4885.7 4903.1 4934.6 4962.8 4562.9 4690.3 4863.5 4873.8 4878.6 4984.2 4985.6 4990.1 4999.4 5002.1 4864.9 4865.8 4872.8 4874.8 4877.4 09:19:29 INFO - PROCESS | 10603 | 09:19:29 INFO - PROCESS | 10603 | [#5] yui.html Cycles:180 Average:12379.53 Median:560.30 stddev:14738.44 (2630.5%) stddev-sans-first:14753.22 09:19:29 INFO - PROCESS | 10603 | Values: 596.9 687.6 711.5 714.2 728.9 9998.6 11180.2 11456.1 11461.8 11478.9 11064.7 11082.4 11141.8 11168.9 11249.2 632.9 768.9 776.5 799.6 801.6 785.7 790.8 792.3 795.0 795.2 826.4 827.0 827.1 827.6 827.9 653.5 811.9 829.3 832.4 837.9 613.3 613.4 614.5 616.0 616.9 11329.9 11334.7 11385.6 11400.5 11409.0 11204.3 11244.3 11639.8 11643.3 12067.0 9215.3 9230.3 9467.4 9536.8 9593.8 3164.5 3185.6 3225.4 3230.1 3268.3 45247.5 45767.3 46644.3 46673.3 46766.5 8633.2 8679.1 8716.0 8737.1 8903.7 16573.8 16592.4 16603.8 16614.3 16635.2 6078.4 6091.5 6478.6 6493.5 6629.8 3484.9 3536.1 3545.6 3697.4 3766.9 2642.5 2720.8 2721.6 2723.6 2743.8 511.8 518.8 521.8 528.5 529.5 450.2 495.1 521.2 522.2 567.0 7291.5 7385.8 7392.2 7511.4 7653.1 558.2 562.4 566.5 572.7 579.3 3496.7 3527.2 3596.2 3677.7 3694.4 46014.3 51675.5 53617.5 54080.0 54148.7 47341.5 48484.8 48711.0 49242.3 50172.5 42914.4 43781.6 43881.8 44674.6 44843.8 7707.6 7753.7 7914.6 7923.1 8089.6 29623.7 29864.0 30329.7 30698.4 30788.4 35604.3 37883.2 37922.8 38835.6 39242.0 9294.6 9455.9 9552.6 9664.6 9705.0 7272.6 9665.2 10561.3 10699.8 11099.4 9572.2 9787.8 9795.7 9945.5 9977.0 9833.0 9891.7 10073.6 10088.1 10212.2 9459.4 9549.0 9592.9 9613.6 10074.3 8296.3 8301.5 8498.2 8663.8 8727.0 8164.0 8266.8 8369.2 8502.7 8538.6 09:19:29 INFO - PROCESS | 10603 | -------- Summary: end -------- 09:19:29 INFO - PROCESS | 10603 | 09:19:30 INFO - TEST-INFO | 10603: exit 0 09:19:30 INFO - TEST-OK | dromaeo_css | took 1119423ms 09:19:30 INFO - TEST-START | kraken 09:19:30 INFO - operating with platform_type : linux_ 09:19:30 INFO - Initialising browser for kraken test... 09:19:30 INFO - TEST-INFO | started process 10777 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpos2Y6G/profile http://localhost:57163/getInfo.html) 09:19:34 INFO - PROCESS | 10777 | __metrics Screen width/height:1600/1200 09:19:34 INFO - PROCESS | 10777 | colorDepth:24 09:19:34 INFO - PROCESS | 10777 | Browser inner width/height: 1024/697 09:19:34 INFO - PROCESS | 10777 | __metrics 09:19:35 ERROR - PROCESS | 10777 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:19:36 INFO - TEST-INFO | 10777: exit 0 09:19:36 INFO - Browser initialized. 09:19:36 INFO - Running cycle 1/1 for kraken test... 09:19:36 INFO - TEST-INFO | started process 10860 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpos2Y6G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:48 INFO - PROCESS | 10860 | RSS: Main: 155549696 09:19:48 INFO - PROCESS | 10860 | 09:19:51 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57163/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:52 INFO - PROCESS | 10860 | RSS: Main: 210804736 09:19:52 INFO - PROCESS | 10860 | 09:19:54 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57163/tests/kraken/driver.html?testName=audio-dft) 09:19:55 INFO - PROCESS | 10860 | RSS: Main: 195874816 09:19:55 INFO - PROCESS | 10860 | 09:19:58 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57163/tests/kraken/driver.html?testName=audio-fft) 09:19:59 INFO - PROCESS | 10860 | RSS: Main: 267755520 09:19:59 INFO - PROCESS | 10860 | 09:20:01 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57163/tests/kraken/driver.html?testName=audio-oscillator) 09:20:02 INFO - PROCESS | 10860 | RSS: Main: 175190016 09:20:02 INFO - PROCESS | 10860 | 09:20:04 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57163/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:05 INFO - PROCESS | 10860 | RSS: Main: 178835456 09:20:05 INFO - PROCESS | 10860 | 09:20:08 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57163/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:08 INFO - PROCESS | 10860 | RSS: Main: 215465984 09:20:08 INFO - PROCESS | 10860 | 09:20:12 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57163/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:12 INFO - PROCESS | 10860 | RSS: Main: 213938176 09:20:12 INFO - PROCESS | 10860 | 09:20:15 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57163/tests/kraken/driver.html?testName=json-parse-financial) 09:20:16 INFO - PROCESS | 10860 | RSS: Main: 215343104 09:20:16 INFO - PROCESS | 10860 | 09:20:18 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57163/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:18 INFO - PROCESS | 10860 | RSS: Main: 203739136 09:20:18 INFO - PROCESS | 10860 | 09:20:20 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:21 INFO - PROCESS | 10860 | RSS: Main: 206192640 09:20:21 INFO - PROCESS | 10860 | 09:20:23 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:24 INFO - PROCESS | 10860 | RSS: Main: 217628672 09:20:24 INFO - PROCESS | 10860 | 09:20:26 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:27 INFO - PROCESS | 10860 | RSS: Main: 224108544 09:20:27 INFO - PROCESS | 10860 | 09:20:30 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:30 INFO - PROCESS | 10860 | RSS: Main: 219398144 09:20:30 INFO - PROCESS | 10860 | 09:20:32 INFO - PROCESS | 10860 | Cycle 1(1): loaded http://localhost:57163/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57163/tests/kraken/driver.html?testName=ai-astar) 09:20:33 INFO - PROCESS | 10860 | RSS: Main: 283000832 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | __start_tp_report 09:20:33 INFO - PROCESS | 10860 | _x_x_mozilla_page_load 09:20:33 INFO - PROCESS | 10860 | _x_x_mozilla_page_load_details 09:20:33 INFO - PROCESS | 10860 | |i|pagename|runs| 09:20:33 INFO - PROCESS | 10860 | |0;ai-astar;114;117;107;114;115;114;103;114;113;113 09:20:33 INFO - PROCESS | 10860 | |1;audio-beat-detection;163;130;160;189;133;169;158;134;188;134 09:20:33 INFO - PROCESS | 10860 | |2;audio-dft;162;163;162;161;165;160;162;162;162;160 09:20:33 INFO - PROCESS | 10860 | |3;audio-fft;75;79;112;113;75;74;75;109;113;74 09:20:33 INFO - PROCESS | 10860 | |4;audio-oscillator;97;95;150;95;95;95;151;94;93;145 09:20:33 INFO - PROCESS | 10860 | |5;imaging-gaussian-blur;117;118;117;117;118;117;117;117;117;118 09:20:33 INFO - PROCESS | 10860 | |6;imaging-darkroom;146;146;147;146;146;146;146;146;146;147 09:20:33 INFO - PROCESS | 10860 | |7;imaging-desaturate;95;95;96;95;95;95;95;94;95;95 09:20:33 INFO - PROCESS | 10860 | |8;json-parse-financial;64;66;66;66;66;68;65;83;62;62 09:20:33 INFO - PROCESS | 10860 | |9;json-stringify-tinderbox;65;64;65;65;65;65;64;66;66;65 09:20:33 INFO - PROCESS | 10860 | |10;stanford-crypto-aes;79;80;79;79;85;79;79;81;80;81 09:20:33 INFO - PROCESS | 10860 | |11;stanford-crypto-ccm;133;133;134;131;133;136;128;133;162;136 09:20:33 INFO - PROCESS | 10860 | |12;stanford-crypto-pbkdf2;176;174;179;184;179;174;172;176;182;176 09:20:33 INFO - PROCESS | 10860 | |13;stanford-crypto-sha256-iterative;73;73;74;73;71;74;71;71;73;72 09:20:33 INFO - PROCESS | 10860 | __end_tp_report 09:20:33 INFO - PROCESS | 10860 | __start_cc_report 09:20:33 INFO - PROCESS | 10860 | _x_x_mozilla_cycle_collect,1934 09:20:33 INFO - PROCESS | 10860 | __end_cc_report 09:20:33 INFO - PROCESS | 10860 | __startTimestamp1461860433047__endTimestamp 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | ------- Summary: start ------- 09:20:33 INFO - PROCESS | 10860 | Number of tests: 14 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#0] ai-astar Cycles:10 Average:112.40 Median:114.00 stddev:4.17 (3.7%) stddev-sans-first:4.38 09:20:33 INFO - PROCESS | 10860 | Values: 114.0 117.0 107.0 114.0 115.0 114.0 103.0 114.0 113.0 113.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#1] audio-beat-detection Cycles:10 Average:155.80 Median:161.50 stddev:22.42 (13.9%) stddev-sans-first:23.63 09:20:33 INFO - PROCESS | 10860 | Values: 163.0 130.0 160.0 189.0 133.0 169.0 158.0 134.0 188.0 134.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.45 (0.9%) stddev-sans-first:1.54 09:20:33 INFO - PROCESS | 10860 | Values: 162.0 163.0 162.0 161.0 165.0 160.0 162.0 162.0 162.0 160.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#3] audio-fft Cycles:10 Average:89.90 Median:74.50 stddev:18.89 (25.4%) stddev-sans-first:19.25 09:20:33 INFO - PROCESS | 10860 | Values: 75.0 79.0 112.0 113.0 75.0 74.0 75.0 109.0 113.0 74.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#4] audio-oscillator Cycles:10 Average:111.00 Median:95.00 stddev:26.06 (27.4%) stddev-sans-first:27.14 09:20:33 INFO - PROCESS | 10860 | Values: 97.0 95.0 150.0 95.0 95.0 95.0 151.0 94.0 93.0 145.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:20:33 INFO - PROCESS | 10860 | Values: 117.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 118.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:20:33 INFO - PROCESS | 10860 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:20:33 INFO - PROCESS | 10860 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:66.00 stddev:6.00 (9.1%) stddev-sans-first:6.27 09:20:33 INFO - PROCESS | 10860 | Values: 64.0 66.0 66.0 66.0 66.0 68.0 65.0 83.0 62.0 62.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 09:20:33 INFO - PROCESS | 10860 | Values: 65.0 64.0 65.0 65.0 65.0 65.0 64.0 66.0 66.0 65.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:1.87 (2.3%) stddev-sans-first:1.94 09:20:33 INFO - PROCESS | 10860 | Values: 79.0 80.0 79.0 79.0 85.0 79.0 79.0 81.0 80.0 81.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#11] stanford-crypto-ccm Cycles:10 Average:135.90 Median:133.50 stddev:9.46 (7.1%) stddev-sans-first:9.97 09:20:33 INFO - PROCESS | 10860 | Values: 133.0 133.0 134.0 131.0 133.0 136.0 128.0 133.0 162.0 136.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:177.50 stddev:3.77 (2.1%) stddev-sans-first:3.97 09:20:33 INFO - PROCESS | 10860 | Values: 176.0 174.0 179.0 184.0 179.0 174.0 172.0 176.0 182.0 176.0 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - PROCESS | 10860 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:73.00 stddev:1.18 (1.6%) stddev-sans-first:1.24 09:20:33 INFO - PROCESS | 10860 | Values: 73.0 73.0 74.0 73.0 71.0 74.0 71.0 71.0 73.0 72.0 09:20:33 INFO - PROCESS | 10860 | -------- Summary: end -------- 09:20:33 INFO - PROCESS | 10860 | 09:20:33 INFO - TEST-INFO | 10860: exit 0 09:20:34 INFO - TEST-OK | kraken | took 63361ms 09:20:34 INFO - SUITE-END | took 1182s 09:20:34 INFO - Completed test suite (00:19:42) 09:20:34 INFO - javascript benchmark 09:20:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2592.948141037179, 2624.6719160104985, 2676.264027392466, 2676.3844315807364, 2677.370817857803, 4534.206513860077, 4604.148232576976, 4615.607673347765, 4634.721916685001, 4662.067586482704, 4602.355670206174, 4622.907541849161, 4674.883127921804, 4718.268668356411, 4720.150372932871, 4170.228507726065, 4192.308269135604, 4235.3858690490015, 4237.491501019882, 4391.253486907218, 4313.676474264443, 4343.609075183233, 4344.304911214206, 4387.254166791653, 4399.142167277366, 4187.539370669224, 4256.4041034254915, 4267.189234045552, 4273.87178384649, 4285.078708077747, 4238.936415953764, 4279.165562715271, 4310.396544483775, 4348.934765978513, 4359.847405340798, 4208.726432781859, 4223.239816832969, 4227.704060715751, 4257.042165512766, 4270.743755374688, 4399.098184872094, 4434.822607095713, 4457.353683715875, 4469.7541635210055, 4478.081993191388, 4919.680220785674, 4979.029089327582, 5019.4227663818765, 5028.421731500887, 5029.0, 4938.1111399947995, 4941.357623508957, 4949.7772600233075, 4974.353334066548, 4980.950190498085, 4498.797554110073, 4627.236505976543, 4632.420947381611, 4634.72191668501, 4640.32715256286, 5048.697078175322, 5054.519820617035, 5065.544101030925, 5111.514406131486, 5118.0, 4831.855044348676, 4836.830710925101, 4842.031593681278, 4846.345743324606, 4894.339264199359, 4797.352357431771, 4863.465018847924, 4911.852644420673, 4944.9258261126115, 4998.825041123543, 4839.830605928775, 4874.53691899269, 4899.436564795023, 4901.142300097468, 4936.580390666762, 4872.244802055687, 4872.537108974641, 4876.731779752076, 4878.829240976549, 4919.606431485473, 4093.63157315843, 4171.165766846643, 4181.853635122817, 4212.473440819897, 4260.4248426462655, 3643.125649844071, 3680.6319368063514, 3709.1839795245005, 3715.294094122108, 3755.4930084438797, 4245.5542168072525, 4274.829006839692, 4293.957060429356, 4300.225959327288, 4337.848175313912, 4810.7835147418455, 4825.758712064383, 4985.127602669519, 4992.276119962585, 5045.520675535819, 4242.066745316026, 4248.468941382327, 4274.166537525152, 4275.551067137968, 4292.613664770186, 4441.800118994653, 4442.622377097919, 4443.200223959719, 4452.109578084332, 4489.551044895485, 4614.561616646548, 4674.813007479663, 4678.0643871226575, 4711.19909615373, 4780.665353425227, 4909.0672772173175, 4986.825461108844, 4992.80028798844, 4997.225430058346, 5015.423226328873, 4637.3739545162325, 4642.2108241598335, 4696.624270058455, 4698.436187657503, 4752.572268495852, 4705.623550116051, 4834.709917404967, 4883.853484395474, 4896.338994235733, 4921.680090794087, 4893.412790465167, 4906.55840974314, 4908.435529914105, 4918.773683766137, 4953.876153096201, 4676.462206846256, 4695.014047050157, 4707.105649926503, 4755.358026666356, 4762.52374762521, 4587.77061146948, 4756.16767065769, 4793.472718001087, 4816.229403295456, 4837.685550439211, 4711.057788442256, 4716.3868697069165, 4719.929201062056, 4734.124187025412, 4793.520647935178, 4681.438227412732, 4818.975905120452, 4834.371531700997, 4844.297576851266, 4877.80488780459, 4666.34671146033, 4718.457377401505, 4719.3156992235245, 4722.7874745635845, 4730.834420795256], "value": 4536.458690887928, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.3123420661, 588.7409539802487, 601.7472661482177, 610.5024405109833, 613.5306490534739, 27092.0, 34266.65733342666, 34894.651053489455, 34925.0, 35052.29895402094, 37971.430428543594, 38776.612233877655, 38888.80555597225, 38893.61106388935, 38937.0, 7658.731944381948, 7873.291403773659, 7876.0, 7878.330341920943, 7885.172056933997, 7569.545827250355, 7641.2740789624895, 7685.6157192140445, 7709.498882572643, 7836.608169591497, 7847.803804904866, 7904.762857114296, 7905.367570594367, 7906.0, 7910.683572657087, 7807.984961954966, 7840.2159784021715, 7845.686172553091, 7855.842883142305, 7858.371330293563, 7860.68557257709, 7977.122516523178, 8010.198980101944, 8012.8397432051615, 8027.959860200662, 38493.61506384928, 39033.80483097566, 39097.609023909965, 39110.21779564393, 39175.41236881449, 2929.8535073246253, 3134.388794185135, 3233.3048394595276, 3289.259916518788, 3399.9830000850084, 29065.564016539767, 29342.266443338875, 29422.852885735647, 30190.69809301923, 30412.239694007607, 3121.82829944353, 3152.668969758165, 3159.08386567895, 3188.3942051010235, 3204.2630195055267, 244745.0, 245527.0, 245642.0, 245658.0, 246617.0, 30489.237769055508, 54743.45256547463, 54798.0, 54823.0, 55269.0, 32231.35537289288, 32319.838400807846, 32377.35245295081, 32408.35183296321, 32418.83790581032, 12552.686182845502, 12750.80873786894, 12755.42600582976, 12758.170718903428, 12765.872341276654, 9667.9033209667, 9688.079632434907, 9725.124738773544, 9739.318247722591, 9787.902120978699, 7721.691132354643, 7756.495827771177, 7822.413319000984, 8078.23256790604, 8223.383246256555, 2999.5200767877036, 3024.1834704629628, 3028.78798484104, 3041.528563072727, 3099.57419586988, 872.6378552900602, 904.3579058868146, 918.2653876898454, 986.2947992185622, 1051.5005372448027, 27757.306067348072, 28594.0, 29012.0, 29182.854085729436, 29298.560521592193, 1007.2596641468515, 1011.4639241202027, 1024.779672370444, 1036.6060896859144, 1036.9740756480996, 15208.619784505476, 15243.847561524464, 15360.308786104652, 15679.294431750599, 15753.68492630141, 363994.0, 369775.0, 370379.0, 371040.0, 372598.0, 48751.0, 49051.509484904695, 49066.754666227156, 49091.0, 49874.25188622173, 1137.181229514748, 1150.3902931446346, 1165.9708507287385, 1225.7671042501895, 1234.2779474007627, 25990.0, 28206.913010620527, 28417.0, 28648.57027144553, 29186.708132919248, 40270.59729402669, 41009.0, 41017.79491102525, 41043.0, 41044.179116418105, 231985.0, 234473.0, 234756.0, 236859.0, 237033.0, 21186.894065529574, 23993.28020159398, 24188.0, 24512.877435612707, 25184.992600295784, 37526.43710344292, 37540.81229593835, 37928.620713793614, 37977.4303385455, 38665.80667096646, 31944.68055319417, 31983.200419989687, 32543.837280813445, 33017.17457063593, 33472.330553389285, 32000.19999499938, 32121.0, 32954.34091318208, 33115.17212069718, 33253.33973282083, 30971.535426968163, 31187.53218701676, 35359.0, 35591.28817423585, 35730.64269357273, 29696.0, 30373.544396834506, 31559.52660710045, 31887.0, 32001.519977200827, 24368.390790230387, 24460.021599136548, 24850.130245441323, 25072.0, 25118.62322065207], "value": 15948.750919526772, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.4853579823897, 384.62114816905347, 389.5247797686821, 396.11665984853755, 400.569387907999, 12871.292078935654, 13398.59804205873, 15770.369185232592, 16892.0, 16975.151242437874, 15389.769153462707, 16172.83827161728, 16252.0, 16866.578335541617, 17341.21964511597, 5024.371953505803, 5236.31927849379, 5240.790368385279, 5257.1851363038595, 5285.656432331905, 5973.313068997056, 6006.519478441714, 6019.247594050744, 6030.246219222598, 6031.909521357183, 5871.3541510433815, 5961.940380596204, 5971.4625683688455, 5985.521158307325, 6003.579749417542, 5843.64938103713, 6016.338202797689, 6028.336882942873, 6049.516038716892, 6054.727537260834, 5170.819021334235, 5260.842174734764, 5339.733013349356, 5340.038793017256, 5349.331333583302, 11622.244554104042, 12329.5068197272, 12380.071494637938, 20623.0, 20830.47923801902, 1887.726279689451, 1889.2726300374454, 1895.668258054835, 1942.1163370666313, 2102.9158833646634, 8829.514376709278, 9366.20387267083, 9513.191378732816, 11406.429678516124, 13165.539206127836, 1775.9822401775946, 1853.7868145163209, 1874.6719324118226, 1881.9623607527774, 1891.4514790710814, 67412.32587674109, 69063.0, 70773.2922670777, 70799.0, 71545.64227178883, 9963.202943764627, 9983.151432128205, 10170.033846784543, 10347.482625868677, 10470.685879423629, 12681.682957926125, 13211.471541138248, 14528.20094894791, 14559.3448294827, 14577.344019519147, 5739.770409183586, 5876.529877609866, 6296.464800491918, 7042.260562640849, 7150.356467917913, 6233.657148856856, 6554.934450655528, 6694.899576506356, 6718.630475323806, 6736.393724564715, 4287.862212964505, 4416.447944006999, 4424.247877860771, 4553.5218802025975, 4564.2240819060835, 1812.9818701813076, 1864.7016477363827, 1865.39374703222, 1892.5079479335473, 1926.9421917342502, 808.8382323535199, 852.8720691896141, 855.3157474020759, 862.8015556422058, 979.9314048016571, 15920.840791591936, 16189.0, 16292.837071629368, 16640.001599903804, 17124.914375428292, 875.9036505984336, 902.3863772634671, 902.8239493298875, 956.9090936361034, 958.0850287974968, 9201.263898888074, 9381.812363752822, 9444.197243234265, 9464.763380915532, 9626.229901607854, 385479.0, 390755.0, 393124.0, 406242.0, 410660.0, 47211.29183062257, 55166.44833551613, 79260.0, 82080.0, 85825.14174858296, 902.9232515236279, 930.223263574909, 974.6052848596188, 985.4678473624306, 986.3637953520083, 12072.3360215189, 15125.36818395593, 15919.442819501724, 16275.674486510441, 16300.673986519965, 35072.29855402929, 35370.29259414849, 36026.0, 36177.638223617425, 37709.05727356838, 45437.772811135736, 48936.510634893195, 48944.2658360132, 49181.75409122931, 49252.26121608107, 7707.653155608124, 7765.572893490912, 7882.84234315299, 7902.841943161219, 8040.356771458386, 6688.398044176048, 9585.808283834423, 10028.097471227624, 11254.718632034264, 11434.428278586203, 9756.0, 9964.402135871871, 10066.899331006889, 10117.645882394081, 10320.48397580103, 9920.255980801325, 9975.501224938578, 10020.849687254551, 10067.798644027225, 10397.792044158923, 8849.51327676984, 8881.955590222007, 9207.217386522221, 9379.953100234456, 9767.413955162712, 7350.963245183953, 7541.35898448638, 7649.464537482323, 7701.576413297323, 7810.609469526615, 5210.1663733803925, 6218.253809542884, 9087.182153606207, 9719.951400242953, 10221.846672300071], "value": 8076.543772173026, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.0625149892073, 1334.4728832111318, 1392.8328600567932, 1392.937317820698, 1537.8231503377108, 6748.19021717394, 7624.085109786826, 7871.291583757461, 7891.881621775671, 7916.247956444143, 7548.471606987513, 7693.961530192341, 7713.0, 7714.537127772338, 7716.92283077169, 1875.0999520230298, 1943.1353047893692, 1945.6886898096307, 2201.2515744646776, 2237.6643503474447, 2169.4359466538654, 2222.688823564702, 2243.3942835434505, 2249.134083377895, 2253.481699209183, 2169.0, 2198.6372248578964, 2204.019211450905, 2206.0844749428966, 2235.228846048119, 1858.3124244029661, 1919.510524816177, 2176.6082105221053, 2178.4771654802894, 2180.0, 1899.857510686704, 1919.4145785535545, 2076.2317942361274, 2147.226998280618, 2153.052656831005, 7538.095428548554, 7607.543547387119, 7612.200718924489, 7630.38956883448, 7633.389328853678, 4315.244832154361, 4492.68551201416, 4506.5493450655, 4534.2971839364955, 4534.36518887356, 2749.5463248563965, 2804.1587523742796, 2810.5643625237917, 2812.2828678686806, 2872.1240021793346, 903.7966457547, 905.3526728389168, 919.1176470588176, 947.673052796784, 964.243069190689, 10026.197904167722, 10039.196864250842, 10050.798984020279, 10052.346597471142, 10066.899331006669, 4078.7348822326453, 4175.248455278079, 4265.637420819203, 4274.658027357804, 4309.5044069932355, 8445.366597505212, 8516.8722469163, 8529.445586036887, 8551.529665868436, 8560.61477233526, 4575.2450845610765, 4639.30410438437, 4644.233701439225, 4645.187092258841, 4646.697964632288, 3691.1325838427947, 4065.329220678555, 4073.816678249486, 4133.1940271647345, 4266.253405653998, 3495.877644282438, 3552.0942159749097, 3610.656987586175, 3625.2568223514118, 3631.763935344247, 1186.0511590727383, 1193.8268951002055, 1196.629044996054, 1200.3038237822043, 1200.903927685783, 744.3784439992667, 757.1671161722062, 818.7993941484256, 850.1073872433919, 871.7384784564605, 4937.580305674058, 5308.30991971049, 5331.920021199685, 5359.517643412111, 5405.648632838853, 406.86776797540915, 413.57195302861993, 417.38644193036185, 418.8575884199387, 423.25084600257986, 3487.4245749451566, 3527.5590551181103, 3548.1129717570607, 3620.6560376764164, 3623.8912832615065, 9144.314176436794, 9565.091316324939, 9579.90420095804, 9684.612615495302, 9695.030496950249, 9679.0, 9750.75623109428, 9782.706518804447, 9826.213902887896, 9847.064528869745, 6624.105745724362, 7032.824179395454, 7066.823329416703, 7103.680334384861, 7110.537815042006, 5043.5460808527405, 5056.84829455117, 5078.7206703631655, 5167.870803229949, 5204.063268611686, 8183.590820459072, 8289.95855020721, 8460.407771455939, 8659.0, 8664.870026949728, 8734.69428569997, 8746.169113934166, 8756.562171891508, 8890.288776897703, 8964.417312874642, 6132.8773424532155, 6546.738130474918, 6744.62904540255, 6806.965965170142, 6929.0, 7191.0, 7209.819754506179, 7231.023811785341, 7264.527805692613, 7269.091363579552, 6608.074869518176, 6726.865462690621, 6730.3606157416925, 6816.829579260359, 6843.589384636938, 6716.731330746716, 6738.427233684995, 6760.864782704417, 6789.558678686068, 6857.27998560144, 6338.049292606146, 6338.080978258219, 6395.552311338161, 6419.775307864203, 6420.967895160495, 4524.502304746541, 4567.634589232787, 4597.310403439511, 4646.558576935186, 4678.906421871611, 4448.733076015449, 4622.399088118657, 4634.0036892068365, 4638.5593368629925, 4735.526447355237], "value": 3984.8152800960243, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.6167136900452, 311.93449375631116, 317.05517557678104, 317.0741435009772, 317.2655302973616, 4613.215753321935, 4672.345871577973, 4682.648801339896, 4730.787114579844, 4755.096531658983, 4354.564543545644, 4454.420925279717, 4533.50394369858, 4644.744539050359, 4657.813687452506, 3715.6098609646006, 3723.73933824631, 3734.477173195755, 3742.9064273393246, 3752.737308388414, 3732.122951106501, 3750.6061863504347, 3751.643593858593, 3752.906177345561, 3764.1906989997146, 3532.364174448598, 3588.1747198144444, 3729.8881033569037, 3734.346489364364, 3746.8875933722034, 3699.6855267302312, 3706.314331848603, 3711.628837116267, 3713.2016616427463, 3730.0, 3329.3174899145615, 3330.383878982396, 3334.3498017886523, 3341.298327351259, 3351.731861451078, 4318.676099292566, 4459.9331010034875, 4472.955270447286, 4483.6861419700635, 4511.015153332523, 4243.6605071594195, 4270.508891477489, 4284.614384705392, 4286.464191976003, 4300.677449191323, 4897.975510122426, 4915.139850526144, 4929.950700492985, 4929.975350123263, 4932.556069953722, 3643.9635603644156, 3648.5804132524568, 3661.9633803662155, 3670.908227294339, 3674.5223120994365, 4977.477364876672, 5160.380754309507, 5171.120909445403, 5177.938522602857, 5179.611529135293, 3691.464737613031, 3711.777293362407, 3727.8881633551036, 3729.9440508392395, 3730.235301763132, 3768.6231376862092, 3770.1140232045445, 3775.301569209705, 3781.262653782486, 3784.6215378462484, 3972.6424621784176, 4185.707000509935, 4234.851780187679, 4236.830526778956, 4256.170046840898, 4858.8785280367565, 4874.975625121922, 4888.6577939543895, 4913.508649135058, 4932.4081110267, 1268.8287081243914, 1289.8516670582815, 1306.5427100514935, 1316.4076165725462, 1318.967025824362, 907.328576853124, 916.7715261549556, 917.3074328881652, 917.6788124156492, 923.3951761596165, 4597.7241365518175, 4605.125026245003, 4631.282151266559, 4644.512326205768, 4645.558671926162, 5429.375621803543, 5432.75552600134, 5441.265429167091, 5444.638840289927, 5445.918311225335, 4417.249067658506, 4566.88582785433, 4570.81716731327, 4578.702384345007, 4615.23848564983, 2844.416894536615, 2867.6702179249655, 2869.2253091665134, 2869.368738877444, 2870.6124673168856, 4959.528844759742, 5051.242313652908, 5069.923951140662, 5091.363579552556, 5108.438071812172, 5458.235846981426, 5462.6995515247045, 5476.7535460904355, 5493.752781124779, 5505.0, 5001.624878134227, 5015.247712843103, 5015.27278544609, 5030.37120359955, 5064.620153488503, 5218.504242096995, 5341.813036543703, 5358.651687640447, 5371.865703357447, 5381.67709937405, 5211.270422140828, 5240.318758561362, 5250.396204436539, 5252.842414727565, 5262.526372626482, 5038.01758657073, 5057.14028615129, 5061.924071138862, 5065.746712664426, 5083.491650835035, 3511.2626348467106, 3693.501377314028, 3793.393057110849, 3843.308204523101, 3848.634379733921, 4987.177115775857, 5035.949640503694, 5040.6471546992825, 5046.545810877039, 5049.838537136553, 4870.659053866196, 4897.534734200245, 4905.950940490692, 4918.327409241451, 4934.0, 4652.0, 4885.706857588414, 4903.141950158779, 4934.62990275738, 4962.776675049704, 4562.8859278516975, 4690.3433519306645, 4863.513648635108, 4873.829415970424, 4878.609711223165, 4984.22744474607, 4985.601151907911, 4990.126727822689, 4999.425066117297, 5002.099622067917, 4864.902701945871, 4865.781039853145, 4872.782883787939, 4874.829380971649, 4877.414710234794], "value": 3815.100992896507, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.9134475501052, 687.6046273392799, 711.5268346549544, 714.2286330762777, 728.9088863892014, 9998.550065247064, 11180.16148788841, 11456.083513318936, 11461.828072578917, 11478.885211147886, 11064.723381915455, 11082.390468524229, 11141.832872506919, 11168.888311116887, 11249.212555121145, 632.9019002054689, 768.8616049111158, 776.54572075336, 799.5762246009596, 801.6272433318505, 785.7485604606528, 790.8102055506665, 792.302773559267, 795.026093036032, 795.1849354411745, 826.3595713322194, 827.0406328658758, 827.0571548434789, 827.5696637748367, 827.9254867061964, 653.5490511547041, 811.9391045671568, 829.340674164035, 832.4464231286188, 837.8868852704868, 613.3406587918006, 613.4233820208989, 614.4500671898626, 615.9599626024338, 616.907463880417, 11329.943350283278, 11334.659960201207, 11385.601503947404, 11400.486978086006, 11409.0, 11204.327740335606, 11244.26912250701, 11639.76720465586, 11643.301401915913, 12067.0, 9215.308851836138, 9230.261579073658, 9467.384619999746, 9536.761580960461, 9593.808123837554, 3164.525321201815, 3185.633652129988, 3225.403300389412, 3230.127865476308, 3268.297316077055, 45247.54752452499, 45767.313490297674, 46644.300335495, 46673.29990050186, 46766.53233467656, 8633.179847914438, 8679.132086791396, 8716.0, 8737.126287371339, 8903.688370906988, 16573.751393729104, 16592.419265325654, 16603.833961660228, 16614.335426583042, 16635.168241588115, 6078.422549857756, 6091.543134264948, 6478.611283322922, 6493.5454518184215, 6629.801105966829, 3484.8780292689635, 3536.133647256387, 3545.5745310562897, 3697.4084146536425, 3766.9434958475645, 2642.4715056988293, 2720.7551320381262, 2721.5509440942024, 2723.591461280784, 2743.8490883001227, 511.8387707872008, 518.7898900945123, 521.7938914128898, 528.5322489596734, 529.5498825997871, 450.23460117799874, 495.1112752609075, 521.1895502493659, 522.2218893848138, 566.9518090962232, 7291.489595728248, 7385.778426647209, 7392.223816499298, 7511.436642251853, 7653.11989121247, 558.1794761700294, 562.4122791682702, 566.5297802823646, 572.6764378126345, 579.2643342954424, 3496.650334966534, 3527.2063785648024, 3596.2268112355446, 3677.724170687209, 3694.408894576855, 46014.309785353245, 51675.483245167816, 53617.46382536203, 54080.0, 54148.729256353465, 47341.5265847344, 48484.7575762126, 48711.0, 49242.26136607954, 50172.49827501751, 42914.356284655754, 43781.56218437839, 43881.78059109684, 44674.55325446769, 44843.77578112089, 7707.614619269127, 7753.728619498516, 7914.643841027283, 7923.128455869734, 8089.635966381645, 29623.703762962094, 29864.0, 30329.69670303269, 30698.386032279675, 30788.38423231478, 35604.287914242086, 37883.242335152594, 37922.810385947894, 38835.61164388433, 39242.01894952649, 9294.581743821409, 9455.858162127435, 9552.617895284111, 9664.565094570902, 9704.951475242578, 7272.550389585105, 9665.226781857575, 10561.260711750103, 10699.839502407625, 11099.445027748741, 9572.234221262142, 9787.755306117404, 9795.706128816148, 9945.453000085065, 9976.950115249378, 9833.008476047264, 9891.653792117242, 10073.647422340184, 10088.092071713581, 10212.234082443994, 9459.432434053979, 9548.952255238679, 9592.856107158537, 9613.615455381987, 10074.294799363974, 8296.294814940798, 8301.543415112226, 8498.235158835734, 8663.826723465621, 8726.956365218133, 8163.959180204061, 8266.793330166794, 8369.204925532065, 8502.659893604434, 8538.615762290588], "value": 5605.790138309589, "unit": "score"}], "name": "dromaeo_css", "value": 6065.658374527576, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 107.0, 114.0, 115.0, 114.0, 103.0, 114.0, 113.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 130.0, 160.0, 189.0, 133.0, 169.0, 158.0, 134.0, 188.0, 134.0], "value": 155.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 161.0, 165.0, 160.0, 162.0, 162.0, 162.0, 160.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 112.0, 113.0, 75.0, 74.0, 75.0, 109.0, 113.0, 74.0], "value": 89.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 150.0, 95.0, 95.0, 95.0, 151.0, 94.0, 93.0, 145.0], "value": 111.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 66.0, 66.0, 68.0, 65.0, 83.0, 62.0, 62.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 64.0, 66.0, 66.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 79.0, 85.0, 79.0, 79.0, 81.0, 80.0, 81.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 133.0, 134.0, 131.0, 133.0, 136.0, 128.0, 133.0, 162.0, 136.0], "value": 135.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 174.0, 179.0, 184.0, 179.0, 174.0, 172.0, 176.0, 182.0, 176.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 73.0, 71.0, 74.0, 71.0, 71.0, 73.0, 72.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1587.1000000000001, "alertThreshold": 2.0}]} 09:20:34 INFO - Return code: 0 09:20:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:20:34 ERROR - # TBPL SUCCESS # 09:20:34 INFO - Running post-action listener: _resource_record_post_action 09:20:34 INFO - Running post-run listener: _resource_record_post_run 09:20:35 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 518656000; Read time: 16; Write time: 389896 09:20:35 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 234885120; Read time: 0; Write time: 237196 09:20:35 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283770880; Read time: 16; Write time: 152700 09:20:35 INFO - Running post-run listener: _upload_blobber_files 09:20:35 INFO - Blob upload gear active. 09:20:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:35 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:36 INFO - (blobuploader) - INFO - Done attempting. 09:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:37 INFO - (blobuploader) - INFO - Done attempting. 09:20:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:37 INFO - Return code: 0 09:20:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bda7324d21324b5fc3ccf48939ab11d5a1f0f090b335af15c5032b604f427f2d1d760fcfbf2d0f1ddd63a9df02b7e9b831d5bd14a3b2764caa5ea3ae42d3193e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2709de1b1e84047f20bf1955c47868456653a39842a58873a2ac72b9f1d9cedaeb303aeae0745137fd19c11ffb2fd2ba53a0053c9f46b33d27a50bb61bb009d"} 09:20:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:37 INFO - Contents: 09:20:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bda7324d21324b5fc3ccf48939ab11d5a1f0f090b335af15c5032b604f427f2d1d760fcfbf2d0f1ddd63a9df02b7e9b831d5bd14a3b2764caa5ea3ae42d3193e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2709de1b1e84047f20bf1955c47868456653a39842a58873a2ac72b9f1d9cedaeb303aeae0745137fd19c11ffb2fd2ba53a0053c9f46b33d27a50bb61bb009d"} 09:20:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:37 INFO - Copying logs to upload dir... 09:20:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.360775 ========= master_lag: 0.43 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-04-28 09:20:37.620695) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:37.625386) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613 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/bda7324d21324b5fc3ccf48939ab11d5a1f0f090b335af15c5032b604f427f2d1d760fcfbf2d0f1ddd63a9df02b7e9b831d5bd14a3b2764caa5ea3ae42d3193e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2709de1b1e84047f20bf1955c47868456653a39842a58873a2ac72b9f1d9cedaeb303aeae0745137fd19c11ffb2fd2ba53a0053c9f46b33d27a50bb61bb009d"} build_url:https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/Ovw8MKyJRke6N0Ow6R7ZJA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bda7324d21324b5fc3ccf48939ab11d5a1f0f090b335af15c5032b604f427f2d1d760fcfbf2d0f1ddd63a9df02b7e9b831d5bd14a3b2764caa5ea3ae42d3193e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d2709de1b1e84047f20bf1955c47868456653a39842a58873a2ac72b9f1d9cedaeb303aeae0745137fd19c11ffb2fd2ba53a0053c9f46b33d27a50bb61bb009d"}' ========= master_lag: 0.14 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:37.773911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:37.777394) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1461857374.302693-1158663613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:20:37.842286) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:20:37.842594) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:20:37.842856) ========= ========= Total master_lag: 0.73 =========