builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1463071960.92 results: success (0) buildid: 20160512093437 builduid: 803aea3cf46e49bfa68bec90c6c6c4c9 revision: 6191f8b4e99d5096c0060113ad71d6c4c5b7116b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:40.919087) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:40.919623) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:40.919960) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:40.975419) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:40.975842) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 09:52: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.3M=0s 2016-05-12 09:52:41 (37.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077751 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:41.072008) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:41.072326) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023295 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:41.133882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 09:52:41.134206) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6191f8b4e99d5096c0060113ad71d6c4c5b7116b --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 6191f8b4e99d5096c0060113ad71d6c4c5b7116b --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=74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 09:52:41,483 truncating revision to first 12 chars 2016-05-12 09:52:41,483 Setting DEBUG logging. 2016-05-12 09:52:41,484 attempt 1/10 2016-05-12 09:52:41,484 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6191f8b4e99d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 09:52:42,830 unpacking tar archive at: fx-team-6191f8b4e99d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.626282 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 09:52:42.776509) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:42.776850) ========= script_repo_revision: 6191f8b4e99d5096c0060113ad71d6c4c5b7116b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:42.777273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:42.777556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 09:52:42.786884) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-12 09:52:42.787270) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:52:43 INFO - MultiFileLogger online at 20160512 09:52:43 in /builds/slave/test 09:52: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 09:52:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:52:43 INFO - {'append_to_log': False, 09:52:43 INFO - 'base_work_dir': '/builds/slave/test', 09:52:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:52:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:52:43 INFO - 'branch': 'Fx-Team-Non-PGO', 09:52:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:52:43 INFO - 'config_files': ('talos/linux_config.py',), 09:52:43 INFO - 'default_actions': ('clobber', 09:52:43 INFO - 'read-buildbot-config', 09:52:43 INFO - 'download-and-extract', 09:52:43 INFO - 'populate-webroot', 09:52:43 INFO - 'create-virtualenv', 09:52:43 INFO - 'install', 09:52:43 INFO - 'run-tests'), 09:52:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:52:43 INFO - 'download_minidump_stackwalk': True, 09:52:43 INFO - 'download_symbols': 'ondemand', 09:52:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:52:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:52:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:52:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:52:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:52:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:52:43 INFO - 'installer_path': 'installer.exe', 09:52:43 INFO - 'log_level': 'info', 09:52:43 INFO - 'log_name': 'talos', 09:52:43 INFO - 'log_to_console': True, 09:52:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:52:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:52:43 INFO - 'opt_config_files': (), 09:52:43 INFO - 'pip_index': False, 09:52:43 INFO - 'sps_profile': False, 09:52:43 INFO - 'sps_profile_interval': 0, 09:52:43 INFO - 'suite': 'dromaeojs', 09:52:43 INFO - 'system_bits': '32', 09:52:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:52:43 INFO - 'title': 'talos-linux64-ix-042', 09:52:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:52:43 INFO - 'use_talos_json': True, 09:52:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:52:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:52:43 INFO - 'work_dir': 'build'} 09:52:43 INFO - ##### 09:52:43 INFO - ##### Running clobber step. 09:52:43 INFO - ##### 09:52:43 INFO - Running pre-action listener: _resource_record_pre_action 09:52:43 INFO - Running main action method: clobber 09:52:43 INFO - rmtree: /builds/slave/test/build 09:52:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:52:43 INFO - Running post-action listener: _resource_record_post_action 09:52:43 INFO - ##### 09:52:43 INFO - ##### Finished clobber step (success) 09:52:43 INFO - ##### 09:52:43 INFO - ##### 09:52:43 INFO - ##### Running read-buildbot-config step. 09:52:43 INFO - ##### 09:52:43 INFO - Running pre-action listener: _resource_record_pre_action 09:52:43 INFO - Running main action method: read_buildbot_config 09:52:43 INFO - Using buildbot properties: 09:52:43 INFO - { 09:52:43 INFO - "project": "", 09:52:43 INFO - "product": "firefox", 09:52:43 INFO - "script_repo_revision": "production", 09:52:43 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:52:43 INFO - "repository": "", 09:52:43 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:52:43 INFO - "buildid": "20160512093437", 09:52:43 INFO - "pgo_build": "False", 09:52:43 INFO - "basedir": "/builds/slave/test", 09:52:43 INFO - "buildnumber": 2969, 09:52:43 INFO - "slavename": "talos-linux64-ix-042", 09:52:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:52:43 INFO - "platform": "ubuntu64_hw", 09:52:43 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:52:43 INFO - "branch": "fx-team", 09:52:43 INFO - "repo_path": "integration/fx-team", 09:52:43 INFO - "revision": "6191f8b4e99d5096c0060113ad71d6c4c5b7116b", 09:52:43 INFO - "stage_platform": "linux64", 09:52:43 INFO - "builduid": "803aea3cf46e49bfa68bec90c6c6c4c9", 09:52:43 INFO - "slavebuilddir": "test" 09:52:43 INFO - } 09:52:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:52:43 INFO - Running post-action listener: _resource_record_post_action 09:52:43 INFO - ##### 09:52:43 INFO - ##### Finished read-buildbot-config step (success) 09:52:43 INFO - ##### 09:52:43 INFO - ##### 09:52:43 INFO - ##### Running download-and-extract step. 09:52:43 INFO - ##### 09:52:43 INFO - Running pre-action listener: _resource_record_pre_action 09:52:43 INFO - Running main action method: download_and_extract 09:52:43 INFO - mkdir: /builds/slave/test/build/tests 09:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:43 INFO - https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:52:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:52:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:52:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:52:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:52:48 INFO - Downloaded 1361 bytes. 09:52:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:52:48 INFO - Using the following test package requirements: 09:52:48 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:52:48 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:52:48 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:52:48 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'jsshell-linux-x86_64.zip'], 09:52:48 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:52:48 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:52:48 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:52:48 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:52:48 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:52:48 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:52:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:52:48 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:52:48 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:52:48 INFO - https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:52:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:52:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:52:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:52:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:52:52 INFO - Downloaded 22330108 bytes. 09:52:52 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:52:52 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:52:54 INFO - Return code: 0 09:52:54 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:52: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')]} 09:52:54 INFO - https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:52:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:52:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:52:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:53:01 INFO - Downloaded 22330108 bytes. 09:53:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:53:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:53:02 INFO - Return code: 0 09:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:02 INFO - https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:53:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:53:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:53:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:53:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:53:03 INFO - Downloaded 10840239 bytes. 09:53:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:53:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:53:04 INFO - Return code: 0 09:53:04 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:53:04 INFO - https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:53:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:53:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:53:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:53:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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:53:05 INFO - Downloaded 54694147 bytes. 09:53:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:53:05 INFO - mkdir: /builds/slave/test/properties 09:53:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:53:05 INFO - Writing to file /builds/slave/test/properties/build_url 09:53:05 INFO - Contents: 09:53:05 INFO - build_url:https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:53:06 INFO - Running post-action listener: _resource_record_post_action 09:53:06 INFO - Running post-action listener: set_extra_try_arguments 09:53:06 INFO - ##### 09:53:06 INFO - ##### Finished download-and-extract step (success) 09:53:06 INFO - ##### 09:53:06 INFO - ##### 09:53:06 INFO - ##### Running populate-webroot step. 09:53:06 INFO - ##### 09:53:06 INFO - Running pre-action listener: _resource_record_pre_action 09:53:06 INFO - Running main action method: populate_webroot 09:53:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:53:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:53:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:53:06 INFO - '--tppagecycles', 09:53:06 INFO - '7'], 09:53:06 INFO - 'tests': ['tsvgm']}}, 09:53:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:53:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:53:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:53:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:53:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:53:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:53:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['damp', 'tps']}, 09:53:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'tests': ['damp', 'tps']}, 09:53:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['dromaeo_dom']}, 09:53:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:53:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['a11yr', 09:53:06 INFO - 'ts_paint', 09:53:06 INFO - 'tpaint', 09:53:06 INFO - 'sessionrestore', 09:53:06 INFO - 'sessionrestore_no_auto_restore', 09:53:06 INFO - 'tabpaint']}, 09:53:06 INFO - 'other-e10s': {'tests': ['a11yr', 09:53:06 INFO - 'ts_paint', 09:53:06 INFO - 'tpaint', 09:53:06 INFO - 'sessionrestore', 09:53:06 INFO - 'sessionrestore_no_auto_restore', 09:53:06 INFO - 'tabpaint']}, 09:53:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:53:06 INFO - 'ts_paint', 09:53:06 INFO - 'tpaint', 09:53:06 INFO - 'sessionrestore', 09:53:06 INFO - 'sessionrestore_no_auto_restore', 09:53:06 INFO - 'tabpaint']}, 09:53:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:53:06 INFO - 'ts_paint', 09:53:06 INFO - 'tpaint', 09:53:06 INFO - 'sessionrestore', 09:53:06 INFO - 'sessionrestore_no_auto_restore', 09:53:06 INFO - 'tabpaint']}, 09:53:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['a11yr', 09:53:06 INFO - 'ts_paint', 09:53:06 INFO - 'tpaint', 09:53:06 INFO - 'sessionrestore', 09:53:06 INFO - 'sessionrestore_no_auto_restore', 09:53:06 INFO - 'tabpaint']}, 09:53:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['a11yr', 09:53:06 INFO - 'ts_paint', 09:53:06 INFO - 'tpaint', 09:53:06 INFO - 'sessionrestore', 09:53:06 INFO - 'sessionrestore_no_auto_restore', 09:53:06 INFO - 'tabpaint']}, 09:53:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['tsvgx', 09:53:06 INFO - 'tsvgr_opacity', 09:53:06 INFO - 'tart', 09:53:06 INFO - 'tscrollx', 09:53:06 INFO - 'cart']}, 09:53:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:53:06 INFO - 'tsvgr_opacity', 09:53:06 INFO - 'tart', 09:53:06 INFO - 'tscrollx', 09:53:06 INFO - 'cart']}, 09:53:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'talos_options': ['--disable-e10s'], 09:53:06 INFO - 'tests': ['tp5o']}, 09:53:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'tests': ['tp5o']}, 09:53:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'talos_options': ['--disable-e10s', 09:53:06 INFO - '--xperf_path', 09:53:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:53:06 INFO - 'tests': ['tp5n']}, 09:53:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:53:06 INFO - 'talos_options': ['--xperf_path', 09:53:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:53:06 INFO - 'tests': ['tp5n']}}, 09:53:06 INFO - 'talos.zip': {'path': '', 09:53:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:53:06 INFO - Running post-action listener: _resource_record_post_action 09:53:06 INFO - ##### 09:53:06 INFO - ##### Finished populate-webroot step (success) 09:53:06 INFO - ##### 09:53:06 INFO - ##### 09:53:06 INFO - ##### Running create-virtualenv step. 09:53:06 INFO - ##### 09:53:06 INFO - Running pre-action listener: _resource_record_pre_action 09:53:06 INFO - Running main action method: create_virtualenv 09:53:06 INFO - Creating virtualenv /builds/slave/test/build/venv 09:53:06 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:53:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:53:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:53:06 INFO - Using real prefix '/usr' 09:53:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:53:07 INFO - Installing distribute.............................................................................................................................................................................................done. 09:53:09 INFO - Installing pip.................done. 09:53:09 INFO - Return code: 0 09:53:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:09 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 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:09 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:53:09 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:53:09 INFO - Using env: {'DISPLAY': ':0', 09:53:09 INFO - 'HOME': '/home/cltbld', 09:53:09 INFO - 'LANG': 'en_US.UTF-8', 09:53:09 INFO - 'LANGUAGE': 'en_US:en', 09:53:09 INFO - 'LOGNAME': 'cltbld', 09:53:09 INFO - 'MAIL': '/var/mail/cltbld', 09:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:09 INFO - 'MOZ_NO_REMOTE': '1', 09:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:09 INFO - 'NO_EM_RESTART': '1', 09:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:09 INFO - 'PWD': '/builds/slave/test', 09:53:09 INFO - 'SHELL': '/bin/bash', 09:53:09 INFO - 'SHLVL': '1', 09:53:09 INFO - 'TERM': 'linux', 09:53:09 INFO - 'TMOUT': '86400', 09:53:09 INFO - 'USER': 'cltbld', 09:53:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:09 INFO - '_': '/tools/buildbot/bin/python'} 09:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:09 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:53:09 INFO - Cleaning up... 09:53:09 INFO - Return code: 0 09:53:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:09 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 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:09 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:53:09 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:53:09 INFO - Using env: {'DISPLAY': ':0', 09:53:09 INFO - 'HOME': '/home/cltbld', 09:53:09 INFO - 'LANG': 'en_US.UTF-8', 09:53:09 INFO - 'LANGUAGE': 'en_US:en', 09:53:09 INFO - 'LOGNAME': 'cltbld', 09:53:09 INFO - 'MAIL': '/var/mail/cltbld', 09:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:09 INFO - 'MOZ_NO_REMOTE': '1', 09:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:09 INFO - 'NO_EM_RESTART': '1', 09:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:09 INFO - 'PWD': '/builds/slave/test', 09:53:09 INFO - 'SHELL': '/bin/bash', 09:53:09 INFO - 'SHLVL': '1', 09:53:09 INFO - 'TERM': 'linux', 09:53:09 INFO - 'TMOUT': '86400', 09:53:09 INFO - 'USER': 'cltbld', 09:53:09 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:09 INFO - '_': '/tools/buildbot/bin/python'} 09:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:09 INFO - Downloading/unpacking psutil>=0.7.1 09:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:53:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:53:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:53:11 INFO - Installing collected packages: psutil 09:53:11 INFO - Running setup.py install for psutil 09:53:11 INFO - building 'psutil._psutil_linux' extension 09:53:11 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:53:11 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:53:11 INFO - building 'psutil._psutil_posix' extension 09:53:11 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:53:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:53:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:53:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:53:12 INFO - Successfully installed psutil 09:53:12 INFO - Cleaning up... 09:53:12 INFO - Return code: 0 09:53:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:53: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:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53: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:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:53:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:53:12 INFO - Using env: {'DISPLAY': ':0', 09:53:12 INFO - 'HOME': '/home/cltbld', 09:53:12 INFO - 'LANG': 'en_US.UTF-8', 09:53:12 INFO - 'LANGUAGE': 'en_US:en', 09:53:12 INFO - 'LOGNAME': 'cltbld', 09:53:12 INFO - 'MAIL': '/var/mail/cltbld', 09:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:12 INFO - 'MOZ_NO_REMOTE': '1', 09:53:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:12 INFO - 'NO_EM_RESTART': '1', 09:53:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:12 INFO - 'PWD': '/builds/slave/test', 09:53:12 INFO - 'SHELL': '/bin/bash', 09:53:12 INFO - 'SHLVL': '1', 09:53:12 INFO - 'TERM': 'linux', 09:53:12 INFO - 'TMOUT': '86400', 09:53:12 INFO - 'USER': 'cltbld', 09:53:12 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:12 INFO - '_': '/tools/buildbot/bin/python'} 09:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:53: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:53: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:53: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:53: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:53: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:53: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:53:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:53:14 INFO - Installing collected packages: mozsystemmonitor 09:53:14 INFO - Running setup.py install for mozsystemmonitor 09:53:14 INFO - Successfully installed mozsystemmonitor 09:53:14 INFO - Cleaning up... 09:53:14 INFO - Return code: 0 09:53:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:53:14 INFO - Using env: {'DISPLAY': ':0', 09:53:14 INFO - 'HOME': '/home/cltbld', 09:53:14 INFO - 'LANG': 'en_US.UTF-8', 09:53:14 INFO - 'LANGUAGE': 'en_US:en', 09:53:14 INFO - 'LOGNAME': 'cltbld', 09:53:14 INFO - 'MAIL': '/var/mail/cltbld', 09:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:14 INFO - 'MOZ_NO_REMOTE': '1', 09:53:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:14 INFO - 'NO_EM_RESTART': '1', 09:53:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:14 INFO - 'PWD': '/builds/slave/test', 09:53:14 INFO - 'SHELL': '/bin/bash', 09:53:14 INFO - 'SHLVL': '1', 09:53:14 INFO - 'TERM': 'linux', 09:53:14 INFO - 'TMOUT': '86400', 09:53:14 INFO - 'USER': 'cltbld', 09:53:14 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:14 INFO - '_': '/tools/buildbot/bin/python'} 09:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:14 INFO - Downloading/unpacking blobuploader==1.2.4 09:53:14 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:53:14 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:53:14 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:53:14 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:53:14 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:53:14 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:53:16 INFO - Downloading blobuploader-1.2.4.tar.gz 09:53:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:53:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:53:16 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:53:16 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:53:16 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:53:16 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:53:16 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:53:16 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:53:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:53:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:53: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:53: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:53: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:53: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:53: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:53: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:53:17 INFO - Downloading docopt-0.6.1.tar.gz 09:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:53:17 INFO - Installing collected packages: blobuploader, requests, docopt 09:53:17 INFO - Running setup.py install for blobuploader 09:53:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:53:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:53:17 INFO - Running setup.py install for requests 09:53:17 INFO - Running setup.py install for docopt 09:53:17 INFO - Successfully installed blobuploader requests docopt 09:53:17 INFO - Cleaning up... 09:53:17 INFO - Return code: 0 09:53:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53: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:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53: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:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:17 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 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:17 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:53:17 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:53:17 INFO - Using env: {'DISPLAY': ':0', 09:53:17 INFO - 'HOME': '/home/cltbld', 09:53:17 INFO - 'LANG': 'en_US.UTF-8', 09:53:17 INFO - 'LANGUAGE': 'en_US:en', 09:53:17 INFO - 'LOGNAME': 'cltbld', 09:53:17 INFO - 'MAIL': '/var/mail/cltbld', 09:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:17 INFO - 'MOZ_NO_REMOTE': '1', 09:53:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:17 INFO - 'NO_EM_RESTART': '1', 09:53:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:17 INFO - 'PWD': '/builds/slave/test', 09:53:17 INFO - 'SHELL': '/bin/bash', 09:53:17 INFO - 'SHLVL': '1', 09:53:17 INFO - 'TERM': 'linux', 09:53:17 INFO - 'TMOUT': '86400', 09:53:17 INFO - 'USER': 'cltbld', 09:53:17 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:17 INFO - '_': '/tools/buildbot/bin/python'} 09:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:18 INFO - Running setup.py (path:/tmp/pip-BPEgKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:18 INFO - Running setup.py (path:/tmp/pip-8M8qUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:18 INFO - Running setup.py (path:/tmp/pip-s60oQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:18 INFO - Running setup.py (path:/tmp/pip-2K8317-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:18 INFO - Running setup.py (path:/tmp/pip-opzcsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:18 INFO - Running setup.py (path:/tmp/pip-fGCIq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:18 INFO - Running setup.py (path:/tmp/pip-gdZhCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:18 INFO - Running setup.py (path:/tmp/pip-zAIZKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:18 INFO - Running setup.py (path:/tmp/pip-Jfsq0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:18 INFO - Running setup.py (path:/tmp/pip-OuNylm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:18 INFO - Running setup.py (path:/tmp/pip-QF0SWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:18 INFO - Running setup.py (path:/tmp/pip-748udd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:18 INFO - Running setup.py (path:/tmp/pip-zXg514-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:19 INFO - Running setup.py (path:/tmp/pip-YdFhC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:19 INFO - Running setup.py (path:/tmp/pip-rCxD4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:19 INFO - Running setup.py (path:/tmp/pip-qX5NXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:19 INFO - Running setup.py (path:/tmp/pip-bR0l0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:53:19 INFO - Running setup.py install for manifestparser 09:53:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:53:19 INFO - Running setup.py install for mozcrash 09:53:19 INFO - Running setup.py install for mozdebug 09:53:19 INFO - Running setup.py install for mozdevice 09:53:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:53:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:53:19 INFO - Running setup.py install for mozfile 09:53:19 INFO - Running setup.py install for mozhttpd 09:53:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:53:19 INFO - Running setup.py install for mozinfo 09:53:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Running setup.py install for mozInstall 09:53:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Running setup.py install for mozleak 09:53:20 INFO - Running setup.py install for mozlog 09:53:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Running setup.py install for moznetwork 09:53:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Running setup.py install for mozprocess 09:53:20 INFO - Running setup.py install for mozprofile 09:53:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Running setup.py install for mozrunner 09:53:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:53:20 INFO - Running setup.py install for mozscreenshot 09:53:20 INFO - Running setup.py install for moztest 09:53:21 INFO - Running setup.py install for mozversion 09:53:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:53:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:53:21 INFO - Cleaning up... 09:53:21 INFO - Return code: 0 09:53:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:53:21 INFO - Using env: {'DISPLAY': ':0', 09:53:21 INFO - 'HOME': '/home/cltbld', 09:53:21 INFO - 'LANG': 'en_US.UTF-8', 09:53:21 INFO - 'LANGUAGE': 'en_US:en', 09:53:21 INFO - 'LOGNAME': 'cltbld', 09:53:21 INFO - 'MAIL': '/var/mail/cltbld', 09:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:21 INFO - 'MOZ_NO_REMOTE': '1', 09:53:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:21 INFO - 'NO_EM_RESTART': '1', 09:53:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:21 INFO - 'PWD': '/builds/slave/test', 09:53:21 INFO - 'SHELL': '/bin/bash', 09:53:21 INFO - 'SHLVL': '1', 09:53:21 INFO - 'TERM': 'linux', 09:53:21 INFO - 'TMOUT': '86400', 09:53:21 INFO - 'USER': 'cltbld', 09:53:21 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:21 INFO - '_': '/tools/buildbot/bin/python'} 09:53:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:21 INFO - Running setup.py (path:/tmp/pip-OtU1ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:21 INFO - Running setup.py (path:/tmp/pip-1UKgSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:21 INFO - Running setup.py (path:/tmp/pip-ZVxSP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:21 INFO - Running setup.py (path:/tmp/pip-PmEmGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:21 INFO - Running setup.py (path:/tmp/pip-S2FFDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:21 INFO - Running setup.py (path:/tmp/pip-GufkRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:21 INFO - Running setup.py (path:/tmp/pip-4B3gcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:21 INFO - Running setup.py (path:/tmp/pip-uTBdjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:21 INFO - Running setup.py (path:/tmp/pip-oxWh1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:21 INFO - Running setup.py (path:/tmp/pip-vWAcAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:22 INFO - Running setup.py (path:/tmp/pip-k0FuYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53:22 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:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:22 INFO - Running setup.py (path:/tmp/pip-Bnafnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:22 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:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:22 INFO - Running setup.py (path:/tmp/pip-EW_1ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:22 INFO - Running setup.py (path:/tmp/pip-7Td6ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:22 INFO - Running setup.py (path:/tmp/pip-rLHw5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:22 INFO - Running setup.py (path:/tmp/pip-bqpAJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:22 INFO - Running setup.py (path:/tmp/pip-bdaMpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:53:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:53:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:24 INFO - Downloading blessings-1.6.tar.gz 09:53:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:53:24 INFO - Installing collected packages: blessings 09:53:24 INFO - Running setup.py install for blessings 09:53:24 INFO - Successfully installed blessings 09:53:24 INFO - Cleaning up... 09:53:24 INFO - Return code: 0 09:53:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:24 INFO - Reading from file tmpfile_stdout 09:53:24 INFO - Current package versions: 09:53:24 INFO - argparse == 1.2.1 09:53:24 INFO - blessings == 1.6 09:53:24 INFO - blobuploader == 1.2.4 09:53:24 INFO - docopt == 0.6.1 09:53:24 INFO - manifestparser == 1.1 09:53:24 INFO - mozInstall == 1.12 09:53:24 INFO - mozcrash == 0.17 09:53:24 INFO - mozdebug == 0.1 09:53:24 INFO - mozdevice == 0.48 09:53:24 INFO - mozfile == 1.2 09:53:24 INFO - mozhttpd == 0.7 09:53:24 INFO - mozinfo == 0.9 09:53:24 INFO - mozleak == 0.1 09:53:24 INFO - mozlog == 3.2 09:53:24 INFO - moznetwork == 0.27 09:53:24 INFO - mozprocess == 0.22 09:53:24 INFO - mozprofile == 0.28 09:53:24 INFO - mozrunner == 6.11 09:53:24 INFO - mozscreenshot == 0.1 09:53:24 INFO - mozsystemmonitor == 0.0 09:53:24 INFO - moztest == 0.7 09:53:24 INFO - mozversion == 1.4 09:53:24 INFO - psutil == 3.1.1 09:53:24 INFO - requests == 1.2.3 09:53:24 INFO - wsgiref == 0.1.2 09:53:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53: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:53:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53: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:53:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:53:24 INFO - Using env: {'DISPLAY': ':0', 09:53:24 INFO - 'HOME': '/home/cltbld', 09:53:24 INFO - 'LANG': 'en_US.UTF-8', 09:53:24 INFO - 'LANGUAGE': 'en_US:en', 09:53:24 INFO - 'LOGNAME': 'cltbld', 09:53:24 INFO - 'MAIL': '/var/mail/cltbld', 09:53:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:24 INFO - 'MOZ_NO_REMOTE': '1', 09:53:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:24 INFO - 'NO_EM_RESTART': '1', 09:53:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:24 INFO - 'PWD': '/builds/slave/test', 09:53:24 INFO - 'SHELL': '/bin/bash', 09:53:24 INFO - 'SHLVL': '1', 09:53:24 INFO - 'TERM': 'linux', 09:53:24 INFO - 'TMOUT': '86400', 09:53:24 INFO - 'USER': 'cltbld', 09:53:24 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:24 INFO - '_': '/tools/buildbot/bin/python'} 09:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:25 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:53:25 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:53:25 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:53:25 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:53:25 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:53:25 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:53:25 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:53:25 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:53:25 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:53:25 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:53:25 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:53:25 INFO - Cleaning up... 09:53:25 INFO - Return code: 0 09:53:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:25 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 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:25 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:53:25 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:53:25 INFO - Using env: {'DISPLAY': ':0', 09:53:25 INFO - 'HOME': '/home/cltbld', 09:53:25 INFO - 'LANG': 'en_US.UTF-8', 09:53:25 INFO - 'LANGUAGE': 'en_US:en', 09:53:25 INFO - 'LOGNAME': 'cltbld', 09:53:25 INFO - 'MAIL': '/var/mail/cltbld', 09:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:25 INFO - 'MOZ_NO_REMOTE': '1', 09:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:25 INFO - 'NO_EM_RESTART': '1', 09:53:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:25 INFO - 'PWD': '/builds/slave/test', 09:53:25 INFO - 'SHELL': '/bin/bash', 09:53:25 INFO - 'SHLVL': '1', 09:53:25 INFO - 'TERM': 'linux', 09:53:25 INFO - 'TMOUT': '86400', 09:53:25 INFO - 'USER': 'cltbld', 09:53:25 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:25 INFO - '_': '/tools/buildbot/bin/python'} 09:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:25 INFO - Downloading/unpacking jsonschema 09:53: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:53: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:53: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:53: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:53: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:53: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:53:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:53:27 INFO - Downloading/unpacking functools32 (from jsonschema) 09:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:53:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:53:27 INFO - warning: no files found matching '*.txt' 09:53:27 INFO - no previously-included directories found matching 'build' 09:53:27 INFO - no previously-included directories found matching 'dist' 09:53:27 INFO - no previously-included directories found matching '.git*' 09:53:27 INFO - Installing collected packages: jsonschema, functools32 09:53:27 INFO - Running setup.py install for functools32 09:53:27 INFO - warning: no files found matching '*.txt' 09:53:27 INFO - no previously-included directories found matching 'build' 09:53:27 INFO - no previously-included directories found matching 'dist' 09:53:27 INFO - no previously-included directories found matching '.git*' 09:53:27 INFO - Successfully installed jsonschema functools32 09:53:27 INFO - Cleaning up... 09:53:27 INFO - Return code: 0 09:53:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:53: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:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53: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:53:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:53:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9555a0af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, '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': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', '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:53:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:53:27 INFO - Using env: {'DISPLAY': ':0', 09:53:27 INFO - 'HOME': '/home/cltbld', 09:53:27 INFO - 'LANG': 'en_US.UTF-8', 09:53:27 INFO - 'LANGUAGE': 'en_US:en', 09:53:27 INFO - 'LOGNAME': 'cltbld', 09:53:27 INFO - 'MAIL': '/var/mail/cltbld', 09:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:27 INFO - 'MOZ_NO_REMOTE': '1', 09:53:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:27 INFO - 'NO_EM_RESTART': '1', 09:53:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:27 INFO - 'PWD': '/builds/slave/test', 09:53:27 INFO - 'SHELL': '/bin/bash', 09:53:27 INFO - 'SHLVL': '1', 09:53:27 INFO - 'TERM': 'linux', 09:53:27 INFO - 'TMOUT': '86400', 09:53:27 INFO - 'USER': 'cltbld', 09:53:27 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:27 INFO - '_': '/tools/buildbot/bin/python'} 09:53:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:27 INFO - Downloading/unpacking flake8 09:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:53:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:53:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:53: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:53: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:53: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:53: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:53: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:53: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:53:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:53: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:53: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:53: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:53: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:53: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:53: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:53:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:53:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:53:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:53:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:53:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:53:30 INFO - no previously-included directories found matching 'docs/_build' 09:53:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:53:30 INFO - Running setup.py install for pep8 09:53:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:53:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:53:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:53:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:53:30 INFO - no previously-included directories found matching 'docs/_build' 09:53:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:53:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:53:30 INFO - Cleaning up... 09:53:30 INFO - Return code: 0 09:53:30 INFO - Running post-action listener: _resource_record_post_action 09:53:30 INFO - Running post-action listener: _start_resource_monitoring 09:53:30 INFO - Starting resource monitoring. 09:53:30 INFO - ##### 09:53:30 INFO - ##### Finished create-virtualenv step (success) 09:53:30 INFO - ##### 09:53:30 INFO - ##### 09:53:30 INFO - ##### Running install step. 09:53:30 INFO - ##### 09:53:30 INFO - Running pre-action listener: _resource_record_pre_action 09:53:30 INFO - Running main action method: install 09:53:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:30 INFO - Reading from file tmpfile_stdout 09:53:30 INFO - Detecting whether we're running mozinstall >=1.0... 09:53:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:53:31 INFO - Reading from file tmpfile_stdout 09:53:31 INFO - Output received: 09:53:31 INFO - Usage: mozinstall [options] installer 09:53:31 INFO - Options: 09:53:31 INFO - -h, --help show this help message and exit 09:53:31 INFO - -d DEST, --destination=DEST 09:53:31 INFO - Directory to install application into. [default: 09:53:31 INFO - "/builds/slave/test"] 09:53:31 INFO - --app=APP Application being installed. [default: firefox] 09:53:31 INFO - mkdir: /builds/slave/test/build/application 09:53:31 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:53:31 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:53:46 INFO - Reading from file tmpfile_stdout 09:53:46 INFO - Output received: 09:53:46 INFO - /builds/slave/test/build/application/firefox/firefox 09:53:46 INFO - Running post-action listener: _resource_record_post_action 09:53:46 INFO - ##### 09:53:46 INFO - ##### Finished install step (success) 09:53:46 INFO - ##### 09:53:46 INFO - ##### 09:53:46 INFO - ##### Running run-tests step. 09:53:46 INFO - ##### 09:53:46 INFO - Running pre-action listener: _resource_record_pre_action 09:53:46 INFO - Running main action method: run_tests 09:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:53:46 INFO - Python 2.7.3 09:53:46 INFO - Return code: 0 09:53:46 INFO - grabbing minidump binary from tooltool 09:53:46 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:53:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f99c0>, '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 0x10fa8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fad50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:53:46 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:53:46 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:53:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:53:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:53:46 INFO - Return code: 0 09:53:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:53:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:53:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:53:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:53:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:53:47 INFO - Return code: 0 09:53:47 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/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:53:47 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/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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:53:47 INFO - Using env: {'DISPLAY': ':0', 09:53:47 INFO - 'HOME': '/home/cltbld', 09:53:47 INFO - 'LANG': 'en_US.UTF-8', 09:53:47 INFO - 'LANGUAGE': 'en_US:en', 09:53:47 INFO - 'LOGNAME': 'cltbld', 09:53:47 INFO - 'MAIL': '/var/mail/cltbld', 09:53:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:53:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:47 INFO - 'MOZ_NO_REMOTE': '1', 09:53:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:53:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:53:47 INFO - 'NO_EM_RESTART': '1', 09:53:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:47 INFO - 'PWD': '/builds/slave/test', 09:53:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:53:47 INFO - 'SHELL': '/bin/bash', 09:53:47 INFO - 'SHLVL': '1', 09:53:47 INFO - 'TERM': 'linux', 09:53:47 INFO - 'TMOUT': '86400', 09:53:47 INFO - 'USER': 'cltbld', 09:53:47 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827', 09:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:47 INFO - '_': '/tools/buildbot/bin/python'} 09:53:47 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/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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:53:48 INFO - mozversion application_buildid: 20160512093437 09:53:48 INFO - mozversion application_changeset: 6191f8b4e99d5096c0060113ad71d6c4c5b7116b 09:53:48 INFO - mozversion application_display_name: Nightly 09:53:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:53:48 INFO - mozversion application_name: Firefox 09:53:48 INFO - mozversion application_remotingname: firefox 09:53:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:53:48 INFO - mozversion application_vendor: Mozilla 09:53:48 INFO - mozversion application_version: 49.0a1 09:53:48 INFO - mozversion platform_buildid: 20160512093437 09:53:48 INFO - mozversion platform_changeset: 6191f8b4e99d5096c0060113ad71d6c4c5b7116b 09:53:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:53:48 INFO - mozversion platform_version: 49.0a1 09:53:48 INFO - using testdate: 1463072028 09:53:48 INFO - actual date: 1463072028 09:53:48 INFO - starting webserver on 'localhost:33110' 09:53:48 INFO - SUITE-START | Running 2 tests 09:53:48 INFO - TEST-START | dromaeo_css 09:53:48 INFO - operating with platform_type : linux_ 09:53:48 INFO - Initialising browser for dromaeo_css test... 09:53:48 INFO - TEST-INFO | started process 755 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Z0IJE/profile http://localhost:33110/getInfo.html) 09:53:52 INFO - PROCESS | 755 | __metrics Screen width/height:1600/1200 09:53:52 INFO - PROCESS | 755 | colorDepth:24 09:53:52 INFO - PROCESS | 755 | Browser inner width/height: 1024/697 09:53:52 INFO - PROCESS | 755 | __metrics 09:53:53 ERROR - PROCESS | 755 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:53:54 INFO - TEST-INFO | 755: exit 0 09:53:54 INFO - Browser initialized. 09:53:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:53:54 INFO - TEST-INFO | started process 838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Z0IJE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:06 INFO - PROCESS | 838 | RSS: Main: 153382912 09:54:06 INFO - PROCESS | 838 | 09:54:58 INFO - PROCESS | 838 | 1463072098128 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:57:09 INFO - PROCESS | 838 | Cycle 1(1): loaded http://localhost:33110/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33110/tests/dromaeo/cssquery-ext.html) 09:57:10 INFO - PROCESS | 838 | RSS: Main: 229163008 09:57:10 INFO - PROCESS | 838 | 10:00:13 INFO - PROCESS | 838 | Cycle 1(1): loaded http://localhost:33110/tests/dromaeo/cssquery-ext.html (next: http://localhost:33110/tests/dromaeo/cssquery-jquery.html) 10:00:13 INFO - PROCESS | 838 | RSS: Main: 232529920 10:00:13 INFO - PROCESS | 838 | 10:03:16 INFO - PROCESS | 838 | Cycle 1(1): loaded http://localhost:33110/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33110/tests/dromaeo/cssquery-mootools.html) 10:03:16 INFO - PROCESS | 838 | RSS: Main: 232693760 10:03:16 INFO - PROCESS | 838 | 10:06:19 INFO - PROCESS | 838 | Cycle 1(1): loaded http://localhost:33110/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33110/tests/dromaeo/cssquery-prototype.html) 10:06:19 INFO - PROCESS | 838 | RSS: Main: 232169472 10:06:19 INFO - PROCESS | 838 | 10:09:22 INFO - PROCESS | 838 | Cycle 1(1): loaded http://localhost:33110/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33110/tests/dromaeo/cssquery-yui.html) 10:09:23 INFO - PROCESS | 838 | RSS: Main: 213782528 10:09:23 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | Cycle 1(1): loaded http://localhost:33110/tests/dromaeo/cssquery-yui.html (next: http://localhost:33110/tests/dromaeo/cssquery-dojo.html) 10:12:26 INFO - PROCESS | 838 | RSS: Main: 283570176 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | __start_tp_report 10:12:26 INFO - PROCESS | 838 | _x_x_mozilla_page_load 10:12:26 INFO - PROCESS | 838 | _x_x_mozilla_page_load_details 10:12:26 INFO - PROCESS | 838 | |i|pagename|runs| 10:12:26 INFO - PROCESS | 838 | |0;dojo.html;2567.820252582319;2609.1389841352357;2641.2472445353064;2641.6301717759507;2647.1926062550924;4569.497355290915;4626.236670949289;4685.133250348684;4749.406324209478;4792.257200133976;4695.5539223773685;4701.9890723494445;4748.928766068503;4805.159097158001;4822.156122678526;4331.155424692186;4338.761368124752;4372.759498227596;4405.118976204772;4532.796024178903;4438.977805110986;4479.462464504264;4489.775511224442;4491.214037543433;4525.615322697575;4273.38035984782;4319.200947824631;4328.956710432887;4403.185410699031;4412.867613971586;4339.392485052095;4346.565343465628;4353.346997950302;4395.164918665475;4399.560043995607;4350;4380;4380.956190438087;4381.25518661825;4413.051193993275;4478.507364189936;4591.081783643284;4643.930341044887;4658.930116048262;4698.8355407560575;5072.036313100499;5090.516400941905;5110.054639891632;5120.795168193268;5129.384473863161;5109.335786347751;5111.923321150149;5136.74316284188;5141.897162056775;5161.5354618084175;4623.514530974233;4703.929441058387;4747.098051370229;4752.28715692642;4807.134715751128;5173.586113110942;5208.218767184952;5248.396434410015;5261.657992230493;5263.552598029134;4812.879678008007;4996.3504744383;5001.699898006132;5049.899002019939;5053.242013697901;4962.627802914796;4999.375078115236;5020.146575082244;5025.673331233458;5103.362079740033;4988.7505624718615;4996.925046124311;4997.30037794709;5008.398992120969;5052.090623687698;4753.762311884392;4973.403191617029;4976.90046199074;4977.65156439053;5031.647784655112;4203.201391735561;4294.162638285567;4311.6335111515245;4325.589069038437;4329.653627709776;3724.3854763964187;3741.120836603396;3745.4569087482164;3782.432635104701;3787.2614840106467;4252.574742525784;4348.499922508889;4357.825686972549;4363.956360436355;4398.604125628709;5058.3929928408825;5086.440491545927;5090;5109.642325037286;5130.87172820675;4373.475182978063;4375.453068366454;4380.364847097217;4390.319500477431;4411.117776444724;4429.136318417877;4565.406497155349;4610.101030299126;4612.238980568236;4651.534846515321;4774.236122220428;4789.30555069506;4801.159797035574;4803.975980120077;4851.223804191312;5146.614003949644;5147.56245719118;5149.897002060013;5152.76812543429;5194.402643696023;4579.702319349231;4674.392328997209;4723.220668589644;4785.353977213032;4850.587700045536;4987.80048798044;4997.225430058346;4999.150144475375;5007.649464537448;5049.697018178921;5074.188129899346;5077.949220507748;5079.390473143247;5080.822171223989;5082.009008243357;4815.30178124177;4877.048975449753;4910.410750709938;4919.827806026772;4929.926051109164;4912.164931961646;4923.8276660316715;4936.802527898843;4937.333459983;4947.727874966911;4950.6782059166035;4951.554360107608;4982.402111746614;4989.900201996013;4993.8751531210555;4700.200965835747;4940.135476291563;4966.553010229097;5006.4743201963265;5104.617153713412;4872.634552408655;4900.215965445512;4905.828296009622;4909.729964851967;4956.405231372259 10:12:26 INFO - PROCESS | 838 | |1;ext.html;573.9741711622977;597.3966294043016;602.9457348838604;607.2985701514752;611.0894766797473;27685.861570692145;28178.295542611446;30153.698463015364;30233.546496802566;30237.39525209497;42131.368029479505;42480.57519424805;43067.35398969018;43076.13847723044;43101.56898431015;7819.178986206452;7936.166702496242;7942.602869856484;7944.682212711485;7946.364290856746;7680.923190768104;7848.646810893495;7860.606969651523;7919.722809701661;7940.483868548555;10081.94959025204;10354.326968747046;10355.74110647236;10665.520051597658;10667.199960002992;10053.949730251376;10104.696859094201;10182.490875456235;10183.89816101837;10190.643327483576;10012.249081318858;10091.949540252324;10097.798044039151;10099.747506312327;10100.595976160945;37454.81272593619;37496.25007499862;37767.81116094429;37775;37811;2429.137656132083;2587.275562842407;2814.5496720524616;3278.541004259406;3342.5153352763714;27058.45883082347;27706.445871082466;30068.24829379261;30218.84890575533;30256.09231723052;3487.1456493159085;3536.398812201932;3567.4827150063356;3612.8735494257576;3626.2928728897878;179177;180142.09928950403;180416.09791951088;180673;180707.09646451814;53729.46270537245;54039;54380.824767008875;54412;54474.727626361615;56857.715711422;56859;57094.7145264271;57210;57303.55616216536;14889.776653350209;15537.145456999748;15537.456189033555;15551.066935983878;15552.300146493435;11924.701882453008;11960.342181180118;11962.760744785055;11976.461059252355;11977.401129943468;15795.605109872115;17844.732329015074;17979.471744901828;18396.63206735858;18422.539436514195;6441.774537891151;6563.901541476882;6689.6989635466525;6712.53012289135;6786.117804685458;888.1695614600369;926.4256161180111;965.8599503072015;1015.263933648114;1052.1319911073394;27261.591076133875;28035.299117522227;28365.574516381857;28491.71508284932;28856.134315970554;1054.393723608928;1057.582255009267;1103.5309993252836;1178.9705257368462;1185.7569198314325;14856.702865942836;15063.774043389358;15091.439067241734;15452.150131742637;15505.922470387575;364091;365069;365350;365590;368601;48323.27515087277;48794;49005.81462204945;49030.50969490331;49075;1270.7522033203436;1293.5407930184838;1297.3707751740399;1307.2548647271155;1311.1215485624655;26425.603615946162;27631.309217268925;27736;27819.44361112807;27964;41617.79191104025;41787.1642567153;42124.789376052926;42157;42165.78917105395;302754;305170;305823;305931;306010;29747.405051898408;31328.373432530767;37138.628613714594;37768.244635107694;42029.1594168121;40970.1805963885;41915.580844191165;41925.37111943385;41961.37057944072;42011.579884200764;35435.645643543234;35490.467642985896;35780.28439431249;35806.46290305595;35942.460863087595;35368.646313536534;35974.820125899205;36007.279854402244;36075.819620901726;36095;34480.482792757626;35147;35437.645623544464;35538.46692299669;35644.28711425705;30450.390992180477;31661.525077123402;32164.678353216168;32230;32344.191395215308;24575.754242457348;24906.377340565905;24969.75030249674;24994.875025624755;25024.62463063019 10:12:26 INFO - PROCESS | 838 | |2;jquery.html;365.2767520309788;384.7345331721113;385.6856661820616;392.40942381715524;409.5044995555378;12908.354582270884;16084.115373654444;16566.668666626676;16810.495685129434;17562.736558951594;16692.415765448182;17048.48854534363;18171.09144542774;18215.451686606622;18225.81774182258;5259.368875734907;5416.972915135419;5721.5994880358185;5725.942740572583;5793.188953546507;3436.381451338758;5380.596455265852;5657.490825825675;5681.715914204273;5694.202811606378;5642.125470552071;5701.0878259478495;5713.028785106542;5714.114312281582;5734.627249228787;5556.666600003972;5642.153676948449;5700.9429905701245;5723.971380143114;5724.685142317171;3436.244026314207;5178.456262092502;5483.890322193533;5487.506124448819;5491.258680078178;12783.808242876365;12789.808152877715;14125.434982600684;20383;20434.182632694825;1971.5366888781125;1983.5140390604395;2339.9938026648533;2377.1561095811085;2445.76765207305;9301.627934882597;9593.42439453635;9919.65281215154;11115;12871.871281287255;1764.3119183518438;1791.1193247090503;1810.1763697517597;1881.181685966609;1896.895670738109;63573.68213158905;66437;66721;68310.65844670795;69046;8965.955170224108;9350.812983740287;9534.761630959281;9754.75613109678;10874.67375978722;12899.613011609667;13746.594345989412;13885.86114138866;14134.010619256553;14196.219207943455;6044.727987240584;7245.529040612449;7258.709651613876;7267.563946163247;7343.082114735658;6588.967055164693;6597.73609055642;6647;6706.362895524917;6798.660066996729;4443.600075993111;4468.240399132091;4495.483019452739;4499.730016198973;4590.609798167126;1699.4986478988728;1738.5740493579153;1804.2305061730444;1908.5133291010982;1938.3215874444113;839.8488272110957;955.5652178258875;964.1503238709304;1003.5383723487132;1036.9792604147874;16430.260638271306;16654.333826647042;16958.576035598962;17101.144942752813;17350.219240134225;891.2068259249273;891.2468963725642;896.5562046786882;904.2585080234145;920.0753242990885;9411.152996230372;9428.764280893032;9461.52692365379;9606.903930960601;9788.021197880154;312030;316162;316880;422915;432222;83579;88535;88810;88921;89828.5508572466;985.6944347252374;986.6941248213077;987.4322264697828;1017.5166795772097;1022.4427686910651;15788.447404340794;15885.682286353976;16071.598210044842;16174.919125404298;16504.75242871334;24438.755612444358;34234.48648270328;34797;36602.8169859149;37163.44254836126;47927.28109078437;48735;49682;49808.501914980385;50034.49965500298;7516.586587737727;7657.88513172314;7745.264199901;8033.0360356757565;8123.390745693979;8908.821823563621;11315.547378104784;11688;11854.348010859485;12402.5659101931;9969.601215951281;10316.690499285034;10526.157907367546;10621.522031508755;10943.835842462211;10377.16982641402;10458.44986016798;10630.787384252426;10823.404712740874;10981.945090274497;10817.40061252987;10864.511097000495;11008.339499630049;11044.779104418027;11085.445727713743;7795.805104872424;7861.331786797958;7896.763097107096;7921.247481489249;7970.9202907970175;8228.136045715113;10108.898911010796;10739.624113156315;10797.56809727602;11200.495977681187 10:12:26 INFO - PROCESS | 838 | |3;mootools.html;1294.1587967820922;1465.868071873531;1530.5408377486754;1637.197773091185;1780.5637618783392;6265.091561723548;7358.889616655748;7554.811129721753;7556;7764.495307804988;7483.663235154418;7644.923550764491;7659.463837531375;7661.042369703787;7661.502002369842;1871.1860340751816;1921.4619906426224;1929.0354822588706;2187.9234226802064;2218.0462401167492;1944.5235917200234;2217.3569664797187;2289.450531872347;2292.862428254302;2310;2206.9558608827815;2216.4791274050585;2218.73375194976;2221.4779526811185;2223.2774348336775;1753.7983131939864;2133.370655656585;2138.6791981202787;2138.6898899659463;2139.796719311663;2060.6805945078536;2116.1958455786857;2126.6491028980354;2134.413036414983;2136.978630213709;7759.379249660014;7786.2603052710465;7796.298333149987;7808.921910780933;7823.765287041341;4369.9126017479475;4431.889202769957;4437.156940181355;4458.331250312447;4460.018795864905;2669.586214136812;2742;2800.187945495804;2811.5079861024237;2840.3183236023206;894.114826321943;914.1726737302752;922.0687106022898;939.3236869453982;943.429225318686;9657.855132173023;9673.467959262238;9680.322377433513;9684.370515916513;9701.126898579161;3968.66266367362;4104.076582768913;4122.855700050483;4272.16692744918;4274.038341373166;8332;8415.747527574182;8418.621162047722;8424.283935865398;8434.536100514531;4405.295152775605;4431.0251744616135;4432.13573353193;4437.933430998473;4438.911221775611;3552.786832790041;3911.941320880189;3945.349017412095;4031.3348297531165;4042.272390969595;3638.7089032877307;3698.593154752975;3807.6573108420375;3813.332666783357;3816.38937769961;1156.8900954409319;1188.3879801902028;1189.547971770739;1197.8502687164105;1205.1021988618536;876.2858270509514;904.7692838326185;913.9908600914039;925.0887875442684;947.6114792934864;5418.783248670009;5481.232627432163;5588.916166257428;5616.326040875121;5639.210510528529;407.7023772645985;413.6669980665533;420.09469593027075;421.6710965446956;425.80625815253984;3623;3746;3766.3408903441787;3789.810509474515;3928.175083232496;9463.479508626953;9714.465704386326;9731.708048758548;9817.460039697886;9817.558209880572;9656;9703;9731.12419882214;9811.26415518839;9854.950725246328;7314.743983960536;7481.663325150382;7544.094708634889;7545.019147510789;7575.280348366897;5631.577631677558;5727.599068065362;5747.339056008613;5784.681842498703;5808.82573522795;8872.290216782512;8989.775255618662;9013.053629368822;9089.318301127574;9105.362624616213;8947.373683842068;9156.084391560791;9244.907550924405;9247.815043698954;9255.583498742708;6025.8192254232445;6520.771772987922;6561.639109848813;7223.277672232734;7487.3261406473675;7508.399328053633;7737.264959828807;7754.108277548155;7788.9610551949145;7804.9219507806465;6775.830604234737;6976.441884649317;6991.056207411934;6999.020137180901;7143.6428178591905;6653.268140504638;6689.431398331197;6693.765718199839;6778.152730908637;6814.046033555207;6482.773102941374;6495.317991611002;6538.869222615616;6587.505937054836;6602.042703808017;4705.576498115186;4755.643326750438;4827.227643577011;4913.06651736169;4931.3096166536;4770.833020844254;4841.1285968526;4903.215485522299;4926.5073492650445;4944.975275123601 10:12:26 INFO - PROCESS | 838 | |4;prototype.html;287.07132426599946;295.7427038476526;296.6499530553946;318.5174460692051;320.0047851182821;4786.521347865212;4798.3282340472315;4811.783469743866;4850.660453768229;4888;4691.43702755669;4750.862112049583;4782.819425082316;4784.736839473828;4810.9037819243595;3563.697085747708;3667.559892812853;3678.5401824771902;3748.6063963283873;3755.774653520784;3721.981390093046;3725.9627403725885;3737.121776382548;3745.5954017243535;3746.8875933722034;3718.3307004739136;3720.162963333242;3721.64160081571;3732.514773079496;3740.1210715481975;3590.35373632746;3646.398344273205;3722.4230244312175;3767.2653832502674;3768.605615922099;3362.899113026613;3375.4936759486272;3402.42158832999;3404.165979335054;3427.7943323400427;4596;4607.723536587782;4617.884552886171;4623;4647.674662773607;4228.1966426379195;4290.978545107255;4327.177836211141;4341.370501277329;4343.153085148398;4962.751862406902;4966.261808367079;4980.676256043382;5004.324416203803;5014.222795466709;3714.294284086042;3717.735969770264;3718.8326525306425;3728.813559322023;3730.8694195703292;5082.974585127087;5171.7672704728375;5267.55225805807;5287.735613219324;5303.787848486056;3766.7174961877968;3788.8295026723863;3790.6831522417883;3811.561670407883;3823.445600387928;3826.4260360945523;3852.8266228019806;3861.5559210690567;3864.478295430137;3872.786996715208;4286.807093680792;4326.286162783168;4333.220020396296;4336.9132617348105;4339.1972485090355;4887.60899128076;4961.503849615082;4969.751512424365;4989.376327959005;5023.874403139877;1318.6110097521255;1319.6502926724427;1330.361426515278;1334.4862228042273;1336.73265346931;881.2553392383426;881.3830318776882;887.4187407248263;921.8340698674303;951.1819834941945;4358.433403657505;4388.824447022147;4407.360932664746;4413.315936029856;4419.690621656518;5072.695638261716;5088.872778180576;5097.133487307166;5101.719405432663;5111.87220319495;4146.843287783157;4370.453693288339;4379.160752483965;4384.495782984997;4394.0446501306615;2687.400996407174;2693.8114331996962;2708.620793088969;2714.9049783257888;2717.1576811188593;4668.603168730696;4689.273162659725;4697.553732395485;4730.692504987165;4734.313524538922;4848.684835485682;5080.415752188472;5100.209467532539;5132.230165475134;5132.871678208075;4700.435947686299;4711.199096153662;4711.952880471152;4723.385959825201;4725.763711814464;4995.525425084611;5009.7244651544515;5022.14180753109;5031.1071134098775;5032.823851165192;4683.976580117214;4914.901701966012;4934.407871055496;4946.208606622923;4957.7273249971595;4657.953420465752;4664.253719404879;4681.953180468152;4705.882352941204;4706.34111224422;3745.0637340664834;3746.1758413149064;3762.529683789526;3763.6612704856693;3770.962290377171;4801.645137460863;4947.925781113358;5015.648904576645;5016.523430274118;5027.170516864822;4811.254255590389;4852.684575502581;4855.441624213261;4869.147899117711;4871.244957031666;4816.277558366273;4828.734419606955;4876.926846097241;4887.584555312696;4891.682040667345;4645.512221216859;4809.879753006203;4839.112746029122;4840.540148685869;4840.709557426566;4870.805167793249;4905.632077594123;4910.508949105061;4916.532929371704;4923.803047878045;4803.29483033518;4811.542903424309;4815.494373090775;4830.468648448738;4830.637702172421 10:12:26 INFO - PROCESS | 838 | |5;yui.html;665.3978149774456;749.411711806232;765.2041876448494;767.5778321922941;804.6821505505324;12967.935160324198;13317.467301307961;13325.33373331332;13329.266890321029;13335.06654534183;12902.22586644802;12932.676683082927;12939.741205175891;12984.480620775157;13024.674383140426;678.2877978122975;822.440740296598;838.7274135905825;847.6185716427601;847.6905929335779;834.6285902773268;843.9029511606183;844.6030365728097;846.272205902923;846.4117438380329;899.4693131052678;903.8734577159203;906.6871929184454;907.1563445995222;907.2605826251616;714.9428045756327;891.6076926152537;901.8421776189174;901.9774505637379;902.6524787956619;669.8258452802257;675.0953722012526;675.6520391998125;676.7123972311748;676.9559978601375;13205.405756740874;13246.337683115806;13293.069485136044;13293.867061329456;13295.86704132956;12329.321887296195;12391.75216495674;12878.227306361649;12917.483300667962;13086.41111149991;11268.718282043015;11339.262947908359;11359.822040378549;11752;11891.227070240408;4216.852410165629;4304;4374.803133858984;4383.671224658164;4486.416765820422;44206;45346.546534534566;45520;45878;45931;10512.894871051345;10516.894831051592;10637.734056648645;10689.30519516229;10720.946395267974;18169.09154542294;18462.907685461487;18517.129844874067;18665.906670466833;18689.158987845578;6905.620190889449;7216.603086830275;7267.600281984436;7310.926890731024;7330.193678695339;3889.474920885644;3964.484616999772;3986.123052928351;3988.3618621020496;4082.530508991504;2906.694797046322;2993.4911065118545;2999;3001.2346851552716;3003.3542788300183;526.1712802336335;575.1114528054167;602.1690067706537;615.3354377272624;618.2025187508065;492.2517772985043;570.8829689913558;593.3473179502599;594.2749845188863;597.9491743201789;8110.553919534483;8180.386471014698;8229.95885020571;8353.498790072616;8405.621747021398;530.4165418040125;570.5088522966448;589.3840936221659;594.8483136800088;603.9879202415927;3783.1487915219404;3915.314819906505;3950.6049395060263;3957.742746721454;3971.4638523798917;53371;53650.7317463418;53798.19302710462;54294.45705542894;55900.44099559034;45526.54473455223;45746;47183.46124040711;47616.52383476121;47789.5221047792;43253.35119973203;43559.56440435555;43626.127477450274;44086.11827763363;44406.33390499145;7656.6554505048525;7661.15727270011;7915.287624113857;7990.640421181177;8057.516549006844;29594.704052959194;30269.394612108073;30541.847290763402;31376.843115784275;31855.36289274248;37640.62359376371;38097;38324;38543.22913541769;39805.80097099612;11073.83389249178;11166;11247.156463265452;11357.54569817198;11592.304461732323;11605.245659032136;11715.882841171479;11935.52257909674;12272.263664180178;12710.110292279453;8377.58112094405;10222.948885255526;10622.628208012682;10653.414062226651;11105.16711246676;11459.770804584028;11607.709807254549;11842.230255033395;12113.81829272544;12122.696932576757;10729.302595331279;11042.558297668003;11071.114310855273;11228.326300422;11283.097352211968;9462;9464.526773661151;9784.60861565558;9875.506224688594;10014.699559013241;9309.813803724022;9600;9676.419414835133;9878.357906736039;9881.407115573089 10:12:26 INFO - PROCESS | 838 | __end_tp_report 10:12:26 INFO - PROCESS | 838 | __start_cc_report 10:12:26 INFO - PROCESS | 838 | _x_x_mozilla_cycle_collect,1055 10:12:26 INFO - PROCESS | 838 | __end_cc_report 10:12:26 INFO - PROCESS | 838 | __startTimestamp1463073146610__endTimestamp 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | ------- Summary: start ------- 10:12:26 INFO - PROCESS | 838 | Number of tests: 6 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | [#0] dojo.html Cycles:165 Average:4687.00 Median:4805.16 stddev:495.35 (10.3%) stddev-sans-first:468.14 10:12:26 INFO - PROCESS | 838 | Values: 2567.8 2609.1 2641.2 2641.6 2647.2 4569.5 4626.2 4685.1 4749.4 4792.3 4695.6 4702.0 4748.9 4805.2 4822.2 4331.2 4338.8 4372.8 4405.1 4532.8 4439.0 4479.5 4489.8 4491.2 4525.6 4273.4 4319.2 4329.0 4403.2 4412.9 4339.4 4346.6 4353.3 4395.2 4399.6 4350.0 4380.0 4381.0 4381.3 4413.1 4478.5 4591.1 4643.9 4658.9 4698.8 5072.0 5090.5 5110.1 5120.8 5129.4 5109.3 5111.9 5136.7 5141.9 5161.5 4623.5 4703.9 4747.1 4752.3 4807.1 5173.6 5208.2 5248.4 5261.7 5263.6 4812.9 4996.4 5001.7 5049.9 5053.2 4962.6 4999.4 5020.1 5025.7 5103.4 4988.8 4996.9 4997.3 5008.4 5052.1 4753.8 4973.4 4976.9 4977.7 5031.6 4203.2 4294.2 4311.6 4325.6 4329.7 3724.4 3741.1 3745.5 3782.4 3787.3 4252.6 4348.5 4357.8 4364.0 4398.6 5058.4 5086.4 5090.0 5109.6 5130.9 4373.5 4375.5 4380.4 4390.3 4411.1 4429.1 4565.4 4610.1 4612.2 4651.5 4774.2 4789.3 4801.2 4804.0 4851.2 5146.6 5147.6 5149.9 5152.8 5194.4 4579.7 4674.4 4723.2 4785.4 4850.6 4987.8 4997.2 4999.2 5007.6 5049.7 5074.2 5077.9 5079.4 5080.8 5082.0 4815.3 4877.0 4910.4 4919.8 4929.9 4912.2 4923.8 4936.8 4937.3 4947.7 4950.7 4951.6 4982.4 4989.9 4993.9 4700.2 4940.1 4966.6 5006.5 5104.6 4872.6 4900.2 4905.8 4909.7 4956.4 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | [#1] ext.html Cycles:180 Average:44786.51 Median:32287.10 stddev:77296.93 (239.4%) stddev-sans-first:77442.48 10:12:26 INFO - PROCESS | 838 | Values: 574.0 597.4 602.9 607.3 611.1 27685.9 28178.3 30153.7 30233.5 30237.4 42131.4 42480.6 43067.4 43076.1 43101.6 7819.2 7936.2 7942.6 7944.7 7946.4 7680.9 7848.6 7860.6 7919.7 7940.5 10081.9 10354.3 10355.7 10665.5 10667.2 10053.9 10104.7 10182.5 10183.9 10190.6 10012.2 10091.9 10097.8 10099.7 10100.6 37454.8 37496.3 37767.8 37775.0 37811.0 2429.1 2587.3 2814.5 3278.5 3342.5 27058.5 27706.4 30068.2 30218.8 30256.1 3487.1 3536.4 3567.5 3612.9 3626.3 179177.0 180142.1 180416.1 180673.0 180707.1 53729.5 54039.0 54380.8 54412.0 54474.7 56857.7 56859.0 57094.7 57210.0 57303.6 14889.8 15537.1 15537.5 15551.1 15552.3 11924.7 11960.3 11962.8 11976.5 11977.4 15795.6 17844.7 17979.5 18396.6 18422.5 6441.8 6563.9 6689.7 6712.5 6786.1 888.2 926.4 965.9 1015.3 1052.1 27261.6 28035.3 28365.6 28491.7 28856.1 1054.4 1057.6 1103.5 1179.0 1185.8 14856.7 15063.8 15091.4 15452.2 15505.9 364091.0 365069.0 365350.0 365590.0 368601.0 48323.3 48794.0 49005.8 49030.5 49075.0 1270.8 1293.5 1297.4 1307.3 1311.1 26425.6 27631.3 27736.0 27819.4 27964.0 41617.8 41787.2 42124.8 42157.0 42165.8 302754.0 305170.0 305823.0 305931.0 306010.0 29747.4 31328.4 37138.6 37768.2 42029.2 40970.2 41915.6 41925.4 41961.4 42011.6 35435.6 35490.5 35780.3 35806.5 35942.5 35368.6 35974.8 36007.3 36075.8 36095.0 34480.5 35147.0 35437.6 35538.5 35644.3 30450.4 31661.5 32164.7 32230.0 32344.2 24575.8 24906.4 24969.8 24994.9 25024.6 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | [#2] jquery.html Cycles:180 Average:23541.55 Median:218332.80 stddev:60573.34 (27.7%) stddev-sans-first:60718.27 10:12:26 INFO - PROCESS | 838 | Values: 365.3 384.7 385.7 392.4 409.5 12908.4 16084.1 16566.7 16810.5 17562.7 16692.4 17048.5 18171.1 18215.5 18225.8 5259.4 5417.0 5721.6 5725.9 5793.2 3436.4 5380.6 5657.5 5681.7 5694.2 5642.1 5701.1 5713.0 5714.1 5734.6 5556.7 5642.2 5700.9 5724.0 5724.7 3436.2 5178.5 5483.9 5487.5 5491.3 12783.8 12789.8 14125.4 20383.0 20434.2 1971.5 1983.5 2340.0 2377.2 2445.8 9301.6 9593.4 9919.7 11115.0 12871.9 1764.3 1791.1 1810.2 1881.2 1896.9 63573.7 66437.0 66721.0 68310.7 69046.0 8966.0 9350.8 9534.8 9754.8 10874.7 12899.6 13746.6 13885.9 14134.0 14196.2 6044.7 7245.5 7258.7 7267.6 7343.1 6589.0 6597.7 6647.0 6706.4 6798.7 4443.6 4468.2 4495.5 4499.7 4590.6 1699.5 1738.6 1804.2 1908.5 1938.3 839.8 955.6 964.2 1003.5 1037.0 16430.3 16654.3 16958.6 17101.1 17350.2 891.2 891.2 896.6 904.3 920.1 9411.2 9428.8 9461.5 9606.9 9788.0 312030.0 316162.0 316880.0 422915.0 432222.0 83579.0 88535.0 88810.0 88921.0 89828.6 985.7 986.7 987.4 1017.5 1022.4 15788.4 15885.7 16071.6 16174.9 16504.8 24438.8 34234.5 34797.0 36602.8 37163.4 47927.3 48735.0 49682.0 49808.5 50034.5 7516.6 7657.9 7745.3 8033.0 8123.4 8908.8 11315.5 11688.0 11854.3 12402.6 9969.6 10316.7 10526.2 10621.5 10943.8 10377.2 10458.4 10630.8 10823.4 10981.9 10817.4 10864.5 11008.3 11044.8 11085.4 7795.8 7861.3 7896.8 7921.2 7970.9 8228.1 10108.9 10739.6 10797.6 11200.5 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | [#3] mootools.html Cycles:180 Average:5124.29 Median:5683.40 stddev:2854.11 (50.2%) stddev-sans-first:2847.60 10:12:26 INFO - PROCESS | 838 | Values: 1294.2 1465.9 1530.5 1637.2 1780.6 6265.1 7358.9 7554.8 7556.0 7764.5 7483.7 7644.9 7659.5 7661.0 7661.5 1871.2 1921.5 1929.0 2187.9 2218.0 1944.5 2217.4 2289.5 2292.9 2310.0 2207.0 2216.5 2218.7 2221.5 2223.3 1753.8 2133.4 2138.7 2138.7 2139.8 2060.7 2116.2 2126.6 2134.4 2137.0 7759.4 7786.3 7796.3 7808.9 7823.8 4369.9 4431.9 4437.2 4458.3 4460.0 2669.6 2742.0 2800.2 2811.5 2840.3 894.1 914.2 922.1 939.3 943.4 9657.9 9673.5 9680.3 9684.4 9701.1 3968.7 4104.1 4122.9 4272.2 4274.0 8332.0 8415.7 8418.6 8424.3 8434.5 4405.3 4431.0 4432.1 4437.9 4438.9 3552.8 3911.9 3945.3 4031.3 4042.3 3638.7 3698.6 3807.7 3813.3 3816.4 1156.9 1188.4 1189.5 1197.9 1205.1 876.3 904.8 914.0 925.1 947.6 5418.8 5481.2 5588.9 5616.3 5639.2 407.7 413.7 420.1 421.7 425.8 3623.0 3746.0 3766.3 3789.8 3928.2 9463.5 9714.5 9731.7 9817.5 9817.6 9656.0 9703.0 9731.1 9811.3 9855.0 7314.7 7481.7 7544.1 7545.0 7575.3 5631.6 5727.6 5747.3 5784.7 5808.8 8872.3 8989.8 9013.1 9089.3 9105.4 8947.4 9156.1 9244.9 9247.8 9255.6 6025.8 6520.8 6561.6 7223.3 7487.3 7508.4 7737.3 7754.1 7789.0 7804.9 6775.8 6976.4 6991.1 6999.0 7143.6 6653.3 6689.4 6693.8 6778.2 6814.0 6482.8 6495.3 6538.9 6587.5 6602.0 4705.6 4755.6 4827.2 4913.1 4931.3 4770.8 4841.1 4903.2 4926.5 4945.0 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | [#4] prototype.html Cycles:180 Average:4130.89 Median:4686.62 stddev:1158.79 (24.7%) stddev-sans-first:1125.55 10:12:26 INFO - PROCESS | 838 | Values: 287.1 295.7 296.6 318.5 320.0 4786.5 4798.3 4811.8 4850.7 4888.0 4691.4 4750.9 4782.8 4784.7 4810.9 3563.7 3667.6 3678.5 3748.6 3755.8 3722.0 3726.0 3737.1 3745.6 3746.9 3718.3 3720.2 3721.6 3732.5 3740.1 3590.4 3646.4 3722.4 3767.3 3768.6 3362.9 3375.5 3402.4 3404.2 3427.8 4596.0 4607.7 4617.9 4623.0 4647.7 4228.2 4291.0 4327.2 4341.4 4343.2 4962.8 4966.3 4980.7 5004.3 5014.2 3714.3 3717.7 3718.8 3728.8 3730.9 5083.0 5171.8 5267.6 5287.7 5303.8 3766.7 3788.8 3790.7 3811.6 3823.4 3826.4 3852.8 3861.6 3864.5 3872.8 4286.8 4326.3 4333.2 4336.9 4339.2 4887.6 4961.5 4969.8 4989.4 5023.9 1318.6 1319.7 1330.4 1334.5 1336.7 881.3 881.4 887.4 921.8 951.2 4358.4 4388.8 4407.4 4413.3 4419.7 5072.7 5088.9 5097.1 5101.7 5111.9 4146.8 4370.5 4379.2 4384.5 4394.0 2687.4 2693.8 2708.6 2714.9 2717.2 4668.6 4689.3 4697.6 4730.7 4734.3 4848.7 5080.4 5100.2 5132.2 5132.9 4700.4 4711.2 4712.0 4723.4 4725.8 4995.5 5009.7 5022.1 5031.1 5032.8 4684.0 4914.9 4934.4 4946.2 4957.7 4658.0 4664.3 4682.0 4705.9 4706.3 3745.1 3746.2 3762.5 3763.7 3771.0 4801.6 4947.9 5015.6 5016.5 5027.2 4811.3 4852.7 4855.4 4869.1 4871.2 4816.3 4828.7 4876.9 4887.6 4891.7 4645.5 4809.9 4839.1 4840.5 4840.7 4870.8 4905.6 4910.5 4916.5 4923.8 4803.3 4811.5 4815.5 4830.5 4830.6 10:12:26 INFO - PROCESS | 838 | 10:12:26 INFO - PROCESS | 838 | [#5] yui.html Cycles:180 Average:13142.64 Median:4379.24 stddev:14623.45 (333.9%) stddev-sans-first:14634.45 10:12:26 INFO - PROCESS | 838 | Values: 665.4 749.4 765.2 767.6 804.7 12967.9 13317.5 13325.3 13329.3 13335.1 12902.2 12932.7 12939.7 12984.5 13024.7 678.3 822.4 838.7 847.6 847.7 834.6 843.9 844.6 846.3 846.4 899.5 903.9 906.7 907.2 907.3 714.9 891.6 901.8 902.0 902.7 669.8 675.1 675.7 676.7 677.0 13205.4 13246.3 13293.1 13293.9 13295.9 12329.3 12391.8 12878.2 12917.5 13086.4 11268.7 11339.3 11359.8 11752.0 11891.2 4216.9 4304.0 4374.8 4383.7 4486.4 44206.0 45346.5 45520.0 45878.0 45931.0 10512.9 10516.9 10637.7 10689.3 10720.9 18169.1 18462.9 18517.1 18665.9 18689.2 6905.6 7216.6 7267.6 7310.9 7330.2 3889.5 3964.5 3986.1 3988.4 4082.5 2906.7 2993.5 2999.0 3001.2 3003.4 526.2 575.1 602.2 615.3 618.2 492.3 570.9 593.3 594.3 597.9 8110.6 8180.4 8230.0 8353.5 8405.6 530.4 570.5 589.4 594.8 604.0 3783.1 3915.3 3950.6 3957.7 3971.5 53371.0 53650.7 53798.2 54294.5 55900.4 45526.5 45746.0 47183.5 47616.5 47789.5 43253.4 43559.6 43626.1 44086.1 44406.3 7656.7 7661.2 7915.3 7990.6 8057.5 29594.7 30269.4 30541.8 31376.8 31855.4 37640.6 38097.0 38324.0 38543.2 39805.8 11073.8 11166.0 11247.2 11357.5 11592.3 11605.2 11715.9 11935.5 12272.3 12710.1 8377.6 10222.9 10622.6 10653.4 11105.2 11459.8 11607.7 11842.2 12113.8 12122.7 10729.3 11042.6 11071.1 11228.3 11283.1 9462.0 9464.5 9784.6 9875.5 10014.7 9309.8 9600.0 9676.4 9878.4 9881.4 10:12:26 INFO - PROCESS | 838 | -------- Summary: end -------- 10:12:26 INFO - PROCESS | 838 | 10:12:27 INFO - TEST-INFO | 838: exit 0 10:12:27 INFO - TEST-OK | dromaeo_css | took 1119221ms 10:12:27 INFO - TEST-START | kraken 10:12:27 INFO - operating with platform_type : linux_ 10:12:27 INFO - Initialising browser for kraken test... 10:12:27 INFO - TEST-INFO | started process 1022 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuY9mhS/profile http://localhost:33110/getInfo.html) 10:12:31 INFO - PROCESS | 1022 | __metrics Screen width/height:1600/1200 10:12:31 INFO - PROCESS | 1022 | colorDepth:24 10:12:31 INFO - PROCESS | 1022 | Browser inner width/height: 1024/697 10:12:31 INFO - PROCESS | 1022 | __metrics 10:12:32 ERROR - PROCESS | 1022 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:12:33 INFO - TEST-INFO | 1022: exit 0 10:12:33 INFO - Browser initialized. 10:12:33 INFO - Running cycle 1/1 for kraken test... 10:12:33 INFO - TEST-INFO | started process 1113 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuY9mhS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:45 INFO - PROCESS | 1113 | RSS: Main: 154456064 10:12:45 INFO - PROCESS | 1113 | 10:12:47 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33110/tests/kraken/driver.html?testName=audio-beat-detection) 10:12:48 INFO - PROCESS | 1113 | RSS: Main: 209788928 10:12:48 INFO - PROCESS | 1113 | 10:12:51 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33110/tests/kraken/driver.html?testName=audio-dft) 10:12:52 INFO - PROCESS | 1113 | RSS: Main: 197353472 10:12:52 INFO - PROCESS | 1113 | 10:12:55 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33110/tests/kraken/driver.html?testName=audio-fft) 10:12:56 INFO - PROCESS | 1113 | RSS: Main: 267124736 10:12:56 INFO - PROCESS | 1113 | 10:12:58 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33110/tests/kraken/driver.html?testName=audio-oscillator) 10:12:59 INFO - PROCESS | 1113 | RSS: Main: 176738304 10:12:59 INFO - PROCESS | 1113 | 10:13:01 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33110/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:13:02 INFO - PROCESS | 1113 | RSS: Main: 181092352 10:13:02 INFO - PROCESS | 1113 | 10:13:05 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33110/tests/kraken/driver.html?testName=imaging-darkroom) 10:13:06 INFO - PROCESS | 1113 | RSS: Main: 216657920 10:13:06 INFO - PROCESS | 1113 | 10:13:09 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33110/tests/kraken/driver.html?testName=imaging-desaturate) 10:13:10 INFO - PROCESS | 1113 | RSS: Main: 214888448 10:13:10 INFO - PROCESS | 1113 | 10:13:13 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33110/tests/kraken/driver.html?testName=json-parse-financial) 10:13:14 INFO - PROCESS | 1113 | RSS: Main: 215216128 10:13:14 INFO - PROCESS | 1113 | 10:13:15 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33110/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:13:16 INFO - PROCESS | 1113 | RSS: Main: 204713984 10:13:16 INFO - PROCESS | 1113 | 10:13:18 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:13:19 INFO - PROCESS | 1113 | RSS: Main: 207712256 10:13:19 INFO - PROCESS | 1113 | 10:13:21 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:13:22 INFO - PROCESS | 1113 | RSS: Main: 219017216 10:13:22 INFO - PROCESS | 1113 | 10:13:24 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:13:25 INFO - PROCESS | 1113 | RSS: Main: 224886784 10:13:25 INFO - PROCESS | 1113 | 10:13:28 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:13:29 INFO - PROCESS | 1113 | RSS: Main: 219529216 10:13:29 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | Cycle 1(1): loaded http://localhost:33110/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33110/tests/kraken/driver.html?testName=ai-astar) 10:13:31 INFO - PROCESS | 1113 | RSS: Main: 265678848 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | __start_tp_report 10:13:31 INFO - PROCESS | 1113 | _x_x_mozilla_page_load 10:13:31 INFO - PROCESS | 1113 | _x_x_mozilla_page_load_details 10:13:31 INFO - PROCESS | 1113 | |i|pagename|runs| 10:13:31 INFO - PROCESS | 1113 | |0;ai-astar;115;110;114;115;116;115;115;117;114;115 10:13:31 INFO - PROCESS | 1113 | |1;audio-beat-detection;168;138;162;211;138;151;165;135;188;136 10:13:31 INFO - PROCESS | 1113 | |2;audio-dft;163;161;160;163;161;162;160;163;161;160 10:13:31 INFO - PROCESS | 1113 | |3;audio-fft;76;76;77;140;76;76;76;75;127;76 10:13:31 INFO - PROCESS | 1113 | |4;audio-oscillator;94;94;149;93;94;93;126;93;94;123 10:13:31 INFO - PROCESS | 1113 | |5;imaging-gaussian-blur;116;117;117;117;117;117;116;117;117;117 10:13:31 INFO - PROCESS | 1113 | |6;imaging-darkroom;146;147;147;146;145;146;147;147;146;146 10:13:31 INFO - PROCESS | 1113 | |7;imaging-desaturate;94;95;95;94;95;95;95;94;94;94 10:13:31 INFO - PROCESS | 1113 | |8;json-parse-financial;66;68;67;66;67;68;67;119;65;66 10:13:31 INFO - PROCESS | 1113 | |9;json-stringify-tinderbox;63;64;64;64;65;64;65;64;65;64 10:13:31 INFO - PROCESS | 1113 | |10;stanford-crypto-aes;82;89;86;83;83;84;84;85;89;84 10:13:31 INFO - PROCESS | 1113 | |11;stanford-crypto-ccm;130;131;143;134;130;134;128;127;194;135 10:13:31 INFO - PROCESS | 1113 | |12;stanford-crypto-pbkdf2;182;174;179;174;173;176;188;175;173;174 10:13:31 INFO - PROCESS | 1113 | |13;stanford-crypto-sha256-iterative;69;68;68;67;67;68;68;67;68;66 10:13:31 INFO - PROCESS | 1113 | __end_tp_report 10:13:31 INFO - PROCESS | 1113 | __start_cc_report 10:13:31 INFO - PROCESS | 1113 | _x_x_mozilla_cycle_collect,2126 10:13:31 INFO - PROCESS | 1113 | __end_cc_report 10:13:31 INFO - PROCESS | 1113 | __startTimestamp1463073211254__endTimestamp 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | ------- Summary: start ------- 10:13:31 INFO - PROCESS | 1113 | Number of tests: 14 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#0] ai-astar Cycles:10 Average:114.60 Median:115.00 stddev:1.84 (1.6%) stddev-sans-first:1.94 10:13:31 INFO - PROCESS | 1113 | Values: 115.0 110.0 114.0 115.0 116.0 115.0 115.0 117.0 114.0 115.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#1] audio-beat-detection Cycles:10 Average:159.20 Median:163.50 stddev:25.21 (15.4%) stddev-sans-first:26.54 10:13:31 INFO - PROCESS | 1113 | Values: 168.0 138.0 162.0 211.0 138.0 151.0 165.0 135.0 188.0 136.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.26 (0.8%) stddev-sans-first:1.20 10:13:31 INFO - PROCESS | 1113 | Values: 163.0 161.0 160.0 163.0 161.0 162.0 160.0 163.0 161.0 160.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#3] audio-fft Cycles:10 Average:87.50 Median:76.00 stddev:24.44 (32.2%) stddev-sans-first:25.57 10:13:31 INFO - PROCESS | 1113 | Values: 76.0 76.0 77.0 140.0 76.0 76.0 76.0 75.0 127.0 76.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#4] audio-oscillator Cycles:10 Average:105.30 Median:93.50 stddev:20.04 (21.4%) stddev-sans-first:20.84 10:13:31 INFO - PROCESS | 1113 | Values: 94.0 94.0 149.0 93.0 94.0 93.0 126.0 93.0 94.0 123.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 10:13:31 INFO - PROCESS | 1113 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 10:13:31 INFO - PROCESS | 1113 | Values: 146.0 147.0 147.0 146.0 145.0 146.0 147.0 147.0 146.0 146.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 10:13:31 INFO - PROCESS | 1113 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 94.0 94.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#8] json-parse-financial Cycles:10 Average:71.90 Median:67.00 stddev:16.58 (24.7%) stddev-sans-first:17.44 10:13:31 INFO - PROCESS | 1113 | Values: 66.0 68.0 67.0 66.0 67.0 68.0 67.0 119.0 65.0 66.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 10:13:31 INFO - PROCESS | 1113 | Values: 63.0 64.0 64.0 64.0 65.0 64.0 65.0 64.0 65.0 64.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:84.50 stddev:2.42 (2.9%) stddev-sans-first:2.33 10:13:31 INFO - PROCESS | 1113 | Values: 82.0 89.0 86.0 83.0 83.0 84.0 84.0 85.0 89.0 84.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#11] stanford-crypto-ccm Cycles:10 Average:138.60 Median:134.00 stddev:19.99 (14.9%) stddev-sans-first:20.96 10:13:31 INFO - PROCESS | 1113 | Values: 130.0 131.0 143.0 134.0 130.0 134.0 128.0 127.0 194.0 135.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:175.50 stddev:4.87 (2.8%) stddev-sans-first:4.79 10:13:31 INFO - PROCESS | 1113 | Values: 182.0 174.0 179.0 174.0 173.0 176.0 188.0 175.0 173.0 174.0 10:13:31 INFO - PROCESS | 1113 | 10:13:31 INFO - PROCESS | 1113 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.84 (1.2%) stddev-sans-first:0.73 10:13:31 INFO - PROCESS | 1113 | Values: 69.0 68.0 68.0 67.0 67.0 68.0 68.0 67.0 68.0 66.0 10:13:31 INFO - PROCESS | 1113 | -------- Summary: end -------- 10:13:31 INFO - PROCESS | 1113 | 10:13:32 INFO - TEST-INFO | 1113: exit 0 10:13:32 INFO - TEST-OK | kraken | took 65051ms 10:13:32 INFO - SUITE-END | took 1184s 10:13:32 INFO - Completed test suite (00:19:44) 10:13:32 INFO - javascript benchmark 10:13:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.820252582319, 2609.1389841352357, 2641.2472445353064, 2641.6301717759507, 2647.1926062550924, 4569.497355290915, 4626.236670949289, 4685.133250348684, 4749.406324209478, 4792.257200133976, 4695.5539223773685, 4701.9890723494445, 4748.928766068503, 4805.159097158001, 4822.156122678526, 4331.155424692186, 4338.761368124752, 4372.759498227596, 4405.118976204772, 4532.796024178903, 4438.977805110986, 4479.462464504264, 4489.775511224442, 4491.214037543433, 4525.615322697575, 4273.38035984782, 4319.200947824631, 4328.956710432887, 4403.185410699031, 4412.867613971586, 4339.392485052095, 4346.565343465628, 4353.346997950302, 4395.164918665475, 4399.560043995607, 4350.0, 4380.0, 4380.956190438087, 4381.25518661825, 4413.051193993275, 4478.507364189936, 4591.081783643284, 4643.930341044887, 4658.930116048262, 4698.8355407560575, 5072.036313100499, 5090.516400941905, 5110.054639891632, 5120.795168193268, 5129.384473863161, 5109.335786347751, 5111.923321150149, 5136.74316284188, 5141.897162056775, 5161.5354618084175, 4623.514530974233, 4703.929441058387, 4747.098051370229, 4752.28715692642, 4807.134715751128, 5173.586113110942, 5208.218767184952, 5248.396434410015, 5261.657992230493, 5263.552598029134, 4812.879678008007, 4996.3504744383, 5001.699898006132, 5049.899002019939, 5053.242013697901, 4962.627802914796, 4999.375078115236, 5020.146575082244, 5025.673331233458, 5103.362079740033, 4988.7505624718615, 4996.925046124311, 4997.30037794709, 5008.398992120969, 5052.090623687698, 4753.762311884392, 4973.403191617029, 4976.90046199074, 4977.65156439053, 5031.647784655112, 4203.201391735561, 4294.162638285567, 4311.6335111515245, 4325.589069038437, 4329.653627709776, 3724.3854763964187, 3741.120836603396, 3745.4569087482164, 3782.432635104701, 3787.2614840106467, 4252.574742525784, 4348.499922508889, 4357.825686972549, 4363.956360436355, 4398.604125628709, 5058.3929928408825, 5086.440491545927, 5090.0, 5109.642325037286, 5130.87172820675, 4373.475182978063, 4375.453068366454, 4380.364847097217, 4390.319500477431, 4411.117776444724, 4429.136318417877, 4565.406497155349, 4610.101030299126, 4612.238980568236, 4651.534846515321, 4774.236122220428, 4789.30555069506, 4801.159797035574, 4803.975980120077, 4851.223804191312, 5146.614003949644, 5147.56245719118, 5149.897002060013, 5152.76812543429, 5194.402643696023, 4579.702319349231, 4674.392328997209, 4723.220668589644, 4785.353977213032, 4850.587700045536, 4987.80048798044, 4997.225430058346, 4999.150144475375, 5007.649464537448, 5049.697018178921, 5074.188129899346, 5077.949220507748, 5079.390473143247, 5080.822171223989, 5082.009008243357, 4815.30178124177, 4877.048975449753, 4910.410750709938, 4919.827806026772, 4929.926051109164, 4912.164931961646, 4923.8276660316715, 4936.802527898843, 4937.333459983, 4947.727874966911, 4950.6782059166035, 4951.554360107608, 4982.402111746614, 4989.900201996013, 4993.8751531210555, 4700.200965835747, 4940.135476291563, 4966.553010229097, 5006.4743201963265, 5104.617153713412, 4872.634552408655, 4900.215965445512, 4905.828296009622, 4909.729964851967, 4956.405231372259], "value": 4655.035486207491, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.9741711622977, 597.3966294043016, 602.9457348838604, 607.2985701514752, 611.0894766797473, 27685.861570692145, 28178.295542611446, 30153.698463015364, 30233.546496802566, 30237.39525209497, 42131.368029479505, 42480.57519424805, 43067.35398969018, 43076.13847723044, 43101.56898431015, 7819.178986206452, 7936.166702496242, 7942.602869856484, 7944.682212711485, 7946.364290856746, 7680.923190768104, 7848.646810893495, 7860.606969651523, 7919.722809701661, 7940.483868548555, 10081.94959025204, 10354.326968747046, 10355.74110647236, 10665.520051597658, 10667.199960002992, 10053.949730251376, 10104.696859094201, 10182.490875456235, 10183.89816101837, 10190.643327483576, 10012.249081318858, 10091.949540252324, 10097.798044039151, 10099.747506312327, 10100.595976160945, 37454.81272593619, 37496.25007499862, 37767.81116094429, 37775.0, 37811.0, 2429.137656132083, 2587.275562842407, 2814.5496720524616, 3278.541004259406, 3342.5153352763714, 27058.45883082347, 27706.445871082466, 30068.24829379261, 30218.84890575533, 30256.09231723052, 3487.1456493159085, 3536.398812201932, 3567.4827150063356, 3612.8735494257576, 3626.2928728897878, 179177.0, 180142.09928950403, 180416.09791951088, 180673.0, 180707.09646451814, 53729.46270537245, 54039.0, 54380.824767008875, 54412.0, 54474.727626361615, 56857.715711422, 56859.0, 57094.7145264271, 57210.0, 57303.55616216536, 14889.776653350209, 15537.145456999748, 15537.456189033555, 15551.066935983878, 15552.300146493435, 11924.701882453008, 11960.342181180118, 11962.760744785055, 11976.461059252355, 11977.401129943468, 15795.605109872115, 17844.732329015074, 17979.471744901828, 18396.63206735858, 18422.539436514195, 6441.774537891151, 6563.901541476882, 6689.6989635466525, 6712.53012289135, 6786.117804685458, 888.1695614600369, 926.4256161180111, 965.8599503072015, 1015.263933648114, 1052.1319911073394, 27261.591076133875, 28035.299117522227, 28365.574516381857, 28491.71508284932, 28856.134315970554, 1054.393723608928, 1057.582255009267, 1103.5309993252836, 1178.9705257368462, 1185.7569198314325, 14856.702865942836, 15063.774043389358, 15091.439067241734, 15452.150131742637, 15505.922470387575, 364091.0, 365069.0, 365350.0, 365590.0, 368601.0, 48323.27515087277, 48794.0, 49005.81462204945, 49030.50969490331, 49075.0, 1270.7522033203436, 1293.5407930184838, 1297.3707751740399, 1307.2548647271155, 1311.1215485624655, 26425.603615946162, 27631.309217268925, 27736.0, 27819.44361112807, 27964.0, 41617.79191104025, 41787.1642567153, 42124.789376052926, 42157.0, 42165.78917105395, 302754.0, 305170.0, 305823.0, 305931.0, 306010.0, 29747.405051898408, 31328.373432530767, 37138.628613714594, 37768.244635107694, 42029.1594168121, 40970.1805963885, 41915.580844191165, 41925.37111943385, 41961.37057944072, 42011.579884200764, 35435.645643543234, 35490.467642985896, 35780.28439431249, 35806.46290305595, 35942.460863087595, 35368.646313536534, 35974.820125899205, 36007.279854402244, 36075.819620901726, 36095.0, 34480.482792757626, 35147.0, 35437.645623544464, 35538.46692299669, 35644.28711425705, 30450.390992180477, 31661.525077123402, 32164.678353216168, 32230.0, 32344.191395215308, 24575.754242457348, 24906.377340565905, 24969.75030249674, 24994.875025624755, 25024.62463063019], "value": 17937.283850932294, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.2767520309788, 384.7345331721113, 385.6856661820616, 392.40942381715524, 409.5044995555378, 12908.354582270884, 16084.115373654444, 16566.668666626676, 16810.495685129434, 17562.736558951594, 16692.415765448182, 17048.48854534363, 18171.09144542774, 18215.451686606622, 18225.81774182258, 5259.368875734907, 5416.972915135419, 5721.5994880358185, 5725.942740572583, 5793.188953546507, 3436.381451338758, 5380.596455265852, 5657.490825825675, 5681.715914204273, 5694.202811606378, 5642.125470552071, 5701.0878259478495, 5713.028785106542, 5714.114312281582, 5734.627249228787, 5556.666600003972, 5642.153676948449, 5700.9429905701245, 5723.971380143114, 5724.685142317171, 3436.244026314207, 5178.456262092502, 5483.890322193533, 5487.506124448819, 5491.258680078178, 12783.808242876365, 12789.808152877715, 14125.434982600684, 20383.0, 20434.182632694825, 1971.5366888781125, 1983.5140390604395, 2339.9938026648533, 2377.1561095811085, 2445.76765207305, 9301.627934882597, 9593.42439453635, 9919.65281215154, 11115.0, 12871.871281287255, 1764.3119183518438, 1791.1193247090503, 1810.1763697517597, 1881.181685966609, 1896.895670738109, 63573.68213158905, 66437.0, 66721.0, 68310.65844670795, 69046.0, 8965.955170224108, 9350.812983740287, 9534.761630959281, 9754.75613109678, 10874.67375978722, 12899.613011609667, 13746.594345989412, 13885.86114138866, 14134.010619256553, 14196.219207943455, 6044.727987240584, 7245.529040612449, 7258.709651613876, 7267.563946163247, 7343.082114735658, 6588.967055164693, 6597.73609055642, 6647.0, 6706.362895524917, 6798.660066996729, 4443.600075993111, 4468.240399132091, 4495.483019452739, 4499.730016198973, 4590.609798167126, 1699.4986478988728, 1738.5740493579153, 1804.2305061730444, 1908.5133291010982, 1938.3215874444113, 839.8488272110957, 955.5652178258875, 964.1503238709304, 1003.5383723487132, 1036.9792604147874, 16430.260638271306, 16654.333826647042, 16958.576035598962, 17101.144942752813, 17350.219240134225, 891.2068259249273, 891.2468963725642, 896.5562046786882, 904.2585080234145, 920.0753242990885, 9411.152996230372, 9428.764280893032, 9461.52692365379, 9606.903930960601, 9788.021197880154, 312030.0, 316162.0, 316880.0, 422915.0, 432222.0, 83579.0, 88535.0, 88810.0, 88921.0, 89828.5508572466, 985.6944347252374, 986.6941248213077, 987.4322264697828, 1017.5166795772097, 1022.4427686910651, 15788.447404340794, 15885.682286353976, 16071.598210044842, 16174.919125404298, 16504.75242871334, 24438.755612444358, 34234.48648270328, 34797.0, 36602.8169859149, 37163.44254836126, 47927.28109078437, 48735.0, 49682.0, 49808.501914980385, 50034.49965500298, 7516.586587737727, 7657.88513172314, 7745.264199901, 8033.0360356757565, 8123.390745693979, 8908.821823563621, 11315.547378104784, 11688.0, 11854.348010859485, 12402.5659101931, 9969.601215951281, 10316.690499285034, 10526.157907367546, 10621.522031508755, 10943.835842462211, 10377.16982641402, 10458.44986016798, 10630.787384252426, 10823.404712740874, 10981.945090274497, 10817.40061252987, 10864.511097000495, 11008.339499630049, 11044.779104418027, 11085.445727713743, 7795.805104872424, 7861.331786797958, 7896.763097107096, 7921.247481489249, 7970.9202907970175, 8228.136045715113, 10108.898911010796, 10739.624113156315, 10797.56809727602, 11200.495977681187], "value": 8302.408022781949, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.1587967820922, 1465.868071873531, 1530.5408377486754, 1637.197773091185, 1780.5637618783392, 6265.091561723548, 7358.889616655748, 7554.811129721753, 7556.0, 7764.495307804988, 7483.663235154418, 7644.923550764491, 7659.463837531375, 7661.042369703787, 7661.502002369842, 1871.1860340751816, 1921.4619906426224, 1929.0354822588706, 2187.9234226802064, 2218.0462401167492, 1944.5235917200234, 2217.3569664797187, 2289.450531872347, 2292.862428254302, 2310.0, 2206.9558608827815, 2216.4791274050585, 2218.73375194976, 2221.4779526811185, 2223.2774348336775, 1753.7983131939864, 2133.370655656585, 2138.6791981202787, 2138.6898899659463, 2139.796719311663, 2060.6805945078536, 2116.1958455786857, 2126.6491028980354, 2134.413036414983, 2136.978630213709, 7759.379249660014, 7786.2603052710465, 7796.298333149987, 7808.921910780933, 7823.765287041341, 4369.9126017479475, 4431.889202769957, 4437.156940181355, 4458.331250312447, 4460.018795864905, 2669.586214136812, 2742.0, 2800.187945495804, 2811.5079861024237, 2840.3183236023206, 894.114826321943, 914.1726737302752, 922.0687106022898, 939.3236869453982, 943.429225318686, 9657.855132173023, 9673.467959262238, 9680.322377433513, 9684.370515916513, 9701.126898579161, 3968.66266367362, 4104.076582768913, 4122.855700050483, 4272.16692744918, 4274.038341373166, 8332.0, 8415.747527574182, 8418.621162047722, 8424.283935865398, 8434.536100514531, 4405.295152775605, 4431.0251744616135, 4432.13573353193, 4437.933430998473, 4438.911221775611, 3552.786832790041, 3911.941320880189, 3945.349017412095, 4031.3348297531165, 4042.272390969595, 3638.7089032877307, 3698.593154752975, 3807.6573108420375, 3813.332666783357, 3816.38937769961, 1156.8900954409319, 1188.3879801902028, 1189.547971770739, 1197.8502687164105, 1205.1021988618536, 876.2858270509514, 904.7692838326185, 913.9908600914039, 925.0887875442684, 947.6114792934864, 5418.783248670009, 5481.232627432163, 5588.916166257428, 5616.326040875121, 5639.210510528529, 407.7023772645985, 413.6669980665533, 420.09469593027075, 421.6710965446956, 425.80625815253984, 3623.0, 3746.0, 3766.3408903441787, 3789.810509474515, 3928.175083232496, 9463.479508626953, 9714.465704386326, 9731.708048758548, 9817.460039697886, 9817.558209880572, 9656.0, 9703.0, 9731.12419882214, 9811.26415518839, 9854.950725246328, 7314.743983960536, 7481.663325150382, 7544.094708634889, 7545.019147510789, 7575.280348366897, 5631.577631677558, 5727.599068065362, 5747.339056008613, 5784.681842498703, 5808.82573522795, 8872.290216782512, 8989.775255618662, 9013.053629368822, 9089.318301127574, 9105.362624616213, 8947.373683842068, 9156.084391560791, 9244.907550924405, 9247.815043698954, 9255.583498742708, 6025.8192254232445, 6520.771772987922, 6561.639109848813, 7223.277672232734, 7487.3261406473675, 7508.399328053633, 7737.264959828807, 7754.108277548155, 7788.9610551949145, 7804.9219507806465, 6775.830604234737, 6976.441884649317, 6991.056207411934, 6999.020137180901, 7143.6428178591905, 6653.268140504638, 6689.431398331197, 6693.765718199839, 6778.152730908637, 6814.046033555207, 6482.773102941374, 6495.317991611002, 6538.869222615616, 6587.505937054836, 6602.042703808017, 4705.576498115186, 4755.643326750438, 4827.227643577011, 4913.06651736169, 4931.3096166536, 4770.833020844254, 4841.1285968526, 4903.215485522299, 4926.5073492650445, 4944.975275123601], "value": 4068.145333748714, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.07132426599946, 295.7427038476526, 296.6499530553946, 318.5174460692051, 320.0047851182821, 4786.521347865212, 4798.3282340472315, 4811.783469743866, 4850.660453768229, 4888.0, 4691.43702755669, 4750.862112049583, 4782.819425082316, 4784.736839473828, 4810.9037819243595, 3563.697085747708, 3667.559892812853, 3678.5401824771902, 3748.6063963283873, 3755.774653520784, 3721.981390093046, 3725.9627403725885, 3737.121776382548, 3745.5954017243535, 3746.8875933722034, 3718.3307004739136, 3720.162963333242, 3721.64160081571, 3732.514773079496, 3740.1210715481975, 3590.35373632746, 3646.398344273205, 3722.4230244312175, 3767.2653832502674, 3768.605615922099, 3362.899113026613, 3375.4936759486272, 3402.42158832999, 3404.165979335054, 3427.7943323400427, 4596.0, 4607.723536587782, 4617.884552886171, 4623.0, 4647.674662773607, 4228.1966426379195, 4290.978545107255, 4327.177836211141, 4341.370501277329, 4343.153085148398, 4962.751862406902, 4966.261808367079, 4980.676256043382, 5004.324416203803, 5014.222795466709, 3714.294284086042, 3717.735969770264, 3718.8326525306425, 3728.813559322023, 3730.8694195703292, 5082.974585127087, 5171.7672704728375, 5267.55225805807, 5287.735613219324, 5303.787848486056, 3766.7174961877968, 3788.8295026723863, 3790.6831522417883, 3811.561670407883, 3823.445600387928, 3826.4260360945523, 3852.8266228019806, 3861.5559210690567, 3864.478295430137, 3872.786996715208, 4286.807093680792, 4326.286162783168, 4333.220020396296, 4336.9132617348105, 4339.1972485090355, 4887.60899128076, 4961.503849615082, 4969.751512424365, 4989.376327959005, 5023.874403139877, 1318.6110097521255, 1319.6502926724427, 1330.361426515278, 1334.4862228042273, 1336.73265346931, 881.2553392383426, 881.3830318776882, 887.4187407248263, 921.8340698674303, 951.1819834941945, 4358.433403657505, 4388.824447022147, 4407.360932664746, 4413.315936029856, 4419.690621656518, 5072.695638261716, 5088.872778180576, 5097.133487307166, 5101.719405432663, 5111.87220319495, 4146.843287783157, 4370.453693288339, 4379.160752483965, 4384.495782984997, 4394.0446501306615, 2687.400996407174, 2693.8114331996962, 2708.620793088969, 2714.9049783257888, 2717.1576811188593, 4668.603168730696, 4689.273162659725, 4697.553732395485, 4730.692504987165, 4734.313524538922, 4848.684835485682, 5080.415752188472, 5100.209467532539, 5132.230165475134, 5132.871678208075, 4700.435947686299, 4711.199096153662, 4711.952880471152, 4723.385959825201, 4725.763711814464, 4995.525425084611, 5009.7244651544515, 5022.14180753109, 5031.1071134098775, 5032.823851165192, 4683.976580117214, 4914.901701966012, 4934.407871055496, 4946.208606622923, 4957.7273249971595, 4657.953420465752, 4664.253719404879, 4681.953180468152, 4705.882352941204, 4706.34111224422, 3745.0637340664834, 3746.1758413149064, 3762.529683789526, 3763.6612704856693, 3770.962290377171, 4801.645137460863, 4947.925781113358, 5015.648904576645, 5016.523430274118, 5027.170516864822, 4811.254255590389, 4852.684575502581, 4855.441624213261, 4869.147899117711, 4871.244957031666, 4816.277558366273, 4828.734419606955, 4876.926846097241, 4887.584555312696, 4891.682040667345, 4645.512221216859, 4809.879753006203, 4839.112746029122, 4840.540148685869, 4840.709557426566, 4870.805167793249, 4905.632077594123, 4910.508949105061, 4916.532929371704, 4923.803047878045, 4803.29483033518, 4811.542903424309, 4815.494373090775, 4830.468648448738, 4830.637702172421], "value": 3768.766071651815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.3978149774456, 749.411711806232, 765.2041876448494, 767.5778321922941, 804.6821505505324, 12967.935160324198, 13317.467301307961, 13325.33373331332, 13329.266890321029, 13335.06654534183, 12902.22586644802, 12932.676683082927, 12939.741205175891, 12984.480620775157, 13024.674383140426, 678.2877978122975, 822.440740296598, 838.7274135905825, 847.6185716427601, 847.6905929335779, 834.6285902773268, 843.9029511606183, 844.6030365728097, 846.272205902923, 846.4117438380329, 899.4693131052678, 903.8734577159203, 906.6871929184454, 907.1563445995222, 907.2605826251616, 714.9428045756327, 891.6076926152537, 901.8421776189174, 901.9774505637379, 902.6524787956619, 669.8258452802257, 675.0953722012526, 675.6520391998125, 676.7123972311748, 676.9559978601375, 13205.405756740874, 13246.337683115806, 13293.069485136044, 13293.867061329456, 13295.86704132956, 12329.321887296195, 12391.75216495674, 12878.227306361649, 12917.483300667962, 13086.41111149991, 11268.718282043015, 11339.262947908359, 11359.822040378549, 11752.0, 11891.227070240408, 4216.852410165629, 4304.0, 4374.803133858984, 4383.671224658164, 4486.416765820422, 44206.0, 45346.546534534566, 45520.0, 45878.0, 45931.0, 10512.894871051345, 10516.894831051592, 10637.734056648645, 10689.30519516229, 10720.946395267974, 18169.09154542294, 18462.907685461487, 18517.129844874067, 18665.906670466833, 18689.158987845578, 6905.620190889449, 7216.603086830275, 7267.600281984436, 7310.926890731024, 7330.193678695339, 3889.474920885644, 3964.484616999772, 3986.123052928351, 3988.3618621020496, 4082.530508991504, 2906.694797046322, 2993.4911065118545, 2999.0, 3001.2346851552716, 3003.3542788300183, 526.1712802336335, 575.1114528054167, 602.1690067706537, 615.3354377272624, 618.2025187508065, 492.2517772985043, 570.8829689913558, 593.3473179502599, 594.2749845188863, 597.9491743201789, 8110.553919534483, 8180.386471014698, 8229.95885020571, 8353.498790072616, 8405.621747021398, 530.4165418040125, 570.5088522966448, 589.3840936221659, 594.8483136800088, 603.9879202415927, 3783.1487915219404, 3915.314819906505, 3950.6049395060263, 3957.742746721454, 3971.4638523798917, 53371.0, 53650.7317463418, 53798.19302710462, 54294.45705542894, 55900.44099559034, 45526.54473455223, 45746.0, 47183.46124040711, 47616.52383476121, 47789.5221047792, 43253.35119973203, 43559.56440435555, 43626.127477450274, 44086.11827763363, 44406.33390499145, 7656.6554505048525, 7661.15727270011, 7915.287624113857, 7990.640421181177, 8057.516549006844, 29594.704052959194, 30269.394612108073, 30541.847290763402, 31376.843115784275, 31855.36289274248, 37640.62359376371, 38097.0, 38324.0, 38543.22913541769, 39805.80097099612, 11073.83389249178, 11166.0, 11247.156463265452, 11357.54569817198, 11592.304461732323, 11605.245659032136, 11715.882841171479, 11935.52257909674, 12272.263664180178, 12710.110292279453, 8377.58112094405, 10222.948885255526, 10622.628208012682, 10653.414062226651, 11105.16711246676, 11459.770804584028, 11607.709807254549, 11842.230255033395, 12113.81829272544, 12122.696932576757, 10729.302595331279, 11042.558297668003, 11071.114310855273, 11228.326300422, 11283.097352211968, 9462.0, 9464.526773661151, 9784.60861565558, 9875.506224688594, 10014.699559013241, 9309.813803724022, 9600.0, 9676.419414835133, 9878.357906736039, 9881.407115573089], "value": 6197.581589281146, "unit": "score"}], "name": "dromaeo_css", "value": 6355.146711351945, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 110.0, 114.0, 115.0, 116.0, 115.0, 115.0, 117.0, 114.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 138.0, 162.0, 211.0, 138.0, 151.0, 165.0, 135.0, 188.0, 136.0], "value": 159.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 160.0, 163.0, 161.0, 162.0, 160.0, 163.0, 161.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 140.0, 76.0, 76.0, 76.0, 75.0, 127.0, 76.0], "value": 87.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 149.0, 93.0, 94.0, 93.0, 126.0, 93.0, 94.0, 123.0], "value": 105.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 66.0, 67.0, 68.0, 67.0, 119.0, 65.0, 66.0], "value": 71.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 65.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 86.0, 83.0, 83.0, 84.0, 84.0, 85.0, 89.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 143.0, 134.0, 130.0, 134.0, 128.0, 127.0, 194.0, 135.0], "value": 138.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 174.0, 179.0, 174.0, 173.0, 176.0, 188.0, 175.0, 173.0, 174.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 68.0, 66.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1589.6, "alertThreshold": 2.0}]} 10:13:32 INFO - Return code: 0 10:13:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:13:32 ERROR - # TBPL SUCCESS # 10:13:32 INFO - Running post-action listener: _resource_record_post_action 10:13:32 INFO - ##### 10:13:32 INFO - ##### Finished run-tests step (success) 10:13:32 INFO - ##### 10:13:32 INFO - Running post-run listener: _resource_record_post_run 10:13:33 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 529805312; Read time: 0; Write time: 496064 10:13:33 INFO - TinderboxPrint: CPU usage
13.1% 10:13:33 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 10:13:33 INFO - TinderboxPrint: I/O write bytes / time
529,805,312 / 496,064 10:13:33 INFO - TinderboxPrint: CPU idle
8,328.7 (86.9%) 10:13:33 INFO - TinderboxPrint: CPU user
1,213.6 (12.7%) 10:13:33 INFO - TinderboxPrint: Swap in / out
0 / 0 10:13:33 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234729472; Read time: 0; Write time: 287176 10:13:33 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 295075840; Read time: 0; Write time: 208888 10:13:33 INFO - Running post-run listener: _upload_blobber_files 10:13:33 INFO - Blob upload gear active. 10:13:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:13:33 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 10:13:33 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'] 10:13:33 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 10:13:34 INFO - (blobuploader) - INFO - Open directory for files ... 10:13:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:13:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:13:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:34 INFO - (blobuploader) - INFO - Done attempting. 10:13:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:13:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:13:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:35 INFO - (blobuploader) - INFO - Done attempting. 10:13:35 INFO - (blobuploader) - INFO - Iteration through files over. 10:13:35 INFO - Return code: 0 10:13:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:13:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:13:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bc989240995ee1625f27ffeea2ca08395f35238163fa165a5b3b1288857c24e06d83dc3b8d1f1fa91ea5d55ec2ebfa78eafc9750e7805a7731d8a988ce6ca659", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c71dafe25dd19eb5321c2bbdd3ca0917c371493bb7af322cdc224b39698261e2bfe3ada5d260e69d10227972e8714194cb1878c1de36bb40ad76145dca2ab5c3"} 10:13:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:13:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:13:35 INFO - Contents: 10:13:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bc989240995ee1625f27ffeea2ca08395f35238163fa165a5b3b1288857c24e06d83dc3b8d1f1fa91ea5d55ec2ebfa78eafc9750e7805a7731d8a988ce6ca659", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c71dafe25dd19eb5321c2bbdd3ca0917c371493bb7af322cdc224b39698261e2bfe3ada5d260e69d10227972e8714194cb1878c1de36bb40ad76145dca2ab5c3"} 10:13:35 INFO - Running post-run listener: copy_logs_to_upload_dir 10:13:35 INFO - Copying logs to upload dir... 10:13:35 INFO - mkdir: /builds/slave/test/build/upload/logs 10:13:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.624826 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-05-12 10:13:35.425444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 10:13:35.429294) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827 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/bc989240995ee1625f27ffeea2ca08395f35238163fa165a5b3b1288857c24e06d83dc3b8d1f1fa91ea5d55ec2ebfa78eafc9750e7805a7731d8a988ce6ca659", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c71dafe25dd19eb5321c2bbdd3ca0917c371493bb7af322cdc224b39698261e2bfe3ada5d260e69d10227972e8714194cb1878c1de36bb40ad76145dca2ab5c3"} build_url:https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/fKe2ViMJRH-r6cNHgrkbJg/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/bc989240995ee1625f27ffeea2ca08395f35238163fa165a5b3b1288857c24e06d83dc3b8d1f1fa91ea5d55ec2ebfa78eafc9750e7805a7731d8a988ce6ca659", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c71dafe25dd19eb5321c2bbdd3ca0917c371493bb7af322cdc224b39698261e2bfe3ada5d260e69d10227972e8714194cb1878c1de36bb40ad76145dca2ab5c3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 10:13:35.454404) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 10:13:35.454804) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1463070081.404447-1774418827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004404 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 10:13:35.536619) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 10:13:35.536949) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 10:13:35.537238) ========= ========= Total master_lag: 0.18 =========