builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-031 starttime: 1464272258.19 results: success (0) buildid: 20160526064851 builduid: c3bc65ec86c4435da3b89c3d4ec24ef6 revision: 9afab6308cd97d470c6514ccb0e348103e034e4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.187206) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.187619) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.187894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.221951) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.222206) ========= 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=db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-26 07:17:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-05-26 07:17:38 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133394 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.369146) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.369435) ========= 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=db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014522 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.418954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:38.419261) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9afab6308cd97d470c6514ccb0e348103e034e4d --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 9afab6308cd97d470c6514ccb0e348103e034e4d --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=db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-26 07:17:38,674 truncating revision to first 12 chars 2016-05-26 07:17:38,674 Setting DEBUG logging. 2016-05-26 07:17:38,675 attempt 1/10 2016-05-26 07:17:38,675 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9afab6308cd9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 07:17:38,977 unpacking tar archive at: fx-team-9afab6308cd9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.653169 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:39.085825) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:39.086168) ========= script_repo_revision: 9afab6308cd97d470c6514ccb0e348103e034e4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:39.086585) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:39.086865) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 07:17:39.097124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-26 07:17:39.097414) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:17:39 INFO - MultiFileLogger online at 20160526 07:17:39 in /builds/slave/test 07:17:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 07:17:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:17:39 INFO - {'append_to_log': False, 07:17:39 INFO - 'base_work_dir': '/builds/slave/test', 07:17:39 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 07:17:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:17:39 INFO - 'branch': 'Fx-Team-Non-PGO', 07:17:39 INFO - 'buildbot_json_path': 'buildprops.json', 07:17:39 INFO - 'config_files': ('talos/linux_config.py',), 07:17:39 INFO - 'default_actions': ('clobber', 07:17:39 INFO - 'read-buildbot-config', 07:17:39 INFO - 'download-and-extract', 07:17:39 INFO - 'populate-webroot', 07:17:39 INFO - 'create-virtualenv', 07:17:39 INFO - 'install', 07:17:39 INFO - 'run-tests'), 07:17:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:17:39 INFO - 'download_minidump_stackwalk': True, 07:17:39 INFO - 'download_symbols': 'ondemand', 07:17:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:17:39 INFO - 'tooltool.py': '/tools/tooltool.py', 07:17:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:17:39 INFO - '/tools/misc-python/virtualenv.py')}, 07:17:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:17:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:17:39 INFO - 'installer_path': 'installer.exe', 07:17:39 INFO - 'log_level': 'info', 07:17:39 INFO - 'log_name': 'talos', 07:17:39 INFO - 'log_to_console': True, 07:17:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:17:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:17:39 INFO - 'opt_config_files': (), 07:17:39 INFO - 'pip_index': False, 07:17:39 INFO - 'sps_profile': False, 07:17:39 INFO - 'sps_profile_interval': 0, 07:17:39 INFO - 'suite': 'dromaeojs', 07:17:39 INFO - 'system_bits': '32', 07:17:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:17:39 INFO - 'title': 'talos-linux64-ix-031', 07:17:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:17:39 INFO - 'use_talos_json': True, 07:17:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:17:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:17:39 INFO - 'work_dir': 'build'} 07:17:39 INFO - ##### 07:17:39 INFO - ##### Running clobber step. 07:17:39 INFO - ##### 07:17:39 INFO - Running pre-action listener: _resource_record_pre_action 07:17:39 INFO - Running main action method: clobber 07:17:39 INFO - rmtree: /builds/slave/test/build 07:17:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:17:40 INFO - Running post-action listener: _resource_record_post_action 07:17:40 INFO - ##### 07:17:40 INFO - ##### Finished clobber step (success) 07:17:40 INFO - ##### 07:17:40 INFO - ##### 07:17:40 INFO - ##### Running read-buildbot-config step. 07:17:40 INFO - ##### 07:17:40 INFO - Running pre-action listener: _resource_record_pre_action 07:17:40 INFO - Running main action method: read_buildbot_config 07:17:40 INFO - Using buildbot properties: 07:17:40 INFO - { 07:17:40 INFO - "project": "", 07:17:40 INFO - "product": "firefox", 07:17:40 INFO - "script_repo_revision": "production", 07:17:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 07:17:40 INFO - "repository": "", 07:17:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 07:17:40 INFO - "buildid": "20160526064851", 07:17:40 INFO - "pgo_build": "False", 07:17:40 INFO - "basedir": "/builds/slave/test", 07:17:40 INFO - "buildnumber": 1726, 07:17:40 INFO - "slavename": "talos-linux64-ix-031", 07:17:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:17:40 INFO - "platform": "ubuntu64_hw", 07:17:40 INFO - "scheduler": "tests-fx-team-linux64-talos", 07:17:40 INFO - "branch": "fx-team", 07:17:40 INFO - "repo_path": "integration/fx-team", 07:17:40 INFO - "revision": "9afab6308cd97d470c6514ccb0e348103e034e4d", 07:17:40 INFO - "stage_platform": "linux64", 07:17:40 INFO - "builduid": "c3bc65ec86c4435da3b89c3d4ec24ef6", 07:17:40 INFO - "slavebuilddir": "test" 07:17:40 INFO - } 07:17:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:17:40 INFO - Running post-action listener: _resource_record_post_action 07:17:40 INFO - ##### 07:17:40 INFO - ##### Finished read-buildbot-config step (success) 07:17:40 INFO - ##### 07:17:40 INFO - ##### 07:17:40 INFO - ##### Running download-and-extract step. 07:17:40 INFO - ##### 07:17:40 INFO - Running pre-action listener: _resource_record_pre_action 07:17:40 INFO - Running main action method: download_and_extract 07:17:40 INFO - mkdir: /builds/slave/test/build/tests 07:17:40 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')]} 07:17:40 INFO - https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:17:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:17:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:17:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:41 INFO - Downloaded 1361 bytes. 07:17:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:17:41 INFO - Using the following test package requirements: 07:17:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:17:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:17:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:17:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'jsshell-linux-x86_64.zip'], 07:17:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:17:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:17:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:17:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:17:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:17:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:17:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:17:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:41 INFO - https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:17:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:17:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:17:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:42 INFO - Downloaded 22835896 bytes. 07:17:42 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'] 07:17:42 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 07:17:43 INFO - Return code: 0 07:17:43 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 07:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:43 INFO - https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:17:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:17:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:17:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:44 INFO - Downloaded 22835896 bytes. 07:17:44 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'] 07:17:44 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 07:17:46 INFO - Return code: 0 07:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:46 INFO - https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:17:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:17:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:17:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:47 INFO - Downloaded 10834034 bytes. 07:17:47 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'] 07:17:47 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 07:17:47 INFO - Return code: 0 07:17: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')]} 07:17:47 INFO - https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:17:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:17:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:17:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuJIivqWS2Szg33v_UiXKA/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 07:17:49 INFO - Downloaded 54821078 bytes. 07:17:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:17:49 INFO - mkdir: /builds/slave/test/properties 07:17:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:17:49 INFO - Writing to file /builds/slave/test/properties/build_url 07:17:49 INFO - Contents: 07:17:49 INFO - build_url:https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:17:49 INFO - Running post-action listener: _resource_record_post_action 07:17:49 INFO - Running post-action listener: set_extra_try_arguments 07:17:49 INFO - ##### 07:17:49 INFO - ##### Finished download-and-extract step (success) 07:17:49 INFO - ##### 07:17:49 INFO - ##### 07:17:49 INFO - ##### Running populate-webroot step. 07:17:49 INFO - ##### 07:17:49 INFO - Running pre-action listener: _resource_record_pre_action 07:17:49 INFO - Running main action method: populate_webroot 07:17:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:17:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:17:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:17:49 INFO - '--tppagecycles', 07:17:49 INFO - '7'], 07:17:49 INFO - 'tests': ['tsvgm']}}, 07:17:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:17:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:17:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:17:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:17:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:17:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:17:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['damp', 'tps']}, 07:17:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'tests': ['damp', 'tps']}, 07:17:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['dromaeo_dom']}, 07:17:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:17:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['a11yr', 07:17:49 INFO - 'ts_paint', 07:17:49 INFO - 'tpaint', 07:17:49 INFO - 'sessionrestore', 07:17:49 INFO - 'sessionrestore_no_auto_restore', 07:17:49 INFO - 'tabpaint']}, 07:17:49 INFO - 'other-e10s': {'tests': ['a11yr', 07:17:49 INFO - 'ts_paint', 07:17:49 INFO - 'tpaint', 07:17:49 INFO - 'sessionrestore', 07:17:49 INFO - 'sessionrestore_no_auto_restore', 07:17:49 INFO - 'tabpaint']}, 07:17:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:17:49 INFO - 'ts_paint', 07:17:49 INFO - 'tpaint', 07:17:49 INFO - 'sessionrestore', 07:17:49 INFO - 'sessionrestore_no_auto_restore', 07:17:49 INFO - 'tabpaint']}, 07:17:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:17:49 INFO - 'ts_paint', 07:17:49 INFO - 'tpaint', 07:17:49 INFO - 'sessionrestore', 07:17:49 INFO - 'sessionrestore_no_auto_restore', 07:17:49 INFO - 'tabpaint']}, 07:17:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['a11yr', 07:17:49 INFO - 'ts_paint', 07:17:49 INFO - 'tpaint', 07:17:49 INFO - 'sessionrestore', 07:17:49 INFO - 'sessionrestore_no_auto_restore', 07:17:49 INFO - 'tabpaint']}, 07:17:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['a11yr', 07:17:49 INFO - 'ts_paint', 07:17:49 INFO - 'tpaint', 07:17:49 INFO - 'sessionrestore', 07:17:49 INFO - 'sessionrestore_no_auto_restore', 07:17:49 INFO - 'tabpaint']}, 07:17:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['tsvgx', 07:17:49 INFO - 'tsvgr_opacity', 07:17:49 INFO - 'tart', 07:17:49 INFO - 'tscrollx', 07:17:49 INFO - 'cart']}, 07:17:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:17:49 INFO - 'tsvgr_opacity', 07:17:49 INFO - 'tart', 07:17:49 INFO - 'tscrollx', 07:17:49 INFO - 'cart']}, 07:17:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'talos_options': ['--disable-e10s'], 07:17:49 INFO - 'tests': ['tp5o']}, 07:17:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'tests': ['tp5o']}, 07:17:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'talos_options': ['--disable-e10s', 07:17:49 INFO - '--xperf_path', 07:17:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:17:49 INFO - 'tests': ['tp5n']}, 07:17:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:17:49 INFO - 'talos_options': ['--xperf_path', 07:17:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:17:49 INFO - 'tests': ['tp5n']}}, 07:17:49 INFO - 'talos.zip': {'path': '', 07:17:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:17:49 INFO - Running post-action listener: _resource_record_post_action 07:17:49 INFO - ##### 07:17:49 INFO - ##### Finished populate-webroot step (success) 07:17:49 INFO - ##### 07:17:49 INFO - ##### 07:17:49 INFO - ##### Running create-virtualenv step. 07:17:49 INFO - ##### 07:17:49 INFO - Running pre-action listener: _resource_record_pre_action 07:17:49 INFO - Running main action method: create_virtualenv 07:17:49 INFO - Creating virtualenv /builds/slave/test/build/venv 07:17:49 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 07:17:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:17:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:17:50 INFO - Using real prefix '/usr' 07:17:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:17:50 INFO - Installing distribute.............................................................................................................................................................................................done. 07:17:52 INFO - Installing pip.................done. 07:17:52 INFO - Return code: 0 07:17:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:17:52 INFO - Using env: {'DISPLAY': ':0', 07:17:52 INFO - 'HOME': '/home/cltbld', 07:17:52 INFO - 'LANG': 'en_US.UTF-8', 07:17:52 INFO - 'LANGUAGE': 'en_US:en', 07:17:52 INFO - 'LOGNAME': 'cltbld', 07:17:52 INFO - 'MAIL': '/var/mail/cltbld', 07:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:52 INFO - 'MOZ_NO_REMOTE': '1', 07:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:17:52 INFO - 'NO_EM_RESTART': '1', 07:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:52 INFO - 'PWD': '/builds/slave/test', 07:17:52 INFO - 'SHELL': '/bin/bash', 07:17:52 INFO - 'SHLVL': '1', 07:17:52 INFO - 'TERM': 'linux', 07:17:52 INFO - 'TMOUT': '86400', 07:17:52 INFO - 'USER': 'cltbld', 07:17:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:52 INFO - '_': '/tools/buildbot/bin/python'} 07:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:52 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 07:17:52 INFO - Cleaning up... 07:17:52 INFO - Return code: 0 07:17:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:17:52 INFO - Using env: {'DISPLAY': ':0', 07:17:52 INFO - 'HOME': '/home/cltbld', 07:17:52 INFO - 'LANG': 'en_US.UTF-8', 07:17:52 INFO - 'LANGUAGE': 'en_US:en', 07:17:52 INFO - 'LOGNAME': 'cltbld', 07:17:52 INFO - 'MAIL': '/var/mail/cltbld', 07:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:52 INFO - 'MOZ_NO_REMOTE': '1', 07:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:17:52 INFO - 'NO_EM_RESTART': '1', 07:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:52 INFO - 'PWD': '/builds/slave/test', 07:17:52 INFO - 'SHELL': '/bin/bash', 07:17:52 INFO - 'SHLVL': '1', 07:17:52 INFO - 'TERM': 'linux', 07:17:52 INFO - 'TMOUT': '86400', 07:17:52 INFO - 'USER': 'cltbld', 07:17:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:52 INFO - '_': '/tools/buildbot/bin/python'} 07:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:52 INFO - Downloading/unpacking psutil>=3.1.1 07:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:17:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:17:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:17:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:17:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:17:54 INFO - Installing collected packages: psutil 07:17:54 INFO - Running setup.py install for psutil 07:17:54 INFO - building 'psutil._psutil_linux' extension 07:17:54 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 07:17:55 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 07:17:55 INFO - building 'psutil._psutil_posix' extension 07:17:55 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 07:17:55 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 07:17:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:17:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:17:55 INFO - Successfully installed psutil 07:17:55 INFO - Cleaning up... 07:17:55 INFO - Return code: 0 07:17:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:17:55 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')]} 07:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:55 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')]} 07:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:55 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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:17:55 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 07:17:55 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 07:17:55 INFO - Using env: {'DISPLAY': ':0', 07:17:55 INFO - 'HOME': '/home/cltbld', 07:17:55 INFO - 'LANG': 'en_US.UTF-8', 07:17:55 INFO - 'LANGUAGE': 'en_US:en', 07:17:55 INFO - 'LOGNAME': 'cltbld', 07:17:55 INFO - 'MAIL': '/var/mail/cltbld', 07:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:55 INFO - 'MOZ_NO_REMOTE': '1', 07:17:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:17:55 INFO - 'NO_EM_RESTART': '1', 07:17:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:55 INFO - 'PWD': '/builds/slave/test', 07:17:55 INFO - 'SHELL': '/bin/bash', 07:17:55 INFO - 'SHLVL': '1', 07:17:55 INFO - 'TERM': 'linux', 07:17:55 INFO - 'TMOUT': '86400', 07:17:55 INFO - 'USER': 'cltbld', 07:17:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:55 INFO - '_': '/tools/buildbot/bin/python'} 07:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:17:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:17:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:17:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:17:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:17:57 INFO - Installing collected packages: mozsystemmonitor 07:17:57 INFO - Running setup.py install for mozsystemmonitor 07:17:57 INFO - Successfully installed mozsystemmonitor 07:17:57 INFO - Cleaning up... 07:17:57 INFO - Return code: 0 07:17:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:17:57 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')]} 07:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:57 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')]} 07:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:17:57 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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:17:57 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 07:17:57 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 07:17:57 INFO - Using env: {'DISPLAY': ':0', 07:17:57 INFO - 'HOME': '/home/cltbld', 07:17:57 INFO - 'LANG': 'en_US.UTF-8', 07:17:57 INFO - 'LANGUAGE': 'en_US:en', 07:17:57 INFO - 'LOGNAME': 'cltbld', 07:17:57 INFO - 'MAIL': '/var/mail/cltbld', 07:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:17:57 INFO - 'MOZ_NO_REMOTE': '1', 07:17:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:17:57 INFO - 'NO_EM_RESTART': '1', 07:17:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:17:57 INFO - 'PWD': '/builds/slave/test', 07:17:57 INFO - 'SHELL': '/bin/bash', 07:17:57 INFO - 'SHLVL': '1', 07:17:57 INFO - 'TERM': 'linux', 07:17:57 INFO - 'TMOUT': '86400', 07:17:57 INFO - 'USER': 'cltbld', 07:17:57 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:17:57 INFO - '_': '/tools/buildbot/bin/python'} 07:17:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:17:57 INFO - Downloading/unpacking blobuploader==1.2.4 07:17:57 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 07:17:57 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 07:17:57 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 07:17:57 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 07:17:57 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 07:17:57 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 07:17:59 INFO - Downloading blobuploader-1.2.4.tar.gz 07:17:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:17:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:17:59 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 07:17:59 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 07:17:59 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 07:17:59 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 07:17:59 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 07:17:59 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 07:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18:00 INFO - Downloading docopt-0.6.1.tar.gz 07:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:00 INFO - Running setup.py install for blobuploader 07:18:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:00 INFO - Running setup.py install for requests 07:18:01 INFO - Running setup.py install for docopt 07:18:01 INFO - Successfully installed blobuploader requests docopt 07:18:01 INFO - Cleaning up... 07:18:01 INFO - Return code: 0 07:18:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:01 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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:18:01 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 07:18:01 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 07:18:01 INFO - Using env: {'DISPLAY': ':0', 07:18:01 INFO - 'HOME': '/home/cltbld', 07:18:01 INFO - 'LANG': 'en_US.UTF-8', 07:18:01 INFO - 'LANGUAGE': 'en_US:en', 07:18:01 INFO - 'LOGNAME': 'cltbld', 07:18:01 INFO - 'MAIL': '/var/mail/cltbld', 07:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:01 INFO - 'MOZ_NO_REMOTE': '1', 07:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:18:01 INFO - 'NO_EM_RESTART': '1', 07:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:01 INFO - 'PWD': '/builds/slave/test', 07:18:01 INFO - 'SHELL': '/bin/bash', 07:18:01 INFO - 'SHLVL': '1', 07:18:01 INFO - 'TERM': 'linux', 07:18:01 INFO - 'TMOUT': '86400', 07:18:01 INFO - 'USER': 'cltbld', 07:18:01 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:01 INFO - '_': '/tools/buildbot/bin/python'} 07:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:01 INFO - Running setup.py (path:/tmp/pip-WJCpRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:01 INFO - Running setup.py (path:/tmp/pip-uVu7Sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:01 INFO - Running setup.py (path:/tmp/pip-85aBV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:01 INFO - Running setup.py (path:/tmp/pip-xngYEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:01 INFO - Running setup.py (path:/tmp/pip-1KuRp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:01 INFO - Running setup.py (path:/tmp/pip-uiM0TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:01 INFO - Running setup.py (path:/tmp/pip-vQPqha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:01 INFO - Running setup.py (path:/tmp/pip-9NjftM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:01 INFO - Running setup.py (path:/tmp/pip-gFZMfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:02 INFO - Running setup.py (path:/tmp/pip-oSQ47X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:02 INFO - Running setup.py (path:/tmp/pip-HC5uDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:02 INFO - Running setup.py (path:/tmp/pip-gj0Er4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:02 INFO - Running setup.py (path:/tmp/pip-yfSxxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:02 INFO - Running setup.py (path:/tmp/pip-hQcW3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:02 INFO - Running setup.py (path:/tmp/pip-VYtBTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:02 INFO - Running setup.py (path:/tmp/pip-Kbvyla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:02 INFO - Running setup.py (path:/tmp/pip-wnLz5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:02 INFO - Running setup.py install for manifestparser 07:18:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:02 INFO - Running setup.py install for mozcrash 07:18:02 INFO - Running setup.py install for mozdebug 07:18:02 INFO - Running setup.py install for mozdevice 07:18:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Running setup.py install for mozfile 07:18:03 INFO - Running setup.py install for mozhttpd 07:18:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Running setup.py install for mozinfo 07:18:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Running setup.py install for mozInstall 07:18:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Running setup.py install for mozleak 07:18:03 INFO - Running setup.py install for mozlog 07:18:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Running setup.py install for moznetwork 07:18:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Running setup.py install for mozprocess 07:18:03 INFO - Running setup.py install for mozprofile 07:18:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:03 INFO - Running setup.py install for mozrunner 07:18:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:04 INFO - Running setup.py install for mozscreenshot 07:18:04 INFO - Running setup.py install for moztest 07:18:04 INFO - Running setup.py install for mozversion 07:18:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:04 INFO - Cleaning up... 07:18:04 INFO - Return code: 0 07:18:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:04 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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:18:04 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 07:18:04 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 07:18:04 INFO - Using env: {'DISPLAY': ':0', 07:18:04 INFO - 'HOME': '/home/cltbld', 07:18:04 INFO - 'LANG': 'en_US.UTF-8', 07:18:04 INFO - 'LANGUAGE': 'en_US:en', 07:18:04 INFO - 'LOGNAME': 'cltbld', 07:18:04 INFO - 'MAIL': '/var/mail/cltbld', 07:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:04 INFO - 'MOZ_NO_REMOTE': '1', 07:18:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:18:04 INFO - 'NO_EM_RESTART': '1', 07:18:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:04 INFO - 'PWD': '/builds/slave/test', 07:18:04 INFO - 'SHELL': '/bin/bash', 07:18:04 INFO - 'SHLVL': '1', 07:18:04 INFO - 'TERM': 'linux', 07:18:04 INFO - 'TMOUT': '86400', 07:18:04 INFO - 'USER': 'cltbld', 07:18:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:04 INFO - '_': '/tools/buildbot/bin/python'} 07:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:04 INFO - Running setup.py (path:/tmp/pip-zh7m7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:04 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)) 07:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:04 INFO - Running setup.py (path:/tmp/pip-sosg2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:04 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)) 07:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:04 INFO - Running setup.py (path:/tmp/pip-5Fyh4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:04 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)) 07:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:04 INFO - Running setup.py (path:/tmp/pip-hDd1bM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:04 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)) 07:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:04 INFO - Running setup.py (path:/tmp/pip-AOXFoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:04 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)) 07:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:04 INFO - Running setup.py (path:/tmp/pip-Bc8jvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:05 INFO - Running setup.py (path:/tmp/pip-BxuypT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:05 INFO - Running setup.py (path:/tmp/pip-9DYlZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:05 INFO - Running setup.py (path:/tmp/pip-TWzKf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:05 INFO - Running setup.py (path:/tmp/pip-3oNnxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:05 INFO - Running setup.py (path:/tmp/pip-7PR7AA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:05 INFO - Running setup.py (path:/tmp/pip-jUvFky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:05 INFO - Running setup.py (path:/tmp/pip-fAkK4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:05 INFO - Running setup.py (path:/tmp/pip-rFEGZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:05 INFO - Running setup.py (path:/tmp/pip-Nsg7EF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:05 INFO - Running setup.py (path:/tmp/pip-RV6brx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:05 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)) 07:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:05 INFO - Running setup.py (path:/tmp/pip-0AQEbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:05 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)) 07:18:05 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)) 07:18:05 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)) 07:18:05 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)) 07:18:05 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)) 07:18:05 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)) 07:18:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:07 INFO - Downloading blessings-1.6.tar.gz 07:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:07 INFO - Installing collected packages: blessings 07:18:07 INFO - Running setup.py install for blessings 07:18:07 INFO - Successfully installed blessings 07:18:07 INFO - Cleaning up... 07:18:07 INFO - Return code: 0 07:18:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:08 INFO - Reading from file tmpfile_stdout 07:18:08 INFO - Current package versions: 07:18:08 INFO - argparse == 1.2.1 07:18:08 INFO - blessings == 1.6 07:18:08 INFO - blobuploader == 1.2.4 07:18:08 INFO - docopt == 0.6.1 07:18:08 INFO - manifestparser == 1.1 07:18:08 INFO - mozInstall == 1.12 07:18:08 INFO - mozcrash == 0.17 07:18:08 INFO - mozdebug == 0.1 07:18:08 INFO - mozdevice == 0.48 07:18:08 INFO - mozfile == 1.2 07:18:08 INFO - mozhttpd == 0.7 07:18:08 INFO - mozinfo == 0.9 07:18:08 INFO - mozleak == 0.1 07:18:08 INFO - mozlog == 3.2 07:18:08 INFO - moznetwork == 0.27 07:18:08 INFO - mozprocess == 0.22 07:18:08 INFO - mozprofile == 0.28 07:18:08 INFO - mozrunner == 6.11 07:18:08 INFO - mozscreenshot == 0.1 07:18:08 INFO - mozsystemmonitor == 0.3 07:18:08 INFO - moztest == 0.7 07:18:08 INFO - mozversion == 1.4 07:18:08 INFO - psutil == 3.1.1 07:18:08 INFO - requests == 1.2.3 07:18:08 INFO - wsgiref == 0.1.2 07:18:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:08 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')]} 07:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:08 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')]} 07:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:08 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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:18:08 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 07:18:08 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 07:18:08 INFO - Using env: {'DISPLAY': ':0', 07:18:08 INFO - 'HOME': '/home/cltbld', 07:18:08 INFO - 'LANG': 'en_US.UTF-8', 07:18:08 INFO - 'LANGUAGE': 'en_US:en', 07:18:08 INFO - 'LOGNAME': 'cltbld', 07:18:08 INFO - 'MAIL': '/var/mail/cltbld', 07:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:08 INFO - 'MOZ_NO_REMOTE': '1', 07:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:18:08 INFO - 'NO_EM_RESTART': '1', 07:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:08 INFO - 'PWD': '/builds/slave/test', 07:18:08 INFO - 'SHELL': '/bin/bash', 07:18:08 INFO - 'SHLVL': '1', 07:18:08 INFO - 'TERM': 'linux', 07:18:08 INFO - 'TMOUT': '86400', 07:18:08 INFO - 'USER': 'cltbld', 07:18:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:08 INFO - '_': '/tools/buildbot/bin/python'} 07:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 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)) 07:18:08 INFO - Cleaning up... 07:18:08 INFO - Return code: 0 07:18:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18:08 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')]} 07:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:08 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')]} 07:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:08 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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:18:08 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 07:18:08 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 07:18:08 INFO - Using env: {'DISPLAY': ':0', 07:18:08 INFO - 'HOME': '/home/cltbld', 07:18:08 INFO - 'LANG': 'en_US.UTF-8', 07:18:08 INFO - 'LANGUAGE': 'en_US:en', 07:18:08 INFO - 'LOGNAME': 'cltbld', 07:18:08 INFO - 'MAIL': '/var/mail/cltbld', 07:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:08 INFO - 'MOZ_NO_REMOTE': '1', 07:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:18:08 INFO - 'NO_EM_RESTART': '1', 07:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:08 INFO - 'PWD': '/builds/slave/test', 07:18:08 INFO - 'SHELL': '/bin/bash', 07:18:08 INFO - 'SHLVL': '1', 07:18:08 INFO - 'TERM': 'linux', 07:18:08 INFO - 'TMOUT': '86400', 07:18:08 INFO - 'USER': 'cltbld', 07:18:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:08 INFO - '_': '/tools/buildbot/bin/python'} 07:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:08 INFO - Downloading/unpacking jsonschema 07:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:10 INFO - Downloading/unpacking functools32 (from jsonschema) 07:18:10 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 07:18:10 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 07:18:10 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 07:18:10 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 07:18:10 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 07:18:10 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 07:18:10 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:10 INFO - warning: no files found matching '*.txt' 07:18:10 INFO - no previously-included directories found matching 'build' 07:18:10 INFO - no previously-included directories found matching 'dist' 07:18:10 INFO - no previously-included directories found matching '.git*' 07:18:10 INFO - Installing collected packages: jsonschema, functools32 07:18:10 INFO - Running setup.py install for functools32 07:18:10 INFO - warning: no files found matching '*.txt' 07:18:10 INFO - no previously-included directories found matching 'build' 07:18:10 INFO - no previously-included directories found matching 'dist' 07:18:10 INFO - no previously-included directories found matching '.git*' 07:18:10 INFO - Successfully installed jsonschema functools32 07:18:10 INFO - Cleaning up... 07:18:11 INFO - Return code: 0 07:18:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:18:11 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')]} 07:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:11 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')]} 07:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:11 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 0x7f2f5eaeaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bf770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', '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 07:18:11 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 07:18:11 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 07:18:11 INFO - Using env: {'DISPLAY': ':0', 07:18:11 INFO - 'HOME': '/home/cltbld', 07:18:11 INFO - 'LANG': 'en_US.UTF-8', 07:18:11 INFO - 'LANGUAGE': 'en_US:en', 07:18:11 INFO - 'LOGNAME': 'cltbld', 07:18:11 INFO - 'MAIL': '/var/mail/cltbld', 07:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:11 INFO - 'MOZ_NO_REMOTE': '1', 07:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:18:11 INFO - 'NO_EM_RESTART': '1', 07:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:11 INFO - 'PWD': '/builds/slave/test', 07:18:11 INFO - 'SHELL': '/bin/bash', 07:18:11 INFO - 'SHLVL': '1', 07:18:11 INFO - 'TERM': 'linux', 07:18:11 INFO - 'TMOUT': '86400', 07:18:11 INFO - 'USER': 'cltbld', 07:18:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:11 INFO - '_': '/tools/buildbot/bin/python'} 07:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:11 INFO - Downloading/unpacking flake8 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:18:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:18:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 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 07:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:18:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:18:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:18:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:18:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:18:13 INFO - no previously-included directories found matching 'docs/_build' 07:18:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:18:13 INFO - Running setup.py install for pep8 07:18:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:18:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:18:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:18:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:18:13 INFO - no previously-included directories found matching 'docs/_build' 07:18:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:18:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:18:14 INFO - Cleaning up... 07:18:14 INFO - Return code: 0 07:18:14 INFO - Running post-action listener: _resource_record_post_action 07:18:14 INFO - Running post-action listener: _start_resource_monitoring 07:18:14 INFO - Starting resource monitoring. 07:18:14 INFO - ##### 07:18:14 INFO - ##### Finished create-virtualenv step (success) 07:18:14 INFO - ##### 07:18:14 INFO - ##### 07:18:14 INFO - ##### Running install step. 07:18:14 INFO - ##### 07:18:14 INFO - Running pre-action listener: _resource_record_pre_action 07:18:14 INFO - Running main action method: install 07:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:14 INFO - Reading from file tmpfile_stdout 07:18:14 INFO - Detecting whether we're running mozinstall >=1.0... 07:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:18:14 INFO - Reading from file tmpfile_stdout 07:18:14 INFO - Output received: 07:18:14 INFO - Usage: mozinstall [options] installer 07:18:14 INFO - Options: 07:18:14 INFO - -h, --help show this help message and exit 07:18:14 INFO - -d DEST, --destination=DEST 07:18:14 INFO - Directory to install application into. [default: 07:18:14 INFO - "/builds/slave/test"] 07:18:14 INFO - --app=APP Application being installed. [default: firefox] 07:18:14 INFO - mkdir: /builds/slave/test/build/application 07:18:14 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'] 07:18:14 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 07:18:30 INFO - Reading from file tmpfile_stdout 07:18:30 INFO - Output received: 07:18:30 INFO - /builds/slave/test/build/application/firefox/firefox 07:18:30 INFO - Running post-action listener: _resource_record_post_action 07:18:30 INFO - ##### 07:18:30 INFO - ##### Finished install step (success) 07:18:30 INFO - ##### 07:18:30 INFO - ##### 07:18:30 INFO - ##### Running run-tests step. 07:18:30 INFO - ##### 07:18:30 INFO - Running pre-action listener: _resource_record_pre_action 07:18:30 INFO - Running main action method: run_tests 07:18:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:18:30 INFO - Python 2.7.3 07:18:30 INFO - Return code: 0 07:18:30 INFO - grabbing minidump binary from tooltool 07:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:30 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 0x244e9c0>, '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 0x244f8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x244fd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:18:30 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 07:18:30 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 07:18:30 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 07:18:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:18:30 INFO - Return code: 0 07:18:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:18:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:18:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:18:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:18:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:18:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:18:31 INFO - Return code: 0 07:18:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 07:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 07:18:31 INFO - Using env: {'DISPLAY': ':0', 07:18:31 INFO - 'HOME': '/home/cltbld', 07:18:31 INFO - 'LANG': 'en_US.UTF-8', 07:18:31 INFO - 'LANGUAGE': 'en_US:en', 07:18:31 INFO - 'LOGNAME': 'cltbld', 07:18:31 INFO - 'MAIL': '/var/mail/cltbld', 07:18:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:18:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:31 INFO - 'MOZ_NO_REMOTE': '1', 07:18:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:18:31 INFO - 'NO_EM_RESTART': '1', 07:18:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:31 INFO - 'PWD': '/builds/slave/test', 07:18:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:18:31 INFO - 'SHELL': '/bin/bash', 07:18:31 INFO - 'SHLVL': '1', 07:18:31 INFO - 'TERM': 'linux', 07:18:31 INFO - 'TMOUT': '86400', 07:18:31 INFO - 'USER': 'cltbld', 07:18:31 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979', 07:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:31 INFO - '_': '/tools/buildbot/bin/python'} 07:18:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 07:18:31 INFO - mozversion application_buildid: 20160526064851 07:18:31 INFO - mozversion application_changeset: 9afab6308cd97d470c6514ccb0e348103e034e4d 07:18:31 INFO - mozversion application_display_name: Nightly 07:18:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:18:31 INFO - mozversion application_name: Firefox 07:18:31 INFO - mozversion application_remotingname: firefox 07:18:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:18:31 INFO - mozversion application_vendor: Mozilla 07:18:31 INFO - mozversion application_version: 49.0a1 07:18:31 INFO - mozversion platform_buildid: 20160526064851 07:18:31 INFO - mozversion platform_changeset: 9afab6308cd97d470c6514ccb0e348103e034e4d 07:18:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:18:31 INFO - mozversion platform_version: 49.0a1 07:18:31 INFO - using testdate: 1464272311 07:18:31 INFO - actual date: 1464272311 07:18:31 INFO - starting webserver on 'localhost:50989' 07:18:31 INFO - SUITE-START | Running 2 tests 07:18:31 INFO - TEST-START | dromaeo_css 07:18:31 INFO - operating with platform_type : linux_ 07:18:31 INFO - Initialising browser for dromaeo_css test... 07:18:31 INFO - TEST-INFO | started process 13398 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprs9cMr/profile http://localhost:50989/getInfo.html) 07:18:35 INFO - PROCESS | 13398 | __metrics Screen width/height:1600/1200 07:18:35 INFO - PROCESS | 13398 | colorDepth:24 07:18:35 INFO - PROCESS | 13398 | Browser inner width/height: 1024/697 07:18:35 INFO - PROCESS | 13398 | __metrics 07:18:36 ERROR - PROCESS | 13398 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:18:37 INFO - TEST-INFO | 13398: exit 0 07:18:37 INFO - Browser initialized. 07:18:37 INFO - Running cycle 1/1 for dromaeo_css test... 07:18:37 INFO - TEST-INFO | started process 13478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprs9cMr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:18:49 INFO - PROCESS | 13478 | RSS: Main: 156811264 07:18:49 INFO - PROCESS | 13478 | 07:19:41 INFO - PROCESS | 13478 | 1464272381110 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] 07:21:52 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:50989/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50989/tests/dromaeo/cssquery-ext.html) 07:21:53 INFO - PROCESS | 13478 | RSS: Main: 241127424 07:21:53 INFO - PROCESS | 13478 | 07:24:56 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:50989/tests/dromaeo/cssquery-ext.html (next: http://localhost:50989/tests/dromaeo/cssquery-jquery.html) 07:24:57 INFO - PROCESS | 13478 | RSS: Main: 241139712 07:24:57 INFO - PROCESS | 13478 | 07:27:59 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:50989/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50989/tests/dromaeo/cssquery-mootools.html) 07:27:59 INFO - PROCESS | 13478 | RSS: Main: 239353856 07:27:59 INFO - PROCESS | 13478 | 07:31:02 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:50989/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50989/tests/dromaeo/cssquery-prototype.html) 07:31:02 INFO - PROCESS | 13478 | RSS: Main: 238608384 07:31:02 INFO - PROCESS | 13478 | 07:34:05 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:50989/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50989/tests/dromaeo/cssquery-yui.html) 07:34:06 INFO - PROCESS | 13478 | RSS: Main: 232124416 07:34:06 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:50989/tests/dromaeo/cssquery-yui.html (next: http://localhost:50989/tests/dromaeo/cssquery-dojo.html) 07:37:09 INFO - PROCESS | 13478 | RSS: Main: 284688384 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | __start_tp_report 07:37:09 INFO - PROCESS | 13478 | _x_x_mozilla_page_load 07:37:09 INFO - PROCESS | 13478 | _x_x_mozilla_page_load_details 07:37:09 INFO - PROCESS | 13478 | |i|pagename|runs| 07:37:09 INFO - PROCESS | 13478 | |0;dojo.html;2691.676998760149;2693.340131667741;2732.795040371972;2733.5489644208706;2758.4483103379316;4561.885952851172;4619.214733495305;4646.372739680142;4746.905061898764;4788.329633851259;4659.976700116503;4707.83522576709;4714.434267887849;4715.85852424427;4718.882027949295;4129.442525259082;4285.764282964444;4379.978100109511;4416.183006143874;4474.597286244238;4383.583559561836;4420.602145806877;4439.911201775963;4441.400410944513;4469.994251293466;4221.852235171769;4290.828366865321;4313.547077556843;4340.60934515895;4348.4999225089205;4353;4368.956310436887;4413.470383553994;4424.867253982354;4428.867133985986;4389.5295076149205;4402.581754733295;4412.24991751403;4443.888902777424;4445.488768791598;4497.078098989699;4629.606483448911;4728.338032675429;4728.432588089417;4795.736234507101;5183.481651834824;5197.8440646780655;5210.505002024802;5210.947890521122;5216.556592689626;4953.752312384402;5186.844394668166;5203.063448579254;5234.973825130888;5241.528262456359;4669.929951050737;4712.2224832902875;4712.976435117871;4754.78603462845;4776.044791041805;5209;5222.660527065728;5224.791008359699;5225.163973764255;5247.921281180786;4747.786349614275;4924.039812236651;4999.675021123688;5050.520200580939;5058.797648094035;5065.214891691794;5088.9745551272745;5111.182210846247;5116.616253780907;5168.870778230575;5028.823991160292;5028.823991160365;5040.067587496252;5040.218766091261;5084.084864724385;4833.347498087784;4838.709677419366;4915.0415668944215;5015.523525265094;5025.447200807908;4234.87295381139;4236.2374772540625;4236.618704316565;4260.510041345223;4270.636995855325;3705.1292946157632;3720.8883733488037;3721.7394782364972;3755.6807671348242;3765.4540091686554;4224.134052519287;4237.788110594458;4255.468066491689;4299.505556861001;4334.263175260214;5112.846614601568;5116.56509196715;5127.538521533005;5127.64106512548;5187.844364669066;4351.238533256731;4351.477822661238;4359.629431498295;4396.758178300161;4397.978010109929;4496.64026877849;4560.315952607136;4595.632349412039;4610.585047345755;4611.377464042312;4793.520647935178;4801.735904525284;4814.350062741485;4817.614590832795;4853.878653033561;5133.486651334837;5144.6398752087;5147.768350424165;5151.227315902494;5193.948060519498;4647.07058588277;4664.277037059262;4696.788644510937;4700.905981880412;4718.575328220477;4963.305137280856;4977.452480227244;4977.800887964586;4981.178105612678;5026.974865125651;4915.18899381598;5000.874978125576;5055.140626093499;5055.191169412877;5093.7707803148205;4879;4914.729689867092;4915.97542012302;4932.21084626458;4950.232713929347;4935.111679897653;4943.752812359296;4953.801847926186;4955.554000140005;5007.39911210657;4749.24012158053;4785.952140478551;4864.537868902448;4882.975585122052;4930.087933732271;4922.72924989129;4928.162212423825;4963.925541116814;5003.67476114066;5004.924926126038;4827.710337379768;4837.4920633332995;4839.346688197189;4843.934334446416;4897.779599917941 07:37:09 INFO - PROCESS | 13478 | |1;ext.html;593.8366949089001;606.2876120558343;608.4463138543924;609.5184804004833;611.3886113886114;27262.727372726265;30016.399672006573;30130.849345753268;38705.22589548207;40956;39875.60124398755;40361.59638403615;40398.59601403985;40415.39376909348;40448.797756011176;7628.313451789338;7810.570418626973;7810.960945195295;7812.414068944823;7816.843663126762;7433.702651893945;7575.204603516634;9821.066998635119;10166.491675416237;10170.338927969695;10151.847722284172;10214.897851021506;10217.335873168258;10219.284650074498;10229.693109206735;7467.253274672544;10126.189904807634;10200;10202.89797102027;10204.336718113336;9634.47010414427;10084.94957525215;10086.747831304203;10470.476476176236;10474.895251047545;39048;39512;39568.40647390291;39621;39643;2861.3561948561614;2979.478591246523;3024.364883374494;3053.7098975597282;3469.809160496197;29509.409811803645;29519.70480295191;29935.101946941628;30084.69915300841;30349.696503035128;3429.2455659754814;3557.7153827693724;3585.3008663310666;3598.514200582914;3599.1002249437643;248838;248933;251197.99999999817;253414;253601;31835.68164318327;32889;32941;34912.127196820285;54930;34652.13369665728;34741.47887781685;34784.30431391358;34942.825285873914;34954.825225873705;12614.180078294881;12773.680657983625;12774.744505109846;12778.424970876147;12793.168444051107;8436.704715334934;9603;9658.3722058066;9670.90329096714;10428.478576071166;7963.681452741941;7976.7208147714555;8023.277904988579;8045.758627241192;8086.43394962347;3146.98426507866;3147.732442742347;3168.7623428242973;3177.158053115926;3187.37846120009;930.2093220762298;957.3901663490411;994.0357852882693;997.4264797741328;1037.7976294622476;28117.297067573476;28495;28752.712472875002;28902;28990.130296091153;945.8434768727312;1001.2640709078821;1045.414567842011;1073.1361254190451;1085.3216739537788;14812.777808332883;15199.392024319124;15260.245071816884;15437.691246175238;15632;369828;370889;373836;374769;375072;49990.25014624783;50288.49711502912;50317;50415.747921260896;50508.74745626322;1081.5133190064346;1178.3106882473678;1184.1000839362148;1191.0590633399518;1223.3821919930378;27800.304992375353;28863.42273154483;29016.854915725286;29561.55657665094;29585.26036849096;47686.28470573013;49093.75453122712;49139.508604914925;54312;54421;345373;350750;350785;350973;351548;33419.1645208862;34349.656503435646;34440;34682.13294667654;37246.44130338101;33499.4975075379;35325.70790905274;43013;43115.353269700354;43540.34689479597;34255;36819.263614728094;36867.26265474729;37416.06459838526;37707.811460943616;32679.836600816845;36454;36824;36921;37402.625973739916;36205.27589448249;36387.818060910584;36619.26761464809;36921.81539092287;37491.43762843505;32499.350012999133;33856;33940.30298485115;34235.828820855735;34368.312633747686;25407.75534679178;25424.872875635505;25731.742682572934;25820.612690810027;26258.343541411617 07:37:09 INFO - PROCESS | 13478 | |2;jquery.html;362.8222171136144;365.6982989034044;366.83675764284885;367.70767240044154;379.4062292512218;15457.45898893539;16822;16982.660346793073;17226.655466890654;17471.912640436796;15026.17356045417;15862.920685396586;16033.118178500177;16050.43823466179;16058.75911861323;4995.775190116445;5177.145770947808;5189.974050129763;5217.634765566412;5227.843164705065;3690.1512652090037;3869.613038696136;5165.63840531163;5660.688662123582;6022.2773267207995;6043.607165534248;6053.122297266894;6067.878642427148;6068.484178844781;6078.908816367758;5909.556783241274;5911.3201981772;6022.397760223987;6045.7581696732295;6092.634441933454;3365.3774051800497;4949.975250123763;5269.710165940872;5282.656627319212;5288.947110528922;19272.710909336372;19889.50276243091;19934.900325498424;20171.798282017284;20183.89908050465;1879.7556317678614;1966.852486063551;2012.5471768852037;2167.1439781286463;2313.6876521669697;12593.874061259361;12686.365681715877;12786.552470663482;12788;13133.343332833416;1733.0468242466718;1740.9912950435294;1754.23690694547;1761.2778760708047;1818.7453756474104;63232;63852.680736596485;64461.355386446005;64873.351266487676;65827;8351.24838764514;8857.424267422726;9060.592273347715;9211.170994610384;9217.216536594331;12145.332006739714;12216.20594661362;12296.754064918652;12311.876881231252;12316.630501084981;6393.232812062489;6475.417212450902;6542.1168142300185;6642.468602511787;6696.06255124283;6395.744170233139;6486.935130648728;6512.088307636935;6554.901676474857;6591.83520411984;3650.780953142767;3709.647583479565;3837.827297771607;3864.961350386516;3892.5718171001167;1517.9848201518064;1718.4329171373417;1769.2834402067183;1828.9268429262947;1844.7325137855205;808.8382323535317;844.3709436469869;861.724248240557;908.9363744537819;912.2975309012156;14865.479708210161;15836.841631583537;16106.919465402598;16288.755668664979;17256.65486690284;798.1818635898251;817.3216230528647;862.499750144927;892.7500299916034;897.2552781191596;8910.153535414125;9240.181253904208;9323.25413966881;9364.391314564531;9405.341626086109;388490;389789;397996;401382.9930850327;405379;71602;75001.62499187469;75204;76194;77451.22548774553;940.6848705683536;963.3304853127149;967.3180407812616;970.9951450242703;986.5264672956878;13967.231802250973;14443.927780361031;15567.84432155664;15778.842211577738;16250.67498650044;35282.470762938065;35904.82047589745;36559.26881462409;37129.44305835361;37234.6276537242;48874.75562622164;49381.506184937694;49533.25700114429;49544.256836148204;49946.500534994186;6424.582402143846;6771.153605799275;7096.822579435349;7257.020302259128;7415.147258065392;10628.25602207838;12062.879371206176;12396.380180990735;12415.068869834979;12506.624801255977;9155.038720934206;9297.767555811159;9343.672971445967;9737.415755054719;9871.555779990062;9627.855582166134;9857.30998830075;10336.948315258376;10650.84023739629;10679.412632304988;5985.461308482258;9287.48918809472;9445.905540944777;9667.033296670277;10103.494825258855;6625.602463852184;6681.599104053772;6765.357291057342;6776.593404395752;6791.286914873867;7422.591757453392;8942.105789421006;9082.727518174466;9452.291078169303;9747.220222382335 07:37:09 INFO - PROCESS | 13478 | |3;mootools.html;1280.846298444187;1395.4139261510168;1445.21958142603;1561.66424218793;1775.3342496563796;6701.22935862376;7585.886211706828;7812.960935195316;7834.373250139982;8099.028116626013;7895.526268423884;7916.050073991115;7937.563434011127;7949.046114466258;7986.9201307986905;2192.079326682789;2234.731832180141;2237.865728056314;2238.1159442020394;2241.372415723592;2164.9242276520326;2189.5511420158828;2228.8551244169157;2231.8326125540566;2234.72065991751;2143.228437762404;2163.7619861815188;2167.566486702658;2169.8372622053407;2181.203860590881;2128.8509804313703;2131.0410315357994;2135.8611690240086;2137.754158271803;2137.967930481036;2098.842586805996;2159.946001349979;2161.664941934003;2162.91348346066;2165.4261620670536;7561.962190189069;7863.960680196619;7915.525068495908;7916.366690664733;7926.128125906146;4392.011797345572;4521.932171017437;4566.13243483737;4574.313852922055;4577.9542204577865;2912.6213592233084;2932.929480739536;2936.853157342124;3002.1894088595955;3032.908153064917;965.4062751407834;971.4220039076808;979.1481411172325;988.0810845913297;992.2508506077937;10660.893391066067;10786.298890572089;10833.891661083446;10850.348979061282;10866.076383507412;4229.281022226229;4360.716553424018;4411.18393097278;4432.911341773147;4436.0906014267;9198.954005230065;9279;9286.582103805345;9288.117628825386;9295.209907157832;4456.866294011184;4462.642988560908;4465.24090904547;4474.664400170001;4476.947917239446;3493.6681015303507;4001.579834117376;4030.6573941214738;4146.875593732193;4194.328907374806;3735.7945313008045;3787.5833658297565;3843.481130047464;3872.0513474199;3897.142628621699;1210.3343161261448;1219.1587804414926;1225.7732319520917;1230.2434003088215;1237.7091383524864;825.5088222507599;839.5004972041747;850.0734199722209;873.0178549132227;897.4211633496358;5006.64953453262;5187.559057480156;5246.3704355477585;5249.47505249472;5278.261043453919;408.4587921004621;411.42811492026107;411.76323613921517;416.16351134220434;855.4610595324906;3334.149791803123;3460.446328587414;3482.982585087109;3490.860365585399;3492.109512074456;9731.172850307661;9904.900950990543;9949.452780097165;10002.399856008662;10032.849507257251;9841.45871977048;9853.261005424622;9943.950280248553;9946.651867184759;9960.950195248977;7089.680964356617;7370.373518250902;7381.778546643609;7512.586807725518;7529.623518824037;5007.448891634796;5269.314989051399;5275;5285.762140703754;5353.625246232727;9092.408993415407;9198.080191980749;9211.953940230256;9269.72190834276;9297.67458138962;9128.406653567497;9334;9501.57242924084;9526.380785248937;9553.04469553039;5800.4489573490455;5996.760129594767;6000.8799824004145;6726.932730672826;6828.385445309747;6750.831229219309;6827.829304267433;7317.15852676949;7555.357794587521;7559.244075592616;6768.4585233182215;6904.4131248844415;6936.895946560705;7089.503734738519;7126.144862616312;6808.761693340907;6843.418309443753;6845.383915447634;6922.10012698346;6933.445324374139;6319.589226700434;6392.4246817786625;6464.127342808661;6471.158749362553;6535.477161826947;4542.227821270458;4545.3863728396245;4577.130345234395;4608.838690645811;4634.165850147006;4642.094791515622;4691.9765401174145;4737.549932756383;4795.400574928134;4826.758662066813 07:37:09 INFO - PROCESS | 13478 | |4;prototype.html;278.5153832331742;286.7978075456803;294.2291197063693;294.3803294065991;295.6481786673858;4623.491415944243;4772.0196118610775;4798.256270278103;4826.0830442216;4841.055994081155;4545.11370282795;4755.286706993944;4780.617550595953;4783.9475315430545;4793.952060479394;3766.8304926278247;3776.5656949450754;3782.773033617978;3788.62113788621;3825.1202223488476;3596.2268112355837;3607.7474576779637;3730.339998700579;3766.2090960898245;3777.244551089779;3693.2244228712;3709.443583462475;3719.716697739289;3729.8135093245364;3736.514253147087;3673.448982652597;3682.226732386189;3697.8335974881193;3707.796071216082;3710.962890371116;3264.183954011497;3267.4445344291526;3272.345530893807;3273.2798784267425;3282.2122690554097;4355.869323920288;4553.954460455419;4558.270676691714;4605.263157894754;4625.606823419979;4174.3321068629175;4200.579942005806;4213.831446742127;4217.557156498585;4227.260229459832;4971.179755340365;4992.425871024806;5006.89986200274;5026.421961474441;5033.546980771748;3414.6243913169533;3507.333606614735;3524.929501409983;3527.2592755521373;3536.6914241730724;5194.649391158326;5223.82463945613;5240.6069544784295;5248.71132087735;5248.947510524884;3598.226381328027;3600.6759391654878;3606.549181352331;3608.386574282378;3608.891733248007;3654.7989860557923;3673.522442082512;3673.5673087495898;3676.889693309205;3685.2445248724484;4107.445494858216;4170.374443833389;4182.498100227992;4184.895377615584;4202.474690663667;4965.726885021359;4996.777078784164;5017.147084995559;5020.548150666457;5034.823781167642;1174.1546086817477;1212.835677749353;1215.6292330839178;1216.2094638485055;1216.4282787090053;826.6114925984778;849.392684230778;855.9957200213959;859.6346552715196;861.1087524412203;4364.236258654722;4370.388145659546;4395.582419670126;4408;4410.140022695544;5025.82409615669;5152.278680984665;5176.818811341585;5194.870128246824;5201.817936372209;4364.170807546556;4411.647068234598;4422.850058984687;4429.383275104564;4432.202203603382;2715.9765974349234;2729.1539622717023;2740.1779466160074;2743.3690251242324;2749.5738160585142;4561;4804.903901921942;4819.614430845524;4853.878653033702;4856.640140760593;5111.207762796773;5140.203268493389;5201.699575106223;5245.763940622681;5246.3704355477585;4792.568668819823;4806.7836947337455;4812.109759694467;4816.229403295456;4816.34979277793;4909.239067944474;5076.18780995039;5094;5113.718745469035;5238.214267859851;5066.493350664904;5087.745612719424;5106.361704786902;5106.395738173484;5136;4873.756312184448;4900.583450406755;4906.852794416173;4909.1654418748185;4913.803447862046;3386.695197432243;3594.4788005739542;3623.221007483404;3626.202235508184;3660.6821544244585;5144.50173881764;5175.430702622633;5196.194589838581;5200.6619569727845;5209.505097015777;4988.226824842155;5006.274090256819;5007.349044624321;5021.4225364083595;5022.835613563431;4787.688800227974;4965.255211718271;5021.568852876912;5031.9245211322595;5096.184610462308;4773.737444440589;4871.48849370811;4940.209566469492;4954.529319714621;4983.875403114806;5070.138076526926;5105.463926287686;5121.436641969455;5126.256692779451;5134.589232861437;4786.2581299898575;4970.925436118533;4972.676776009548;4992.951480189201;4999.325091112799 07:37:09 INFO - PROCESS | 13478 | |5;yui.html;651.6871901487286;675.0970825450561;706.2090458686268;774.4114473000518;789.7946533901184;12126.818097728541;12208.389580520972;12260.5708800192;12267.079969002327;12287.631371058871;10012.499375031255;12033.398330083504;12069.155159138863;12095.879041209586;12096.758064838697;633.4520639646707;761.958092304923;801.1547817053007;804.0271271761176;812.6018251056998;793.7817100297407;796.24356860982;796.5658715999793;801.0827602395254;802.4663598706853;850.3622283287534;858.8883445152121;859.6862145316977;859.8710193470966;860;659.680055173243;804.0753133896008;854.1159899503983;855.4140413816556;856.015582080606;616.747133675191;616.8704572039877;617.672633504239;618.153130211613;618.7215752911208;11522.135839811965;11559.53761849525;11574.131940104442;11583.536658533649;11590.478428470738;11903.16677832552;12306.26162430248;12388.31864247466;12670.113092083559;12670.493180272779;11014.44927753609;11068.28056176346;11073.50169242386;11262.267952583055;11438.942805286004;4025.7383270087353;4058.066644671742;4076.103257283393;4088.795560221977;4167;43596;43758.56241437545;44101.77949110266;44126.55873441257;44205;9578.671650173705;9615.182709469633;9723.465209413409;9780.462074585967;9951;17260.568485787957;17292.827071729374;17324.913375433294;17340.47978560645;17355.13224338778;6630.668466576652;6674.43267322283;6709.966450167718;6732.798016059526;6918.066061081689;3732.496113024761;3758.586555478895;3760.0975765816006;3787.753796003251;3821;2649.748273913975;2733.2620192548297;2734.6171535984977;2734.808563400543;2736.4253506763403;453.1752210975992;466.21442868766235;467.2733898787418;467.96256299495957;486.3240096266193;390.65036792071504;483.28473858689335;484.1865665681618;488.82158012325243;491.1968930798099;6478.676066196671;6649.867002659919;6658.334166583303;6680.766173183915;6734.191896972395;427.0178589244725;451.40640058323197;461.5684335146649;467.6562726396096;489.6058672768456;3291.0949488890747;3293.1602441377295;3300.5214243934975;3317.9834100829826;3396.3207358528393;52435.21347179796;54288.45711542834;54935.17597236045;56033.719831401395;56184.719076404355;48607;50005;50010.249846252336;50420.49579504231;50880.49119508832;46076.3088553672;46275;46359;46832;47139;7628.732994345171;7938.3252423544645;8132.2680958714;8138.064271985952;8178.304844088081;31100;31257.218569535034;31647;31934.52098218575;32185.517217242228;38573.42139867848;40433.191336172524;40473.79763101166;40524;40940.38589421221;10397.740056498647;10507;10831.837522437012;10840.620578279722;10987.285826421257;10969.670909872717;11621.941890290494;11706.122040847142;11710.765784684088;11953;11078.335299882034;11201.88798112041;11657.06743460538;11815.350155741517;12022.519099235933;10229.590816367261;10352.53413596375;10826.025657690845;11400.657980260605;11500.884991149982;11061.723456913642;11087.279326843729;11094.722631933942;11241.269317494336;11304.717382065513;9024.458532488072;9282.443053416817;9286.907130928605;9296.39573427725;9298.349115561845;9050.592723327334;9240.815183696153;9439;9494.28792840526;9495.43027418357 07:37:09 INFO - PROCESS | 13478 | __end_tp_report 07:37:09 INFO - PROCESS | 13478 | __start_cc_report 07:37:09 INFO - PROCESS | 13478 | _x_x_mozilla_cycle_collect,948 07:37:09 INFO - PROCESS | 13478 | __end_cc_report 07:37:09 INFO - PROCESS | 13478 | __startTimestamp1464273429258__endTimestamp 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | ------- Summary: start ------- 07:37:09 INFO - PROCESS | 13478 | Number of tests: 6 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | [#0] dojo.html Cycles:165 Average:4683.30 Median:4793.52 stddev:492.22 (10.3%) stddev-sans-first:468.28 07:37:09 INFO - PROCESS | 13478 | Values: 2691.7 2693.3 2732.8 2733.5 2758.4 4561.9 4619.2 4646.4 4746.9 4788.3 4660.0 4707.8 4714.4 4715.9 4718.9 4129.4 4285.8 4380.0 4416.2 4474.6 4383.6 4420.6 4439.9 4441.4 4470.0 4221.9 4290.8 4313.5 4340.6 4348.5 4353.0 4369.0 4413.5 4424.9 4428.9 4389.5 4402.6 4412.2 4443.9 4445.5 4497.1 4629.6 4728.3 4728.4 4795.7 5183.5 5197.8 5210.5 5210.9 5216.6 4953.8 5186.8 5203.1 5235.0 5241.5 4669.9 4712.2 4713.0 4754.8 4776.0 5209.0 5222.7 5224.8 5225.2 5247.9 4747.8 4924.0 4999.7 5050.5 5058.8 5065.2 5089.0 5111.2 5116.6 5168.9 5028.8 5028.8 5040.1 5040.2 5084.1 4833.3 4838.7 4915.0 5015.5 5025.4 4234.9 4236.2 4236.6 4260.5 4270.6 3705.1 3720.9 3721.7 3755.7 3765.5 4224.1 4237.8 4255.5 4299.5 4334.3 5112.8 5116.6 5127.5 5127.6 5187.8 4351.2 4351.5 4359.6 4396.8 4398.0 4496.6 4560.3 4595.6 4610.6 4611.4 4793.5 4801.7 4814.4 4817.6 4853.9 5133.5 5144.6 5147.8 5151.2 5193.9 4647.1 4664.3 4696.8 4700.9 4718.6 4963.3 4977.5 4977.8 4981.2 5027.0 4915.2 5000.9 5055.1 5055.2 5093.8 4879.0 4914.7 4916.0 4932.2 4950.2 4935.1 4943.8 4953.8 4955.6 5007.4 4749.2 4786.0 4864.5 4883.0 4930.1 4922.7 4928.2 4963.9 5003.7 5004.9 4827.7 4837.5 4839.3 4843.9 4897.8 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | [#1] ext.html Cycles:180 Average:47156.11 Median:19075.97 stddev:86662.00 (454.3%) stddev-sans-first:86834.59 07:37:09 INFO - PROCESS | 13478 | Values: 593.8 606.3 608.4 609.5 611.4 27262.7 30016.4 30130.8 38705.2 40956.0 39875.6 40361.6 40398.6 40415.4 40448.8 7628.3 7810.6 7811.0 7812.4 7816.8 7433.7 7575.2 9821.1 10166.5 10170.3 10151.8 10214.9 10217.3 10219.3 10229.7 7467.3 10126.2 10200.0 10202.9 10204.3 9634.5 10084.9 10086.7 10470.5 10474.9 39048.0 39512.0 39568.4 39621.0 39643.0 2861.4 2979.5 3024.4 3053.7 3469.8 29509.4 29519.7 29935.1 30084.7 30349.7 3429.2 3557.7 3585.3 3598.5 3599.1 248838.0 248933.0 251198.0 253414.0 253601.0 31835.7 32889.0 32941.0 34912.1 54930.0 34652.1 34741.5 34784.3 34942.8 34954.8 12614.2 12773.7 12774.7 12778.4 12793.2 8436.7 9603.0 9658.4 9670.9 10428.5 7963.7 7976.7 8023.3 8045.8 8086.4 3147.0 3147.7 3168.8 3177.2 3187.4 930.2 957.4 994.0 997.4 1037.8 28117.3 28495.0 28752.7 28902.0 28990.1 945.8 1001.3 1045.4 1073.1 1085.3 14812.8 15199.4 15260.2 15437.7 15632.0 369828.0 370889.0 373836.0 374769.0 375072.0 49990.3 50288.5 50317.0 50415.7 50508.7 1081.5 1178.3 1184.1 1191.1 1223.4 27800.3 28863.4 29016.9 29561.6 29585.3 47686.3 49093.8 49139.5 54312.0 54421.0 345373.0 350750.0 350785.0 350973.0 351548.0 33419.2 34349.7 34440.0 34682.1 37246.4 33499.5 35325.7 43013.0 43115.4 43540.3 34255.0 36819.3 36867.3 37416.1 37707.8 32679.8 36454.0 36824.0 36921.0 37402.6 36205.3 36387.8 36619.3 36921.8 37491.4 32499.4 33856.0 33940.3 34235.8 34368.3 25407.8 25424.9 25731.7 25820.6 26258.3 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | [#2] jquery.html Cycles:180 Average:24019.31 Median:5183.56 stddev:65351.53 (1260.7%) stddev-sans-first:65510.72 07:37:09 INFO - PROCESS | 13478 | Values: 362.8 365.7 366.8 367.7 379.4 15457.5 16822.0 16982.7 17226.7 17471.9 15026.2 15862.9 16033.1 16050.4 16058.8 4995.8 5177.1 5190.0 5217.6 5227.8 3690.2 3869.6 5165.6 5660.7 6022.3 6043.6 6053.1 6067.9 6068.5 6078.9 5909.6 5911.3 6022.4 6045.8 6092.6 3365.4 4950.0 5269.7 5282.7 5288.9 19272.7 19889.5 19934.9 20171.8 20183.9 1879.8 1966.9 2012.5 2167.1 2313.7 12593.9 12686.4 12786.6 12788.0 13133.3 1733.0 1741.0 1754.2 1761.3 1818.7 63232.0 63852.7 64461.4 64873.4 65827.0 8351.2 8857.4 9060.6 9211.2 9217.2 12145.3 12216.2 12296.8 12311.9 12316.6 6393.2 6475.4 6542.1 6642.5 6696.1 6395.7 6486.9 6512.1 6554.9 6591.8 3650.8 3709.6 3837.8 3865.0 3892.6 1518.0 1718.4 1769.3 1828.9 1844.7 808.8 844.4 861.7 908.9 912.3 14865.5 15836.8 16106.9 16288.8 17256.7 798.2 817.3 862.5 892.8 897.3 8910.2 9240.2 9323.3 9364.4 9405.3 388490.0 389789.0 397996.0 401383.0 405379.0 71602.0 75001.6 75204.0 76194.0 77451.2 940.7 963.3 967.3 971.0 986.5 13967.2 14443.9 15567.8 15778.8 16250.7 35282.5 35904.8 36559.3 37129.4 37234.6 48874.8 49381.5 49533.3 49544.3 49946.5 6424.6 6771.2 7096.8 7257.0 7415.1 10628.3 12062.9 12396.4 12415.1 12506.6 9155.0 9297.8 9343.7 9737.4 9871.6 9627.9 9857.3 10336.9 10650.8 10679.4 5985.5 9287.5 9445.9 9667.0 10103.5 6625.6 6681.6 6765.4 6776.6 6791.3 7422.6 8942.1 9082.7 9452.3 9747.2 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | [#3] mootools.html Cycles:180 Average:5175.14 Median:4916.70 stddev:2953.63 (60.1%) stddev-sans-first:2947.41 07:37:09 INFO - PROCESS | 13478 | Values: 1280.8 1395.4 1445.2 1561.7 1775.3 6701.2 7585.9 7813.0 7834.4 8099.0 7895.5 7916.1 7937.6 7949.0 7986.9 2192.1 2234.7 2237.9 2238.1 2241.4 2164.9 2189.6 2228.9 2231.8 2234.7 2143.2 2163.8 2167.6 2169.8 2181.2 2128.9 2131.0 2135.9 2137.8 2138.0 2098.8 2159.9 2161.7 2162.9 2165.4 7562.0 7864.0 7915.5 7916.4 7926.1 4392.0 4521.9 4566.1 4574.3 4578.0 2912.6 2932.9 2936.9 3002.2 3032.9 965.4 971.4 979.1 988.1 992.3 10660.9 10786.3 10833.9 10850.3 10866.1 4229.3 4360.7 4411.2 4432.9 4436.1 9199.0 9279.0 9286.6 9288.1 9295.2 4456.9 4462.6 4465.2 4474.7 4476.9 3493.7 4001.6 4030.7 4146.9 4194.3 3735.8 3787.6 3843.5 3872.1 3897.1 1210.3 1219.2 1225.8 1230.2 1237.7 825.5 839.5 850.1 873.0 897.4 5006.6 5187.6 5246.4 5249.5 5278.3 408.5 411.4 411.8 416.2 855.5 3334.1 3460.4 3483.0 3490.9 3492.1 9731.2 9904.9 9949.5 10002.4 10032.8 9841.5 9853.3 9944.0 9946.7 9961.0 7089.7 7370.4 7381.8 7512.6 7529.6 5007.4 5269.3 5275.0 5285.8 5353.6 9092.4 9198.1 9212.0 9269.7 9297.7 9128.4 9334.0 9501.6 9526.4 9553.0 5800.4 5996.8 6000.9 6726.9 6828.4 6750.8 6827.8 7317.2 7555.4 7559.2 6768.5 6904.4 6936.9 7089.5 7126.1 6808.8 6843.4 6845.4 6922.1 6933.4 6319.6 6392.4 6464.1 6471.2 6535.5 4542.2 4545.4 4577.1 4608.8 4634.2 4642.1 4692.0 4737.5 4795.4 4826.8 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | [#4] prototype.html Cycles:180 Average:4145.16 Median:4777.18 stddev:1214.99 (25.4%) stddev-sans-first:1183.23 07:37:09 INFO - PROCESS | 13478 | Values: 278.5 286.8 294.2 294.4 295.6 4623.5 4772.0 4798.3 4826.1 4841.1 4545.1 4755.3 4780.6 4783.9 4794.0 3766.8 3776.6 3782.8 3788.6 3825.1 3596.2 3607.7 3730.3 3766.2 3777.2 3693.2 3709.4 3719.7 3729.8 3736.5 3673.4 3682.2 3697.8 3707.8 3711.0 3264.2 3267.4 3272.3 3273.3 3282.2 4355.9 4554.0 4558.3 4605.3 4625.6 4174.3 4200.6 4213.8 4217.6 4227.3 4971.2 4992.4 5006.9 5026.4 5033.5 3414.6 3507.3 3524.9 3527.3 3536.7 5194.6 5223.8 5240.6 5248.7 5248.9 3598.2 3600.7 3606.5 3608.4 3608.9 3654.8 3673.5 3673.6 3676.9 3685.2 4107.4 4170.4 4182.5 4184.9 4202.5 4965.7 4996.8 5017.1 5020.5 5034.8 1174.2 1212.8 1215.6 1216.2 1216.4 826.6 849.4 856.0 859.6 861.1 4364.2 4370.4 4395.6 4408.0 4410.1 5025.8 5152.3 5176.8 5194.9 5201.8 4364.2 4411.6 4422.9 4429.4 4432.2 2716.0 2729.2 2740.2 2743.4 2749.6 4561.0 4804.9 4819.6 4853.9 4856.6 5111.2 5140.2 5201.7 5245.8 5246.4 4792.6 4806.8 4812.1 4816.2 4816.3 4909.2 5076.2 5094.0 5113.7 5238.2 5066.5 5087.7 5106.4 5106.4 5136.0 4873.8 4900.6 4906.9 4909.2 4913.8 3386.7 3594.5 3623.2 3626.2 3660.7 5144.5 5175.4 5196.2 5200.7 5209.5 4988.2 5006.3 5007.3 5021.4 5022.8 4787.7 4965.3 5021.6 5031.9 5096.2 4773.7 4871.5 4940.2 4954.5 4983.9 5070.1 5105.5 5121.4 5126.3 5134.6 4786.3 4970.9 4972.7 4993.0 4999.3 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - PROCESS | 13478 | [#5] yui.html Cycles:180 Average:13022.69 Median:44165.78 stddev:15142.28 (34.3%) stddev-sans-first:15156.25 07:37:09 INFO - PROCESS | 13478 | Values: 651.7 675.1 706.2 774.4 789.8 12126.8 12208.4 12260.6 12267.1 12287.6 10012.5 12033.4 12069.2 12095.9 12096.8 633.5 762.0 801.2 804.0 812.6 793.8 796.2 796.6 801.1 802.5 850.4 858.9 859.7 859.9 860.0 659.7 804.1 854.1 855.4 856.0 616.7 616.9 617.7 618.2 618.7 11522.1 11559.5 11574.1 11583.5 11590.5 11903.2 12306.3 12388.3 12670.1 12670.5 11014.4 11068.3 11073.5 11262.3 11438.9 4025.7 4058.1 4076.1 4088.8 4167.0 43596.0 43758.6 44101.8 44126.6 44205.0 9578.7 9615.2 9723.5 9780.5 9951.0 17260.6 17292.8 17324.9 17340.5 17355.1 6630.7 6674.4 6710.0 6732.8 6918.1 3732.5 3758.6 3760.1 3787.8 3821.0 2649.7 2733.3 2734.6 2734.8 2736.4 453.2 466.2 467.3 468.0 486.3 390.7 483.3 484.2 488.8 491.2 6478.7 6649.9 6658.3 6680.8 6734.2 427.0 451.4 461.6 467.7 489.6 3291.1 3293.2 3300.5 3318.0 3396.3 52435.2 54288.5 54935.2 56033.7 56184.7 48607.0 50005.0 50010.2 50420.5 50880.5 46076.3 46275.0 46359.0 46832.0 47139.0 7628.7 7938.3 8132.3 8138.1 8178.3 31100.0 31257.2 31647.0 31934.5 32185.5 38573.4 40433.2 40473.8 40524.0 40940.4 10397.7 10507.0 10831.8 10840.6 10987.3 10969.7 11621.9 11706.1 11710.8 11953.0 11078.3 11201.9 11657.1 11815.4 12022.5 10229.6 10352.5 10826.0 11400.7 11500.9 11061.7 11087.3 11094.7 11241.3 11304.7 9024.5 9282.4 9286.9 9296.4 9298.3 9050.6 9240.8 9439.0 9494.3 9495.4 07:37:09 INFO - PROCESS | 13478 | -------- Summary: end -------- 07:37:09 INFO - PROCESS | 13478 | 07:37:09 INFO - TEST-INFO | 13478: exit 0 07:37:10 INFO - TEST-OK | dromaeo_css | took 1118632ms 07:37:10 INFO - TEST-START | kraken 07:37:10 INFO - operating with platform_type : linux_ 07:37:10 INFO - Initialising browser for kraken test... 07:37:10 INFO - TEST-INFO | started process 13691 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpALhQKK/profile http://localhost:50989/getInfo.html) 07:37:14 INFO - PROCESS | 13691 | __metrics Screen width/height:1600/1200 07:37:14 INFO - PROCESS | 13691 | colorDepth:24 07:37:14 INFO - PROCESS | 13691 | Browser inner width/height: 1024/697 07:37:14 INFO - PROCESS | 13691 | __metrics 07:37:15 ERROR - PROCESS | 13691 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:37:16 INFO - TEST-INFO | 13691: exit 0 07:37:16 INFO - Browser initialized. 07:37:16 INFO - Running cycle 1/1 for kraken test... 07:37:16 INFO - TEST-INFO | started process 13771 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpALhQKK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:37:28 INFO - PROCESS | 13771 | RSS: Main: 156626944 07:37:28 INFO - PROCESS | 13771 | 07:37:30 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50989/tests/kraken/driver.html?testName=audio-beat-detection) 07:37:31 INFO - PROCESS | 13771 | RSS: Main: 212021248 07:37:31 INFO - PROCESS | 13771 | 07:37:34 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50989/tests/kraken/driver.html?testName=audio-dft) 07:37:35 INFO - PROCESS | 13771 | RSS: Main: 200278016 07:37:35 INFO - PROCESS | 13771 | 07:37:38 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50989/tests/kraken/driver.html?testName=audio-fft) 07:37:38 INFO - PROCESS | 13771 | RSS: Main: 269533184 07:37:38 INFO - PROCESS | 13771 | 07:37:41 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50989/tests/kraken/driver.html?testName=audio-oscillator) 07:37:41 INFO - PROCESS | 13771 | RSS: Main: 179232768 07:37:41 INFO - PROCESS | 13771 | 07:37:44 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50989/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:37:44 INFO - PROCESS | 13771 | RSS: Main: 183017472 07:37:44 INFO - PROCESS | 13771 | 07:37:47 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50989/tests/kraken/driver.html?testName=imaging-darkroom) 07:37:48 INFO - PROCESS | 13771 | RSS: Main: 218140672 07:37:48 INFO - PROCESS | 13771 | 07:37:51 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50989/tests/kraken/driver.html?testName=imaging-desaturate) 07:37:52 INFO - PROCESS | 13771 | RSS: Main: 219103232 07:37:52 INFO - PROCESS | 13771 | 07:37:55 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50989/tests/kraken/driver.html?testName=json-parse-financial) 07:37:56 INFO - PROCESS | 13771 | RSS: Main: 219791360 07:37:56 INFO - PROCESS | 13771 | 07:37:57 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50989/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:37:58 INFO - PROCESS | 13771 | RSS: Main: 206192640 07:37:58 INFO - PROCESS | 13771 | 07:38:00 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:00 INFO - PROCESS | 13771 | RSS: Main: 209858560 07:38:00 INFO - PROCESS | 13771 | 07:38:02 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:03 INFO - PROCESS | 13771 | RSS: Main: 220151808 07:38:03 INFO - PROCESS | 13771 | 07:38:06 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:07 INFO - PROCESS | 13771 | RSS: Main: 228708352 07:38:07 INFO - PROCESS | 13771 | 07:38:10 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:38:10 INFO - PROCESS | 13771 | RSS: Main: 226062336 07:38:10 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | Cycle 1(1): loaded http://localhost:50989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50989/tests/kraken/driver.html?testName=ai-astar) 07:38:12 INFO - PROCESS | 13771 | RSS: Main: 271130624 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | __start_tp_report 07:38:12 INFO - PROCESS | 13771 | _x_x_mozilla_page_load 07:38:12 INFO - PROCESS | 13771 | _x_x_mozilla_page_load_details 07:38:12 INFO - PROCESS | 13771 | |i|pagename|runs| 07:38:12 INFO - PROCESS | 13771 | |0;ai-astar;114;108;114;116;112;112;112;112;113;112 07:38:12 INFO - PROCESS | 13771 | |1;audio-beat-detection;155;130;133;188;133;131;151;130;166;135 07:38:12 INFO - PROCESS | 13771 | |2;audio-dft;164;163;162;159;160;163;162;162;162;161 07:38:12 INFO - PROCESS | 13771 | |3;audio-fft;75;76;74;113;75;76;75;75;134;76 07:38:12 INFO - PROCESS | 13771 | |4;audio-oscillator;84;84;123;86;85;84;117;84;84;123 07:38:12 INFO - PROCESS | 13771 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;116;117 07:38:12 INFO - PROCESS | 13771 | |6;imaging-darkroom;147;146;146;146;146;147;147;147;145;146 07:38:12 INFO - PROCESS | 13771 | |7;imaging-desaturate;95;94;94;95;94;94;94;95;95;94 07:38:12 INFO - PROCESS | 13771 | |8;json-parse-financial;63;64;63;64;66;63;66;80;61;60 07:38:12 INFO - PROCESS | 13771 | |9;json-stringify-tinderbox;66;65;65;66;66;65;65;65;65;65 07:38:12 INFO - PROCESS | 13771 | |10;stanford-crypto-aes;83;88;88;86;88;83;81;84;90;83 07:38:12 INFO - PROCESS | 13771 | |11;stanford-crypto-ccm;140;142;140;142;137;128;128;130;166;135 07:38:12 INFO - PROCESS | 13771 | |12;stanford-crypto-pbkdf2;179;177;170;173;176;176;170;175;172;173 07:38:12 INFO - PROCESS | 13771 | |13;stanford-crypto-sha256-iterative;71;67;67;70;67;66;67;72;66;76 07:38:12 INFO - PROCESS | 13771 | __end_tp_report 07:38:12 INFO - PROCESS | 13771 | __start_cc_report 07:38:12 INFO - PROCESS | 13771 | _x_x_mozilla_cycle_collect,1894 07:38:12 INFO - PROCESS | 13771 | __end_cc_report 07:38:12 INFO - PROCESS | 13771 | __startTimestamp1464273492688__endTimestamp 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | ------- Summary: start ------- 07:38:12 INFO - PROCESS | 13771 | Number of tests: 14 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.50 stddev:2.07 (1.8%) stddev-sans-first:2.12 07:38:12 INFO - PROCESS | 13771 | Values: 114.0 108.0 114.0 116.0 112.0 112.0 112.0 112.0 113.0 112.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:143.00 stddev:19.61 (13.7%) stddev-sans-first:20.47 07:38:12 INFO - PROCESS | 13771 | Values: 155.0 130.0 133.0 188.0 133.0 131.0 151.0 130.0 166.0 135.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.48 (0.9%) stddev-sans-first:1.33 07:38:12 INFO - PROCESS | 13771 | Values: 164.0 163.0 162.0 159.0 160.0 163.0 162.0 162.0 162.0 161.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#3] audio-fft Cycles:10 Average:84.90 Median:75.00 stddev:20.95 (27.9%) stddev-sans-first:21.91 07:38:12 INFO - PROCESS | 13771 | Values: 75.0 76.0 74.0 113.0 75.0 76.0 75.0 75.0 134.0 76.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:84.00 stddev:17.75 (21.1%) stddev-sans-first:18.34 07:38:12 INFO - PROCESS | 13771 | Values: 84.0 84.0 123.0 86.0 85.0 84.0 117.0 84.0 84.0 123.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:38:12 INFO - PROCESS | 13771 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 07:38:12 INFO - PROCESS | 13771 | Values: 147.0 146.0 146.0 146.0 146.0 147.0 147.0 147.0 145.0 146.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 07:38:12 INFO - PROCESS | 13771 | Values: 95.0 94.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:64.00 stddev:5.60 (8.7%) stddev-sans-first:5.89 07:38:12 INFO - PROCESS | 13771 | Values: 63.0 64.0 63.0 64.0 66.0 63.0 66.0 80.0 61.0 60.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 07:38:12 INFO - PROCESS | 13771 | Values: 66.0 65.0 65.0 66.0 66.0 65.0 65.0 65.0 65.0 65.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:87.00 stddev:2.99 (3.4%) stddev-sans-first:3.04 07:38:12 INFO - PROCESS | 13771 | Values: 83.0 88.0 88.0 86.0 88.0 83.0 81.0 84.0 90.0 83.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:140.00 stddev:11.01 (7.9%) stddev-sans-first:11.67 07:38:12 INFO - PROCESS | 13771 | Values: 140.0 142.0 140.0 142.0 137.0 128.0 128.0 130.0 166.0 135.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:175.50 stddev:3.00 (1.7%) stddev-sans-first:2.60 07:38:12 INFO - PROCESS | 13771 | Values: 179.0 177.0 170.0 173.0 176.0 176.0 170.0 175.0 172.0 173.0 07:38:12 INFO - PROCESS | 13771 | 07:38:12 INFO - PROCESS | 13771 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.50 stddev:3.28 (4.8%) stddev-sans-first:3.39 07:38:12 INFO - PROCESS | 13771 | Values: 71.0 67.0 67.0 70.0 67.0 66.0 67.0 72.0 66.0 76.0 07:38:12 INFO - PROCESS | 13771 | -------- Summary: end -------- 07:38:12 INFO - PROCESS | 13771 | 07:38:13 INFO - TEST-INFO | 13771: exit 0 07:38:13 INFO - TEST-OK | kraken | took 63742ms 07:38:13 INFO - SUITE-END | took 1182s 07:38:13 INFO - Completed test suite (00:19:42) 07:38:13 INFO - javascript benchmark 07:38:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2691.676998760149, 2693.340131667741, 2732.795040371972, 2733.5489644208706, 2758.4483103379316, 4561.885952851172, 4619.214733495305, 4646.372739680142, 4746.905061898764, 4788.329633851259, 4659.976700116503, 4707.83522576709, 4714.434267887849, 4715.85852424427, 4718.882027949295, 4129.442525259082, 4285.764282964444, 4379.978100109511, 4416.183006143874, 4474.597286244238, 4383.583559561836, 4420.602145806877, 4439.911201775963, 4441.400410944513, 4469.994251293466, 4221.852235171769, 4290.828366865321, 4313.547077556843, 4340.60934515895, 4348.4999225089205, 4353.0, 4368.956310436887, 4413.470383553994, 4424.867253982354, 4428.867133985986, 4389.5295076149205, 4402.581754733295, 4412.24991751403, 4443.888902777424, 4445.488768791598, 4497.078098989699, 4629.606483448911, 4728.338032675429, 4728.432588089417, 4795.736234507101, 5183.481651834824, 5197.8440646780655, 5210.505002024802, 5210.947890521122, 5216.556592689626, 4953.752312384402, 5186.844394668166, 5203.063448579254, 5234.973825130888, 5241.528262456359, 4669.929951050737, 4712.2224832902875, 4712.976435117871, 4754.78603462845, 4776.044791041805, 5209.0, 5222.660527065728, 5224.791008359699, 5225.163973764255, 5247.921281180786, 4747.786349614275, 4924.039812236651, 4999.675021123688, 5050.520200580939, 5058.797648094035, 5065.214891691794, 5088.9745551272745, 5111.182210846247, 5116.616253780907, 5168.870778230575, 5028.823991160292, 5028.823991160365, 5040.067587496252, 5040.218766091261, 5084.084864724385, 4833.347498087784, 4838.709677419366, 4915.0415668944215, 5015.523525265094, 5025.447200807908, 4234.87295381139, 4236.2374772540625, 4236.618704316565, 4260.510041345223, 4270.636995855325, 3705.1292946157632, 3720.8883733488037, 3721.7394782364972, 3755.6807671348242, 3765.4540091686554, 4224.134052519287, 4237.788110594458, 4255.468066491689, 4299.505556861001, 4334.263175260214, 5112.846614601568, 5116.56509196715, 5127.538521533005, 5127.64106512548, 5187.844364669066, 4351.238533256731, 4351.477822661238, 4359.629431498295, 4396.758178300161, 4397.978010109929, 4496.64026877849, 4560.315952607136, 4595.632349412039, 4610.585047345755, 4611.377464042312, 4793.520647935178, 4801.735904525284, 4814.350062741485, 4817.614590832795, 4853.878653033561, 5133.486651334837, 5144.6398752087, 5147.768350424165, 5151.227315902494, 5193.948060519498, 4647.07058588277, 4664.277037059262, 4696.788644510937, 4700.905981880412, 4718.575328220477, 4963.305137280856, 4977.452480227244, 4977.800887964586, 4981.178105612678, 5026.974865125651, 4915.18899381598, 5000.874978125576, 5055.140626093499, 5055.191169412877, 5093.7707803148205, 4879.0, 4914.729689867092, 4915.97542012302, 4932.21084626458, 4950.232713929347, 4935.111679897653, 4943.752812359296, 4953.801847926186, 4955.554000140005, 5007.39911210657, 4749.24012158053, 4785.952140478551, 4864.537868902448, 4882.975585122052, 4930.087933732271, 4922.72924989129, 4928.162212423825, 4963.925541116814, 5003.67476114066, 5004.924926126038, 4827.710337379768, 4837.4920633332995, 4839.346688197189, 4843.934334446416, 4897.779599917941], "value": 4652.621836224643, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.8366949089001, 606.2876120558343, 608.4463138543924, 609.5184804004833, 611.3886113886114, 27262.727372726265, 30016.399672006573, 30130.849345753268, 38705.22589548207, 40956.0, 39875.60124398755, 40361.59638403615, 40398.59601403985, 40415.39376909348, 40448.797756011176, 7628.313451789338, 7810.570418626973, 7810.960945195295, 7812.414068944823, 7816.843663126762, 7433.702651893945, 7575.204603516634, 9821.066998635119, 10166.491675416237, 10170.338927969695, 10151.847722284172, 10214.897851021506, 10217.335873168258, 10219.284650074498, 10229.693109206735, 7467.253274672544, 10126.189904807634, 10200.0, 10202.89797102027, 10204.336718113336, 9634.47010414427, 10084.94957525215, 10086.747831304203, 10470.476476176236, 10474.895251047545, 39048.0, 39512.0, 39568.40647390291, 39621.0, 39643.0, 2861.3561948561614, 2979.478591246523, 3024.364883374494, 3053.7098975597282, 3469.809160496197, 29509.409811803645, 29519.70480295191, 29935.101946941628, 30084.69915300841, 30349.696503035128, 3429.2455659754814, 3557.7153827693724, 3585.3008663310666, 3598.514200582914, 3599.1002249437643, 248838.0, 248933.0, 251197.99999999817, 253414.0, 253601.0, 31835.68164318327, 32889.0, 32941.0, 34912.127196820285, 54930.0, 34652.13369665728, 34741.47887781685, 34784.30431391358, 34942.825285873914, 34954.825225873705, 12614.180078294881, 12773.680657983625, 12774.744505109846, 12778.424970876147, 12793.168444051107, 8436.704715334934, 9603.0, 9658.3722058066, 9670.90329096714, 10428.478576071166, 7963.681452741941, 7976.7208147714555, 8023.277904988579, 8045.758627241192, 8086.43394962347, 3146.98426507866, 3147.732442742347, 3168.7623428242973, 3177.158053115926, 3187.37846120009, 930.2093220762298, 957.3901663490411, 994.0357852882693, 997.4264797741328, 1037.7976294622476, 28117.297067573476, 28495.0, 28752.712472875002, 28902.0, 28990.130296091153, 945.8434768727312, 1001.2640709078821, 1045.414567842011, 1073.1361254190451, 1085.3216739537788, 14812.777808332883, 15199.392024319124, 15260.245071816884, 15437.691246175238, 15632.0, 369828.0, 370889.0, 373836.0, 374769.0, 375072.0, 49990.25014624783, 50288.49711502912, 50317.0, 50415.747921260896, 50508.74745626322, 1081.5133190064346, 1178.3106882473678, 1184.1000839362148, 1191.0590633399518, 1223.3821919930378, 27800.304992375353, 28863.42273154483, 29016.854915725286, 29561.55657665094, 29585.26036849096, 47686.28470573013, 49093.75453122712, 49139.508604914925, 54312.0, 54421.0, 345373.0, 350750.0, 350785.0, 350973.0, 351548.0, 33419.1645208862, 34349.656503435646, 34440.0, 34682.13294667654, 37246.44130338101, 33499.4975075379, 35325.70790905274, 43013.0, 43115.353269700354, 43540.34689479597, 34255.0, 36819.263614728094, 36867.26265474729, 37416.06459838526, 37707.811460943616, 32679.836600816845, 36454.0, 36824.0, 36921.0, 37402.625973739916, 36205.27589448249, 36387.818060910584, 36619.26761464809, 36921.81539092287, 37491.43762843505, 32499.350012999133, 33856.0, 33940.30298485115, 34235.828820855735, 34368.312633747686, 25407.75534679178, 25424.872875635505, 25731.742682572934, 25820.612690810027, 26258.343541411617], "value": 17051.38958135269, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.8222171136144, 365.6982989034044, 366.83675764284885, 367.70767240044154, 379.4062292512218, 15457.45898893539, 16822.0, 16982.660346793073, 17226.655466890654, 17471.912640436796, 15026.17356045417, 15862.920685396586, 16033.118178500177, 16050.43823466179, 16058.75911861323, 4995.775190116445, 5177.145770947808, 5189.974050129763, 5217.634765566412, 5227.843164705065, 3690.1512652090037, 3869.613038696136, 5165.63840531163, 5660.688662123582, 6022.2773267207995, 6043.607165534248, 6053.122297266894, 6067.878642427148, 6068.484178844781, 6078.908816367758, 5909.556783241274, 5911.3201981772, 6022.397760223987, 6045.7581696732295, 6092.634441933454, 3365.3774051800497, 4949.975250123763, 5269.710165940872, 5282.656627319212, 5288.947110528922, 19272.710909336372, 19889.50276243091, 19934.900325498424, 20171.798282017284, 20183.89908050465, 1879.7556317678614, 1966.852486063551, 2012.5471768852037, 2167.1439781286463, 2313.6876521669697, 12593.874061259361, 12686.365681715877, 12786.552470663482, 12788.0, 13133.343332833416, 1733.0468242466718, 1740.9912950435294, 1754.23690694547, 1761.2778760708047, 1818.7453756474104, 63232.0, 63852.680736596485, 64461.355386446005, 64873.351266487676, 65827.0, 8351.24838764514, 8857.424267422726, 9060.592273347715, 9211.170994610384, 9217.216536594331, 12145.332006739714, 12216.20594661362, 12296.754064918652, 12311.876881231252, 12316.630501084981, 6393.232812062489, 6475.417212450902, 6542.1168142300185, 6642.468602511787, 6696.06255124283, 6395.744170233139, 6486.935130648728, 6512.088307636935, 6554.901676474857, 6591.83520411984, 3650.780953142767, 3709.647583479565, 3837.827297771607, 3864.961350386516, 3892.5718171001167, 1517.9848201518064, 1718.4329171373417, 1769.2834402067183, 1828.9268429262947, 1844.7325137855205, 808.8382323535317, 844.3709436469869, 861.724248240557, 908.9363744537819, 912.2975309012156, 14865.479708210161, 15836.841631583537, 16106.919465402598, 16288.755668664979, 17256.65486690284, 798.1818635898251, 817.3216230528647, 862.499750144927, 892.7500299916034, 897.2552781191596, 8910.153535414125, 9240.181253904208, 9323.25413966881, 9364.391314564531, 9405.341626086109, 388490.0, 389789.0, 397996.0, 401382.9930850327, 405379.0, 71602.0, 75001.62499187469, 75204.0, 76194.0, 77451.22548774553, 940.6848705683536, 963.3304853127149, 967.3180407812616, 970.9951450242703, 986.5264672956878, 13967.231802250973, 14443.927780361031, 15567.84432155664, 15778.842211577738, 16250.67498650044, 35282.470762938065, 35904.82047589745, 36559.26881462409, 37129.44305835361, 37234.6276537242, 48874.75562622164, 49381.506184937694, 49533.25700114429, 49544.256836148204, 49946.500534994186, 6424.582402143846, 6771.153605799275, 7096.822579435349, 7257.020302259128, 7415.147258065392, 10628.25602207838, 12062.879371206176, 12396.380180990735, 12415.068869834979, 12506.624801255977, 9155.038720934206, 9297.767555811159, 9343.672971445967, 9737.415755054719, 9871.555779990062, 9627.855582166134, 9857.30998830075, 10336.948315258376, 10650.84023739629, 10679.412632304988, 5985.461308482258, 9287.48918809472, 9445.905540944777, 9667.033296670277, 10103.494825258855, 6625.602463852184, 6681.599104053772, 6765.357291057342, 6776.593404395752, 6791.286914873867, 7422.591757453392, 8942.105789421006, 9082.727518174466, 9452.291078169303, 9747.220222382335], "value": 7983.866666825259, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1280.846298444187, 1395.4139261510168, 1445.21958142603, 1561.66424218793, 1775.3342496563796, 6701.22935862376, 7585.886211706828, 7812.960935195316, 7834.373250139982, 8099.028116626013, 7895.526268423884, 7916.050073991115, 7937.563434011127, 7949.046114466258, 7986.9201307986905, 2192.079326682789, 2234.731832180141, 2237.865728056314, 2238.1159442020394, 2241.372415723592, 2164.9242276520326, 2189.5511420158828, 2228.8551244169157, 2231.8326125540566, 2234.72065991751, 2143.228437762404, 2163.7619861815188, 2167.566486702658, 2169.8372622053407, 2181.203860590881, 2128.8509804313703, 2131.0410315357994, 2135.8611690240086, 2137.754158271803, 2137.967930481036, 2098.842586805996, 2159.946001349979, 2161.664941934003, 2162.91348346066, 2165.4261620670536, 7561.962190189069, 7863.960680196619, 7915.525068495908, 7916.366690664733, 7926.128125906146, 4392.011797345572, 4521.932171017437, 4566.13243483737, 4574.313852922055, 4577.9542204577865, 2912.6213592233084, 2932.929480739536, 2936.853157342124, 3002.1894088595955, 3032.908153064917, 965.4062751407834, 971.4220039076808, 979.1481411172325, 988.0810845913297, 992.2508506077937, 10660.893391066067, 10786.298890572089, 10833.891661083446, 10850.348979061282, 10866.076383507412, 4229.281022226229, 4360.716553424018, 4411.18393097278, 4432.911341773147, 4436.0906014267, 9198.954005230065, 9279.0, 9286.582103805345, 9288.117628825386, 9295.209907157832, 4456.866294011184, 4462.642988560908, 4465.24090904547, 4474.664400170001, 4476.947917239446, 3493.6681015303507, 4001.579834117376, 4030.6573941214738, 4146.875593732193, 4194.328907374806, 3735.7945313008045, 3787.5833658297565, 3843.481130047464, 3872.0513474199, 3897.142628621699, 1210.3343161261448, 1219.1587804414926, 1225.7732319520917, 1230.2434003088215, 1237.7091383524864, 825.5088222507599, 839.5004972041747, 850.0734199722209, 873.0178549132227, 897.4211633496358, 5006.64953453262, 5187.559057480156, 5246.3704355477585, 5249.47505249472, 5278.261043453919, 408.4587921004621, 411.42811492026107, 411.76323613921517, 416.16351134220434, 855.4610595324906, 3334.149791803123, 3460.446328587414, 3482.982585087109, 3490.860365585399, 3492.109512074456, 9731.172850307661, 9904.900950990543, 9949.452780097165, 10002.399856008662, 10032.849507257251, 9841.45871977048, 9853.261005424622, 9943.950280248553, 9946.651867184759, 9960.950195248977, 7089.680964356617, 7370.373518250902, 7381.778546643609, 7512.586807725518, 7529.623518824037, 5007.448891634796, 5269.314989051399, 5275.0, 5285.762140703754, 5353.625246232727, 9092.408993415407, 9198.080191980749, 9211.953940230256, 9269.72190834276, 9297.67458138962, 9128.406653567497, 9334.0, 9501.57242924084, 9526.380785248937, 9553.04469553039, 5800.4489573490455, 5996.760129594767, 6000.8799824004145, 6726.932730672826, 6828.385445309747, 6750.831229219309, 6827.829304267433, 7317.15852676949, 7555.357794587521, 7559.244075592616, 6768.4585233182215, 6904.4131248844415, 6936.895946560705, 7089.503734738519, 7126.144862616312, 6808.761693340907, 6843.418309443753, 6845.383915447634, 6922.10012698346, 6933.445324374139, 6319.589226700434, 6392.4246817786625, 6464.127342808661, 6471.158749362553, 6535.477161826947, 4542.227821270458, 4545.3863728396245, 4577.130345234395, 4608.838690645811, 4634.165850147006, 4642.094791515622, 4691.9765401174145, 4737.549932756383, 4795.400574928134, 4826.758662066813], "value": 4111.419528464079, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.5153832331742, 286.7978075456803, 294.2291197063693, 294.3803294065991, 295.6481786673858, 4623.491415944243, 4772.0196118610775, 4798.256270278103, 4826.0830442216, 4841.055994081155, 4545.11370282795, 4755.286706993944, 4780.617550595953, 4783.9475315430545, 4793.952060479394, 3766.8304926278247, 3776.5656949450754, 3782.773033617978, 3788.62113788621, 3825.1202223488476, 3596.2268112355837, 3607.7474576779637, 3730.339998700579, 3766.2090960898245, 3777.244551089779, 3693.2244228712, 3709.443583462475, 3719.716697739289, 3729.8135093245364, 3736.514253147087, 3673.448982652597, 3682.226732386189, 3697.8335974881193, 3707.796071216082, 3710.962890371116, 3264.183954011497, 3267.4445344291526, 3272.345530893807, 3273.2798784267425, 3282.2122690554097, 4355.869323920288, 4553.954460455419, 4558.270676691714, 4605.263157894754, 4625.606823419979, 4174.3321068629175, 4200.579942005806, 4213.831446742127, 4217.557156498585, 4227.260229459832, 4971.179755340365, 4992.425871024806, 5006.89986200274, 5026.421961474441, 5033.546980771748, 3414.6243913169533, 3507.333606614735, 3524.929501409983, 3527.2592755521373, 3536.6914241730724, 5194.649391158326, 5223.82463945613, 5240.6069544784295, 5248.71132087735, 5248.947510524884, 3598.226381328027, 3600.6759391654878, 3606.549181352331, 3608.386574282378, 3608.891733248007, 3654.7989860557923, 3673.522442082512, 3673.5673087495898, 3676.889693309205, 3685.2445248724484, 4107.445494858216, 4170.374443833389, 4182.498100227992, 4184.895377615584, 4202.474690663667, 4965.726885021359, 4996.777078784164, 5017.147084995559, 5020.548150666457, 5034.823781167642, 1174.1546086817477, 1212.835677749353, 1215.6292330839178, 1216.2094638485055, 1216.4282787090053, 826.6114925984778, 849.392684230778, 855.9957200213959, 859.6346552715196, 861.1087524412203, 4364.236258654722, 4370.388145659546, 4395.582419670126, 4408.0, 4410.140022695544, 5025.82409615669, 5152.278680984665, 5176.818811341585, 5194.870128246824, 5201.817936372209, 4364.170807546556, 4411.647068234598, 4422.850058984687, 4429.383275104564, 4432.202203603382, 2715.9765974349234, 2729.1539622717023, 2740.1779466160074, 2743.3690251242324, 2749.5738160585142, 4561.0, 4804.903901921942, 4819.614430845524, 4853.878653033702, 4856.640140760593, 5111.207762796773, 5140.203268493389, 5201.699575106223, 5245.763940622681, 5246.3704355477585, 4792.568668819823, 4806.7836947337455, 4812.109759694467, 4816.229403295456, 4816.34979277793, 4909.239067944474, 5076.18780995039, 5094.0, 5113.718745469035, 5238.214267859851, 5066.493350664904, 5087.745612719424, 5106.361704786902, 5106.395738173484, 5136.0, 4873.756312184448, 4900.583450406755, 4906.852794416173, 4909.1654418748185, 4913.803447862046, 3386.695197432243, 3594.4788005739542, 3623.221007483404, 3626.202235508184, 3660.6821544244585, 5144.50173881764, 5175.430702622633, 5196.194589838581, 5200.6619569727845, 5209.505097015777, 4988.226824842155, 5006.274090256819, 5007.349044624321, 5021.4225364083595, 5022.835613563431, 4787.688800227974, 4965.255211718271, 5021.568852876912, 5031.9245211322595, 5096.184610462308, 4773.737444440589, 4871.48849370811, 4940.209566469492, 4954.529319714621, 4983.875403114806, 5070.138076526926, 5105.463926287686, 5121.436641969455, 5126.256692779451, 5134.589232861437, 4786.2581299898575, 4970.925436118533, 4972.676776009548, 4992.951480189201, 4999.325091112799], "value": 3755.5198354632016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.6871901487286, 675.0970825450561, 706.2090458686268, 774.4114473000518, 789.7946533901184, 12126.818097728541, 12208.389580520972, 12260.5708800192, 12267.079969002327, 12287.631371058871, 10012.499375031255, 12033.398330083504, 12069.155159138863, 12095.879041209586, 12096.758064838697, 633.4520639646707, 761.958092304923, 801.1547817053007, 804.0271271761176, 812.6018251056998, 793.7817100297407, 796.24356860982, 796.5658715999793, 801.0827602395254, 802.4663598706853, 850.3622283287534, 858.8883445152121, 859.6862145316977, 859.8710193470966, 860.0, 659.680055173243, 804.0753133896008, 854.1159899503983, 855.4140413816556, 856.015582080606, 616.747133675191, 616.8704572039877, 617.672633504239, 618.153130211613, 618.7215752911208, 11522.135839811965, 11559.53761849525, 11574.131940104442, 11583.536658533649, 11590.478428470738, 11903.16677832552, 12306.26162430248, 12388.31864247466, 12670.113092083559, 12670.493180272779, 11014.44927753609, 11068.28056176346, 11073.50169242386, 11262.267952583055, 11438.942805286004, 4025.7383270087353, 4058.066644671742, 4076.103257283393, 4088.795560221977, 4167.0, 43596.0, 43758.56241437545, 44101.77949110266, 44126.55873441257, 44205.0, 9578.671650173705, 9615.182709469633, 9723.465209413409, 9780.462074585967, 9951.0, 17260.568485787957, 17292.827071729374, 17324.913375433294, 17340.47978560645, 17355.13224338778, 6630.668466576652, 6674.43267322283, 6709.966450167718, 6732.798016059526, 6918.066061081689, 3732.496113024761, 3758.586555478895, 3760.0975765816006, 3787.753796003251, 3821.0, 2649.748273913975, 2733.2620192548297, 2734.6171535984977, 2734.808563400543, 2736.4253506763403, 453.1752210975992, 466.21442868766235, 467.2733898787418, 467.96256299495957, 486.3240096266193, 390.65036792071504, 483.28473858689335, 484.1865665681618, 488.82158012325243, 491.1968930798099, 6478.676066196671, 6649.867002659919, 6658.334166583303, 6680.766173183915, 6734.191896972395, 427.0178589244725, 451.40640058323197, 461.5684335146649, 467.6562726396096, 489.6058672768456, 3291.0949488890747, 3293.1602441377295, 3300.5214243934975, 3317.9834100829826, 3396.3207358528393, 52435.21347179796, 54288.45711542834, 54935.17597236045, 56033.719831401395, 56184.719076404355, 48607.0, 50005.0, 50010.249846252336, 50420.49579504231, 50880.49119508832, 46076.3088553672, 46275.0, 46359.0, 46832.0, 47139.0, 7628.732994345171, 7938.3252423544645, 8132.2680958714, 8138.064271985952, 8178.304844088081, 31100.0, 31257.218569535034, 31647.0, 31934.52098218575, 32185.517217242228, 38573.42139867848, 40433.191336172524, 40473.79763101166, 40524.0, 40940.38589421221, 10397.740056498647, 10507.0, 10831.837522437012, 10840.620578279722, 10987.285826421257, 10969.670909872717, 11621.941890290494, 11706.122040847142, 11710.765784684088, 11953.0, 11078.335299882034, 11201.88798112041, 11657.06743460538, 11815.350155741517, 12022.519099235933, 10229.590816367261, 10352.53413596375, 10826.025657690845, 11400.657980260605, 11500.884991149982, 11061.723456913642, 11087.279326843729, 11094.722631933942, 11241.269317494336, 11304.717382065513, 9024.458532488072, 9282.443053416817, 9286.907130928605, 9296.39573427725, 9298.349115561845, 9050.592723327334, 9240.815183696153, 9439.0, 9494.28792840526, 9495.43027418357], "value": 5839.4933578866385, "unit": "score"}], "name": "dromaeo_css", "value": 6205.728846979844, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 114.0, 116.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 130.0, 133.0, 188.0, 133.0, 131.0, 151.0, 130.0, 166.0, 135.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 159.0, 160.0, 163.0, 162.0, 162.0, 162.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 113.0, 75.0, 76.0, 75.0, 75.0, 134.0, 76.0], "value": 84.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 123.0, 86.0, 85.0, 84.0, 117.0, 84.0, 84.0, 123.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 145.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 64.0, 66.0, 63.0, 66.0, 80.0, 61.0, 60.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 88.0, 86.0, 88.0, 83.0, 81.0, 84.0, 90.0, 83.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 140.0, 142.0, 137.0, 128.0, 128.0, 130.0, 166.0, 135.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 170.0, 173.0, 176.0, 176.0, 170.0, 175.0, 172.0, 173.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 67.0, 70.0, 67.0, 66.0, 67.0, 72.0, 66.0, 76.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1554.7, "alertThreshold": 2.0}]} 07:38:13 INFO - Return code: 0 07:38:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:38:13 ERROR - # TBPL SUCCESS # 07:38:13 INFO - Running post-action listener: _resource_record_post_action 07:38:13 INFO - ##### 07:38:13 INFO - ##### Finished run-tests step (success) 07:38:13 INFO - ##### 07:38:13 INFO - Running post-run listener: _resource_record_post_run 07:38:14 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 2613248; Write bytes: 535515136; Read time: 688; Write time: 546520 07:38:14 INFO - TinderboxPrint: CPU usage
13.1% 07:38:14 INFO - TinderboxPrint: I/O read bytes / time
2,613,248 / 688 07:38:14 INFO - TinderboxPrint: I/O write bytes / time
535,515,136 / 546,520 07:38:14 INFO - TinderboxPrint: CPU idle
8,316.6 (86.9%) 07:38:14 INFO - TinderboxPrint: CPU user
1,209.2 (12.6%) 07:38:14 INFO - TinderboxPrint: Swap in / out
0 / 0 07:38:14 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 250261504; Read time: 0; Write time: 378640 07:38:14 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 2613248; Write bytes: 285253632; Read time: 688; Write time: 167880 07:38:14 INFO - Running post-run listener: _upload_blobber_files 07:38:14 INFO - Blob upload gear active. 07:38:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:38:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:38:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:38:15 INFO - (blobuploader) - INFO - Open directory for files ... 07:38:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:38:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:38:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:20 INFO - (blobuploader) - INFO - Done attempting. 07:38:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:38:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:38:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:21 INFO - (blobuploader) - INFO - Done attempting. 07:38:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:38:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:38:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:38:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:38:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:38:22 INFO - (blobuploader) - INFO - Done attempting. 07:38:22 INFO - (blobuploader) - INFO - Iteration through files over. 07:38:22 INFO - Return code: 0 07:38:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:38:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:38:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/583317b3311fef1bb451c2a79b14706253245f5ef37f3d688c26d3fba33ce50a7c14dcfc548fd4881126c2628240928d004224de3787cb322f6a4a4598ac6475", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ab9d609b224488eb546a2321111e64592d46b46510fe06480321a23ddf3a7cd0bc550b13effe2e1029fa2a4d5ff227d6208533c85ad3fb4f002cff388ab3851c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5051e77d3f5dbb9e7baa157d05dc95a1a4acae2e3e50180e1184fe6514358afed8dc0e8c868064caff2969b27d7be333eb5d1f361611ccf89b4830d082b7766c"} 07:38:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:38:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:38:22 INFO - Contents: 07:38:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/583317b3311fef1bb451c2a79b14706253245f5ef37f3d688c26d3fba33ce50a7c14dcfc548fd4881126c2628240928d004224de3787cb322f6a4a4598ac6475", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ab9d609b224488eb546a2321111e64592d46b46510fe06480321a23ddf3a7cd0bc550b13effe2e1029fa2a4d5ff227d6208533c85ad3fb4f002cff388ab3851c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5051e77d3f5dbb9e7baa157d05dc95a1a4acae2e3e50180e1184fe6514358afed8dc0e8c868064caff2969b27d7be333eb5d1f361611ccf89b4830d082b7766c"} 07:38:22 INFO - Running post-run listener: copy_logs_to_upload_dir 07:38:22 INFO - Copying logs to upload dir... 07:38:22 INFO - mkdir: /builds/slave/test/build/upload/logs 07:38:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.830755 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-26 07:38:21.947042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 07:38:21.950818) ========= 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=db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/583317b3311fef1bb451c2a79b14706253245f5ef37f3d688c26d3fba33ce50a7c14dcfc548fd4881126c2628240928d004224de3787cb322f6a4a4598ac6475", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ab9d609b224488eb546a2321111e64592d46b46510fe06480321a23ddf3a7cd0bc550b13effe2e1029fa2a4d5ff227d6208533c85ad3fb4f002cff388ab3851c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5051e77d3f5dbb9e7baa157d05dc95a1a4acae2e3e50180e1184fe6514358afed8dc0e8c868064caff2969b27d7be333eb5d1f361611ccf89b4830d082b7766c"} build_url:https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011501 build_url: 'https://queue.taskcluster.net/v1/task/VuJIivqWS2Szg33v_UiXKA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/583317b3311fef1bb451c2a79b14706253245f5ef37f3d688c26d3fba33ce50a7c14dcfc548fd4881126c2628240928d004224de3787cb322f6a4a4598ac6475", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ab9d609b224488eb546a2321111e64592d46b46510fe06480321a23ddf3a7cd0bc550b13effe2e1029fa2a4d5ff227d6208533c85ad3fb4f002cff388ab3851c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5051e77d3f5dbb9e7baa157d05dc95a1a4acae2e3e50180e1184fe6514358afed8dc0e8c868064caff2969b27d7be333eb5d1f361611ccf89b4830d082b7766c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 07:38:21.975636) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:38:21.975965) ========= 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=db0483c52d987ecb441a263b000001ad-1464268844.348856-879150979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 07:38:22.058362) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 07:38:22.058690) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 07:38:22.058992) ========= ========= Total master_lag: 0.17 =========