builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-048 starttime: 1461430093.39 results: success (0) buildid: 20160423090652 builduid: 9592fd3c8dd54344847d320615434aff revision: e5962142ff0a538f42abfd5518cbb2477e987dd8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.393987) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.394374) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.394734) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.450344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.450610) ========= 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=fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-23 09:48:14-- 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% 223M=0s 2016-04-23 09:48:14 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115189 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.586730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.587006) ========= 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=fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016620 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.639212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:13.639512) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e5962142ff0a538f42abfd5518cbb2477e987dd8 --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 e5962142ff0a538f42abfd5518cbb2477e987dd8 --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=fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-23 09:48:14,725 truncating revision to first 12 chars 2016-04-23 09:48:14,725 Setting DEBUG logging. 2016-04-23 09:48:14,725 attempt 1/10 2016-04-23 09:48:14,725 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e5962142ff0a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-23 09:48:15,064 unpacking tar archive at: fx-team-e5962142ff0a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642980 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:14.295773) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:14.296058) ========= script_repo_revision: e5962142ff0a538f42abfd5518cbb2477e987dd8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:14.296445) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:14.296732) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 09:48:14.305645) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-04-23 09:48:14.305927) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:48:15 INFO - MultiFileLogger online at 20160423 09:48:15 in /builds/slave/test 09:48:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:48:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:15 INFO - {'append_to_log': False, 09:48:15 INFO - 'base_work_dir': '/builds/slave/test', 09:48:15 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:48:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:15 INFO - 'branch': 'Fx-Team-Non-PGO', 09:48:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:15 INFO - 'config_files': ('talos/linux_config.py',), 09:48:15 INFO - 'default_actions': ('clobber', 09:48:15 INFO - 'read-buildbot-config', 09:48:15 INFO - 'download-and-extract', 09:48:15 INFO - 'populate-webroot', 09:48:15 INFO - 'create-virtualenv', 09:48:15 INFO - 'install', 09:48:15 INFO - 'run-tests'), 09:48:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:15 INFO - 'download_minidump_stackwalk': True, 09:48:15 INFO - 'download_symbols': 'ondemand', 09:48:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:15 INFO - 'installer_path': 'installer.exe', 09:48:15 INFO - 'log_level': 'info', 09:48:15 INFO - 'log_name': 'talos', 09:48:15 INFO - 'log_to_console': True, 09:48:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:48:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:48:15 INFO - 'opt_config_files': (), 09:48:15 INFO - 'pip_index': False, 09:48:15 INFO - 'sps_profile': False, 09:48:15 INFO - 'sps_profile_interval': 0, 09:48:15 INFO - 'suite': 'dromaeojs-e10s', 09:48:15 INFO - 'system_bits': '32', 09:48:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:15 INFO - 'title': 'talos-linux64-ix-048', 09:48:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:15 INFO - 'use_talos_json': True, 09:48:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:15 INFO - 'work_dir': 'build'} 09:48:15 INFO - ##### 09:48:15 INFO - ##### Running clobber step. 09:48:15 INFO - ##### 09:48:15 INFO - Running pre-action listener: _resource_record_pre_action 09:48:15 INFO - Running main action method: clobber 09:48:15 INFO - rmtree: /builds/slave/test/build 09:48:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:16 INFO - Running post-action listener: _resource_record_post_action 09:48:16 INFO - ##### 09:48:16 INFO - ##### Running read-buildbot-config step. 09:48:16 INFO - ##### 09:48:16 INFO - Running pre-action listener: _resource_record_pre_action 09:48:16 INFO - Running main action method: read_buildbot_config 09:48:16 INFO - Using buildbot properties: 09:48:16 INFO - { 09:48:16 INFO - "project": "", 09:48:16 INFO - "product": "firefox", 09:48:16 INFO - "script_repo_revision": "production", 09:48:16 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 09:48:16 INFO - "repository": "", 09:48:16 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 09:48:16 INFO - "buildid": "20160423090652", 09:48:16 INFO - "pgo_build": "False", 09:48:16 INFO - "basedir": "/builds/slave/test", 09:48:16 INFO - "buildnumber": 1434, 09:48:16 INFO - "slavename": "talos-linux64-ix-048", 09:48:16 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:48:16 INFO - "platform": "ubuntu64_hw", 09:48:16 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:48:16 INFO - "branch": "fx-team", 09:48:16 INFO - "repo_path": "integration/fx-team", 09:48:16 INFO - "revision": "e5962142ff0a538f42abfd5518cbb2477e987dd8", 09:48:16 INFO - "stage_platform": "linux64", 09:48:16 INFO - "builduid": "9592fd3c8dd54344847d320615434aff", 09:48:16 INFO - "slavebuilddir": "test" 09:48:16 INFO - } 09:48:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 09:48:16 INFO - Running post-action listener: _resource_record_post_action 09:48:16 INFO - ##### 09:48:16 INFO - ##### Running download-and-extract step. 09:48:16 INFO - ##### 09:48:16 INFO - Running pre-action listener: _resource_record_pre_action 09:48:16 INFO - Running main action method: download_and_extract 09:48:16 INFO - mkdir: /builds/slave/test/build/tests 09:48:16 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:48:16 INFO - https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:48:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:48:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:48:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:48:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:48:20 INFO - Downloaded 1361 bytes. 09:48:20 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 09:48:20 INFO - Using the following test package requirements: 09:48:20 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:20 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:48:20 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:48:20 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'jsshell-linux-x86_64.zip'], 09:48:20 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:48:20 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:20 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:48:20 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:48:20 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:48:20 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:48:20 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:20 INFO - https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:48:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:48:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:48:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:48:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:21 INFO - Downloaded 22285608 bytes. 09:48:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:48:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:48:22 INFO - Return code: 0 09:48:22 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:48:22 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:48:22 INFO - https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:48:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:48:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:48:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 09:48:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:24 INFO - Downloaded 22285608 bytes. 09:48:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:48:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:48:25 INFO - Return code: 0 09:48: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:48:25 INFO - https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:48:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:48:27 INFO - Downloaded 10840145 bytes. 09:48:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:48:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:48:27 INFO - Return code: 0 09:48: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:48:27 INFO - https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:48:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:48:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:48:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:48:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:48:29 INFO - Downloaded 54749507 bytes. 09:48:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:48:29 INFO - mkdir: /builds/slave/test/properties 09:48:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:29 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:29 INFO - Contents: 09:48:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 09:48:30 INFO - Running post-action listener: _resource_record_post_action 09:48:30 INFO - Running post-action listener: set_extra_try_arguments 09:48:30 INFO - ##### 09:48:30 INFO - ##### Running populate-webroot step. 09:48:30 INFO - ##### 09:48:30 INFO - Running pre-action listener: _resource_record_pre_action 09:48:30 INFO - Running main action method: populate_webroot 09:48:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:30 INFO - '--tppagecycles', 09:48:30 INFO - '7'], 09:48:30 INFO - 'tests': ['tsvgm']}}, 09:48:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['damp', 'tps']}, 09:48:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'tests': ['damp', 'tps']}, 09:48:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['dromaeo_dom']}, 09:48:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['a11yr', 09:48:30 INFO - 'ts_paint', 09:48:30 INFO - 'tpaint', 09:48:30 INFO - 'sessionrestore', 09:48:30 INFO - 'sessionrestore_no_auto_restore', 09:48:30 INFO - 'tabpaint']}, 09:48:30 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:30 INFO - 'ts_paint', 09:48:30 INFO - 'tpaint', 09:48:30 INFO - 'sessionrestore', 09:48:30 INFO - 'sessionrestore_no_auto_restore', 09:48:30 INFO - 'tabpaint']}, 09:48:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:30 INFO - 'ts_paint', 09:48:30 INFO - 'tpaint', 09:48:30 INFO - 'sessionrestore', 09:48:30 INFO - 'sessionrestore_no_auto_restore', 09:48:30 INFO - 'tabpaint']}, 09:48:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:30 INFO - 'ts_paint', 09:48:30 INFO - 'tpaint', 09:48:30 INFO - 'sessionrestore', 09:48:30 INFO - 'sessionrestore_no_auto_restore', 09:48:30 INFO - 'tabpaint']}, 09:48:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['a11yr', 09:48:30 INFO - 'ts_paint', 09:48:30 INFO - 'tpaint', 09:48:30 INFO - 'sessionrestore', 09:48:30 INFO - 'sessionrestore_no_auto_restore', 09:48:30 INFO - 'tabpaint']}, 09:48:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['a11yr', 09:48:30 INFO - 'ts_paint', 09:48:30 INFO - 'tpaint', 09:48:30 INFO - 'sessionrestore', 09:48:30 INFO - 'sessionrestore_no_auto_restore', 09:48:30 INFO - 'tabpaint']}, 09:48:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['tsvgx', 09:48:30 INFO - 'tsvgr_opacity', 09:48:30 INFO - 'tart', 09:48:30 INFO - 'tscrollx', 09:48:30 INFO - 'cart']}, 09:48:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:30 INFO - 'tsvgr_opacity', 09:48:30 INFO - 'tart', 09:48:30 INFO - 'tscrollx', 09:48:30 INFO - 'cart']}, 09:48:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'talos_options': ['--disable-e10s'], 09:48:30 INFO - 'tests': ['tp5o']}, 09:48:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'tests': ['tp5o']}, 09:48:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'talos_options': ['--disable-e10s', 09:48:30 INFO - '--xperf_path', 09:48:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:30 INFO - 'tests': ['tp5n']}, 09:48:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:30 INFO - 'talos_options': ['--xperf_path', 09:48:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:30 INFO - 'tests': ['tp5n']}}, 09:48:30 INFO - 'talos.zip': {'path': '', 09:48:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:30 INFO - Running post-action listener: _resource_record_post_action 09:48:30 INFO - ##### 09:48:30 INFO - ##### Running create-virtualenv step. 09:48:30 INFO - ##### 09:48:30 INFO - Running pre-action listener: _resource_record_pre_action 09:48:30 INFO - Running main action method: create_virtualenv 09:48:30 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:30 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:48:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:30 INFO - Using real prefix '/usr' 09:48:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:31 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:33 INFO - Installing pip.................done. 09:48:33 INFO - Return code: 0 09:48:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:33 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:48:33 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:48:33 INFO - Using env: {'DISPLAY': ':0', 09:48:33 INFO - 'HOME': '/home/cltbld', 09:48:33 INFO - 'LANG': 'en_US.UTF-8', 09:48:33 INFO - 'LANGUAGE': 'en_US:en', 09:48:33 INFO - 'LOGNAME': 'cltbld', 09:48:33 INFO - 'MAIL': '/var/mail/cltbld', 09:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:33 INFO - 'MOZ_NO_REMOTE': '1', 09:48:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:33 INFO - 'NO_EM_RESTART': '1', 09:48:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:33 INFO - 'PWD': '/builds/slave/test', 09:48:33 INFO - 'SHELL': '/bin/bash', 09:48:33 INFO - 'SHLVL': '1', 09:48:33 INFO - 'TERM': 'linux', 09:48:33 INFO - 'TMOUT': '86400', 09:48:33 INFO - 'USER': 'cltbld', 09:48:33 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:33 INFO - '_': '/tools/buildbot/bin/python'} 09:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:33 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:48:33 INFO - Cleaning up... 09:48:33 INFO - Return code: 0 09:48:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:33 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:33 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:48:33 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:48:33 INFO - Using env: {'DISPLAY': ':0', 09:48:33 INFO - 'HOME': '/home/cltbld', 09:48:33 INFO - 'LANG': 'en_US.UTF-8', 09:48:33 INFO - 'LANGUAGE': 'en_US:en', 09:48:33 INFO - 'LOGNAME': 'cltbld', 09:48:33 INFO - 'MAIL': '/var/mail/cltbld', 09:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:33 INFO - 'MOZ_NO_REMOTE': '1', 09:48:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:33 INFO - 'NO_EM_RESTART': '1', 09:48:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:33 INFO - 'PWD': '/builds/slave/test', 09:48:33 INFO - 'SHELL': '/bin/bash', 09:48:33 INFO - 'SHLVL': '1', 09:48:33 INFO - 'TERM': 'linux', 09:48:33 INFO - 'TMOUT': '86400', 09:48:33 INFO - 'USER': 'cltbld', 09:48:33 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:33 INFO - '_': '/tools/buildbot/bin/python'} 09:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:33 INFO - Downloading/unpacking psutil>=0.7.1 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:35 INFO - Installing collected packages: psutil 09:48:35 INFO - Running setup.py install for psutil 09:48:35 INFO - building 'psutil._psutil_linux' extension 09:48:35 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:48:35 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:48:35 INFO - building 'psutil._psutil_posix' extension 09:48:35 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:48:36 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:48:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:36 INFO - Successfully installed psutil 09:48:36 INFO - Cleaning up... 09:48:36 INFO - Return code: 0 09:48:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:48:36 INFO - Using env: {'DISPLAY': ':0', 09:48:36 INFO - 'HOME': '/home/cltbld', 09:48:36 INFO - 'LANG': 'en_US.UTF-8', 09:48:36 INFO - 'LANGUAGE': 'en_US:en', 09:48:36 INFO - 'LOGNAME': 'cltbld', 09:48:36 INFO - 'MAIL': '/var/mail/cltbld', 09:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:36 INFO - 'MOZ_NO_REMOTE': '1', 09:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:36 INFO - 'NO_EM_RESTART': '1', 09:48:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:36 INFO - 'PWD': '/builds/slave/test', 09:48:36 INFO - 'SHELL': '/bin/bash', 09:48:36 INFO - 'SHLVL': '1', 09:48:36 INFO - 'TERM': 'linux', 09:48:36 INFO - 'TMOUT': '86400', 09:48:36 INFO - 'USER': 'cltbld', 09:48:36 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:36 INFO - '_': '/tools/buildbot/bin/python'} 09:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:48:36 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:48:36 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:48:36 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:48:36 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:48:36 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:48:36 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:48:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:38 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:48:38 INFO - Installing collected packages: mozsystemmonitor 09:48:38 INFO - Running setup.py install for mozsystemmonitor 09:48:38 INFO - Successfully installed mozsystemmonitor 09:48:38 INFO - Cleaning up... 09:48:38 INFO - Return code: 0 09:48:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48:38 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:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:38 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:48:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:38 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:38 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:48:38 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:48:38 INFO - Using env: {'DISPLAY': ':0', 09:48:38 INFO - 'HOME': '/home/cltbld', 09:48:38 INFO - 'LANG': 'en_US.UTF-8', 09:48:38 INFO - 'LANGUAGE': 'en_US:en', 09:48:38 INFO - 'LOGNAME': 'cltbld', 09:48:38 INFO - 'MAIL': '/var/mail/cltbld', 09:48:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:38 INFO - 'MOZ_NO_REMOTE': '1', 09:48:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:38 INFO - 'NO_EM_RESTART': '1', 09:48:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:38 INFO - 'PWD': '/builds/slave/test', 09:48:38 INFO - 'SHELL': '/bin/bash', 09:48:38 INFO - 'SHLVL': '1', 09:48:38 INFO - 'TERM': 'linux', 09:48:38 INFO - 'TMOUT': '86400', 09:48:38 INFO - 'USER': 'cltbld', 09:48:38 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:38 INFO - '_': '/tools/buildbot/bin/python'} 09:48:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:38 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:38 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:48:38 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:48:38 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:48:38 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:48:38 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:48:38 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:48:40 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48:40 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:48:40 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:48:40 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:48:40 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:48:40 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:48:40 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:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:48:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48:41 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:48:41 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:48:41 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:48:41 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:48:41 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:48:41 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:48:41 INFO - Downloading docopt-0.6.1.tar.gz 09:48:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:41 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:41 INFO - Running setup.py install for blobuploader 09:48:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:48:41 INFO - Running setup.py install for requests 09:48:41 INFO - Running setup.py install for docopt 09:48:41 INFO - Successfully installed blobuploader requests docopt 09:48:41 INFO - Cleaning up... 09:48:42 INFO - Return code: 0 09:48:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:42 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:42 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:48:42 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:48:42 INFO - Using env: {'DISPLAY': ':0', 09:48:42 INFO - 'HOME': '/home/cltbld', 09:48:42 INFO - 'LANG': 'en_US.UTF-8', 09:48:42 INFO - 'LANGUAGE': 'en_US:en', 09:48:42 INFO - 'LOGNAME': 'cltbld', 09:48:42 INFO - 'MAIL': '/var/mail/cltbld', 09:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:42 INFO - 'MOZ_NO_REMOTE': '1', 09:48:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:42 INFO - 'NO_EM_RESTART': '1', 09:48:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:42 INFO - 'PWD': '/builds/slave/test', 09:48:42 INFO - 'SHELL': '/bin/bash', 09:48:42 INFO - 'SHLVL': '1', 09:48:42 INFO - 'TERM': 'linux', 09:48:42 INFO - 'TMOUT': '86400', 09:48:42 INFO - 'USER': 'cltbld', 09:48:42 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:42 INFO - '_': '/tools/buildbot/bin/python'} 09:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:42 INFO - Running setup.py (path:/tmp/pip-wIrRRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:42 INFO - Running setup.py (path:/tmp/pip-19FSOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:42 INFO - Running setup.py (path:/tmp/pip-kEsonp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:42 INFO - Running setup.py (path:/tmp/pip-XXxU4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:42 INFO - Running setup.py (path:/tmp/pip-ddCgEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:42 INFO - Running setup.py (path:/tmp/pip-cExrNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:42 INFO - Running setup.py (path:/tmp/pip-qwrw_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:42 INFO - Running setup.py (path:/tmp/pip-rCHChC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:42 INFO - Running setup.py (path:/tmp/pip-5gcEev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:42 INFO - Running setup.py (path:/tmp/pip-CUeagK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:42 INFO - Running setup.py (path:/tmp/pip-12jF0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:43 INFO - Running setup.py (path:/tmp/pip-ckGWDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:43 INFO - Running setup.py (path:/tmp/pip-eCslw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:43 INFO - Running setup.py (path:/tmp/pip-Cbso16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:43 INFO - Running setup.py (path:/tmp/pip-7YtZ5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:43 INFO - Running setup.py (path:/tmp/pip-rDDzLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:43 INFO - Running setup.py (path:/tmp/pip-lzPG1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:43 INFO - Running setup.py install for manifestparser 09:48:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Running setup.py install for mozcrash 09:48:43 INFO - Running setup.py install for mozdebug 09:48:43 INFO - Running setup.py install for mozdevice 09:48:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:48:43 INFO - Running setup.py install for mozfile 09:48:43 INFO - Running setup.py install for mozhttpd 09:48:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Running setup.py install for mozinfo 09:48:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Running setup.py install for mozInstall 09:48:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Running setup.py install for mozleak 09:48:44 INFO - Running setup.py install for mozlog 09:48:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Running setup.py install for moznetwork 09:48:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Running setup.py install for mozprocess 09:48:44 INFO - Running setup.py install for mozprofile 09:48:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Running setup.py install for mozrunner 09:48:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:48:44 INFO - Running setup.py install for mozscreenshot 09:48:44 INFO - Running setup.py install for moztest 09:48:45 INFO - Running setup.py install for mozversion 09:48:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:48:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:45 INFO - Cleaning up... 09:48:45 INFO - Return code: 0 09:48:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:45 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:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:45 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:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:45 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:45 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:48:45 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:48:45 INFO - Using env: {'DISPLAY': ':0', 09:48:45 INFO - 'HOME': '/home/cltbld', 09:48:45 INFO - 'LANG': 'en_US.UTF-8', 09:48:45 INFO - 'LANGUAGE': 'en_US:en', 09:48:45 INFO - 'LOGNAME': 'cltbld', 09:48:45 INFO - 'MAIL': '/var/mail/cltbld', 09:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:45 INFO - 'MOZ_NO_REMOTE': '1', 09:48:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:45 INFO - 'NO_EM_RESTART': '1', 09:48:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:45 INFO - 'PWD': '/builds/slave/test', 09:48:45 INFO - 'SHELL': '/bin/bash', 09:48:45 INFO - 'SHLVL': '1', 09:48:45 INFO - 'TERM': 'linux', 09:48:45 INFO - 'TMOUT': '86400', 09:48:45 INFO - 'USER': 'cltbld', 09:48:45 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:45 INFO - '_': '/tools/buildbot/bin/python'} 09:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:45 INFO - Running setup.py (path:/tmp/pip-B7KIrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:45 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:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:45 INFO - Running setup.py (path:/tmp/pip-G4Fdzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:45 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:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:45 INFO - Running setup.py (path:/tmp/pip-8Frs_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:45 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:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:45 INFO - Running setup.py (path:/tmp/pip-iIp95d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:45 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:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:45 INFO - Running setup.py (path:/tmp/pip-H0OUbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:45 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:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:45 INFO - Running setup.py (path:/tmp/pip-V_dUcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:45 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:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:45 INFO - Running setup.py (path:/tmp/pip-_Fm9Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:45 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:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:45 INFO - Running setup.py (path:/tmp/pip-SGbN90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:46 INFO - Running setup.py (path:/tmp/pip-rGKVS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:46 INFO - Running setup.py (path:/tmp/pip-VoomxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:46 INFO - Running setup.py (path:/tmp/pip-56AA_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:46 INFO - Running setup.py (path:/tmp/pip-AgOyYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:46 INFO - Running setup.py (path:/tmp/pip-pT4LnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:46 INFO - Running setup.py (path:/tmp/pip-Mb1a4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:46 INFO - Running setup.py (path:/tmp/pip-1EfWLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:46 INFO - Running setup.py (path:/tmp/pip-yibSpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:46 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:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:46 INFO - Running setup.py (path:/tmp/pip-HP9_Hv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:46 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:48:48 INFO - Downloading blessings-1.6.tar.gz 09:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:48 INFO - Installing collected packages: blessings 09:48:48 INFO - Running setup.py install for blessings 09:48:48 INFO - Successfully installed blessings 09:48:48 INFO - Cleaning up... 09:48:48 INFO - Return code: 0 09:48:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:48:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:49 INFO - Reading from file tmpfile_stdout 09:48:49 INFO - Current package versions: 09:48:49 INFO - argparse == 1.2.1 09:48:49 INFO - blessings == 1.6 09:48:49 INFO - blobuploader == 1.2.4 09:48:49 INFO - docopt == 0.6.1 09:48:49 INFO - manifestparser == 1.1 09:48:49 INFO - mozInstall == 1.12 09:48:49 INFO - mozcrash == 0.17 09:48:49 INFO - mozdebug == 0.1 09:48:49 INFO - mozdevice == 0.48 09:48:49 INFO - mozfile == 1.2 09:48:49 INFO - mozhttpd == 0.7 09:48:49 INFO - mozinfo == 0.9 09:48:49 INFO - mozleak == 0.1 09:48:49 INFO - mozlog == 3.1 09:48:49 INFO - moznetwork == 0.27 09:48:49 INFO - mozprocess == 0.22 09:48:49 INFO - mozprofile == 0.28 09:48:49 INFO - mozrunner == 6.11 09:48:49 INFO - mozscreenshot == 0.1 09:48:49 INFO - mozsystemmonitor == 0.0 09:48:49 INFO - moztest == 0.7 09:48:49 INFO - mozversion == 1.4 09:48:49 INFO - psutil == 3.1.1 09:48:49 INFO - requests == 1.2.3 09:48:49 INFO - wsgiref == 0.1.2 09:48:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:49 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:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:49 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:48:49 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:48:49 INFO - Using env: {'DISPLAY': ':0', 09:48:49 INFO - 'HOME': '/home/cltbld', 09:48:49 INFO - 'LANG': 'en_US.UTF-8', 09:48:49 INFO - 'LANGUAGE': 'en_US:en', 09:48:49 INFO - 'LOGNAME': 'cltbld', 09:48:49 INFO - 'MAIL': '/var/mail/cltbld', 09:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:49 INFO - 'MOZ_NO_REMOTE': '1', 09:48:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:49 INFO - 'NO_EM_RESTART': '1', 09:48:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:49 INFO - 'PWD': '/builds/slave/test', 09:48:49 INFO - 'SHELL': '/bin/bash', 09:48:49 INFO - 'SHLVL': '1', 09:48:49 INFO - 'TERM': 'linux', 09:48:49 INFO - 'TMOUT': '86400', 09:48:49 INFO - 'USER': 'cltbld', 09:48:49 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:49 INFO - '_': '/tools/buildbot/bin/python'} 09:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:49 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:48:49 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:48:49 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:48:49 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:48:49 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:48:49 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:48:49 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:48:49 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:48:49 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:48:49 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:48:49 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:48:49 INFO - Cleaning up... 09:48:49 INFO - Return code: 0 09:48:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:48:49 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:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:49 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:49 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:48:49 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:48:49 INFO - Using env: {'DISPLAY': ':0', 09:48:49 INFO - 'HOME': '/home/cltbld', 09:48:49 INFO - 'LANG': 'en_US.UTF-8', 09:48:49 INFO - 'LANGUAGE': 'en_US:en', 09:48:49 INFO - 'LOGNAME': 'cltbld', 09:48:49 INFO - 'MAIL': '/var/mail/cltbld', 09:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:49 INFO - 'MOZ_NO_REMOTE': '1', 09:48:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:49 INFO - 'NO_EM_RESTART': '1', 09:48:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:49 INFO - 'PWD': '/builds/slave/test', 09:48:49 INFO - 'SHELL': '/bin/bash', 09:48:49 INFO - 'SHLVL': '1', 09:48:49 INFO - 'TERM': 'linux', 09:48:49 INFO - 'TMOUT': '86400', 09:48:49 INFO - 'USER': 'cltbld', 09:48:49 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:49 INFO - '_': '/tools/buildbot/bin/python'} 09:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:49 INFO - Downloading/unpacking jsonschema 09:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:51 INFO - Downloading/unpacking functools32 (from jsonschema) 09:48:51 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:48:51 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:48:51 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:48:51 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:48:51 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:48:51 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:48:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:51 INFO - warning: no files found matching '*.txt' 09:48:51 INFO - no previously-included directories found matching 'build' 09:48:51 INFO - no previously-included directories found matching 'dist' 09:48:51 INFO - no previously-included directories found matching '.git*' 09:48:51 INFO - Installing collected packages: jsonschema, functools32 09:48:51 INFO - Running setup.py install for functools32 09:48:51 INFO - warning: no files found matching '*.txt' 09:48:51 INFO - no previously-included directories found matching 'build' 09:48:51 INFO - no previously-included directories found matching 'dist' 09:48:51 INFO - no previously-included directories found matching '.git*' 09:48:51 INFO - Successfully installed jsonschema functools32 09:48:51 INFO - Cleaning up... 09:48:51 INFO - Return code: 0 09:48:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:48:51 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:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:51 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:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:51 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 0x7ffa0ad76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11445a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, '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': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', '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:48:51 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:48:51 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:48:51 INFO - Using env: {'DISPLAY': ':0', 09:48:51 INFO - 'HOME': '/home/cltbld', 09:48:51 INFO - 'LANG': 'en_US.UTF-8', 09:48:51 INFO - 'LANGUAGE': 'en_US:en', 09:48:51 INFO - 'LOGNAME': 'cltbld', 09:48:51 INFO - 'MAIL': '/var/mail/cltbld', 09:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:51 INFO - 'MOZ_NO_REMOTE': '1', 09:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:51 INFO - 'NO_EM_RESTART': '1', 09:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:51 INFO - 'PWD': '/builds/slave/test', 09:48:51 INFO - 'SHELL': '/bin/bash', 09:48:51 INFO - 'SHLVL': '1', 09:48:51 INFO - 'TERM': 'linux', 09:48:51 INFO - 'TMOUT': '86400', 09:48:51 INFO - 'USER': 'cltbld', 09:48:51 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:51 INFO - '_': '/tools/buildbot/bin/python'} 09:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:52 INFO - Downloading/unpacking flake8 09:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:48:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:48:53 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:48:53 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:48:53 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:48:53 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:48:53 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:48:53 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:48:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:48:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:48:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:48:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:48:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:48:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:48:54 INFO - no previously-included directories found matching 'docs/_build' 09:48:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:48:54 INFO - Running setup.py install for pep8 09:48:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:48:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:48:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:48:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:48:54 INFO - no previously-included directories found matching 'docs/_build' 09:48:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:48:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:48:54 INFO - Cleaning up... 09:48:54 INFO - Return code: 0 09:48:54 INFO - Running post-action listener: _resource_record_post_action 09:48:54 INFO - Running post-action listener: _start_resource_monitoring 09:48:54 INFO - Starting resource monitoring. 09:48:54 INFO - ##### 09:48:54 INFO - ##### Running install step. 09:48:54 INFO - ##### 09:48:54 INFO - Running pre-action listener: _resource_record_pre_action 09:48:54 INFO - Running main action method: install 09:48:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:55 INFO - Reading from file tmpfile_stdout 09:48:55 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:48:55 INFO - Reading from file tmpfile_stdout 09:48:55 INFO - Output received: 09:48:55 INFO - Usage: mozinstall [options] installer 09:48:55 INFO - Options: 09:48:55 INFO - -h, --help show this help message and exit 09:48:55 INFO - -d DEST, --destination=DEST 09:48:55 INFO - Directory to install application into. [default: 09:48:55 INFO - "/builds/slave/test"] 09:48:55 INFO - --app=APP Application being installed. [default: firefox] 09:48:55 INFO - mkdir: /builds/slave/test/build/application 09:48:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:49:10 INFO - Reading from file tmpfile_stdout 09:49:10 INFO - Output received: 09:49:10 INFO - /builds/slave/test/build/application/firefox/firefox 09:49:10 INFO - Running post-action listener: _resource_record_post_action 09:49:10 INFO - ##### 09:49:10 INFO - ##### Running run-tests step. 09:49:10 INFO - ##### 09:49:10 INFO - Running pre-action listener: _resource_record_pre_action 09:49:10 INFO - Running main action method: run_tests 09:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:10 INFO - Python 2.7.3 09:49:10 INFO - Return code: 0 09:49:10 INFO - grabbing minidump binary from tooltool 09:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:49:10 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11d3410>, '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 0x11d4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11d47a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:10 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:49:10 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:49:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:10 INFO - Return code: 0 09:49:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:49:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:49:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:49:11 INFO - Return code: 0 09:49:11 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:49:11 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:49:11 INFO - Using env: {'DISPLAY': ':0', 09:49:11 INFO - 'HOME': '/home/cltbld', 09:49:11 INFO - 'LANG': 'en_US.UTF-8', 09:49:11 INFO - 'LANGUAGE': 'en_US:en', 09:49:11 INFO - 'LOGNAME': 'cltbld', 09:49:11 INFO - 'MAIL': '/var/mail/cltbld', 09:49:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:11 INFO - 'MOZ_NO_REMOTE': '1', 09:49:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:11 INFO - 'NO_EM_RESTART': '1', 09:49:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:11 INFO - 'PWD': '/builds/slave/test', 09:49:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:11 INFO - 'SHELL': '/bin/bash', 09:49:11 INFO - 'SHLVL': '1', 09:49:11 INFO - 'TERM': 'linux', 09:49:11 INFO - 'TMOUT': '86400', 09:49:11 INFO - 'USER': 'cltbld', 09:49:11 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809', 09:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:11 INFO - '_': '/tools/buildbot/bin/python'} 09:49:11 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:49:12 INFO - mozversion application_buildid: 20160423090652 09:49:12 INFO - mozversion application_changeset: e5962142ff0a538f42abfd5518cbb2477e987dd8 09:49:12 INFO - mozversion application_display_name: Nightly 09:49:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:12 INFO - mozversion application_name: Firefox 09:49:12 INFO - mozversion application_remotingname: firefox 09:49:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:49:12 INFO - mozversion application_vendor: Mozilla 09:49:12 INFO - mozversion application_version: 48.0a1 09:49:12 INFO - mozversion platform_buildid: 20160423090652 09:49:12 INFO - mozversion platform_changeset: e5962142ff0a538f42abfd5518cbb2477e987dd8 09:49:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:49:12 INFO - mozversion platform_version: 48.0a1 09:49:12 INFO - using testdate: 1461430152 09:49:12 INFO - actual date: 1461430152 09:49:12 INFO - starting webserver on 'localhost:41891' 09:49:12 INFO - SUITE-START | Running 2 tests 09:49:12 INFO - TEST-START | dromaeo_css 09:49:12 INFO - operating with platform_type : linux_ 09:49:12 INFO - Initialising browser for dromaeo_css test... 09:49:12 INFO - TEST-INFO | started process 1290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqnImKp/profile http://localhost:41891/getInfo.html) 09:49:16 INFO - PROCESS | 1290 | 09:49:16 INFO - PROCESS | 1290 | (process:1356): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:49:17 INFO - PROCESS | 1290 | __metrics Screen width/height:1600/1200 09:49:17 INFO - PROCESS | 1290 | colorDepth:24 09:49:17 INFO - PROCESS | 1290 | Browser inner width/height: 1024/697 09:49:17 INFO - PROCESS | 1290 | __metrics 09:49:17 ERROR - PROCESS | 1290 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:49:18 INFO - TEST-INFO | 1290: exit 0 09:49:18 INFO - Browser initialized. 09:49:18 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:18 INFO - TEST-INFO | started process 1408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqnImKp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:20 INFO - PROCESS | 1408 | 09:49:20 INFO - PROCESS | 1408 | (process:1469): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:49:30 INFO - PROCESS | 1408 | RSS: Main: 166260736 09:49:30 INFO - PROCESS | 1408 | 09:50:20 INFO - PROCESS | 1408 | 1461430220229 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:52:32 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:41891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41891/tests/dromaeo/cssquery-ext.html) 09:52:33 INFO - PROCESS | 1408 | RSS: Main: 168595456 09:52:33 INFO - PROCESS | 1408 | 09:55:35 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:41891/tests/dromaeo/cssquery-ext.html (next: http://localhost:41891/tests/dromaeo/cssquery-jquery.html) 09:55:36 INFO - PROCESS | 1408 | RSS: Main: 165941248 09:55:36 INFO - PROCESS | 1408 | 09:58:37 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:41891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41891/tests/dromaeo/cssquery-mootools.html) 09:58:38 INFO - PROCESS | 1408 | RSS: Main: 168427520 09:58:38 INFO - PROCESS | 1408 | 10:01:40 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:41891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41891/tests/dromaeo/cssquery-prototype.html) 10:01:41 INFO - PROCESS | 1408 | RSS: Main: 164970496 10:01:41 INFO - PROCESS | 1408 | 10:04:43 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:41891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41891/tests/dromaeo/cssquery-yui.html) 10:04:44 INFO - PROCESS | 1408 | RSS: Main: 165576704 10:04:44 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | Cycle 1(1): loaded http://localhost:41891/tests/dromaeo/cssquery-yui.html (next: http://localhost:41891/tests/dromaeo/cssquery-dojo.html) 10:07:47 INFO - PROCESS | 1408 | RSS: Main: 168235008 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | __start_tp_report 10:07:47 INFO - PROCESS | 1408 | _x_x_mozilla_page_load 10:07:47 INFO - PROCESS | 1408 | _x_x_mozilla_page_load_details 10:07:47 INFO - PROCESS | 1408 | |i|pagename|runs| 10:07:47 INFO - PROCESS | 1408 | |0;dojo.html;2641.511320405725;2690.1122629532256;2732.9453410931783;2735.63068985687;2736.370634754005;4813.711177329363;4827.734474603895;4840.273958906161;4841.8305359312435;4906.264060390943;4754.239321708527;4821.975890120544;4857.174280372336;4949.381327334083;4964.106460837048;4393.93409098863;4419.845305414311;4469.3296005599095;4475.932861007088;4511.819527218908;4418.97790511047;4455.554444555551;4507.594316511513;4529.479109902354;4532.138893610204;4365;4367.890802729926;4407.845725399628;4429.712068715539;4486.528914463967;4407.603315701603;4418.160549495586;4444.444444444444;4454.754988475633;4501;4422.469303683579;4422.535633758441;4422.601965823092;4422.911541769179;4449.17690227309;4598.149342371672;4728.148933192024;4742.099001189763;4765.952340476586;4769.475357710649;5131.153359695646;5137.486251374833;5141.511556402136;5143.511366420222;5165.147750621143;5121.641485096045;5178.689278643294;5180.689158650455;5183.222516622537;5201.297824793643;4806.639502037361;4854.660173787872;4855.174620314555;4913.926291105637;4919.2129259318335;5242.292290540804;5309.150535914235;5312.415634280226;5318.521333080042;5365.517103460629;4956.207006878882;5045.2432135180015;5062.417821950449;5080.314157588752;5083.389993200765;5163;5170.431252562215;5185.766640501187;5216.765245563883;5244.213367994831;5026.095302845523;5105.897882042338;5123.487651234846;5124.666896651705;5126.128558145124;5026.1204289249235;5056.646034777604;5136.383633963948;5150.201718733602;5181.948180518222;4301.225779359683;4437.711548749321;4454.821807127679;4456.955430445654;4502.819887204475;3772.3398405279513;3828.272628200633;3832.080300727805;3867.477890484805;3872.3223435899167;4413.183561041154;4448;4448.933266001012;4461.24158892989;4490.640748740093;5122.078025955289;5181.715005674649;5210.400803907523;5213.713245771443;5219.791208351623;4466.64266858657;4470.1059788041875;4476.306172543261;4492.640588752892;4523.547645235516;4614.215583350839;4689.320048592935;4695.5069717679835;4698.060387922429;4742.881427964259;4747.881302967454;4794.808207671654;4857.53853383928;4884.65807393479;4893.657443979029;5148.30497882796;5166.922496162636;5187.221916712523;5189.273501709839;5189.792408303626;4825.9758701207675;4828.637852161031;4830.81644996978;4886.706797592155;4897.583705385082;5003.749812509287;5086.109930762176;5133.666311689728;5174.172132458789;5198.9740051299505;5090.185570308733;5102.08162530748;5102.821401250939;5109.79560817578;5175.922361164661;4907.411110666742;4966.652334336562;4989.750512474435;4993.27597498368;5014.598832093351;4892;4954.430240522386;5024.447310795883;5062.088824011713;5065.544101030925;4825.589824864785;4828;4850.17547017015;4877.268409738567;4937.530934561211;5053.4946505350645;5061.240813877947;5077.7968881245815;5082.542571168613;5111.5910727141;4879.39007624047;4913.287573301923;4922.2616607507725;4957.330760347307;4970.726610036482 10:07:47 INFO - PROCESS | 1408 | |1;ext.html;573.0515996026575;593.3147214966714;613.3069631316614;614.6127939398178;641.0384423364952;34605.82697086514;36000.99999999996;36012.45981310279;36107.63892361076;36181.276374472494;39648.405273920915;40280.39579406304;40299.798501007455;40408.797956010254;40475.39286910699;7695.6921723131145;7848.175941526143;7852.489588176779;7853.8821917671285;7885;7725.18885517021;7862.449628526006;7907.960460197691;7911.8417631647335;7929.286364227219;7852.05775306964;7889;7907.802304942365;7932.246436588515;7939.1663875293125;7778.455508114434;7875.881861772078;7879.606019699021;7887;7903.565303908283;7800;7810.258025487569;7865.056193256789;7874.05511338643;7877.881831772528;30401.69598304033;36664.63335366639;37252.813735931144;37286.06784830374;37335.45313195242;3066.861991210401;3168.6197656281165;3204.279037216631;3341.8830340938193;3376.898693039213;27751;28791.280217994507;28932;29230.269243268875;29668;3342.4484959981573;3365.7980521168565;3387.915302117442;3501.0197144799495;3507.5440192774777;251528;258841;259585;262329;262420.6878965612;33600;34846.12884677904;34873.47689784655;58778.70610647005;59297.40702593005;35615.28769424597;35688.28623427517;35981.280374392365;36022;36161.457578136346;12133.817992730115;12250;12252.387380631111;12334.444949977273;12337.876621233852;10161.577379166922;10205.591776329013;10263;10267.383956962607;10318.587256509656;8399;8401.537915414588;8466.32269418445;8479.745607631781;8525.062243153248;3332.900012999614;3371.8314084296176;3372.106391806173;3392.1010932103013;3415.470602056685;936.7892224249489;953.8759961205137;960.9759756006156;979.637534112383;1092.8961748633867;28995.275118121794;29348.11955641334;29409.411811763646;29444;29741.40517189644;996.6926565001997;1019.9643012494527;1056.271172890703;1078.2937176149635;1088.6842815583393;15197.468088616846;15374.769378459332;15394.692106157814;15560.688786224438;15755.606109847116;350882;360594;360730;361589;362354;50771;50806.74596626993;51665.741671291406;51693.7415312921;51769.22346164811;1265.9050571207197;1274.566647339909;1277.5847849448967;1285.0490636928605;1285.126114242324;27144.864275678494;28417.933123104194;28601.28496787597;28921.132366029055;29169.270768230963;43875;44417.777911110235;44438.77780611076;44635.33047004363;44722;307642;312818;316087;316960;317109;26483.470330593667;26643.33391665224;26879;26929;26969.460610787282;43503.78248108739;43563.78218108889;43890.56109438865;44204.34773913575;45030.77484612556;36554.8172259137;37189.25621487609;37283;37606.62393376031;38059.2388152241;34313.65686343204;36724;36793.448098278015;37106.44340334843;37626.43560346652;36258.637413625525;36396;36476.45285320775;37231.44152837764;37397.43903841499;31983.520247196775;32558.139534884063;32841;33212.33575328528;33502.32995340031;25266.621000685373;25441.61837572475;25863.482730345662;25913.740862591887;26422 10:07:47 INFO - PROCESS | 1408 | |2;jquery.html;381.193775165525;381.2045266793404;384.1241968312247;386.7061033614453;401.89148929788973;15218.32598414175;16682.66634667306;17082.01650153555;17133.22900469479;17247.396341128064;14279.357428915699;14785.408583656641;14901.40394384226;14904.925475372635;14909.627259318522;4983.451820299764;5121.5390614844655;5175.534201921826;5181.948180518184;5183.53348198662;3725.068732816796;5588.748506317205;5917.201177841001;5935.910961335583;5937.287525496925;5717.142428635696;5890.175375447441;5898.734556944948;5904.409559044104;5908.704564771766;5820.185174075633;5836.97081514594;5857.70711464425;5874.060150375941;5881.117832325164;5110.9744451277875;5176;5214.556762675177;5215.765290561933;5338.706371149585;20023.49941251466;20273.69589456159;20409;20448.488787780276;20651.380458586267;1907.8664493485462;2015.254355888316;2029.5839352932614;2064.277502873997;2310.330004298751;13054.2820144892;13143.802842957364;13195.2742599157;13327.200367977855;13424.395902184327;1762.7444020617068;1776.644671065792;1798.2537247042592;1837.843783278423;1853.203122657257;70316;70961.2903870965;71147.64426177887;72057;72093.2790672097;9472.384295020802;9514.429134251966;9829.361091529028;9879.259055570861;9941.304108712466;13802.792958105636;13902.791458128137;13921;13925.582232533041;13938.094023888414;6896.137982752156;6959.478039147084;6968.338007889344;7141.428685705131;7214.96392518034;6483.546151769425;6637.137172167587;6655.534112612071;6743.055972163901;6744.494162937799;6038.969805150946;6075.878482430327;6106.267247930188;6117.571769976059;6118.112873633388;1656.3209084275388;1747.2748809244276;1852.8610354223217;1876.7747870255657;1889.7826749923934;873.4628203654834;889.2041622747605;892.5314210039782;1011.7723512209694;1047.7904419116055;14350.712985740227;15027.474038408604;16802.495925122264;17009.829901700825;17272.740908886375;908.3096846396786;943.4716558727134;945.7162851144767;959.4387283439266;989.366805244644;9699.51502424876;9740.220782337396;9819.75450613726;9886.901130988597;9925.496399574726;390686;402963;405408;405740;406997.4052205536;69661;72304.27695722975;73044;73248;73756.63121684357;904.1907492794044;955.7085089047769;963.4026903319847;964.2189826240767;998.4658207858906;13935.30323483842;14940.626484337978;15061.849381506045;15143.015703979207;15202.771958420411;28929.276768080967;29483.41033179367;29892;32436.83781581077;33454.33091338208;44100;46009.539904600526;48003;48153.518464816305;48182;7720.575368354795;7733.419993500623;7804.336631386395;8029.638666259915;8123.79690507742;10304.208543027527;11167.274127181707;11289.153313501685;11351.943240283746;11428;4633.8146474142;5633.63381380209;9458.527073646428;9974.700758977242;10345.63790267337;9958.950205248928;10068.194544436574;10301.896981030093;10468.581256749645;10478.63324783629;10094;10524.894751052392;10662.626808061681;10785.352878826981;10959.342439453658;7763.893111887508;7823.765287041398;7865.4494185406475;8037.879431808645;8042.798930026796;8379.70671026511;8439.493630382196;8540.572971351283;8650.307975362082;8656.0478347383 10:07:47 INFO - PROCESS | 1408 | |3;mootools.html;1464.3703207620727;1482.9851701482985;1662.617597952471;1786.2319202742815;1829.0580351119177;6924.480663950211;7611.771646850603;7654.617269136542;7945.72189973351;7952.363810895129;7629.084509858824;7635;7641;7662.923370766291;7713.7685869424;2186;2196.2203417786695;2198.208644887839;2217.2904670505445;2221.3447033125185;2123.4054464749815;2141.4218161096496;2168.7072245246927;2171.1966572368246;2181.6291230490774;2127.319257837493;2163.653815389538;2167.100653228916;2170.630992731232;2194.36363454598;2137.8289736821016;2138.2088627207954;2156.4069880782754;2158.363282831561;2169.197396963118;2145.2062736787334;2145.6459684152096;2168.913243470259;2170.934871953844;2171.8045375916245;7812.960935195288;7905.565193914333;7921.287084162453;7941.166177551332;7945.086315073725;4579.771011449414;4757.833475828329;4772.16487114757;4779.545943135397;4790.856274311742;2928.824270543753;3033.5752994580776;3080.9845950770327;3109.23823663204;3110.522501811644;926.8609708543705;936.6674830435165;951.9428834269896;954.1079091049891;961.2886464016648;10853.891461085366;10893.128549716004;10920.563177472892;11014.39420831854;11031.172662050298;4240.597143271384;4265.808038638269;4449.132419178294;4458.5987261146;4461.353103799931;9211.677591284273;9235.861462078074;9250.86123708145;9263.351565390492;9271.953640231755;4779;4804.447488538793;4821.54195351441;4822.71063736177;4831.251156070814;3854;4172.144710334434;4301.2257793597455;4345.196138714348;4366.432363792686;3665.706743460517;3669.229461812996;3728.3848165052464;3759.0602349412648;3812.9237415252096;1242.310517662706;1251.768422841759;1255.4727014653868;1270.0601554849345;1272.4782839035952;648.4617767253169;759.6050053971903;887.2281115429489;893.2050475077186;925.5233554719325;5396.83809485716;5421.2681288025615;5426.158945363475;5473.534749546333;5656.1798539212405;847.9872801907976;861.4271509446148;935.7520257131865;954.370115723619;980.122790102854;3745;3816.637419445148;3870.903227419337;3876.108495046155;4001.619846114673;9159.450432974043;9499.857502137473;9585.137337639646;9621.663241786504;9883.604655813831;9613.375130616627;9646.469444180497;9811.018898110131;9815.852762208573;9853.655122070693;7260.636968151572;7335.633218339169;7343.265673432614;7396.926030739732;7419.85160296791;5279.551238144801;5387.973060134675;5392.029434701792;5488.807891723771;5591.245181900392;8953.059928707653;8993.370464067453;9014.819703606023;9031.909680903107;9037.638494460149;8534.487930724177;8877.511736854534;8907.866382004404;9051.683191088281;9220.815583688154;6174.135621012972;6411.262704788823;6646.368594983663;6826.453883689393;7031.08595882532;7534.133574638987;7712.961435193012;7727.92272077272;7742.380609551335;7810.57041862703;6717.294684058299;7070.292970703094;7094.290570943071;7166.677499512639;7213.134423869169;6935.791926242019;7011.544249623758;7056.576605403692;7103.431725462054;7142.714291428285;6457.644829534421;6518.185226846645;6534.379233972765;6554.80335589933;6557.114789503356;4597.7241365518175;4793.808247670054;4858.125537403302;4875.341828853059;4886.780094895667;4846.127697014571;4859.2954021667365;4902.436219834764;4909.95090049095;4911.656184067224 10:07:47 INFO - PROCESS | 1408 | |4;prototype.html;309.8559169985958;315.9889403870864;320.94383482890487;324.97562682798787;326.4140867143477;5199.818006369778;5338;5429.619926605134;5450.972745136274;5475.795325028493;5420.864478388043;5421.159720243359;5425.511703946644;5428.647137936031;5433.293671822667;4196.559361267054;4228.864423466161;4233.174530966455;4236.597523235303;4247.617714405703;4151.481064866892;4237.364395340693;4239.130978149472;4252.468441444819;4269.658427325822;4156.438880751105;4189.37159426087;4191.455110835603;4217.114405974749;4217.40956266123;4195.895102622444;4197.601227883346;4199.517055538607;4230.830766769325;4234.649769377994;3740.139767853409;3741.1769410729594;3749.268892565951;3764.3976963685677;3767.416050512175;4942.481039490837;5231;5231.110711179109;5235.528802407802;5246.842594722164;4747.525247475225;4792.113459010095;4799.472058073609;4807;4813.350197723297;5410.675359478444;5725.139329717858;5751.856203594902;5782.161586569966;5790.826275211751;3991.960080399188;3997.6813448200264;4001.559828418872;4011.338129208677;4022.9195416091807;5969.641821490724;6041.456268935817;6042.193418146583;6052.9697351513405;6077.574569780118;3993.1614360983936;4099.590040995877;4100.601148328202;4112.732672376286;4119.361498967605;4156.355764856452;4177.624013838708;4179.937300940488;4180.268453020709;4193.811278492475;4784.545468180518;4788.449328327218;4795.304680821261;4803.663743537919;4809.182438985381;5102.580973913248;5732.16883551891;5734.856628584319;5748.885022299531;5807.5063619591965;1419.950301739434;1447.8769304609016;1449.7173051254904;1454.810874586297;1455.5269537400388;935.5415846235296;947.6162154327509;974.7173319737338;974.8001659659752;979.9412035277907;4935.531124543163;4936.136176169228;4941.308216849644;4952.5542701157065;4958.925616115761;5910.763569457259;5966.164736936832;5969.611975221596;5974.4324289192455;5974.701264936736;4682.929756053662;4914.680545764514;4931.7040977541465;4949.084419382426;4974.801007959641;3187.87248510057;3210.5184222366365;3222.9194270142966;3233.4341490239585;3233.8383080845865;5621.269234999424;5622.184783206412;5632.408597097327;5632.85917852057;5634.464725851038;5974.85062873423;6141.785037523665;6180.969095154495;6183.319834818167;6187.628742275478;5569.470900264469;5589.552835773128;5592.888142237214;5604.663720176803;5619.943800561942;5677.858053548694;5919.91120133207;5934.258217722785;5975.462208401264;5986.054532277114;5873.94126058751;5884.6469211847425;5889.705514724333;5911.940880591139;5913.526917846648;5578.972105139448;5604.169894203401;5609.411011843697;5615.550755939434;5631.155326701028;4030.959690403176;4089.9795501022304;4099.487564054493;4119.958800411958;4134.235166494208;5671.914921276101;5767.826965191051;5925.288965324016;5930.525557955267;5966.642001479751;5740.138979152994;5746.367899531132;5776.9133462998925;5781.364049954417;5782.421757824184;5725.828225153252;5763.279590051244;5767.7981270657;5775.476959054889;5795.536357091507;5542.7782888685615;5649.067903795828;5688.601797874109;5703.914441283301;5709.200711900255;5757;5760.596758226884;5808.564357673107;5817.621854579439;5827.271591051119;5498.45015498447;5644.745986430538;5672.376038635829;5686.152000599739;5689.7439615218245 10:07:47 INFO - PROCESS | 1408 | |5;yui.html;639.3191251317345;649.3214590752664;757.4205732614547;772.3164998975905;774.2025713515078;10933.945330273347;11407.885921140796;11541.711457213563;11558.884411155886;11576.305421674706;11391.886081139186;11470.770584588303;11481.368524731137;11913.166078374517;12212.877871221284;635.6027482823235;800.2237829305593;801.7875263055295;806;809.1827254472965;790.3637571754736;813.5444151275304;816.0126247240846;817.0971076959933;817.9100298967109;852.7271273192581;853.257665830725;853.3002937591177;854.5428195915187;855.6620135046653;838.0278876503257;841.3563623827777;850.0606829453488;850.1456036683094;850.3962186847345;612.097156693873;615.062030403638;616.808789275326;619.4084649160086;619.7211254935252;10857.022867941923;10875;10890.292131011538;10890.564377424893;10891.509882055327;12713.682157946032;12857.357132143356;12897.87102128976;12970;13001.089923705344;9736.464494452803;9771.706848794547;9840.852387214198;9909.801803963881;10152.137068349199;3323;3384.4923261510726;3427.520147179397;3441.48377743338;4176.686748493875;41325.173496529904;41404.585954140675;41485;42019;42066.57933420657;9552.522373881417;9714.805703885882;9909.504524773733;9930.106290433896;9940.502974851228;16523.421680241136;16575.336986520644;16833.494995150166;16945.23746431389;17026.063566503715;6744.291849355845;6837.62393068386;6850.588964662136;6912.792616221522;6948.513604047668;3810.6951443884423;3886.067343837515;4009.0979529605606;4026.8187931543152;4029.37544680575;2771.0716909835432;2841.346490307241;2855.0435604072454;2861.48493271209;2868.856557172133;514.1721827857147;514.7503460821498;526.9473052694776;531.665051017864;545.4109561673383;486.77851577532135;569.2571194591098;572.3360901354409;579;622.4988883948374;7685.385169186452;7739.845203096019;7881;7964.840703185904;7980;640.276487569044;645.0775391190642;650.5511197273865;657.8849000463533;671.8253254153857;3723.478712980185;3887.125396785757;3926.7251292409255;3998.9200215996098;4045.91908161841;51207.74396127996;52405.737971310664;53083.73458132762;53085.46914530882;53174.468255316955;37573.81213093917;38141.427878581846;43594.56405435905;45694.771526142154;46577.534224658;38566;39239.60760392417;40258.597414025484;40788;41360.58639413628;8025.317847982753;8229.341652667652;8314.667413303641;8359.456635318686;8428.367872409717;27851;28089;28433.147005589864;28606;28627.570586440805;36329;38183;38336.42495362516;38394.23211535809;38886;10725;10783.352998820095;10835.67492975212;11042.061424778984;11144.777104457704;11486.253393529394;11873.584424545099;11874.76250475003;11895.167338286237;12094.153409261618;11314.830277546009;11558.94220528892;11725.296482211095;11839.526418943145;12003.699907502383;11099.50052247667;11334.319940803578;11499.8275025873;11746.29522228669;11782.175247732577;11065;11362.318260904372;11400.885991139983;11401.486933088197;11502.42487875586;9248.213901818151;9262.907370926205;9474.95262523683;9586.089321514444;9940.950295248478;9388.202002829836;9458.29062820305;9483.762905927408;9488.715338539854;9545.093216144454 10:07:47 INFO - PROCESS | 1408 | __end_tp_report 10:07:47 INFO - PROCESS | 1408 | __start_cc_report 10:07:47 INFO - PROCESS | 1408 | _x_x_mozilla_cycle_collect,397 10:07:47 INFO - PROCESS | 1408 | __end_cc_report 10:07:47 INFO - PROCESS | 1408 | __startTimestamp1461431267378__endTimestamp 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | ------- Summary: start ------- 10:07:47 INFO - PROCESS | 1408 | Number of tests: 6 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | [#0] dojo.html Cycles:165 Average:4768.07 Median:4886.71 stddev:492.18 (10.1%) stddev-sans-first:464.56 10:07:47 INFO - PROCESS | 1408 | Values: 2641.5 2690.1 2732.9 2735.6 2736.4 4813.7 4827.7 4840.3 4841.8 4906.3 4754.2 4822.0 4857.2 4949.4 4964.1 4393.9 4419.8 4469.3 4475.9 4511.8 4419.0 4455.6 4507.6 4529.5 4532.1 4365.0 4367.9 4407.8 4429.7 4486.5 4407.6 4418.2 4444.4 4454.8 4501.0 4422.5 4422.5 4422.6 4422.9 4449.2 4598.1 4728.1 4742.1 4766.0 4769.5 5131.2 5137.5 5141.5 5143.5 5165.1 5121.6 5178.7 5180.7 5183.2 5201.3 4806.6 4854.7 4855.2 4913.9 4919.2 5242.3 5309.2 5312.4 5318.5 5365.5 4956.2 5045.2 5062.4 5080.3 5083.4 5163.0 5170.4 5185.8 5216.8 5244.2 5026.1 5105.9 5123.5 5124.7 5126.1 5026.1 5056.6 5136.4 5150.2 5181.9 4301.2 4437.7 4454.8 4457.0 4502.8 3772.3 3828.3 3832.1 3867.5 3872.3 4413.2 4448.0 4448.9 4461.2 4490.6 5122.1 5181.7 5210.4 5213.7 5219.8 4466.6 4470.1 4476.3 4492.6 4523.5 4614.2 4689.3 4695.5 4698.1 4742.9 4747.9 4794.8 4857.5 4884.7 4893.7 5148.3 5166.9 5187.2 5189.3 5189.8 4826.0 4828.6 4830.8 4886.7 4897.6 5003.7 5086.1 5133.7 5174.2 5199.0 5090.2 5102.1 5102.8 5109.8 5175.9 4907.4 4966.7 4989.8 4993.3 5014.6 4892.0 4954.4 5024.4 5062.1 5065.5 4825.6 4828.0 4850.2 4877.3 4937.5 5053.5 5061.2 5077.8 5082.5 5111.6 4879.4 4913.3 4922.3 4957.3 4970.7 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | [#1] ext.html Cycles:180 Average:45734.07 Median:36006.73 stddev:82490.57 (229.1%) stddev-sans-first:82652.29 10:07:47 INFO - PROCESS | 1408 | Values: 573.1 593.3 613.3 614.6 641.0 34605.8 36001.0 36012.5 36107.6 36181.3 39648.4 40280.4 40299.8 40408.8 40475.4 7695.7 7848.2 7852.5 7853.9 7885.0 7725.2 7862.4 7908.0 7911.8 7929.3 7852.1 7889.0 7907.8 7932.2 7939.2 7778.5 7875.9 7879.6 7887.0 7903.6 7800.0 7810.3 7865.1 7874.1 7877.9 30401.7 36664.6 37252.8 37286.1 37335.5 3066.9 3168.6 3204.3 3341.9 3376.9 27751.0 28791.3 28932.0 29230.3 29668.0 3342.4 3365.8 3387.9 3501.0 3507.5 251528.0 258841.0 259585.0 262329.0 262420.7 33600.0 34846.1 34873.5 58778.7 59297.4 35615.3 35688.3 35981.3 36022.0 36161.5 12133.8 12250.0 12252.4 12334.4 12337.9 10161.6 10205.6 10263.0 10267.4 10318.6 8399.0 8401.5 8466.3 8479.7 8525.1 3332.9 3371.8 3372.1 3392.1 3415.5 936.8 953.9 961.0 979.6 1092.9 28995.3 29348.1 29409.4 29444.0 29741.4 996.7 1020.0 1056.3 1078.3 1088.7 15197.5 15374.8 15394.7 15560.7 15755.6 350882.0 360594.0 360730.0 361589.0 362354.0 50771.0 50806.7 51665.7 51693.7 51769.2 1265.9 1274.6 1277.6 1285.0 1285.1 27144.9 28417.9 28601.3 28921.1 29169.3 43875.0 44417.8 44438.8 44635.3 44722.0 307642.0 312818.0 316087.0 316960.0 317109.0 26483.5 26643.3 26879.0 26929.0 26969.5 43503.8 43563.8 43890.6 44204.3 45030.8 36554.8 37189.3 37283.0 37606.6 38059.2 34313.7 36724.0 36793.4 37106.4 37626.4 36258.6 36396.0 36476.5 37231.4 37397.4 31983.5 32558.1 32841.0 33212.3 33502.3 25266.6 25441.6 25863.5 25913.7 26422.0 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | [#2] jquery.html Cycles:180 Average:24269.04 Median:5116.26 stddev:66237.24 (1294.6%) stddev-sans-first:66398.77 10:07:47 INFO - PROCESS | 1408 | Values: 381.2 381.2 384.1 386.7 401.9 15218.3 16682.7 17082.0 17133.2 17247.4 14279.4 14785.4 14901.4 14904.9 14909.6 4983.5 5121.5 5175.5 5181.9 5183.5 3725.1 5588.7 5917.2 5935.9 5937.3 5717.1 5890.2 5898.7 5904.4 5908.7 5820.2 5837.0 5857.7 5874.1 5881.1 5111.0 5176.0 5214.6 5215.8 5338.7 20023.5 20273.7 20409.0 20448.5 20651.4 1907.9 2015.3 2029.6 2064.3 2310.3 13054.3 13143.8 13195.3 13327.2 13424.4 1762.7 1776.6 1798.3 1837.8 1853.2 70316.0 70961.3 71147.6 72057.0 72093.3 9472.4 9514.4 9829.4 9879.3 9941.3 13802.8 13902.8 13921.0 13925.6 13938.1 6896.1 6959.5 6968.3 7141.4 7215.0 6483.5 6637.1 6655.5 6743.1 6744.5 6039.0 6075.9 6106.3 6117.6 6118.1 1656.3 1747.3 1852.9 1876.8 1889.8 873.5 889.2 892.5 1011.8 1047.8 14350.7 15027.5 16802.5 17009.8 17272.7 908.3 943.5 945.7 959.4 989.4 9699.5 9740.2 9819.8 9886.9 9925.5 390686.0 402963.0 405408.0 405740.0 406997.4 69661.0 72304.3 73044.0 73248.0 73756.6 904.2 955.7 963.4 964.2 998.5 13935.3 14940.6 15061.8 15143.0 15202.8 28929.3 29483.4 29892.0 32436.8 33454.3 44100.0 46009.5 48003.0 48153.5 48182.0 7720.6 7733.4 7804.3 8029.6 8123.8 10304.2 11167.3 11289.2 11351.9 11428.0 4633.8 5633.6 9458.5 9974.7 10345.6 9959.0 10068.2 10301.9 10468.6 10478.6 10094.0 10524.9 10662.6 10785.4 10959.3 7763.9 7823.8 7865.4 8037.9 8042.8 8379.7 8439.5 8540.6 8650.3 8656.0 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | [#3] mootools.html Cycles:180 Average:5241.19 Median:5449.85 stddev:2891.05 (53.0%) stddev-sans-first:2885.23 10:07:47 INFO - PROCESS | 1408 | Values: 1464.4 1483.0 1662.6 1786.2 1829.1 6924.5 7611.8 7654.6 7945.7 7952.4 7629.1 7635.0 7641.0 7662.9 7713.8 2186.0 2196.2 2198.2 2217.3 2221.3 2123.4 2141.4 2168.7 2171.2 2181.6 2127.3 2163.7 2167.1 2170.6 2194.4 2137.8 2138.2 2156.4 2158.4 2169.2 2145.2 2145.6 2168.9 2170.9 2171.8 7813.0 7905.6 7921.3 7941.2 7945.1 4579.8 4757.8 4772.2 4779.5 4790.9 2928.8 3033.6 3081.0 3109.2 3110.5 926.9 936.7 951.9 954.1 961.3 10853.9 10893.1 10920.6 11014.4 11031.2 4240.6 4265.8 4449.1 4458.6 4461.4 9211.7 9235.9 9250.9 9263.4 9272.0 4779.0 4804.4 4821.5 4822.7 4831.3 3854.0 4172.1 4301.2 4345.2 4366.4 3665.7 3669.2 3728.4 3759.1 3812.9 1242.3 1251.8 1255.5 1270.1 1272.5 648.5 759.6 887.2 893.2 925.5 5396.8 5421.3 5426.2 5473.5 5656.2 848.0 861.4 935.8 954.4 980.1 3745.0 3816.6 3870.9 3876.1 4001.6 9159.5 9499.9 9585.1 9621.7 9883.6 9613.4 9646.5 9811.0 9815.9 9853.7 7260.6 7335.6 7343.3 7396.9 7419.9 5279.6 5388.0 5392.0 5488.8 5591.2 8953.1 8993.4 9014.8 9031.9 9037.6 8534.5 8877.5 8907.9 9051.7 9220.8 6174.1 6411.3 6646.4 6826.5 7031.1 7534.1 7713.0 7727.9 7742.4 7810.6 6717.3 7070.3 7094.3 7166.7 7213.1 6935.8 7011.5 7056.6 7103.4 7142.7 6457.6 6518.2 6534.4 6554.8 6557.1 4597.7 4793.8 4858.1 4875.3 4886.8 4846.1 4859.3 4902.4 4910.0 4911.7 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | [#4] prototype.html Cycles:180 Average:4746.61 Median:5423.34 stddev:1397.58 (25.8%) stddev-sans-first:1361.25 10:07:47 INFO - PROCESS | 1408 | Values: 309.9 316.0 320.9 325.0 326.4 5199.8 5338.0 5429.6 5451.0 5475.8 5420.9 5421.2 5425.5 5428.6 5433.3 4196.6 4228.9 4233.2 4236.6 4247.6 4151.5 4237.4 4239.1 4252.5 4269.7 4156.4 4189.4 4191.5 4217.1 4217.4 4195.9 4197.6 4199.5 4230.8 4234.6 3740.1 3741.2 3749.3 3764.4 3767.4 4942.5 5231.0 5231.1 5235.5 5246.8 4747.5 4792.1 4799.5 4807.0 4813.4 5410.7 5725.1 5751.9 5782.2 5790.8 3992.0 3997.7 4001.6 4011.3 4022.9 5969.6 6041.5 6042.2 6053.0 6077.6 3993.2 4099.6 4100.6 4112.7 4119.4 4156.4 4177.6 4179.9 4180.3 4193.8 4784.5 4788.4 4795.3 4803.7 4809.2 5102.6 5732.2 5734.9 5748.9 5807.5 1420.0 1447.9 1449.7 1454.8 1455.5 935.5 947.6 974.7 974.8 979.9 4935.5 4936.1 4941.3 4952.6 4958.9 5910.8 5966.2 5969.6 5974.4 5974.7 4682.9 4914.7 4931.7 4949.1 4974.8 3187.9 3210.5 3222.9 3233.4 3233.8 5621.3 5622.2 5632.4 5632.9 5634.5 5974.9 6141.8 6181.0 6183.3 6187.6 5569.5 5589.6 5592.9 5604.7 5619.9 5677.9 5919.9 5934.3 5975.5 5986.1 5873.9 5884.6 5889.7 5911.9 5913.5 5579.0 5604.2 5609.4 5615.6 5631.2 4031.0 4090.0 4099.5 4120.0 4134.2 5671.9 5767.8 5925.3 5930.5 5966.6 5740.1 5746.4 5776.9 5781.4 5782.4 5725.8 5763.3 5767.8 5775.5 5795.5 5542.8 5649.1 5688.6 5703.9 5709.2 5757.0 5760.6 5808.6 5817.6 5827.3 5498.5 5644.7 5672.4 5686.2 5689.7 10:07:47 INFO - PROCESS | 1408 | 10:07:47 INFO - PROCESS | 1408 | [#5] yui.html Cycles:180 Average:12393.11 Median:23097.84 stddev:13709.52 (59.4%) stddev-sans-first:13719.56 10:07:47 INFO - PROCESS | 1408 | Values: 639.3 649.3 757.4 772.3 774.2 10933.9 11407.9 11541.7 11558.9 11576.3 11391.9 11470.8 11481.4 11913.2 12212.9 635.6 800.2 801.8 806.0 809.2 790.4 813.5 816.0 817.1 817.9 852.7 853.3 853.3 854.5 855.7 838.0 841.4 850.1 850.1 850.4 612.1 615.1 616.8 619.4 619.7 10857.0 10875.0 10890.3 10890.6 10891.5 12713.7 12857.4 12897.9 12970.0 13001.1 9736.5 9771.7 9840.9 9909.8 10152.1 3323.0 3384.5 3427.5 3441.5 4176.7 41325.2 41404.6 41485.0 42019.0 42066.6 9552.5 9714.8 9909.5 9930.1 9940.5 16523.4 16575.3 16833.5 16945.2 17026.1 6744.3 6837.6 6850.6 6912.8 6948.5 3810.7 3886.1 4009.1 4026.8 4029.4 2771.1 2841.3 2855.0 2861.5 2868.9 514.2 514.8 526.9 531.7 545.4 486.8 569.3 572.3 579.0 622.5 7685.4 7739.8 7881.0 7964.8 7980.0 640.3 645.1 650.6 657.9 671.8 3723.5 3887.1 3926.7 3998.9 4045.9 51207.7 52405.7 53083.7 53085.5 53174.5 37573.8 38141.4 43594.6 45694.8 46577.5 38566.0 39239.6 40258.6 40788.0 41360.6 8025.3 8229.3 8314.7 8359.5 8428.4 27851.0 28089.0 28433.1 28606.0 28627.6 36329.0 38183.0 38336.4 38394.2 38886.0 10725.0 10783.4 10835.7 11042.1 11144.8 11486.3 11873.6 11874.8 11895.2 12094.2 11314.8 11558.9 11725.3 11839.5 12003.7 11099.5 11334.3 11499.8 11746.3 11782.2 11065.0 11362.3 11400.9 11401.5 11502.4 9248.2 9262.9 9475.0 9586.1 9941.0 9388.2 9458.3 9483.8 9488.7 9545.1 10:07:47 INFO - PROCESS | 1408 | -------- Summary: end -------- 10:07:47 INFO - PROCESS | 1408 | 10:07:48 INFO - TEST-INFO | 1408: exit 0 10:07:48 INFO - TEST-OK | dromaeo_css | took 1115955ms 10:07:48 INFO - TEST-START | kraken 10:07:48 INFO - operating with platform_type : linux_ 10:07:48 INFO - Initialising browser for kraken test... 10:07:48 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUHmVjQ/profile http://localhost:41891/getInfo.html) 10:07:52 INFO - PROCESS | 1639 | 10:07:52 INFO - PROCESS | 1639 | (process:1705): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 10:07:52 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 10:07:52 INFO - PROCESS | 1639 | colorDepth:24 10:07:52 INFO - PROCESS | 1639 | Browser inner width/height: 1024/697 10:07:52 INFO - PROCESS | 1639 | __metrics 10:07:53 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 10:07:54 INFO - TEST-INFO | 1639: exit 0 10:07:54 INFO - Browser initialized. 10:07:54 INFO - Running cycle 1/1 for kraken test... 10:07:54 INFO - TEST-INFO | started process 1756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUHmVjQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:07:56 INFO - PROCESS | 1756 | 10:07:56 INFO - PROCESS | 1756 | (process:1817): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 10:08:06 INFO - PROCESS | 1756 | RSS: Main: 166031360 10:08:06 INFO - PROCESS | 1756 | 10:08:09 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41891/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:10 INFO - PROCESS | 1756 | RSS: Main: 170307584 10:08:10 INFO - PROCESS | 1756 | 10:08:12 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41891/tests/kraken/driver.html?testName=audio-dft) 10:08:13 INFO - PROCESS | 1756 | RSS: Main: 173629440 10:08:13 INFO - PROCESS | 1756 | 10:08:16 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41891/tests/kraken/driver.html?testName=audio-fft) 10:08:17 INFO - PROCESS | 1756 | RSS: Main: 164790272 10:08:17 INFO - PROCESS | 1756 | 10:08:19 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41891/tests/kraken/driver.html?testName=audio-oscillator) 10:08:19 INFO - PROCESS | 1756 | RSS: Main: 165404672 10:08:19 INFO - PROCESS | 1756 | 10:08:22 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41891/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:22 INFO - PROCESS | 1756 | RSS: Main: 165638144 10:08:22 INFO - PROCESS | 1756 | 10:08:25 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41891/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:26 INFO - PROCESS | 1756 | RSS: Main: 165953536 10:08:26 INFO - PROCESS | 1756 | 10:08:30 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41891/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:30 INFO - PROCESS | 1756 | RSS: Main: 165666816 10:08:30 INFO - PROCESS | 1756 | 10:08:33 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41891/tests/kraken/driver.html?testName=json-parse-financial) 10:08:34 INFO - PROCESS | 1756 | RSS: Main: 166236160 10:08:34 INFO - PROCESS | 1756 | 10:08:35 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41891/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:36 INFO - PROCESS | 1756 | RSS: Main: 166424576 10:08:36 INFO - PROCESS | 1756 | 10:08:38 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:39 INFO - PROCESS | 1756 | RSS: Main: 166342656 10:08:39 INFO - PROCESS | 1756 | 10:08:41 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:41 INFO - PROCESS | 1756 | RSS: Main: 166486016 10:08:41 INFO - PROCESS | 1756 | 10:08:44 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:45 INFO - PROCESS | 1756 | RSS: Main: 166879232 10:08:45 INFO - PROCESS | 1756 | 10:08:48 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:08:49 INFO - PROCESS | 1756 | RSS: Main: 167161856 10:08:49 INFO - PROCESS | 1756 | 10:08:50 INFO - PROCESS | 1756 | Cycle 1(1): loaded http://localhost:41891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41891/tests/kraken/driver.html?testName=ai-astar) 10:08:51 INFO - PROCESS | 1756 | RSS: Main: 168501248 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | __start_tp_report 10:08:51 INFO - PROCESS | 1756 | _x_x_mozilla_page_load 10:08:51 INFO - PROCESS | 1756 | _x_x_mozilla_page_load_details 10:08:51 INFO - PROCESS | 1756 | |i|pagename|runs| 10:08:51 INFO - PROCESS | 1756 | |0;ai-astar;116;109;113;115;116;126;113;112;114;114 10:08:51 INFO - PROCESS | 1756 | |1;audio-beat-detection;157;136;162;188;135;163;158;136;194;134 10:08:51 INFO - PROCESS | 1756 | |2;audio-dft;162;164;163;164;170;163;163;165;166;163 10:08:51 INFO - PROCESS | 1756 | |3;audio-fft;77;78;77;158;78;75;74;76;155;74 10:08:51 INFO - PROCESS | 1756 | |4;audio-oscillator;94;95;148;94;95;95;159;93;94;141 10:08:51 INFO - PROCESS | 1756 | |5;imaging-gaussian-blur;117;118;118;118;118;121;117;118;117;118 10:08:51 INFO - PROCESS | 1756 | |6;imaging-darkroom;148;148;146;148;147;148;148;147;147;148 10:08:51 INFO - PROCESS | 1756 | |7;imaging-desaturate;96;95;95;96;94;94;96;96;97;96 10:08:51 INFO - PROCESS | 1756 | |8;json-parse-financial;66;67;65;67;71;67;70;86;65;64 10:08:51 INFO - PROCESS | 1756 | |9;json-stringify-tinderbox;64;65;64;63;64;66;65;64;65;65 10:08:51 INFO - PROCESS | 1756 | |10;stanford-crypto-aes;90;80;80;79;80;81;80;80;80;89 10:08:51 INFO - PROCESS | 1756 | |11;stanford-crypto-ccm;133;131;136;138;143;142;143;133;172;138 10:08:51 INFO - PROCESS | 1756 | |12;stanford-crypto-pbkdf2;177;184;177;174;180;180;173;171;172;171 10:08:51 INFO - PROCESS | 1756 | |13;stanford-crypto-sha256-iterative;73;74;73;81;73;71;73;72;70;71 10:08:51 INFO - PROCESS | 1756 | __end_tp_report 10:08:51 INFO - PROCESS | 1756 | __start_cc_report 10:08:51 INFO - PROCESS | 1756 | _x_x_mozilla_cycle_collect,938 10:08:51 INFO - PROCESS | 1756 | __end_cc_report 10:08:51 INFO - PROCESS | 1756 | __startTimestamp1461431331068__endTimestamp 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | ------- Summary: start ------- 10:08:51 INFO - PROCESS | 1756 | Number of tests: 14 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#0] ai-astar Cycles:10 Average:114.80 Median:114.50 stddev:4.44 (3.9%) stddev-sans-first:4.69 10:08:51 INFO - PROCESS | 1756 | Values: 116.0 109.0 113.0 115.0 116.0 126.0 113.0 112.0 114.0 114.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#1] audio-beat-detection Cycles:10 Average:156.30 Median:160.00 stddev:21.81 (13.6%) stddev-sans-first:23.13 10:08:51 INFO - PROCESS | 1756 | Values: 157.0 136.0 162.0 188.0 135.0 163.0 158.0 136.0 194.0 134.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:2.31 (1.4%) stddev-sans-first:2.30 10:08:51 INFO - PROCESS | 1756 | Values: 162.0 164.0 163.0 164.0 170.0 163.0 163.0 165.0 166.0 163.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#3] audio-fft Cycles:10 Average:92.20 Median:76.50 stddev:33.93 (44.3%) stddev-sans-first:35.54 10:08:51 INFO - PROCESS | 1756 | Values: 77.0 78.0 77.0 158.0 78.0 75.0 74.0 76.0 155.0 74.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#4] audio-oscillator Cycles:10 Average:110.80 Median:94.00 stddev:26.94 (28.7%) stddev-sans-first:27.88 10:08:51 INFO - PROCESS | 1756 | Values: 94.0 95.0 148.0 94.0 95.0 95.0 159.0 93.0 94.0 141.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:1.15 (1.0%) stddev-sans-first:1.17 10:08:51 INFO - PROCESS | 1756 | Values: 117.0 118.0 118.0 118.0 118.0 121.0 117.0 118.0 117.0 118.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.71 (0.5%) stddev-sans-first:0.73 10:08:51 INFO - PROCESS | 1756 | Values: 148.0 148.0 146.0 148.0 147.0 148.0 148.0 147.0 147.0 148.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 10:08:51 INFO - PROCESS | 1756 | Values: 96.0 95.0 95.0 96.0 94.0 94.0 96.0 96.0 97.0 96.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:67.00 stddev:6.43 (9.6%) stddev-sans-first:6.74 10:08:51 INFO - PROCESS | 1756 | Values: 66.0 67.0 65.0 67.0 71.0 67.0 70.0 86.0 65.0 64.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:65.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 10:08:51 INFO - PROCESS | 1756 | Values: 64.0 65.0 64.0 63.0 64.0 66.0 65.0 64.0 65.0 65.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:80.00 stddev:4.04 (5.1%) stddev-sans-first:3.04 10:08:51 INFO - PROCESS | 1756 | Values: 90.0 80.0 80.0 79.0 80.0 81.0 80.0 80.0 80.0 89.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:140.00 stddev:11.74 (8.4%) stddev-sans-first:12.10 10:08:51 INFO - PROCESS | 1756 | Values: 133.0 131.0 136.0 138.0 143.0 142.0 143.0 133.0 172.0 138.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:177.00 stddev:4.43 (2.5%) stddev-sans-first:4.68 10:08:51 INFO - PROCESS | 1756 | Values: 177.0 184.0 177.0 174.0 180.0 180.0 173.0 171.0 172.0 171.0 10:08:51 INFO - PROCESS | 1756 | 10:08:51 INFO - PROCESS | 1756 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.00 stddev:3.03 (4.2%) stddev-sans-first:3.22 10:08:51 INFO - PROCESS | 1756 | Values: 73.0 74.0 73.0 81.0 73.0 71.0 73.0 72.0 70.0 71.0 10:08:51 INFO - PROCESS | 1756 | -------- Summary: end -------- 10:08:51 INFO - PROCESS | 1756 | 10:08:52 INFO - TEST-INFO | 1756: exit 0 10:08:52 INFO - TEST-OK | kraken | took 64040ms 10:08:52 INFO - SUITE-END | took 1179s 10:08:52 INFO - Completed test suite (00:19:40) 10:08:52 INFO - javascript benchmark 10:08:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2641.511320405725, 2690.1122629532256, 2732.9453410931783, 2735.63068985687, 2736.370634754005, 4813.711177329363, 4827.734474603895, 4840.273958906161, 4841.8305359312435, 4906.264060390943, 4754.239321708527, 4821.975890120544, 4857.174280372336, 4949.381327334083, 4964.106460837048, 4393.93409098863, 4419.845305414311, 4469.3296005599095, 4475.932861007088, 4511.819527218908, 4418.97790511047, 4455.554444555551, 4507.594316511513, 4529.479109902354, 4532.138893610204, 4365.0, 4367.890802729926, 4407.845725399628, 4429.712068715539, 4486.528914463967, 4407.603315701603, 4418.160549495586, 4444.444444444444, 4454.754988475633, 4501.0, 4422.469303683579, 4422.535633758441, 4422.601965823092, 4422.911541769179, 4449.17690227309, 4598.149342371672, 4728.148933192024, 4742.099001189763, 4765.952340476586, 4769.475357710649, 5131.153359695646, 5137.486251374833, 5141.511556402136, 5143.511366420222, 5165.147750621143, 5121.641485096045, 5178.689278643294, 5180.689158650455, 5183.222516622537, 5201.297824793643, 4806.639502037361, 4854.660173787872, 4855.174620314555, 4913.926291105637, 4919.2129259318335, 5242.292290540804, 5309.150535914235, 5312.415634280226, 5318.521333080042, 5365.517103460629, 4956.207006878882, 5045.2432135180015, 5062.417821950449, 5080.314157588752, 5083.389993200765, 5163.0, 5170.431252562215, 5185.766640501187, 5216.765245563883, 5244.213367994831, 5026.095302845523, 5105.897882042338, 5123.487651234846, 5124.666896651705, 5126.128558145124, 5026.1204289249235, 5056.646034777604, 5136.383633963948, 5150.201718733602, 5181.948180518222, 4301.225779359683, 4437.711548749321, 4454.821807127679, 4456.955430445654, 4502.819887204475, 3772.3398405279513, 3828.272628200633, 3832.080300727805, 3867.477890484805, 3872.3223435899167, 4413.183561041154, 4448.0, 4448.933266001012, 4461.24158892989, 4490.640748740093, 5122.078025955289, 5181.715005674649, 5210.400803907523, 5213.713245771443, 5219.791208351623, 4466.64266858657, 4470.1059788041875, 4476.306172543261, 4492.640588752892, 4523.547645235516, 4614.215583350839, 4689.320048592935, 4695.5069717679835, 4698.060387922429, 4742.881427964259, 4747.881302967454, 4794.808207671654, 4857.53853383928, 4884.65807393479, 4893.657443979029, 5148.30497882796, 5166.922496162636, 5187.221916712523, 5189.273501709839, 5189.792408303626, 4825.9758701207675, 4828.637852161031, 4830.81644996978, 4886.706797592155, 4897.583705385082, 5003.749812509287, 5086.109930762176, 5133.666311689728, 5174.172132458789, 5198.9740051299505, 5090.185570308733, 5102.08162530748, 5102.821401250939, 5109.79560817578, 5175.922361164661, 4907.411110666742, 4966.652334336562, 4989.750512474435, 4993.27597498368, 5014.598832093351, 4892.0, 4954.430240522386, 5024.447310795883, 5062.088824011713, 5065.544101030925, 4825.589824864785, 4828.0, 4850.17547017015, 4877.268409738567, 4937.530934561211, 5053.4946505350645, 5061.240813877947, 5077.7968881245815, 5082.542571168613, 5111.5910727141, 4879.39007624047, 4913.287573301923, 4922.2616607507725, 4957.330760347307, 4970.726610036482], "value": 4737.054914493299, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.0515996026575, 593.3147214966714, 613.3069631316614, 614.6127939398178, 641.0384423364952, 34605.82697086514, 36000.99999999996, 36012.45981310279, 36107.63892361076, 36181.276374472494, 39648.405273920915, 40280.39579406304, 40299.798501007455, 40408.797956010254, 40475.39286910699, 7695.6921723131145, 7848.175941526143, 7852.489588176779, 7853.8821917671285, 7885.0, 7725.18885517021, 7862.449628526006, 7907.960460197691, 7911.8417631647335, 7929.286364227219, 7852.05775306964, 7889.0, 7907.802304942365, 7932.246436588515, 7939.1663875293125, 7778.455508114434, 7875.881861772078, 7879.606019699021, 7887.0, 7903.565303908283, 7800.0, 7810.258025487569, 7865.056193256789, 7874.05511338643, 7877.881831772528, 30401.69598304033, 36664.63335366639, 37252.813735931144, 37286.06784830374, 37335.45313195242, 3066.861991210401, 3168.6197656281165, 3204.279037216631, 3341.8830340938193, 3376.898693039213, 27751.0, 28791.280217994507, 28932.0, 29230.269243268875, 29668.0, 3342.4484959981573, 3365.7980521168565, 3387.915302117442, 3501.0197144799495, 3507.5440192774777, 251528.0, 258841.0, 259585.0, 262329.0, 262420.6878965612, 33600.0, 34846.12884677904, 34873.47689784655, 58778.70610647005, 59297.40702593005, 35615.28769424597, 35688.28623427517, 35981.280374392365, 36022.0, 36161.457578136346, 12133.817992730115, 12250.0, 12252.387380631111, 12334.444949977273, 12337.876621233852, 10161.577379166922, 10205.591776329013, 10263.0, 10267.383956962607, 10318.587256509656, 8399.0, 8401.537915414588, 8466.32269418445, 8479.745607631781, 8525.062243153248, 3332.900012999614, 3371.8314084296176, 3372.106391806173, 3392.1010932103013, 3415.470602056685, 936.7892224249489, 953.8759961205137, 960.9759756006156, 979.637534112383, 1092.8961748633867, 28995.275118121794, 29348.11955641334, 29409.411811763646, 29444.0, 29741.40517189644, 996.6926565001997, 1019.9643012494527, 1056.271172890703, 1078.2937176149635, 1088.6842815583393, 15197.468088616846, 15374.769378459332, 15394.692106157814, 15560.688786224438, 15755.606109847116, 350882.0, 360594.0, 360730.0, 361589.0, 362354.0, 50771.0, 50806.74596626993, 51665.741671291406, 51693.7415312921, 51769.22346164811, 1265.9050571207197, 1274.566647339909, 1277.5847849448967, 1285.0490636928605, 1285.126114242324, 27144.864275678494, 28417.933123104194, 28601.28496787597, 28921.132366029055, 29169.270768230963, 43875.0, 44417.777911110235, 44438.77780611076, 44635.33047004363, 44722.0, 307642.0, 312818.0, 316087.0, 316960.0, 317109.0, 26483.470330593667, 26643.33391665224, 26879.0, 26929.0, 26969.460610787282, 43503.78248108739, 43563.78218108889, 43890.56109438865, 44204.34773913575, 45030.77484612556, 36554.8172259137, 37189.25621487609, 37283.0, 37606.62393376031, 38059.2388152241, 34313.65686343204, 36724.0, 36793.448098278015, 37106.44340334843, 37626.43560346652, 36258.637413625525, 36396.0, 36476.45285320775, 37231.44152837764, 37397.43903841499, 31983.520247196775, 32558.139534884063, 32841.0, 33212.33575328528, 33502.32995340031, 25266.621000685373, 25441.61837572475, 25863.482730345662, 25913.740862591887, 26422.0], "value": 16669.03565872951, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.193775165525, 381.2045266793404, 384.1241968312247, 386.7061033614453, 401.89148929788973, 15218.32598414175, 16682.66634667306, 17082.01650153555, 17133.22900469479, 17247.396341128064, 14279.357428915699, 14785.408583656641, 14901.40394384226, 14904.925475372635, 14909.627259318522, 4983.451820299764, 5121.5390614844655, 5175.534201921826, 5181.948180518184, 5183.53348198662, 3725.068732816796, 5588.748506317205, 5917.201177841001, 5935.910961335583, 5937.287525496925, 5717.142428635696, 5890.175375447441, 5898.734556944948, 5904.409559044104, 5908.704564771766, 5820.185174075633, 5836.97081514594, 5857.70711464425, 5874.060150375941, 5881.117832325164, 5110.9744451277875, 5176.0, 5214.556762675177, 5215.765290561933, 5338.706371149585, 20023.49941251466, 20273.69589456159, 20409.0, 20448.488787780276, 20651.380458586267, 1907.8664493485462, 2015.254355888316, 2029.5839352932614, 2064.277502873997, 2310.330004298751, 13054.2820144892, 13143.802842957364, 13195.2742599157, 13327.200367977855, 13424.395902184327, 1762.7444020617068, 1776.644671065792, 1798.2537247042592, 1837.843783278423, 1853.203122657257, 70316.0, 70961.2903870965, 71147.64426177887, 72057.0, 72093.2790672097, 9472.384295020802, 9514.429134251966, 9829.361091529028, 9879.259055570861, 9941.304108712466, 13802.792958105636, 13902.791458128137, 13921.0, 13925.582232533041, 13938.094023888414, 6896.137982752156, 6959.478039147084, 6968.338007889344, 7141.428685705131, 7214.96392518034, 6483.546151769425, 6637.137172167587, 6655.534112612071, 6743.055972163901, 6744.494162937799, 6038.969805150946, 6075.878482430327, 6106.267247930188, 6117.571769976059, 6118.112873633388, 1656.3209084275388, 1747.2748809244276, 1852.8610354223217, 1876.7747870255657, 1889.7826749923934, 873.4628203654834, 889.2041622747605, 892.5314210039782, 1011.7723512209694, 1047.7904419116055, 14350.712985740227, 15027.474038408604, 16802.495925122264, 17009.829901700825, 17272.740908886375, 908.3096846396786, 943.4716558727134, 945.7162851144767, 959.4387283439266, 989.366805244644, 9699.51502424876, 9740.220782337396, 9819.75450613726, 9886.901130988597, 9925.496399574726, 390686.0, 402963.0, 405408.0, 405740.0, 406997.4052205536, 69661.0, 72304.27695722975, 73044.0, 73248.0, 73756.63121684357, 904.1907492794044, 955.7085089047769, 963.4026903319847, 964.2189826240767, 998.4658207858906, 13935.30323483842, 14940.626484337978, 15061.849381506045, 15143.015703979207, 15202.771958420411, 28929.276768080967, 29483.41033179367, 29892.0, 32436.83781581077, 33454.33091338208, 44100.0, 46009.539904600526, 48003.0, 48153.518464816305, 48182.0, 7720.575368354795, 7733.419993500623, 7804.336631386395, 8029.638666259915, 8123.79690507742, 10304.208543027527, 11167.274127181707, 11289.153313501685, 11351.943240283746, 11428.0, 4633.8146474142, 5633.63381380209, 9458.527073646428, 9974.700758977242, 10345.63790267337, 9958.950205248928, 10068.194544436574, 10301.896981030093, 10468.581256749645, 10478.63324783629, 10094.0, 10524.894751052392, 10662.626808061681, 10785.352878826981, 10959.342439453658, 7763.893111887508, 7823.765287041398, 7865.4494185406475, 8037.879431808645, 8042.798930026796, 8379.70671026511, 8439.493630382196, 8540.572971351283, 8650.307975362082, 8656.0478347383], "value": 8261.86791845611, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.3703207620727, 1482.9851701482985, 1662.617597952471, 1786.2319202742815, 1829.0580351119177, 6924.480663950211, 7611.771646850603, 7654.617269136542, 7945.72189973351, 7952.363810895129, 7629.084509858824, 7635.0, 7641.0, 7662.923370766291, 7713.7685869424, 2186.0, 2196.2203417786695, 2198.208644887839, 2217.2904670505445, 2221.3447033125185, 2123.4054464749815, 2141.4218161096496, 2168.7072245246927, 2171.1966572368246, 2181.6291230490774, 2127.319257837493, 2163.653815389538, 2167.100653228916, 2170.630992731232, 2194.36363454598, 2137.8289736821016, 2138.2088627207954, 2156.4069880782754, 2158.363282831561, 2169.197396963118, 2145.2062736787334, 2145.6459684152096, 2168.913243470259, 2170.934871953844, 2171.8045375916245, 7812.960935195288, 7905.565193914333, 7921.287084162453, 7941.166177551332, 7945.086315073725, 4579.771011449414, 4757.833475828329, 4772.16487114757, 4779.545943135397, 4790.856274311742, 2928.824270543753, 3033.5752994580776, 3080.9845950770327, 3109.23823663204, 3110.522501811644, 926.8609708543705, 936.6674830435165, 951.9428834269896, 954.1079091049891, 961.2886464016648, 10853.891461085366, 10893.128549716004, 10920.563177472892, 11014.39420831854, 11031.172662050298, 4240.597143271384, 4265.808038638269, 4449.132419178294, 4458.5987261146, 4461.353103799931, 9211.677591284273, 9235.861462078074, 9250.86123708145, 9263.351565390492, 9271.953640231755, 4779.0, 4804.447488538793, 4821.54195351441, 4822.71063736177, 4831.251156070814, 3854.0, 4172.144710334434, 4301.2257793597455, 4345.196138714348, 4366.432363792686, 3665.706743460517, 3669.229461812996, 3728.3848165052464, 3759.0602349412648, 3812.9237415252096, 1242.310517662706, 1251.768422841759, 1255.4727014653868, 1270.0601554849345, 1272.4782839035952, 648.4617767253169, 759.6050053971903, 887.2281115429489, 893.2050475077186, 925.5233554719325, 5396.83809485716, 5421.2681288025615, 5426.158945363475, 5473.534749546333, 5656.1798539212405, 847.9872801907976, 861.4271509446148, 935.7520257131865, 954.370115723619, 980.122790102854, 3745.0, 3816.637419445148, 3870.903227419337, 3876.108495046155, 4001.619846114673, 9159.450432974043, 9499.857502137473, 9585.137337639646, 9621.663241786504, 9883.604655813831, 9613.375130616627, 9646.469444180497, 9811.018898110131, 9815.852762208573, 9853.655122070693, 7260.636968151572, 7335.633218339169, 7343.265673432614, 7396.926030739732, 7419.85160296791, 5279.551238144801, 5387.973060134675, 5392.029434701792, 5488.807891723771, 5591.245181900392, 8953.059928707653, 8993.370464067453, 9014.819703606023, 9031.909680903107, 9037.638494460149, 8534.487930724177, 8877.511736854534, 8907.866382004404, 9051.683191088281, 9220.815583688154, 6174.135621012972, 6411.262704788823, 6646.368594983663, 6826.453883689393, 7031.08595882532, 7534.133574638987, 7712.961435193012, 7727.92272077272, 7742.380609551335, 7810.57041862703, 6717.294684058299, 7070.292970703094, 7094.290570943071, 7166.677499512639, 7213.134423869169, 6935.791926242019, 7011.544249623758, 7056.576605403692, 7103.431725462054, 7142.714291428285, 6457.644829534421, 6518.185226846645, 6534.379233972765, 6554.80335589933, 6557.114789503356, 4597.7241365518175, 4793.808247670054, 4858.125537403302, 4875.341828853059, 4886.780094895667, 4846.127697014571, 4859.2954021667365, 4902.436219834764, 4909.95090049095, 4911.656184067224], "value": 4240.464245232228, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.8559169985958, 315.9889403870864, 320.94383482890487, 324.97562682798787, 326.4140867143477, 5199.818006369778, 5338.0, 5429.619926605134, 5450.972745136274, 5475.795325028493, 5420.864478388043, 5421.159720243359, 5425.511703946644, 5428.647137936031, 5433.293671822667, 4196.559361267054, 4228.864423466161, 4233.174530966455, 4236.597523235303, 4247.617714405703, 4151.481064866892, 4237.364395340693, 4239.130978149472, 4252.468441444819, 4269.658427325822, 4156.438880751105, 4189.37159426087, 4191.455110835603, 4217.114405974749, 4217.40956266123, 4195.895102622444, 4197.601227883346, 4199.517055538607, 4230.830766769325, 4234.649769377994, 3740.139767853409, 3741.1769410729594, 3749.268892565951, 3764.3976963685677, 3767.416050512175, 4942.481039490837, 5231.0, 5231.110711179109, 5235.528802407802, 5246.842594722164, 4747.525247475225, 4792.113459010095, 4799.472058073609, 4807.0, 4813.350197723297, 5410.675359478444, 5725.139329717858, 5751.856203594902, 5782.161586569966, 5790.826275211751, 3991.960080399188, 3997.6813448200264, 4001.559828418872, 4011.338129208677, 4022.9195416091807, 5969.641821490724, 6041.456268935817, 6042.193418146583, 6052.9697351513405, 6077.574569780118, 3993.1614360983936, 4099.590040995877, 4100.601148328202, 4112.732672376286, 4119.361498967605, 4156.355764856452, 4177.624013838708, 4179.937300940488, 4180.268453020709, 4193.811278492475, 4784.545468180518, 4788.449328327218, 4795.304680821261, 4803.663743537919, 4809.182438985381, 5102.580973913248, 5732.16883551891, 5734.856628584319, 5748.885022299531, 5807.5063619591965, 1419.950301739434, 1447.8769304609016, 1449.7173051254904, 1454.810874586297, 1455.5269537400388, 935.5415846235296, 947.6162154327509, 974.7173319737338, 974.8001659659752, 979.9412035277907, 4935.531124543163, 4936.136176169228, 4941.308216849644, 4952.5542701157065, 4958.925616115761, 5910.763569457259, 5966.164736936832, 5969.611975221596, 5974.4324289192455, 5974.701264936736, 4682.929756053662, 4914.680545764514, 4931.7040977541465, 4949.084419382426, 4974.801007959641, 3187.87248510057, 3210.5184222366365, 3222.9194270142966, 3233.4341490239585, 3233.8383080845865, 5621.269234999424, 5622.184783206412, 5632.408597097327, 5632.85917852057, 5634.464725851038, 5974.85062873423, 6141.785037523665, 6180.969095154495, 6183.319834818167, 6187.628742275478, 5569.470900264469, 5589.552835773128, 5592.888142237214, 5604.663720176803, 5619.943800561942, 5677.858053548694, 5919.91120133207, 5934.258217722785, 5975.462208401264, 5986.054532277114, 5873.94126058751, 5884.6469211847425, 5889.705514724333, 5911.940880591139, 5913.526917846648, 5578.972105139448, 5604.169894203401, 5609.411011843697, 5615.550755939434, 5631.155326701028, 4030.959690403176, 4089.9795501022304, 4099.487564054493, 4119.958800411958, 4134.235166494208, 5671.914921276101, 5767.826965191051, 5925.288965324016, 5930.525557955267, 5966.642001479751, 5740.138979152994, 5746.367899531132, 5776.9133462998925, 5781.364049954417, 5782.421757824184, 5725.828225153252, 5763.279590051244, 5767.7981270657, 5775.476959054889, 5795.536357091507, 5542.7782888685615, 5649.067903795828, 5688.601797874109, 5703.914441283301, 5709.200711900255, 5757.0, 5760.596758226884, 5808.564357673107, 5817.621854579439, 5827.271591051119, 5498.45015498447, 5644.745986430538, 5672.376038635829, 5686.152000599739, 5689.7439615218245], "value": 4297.404208310735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.3191251317345, 649.3214590752664, 757.4205732614547, 772.3164998975905, 774.2025713515078, 10933.945330273347, 11407.885921140796, 11541.711457213563, 11558.884411155886, 11576.305421674706, 11391.886081139186, 11470.770584588303, 11481.368524731137, 11913.166078374517, 12212.877871221284, 635.6027482823235, 800.2237829305593, 801.7875263055295, 806.0, 809.1827254472965, 790.3637571754736, 813.5444151275304, 816.0126247240846, 817.0971076959933, 817.9100298967109, 852.7271273192581, 853.257665830725, 853.3002937591177, 854.5428195915187, 855.6620135046653, 838.0278876503257, 841.3563623827777, 850.0606829453488, 850.1456036683094, 850.3962186847345, 612.097156693873, 615.062030403638, 616.808789275326, 619.4084649160086, 619.7211254935252, 10857.022867941923, 10875.0, 10890.292131011538, 10890.564377424893, 10891.509882055327, 12713.682157946032, 12857.357132143356, 12897.87102128976, 12970.0, 13001.089923705344, 9736.464494452803, 9771.706848794547, 9840.852387214198, 9909.801803963881, 10152.137068349199, 3323.0, 3384.4923261510726, 3427.520147179397, 3441.48377743338, 4176.686748493875, 41325.173496529904, 41404.585954140675, 41485.0, 42019.0, 42066.57933420657, 9552.522373881417, 9714.805703885882, 9909.504524773733, 9930.106290433896, 9940.502974851228, 16523.421680241136, 16575.336986520644, 16833.494995150166, 16945.23746431389, 17026.063566503715, 6744.291849355845, 6837.62393068386, 6850.588964662136, 6912.792616221522, 6948.513604047668, 3810.6951443884423, 3886.067343837515, 4009.0979529605606, 4026.8187931543152, 4029.37544680575, 2771.0716909835432, 2841.346490307241, 2855.0435604072454, 2861.48493271209, 2868.856557172133, 514.1721827857147, 514.7503460821498, 526.9473052694776, 531.665051017864, 545.4109561673383, 486.77851577532135, 569.2571194591098, 572.3360901354409, 579.0, 622.4988883948374, 7685.385169186452, 7739.845203096019, 7881.0, 7964.840703185904, 7980.0, 640.276487569044, 645.0775391190642, 650.5511197273865, 657.8849000463533, 671.8253254153857, 3723.478712980185, 3887.125396785757, 3926.7251292409255, 3998.9200215996098, 4045.91908161841, 51207.74396127996, 52405.737971310664, 53083.73458132762, 53085.46914530882, 53174.468255316955, 37573.81213093917, 38141.427878581846, 43594.56405435905, 45694.771526142154, 46577.534224658, 38566.0, 39239.60760392417, 40258.597414025484, 40788.0, 41360.58639413628, 8025.317847982753, 8229.341652667652, 8314.667413303641, 8359.456635318686, 8428.367872409717, 27851.0, 28089.0, 28433.147005589864, 28606.0, 28627.570586440805, 36329.0, 38183.0, 38336.42495362516, 38394.23211535809, 38886.0, 10725.0, 10783.352998820095, 10835.67492975212, 11042.061424778984, 11144.777104457704, 11486.253393529394, 11873.584424545099, 11874.76250475003, 11895.167338286237, 12094.153409261618, 11314.830277546009, 11558.94220528892, 11725.296482211095, 11839.526418943145, 12003.699907502383, 11099.50052247667, 11334.319940803578, 11499.8275025873, 11746.29522228669, 11782.175247732577, 11065.0, 11362.318260904372, 11400.885991139983, 11401.486933088197, 11502.42487875586, 9248.213901818151, 9262.907370926205, 9474.95262523683, 9586.089321514444, 9940.950295248478, 9388.202002829836, 9458.29062820305, 9483.762905927408, 9488.715338539854, 9545.093216144454], "value": 5907.381714869099, "unit": "score"}], "name": "dromaeo_css", "value": 6423.310251519133, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 113.0, 115.0, 116.0, 126.0, 113.0, 112.0, 114.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 136.0, 162.0, 188.0, 135.0, 163.0, 158.0, 136.0, 194.0, 134.0], "value": 156.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 163.0, 164.0, 170.0, 163.0, 163.0, 165.0, 166.0, 163.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 158.0, 78.0, 75.0, 74.0, 76.0, 155.0, 74.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 148.0, 94.0, 95.0, 95.0, 159.0, 93.0, 94.0, 141.0], "value": 110.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 118.0, 118.0, 121.0, 117.0, 118.0, 117.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 146.0, 148.0, 147.0, 148.0, 148.0, 147.0, 147.0, 148.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 94.0, 94.0, 96.0, 96.0, 97.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 67.0, 71.0, 67.0, 70.0, 86.0, 65.0, 64.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 63.0, 64.0, 66.0, 65.0, 64.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 80.0, 80.0, 79.0, 80.0, 81.0, 80.0, 80.0, 80.0, 89.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 136.0, 138.0, 143.0, 142.0, 143.0, 133.0, 172.0, 138.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 184.0, 177.0, 174.0, 180.0, 180.0, 173.0, 171.0, 172.0, 171.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 81.0, 73.0, 71.0, 73.0, 72.0, 70.0, 71.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1604.5000000000002, "alertThreshold": 2.0}]} 10:08:52 INFO - Return code: 0 10:08:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:08:52 ERROR - # TBPL SUCCESS # 10:08:52 INFO - Running post-action listener: _resource_record_post_action 10:08:52 INFO - Running post-run listener: _resource_record_post_run 10:08:53 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506994688; Read time: 96; Write time: 473684 10:08:53 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 223539200; Read time: 0; Write time: 235668 10:08:53 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283455488; Read time: 96; Write time: 238016 10:08:53 INFO - Running post-run listener: _upload_blobber_files 10:08:53 INFO - Blob upload gear active. 10:08:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:08:53 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:08:53 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:08:53 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:08:53 INFO - (blobuploader) - INFO - Open directory for files ... 10:08:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:08:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:08:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:54 INFO - (blobuploader) - INFO - Done attempting. 10:08:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:08:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:08:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:55 INFO - (blobuploader) - INFO - Done attempting. 10:08:55 INFO - (blobuploader) - INFO - Iteration through files over. 10:08:55 INFO - Return code: 0 10:08:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:08:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:08:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3df055b0f5399062243996e6b37db14889346e60ffed2a80f726f6660dab0533a786e448c09975c92883af49df4b1c97661e8401139329a4ee3cdfd75dcdcfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f5e019dbee3b3098378515134f4b131a54b51667fd896bf796f14d386b773beaa4ec27fe979d6cf803a5ba963a80bd33713d7f70e6f6bc839f3e34642863f24"} 10:08:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:08:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:08:55 INFO - Contents: 10:08:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3df055b0f5399062243996e6b37db14889346e60ffed2a80f726f6660dab0533a786e448c09975c92883af49df4b1c97661e8401139329a4ee3cdfd75dcdcfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f5e019dbee3b3098378515134f4b131a54b51667fd896bf796f14d386b773beaa4ec27fe979d6cf803a5ba963a80bd33713d7f70e6f6bc839f3e34642863f24"} 10:08:55 INFO - Running post-run listener: copy_logs_to_upload_dir 10:08:55 INFO - Copying logs to upload dir... 10:08:55 INFO - mkdir: /builds/slave/test/build/upload/logs 10:08:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.107172 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-04-23 10:08:54.420007) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 10:08:54.423066) ========= 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=fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3df055b0f5399062243996e6b37db14889346e60ffed2a80f726f6660dab0533a786e448c09975c92883af49df4b1c97661e8401139329a4ee3cdfd75dcdcfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f5e019dbee3b3098378515134f4b131a54b51667fd896bf796f14d386b773beaa4ec27fe979d6cf803a5ba963a80bd33713d7f70e6f6bc839f3e34642863f24"} build_url:https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011080 build_url: 'https://queue.taskcluster.net/v1/task/Y9r0fL86QT-Tm1HTpHnfNw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3df055b0f5399062243996e6b37db14889346e60ffed2a80f726f6660dab0533a786e448c09975c92883af49df4b1c97661e8401139329a4ee3cdfd75dcdcfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f5e019dbee3b3098378515134f4b131a54b51667fd896bf796f14d386b773beaa4ec27fe979d6cf803a5ba963a80bd33713d7f70e6f6bc839f3e34642863f24"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 10:08:54.447092) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 10:08:54.447390) ========= 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=fa44defc139c4ba8574fe040000001db-1461424277.552580-1112295809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 10:08:54.529599) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-23 10:08:54.529941) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-23 10:08:54.530251) ========= ========= Total master_lag: 0.17 =========