builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1463390132.78 results: success (0) buildid: 20160515233005 builduid: 6950fae349ac4ce69728f18f313764ac revision: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.784823) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.785370) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.785748) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.803198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.803637) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-16 02:15:32-- 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% 49.7M=0s 2016-05-16 02:15:33 (49.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110899 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.928622) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.928972) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019527 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:32.979436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 02:15:32.980498) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 --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 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 --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=7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-16 02:15:33,197 truncating revision to first 12 chars 2016-05-16 02:15:33,197 Setting DEBUG logging. 2016-05-16 02:15:33,197 attempt 1/10 2016-05-16 02:15:33,197 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/668a9155cc8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 02:15:33,742 unpacking tar archive at: fx-team-668a9155cc8b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.152822 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 02:15:34.146809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:34.147157) ========= script_repo_revision: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:34.147592) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:34.147885) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 02:15:34.157905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-16 02:15:34.158225) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:15:34 INFO - MultiFileLogger online at 20160516 02:15:34 in /builds/slave/test 02:15:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:15:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:15:34 INFO - {'append_to_log': False, 02:15:34 INFO - 'base_work_dir': '/builds/slave/test', 02:15:34 INFO - 'blob_upload_branch': 'Fx-Team', 02:15:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:15:34 INFO - 'branch': 'Fx-Team', 02:15:34 INFO - 'buildbot_json_path': 'buildprops.json', 02:15:34 INFO - 'config_files': ('talos/linux_config.py',), 02:15:34 INFO - 'default_actions': ('clobber', 02:15:34 INFO - 'read-buildbot-config', 02:15:34 INFO - 'download-and-extract', 02:15:34 INFO - 'populate-webroot', 02:15:34 INFO - 'create-virtualenv', 02:15:34 INFO - 'install', 02:15:34 INFO - 'run-tests'), 02:15:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:15:34 INFO - 'download_minidump_stackwalk': True, 02:15:34 INFO - 'download_symbols': 'ondemand', 02:15:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:15:34 INFO - 'tooltool.py': '/tools/tooltool.py', 02:15:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:15:34 INFO - '/tools/misc-python/virtualenv.py')}, 02:15:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:15:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:15:34 INFO - 'installer_path': 'installer.exe', 02:15:34 INFO - 'log_level': 'info', 02:15:34 INFO - 'log_name': 'talos', 02:15:34 INFO - 'log_to_console': True, 02:15:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:15:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:15:34 INFO - 'opt_config_files': (), 02:15:34 INFO - 'pip_index': False, 02:15:34 INFO - 'sps_profile': False, 02:15:34 INFO - 'sps_profile_interval': 0, 02:15:34 INFO - 'suite': 'dromaeojs', 02:15:34 INFO - 'system_bits': '32', 02:15:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:15:34 INFO - 'title': 'talos-linux64-ix-005', 02:15:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:15:34 INFO - 'use_talos_json': True, 02:15:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:15:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:15:34 INFO - 'work_dir': 'build'} 02:15:34 INFO - ##### 02:15:34 INFO - ##### Running clobber step. 02:15:34 INFO - ##### 02:15:34 INFO - Running pre-action listener: _resource_record_pre_action 02:15:34 INFO - Running main action method: clobber 02:15:34 INFO - rmtree: /builds/slave/test/build 02:15:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:15:34 INFO - Running post-action listener: _resource_record_post_action 02:15:34 INFO - ##### 02:15:34 INFO - ##### Finished clobber step (success) 02:15:34 INFO - ##### 02:15:34 INFO - ##### 02:15:34 INFO - ##### Running read-buildbot-config step. 02:15:34 INFO - ##### 02:15:34 INFO - Running pre-action listener: _resource_record_pre_action 02:15:34 INFO - Running main action method: read_buildbot_config 02:15:34 INFO - Using buildbot properties: 02:15:34 INFO - { 02:15:34 INFO - "project": "", 02:15:34 INFO - "product": "firefox", 02:15:34 INFO - "script_repo_revision": "production", 02:15:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:15:34 INFO - "repository": "", 02:15:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 02:15:34 INFO - "buildid": "20160515233005", 02:15:34 INFO - "pgo_build": "True", 02:15:34 INFO - "basedir": "/builds/slave/test", 02:15:34 INFO - "buildnumber": 681, 02:15:34 INFO - "slavename": "talos-linux64-ix-005", 02:15:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:15:34 INFO - "platform": "ubuntu64_hw", 02:15:34 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 02:15:34 INFO - "branch": "fx-team", 02:15:34 INFO - "repo_path": "integration/fx-team", 02:15:34 INFO - "revision": "668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1", 02:15:34 INFO - "stage_platform": "linux64-pgo", 02:15:34 INFO - "builduid": "6950fae349ac4ce69728f18f313764ac", 02:15:34 INFO - "slavebuilddir": "test" 02:15:34 INFO - } 02:15:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 02:15:34 INFO - Running post-action listener: _resource_record_post_action 02:15:34 INFO - ##### 02:15:34 INFO - ##### Finished read-buildbot-config step (success) 02:15:34 INFO - ##### 02:15:34 INFO - ##### 02:15:34 INFO - ##### Running download-and-extract step. 02:15:34 INFO - ##### 02:15:34 INFO - Running pre-action listener: _resource_record_pre_action 02:15:34 INFO - Running main action method: download_and_extract 02:15:34 INFO - mkdir: /builds/slave/test/build/tests 02:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:34 INFO - https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:15:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:15:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:15:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:15:35 INFO - Downloaded 1361 bytes. 02:15:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:15:35 INFO - Using the following test package requirements: 02:15:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:15:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:15:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:15:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'jsshell-linux-x86_64.zip'], 02:15:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:15:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:15:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:15:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:15:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:15:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:15:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:15:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:15:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:35 INFO - https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:15:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:15:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:15:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:15:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:15:40 INFO - Downloaded 24023292 bytes. 02:15:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:15:42 INFO - Return code: 0 02:15:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:42 INFO - https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:15:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:15:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:15:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:15:46 INFO - Downloaded 24023292 bytes. 02:15:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:15:47 INFO - Return code: 0 02:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:47 INFO - https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:15:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:15:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:15:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:15:48 INFO - Downloaded 10840194 bytes. 02:15:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:15:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:15:48 INFO - Return code: 0 02:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:48 INFO - https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:15:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:15:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:15:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:15:50 INFO - Downloaded 58413495 bytes. 02:15:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:15:50 INFO - mkdir: /builds/slave/test/properties 02:15:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:15:50 INFO - Writing to file /builds/slave/test/properties/build_url 02:15:50 INFO - Contents: 02:15:50 INFO - build_url:https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:15:50 INFO - Running post-action listener: _resource_record_post_action 02:15:50 INFO - Running post-action listener: set_extra_try_arguments 02:15:50 INFO - ##### 02:15:50 INFO - ##### Finished download-and-extract step (success) 02:15:50 INFO - ##### 02:15:50 INFO - ##### 02:15:50 INFO - ##### Running populate-webroot step. 02:15:50 INFO - ##### 02:15:50 INFO - Running pre-action listener: _resource_record_pre_action 02:15:50 INFO - Running main action method: populate_webroot 02:15:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:15:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:15:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:15:50 INFO - '--tppagecycles', 02:15:50 INFO - '7'], 02:15:50 INFO - 'tests': ['tsvgm']}}, 02:15:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:15:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:15:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:15:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:15:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:15:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:15:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['damp', 'tps']}, 02:15:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'tests': ['damp', 'tps']}, 02:15:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['dromaeo_dom']}, 02:15:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:15:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['a11yr', 02:15:50 INFO - 'ts_paint', 02:15:50 INFO - 'tpaint', 02:15:50 INFO - 'sessionrestore', 02:15:50 INFO - 'sessionrestore_no_auto_restore', 02:15:50 INFO - 'tabpaint']}, 02:15:50 INFO - 'other-e10s': {'tests': ['a11yr', 02:15:50 INFO - 'ts_paint', 02:15:50 INFO - 'tpaint', 02:15:50 INFO - 'sessionrestore', 02:15:50 INFO - 'sessionrestore_no_auto_restore', 02:15:50 INFO - 'tabpaint']}, 02:15:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:15:50 INFO - 'ts_paint', 02:15:50 INFO - 'tpaint', 02:15:50 INFO - 'sessionrestore', 02:15:50 INFO - 'sessionrestore_no_auto_restore', 02:15:50 INFO - 'tabpaint']}, 02:15:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:15:50 INFO - 'ts_paint', 02:15:50 INFO - 'tpaint', 02:15:50 INFO - 'sessionrestore', 02:15:50 INFO - 'sessionrestore_no_auto_restore', 02:15:50 INFO - 'tabpaint']}, 02:15:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['a11yr', 02:15:50 INFO - 'ts_paint', 02:15:50 INFO - 'tpaint', 02:15:50 INFO - 'sessionrestore', 02:15:50 INFO - 'sessionrestore_no_auto_restore', 02:15:50 INFO - 'tabpaint']}, 02:15:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['a11yr', 02:15:50 INFO - 'ts_paint', 02:15:50 INFO - 'tpaint', 02:15:50 INFO - 'sessionrestore', 02:15:50 INFO - 'sessionrestore_no_auto_restore', 02:15:50 INFO - 'tabpaint']}, 02:15:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['tsvgx', 02:15:50 INFO - 'tsvgr_opacity', 02:15:50 INFO - 'tart', 02:15:50 INFO - 'tscrollx', 02:15:50 INFO - 'cart']}, 02:15:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:15:50 INFO - 'tsvgr_opacity', 02:15:50 INFO - 'tart', 02:15:50 INFO - 'tscrollx', 02:15:50 INFO - 'cart']}, 02:15:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'talos_options': ['--disable-e10s'], 02:15:50 INFO - 'tests': ['tp5o']}, 02:15:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'tests': ['tp5o']}, 02:15:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'talos_options': ['--disable-e10s', 02:15:50 INFO - '--xperf_path', 02:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:15:50 INFO - 'tests': ['tp5n']}, 02:15:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:15:50 INFO - 'talos_options': ['--xperf_path', 02:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:15:50 INFO - 'tests': ['tp5n']}}, 02:15:50 INFO - 'talos.zip': {'path': '', 02:15:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:15:50 INFO - Running post-action listener: _resource_record_post_action 02:15:50 INFO - ##### 02:15:50 INFO - ##### Finished populate-webroot step (success) 02:15:50 INFO - ##### 02:15:50 INFO - ##### 02:15:50 INFO - ##### Running create-virtualenv step. 02:15:50 INFO - ##### 02:15:50 INFO - Running pre-action listener: _resource_record_pre_action 02:15:50 INFO - Running main action method: create_virtualenv 02:15:50 INFO - Creating virtualenv /builds/slave/test/build/venv 02:15:50 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 02:15:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:15:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:15:51 INFO - Using real prefix '/usr' 02:15:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:15:51 INFO - Installing distribute.............................................................................................................................................................................................done. 02:15:53 INFO - Installing pip.................done. 02:15:53 INFO - Return code: 0 02:15:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:15:53 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')]} 02:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:53 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')]} 02:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:53 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 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:15:53 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 02:15:53 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 02:15:53 INFO - Using env: {'DISPLAY': ':0', 02:15:53 INFO - 'HOME': '/home/cltbld', 02:15:53 INFO - 'LANG': 'en_US.UTF-8', 02:15:53 INFO - 'LANGUAGE': 'en_US:en', 02:15:53 INFO - 'LOGNAME': 'cltbld', 02:15:53 INFO - 'MAIL': '/var/mail/cltbld', 02:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:53 INFO - 'MOZ_NO_REMOTE': '1', 02:15:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:15:53 INFO - 'NO_EM_RESTART': '1', 02:15:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:53 INFO - 'PWD': '/builds/slave/test', 02:15:53 INFO - 'SHELL': '/bin/bash', 02:15:53 INFO - 'SHLVL': '1', 02:15:53 INFO - 'TERM': 'linux', 02:15:53 INFO - 'TMOUT': '86400', 02:15:53 INFO - 'USER': 'cltbld', 02:15:53 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:53 INFO - '_': '/tools/buildbot/bin/python'} 02:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:53 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 02:15:53 INFO - Cleaning up... 02:15:53 INFO - Return code: 0 02:15:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:15:53 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')]} 02:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:53 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')]} 02:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:53 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:15:53 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>=3.1.1'] in /builds/slave/test/build 02:15:53 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>=3.1.1 02:15:53 INFO - Using env: {'DISPLAY': ':0', 02:15:53 INFO - 'HOME': '/home/cltbld', 02:15:53 INFO - 'LANG': 'en_US.UTF-8', 02:15:53 INFO - 'LANGUAGE': 'en_US:en', 02:15:53 INFO - 'LOGNAME': 'cltbld', 02:15:53 INFO - 'MAIL': '/var/mail/cltbld', 02:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:53 INFO - 'MOZ_NO_REMOTE': '1', 02:15:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:15:53 INFO - 'NO_EM_RESTART': '1', 02:15:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:53 INFO - 'PWD': '/builds/slave/test', 02:15:53 INFO - 'SHELL': '/bin/bash', 02:15:53 INFO - 'SHLVL': '1', 02:15:53 INFO - 'TERM': 'linux', 02:15:53 INFO - 'TMOUT': '86400', 02:15:53 INFO - 'USER': 'cltbld', 02:15:53 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:53 INFO - '_': '/tools/buildbot/bin/python'} 02:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:53 INFO - Downloading/unpacking psutil>=3.1.1 02:15: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 02:15: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 02:15: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 02:15: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 02:15: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 02:15: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 02:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:15:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:15:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:15:55 INFO - Installing collected packages: psutil 02:15:55 INFO - Running setup.py install for psutil 02:15:55 INFO - building 'psutil._psutil_linux' extension 02:15:55 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 02:15:56 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 02:15:56 INFO - building 'psutil._psutil_posix' extension 02:15:56 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 02:15:56 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 02:15:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:15:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:15:56 INFO - Successfully installed psutil 02:15:56 INFO - Cleaning up... 02:15:56 INFO - Return code: 0 02:15:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:56 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:15:56 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.3'] in /builds/slave/test/build 02:15:56 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.3 02:15:56 INFO - Using env: {'DISPLAY': ':0', 02:15:56 INFO - 'HOME': '/home/cltbld', 02:15:56 INFO - 'LANG': 'en_US.UTF-8', 02:15:56 INFO - 'LANGUAGE': 'en_US:en', 02:15:56 INFO - 'LOGNAME': 'cltbld', 02:15:56 INFO - 'MAIL': '/var/mail/cltbld', 02:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:56 INFO - 'MOZ_NO_REMOTE': '1', 02:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:15:56 INFO - 'NO_EM_RESTART': '1', 02:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:56 INFO - 'PWD': '/builds/slave/test', 02:15:56 INFO - 'SHELL': '/bin/bash', 02:15:56 INFO - 'SHLVL': '1', 02:15:56 INFO - 'TERM': 'linux', 02:15:56 INFO - 'TMOUT': '86400', 02:15:56 INFO - 'USER': 'cltbld', 02:15:56 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:56 INFO - '_': '/tools/buildbot/bin/python'} 02:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:15:56 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 02:15:56 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 02:15:56 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 02:15:56 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 02:15:56 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 02:15:56 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 02:15:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:15:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:15:58 INFO - Installing collected packages: mozsystemmonitor 02:15:58 INFO - Running setup.py install for mozsystemmonitor 02:15:58 INFO - Successfully installed mozsystemmonitor 02:15:58 INFO - Cleaning up... 02:15:58 INFO - Return code: 0 02:15:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:15:58 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')]} 02:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:58 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')]} 02:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:15:58 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 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:15:58 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 02:15:58 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 02:15:58 INFO - Using env: {'DISPLAY': ':0', 02:15:58 INFO - 'HOME': '/home/cltbld', 02:15:58 INFO - 'LANG': 'en_US.UTF-8', 02:15:58 INFO - 'LANGUAGE': 'en_US:en', 02:15:58 INFO - 'LOGNAME': 'cltbld', 02:15:58 INFO - 'MAIL': '/var/mail/cltbld', 02:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:58 INFO - 'MOZ_NO_REMOTE': '1', 02:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:15:58 INFO - 'NO_EM_RESTART': '1', 02:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:58 INFO - 'PWD': '/builds/slave/test', 02:15:58 INFO - 'SHELL': '/bin/bash', 02:15:58 INFO - 'SHLVL': '1', 02:15:58 INFO - 'TERM': 'linux', 02:15:58 INFO - 'TMOUT': '86400', 02:15:58 INFO - 'USER': 'cltbld', 02:15:58 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:58 INFO - '_': '/tools/buildbot/bin/python'} 02:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:15:58 INFO - Downloading/unpacking blobuploader==1.2.4 02:15:58 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 02:15:58 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 02:15:58 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 02:15:58 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 02:15:58 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 02:15:58 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 02:16:00 INFO - Downloading blobuploader-1.2.4.tar.gz 02:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:16:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:16:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:16:01 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 02:16:01 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 02:16:01 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 02:16:01 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 02:16:01 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 02:16:01 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 02:16:01 INFO - Downloading docopt-0.6.1.tar.gz 02:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:16:01 INFO - Installing collected packages: blobuploader, requests, docopt 02:16:01 INFO - Running setup.py install for blobuploader 02:16:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:16:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:16:01 INFO - Running setup.py install for requests 02:16:02 INFO - Running setup.py install for docopt 02:16:02 INFO - Successfully installed blobuploader requests docopt 02:16:02 INFO - Cleaning up... 02:16:02 INFO - Return code: 0 02:16:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:02 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 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:16:02 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 02:16:02 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 02:16:02 INFO - Using env: {'DISPLAY': ':0', 02:16:02 INFO - 'HOME': '/home/cltbld', 02:16:02 INFO - 'LANG': 'en_US.UTF-8', 02:16:02 INFO - 'LANGUAGE': 'en_US:en', 02:16:02 INFO - 'LOGNAME': 'cltbld', 02:16:02 INFO - 'MAIL': '/var/mail/cltbld', 02:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:02 INFO - 'MOZ_NO_REMOTE': '1', 02:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:02 INFO - 'NO_EM_RESTART': '1', 02:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:02 INFO - 'PWD': '/builds/slave/test', 02:16:02 INFO - 'SHELL': '/bin/bash', 02:16:02 INFO - 'SHLVL': '1', 02:16:02 INFO - 'TERM': 'linux', 02:16:02 INFO - 'TMOUT': '86400', 02:16:02 INFO - 'USER': 'cltbld', 02:16:02 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:02 INFO - '_': '/tools/buildbot/bin/python'} 02:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:02 INFO - Running setup.py (path:/tmp/pip-Vreeu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:02 INFO - Running setup.py (path:/tmp/pip-dVhMVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:02 INFO - Running setup.py (path:/tmp/pip-XGHigb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:02 INFO - Running setup.py (path:/tmp/pip-8pOc6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:02 INFO - Running setup.py (path:/tmp/pip-mqACQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:02 INFO - Running setup.py (path:/tmp/pip-J72anC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:02 INFO - Running setup.py (path:/tmp/pip-Hm5oWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:02 INFO - Running setup.py (path:/tmp/pip-L13YNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:02 INFO - Running setup.py (path:/tmp/pip-CypYCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:03 INFO - Running setup.py (path:/tmp/pip-3tb0WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:03 INFO - Running setup.py (path:/tmp/pip-kDHHqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:03 INFO - Running setup.py (path:/tmp/pip-WoBqsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:03 INFO - Running setup.py (path:/tmp/pip-gR6l79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:03 INFO - Running setup.py (path:/tmp/pip-zqji1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:03 INFO - Running setup.py (path:/tmp/pip-MRDcVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:03 INFO - Running setup.py (path:/tmp/pip-Jdn9KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:03 INFO - Running setup.py (path:/tmp/pip-oq9ILR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:16:03 INFO - Running setup.py install for manifestparser 02:16:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:16:03 INFO - Running setup.py install for mozcrash 02:16:03 INFO - Running setup.py install for mozdebug 02:16:03 INFO - Running setup.py install for mozdevice 02:16:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Running setup.py install for mozfile 02:16:04 INFO - Running setup.py install for mozhttpd 02:16:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Running setup.py install for mozinfo 02:16:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Running setup.py install for mozInstall 02:16:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Running setup.py install for mozleak 02:16:04 INFO - Running setup.py install for mozlog 02:16:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Running setup.py install for moznetwork 02:16:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Running setup.py install for mozprocess 02:16:04 INFO - Running setup.py install for mozprofile 02:16:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:16:04 INFO - Running setup.py install for mozrunner 02:16:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:16:05 INFO - Running setup.py install for mozscreenshot 02:16:05 INFO - Running setup.py install for moztest 02:16:05 INFO - Running setup.py install for mozversion 02:16:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:16:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:16:05 INFO - Cleaning up... 02:16:05 INFO - Return code: 0 02:16:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:05 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 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:16:05 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 02:16:05 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 02:16:05 INFO - Using env: {'DISPLAY': ':0', 02:16:05 INFO - 'HOME': '/home/cltbld', 02:16:05 INFO - 'LANG': 'en_US.UTF-8', 02:16:05 INFO - 'LANGUAGE': 'en_US:en', 02:16:05 INFO - 'LOGNAME': 'cltbld', 02:16:05 INFO - 'MAIL': '/var/mail/cltbld', 02:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:05 INFO - 'MOZ_NO_REMOTE': '1', 02:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:05 INFO - 'NO_EM_RESTART': '1', 02:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:05 INFO - 'PWD': '/builds/slave/test', 02:16:05 INFO - 'SHELL': '/bin/bash', 02:16:05 INFO - 'SHLVL': '1', 02:16:05 INFO - 'TERM': 'linux', 02:16:05 INFO - 'TMOUT': '86400', 02:16:05 INFO - 'USER': 'cltbld', 02:16:05 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:05 INFO - '_': '/tools/buildbot/bin/python'} 02:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:05 INFO - Running setup.py (path:/tmp/pip-5L4UkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:05 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)) 02:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:05 INFO - Running setup.py (path:/tmp/pip-TPKsOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:05 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)) 02:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:05 INFO - Running setup.py (path:/tmp/pip-cnQgjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:05 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)) 02:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:05 INFO - Running setup.py (path:/tmp/pip-CtMfI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:05 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)) 02:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:05 INFO - Running setup.py (path:/tmp/pip-6Ut0pH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:05 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)) 02:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:05 INFO - Running setup.py (path:/tmp/pip-cSefG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:06 INFO - Running setup.py (path:/tmp/pip-m975w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:06 INFO - Running setup.py (path:/tmp/pip-k7JFFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:06 INFO - Running setup.py (path:/tmp/pip-rBEyca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:06 INFO - Running setup.py (path:/tmp/pip-u09I_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:06 INFO - Running setup.py (path:/tmp/pip-I33XGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:06 INFO - Running setup.py (path:/tmp/pip-zsJhve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:06 INFO - Running setup.py (path:/tmp/pip-bncuok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:06 INFO - Running setup.py (path:/tmp/pip-GrZdFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:06 INFO - Running setup.py (path:/tmp/pip-ajsy0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:06 INFO - Running setup.py (path:/tmp/pip-MkYS2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:06 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)) 02:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:06 INFO - Running setup.py (path:/tmp/pip-GTP2Se-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:06 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)) 02:16:06 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)) 02:16:06 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)) 02:16:06 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)) 02:16:06 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)) 02:16:06 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)) 02:16:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:08 INFO - Downloading blessings-1.6.tar.gz 02:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:16:08 INFO - Installing collected packages: blessings 02:16:08 INFO - Running setup.py install for blessings 02:16:09 INFO - Successfully installed blessings 02:16:09 INFO - Cleaning up... 02:16:09 INFO - Return code: 0 02:16:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:09 INFO - Reading from file tmpfile_stdout 02:16:09 INFO - Current package versions: 02:16:09 INFO - argparse == 1.2.1 02:16:09 INFO - blessings == 1.6 02:16:09 INFO - blobuploader == 1.2.4 02:16:09 INFO - docopt == 0.6.1 02:16:09 INFO - manifestparser == 1.1 02:16:09 INFO - mozInstall == 1.12 02:16:09 INFO - mozcrash == 0.17 02:16:09 INFO - mozdebug == 0.1 02:16:09 INFO - mozdevice == 0.48 02:16:09 INFO - mozfile == 1.2 02:16:09 INFO - mozhttpd == 0.7 02:16:09 INFO - mozinfo == 0.9 02:16:09 INFO - mozleak == 0.1 02:16:09 INFO - mozlog == 3.2 02:16:09 INFO - moznetwork == 0.27 02:16:09 INFO - mozprocess == 0.22 02:16:09 INFO - mozprofile == 0.28 02:16:09 INFO - mozrunner == 6.11 02:16:09 INFO - mozscreenshot == 0.1 02:16:09 INFO - mozsystemmonitor == 0.3 02:16:09 INFO - moztest == 0.7 02:16:09 INFO - mozversion == 1.4 02:16:09 INFO - psutil == 3.1.1 02:16:09 INFO - requests == 1.2.3 02:16:09 INFO - wsgiref == 0.1.2 02:16:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:09 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 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:16:09 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 02:16:09 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 02:16:09 INFO - Using env: {'DISPLAY': ':0', 02:16:09 INFO - 'HOME': '/home/cltbld', 02:16:09 INFO - 'LANG': 'en_US.UTF-8', 02:16:09 INFO - 'LANGUAGE': 'en_US:en', 02:16:09 INFO - 'LOGNAME': 'cltbld', 02:16:09 INFO - 'MAIL': '/var/mail/cltbld', 02:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:09 INFO - 'MOZ_NO_REMOTE': '1', 02:16:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:09 INFO - 'NO_EM_RESTART': '1', 02:16:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:09 INFO - 'PWD': '/builds/slave/test', 02:16:09 INFO - 'SHELL': '/bin/bash', 02:16:09 INFO - 'SHLVL': '1', 02:16:09 INFO - 'TERM': 'linux', 02:16:09 INFO - 'TMOUT': '86400', 02:16:09 INFO - 'USER': 'cltbld', 02:16:09 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:09 INFO - '_': '/tools/buildbot/bin/python'} 02:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 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)) 02:16:09 INFO - Cleaning up... 02:16:09 INFO - Return code: 0 02:16:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:16:09 INFO - Using env: {'DISPLAY': ':0', 02:16:09 INFO - 'HOME': '/home/cltbld', 02:16:09 INFO - 'LANG': 'en_US.UTF-8', 02:16:09 INFO - 'LANGUAGE': 'en_US:en', 02:16:09 INFO - 'LOGNAME': 'cltbld', 02:16:09 INFO - 'MAIL': '/var/mail/cltbld', 02:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:09 INFO - 'MOZ_NO_REMOTE': '1', 02:16:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:09 INFO - 'NO_EM_RESTART': '1', 02:16:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:09 INFO - 'PWD': '/builds/slave/test', 02:16:09 INFO - 'SHELL': '/bin/bash', 02:16:09 INFO - 'SHLVL': '1', 02:16:09 INFO - 'TERM': 'linux', 02:16:09 INFO - 'TMOUT': '86400', 02:16:09 INFO - 'USER': 'cltbld', 02:16:09 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:09 INFO - '_': '/tools/buildbot/bin/python'} 02:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:09 INFO - Downloading/unpacking jsonschema 02:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:16:11 INFO - Downloading/unpacking functools32 (from jsonschema) 02:16:11 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 02:16:11 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 02:16:11 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 02:16:11 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 02:16:11 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 02:16:11 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 02:16:11 INFO - Downloading functools32-3.2.3-2.tar.gz 02:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:16:11 INFO - warning: no files found matching '*.txt' 02:16:11 INFO - no previously-included directories found matching 'build' 02:16:11 INFO - no previously-included directories found matching 'dist' 02:16:11 INFO - no previously-included directories found matching '.git*' 02:16:11 INFO - Installing collected packages: jsonschema, functools32 02:16:11 INFO - Running setup.py install for functools32 02:16:12 INFO - warning: no files found matching '*.txt' 02:16:12 INFO - no previously-included directories found matching 'build' 02:16:12 INFO - no previously-included directories found matching 'dist' 02:16:12 INFO - no previously-included directories found matching '.git*' 02:16:12 INFO - Successfully installed jsonschema functools32 02:16:12 INFO - Cleaning up... 02:16:12 INFO - Return code: 0 02:16:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda02ecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29f9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, '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': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', '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 02:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:16:12 INFO - Using env: {'DISPLAY': ':0', 02:16:12 INFO - 'HOME': '/home/cltbld', 02:16:12 INFO - 'LANG': 'en_US.UTF-8', 02:16:12 INFO - 'LANGUAGE': 'en_US:en', 02:16:12 INFO - 'LOGNAME': 'cltbld', 02:16:12 INFO - 'MAIL': '/var/mail/cltbld', 02:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:12 INFO - 'MOZ_NO_REMOTE': '1', 02:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:12 INFO - 'NO_EM_RESTART': '1', 02:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:12 INFO - 'PWD': '/builds/slave/test', 02:16:12 INFO - 'SHELL': '/bin/bash', 02:16:12 INFO - 'SHLVL': '1', 02:16:12 INFO - 'TERM': 'linux', 02:16:12 INFO - 'TMOUT': '86400', 02:16:12 INFO - 'USER': 'cltbld', 02:16:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:12 INFO - '_': '/tools/buildbot/bin/python'} 02:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:12 INFO - Downloading/unpacking flake8 02:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:16:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:16:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:16:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:16:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:16:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:16:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:16:14 INFO - no previously-included directories found matching 'docs/_build' 02:16:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:16:15 INFO - Running setup.py install for pep8 02:16:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:16:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:16:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:16:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:16:15 INFO - no previously-included directories found matching 'docs/_build' 02:16:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:16:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:16:15 INFO - Cleaning up... 02:16:15 INFO - Return code: 0 02:16:15 INFO - Running post-action listener: _resource_record_post_action 02:16:15 INFO - Running post-action listener: _start_resource_monitoring 02:16:15 INFO - Starting resource monitoring. 02:16:15 INFO - ##### 02:16:15 INFO - ##### Finished create-virtualenv step (success) 02:16:15 INFO - ##### 02:16:15 INFO - ##### 02:16:15 INFO - ##### Running install step. 02:16:15 INFO - ##### 02:16:15 INFO - Running pre-action listener: _resource_record_pre_action 02:16:15 INFO - Running main action method: install 02:16:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:15 INFO - Reading from file tmpfile_stdout 02:16:15 INFO - Detecting whether we're running mozinstall >=1.0... 02:16:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:16:15 INFO - Reading from file tmpfile_stdout 02:16:15 INFO - Output received: 02:16:15 INFO - Usage: mozinstall [options] installer 02:16:15 INFO - Options: 02:16:15 INFO - -h, --help show this help message and exit 02:16:15 INFO - -d DEST, --destination=DEST 02:16:15 INFO - Directory to install application into. [default: 02:16:15 INFO - "/builds/slave/test"] 02:16:15 INFO - --app=APP Application being installed. [default: firefox] 02:16:15 INFO - mkdir: /builds/slave/test/build/application 02:16:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:16:32 INFO - Reading from file tmpfile_stdout 02:16:32 INFO - Output received: 02:16:32 INFO - /builds/slave/test/build/application/firefox/firefox 02:16:32 INFO - Running post-action listener: _resource_record_post_action 02:16:32 INFO - ##### 02:16:32 INFO - ##### Finished install step (success) 02:16:32 INFO - ##### 02:16:32 INFO - ##### 02:16:32 INFO - ##### Running run-tests step. 02:16:32 INFO - ##### 02:16:32 INFO - Running pre-action listener: _resource_record_pre_action 02:16:32 INFO - Running main action method: run_tests 02:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:16:32 INFO - Python 2.7.3 02:16:32 INFO - Return code: 0 02:16:32 INFO - grabbing minidump binary from tooltool 02:16:32 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')]} 02:16:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a889c0>, '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 0x2a898c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a89d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:16:32 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 02:16:32 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 02:16:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:16:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:16:32 INFO - Return code: 0 02:16:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:16:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:16:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:16:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:16:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:16:33 INFO - Return code: 0 02:16:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:16:33 INFO - Using env: {'DISPLAY': ':0', 02:16:33 INFO - 'HOME': '/home/cltbld', 02:16:33 INFO - 'LANG': 'en_US.UTF-8', 02:16:33 INFO - 'LANGUAGE': 'en_US:en', 02:16:33 INFO - 'LOGNAME': 'cltbld', 02:16:33 INFO - 'MAIL': '/var/mail/cltbld', 02:16:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:16:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:33 INFO - 'MOZ_NO_REMOTE': '1', 02:16:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:16:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:16:33 INFO - 'NO_EM_RESTART': '1', 02:16:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:33 INFO - 'PWD': '/builds/slave/test', 02:16:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:16:33 INFO - 'SHELL': '/bin/bash', 02:16:33 INFO - 'SHLVL': '1', 02:16:33 INFO - 'TERM': 'linux', 02:16:33 INFO - 'TMOUT': '86400', 02:16:33 INFO - 'USER': 'cltbld', 02:16:33 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627', 02:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:33 INFO - '_': '/tools/buildbot/bin/python'} 02:16:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:16:33 INFO - mozversion application_buildid: 20160515233005 02:16:33 INFO - mozversion application_changeset: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 02:16:33 INFO - mozversion application_display_name: Nightly 02:16:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:16:33 INFO - mozversion application_name: Firefox 02:16:33 INFO - mozversion application_remotingname: firefox 02:16:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:16:33 INFO - mozversion application_vendor: Mozilla 02:16:33 INFO - mozversion application_version: 49.0a1 02:16:33 INFO - mozversion platform_buildid: 20160515233005 02:16:33 INFO - mozversion platform_changeset: 668a9155cc8b0dad7e84ea83019ba2f2ce20a4c1 02:16:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:16:33 INFO - mozversion platform_version: 49.0a1 02:16:33 INFO - using testdate: 1463390193 02:16:33 INFO - actual date: 1463390193 02:16:33 INFO - starting webserver on 'localhost:57351' 02:16:33 INFO - SUITE-START | Running 2 tests 02:16:33 INFO - TEST-START | dromaeo_css 02:16:33 INFO - operating with platform_type : linux_ 02:16:33 INFO - Initialising browser for dromaeo_css test... 02:16:33 INFO - TEST-INFO | started process 7821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWH5UrV/profile http://localhost:57351/getInfo.html) 02:16:37 INFO - PROCESS | 7821 | __metrics Screen width/height:1600/1200 02:16:37 INFO - PROCESS | 7821 | colorDepth:24 02:16:37 INFO - PROCESS | 7821 | Browser inner width/height: 1024/697 02:16:37 INFO - PROCESS | 7821 | __metrics 02:16:38 ERROR - PROCESS | 7821 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:16:38 INFO - TEST-INFO | 7821: exit 0 02:16:38 INFO - Browser initialized. 02:16:38 INFO - Running cycle 1/1 for dromaeo_css test... 02:16:38 INFO - TEST-INFO | started process 7903 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWH5UrV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:16:50 INFO - PROCESS | 7903 | RSS: Main: 152567808 02:16:50 INFO - PROCESS | 7903 | 02:17:41 INFO - PROCESS | 7903 | 1463390261915 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] 02:19:49 INFO - PROCESS | 7903 | Cycle 1(1): loaded http://localhost:57351/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57351/tests/dromaeo/cssquery-ext.html) 02:19:50 INFO - PROCESS | 7903 | RSS: Main: 256180224 02:19:50 INFO - PROCESS | 7903 | 02:22:52 INFO - PROCESS | 7903 | Cycle 1(1): loaded http://localhost:57351/tests/dromaeo/cssquery-ext.html (next: http://localhost:57351/tests/dromaeo/cssquery-jquery.html) 02:22:53 INFO - PROCESS | 7903 | RSS: Main: 234840064 02:22:53 INFO - PROCESS | 7903 | 02:25:55 INFO - PROCESS | 7903 | Cycle 1(1): loaded http://localhost:57351/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57351/tests/dromaeo/cssquery-mootools.html) 02:25:56 INFO - PROCESS | 7903 | RSS: Main: 235991040 02:25:56 INFO - PROCESS | 7903 | 02:28:58 INFO - PROCESS | 7903 | Cycle 1(1): loaded http://localhost:57351/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57351/tests/dromaeo/cssquery-prototype.html) 02:28:58 INFO - PROCESS | 7903 | RSS: Main: 228458496 02:28:58 INFO - PROCESS | 7903 | 02:32:01 INFO - PROCESS | 7903 | Cycle 1(1): loaded http://localhost:57351/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57351/tests/dromaeo/cssquery-yui.html) 02:32:01 INFO - PROCESS | 7903 | RSS: Main: 211881984 02:32:01 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | Cycle 1(1): loaded http://localhost:57351/tests/dromaeo/cssquery-yui.html (next: http://localhost:57351/tests/dromaeo/cssquery-dojo.html) 02:35:04 INFO - PROCESS | 7903 | RSS: Main: 328708096 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | __start_tp_report 02:35:04 INFO - PROCESS | 7903 | _x_x_mozilla_page_load 02:35:04 INFO - PROCESS | 7903 | _x_x_mozilla_page_load_details 02:35:04 INFO - PROCESS | 7903 | |i|pagename|runs| 02:35:04 INFO - PROCESS | 7903 | |0;dojo.html;3147.6222853257605;3150.7951983121097;3159.2259896325404;3175.1585829755104;3217.372612340593;5251.448597897223;5257.710825904578;5299.576033917287;5381.488758567934;5404.054290499157;5285.86785330366;5323.041852466555;5337.839864804052;5354.116570765828;5370.624056316059;4813.013332266885;4858.757062146896;4897.657163998521;4907.337509436216;5013.849584512453;4920.3357546731095;4928.285398617199;4962.751862406865;4980.7509624518625;4992.051510213066;4725.17309470843;4816.325714399987;4854.029194161164;4874;4875.512448755132;4869.488703686133;4951.3810773653295;4957.652964292501;4961.107000739901;5002.299678045077;4692.202325604621;4818.903621927542;4893.388326459192;4913.877412530199;4959.479254678279;5255.816046438356;5307.628466007381;5343.225232341289;5344.652597581145;5356.9732151339385;5763.193152958589;5776.364599894008;5788;5791.623544469639;5793.73928173229;5535.142052981794;5684.857878553028;5714.742836572323;5818.5345172386105;5834.5040671542965;5391.676499410009;5408.78364865401;5417.26866872971;5419.647722897999;5420.593455490814;5934.584579079509;5939.22790037301;5945.197398351167;5945.1973983512535;5948.375420580864;5529.391766905717;5581.553475721932;5642.887142257132;5664.631798933056;5682.801101961411;5739.110437882135;5744.856378590485;5751.051076572402;5790.36306006339;5804.535637149018;5705.5150312223095;5706.9429305707245;5713.22871412354;5713.4000929902595;5714.885702285931;5517.917231241535;5637.661740295595;5686.232358631614;5697.173909783141;5711.400302968212;4352.564743525622;4353.912921741611;4357.1939191249085;4367.650587952956;4391.297392417198;3828.2343531293855;3891.3384724596885;3902.453656488094;3911.8239679214503;3957.1887763008513;5361.785528578813;5386.48828361305;5418.539424148913;5434.347878254634;5439.238506609078;5764.942350576441;5765.04876695349;5787.421257874263;5788.88422231553;5794.797182098607;5253.369595648546;5358.839234822962;5418.4039755626845;5422.810201642924;5425.159100339453;4972.378452693413;4973.229149481836;4991.276264941564;4993.1262029144755;5023.8492845214705;6029.487493563096;6029.939700602938;6031.095335699592;6033.517318614413;6034.064719968412;5582.972085139549;5732.885342293047;5792.594518383841;5832.94167058324;5851;6003.129546215687;6003.519718422603;6010;6048.667323297261;6062.090686396899;5706.229658995982;5710.3433105193435;5711.685857277889;5720.199172115824;5721.885562288648;5524.364698059614;5680.602357834912;5684.7157642118555;5793.188953546591;5804.332501762351;5594.748236329294;5642;5643.802466913638;5644.040513112863;5680.886382272414;5584.329880414376;5621.184928185308;5636.520895723909;5644.181593668813;5649.293838270216;5366.758495867617;5466.043442397644;5501.092319767194;5523.116301391758;5525.806596769094;5685.374608793112;5688.175214593944;5744.310682718101;5750.884982300414;5758.107493338539;5585.273914391103;5586.888262234814;5588.357338905916;5588.608797384144;5630.211770352072 02:35:04 INFO - PROCESS | 7903 | |1;ext.html;605.7819185093366;630.5082036011911;639.2073828452718;650.9153810004699;660.7555204574309;34084.31831363371;37331.626683733186;45037.09925801486;46136.30795538061;46174.538254617444;48523.75738121313;48662.27006594904;48945.51054489454;48957.265641015416;48999.51000490003;8854.247388971946;9033.774155646095;9035.864462033074;9039.322050846213;9043;8807.64769409223;8892.37753357262;8922.375433719642;8923.107689231058;8924.286057115447;8965.282777377826;8974.955125224365;8982.775430614221;8984.281257499417;8985.37102402832;8859.246964008034;8903.821923561558;8908.376413651014;8910.064443233465;8914.465132092031;8765.474071555727;8823.161799629024;8847.73456796297;8849.380543361967;8853.778655533599;43786.56213437857;44171.337429938256;44180.779096104314;44187;44256.557434425566;3470.16715988161;3769.7738135711943;3852.84588616455;3885.494885664874;4103.2409004334295;33604.49593256103;34078.48882266768;34203.65796342029;34257.314853703036;35297.82351088254;4108.876733697994;4149.107941792499;4205.558416366299;4240.597143271384;4338.240807858644;331938;332691.0289036999;333837;334574;335023;54683.72658136684;60246.39753602495;61414.692926535085;61479.692601536706;62031.689841550506;57274.140887886715;63606.68196658987;63729.36270637327;63753.681231593546;63827.68086159539;17657;17834.91082544579;17846;17846.91076544609;17852.55368615795;12680.365981700877;13269.535566255136;13324.73350532984;13595.728085438433;13951.930240348733;19222.423327300203;19301.227950882087;19348.4195474136;19400.126994285292;19407.708884366744;6997.895031574531;7106.5736055836815;7115.466340024519;7195.676194571257;7205.711771529185;1026.810040142576;1064.5954537275786;1124.1344164992909;1179.2688533109356;1251.599488163797;32793.18017049545;33453.66546334554;33677.14775687312;33854.49218261728;34324.828375857964;1203.2599951030054;1208.5649166300125;1218.5552273420137;1232.7287996640725;1243.241622610225;17386.826131738773;17851.553711157325;17860.55348616295;17895.821041789677;17910.91044544769;514597;516104;518289;519010;520074;59857.401425986056;59943.70028149831;60082.09876851851;60117.39882601205;60160.69919650374;1399.2164387942785;1441.0473807990231;1442.0986883198002;1453.3677850135014;1457.8760805331453;31364.372712545166;32115.35769284648;33037.668586244436;33162.83418582891;33376.165595859326;53123;53278.46721532735;53604.46395535995;53606.73196633992;53637.46362536325;408965;409247;409484;409667;448690;42250.788746057304;43169.784151079046;43381.3492797602;46539;46877.76561117172;48704.2694359592;48822.511774881794;49254.507454926425;49768.50231497639;50216.74891625519;41482.58517414908;41937.1612567753;42030;42447.787761060994;43345.78327108344;39415.40876886906;42095.57904421039;42356;42499;43374.78312608417;41988.3701744468;42914.57085429231;43212;43253.56746432495;43310.56689433191;35806;36359.81820090988;37105.44341834821;37845.05387365338;38427.42358864563;26754.866225668746;29270.41459170762;29431.85284073566;29449.411011780074;29636.703632963396 02:35:04 INFO - PROCESS | 7903 | |2;jquery.html;409.1836785612703;410.6612045062823;411.1078958659708;417.5490470292085;419.5573669778383;18083.819161808377;18904.432867013977;19966.7004994925;19990.200391984323;20080.799192008075;18368.448946531593;18582.628347433045;19486.707699384522;19546.511337216576;19579;5542.944570554283;5593.021221286269;5834.795782147626;5839.883202335951;5948.702564871761;5784.971075144618;5793.739281732311;5805.535557155438;5824.796132135376;5832.737526811304;5730.19777231188;5827.41725827416;5829.242198514187;5864.23764910561;5888.116782482641;5799.565032622549;5829.0964900440285;5834.970825145869;5869.589128760988;6449.290578036413;5733.885322293572;5749.8275051748515;5754.050581654022;5776.306843178845;5777.277840269966;12368.567100151451;12619.621411357673;13815.378307976165;13851.16892986424;13882.4447022119;2440.060576677974;2549.3371723351875;2638.8020898432696;2677.8259413138085;2780.7914406419395;9977.900220997843;10027.949860250652;10598.205134614935;12224.388780560936;14694.750946169588;1952.570434504421;1964.5285131568464;1965.1942703491643;2012.0543344628002;2065.710800487933;57155.142672859525;69374.99999999949;71647.64176179138;76697;80429;10898.94550527258;11035.44822758859;11152.609658661908;11252.26860254081;11522.444469996442;13446.260455675032;14264.500742473963;15141.091534507965;15161;15735.44925927587;8355.248027677539;8455.816185734002;8507.149285071444;8613.181747733955;8657.480551166951;8112.553809540414;8163.265306122477;8226.629801658939;8302.8339433211;8465.32277417805;4792.688475249098;4849.927251091236;4906.8773280668265;4919.1883339248625;4943.060818444484;1934.148974451265;1991.7809041005478;2006.6488364536149;2033.9796602034087;2073.2018173003503;959.1990687775645;1014.5231741081827;1042.4162469017374;1090.378484263962;1127.115214556569;18960.345551622508;19044.428667140008;19623;19730.37747153996;19767.406977790688;1055.567217440861;1072.8766191887876;1073.6196319018386;1074.4520294649628;1089.3137323486153;10371.792564148675;11103.333799972028;11163.162762792823;11174.888251117547;11206.383648899226;473577;478014;483917;484679;489765;55976.720116399156;58616.70691646515;78639.60680196562;92428.99999999866;93010.53494732484;1037.9532921018572;1053.1522124689698;1056.213121224708;1073.9904489779738;1076.3810808784824;16029.358825646843;16985.235664394884;17214.827851721824;17220.78952588802;17305.740413894055;41440.792796037036;42236.78881605572;42568.787156065264;43124.137517248855;43347;54616.18075728947;56082.1587676177;56314.718426407606;56424;57176.42823571878;8880.245179159843;9014.774630634025;9059.320550958837;9138.406003609743;9329.393589416666;8254.463459875089;12676;12739.490420383081;13776;14027.228502432465;9735.61057557718;11933.462994165457;12240.204386714835;12421.565245216374;12712.74574508523;11288.779147887595;11661.35862527569;11957.701057473632;12093;12326.876731232573;6581.407673309425;10364.740881478023;12571.912857071857;12637.68405789826;12709.983201343686;8813;8959.462432253826;9036.45781253127;9158.95420522893;9255.861162082709;12577.496900123893;12626.810597840857;12703.110782245523;12754.107212495408;12853.16454430459 02:35:04 INFO - PROCESS | 7903 | |3;mootools.html;1390.9165450073003;1507.6230942264433;1551.0383562191444;1723.3451288510364;1890.1706140810234;8607.354448416363;9016.188543031127;9021.458712477248;9296.442213467197;9318.72043838686;9075.90924090759;9242.722718318444;9257.583408746606;9260.814783704322;9280;2070.2960993262286;2301.6892719482908;2364.4443555764383;2390.760923907613;2419.5160967806423;2367.337145599235;2371.419002344428;2372.4780548279437;2375.323032935614;2375.4773949731116;2368.159303447277;2378.6907701998803;2398.880055997202;2400.663907053014;2404.903803847844;1963.3717210492648;2274.9545009099893;2314.0975019742314;2336.4275752440763;2338.3920180752957;2322.500662357584;2339.181286549694;2342.8711420871846;2342.976570234293;2343.906243750248;9060.728178154666;9110.0434454382;9119.088091190893;9127.817443651156;9175.174234318943;5400.91898621521;5524.309461317335;5569.192467092249;5571.108622620402;5571.554275657938;3406.114410253327;3422.709069729076;3483.686468217847;3552.5559305086863;3553.7690050146657;1116.2242241642061;1133.1501373969522;1164.7903377392065;1166.830809532613;1195.1454709882476;12959.092863499558;13105.672358191121;13113.934430327883;13119.081664283409;13124.737505249841;4571;4613.607843333354;4731.787069581877;4733.952660473351;4737.8104875805275;10224.948875255575;10256.794864102676;10265.794684106277;10281;10292.022257885488;5357.517823395913;5429.104197807396;5432.511074003359;5435.945640543623;5440.564754819605;4334.891627709332;4771.379720636295;4919.065377578248;4920.040592084509;4978.979309241597;3921.11774850662;3962.623550762673;3994.3409337459575;4050.311447054008;4062.1266427718183;1192.6600918738118;1225.601679454181;1226.938653067343;1228.2753175626424;1249.6126200877757;909.3725329522608;913.9954300228457;932.5430539035824;933.6032186320787;951.4671783801093;5784.566157538202;5803.303603567599;5883.823485295448;6064.514838812885;6255.530835187379;445.4632168237266;446.8860440587694;453.6982906367295;453.83208212961415;458.2553350804942;4268.274393353137;4273.273543497613;4288.807003684841;4337.652987761035;4338.7830608470085;11196.440177991231;11524.135689823297;11526.711832204095;11580.131490138105;11591.826122608167;11386.487608057658;11402.258853174517;11476.19666623345;11487.827682584768;11510.712232194262;8181.918180818235;8534.231919127309;8584.957075214585;8817.867731984026;8826.735197944072;5997.370276120944;6257.812265632038;6312.747490100528;6355.777547785805;6477.546571739933;10028.29801913859;10405.739856503647;10605.416702081151;10710.625128120479;10734.570617175226;10397.272190946864;10695.03744662984;10717.571297148026;10757.892421075689;10820.56717731282;7439.367653749276;7672.424568157299;7682.193369696325;7972.800679983046;8123.878141827996;8396.916030839613;8616.224539791448;8659.13408659129;8830.867536987078;8837.558122093998;8037.919620803717;8183.181681831769;8185.140560241327;8256.504609723435;8281.585920703821;7912;8069.878951815845;8097.797555061171;8102.392320575863;8122.796930076795;7246.1666908306215;7248.492605517563;7330.780076597711;7357.043584334002;7387.224341444071;5318.867028324323;5400.378956419905;5506.449355064461;5525.751341189576;5542.334919809648;5355.544778693698;5449.6730196188355;5477.178423236546;5570.58220633446;5658.717064146694 02:35:04 INFO - PROCESS | 7903 | |4;prototype.html;333.78637671889993;340.11230687904566;342.92455659754853;353.4697953070394;353.78242380935734;5411.864703382417;5463.3717122530825;5471.68679516916;5503.20203570482;5533.778648854051;5311.548518375933;5340.54605358545;5357.49103835136;5464.398916119224;5477.260569823073;4001.1197536541913;4231.1749208904275;4242.469691288589;4246.36304554317;4274.187904298174;4240.893977650568;4252.8511502097435;4259.552746961571;4263.742196052157;4271.743695378272;4207.747535147885;4218.957810421903;4230.619244268015;4249.87250382489;4287.292596721537;4064.532578753437;4107.158032603309;4195.7063005589625;4195.895102622429;4212.220739163265;3673.246984368198;3691.169486865461;3696.667299942991;3697.6672099511175;3734.066483379155;5057.974710126463;5249.580033597303;5253.448387919251;5253.474652534755;5262.8684282892855;4763.261694437368;4876.804927802884;4893.8776530586665;4895.657303988723;4901.191303434929;5661.405552416978;5669.688167150805;5675.921574900755;5691.48776610103;5730.255066841326;3986.900327491807;4128.587141285878;4153.418521407006;4187.14163596462;4199.853005144836;5839.503642190419;5853.64878107315;5867.383924687889;5887.352391237004;5900.675462849542;4270.978645106755;4286.571342865688;4297.763623000703;4315.719478233905;4323.135372925428;4236.533981262059;4312.805923733439;4341.848035318749;4364.127174565099;4375.518692943773;4856.465788763233;4856.73287969158;4873.488283730228;4878.292647566083;4879.9016422938475;5673.631213971079;5683.914741278884;5707.315122185364;5726.570507211975;5729.713514324267;1426.1585664457812;1485.6211666025094;1496.6632507685683;1502.2864139533633;1515.454436402894;960.596549449233;962.0235461007084;962.6486332488591;983.9852402213972;984.5077461269366;4828.420589529209;4829.44461386945;4879.682820616648;4908.8155770682415;4921.3356196912955;5112.488751124858;5197.350331208599;5198.844034678966;5209.661372010807;5218.582513398919;4870.634702397334;5251.76367063483;5310.468953104736;5468.562514998791;5495.285612870382;3063.92340191497;3077.1230199393513;3082.676318986519;3096.2723759916403;3101.5037593984853;5769.826905192851;6016.7292471838;6032.909506357408;6048.939510604926;6056.606320589148;5850.5612079094235;5981.252343457068;5987.431194036647;6029.336772954972;6037.879242415127;5967.492763115097;5976.416249693834;6002.189704390026;6008.849778755479;6032.3967603239325;5644.548436125018;5760.452756988079;5808.419158084327;5825.970870145792;5826.649676435982;5734.713264336683;5757.165211044459;5767.798127065533;5803.7388317524965;5812.651240925558;5729.4843464088435;5984.371640977634;6006.519478441802;6024.216851809237;6052.394760523913;4150.958490415057;4169.854055108057;4212;4218.240716671029;4236.728981305658;5924.763009479745;5946.453921980403;5946.494547963472;5949.315828679757;5966.254218222723;5636.393627816053;5721.971390143022;5727.799527016535;5761.711914404347;5834.824955251349;5679.734152862038;5708.714564271853;5716.971415142898;5728.283964504437;5735.627184233012;5370.355557332989;5584.888302234014;5618.494335509824;5662.5469962402085;5662.971685141548;5674.233978412862;5715.514181294637;5740.282464691913;5744.425557444222;5747.453991870766;5422.755975981011;5584.888302234014;5597.384287728428;5620.943790562042;5631.49316561495 02:35:04 INFO - PROCESS | 7903 | |5;yui.html;835.8996920369555;853.4495250563383;862.2196911794824;865.2774932931002;882.4749274181862;15085.849141508581;15448.922755386207;15462.84537154631;15475.14886681232;15515.534533963984;14256.928715356435;14929.92535037326;14970.925145374285;14978.925105374485;14991.400343986228;839.836231934773;850.8596081646521;853.2832420766555;853.4751128056232;854.1927878155146;703.3704834173411;721.6824597177225;722.0072400449382;723.4176487927216;849.9490030598153;868.3010176807667;897.1208215948375;899.9235065019449;901.4681338010572;902.2195800632438;888.2449917570078;888.4580405952364;888.844452220862;888.9244414224798;889.0131953531575;541.8997485465202;624.272722278545;644.44899610833;646.0793369448517;646.0857886091176;14265.286735663176;14276.428942842274;14287.357068931819;14304.57086287413;14315.642108947255;13158.276294803782;13978.370973306124;13997.440102395893;14091.436342546285;14171.716565668732;11503;11628.360440175788;11696.47365868538;12041.819372709417;12124.03007759386;4623.21264985861;4647.209974304376;4674.275487299474;4706.835260765891;4811.687240329403;62217.688911555604;63053.36946630567;63572.36427635757;63769.68115159441;63826;11298.774024519627;11316.547338106384;11317.434128293553;11426.828597571042;11837.289762614098;20344.18623255053;20473.385798426072;20561.177552897716;20572.27997020127;20599.588008239753;8497.74506764798;8635.568221589021;8760.386772925835;8761.956190219009;8928.419652722552;4842.903141937141;4903.313536104948;4926.088673595396;4930.433000204951;5177.326947496801;3464.185916309665;3548.840302186408;3548.8757893473603;3553.200529880756;3557.8043207623828;655.4265018109154;656.8193746719691;658.4929604204735;678.9388954994074;687.9002544631101;642.5125833694981;753.182796665624;790.707438247852;809.1301850510724;829.3158144530786;9479.715608531755;9696.17582505495;9850.901490984998;10080.59677612902;10084.546195421224;610.7709608896664;661.7716887673745;667.2218587036339;751.2112282103769;768.8539177556246;4267.210566045292;4349.173657005159;4390.846320378771;4426.1369033038245;4679.532046795362;65995;66153.66923165353;67208.6639566799;67277.32722672808;69163.3083669167;31321.686783132332;55459.445405546234;58579.70710146422;60346.39653603408;61160.96648885346;49586;50760.74619626878;53687.463125369024;55162.448375515734;55275.44724552784;9693.224542036762;9698.27262955267;9937.701868943943;9994.650187243411;10005.249606279413;37536.43695344627;37730.8113459431;37862;37872.81063594665;37956.24087518179;46709.2993605103;50473.747631261605;50641.49358506515;50683.74658126833;52011.479885200664;12557.246565206116;12661.893492306092;12802.103852730219;12936.676583085498;13342.599722008355;14439.133651980916;14639.268036597914;14908.7763683547;15391.923040384727;15426.151561664215;13683.315834208448;13932.791008134684;14087.225202613954;14438.639034024234;14836.703265934837;13306.135101218777;13350.9332453341;13499.797503037658;13756.243406612732;13807.447702091804;13256;13474.79787803164;14147.00970932066;14386.856131438552;14681.339339729524;10956.451685091235;10957;11103.278286911325;11293.265937714023;11666.70833229176;10951.178661600252;11440.942795286302;11781.52873885035;11803.114766392384;11842.703932401759 02:35:04 INFO - PROCESS | 7903 | __end_tp_report 02:35:04 INFO - PROCESS | 7903 | __start_cc_report 02:35:04 INFO - PROCESS | 7903 | _x_x_mozilla_cycle_collect,863 02:35:04 INFO - PROCESS | 7903 | __end_cc_report 02:35:04 INFO - PROCESS | 7903 | __startTimestamp1463391304838__endTimestamp 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | ------- Summary: start ------- 02:35:04 INFO - PROCESS | 7903 | Number of tests: 6 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | [#0] dojo.html Cycles:165 Average:5343.78 Median:5529.39 stddev:608.12 (11.0%) stddev-sans-first:585.07 02:35:04 INFO - PROCESS | 7903 | Values: 3147.6 3150.8 3159.2 3175.2 3217.4 5251.4 5257.7 5299.6 5381.5 5404.1 5285.9 5323.0 5337.8 5354.1 5370.6 4813.0 4858.8 4897.7 4907.3 5013.8 4920.3 4928.3 4962.8 4980.8 4992.1 4725.2 4816.3 4854.0 4874.0 4875.5 4869.5 4951.4 4957.7 4961.1 5002.3 4692.2 4818.9 4893.4 4913.9 4959.5 5255.8 5307.6 5343.2 5344.7 5357.0 5763.2 5776.4 5788.0 5791.6 5793.7 5535.1 5684.9 5714.7 5818.5 5834.5 5391.7 5408.8 5417.3 5419.6 5420.6 5934.6 5939.2 5945.2 5945.2 5948.4 5529.4 5581.6 5642.9 5664.6 5682.8 5739.1 5744.9 5751.1 5790.4 5804.5 5705.5 5706.9 5713.2 5713.4 5714.9 5517.9 5637.7 5686.2 5697.2 5711.4 4352.6 4353.9 4357.2 4367.7 4391.3 3828.2 3891.3 3902.5 3911.8 3957.2 5361.8 5386.5 5418.5 5434.3 5439.2 5764.9 5765.0 5787.4 5788.9 5794.8 5253.4 5358.8 5418.4 5422.8 5425.2 4972.4 4973.2 4991.3 4993.1 5023.8 6029.5 6029.9 6031.1 6033.5 6034.1 5583.0 5732.9 5792.6 5832.9 5851.0 6003.1 6003.5 6010.0 6048.7 6062.1 5706.2 5710.3 5711.7 5720.2 5721.9 5524.4 5680.6 5684.7 5793.2 5804.3 5594.7 5642.0 5643.8 5644.0 5680.9 5584.3 5621.2 5636.5 5644.2 5649.3 5366.8 5466.0 5501.1 5523.1 5525.8 5685.4 5688.2 5744.3 5750.9 5758.1 5585.3 5586.9 5588.4 5588.6 5630.2 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | [#1] ext.html Cycles:180 Average:59970.83 Median:43042.18 stddev:113501.61 (263.7%) stddev-sans-first:113732.49 02:35:04 INFO - PROCESS | 7903 | Values: 605.8 630.5 639.2 650.9 660.8 34084.3 37331.6 45037.1 46136.3 46174.5 48523.8 48662.3 48945.5 48957.3 48999.5 8854.2 9033.8 9035.9 9039.3 9043.0 8807.6 8892.4 8922.4 8923.1 8924.3 8965.3 8975.0 8982.8 8984.3 8985.4 8859.2 8903.8 8908.4 8910.1 8914.5 8765.5 8823.2 8847.7 8849.4 8853.8 43786.6 44171.3 44180.8 44187.0 44256.6 3470.2 3769.8 3852.8 3885.5 4103.2 33604.5 34078.5 34203.7 34257.3 35297.8 4108.9 4149.1 4205.6 4240.6 4338.2 331938.0 332691.0 333837.0 334574.0 335023.0 54683.7 60246.4 61414.7 61479.7 62031.7 57274.1 63606.7 63729.4 63753.7 63827.7 17657.0 17834.9 17846.0 17846.9 17852.6 12680.4 13269.5 13324.7 13595.7 13951.9 19222.4 19301.2 19348.4 19400.1 19407.7 6997.9 7106.6 7115.5 7195.7 7205.7 1026.8 1064.6 1124.1 1179.3 1251.6 32793.2 33453.7 33677.1 33854.5 34324.8 1203.3 1208.6 1218.6 1232.7 1243.2 17386.8 17851.6 17860.6 17895.8 17910.9 514597.0 516104.0 518289.0 519010.0 520074.0 59857.4 59943.7 60082.1 60117.4 60160.7 1399.2 1441.0 1442.1 1453.4 1457.9 31364.4 32115.4 33037.7 33162.8 33376.2 53123.0 53278.5 53604.5 53606.7 53637.5 408965.0 409247.0 409484.0 409667.0 448690.0 42250.8 43169.8 43381.3 46539.0 46877.8 48704.3 48822.5 49254.5 49768.5 50216.7 41482.6 41937.2 42030.0 42447.8 43345.8 39415.4 42095.6 42356.0 42499.0 43374.8 41988.4 42914.6 43212.0 43253.6 43310.6 35806.0 36359.8 37105.4 37845.1 38427.4 26754.9 29270.4 29431.9 29449.4 29636.7 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | [#2] jquery.html Cycles:180 Average:27856.27 Median:2494.70 stddev:79083.61 (3170.1%) stddev-sans-first:79278.61 02:35:04 INFO - PROCESS | 7903 | Values: 409.2 410.7 411.1 417.5 419.6 18083.8 18904.4 19966.7 19990.2 20080.8 18368.4 18582.6 19486.7 19546.5 19579.0 5542.9 5593.0 5834.8 5839.9 5948.7 5785.0 5793.7 5805.5 5824.8 5832.7 5730.2 5827.4 5829.2 5864.2 5888.1 5799.6 5829.1 5835.0 5869.6 6449.3 5733.9 5749.8 5754.1 5776.3 5777.3 12368.6 12619.6 13815.4 13851.2 13882.4 2440.1 2549.3 2638.8 2677.8 2780.8 9977.9 10027.9 10598.2 12224.4 14694.8 1952.6 1964.5 1965.2 2012.1 2065.7 57155.1 69375.0 71647.6 76697.0 80429.0 10898.9 11035.4 11152.6 11252.3 11522.4 13446.3 14264.5 15141.1 15161.0 15735.4 8355.2 8455.8 8507.1 8613.2 8657.5 8112.6 8163.3 8226.6 8302.8 8465.3 4792.7 4849.9 4906.9 4919.2 4943.1 1934.1 1991.8 2006.6 2034.0 2073.2 959.2 1014.5 1042.4 1090.4 1127.1 18960.3 19044.4 19623.0 19730.4 19767.4 1055.6 1072.9 1073.6 1074.5 1089.3 10371.8 11103.3 11163.2 11174.9 11206.4 473577.0 478014.0 483917.0 484679.0 489765.0 55976.7 58616.7 78639.6 92429.0 93010.5 1038.0 1053.2 1056.2 1074.0 1076.4 16029.4 16985.2 17214.8 17220.8 17305.7 41440.8 42236.8 42568.8 43124.1 43347.0 54616.2 56082.2 56314.7 56424.0 57176.4 8880.2 9014.8 9059.3 9138.4 9329.4 8254.5 12676.0 12739.5 13776.0 14027.2 9735.6 11933.5 12240.2 12421.6 12712.7 11288.8 11661.4 11957.7 12093.0 12326.9 6581.4 10364.7 12571.9 12637.7 12710.0 8813.0 8959.5 9036.5 9159.0 9255.9 12577.5 12626.8 12703.1 12754.1 12853.2 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | [#3] mootools.html Cycles:180 Average:5996.47 Median:2514.63 stddev:3485.82 (138.6%) stddev-sans-first:3478.42 02:35:04 INFO - PROCESS | 7903 | Values: 1390.9 1507.6 1551.0 1723.3 1890.2 8607.4 9016.2 9021.5 9296.4 9318.7 9075.9 9242.7 9257.6 9260.8 9280.0 2070.3 2301.7 2364.4 2390.8 2419.5 2367.3 2371.4 2372.5 2375.3 2375.5 2368.2 2378.7 2398.9 2400.7 2404.9 1963.4 2275.0 2314.1 2336.4 2338.4 2322.5 2339.2 2342.9 2343.0 2343.9 9060.7 9110.0 9119.1 9127.8 9175.2 5400.9 5524.3 5569.2 5571.1 5571.6 3406.1 3422.7 3483.7 3552.6 3553.8 1116.2 1133.2 1164.8 1166.8 1195.1 12959.1 13105.7 13113.9 13119.1 13124.7 4571.0 4613.6 4731.8 4734.0 4737.8 10224.9 10256.8 10265.8 10281.0 10292.0 5357.5 5429.1 5432.5 5435.9 5440.6 4334.9 4771.4 4919.1 4920.0 4979.0 3921.1 3962.6 3994.3 4050.3 4062.1 1192.7 1225.6 1226.9 1228.3 1249.6 909.4 914.0 932.5 933.6 951.5 5784.6 5803.3 5883.8 6064.5 6255.5 445.5 446.9 453.7 453.8 458.3 4268.3 4273.3 4288.8 4337.7 4338.8 11196.4 11524.1 11526.7 11580.1 11591.8 11386.5 11402.3 11476.2 11487.8 11510.7 8181.9 8534.2 8585.0 8817.9 8826.7 5997.4 6257.8 6312.7 6355.8 6477.5 10028.3 10405.7 10605.4 10710.6 10734.6 10397.3 10695.0 10717.6 10757.9 10820.6 7439.4 7672.4 7682.2 7972.8 8123.9 8396.9 8616.2 8659.1 8830.9 8837.6 8037.9 8183.2 8185.1 8256.5 8281.6 7912.0 8069.9 8097.8 8102.4 8122.8 7246.2 7248.5 7330.8 7357.0 7387.2 5318.9 5400.4 5506.4 5525.8 5542.3 5355.5 5449.7 5477.2 5570.6 5658.7 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | [#4] prototype.html Cycles:180 Average:4765.17 Median:5363.92 stddev:1389.41 (25.9%) stddev-sans-first:1352.91 02:35:04 INFO - PROCESS | 7903 | Values: 333.8 340.1 342.9 353.5 353.8 5411.9 5463.4 5471.7 5503.2 5533.8 5311.5 5340.5 5357.5 5464.4 5477.3 4001.1 4231.2 4242.5 4246.4 4274.2 4240.9 4252.9 4259.6 4263.7 4271.7 4207.7 4219.0 4230.6 4249.9 4287.3 4064.5 4107.2 4195.7 4195.9 4212.2 3673.2 3691.2 3696.7 3697.7 3734.1 5058.0 5249.6 5253.4 5253.5 5262.9 4763.3 4876.8 4893.9 4895.7 4901.2 5661.4 5669.7 5675.9 5691.5 5730.3 3986.9 4128.6 4153.4 4187.1 4199.9 5839.5 5853.6 5867.4 5887.4 5900.7 4271.0 4286.6 4297.8 4315.7 4323.1 4236.5 4312.8 4341.8 4364.1 4375.5 4856.5 4856.7 4873.5 4878.3 4879.9 5673.6 5683.9 5707.3 5726.6 5729.7 1426.2 1485.6 1496.7 1502.3 1515.5 960.6 962.0 962.6 984.0 984.5 4828.4 4829.4 4879.7 4908.8 4921.3 5112.5 5197.4 5198.8 5209.7 5218.6 4870.6 5251.8 5310.5 5468.6 5495.3 3063.9 3077.1 3082.7 3096.3 3101.5 5769.8 6016.7 6032.9 6048.9 6056.6 5850.6 5981.3 5987.4 6029.3 6037.9 5967.5 5976.4 6002.2 6008.8 6032.4 5644.5 5760.5 5808.4 5826.0 5826.6 5734.7 5757.2 5767.8 5803.7 5812.7 5729.5 5984.4 6006.5 6024.2 6052.4 4151.0 4169.9 4212.0 4218.2 4236.7 5924.8 5946.5 5946.5 5949.3 5966.3 5636.4 5722.0 5727.8 5761.7 5834.8 5679.7 5708.7 5717.0 5728.3 5735.6 5370.4 5584.9 5618.5 5662.5 5663.0 5674.2 5715.5 5740.3 5744.4 5747.5 5422.8 5584.9 5597.4 5620.9 5631.5 02:35:04 INFO - PROCESS | 7903 | 02:35:04 INFO - PROCESS | 7903 | [#5] yui.html Cycles:180 Average:15890.83 Median:25691.79 stddev:18516.91 (72.1%) stddev-sans-first:18534.34 02:35:04 INFO - PROCESS | 7903 | Values: 835.9 853.4 862.2 865.3 882.5 15085.8 15448.9 15462.8 15475.1 15515.5 14256.9 14929.9 14970.9 14978.9 14991.4 839.8 850.9 853.3 853.5 854.2 703.4 721.7 722.0 723.4 849.9 868.3 897.1 899.9 901.5 902.2 888.2 888.5 888.8 888.9 889.0 541.9 624.3 644.4 646.1 646.1 14265.3 14276.4 14287.4 14304.6 14315.6 13158.3 13978.4 13997.4 14091.4 14171.7 11503.0 11628.4 11696.5 12041.8 12124.0 4623.2 4647.2 4674.3 4706.8 4811.7 62217.7 63053.4 63572.4 63769.7 63826.0 11298.8 11316.5 11317.4 11426.8 11837.3 20344.2 20473.4 20561.2 20572.3 20599.6 8497.7 8635.6 8760.4 8762.0 8928.4 4842.9 4903.3 4926.1 4930.4 5177.3 3464.2 3548.8 3548.9 3553.2 3557.8 655.4 656.8 658.5 678.9 687.9 642.5 753.2 790.7 809.1 829.3 9479.7 9696.2 9850.9 10080.6 10084.5 610.8 661.8 667.2 751.2 768.9 4267.2 4349.2 4390.8 4426.1 4679.5 65995.0 66153.7 67208.7 67277.3 69163.3 31321.7 55459.4 58579.7 60346.4 61161.0 49586.0 50760.7 53687.5 55162.4 55275.4 9693.2 9698.3 9937.7 9994.7 10005.2 37536.4 37730.8 37862.0 37872.8 37956.2 46709.3 50473.7 50641.5 50683.7 52011.5 12557.2 12661.9 12802.1 12936.7 13342.6 14439.1 14639.3 14908.8 15391.9 15426.2 13683.3 13932.8 14087.2 14438.6 14836.7 13306.1 13350.9 13499.8 13756.2 13807.4 13256.0 13474.8 14147.0 14386.9 14681.3 10956.5 10957.0 11103.3 11293.3 11666.7 10951.2 11440.9 11781.5 11803.1 11842.7 02:35:04 INFO - PROCESS | 7903 | -------- Summary: end -------- 02:35:04 INFO - PROCESS | 7903 | 02:35:05 INFO - TEST-INFO | 7903: exit 0 02:35:05 INFO - TEST-OK | dromaeo_css | took 1112284ms 02:35:05 INFO - TEST-START | kraken 02:35:05 INFO - operating with platform_type : linux_ 02:35:05 INFO - Initialising browser for kraken test... 02:35:05 INFO - TEST-INFO | started process 8079 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPAYa9Z/profile http://localhost:57351/getInfo.html) 02:35:09 INFO - PROCESS | 8079 | __metrics Screen width/height:1600/1200 02:35:09 INFO - PROCESS | 8079 | colorDepth:24 02:35:09 INFO - PROCESS | 8079 | Browser inner width/height: 1024/697 02:35:09 INFO - PROCESS | 8079 | __metrics 02:35:10 ERROR - PROCESS | 8079 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:35:11 INFO - TEST-INFO | 8079: exit 0 02:35:11 INFO - Browser initialized. 02:35:11 INFO - Running cycle 1/1 for kraken test... 02:35:11 INFO - TEST-INFO | started process 8159 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPAYa9Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:35:23 INFO - PROCESS | 8159 | RSS: Main: 152637440 02:35:23 INFO - PROCESS | 8159 | 02:35:25 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57351/tests/kraken/driver.html?testName=audio-beat-detection) 02:35:26 INFO - PROCESS | 8159 | RSS: Main: 208728064 02:35:26 INFO - PROCESS | 8159 | 02:35:29 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57351/tests/kraken/driver.html?testName=audio-dft) 02:35:29 INFO - PROCESS | 8159 | RSS: Main: 197472256 02:35:29 INFO - PROCESS | 8159 | 02:35:33 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57351/tests/kraken/driver.html?testName=audio-fft) 02:35:33 INFO - PROCESS | 8159 | RSS: Main: 265412608 02:35:33 INFO - PROCESS | 8159 | 02:35:35 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57351/tests/kraken/driver.html?testName=audio-oscillator) 02:35:36 INFO - PROCESS | 8159 | RSS: Main: 174522368 02:35:36 INFO - PROCESS | 8159 | 02:35:38 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57351/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:35:39 INFO - PROCESS | 8159 | RSS: Main: 178728960 02:35:39 INFO - PROCESS | 8159 | 02:35:42 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57351/tests/kraken/driver.html?testName=imaging-darkroom) 02:35:43 INFO - PROCESS | 8159 | RSS: Main: 214466560 02:35:43 INFO - PROCESS | 8159 | 02:35:46 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57351/tests/kraken/driver.html?testName=imaging-desaturate) 02:35:47 INFO - PROCESS | 8159 | RSS: Main: 215101440 02:35:47 INFO - PROCESS | 8159 | 02:35:50 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57351/tests/kraken/driver.html?testName=json-parse-financial) 02:35:50 INFO - PROCESS | 8159 | RSS: Main: 215269376 02:35:50 INFO - PROCESS | 8159 | 02:35:52 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57351/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:35:52 INFO - PROCESS | 8159 | RSS: Main: 205189120 02:35:52 INFO - PROCESS | 8159 | 02:35:54 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:35:55 INFO - PROCESS | 8159 | RSS: Main: 207699968 02:35:55 INFO - PROCESS | 8159 | 02:35:57 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:35:58 INFO - PROCESS | 8159 | RSS: Main: 217088000 02:35:58 INFO - PROCESS | 8159 | 02:36:00 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:36:01 INFO - PROCESS | 8159 | RSS: Main: 223645696 02:36:01 INFO - PROCESS | 8159 | 02:36:04 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:36:04 INFO - PROCESS | 8159 | RSS: Main: 218337280 02:36:04 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | Cycle 1(1): loaded http://localhost:57351/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57351/tests/kraken/driver.html?testName=ai-astar) 02:36:06 INFO - PROCESS | 8159 | RSS: Main: 263696384 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | __start_tp_report 02:36:06 INFO - PROCESS | 8159 | _x_x_mozilla_page_load 02:36:06 INFO - PROCESS | 8159 | _x_x_mozilla_page_load_details 02:36:06 INFO - PROCESS | 8159 | |i|pagename|runs| 02:36:06 INFO - PROCESS | 8159 | |0;ai-astar;111;106;109;108;109;109;109;109;109;109 02:36:06 INFO - PROCESS | 8159 | |1;audio-beat-detection;159;131;151;215;126;157;153;129;182;124 02:36:06 INFO - PROCESS | 8159 | |2;audio-dft;160;161;159;159;158;159;159;161;163;159 02:36:06 INFO - PROCESS | 8159 | |3;audio-fft;76;75;75;152;75;75;75;74;130;74 02:36:06 INFO - PROCESS | 8159 | |4;audio-oscillator;90;90;152;90;91;92;123;91;90;136 02:36:06 INFO - PROCESS | 8159 | |5;imaging-gaussian-blur;117;116;117;116;117;116;117;116;116;116 02:36:06 INFO - PROCESS | 8159 | |6;imaging-darkroom;145;145;145;145;144;144;145;145;144;145 02:36:06 INFO - PROCESS | 8159 | |7;imaging-desaturate;95;94;94;95;95;95;95;95;94;94 02:36:06 INFO - PROCESS | 8159 | |8;json-parse-financial;56;56;56;55;56;55;56;109;55;54 02:36:06 INFO - PROCESS | 8159 | |9;json-stringify-tinderbox;59;60;59;59;59;59;59;60;60;60 02:36:06 INFO - PROCESS | 8159 | |10;stanford-crypto-aes;78;78;77;76;80;79;85;81;79;80 02:36:06 INFO - PROCESS | 8159 | |11;stanford-crypto-ccm;121;126;125;117;116;116;117;117;208;121 02:36:06 INFO - PROCESS | 8159 | |12;stanford-crypto-pbkdf2;160;177;162;164;162;165;162;160;159;157 02:36:06 INFO - PROCESS | 8159 | |13;stanford-crypto-sha256-iterative;70;67;62;70;62;62;64;61;62;67 02:36:06 INFO - PROCESS | 8159 | __end_tp_report 02:36:06 INFO - PROCESS | 8159 | __start_cc_report 02:36:06 INFO - PROCESS | 8159 | _x_x_mozilla_cycle_collect,1845 02:36:06 INFO - PROCESS | 8159 | __end_cc_report 02:36:06 INFO - PROCESS | 8159 | __startTimestamp1463391366728__endTimestamp 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | ------- Summary: start ------- 02:36:06 INFO - PROCESS | 8159 | Number of tests: 14 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#0] ai-astar Cycles:10 Average:108.80 Median:109.00 stddev:1.23 (1.1%) stddev-sans-first:1.01 02:36:06 INFO - PROCESS | 8159 | Values: 111.0 106.0 109.0 108.0 109.0 109.0 109.0 109.0 109.0 109.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:155.00 stddev:28.62 (18.5%) stddev-sans-first:30.26 02:36:06 INFO - PROCESS | 8159 | Values: 159.0 131.0 151.0 215.0 126.0 157.0 153.0 129.0 182.0 124.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#2] audio-dft Cycles:10 Average:159.80 Median:159.50 stddev:1.48 (0.9%) stddev-sans-first:1.56 02:36:06 INFO - PROCESS | 8159 | Values: 160.0 161.0 159.0 159.0 158.0 159.0 159.0 161.0 163.0 159.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#3] audio-fft Cycles:10 Average:88.10 Median:75.00 stddev:28.36 (37.8%) stddev-sans-first:29.75 02:36:06 INFO - PROCESS | 8159 | Values: 76.0 75.0 75.0 152.0 75.0 75.0 75.0 74.0 130.0 74.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:90.00 stddev:23.46 (26.1%) stddev-sans-first:24.29 02:36:06 INFO - PROCESS | 8159 | Values: 90.0 90.0 152.0 90.0 91.0 92.0 123.0 91.0 90.0 136.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 02:36:06 INFO - PROCESS | 8159 | Values: 117.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 116.0 116.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#6] imaging-darkroom Cycles:10 Average:144.70 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 02:36:06 INFO - PROCESS | 8159 | Values: 145.0 145.0 145.0 145.0 144.0 144.0 145.0 145.0 144.0 145.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 02:36:06 INFO - PROCESS | 8159 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:56.00 stddev:16.95 (30.3%) stddev-sans-first:17.89 02:36:06 INFO - PROCESS | 8159 | Values: 56.0 56.0 56.0 55.0 56.0 55.0 56.0 109.0 55.0 54.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 02:36:06 INFO - PROCESS | 8159 | Values: 59.0 60.0 59.0 59.0 59.0 59.0 59.0 60.0 60.0 60.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.50 stddev:2.50 (3.1%) stddev-sans-first:2.60 02:36:06 INFO - PROCESS | 8159 | Values: 78.0 78.0 77.0 76.0 80.0 79.0 85.0 81.0 79.0 80.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:121.00 stddev:28.21 (23.3%) stddev-sans-first:29.79 02:36:06 INFO - PROCESS | 8159 | Values: 121.0 126.0 125.0 117.0 116.0 116.0 117.0 117.0 208.0 121.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:162.00 stddev:5.51 (3.4%) stddev-sans-first:5.75 02:36:06 INFO - PROCESS | 8159 | Values: 160.0 177.0 162.0 164.0 162.0 165.0 162.0 160.0 159.0 157.0 02:36:06 INFO - PROCESS | 8159 | 02:36:06 INFO - PROCESS | 8159 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.50 stddev:3.50 (5.3%) stddev-sans-first:3.14 02:36:06 INFO - PROCESS | 8159 | Values: 70.0 67.0 62.0 70.0 62.0 62.0 64.0 61.0 62.0 67.0 02:36:06 INFO - PROCESS | 8159 | -------- Summary: end -------- 02:36:06 INFO - PROCESS | 8159 | 02:36:07 INFO - TEST-INFO | 8159: exit 0 02:36:07 INFO - TEST-OK | kraken | took 62123ms 02:36:07 INFO - SUITE-END | took 1174s 02:36:07 INFO - Completed test suite (00:19:34) 02:36:07 INFO - javascript benchmark 02:36:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.6222853257605, 3150.7951983121097, 3159.2259896325404, 3175.1585829755104, 3217.372612340593, 5251.448597897223, 5257.710825904578, 5299.576033917287, 5381.488758567934, 5404.054290499157, 5285.86785330366, 5323.041852466555, 5337.839864804052, 5354.116570765828, 5370.624056316059, 4813.013332266885, 4858.757062146896, 4897.657163998521, 4907.337509436216, 5013.849584512453, 4920.3357546731095, 4928.285398617199, 4962.751862406865, 4980.7509624518625, 4992.051510213066, 4725.17309470843, 4816.325714399987, 4854.029194161164, 4874.0, 4875.512448755132, 4869.488703686133, 4951.3810773653295, 4957.652964292501, 4961.107000739901, 5002.299678045077, 4692.202325604621, 4818.903621927542, 4893.388326459192, 4913.877412530199, 4959.479254678279, 5255.816046438356, 5307.628466007381, 5343.225232341289, 5344.652597581145, 5356.9732151339385, 5763.193152958589, 5776.364599894008, 5788.0, 5791.623544469639, 5793.73928173229, 5535.142052981794, 5684.857878553028, 5714.742836572323, 5818.5345172386105, 5834.5040671542965, 5391.676499410009, 5408.78364865401, 5417.26866872971, 5419.647722897999, 5420.593455490814, 5934.584579079509, 5939.22790037301, 5945.197398351167, 5945.1973983512535, 5948.375420580864, 5529.391766905717, 5581.553475721932, 5642.887142257132, 5664.631798933056, 5682.801101961411, 5739.110437882135, 5744.856378590485, 5751.051076572402, 5790.36306006339, 5804.535637149018, 5705.5150312223095, 5706.9429305707245, 5713.22871412354, 5713.4000929902595, 5714.885702285931, 5517.917231241535, 5637.661740295595, 5686.232358631614, 5697.173909783141, 5711.400302968212, 4352.564743525622, 4353.912921741611, 4357.1939191249085, 4367.650587952956, 4391.297392417198, 3828.2343531293855, 3891.3384724596885, 3902.453656488094, 3911.8239679214503, 3957.1887763008513, 5361.785528578813, 5386.48828361305, 5418.539424148913, 5434.347878254634, 5439.238506609078, 5764.942350576441, 5765.04876695349, 5787.421257874263, 5788.88422231553, 5794.797182098607, 5253.369595648546, 5358.839234822962, 5418.4039755626845, 5422.810201642924, 5425.159100339453, 4972.378452693413, 4973.229149481836, 4991.276264941564, 4993.1262029144755, 5023.8492845214705, 6029.487493563096, 6029.939700602938, 6031.095335699592, 6033.517318614413, 6034.064719968412, 5582.972085139549, 5732.885342293047, 5792.594518383841, 5832.94167058324, 5851.0, 6003.129546215687, 6003.519718422603, 6010.0, 6048.667323297261, 6062.090686396899, 5706.229658995982, 5710.3433105193435, 5711.685857277889, 5720.199172115824, 5721.885562288648, 5524.364698059614, 5680.602357834912, 5684.7157642118555, 5793.188953546591, 5804.332501762351, 5594.748236329294, 5642.0, 5643.802466913638, 5644.040513112863, 5680.886382272414, 5584.329880414376, 5621.184928185308, 5636.520895723909, 5644.181593668813, 5649.293838270216, 5366.758495867617, 5466.043442397644, 5501.092319767194, 5523.116301391758, 5525.806596769094, 5685.374608793112, 5688.175214593944, 5744.310682718101, 5750.884982300414, 5758.107493338539, 5585.273914391103, 5586.888262234814, 5588.357338905916, 5588.608797384144, 5630.211770352072], "value": 5303.074680805105, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.7819185093366, 630.5082036011911, 639.2073828452718, 650.9153810004699, 660.7555204574309, 34084.31831363371, 37331.626683733186, 45037.09925801486, 46136.30795538061, 46174.538254617444, 48523.75738121313, 48662.27006594904, 48945.51054489454, 48957.265641015416, 48999.51000490003, 8854.247388971946, 9033.774155646095, 9035.864462033074, 9039.322050846213, 9043.0, 8807.64769409223, 8892.37753357262, 8922.375433719642, 8923.107689231058, 8924.286057115447, 8965.282777377826, 8974.955125224365, 8982.775430614221, 8984.281257499417, 8985.37102402832, 8859.246964008034, 8903.821923561558, 8908.376413651014, 8910.064443233465, 8914.465132092031, 8765.474071555727, 8823.161799629024, 8847.73456796297, 8849.380543361967, 8853.778655533599, 43786.56213437857, 44171.337429938256, 44180.779096104314, 44187.0, 44256.557434425566, 3470.16715988161, 3769.7738135711943, 3852.84588616455, 3885.494885664874, 4103.2409004334295, 33604.49593256103, 34078.48882266768, 34203.65796342029, 34257.314853703036, 35297.82351088254, 4108.876733697994, 4149.107941792499, 4205.558416366299, 4240.597143271384, 4338.240807858644, 331938.0, 332691.0289036999, 333837.0, 334574.0, 335023.0, 54683.72658136684, 60246.39753602495, 61414.692926535085, 61479.692601536706, 62031.689841550506, 57274.140887886715, 63606.68196658987, 63729.36270637327, 63753.681231593546, 63827.68086159539, 17657.0, 17834.91082544579, 17846.0, 17846.91076544609, 17852.55368615795, 12680.365981700877, 13269.535566255136, 13324.73350532984, 13595.728085438433, 13951.930240348733, 19222.423327300203, 19301.227950882087, 19348.4195474136, 19400.126994285292, 19407.708884366744, 6997.895031574531, 7106.5736055836815, 7115.466340024519, 7195.676194571257, 7205.711771529185, 1026.810040142576, 1064.5954537275786, 1124.1344164992909, 1179.2688533109356, 1251.599488163797, 32793.18017049545, 33453.66546334554, 33677.14775687312, 33854.49218261728, 34324.828375857964, 1203.2599951030054, 1208.5649166300125, 1218.5552273420137, 1232.7287996640725, 1243.241622610225, 17386.826131738773, 17851.553711157325, 17860.55348616295, 17895.821041789677, 17910.91044544769, 514597.0, 516104.0, 518289.0, 519010.0, 520074.0, 59857.401425986056, 59943.70028149831, 60082.09876851851, 60117.39882601205, 60160.69919650374, 1399.2164387942785, 1441.0473807990231, 1442.0986883198002, 1453.3677850135014, 1457.8760805331453, 31364.372712545166, 32115.35769284648, 33037.668586244436, 33162.83418582891, 33376.165595859326, 53123.0, 53278.46721532735, 53604.46395535995, 53606.73196633992, 53637.46362536325, 408965.0, 409247.0, 409484.0, 409667.0, 448690.0, 42250.788746057304, 43169.784151079046, 43381.3492797602, 46539.0, 46877.76561117172, 48704.2694359592, 48822.511774881794, 49254.507454926425, 49768.50231497639, 50216.74891625519, 41482.58517414908, 41937.1612567753, 42030.0, 42447.787761060994, 43345.78327108344, 39415.40876886906, 42095.57904421039, 42356.0, 42499.0, 43374.78312608417, 41988.3701744468, 42914.57085429231, 43212.0, 43253.56746432495, 43310.56689433191, 35806.0, 36359.81820090988, 37105.44341834821, 37845.05387365338, 38427.42358864563, 26754.866225668746, 29270.41459170762, 29431.85284073566, 29449.411011780074, 29636.703632963396], "value": 20928.1269987109, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.1836785612703, 410.6612045062823, 411.1078958659708, 417.5490470292085, 419.5573669778383, 18083.819161808377, 18904.432867013977, 19966.7004994925, 19990.200391984323, 20080.799192008075, 18368.448946531593, 18582.628347433045, 19486.707699384522, 19546.511337216576, 19579.0, 5542.944570554283, 5593.021221286269, 5834.795782147626, 5839.883202335951, 5948.702564871761, 5784.971075144618, 5793.739281732311, 5805.535557155438, 5824.796132135376, 5832.737526811304, 5730.19777231188, 5827.41725827416, 5829.242198514187, 5864.23764910561, 5888.116782482641, 5799.565032622549, 5829.0964900440285, 5834.970825145869, 5869.589128760988, 6449.290578036413, 5733.885322293572, 5749.8275051748515, 5754.050581654022, 5776.306843178845, 5777.277840269966, 12368.567100151451, 12619.621411357673, 13815.378307976165, 13851.16892986424, 13882.4447022119, 2440.060576677974, 2549.3371723351875, 2638.8020898432696, 2677.8259413138085, 2780.7914406419395, 9977.900220997843, 10027.949860250652, 10598.205134614935, 12224.388780560936, 14694.750946169588, 1952.570434504421, 1964.5285131568464, 1965.1942703491643, 2012.0543344628002, 2065.710800487933, 57155.142672859525, 69374.99999999949, 71647.64176179138, 76697.0, 80429.0, 10898.94550527258, 11035.44822758859, 11152.609658661908, 11252.26860254081, 11522.444469996442, 13446.260455675032, 14264.500742473963, 15141.091534507965, 15161.0, 15735.44925927587, 8355.248027677539, 8455.816185734002, 8507.149285071444, 8613.181747733955, 8657.480551166951, 8112.553809540414, 8163.265306122477, 8226.629801658939, 8302.8339433211, 8465.32277417805, 4792.688475249098, 4849.927251091236, 4906.8773280668265, 4919.1883339248625, 4943.060818444484, 1934.148974451265, 1991.7809041005478, 2006.6488364536149, 2033.9796602034087, 2073.2018173003503, 959.1990687775645, 1014.5231741081827, 1042.4162469017374, 1090.378484263962, 1127.115214556569, 18960.345551622508, 19044.428667140008, 19623.0, 19730.37747153996, 19767.406977790688, 1055.567217440861, 1072.8766191887876, 1073.6196319018386, 1074.4520294649628, 1089.3137323486153, 10371.792564148675, 11103.333799972028, 11163.162762792823, 11174.888251117547, 11206.383648899226, 473577.0, 478014.0, 483917.0, 484679.0, 489765.0, 55976.720116399156, 58616.70691646515, 78639.60680196562, 92428.99999999866, 93010.53494732484, 1037.9532921018572, 1053.1522124689698, 1056.213121224708, 1073.9904489779738, 1076.3810808784824, 16029.358825646843, 16985.235664394884, 17214.827851721824, 17220.78952588802, 17305.740413894055, 41440.792796037036, 42236.78881605572, 42568.787156065264, 43124.137517248855, 43347.0, 54616.18075728947, 56082.1587676177, 56314.718426407606, 56424.0, 57176.42823571878, 8880.245179159843, 9014.774630634025, 9059.320550958837, 9138.406003609743, 9329.393589416666, 8254.463459875089, 12676.0, 12739.490420383081, 13776.0, 14027.228502432465, 9735.61057557718, 11933.462994165457, 12240.204386714835, 12421.565245216374, 12712.74574508523, 11288.779147887595, 11661.35862527569, 11957.701057473632, 12093.0, 12326.876731232573, 6581.407673309425, 10364.740881478023, 12571.912857071857, 12637.68405789826, 12709.983201343686, 8813.0, 8959.462432253826, 9036.45781253127, 9158.95420522893, 9255.861162082709, 12577.496900123893, 12626.810597840857, 12703.110782245523, 12754.107212495408, 12853.16454430459], "value": 9197.454005003912, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.9165450073003, 1507.6230942264433, 1551.0383562191444, 1723.3451288510364, 1890.1706140810234, 8607.354448416363, 9016.188543031127, 9021.458712477248, 9296.442213467197, 9318.72043838686, 9075.90924090759, 9242.722718318444, 9257.583408746606, 9260.814783704322, 9280.0, 2070.2960993262286, 2301.6892719482908, 2364.4443555764383, 2390.760923907613, 2419.5160967806423, 2367.337145599235, 2371.419002344428, 2372.4780548279437, 2375.323032935614, 2375.4773949731116, 2368.159303447277, 2378.6907701998803, 2398.880055997202, 2400.663907053014, 2404.903803847844, 1963.3717210492648, 2274.9545009099893, 2314.0975019742314, 2336.4275752440763, 2338.3920180752957, 2322.500662357584, 2339.181286549694, 2342.8711420871846, 2342.976570234293, 2343.906243750248, 9060.728178154666, 9110.0434454382, 9119.088091190893, 9127.817443651156, 9175.174234318943, 5400.91898621521, 5524.309461317335, 5569.192467092249, 5571.108622620402, 5571.554275657938, 3406.114410253327, 3422.709069729076, 3483.686468217847, 3552.5559305086863, 3553.7690050146657, 1116.2242241642061, 1133.1501373969522, 1164.7903377392065, 1166.830809532613, 1195.1454709882476, 12959.092863499558, 13105.672358191121, 13113.934430327883, 13119.081664283409, 13124.737505249841, 4571.0, 4613.607843333354, 4731.787069581877, 4733.952660473351, 4737.8104875805275, 10224.948875255575, 10256.794864102676, 10265.794684106277, 10281.0, 10292.022257885488, 5357.517823395913, 5429.104197807396, 5432.511074003359, 5435.945640543623, 5440.564754819605, 4334.891627709332, 4771.379720636295, 4919.065377578248, 4920.040592084509, 4978.979309241597, 3921.11774850662, 3962.623550762673, 3994.3409337459575, 4050.311447054008, 4062.1266427718183, 1192.6600918738118, 1225.601679454181, 1226.938653067343, 1228.2753175626424, 1249.6126200877757, 909.3725329522608, 913.9954300228457, 932.5430539035824, 933.6032186320787, 951.4671783801093, 5784.566157538202, 5803.303603567599, 5883.823485295448, 6064.514838812885, 6255.530835187379, 445.4632168237266, 446.8860440587694, 453.6982906367295, 453.83208212961415, 458.2553350804942, 4268.274393353137, 4273.273543497613, 4288.807003684841, 4337.652987761035, 4338.7830608470085, 11196.440177991231, 11524.135689823297, 11526.711832204095, 11580.131490138105, 11591.826122608167, 11386.487608057658, 11402.258853174517, 11476.19666623345, 11487.827682584768, 11510.712232194262, 8181.918180818235, 8534.231919127309, 8584.957075214585, 8817.867731984026, 8826.735197944072, 5997.370276120944, 6257.812265632038, 6312.747490100528, 6355.777547785805, 6477.546571739933, 10028.29801913859, 10405.739856503647, 10605.416702081151, 10710.625128120479, 10734.570617175226, 10397.272190946864, 10695.03744662984, 10717.571297148026, 10757.892421075689, 10820.56717731282, 7439.367653749276, 7672.424568157299, 7682.193369696325, 7972.800679983046, 8123.878141827996, 8396.916030839613, 8616.224539791448, 8659.13408659129, 8830.867536987078, 8837.558122093998, 8037.919620803717, 8183.181681831769, 8185.140560241327, 8256.504609723435, 8281.585920703821, 7912.0, 8069.878951815845, 8097.797555061171, 8102.392320575863, 8122.796930076795, 7246.1666908306215, 7248.492605517563, 7330.780076597711, 7357.043584334002, 7387.224341444071, 5318.867028324323, 5400.378956419905, 5506.449355064461, 5525.751341189576, 5542.334919809648, 5355.544778693698, 5449.6730196188355, 5477.178423236546, 5570.58220633446, 5658.717064146694], "value": 4671.050052791466, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.78637671889993, 340.11230687904566, 342.92455659754853, 353.4697953070394, 353.78242380935734, 5411.864703382417, 5463.3717122530825, 5471.68679516916, 5503.20203570482, 5533.778648854051, 5311.548518375933, 5340.54605358545, 5357.49103835136, 5464.398916119224, 5477.260569823073, 4001.1197536541913, 4231.1749208904275, 4242.469691288589, 4246.36304554317, 4274.187904298174, 4240.893977650568, 4252.8511502097435, 4259.552746961571, 4263.742196052157, 4271.743695378272, 4207.747535147885, 4218.957810421903, 4230.619244268015, 4249.87250382489, 4287.292596721537, 4064.532578753437, 4107.158032603309, 4195.7063005589625, 4195.895102622429, 4212.220739163265, 3673.246984368198, 3691.169486865461, 3696.667299942991, 3697.6672099511175, 3734.066483379155, 5057.974710126463, 5249.580033597303, 5253.448387919251, 5253.474652534755, 5262.8684282892855, 4763.261694437368, 4876.804927802884, 4893.8776530586665, 4895.657303988723, 4901.191303434929, 5661.405552416978, 5669.688167150805, 5675.921574900755, 5691.48776610103, 5730.255066841326, 3986.900327491807, 4128.587141285878, 4153.418521407006, 4187.14163596462, 4199.853005144836, 5839.503642190419, 5853.64878107315, 5867.383924687889, 5887.352391237004, 5900.675462849542, 4270.978645106755, 4286.571342865688, 4297.763623000703, 4315.719478233905, 4323.135372925428, 4236.533981262059, 4312.805923733439, 4341.848035318749, 4364.127174565099, 4375.518692943773, 4856.465788763233, 4856.73287969158, 4873.488283730228, 4878.292647566083, 4879.9016422938475, 5673.631213971079, 5683.914741278884, 5707.315122185364, 5726.570507211975, 5729.713514324267, 1426.1585664457812, 1485.6211666025094, 1496.6632507685683, 1502.2864139533633, 1515.454436402894, 960.596549449233, 962.0235461007084, 962.6486332488591, 983.9852402213972, 984.5077461269366, 4828.420589529209, 4829.44461386945, 4879.682820616648, 4908.8155770682415, 4921.3356196912955, 5112.488751124858, 5197.350331208599, 5198.844034678966, 5209.661372010807, 5218.582513398919, 4870.634702397334, 5251.76367063483, 5310.468953104736, 5468.562514998791, 5495.285612870382, 3063.92340191497, 3077.1230199393513, 3082.676318986519, 3096.2723759916403, 3101.5037593984853, 5769.826905192851, 6016.7292471838, 6032.909506357408, 6048.939510604926, 6056.606320589148, 5850.5612079094235, 5981.252343457068, 5987.431194036647, 6029.336772954972, 6037.879242415127, 5967.492763115097, 5976.416249693834, 6002.189704390026, 6008.849778755479, 6032.3967603239325, 5644.548436125018, 5760.452756988079, 5808.419158084327, 5825.970870145792, 5826.649676435982, 5734.713264336683, 5757.165211044459, 5767.798127065533, 5803.7388317524965, 5812.651240925558, 5729.4843464088435, 5984.371640977634, 6006.519478441802, 6024.216851809237, 6052.394760523913, 4150.958490415057, 4169.854055108057, 4212.0, 4218.240716671029, 4236.728981305658, 5924.763009479745, 5946.453921980403, 5946.494547963472, 5949.315828679757, 5966.254218222723, 5636.393627816053, 5721.971390143022, 5727.799527016535, 5761.711914404347, 5834.824955251349, 5679.734152862038, 5708.714564271853, 5716.971415142898, 5728.283964504437, 5735.627184233012, 5370.355557332989, 5584.888302234014, 5618.494335509824, 5662.5469962402085, 5662.971685141548, 5674.233978412862, 5715.514181294637, 5740.282464691913, 5744.425557444222, 5747.453991870766, 5422.755975981011, 5584.888302234014, 5597.384287728428, 5620.943790562042, 5631.49316561495], "value": 4325.847419089633, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.8996920369555, 853.4495250563383, 862.2196911794824, 865.2774932931002, 882.4749274181862, 15085.849141508581, 15448.922755386207, 15462.84537154631, 15475.14886681232, 15515.534533963984, 14256.928715356435, 14929.92535037326, 14970.925145374285, 14978.925105374485, 14991.400343986228, 839.836231934773, 850.8596081646521, 853.2832420766555, 853.4751128056232, 854.1927878155146, 703.3704834173411, 721.6824597177225, 722.0072400449382, 723.4176487927216, 849.9490030598153, 868.3010176807667, 897.1208215948375, 899.9235065019449, 901.4681338010572, 902.2195800632438, 888.2449917570078, 888.4580405952364, 888.844452220862, 888.9244414224798, 889.0131953531575, 541.8997485465202, 624.272722278545, 644.44899610833, 646.0793369448517, 646.0857886091176, 14265.286735663176, 14276.428942842274, 14287.357068931819, 14304.57086287413, 14315.642108947255, 13158.276294803782, 13978.370973306124, 13997.440102395893, 14091.436342546285, 14171.716565668732, 11503.0, 11628.360440175788, 11696.47365868538, 12041.819372709417, 12124.03007759386, 4623.21264985861, 4647.209974304376, 4674.275487299474, 4706.835260765891, 4811.687240329403, 62217.688911555604, 63053.36946630567, 63572.36427635757, 63769.68115159441, 63826.0, 11298.774024519627, 11316.547338106384, 11317.434128293553, 11426.828597571042, 11837.289762614098, 20344.18623255053, 20473.385798426072, 20561.177552897716, 20572.27997020127, 20599.588008239753, 8497.74506764798, 8635.568221589021, 8760.386772925835, 8761.956190219009, 8928.419652722552, 4842.903141937141, 4903.313536104948, 4926.088673595396, 4930.433000204951, 5177.326947496801, 3464.185916309665, 3548.840302186408, 3548.8757893473603, 3553.200529880756, 3557.8043207623828, 655.4265018109154, 656.8193746719691, 658.4929604204735, 678.9388954994074, 687.9002544631101, 642.5125833694981, 753.182796665624, 790.707438247852, 809.1301850510724, 829.3158144530786, 9479.715608531755, 9696.17582505495, 9850.901490984998, 10080.59677612902, 10084.546195421224, 610.7709608896664, 661.7716887673745, 667.2218587036339, 751.2112282103769, 768.8539177556246, 4267.210566045292, 4349.173657005159, 4390.846320378771, 4426.1369033038245, 4679.532046795362, 65995.0, 66153.66923165353, 67208.6639566799, 67277.32722672808, 69163.3083669167, 31321.686783132332, 55459.445405546234, 58579.70710146422, 60346.39653603408, 61160.96648885346, 49586.0, 50760.74619626878, 53687.463125369024, 55162.448375515734, 55275.44724552784, 9693.224542036762, 9698.27262955267, 9937.701868943943, 9994.650187243411, 10005.249606279413, 37536.43695344627, 37730.8113459431, 37862.0, 37872.81063594665, 37956.24087518179, 46709.2993605103, 50473.747631261605, 50641.49358506515, 50683.74658126833, 52011.479885200664, 12557.246565206116, 12661.893492306092, 12802.103852730219, 12936.676583085498, 13342.599722008355, 14439.133651980916, 14639.268036597914, 14908.7763683547, 15391.923040384727, 15426.151561664215, 13683.315834208448, 13932.791008134684, 14087.225202613954, 14438.639034024234, 14836.703265934837, 13306.135101218777, 13350.9332453341, 13499.797503037658, 13756.243406612732, 13807.447702091804, 13256.0, 13474.79787803164, 14147.00970932066, 14386.856131438552, 14681.339339729524, 10956.451685091235, 10957.0, 11103.278286911325, 11293.265937714023, 11666.70833229176, 10951.178661600252, 11440.942795286302, 11781.52873885035, 11803.114766392384, 11842.703932401759], "value": 7147.29895481796, "unit": "score"}], "name": "dromaeo_css", "value": 7268.305036458599, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 131.0, 151.0, 215.0, 126.0, 157.0, 153.0, 129.0, 182.0, 124.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 159.0, 158.0, 159.0, 159.0, 161.0, 163.0, 159.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 152.0, 75.0, 75.0, 75.0, 74.0, 130.0, 74.0], "value": 88.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 152.0, 90.0, 91.0, 92.0, 123.0, 91.0, 90.0, 136.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 144.0, 144.0, 145.0, 145.0, 144.0, 145.0], "value": 144.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 55.0, 56.0, 55.0, 56.0, 109.0, 55.0, 54.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 77.0, 76.0, 80.0, 79.0, 85.0, 81.0, 79.0, 80.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 126.0, 125.0, 117.0, 116.0, 116.0, 117.0, 117.0, 208.0, 121.0], "value": 128.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 177.0, 162.0, 164.0, 162.0, 165.0, 162.0, 160.0, 159.0, 157.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 62.0, 70.0, 62.0, 62.0, 64.0, 61.0, 62.0, 67.0], "value": 64.7, "unit": "score"}], "name": "kraken", "value": 1525.0000000000002, "alertThreshold": 2.0}]} 02:36:07 INFO - Return code: 0 02:36:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:36:08 ERROR - # TBPL SUCCESS # 02:36:08 INFO - Running post-action listener: _resource_record_post_action 02:36:08 INFO - ##### 02:36:08 INFO - ##### Finished run-tests step (success) 02:36:08 INFO - ##### 02:36:08 INFO - Running post-run listener: _resource_record_post_run 02:36:08 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 536879104; Read time: 0; Write time: 359924 02:36:08 INFO - TinderboxPrint: CPU usage
13.1% 02:36:08 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 02:36:08 INFO - TinderboxPrint: I/O write bytes / time
536,879,104 / 359,924 02:36:08 INFO - TinderboxPrint: CPU idle
8,267.9 (86.9%) 02:36:08 INFO - TinderboxPrint: CPU user
1,202.6 (12.6%) 02:36:08 INFO - TinderboxPrint: Swap in / out
0 / 0 02:36:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246345728; Read time: 0; Write time: 175644 02:36:08 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 290533376; Read time: 0; Write time: 184280 02:36:08 INFO - Running post-run listener: _upload_blobber_files 02:36:08 INFO - Blob upload gear active. 02:36:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:36:08 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 02:36:08 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:36:08 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:36:09 INFO - (blobuploader) - INFO - Open directory for files ... 02:36:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:36:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:36:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:09 INFO - (blobuploader) - INFO - Done attempting. 02:36:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:36:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:36:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:10 INFO - (blobuploader) - INFO - Done attempting. 02:36:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:36:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:36:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:36:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:36:14 INFO - (blobuploader) - INFO - Done attempting. 02:36:14 INFO - (blobuploader) - INFO - Iteration through files over. 02:36:14 INFO - Return code: 0 02:36:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:36:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:36:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e1afc3bbc8678ba60a56e86b802de4861dc18975b166dfc97b947adaebbf249cc12584efc571c4e31473ef84727566a70e419d24b4d903d85b8d5f04472aa06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b83fbca2f8c442a48b21bc442b4b2b7b2dd3aa74528f5d4b8e56aa3aaeb271517a484cd46715662369b0497cc6df3acead4ac104042ceac8e952db76b3e92d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbb96073b3031f65b1eb2ceca86a08d86de4be9dfc53be97971f02db96215cc39664bc267fe05ce9d784b69a8971f1366da62cb0d80f837c1d8a9068cd2e8da6"} 02:36:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:36:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:36:14 INFO - Contents: 02:36:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e1afc3bbc8678ba60a56e86b802de4861dc18975b166dfc97b947adaebbf249cc12584efc571c4e31473ef84727566a70e419d24b4d903d85b8d5f04472aa06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b83fbca2f8c442a48b21bc442b4b2b7b2dd3aa74528f5d4b8e56aa3aaeb271517a484cd46715662369b0497cc6df3acead4ac104042ceac8e952db76b3e92d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbb96073b3031f65b1eb2ceca86a08d86de4be9dfc53be97971f02db96215cc39664bc267fe05ce9d784b69a8971f1366da62cb0d80f837c1d8a9068cd2e8da6"} 02:36:14 INFO - Running post-run listener: copy_logs_to_upload_dir 02:36:14 INFO - Copying logs to upload dir... 02:36:14 INFO - mkdir: /builds/slave/test/build/upload/logs 02:36:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.879213 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-16 02:36:14.051176) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 02:36:14.055029) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e1afc3bbc8678ba60a56e86b802de4861dc18975b166dfc97b947adaebbf249cc12584efc571c4e31473ef84727566a70e419d24b4d903d85b8d5f04472aa06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b83fbca2f8c442a48b21bc442b4b2b7b2dd3aa74528f5d4b8e56aa3aaeb271517a484cd46715662369b0497cc6df3acead4ac104042ceac8e952db76b3e92d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbb96073b3031f65b1eb2ceca86a08d86de4be9dfc53be97971f02db96215cc39664bc267fe05ce9d784b69a8971f1366da62cb0d80f837c1d8a9068cd2e8da6"} build_url:https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/R-h4okpgT6-xrog2OPqiuA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9e1afc3bbc8678ba60a56e86b802de4861dc18975b166dfc97b947adaebbf249cc12584efc571c4e31473ef84727566a70e419d24b4d903d85b8d5f04472aa06", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b83fbca2f8c442a48b21bc442b4b2b7b2dd3aa74528f5d4b8e56aa3aaeb271517a484cd46715662369b0497cc6df3acead4ac104042ceac8e952db76b3e92d90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bbb96073b3031f65b1eb2ceca86a08d86de4be9dfc53be97971f02db96215cc39664bc267fe05ce9d784b69a8971f1366da62cb0d80f837c1d8a9068cd2e8da6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 02:36:14.080140) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:36:14.080499) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1463387535.434456-705620627 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:36:14.164519) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 02:36:14.164874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 02:36:14.165184) ========= ========= Total master_lag: 0.17 =========