builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-040 starttime: 1463674358.62 results: success (0) buildid: 20160519082857 builduid: adb3077601154d27897ca3bfd922121d revision: ebd649229181d40d9f5ae41ea4d2109c8f782fe8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.622409) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.622893) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.623208) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.639148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.639456) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-19 09:12: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-05-19 09:12:39 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098487 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.751704) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.752051) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017271 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.801489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:38.801870) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ebd649229181d40d9f5ae41ea4d2109c8f782fe8 --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 ebd649229181d40d9f5ae41ea4d2109c8f782fe8 --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=9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-19 09:12:39,138 truncating revision to first 12 chars 2016-05-19 09:12:39,138 Setting DEBUG logging. 2016-05-19 09:12:39,138 attempt 1/10 2016-05-19 09:12:39,138 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ebd649229181?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 09:12:39,396 unpacking tar archive at: fx-team-ebd649229181/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541906 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:39.356994) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:39.357360) ========= script_repo_revision: ebd649229181d40d9f5ae41ea4d2109c8f782fe8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:39.357822) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:39.358127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 09:12:39.366037) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-19 09:12:39.366340) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:12:39 INFO - MultiFileLogger online at 20160519 09:12:39 in /builds/slave/test 09:12: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 09:12:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:12:39 INFO - {'append_to_log': False, 09:12:39 INFO - 'base_work_dir': '/builds/slave/test', 09:12:39 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:12:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:12:39 INFO - 'branch': 'Fx-Team-Non-PGO', 09:12:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:12:39 INFO - 'config_files': ('talos/linux_config.py',), 09:12:39 INFO - 'default_actions': ('clobber', 09:12:39 INFO - 'read-buildbot-config', 09:12:39 INFO - 'download-and-extract', 09:12:39 INFO - 'populate-webroot', 09:12:39 INFO - 'create-virtualenv', 09:12:39 INFO - 'install', 09:12:39 INFO - 'run-tests'), 09:12:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:12:39 INFO - 'download_minidump_stackwalk': True, 09:12:39 INFO - 'download_symbols': 'ondemand', 09:12:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:12:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:12:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:12:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:12:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:12:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:12:39 INFO - 'installer_path': 'installer.exe', 09:12:39 INFO - 'log_level': 'info', 09:12:39 INFO - 'log_name': 'talos', 09:12:39 INFO - 'log_to_console': True, 09:12:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:12:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:12:39 INFO - 'opt_config_files': (), 09:12:39 INFO - 'pip_index': False, 09:12:39 INFO - 'sps_profile': False, 09:12:39 INFO - 'sps_profile_interval': 0, 09:12:39 INFO - 'suite': 'dromaeojs', 09:12:39 INFO - 'system_bits': '32', 09:12:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:12:39 INFO - 'title': 'talos-linux64-ix-040', 09:12:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:12:39 INFO - 'use_talos_json': True, 09:12:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:12:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:12:39 INFO - 'work_dir': 'build'} 09:12:39 INFO - ##### 09:12:39 INFO - ##### Running clobber step. 09:12:39 INFO - ##### 09:12:39 INFO - Running pre-action listener: _resource_record_pre_action 09:12:39 INFO - Running main action method: clobber 09:12:39 INFO - rmtree: /builds/slave/test/build 09:12:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:12:40 INFO - Running post-action listener: _resource_record_post_action 09:12:40 INFO - ##### 09:12:40 INFO - ##### Finished clobber step (success) 09:12:40 INFO - ##### 09:12:40 INFO - ##### 09:12:40 INFO - ##### Running read-buildbot-config step. 09:12:40 INFO - ##### 09:12:40 INFO - Running pre-action listener: _resource_record_pre_action 09:12:40 INFO - Running main action method: read_buildbot_config 09:12:40 INFO - Using buildbot properties: 09:12:40 INFO - { 09:12:40 INFO - "project": "", 09:12:40 INFO - "product": "firefox", 09:12:40 INFO - "script_repo_revision": "production", 09:12:40 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:12:40 INFO - "repository": "", 09:12:40 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:12:40 INFO - "buildid": "20160519082857", 09:12:40 INFO - "pgo_build": "False", 09:12:40 INFO - "basedir": "/builds/slave/test", 09:12:40 INFO - "buildnumber": 2993, 09:12:40 INFO - "slavename": "talos-linux64-ix-040", 09:12:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:12:40 INFO - "platform": "ubuntu64_hw", 09:12:40 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:12:40 INFO - "branch": "fx-team", 09:12:40 INFO - "repo_path": "integration/fx-team", 09:12:40 INFO - "revision": "ebd649229181d40d9f5ae41ea4d2109c8f782fe8", 09:12:40 INFO - "stage_platform": "linux64", 09:12:40 INFO - "builduid": "adb3077601154d27897ca3bfd922121d", 09:12:40 INFO - "slavebuilddir": "test" 09:12:40 INFO - } 09:12:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:12:40 INFO - Running post-action listener: _resource_record_post_action 09:12:40 INFO - ##### 09:12:40 INFO - ##### Finished read-buildbot-config step (success) 09:12:40 INFO - ##### 09:12:40 INFO - ##### 09:12:40 INFO - ##### Running download-and-extract step. 09:12:40 INFO - ##### 09:12:40 INFO - Running pre-action listener: _resource_record_pre_action 09:12:40 INFO - Running main action method: download_and_extract 09:12:40 INFO - mkdir: /builds/slave/test/build/tests 09:12: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')]} 09:12:40 INFO - https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:12:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:12:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:12:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:12:41 INFO - Downloaded 1361 bytes. 09:12:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:12:41 INFO - Using the following test package requirements: 09:12:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:12:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:12:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:12:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'jsshell-linux-x86_64.zip'], 09:12:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:12:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:12:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:12:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:12:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:12:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:12:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:12:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:12: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')]} 09:12:41 INFO - https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:12:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:12:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:12:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:12:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:12:42 INFO - Downloaded 22812201 bytes. 09:12: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'] 09:12: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 09:12:43 INFO - Return code: 0 09:12: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 09:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:43 INFO - https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:12:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:12:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:12:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:12:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:12:44 INFO - Downloaded 22812201 bytes. 09:12: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'] 09:12: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 09:12:46 INFO - Return code: 0 09:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:46 INFO - https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:12:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:12:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:12:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:12:46 INFO - Downloaded 10840194 bytes. 09:12:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:12:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:12:47 INFO - Return code: 0 09:12: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')]} 09:12:47 INFO - https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:12:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:12:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:12:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:12:48 INFO - Downloaded 54797613 bytes. 09:12:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:12:48 INFO - mkdir: /builds/slave/test/properties 09:12:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:48 INFO - Contents: 09:12:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:12:49 INFO - Running post-action listener: _resource_record_post_action 09:12:49 INFO - Running post-action listener: set_extra_try_arguments 09:12:49 INFO - ##### 09:12:49 INFO - ##### Finished download-and-extract step (success) 09:12:49 INFO - ##### 09:12:49 INFO - ##### 09:12:49 INFO - ##### Running populate-webroot step. 09:12:49 INFO - ##### 09:12:49 INFO - Running pre-action listener: _resource_record_pre_action 09:12:49 INFO - Running main action method: populate_webroot 09:12:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:49 INFO - '--tppagecycles', 09:12:49 INFO - '7'], 09:12:49 INFO - 'tests': ['tsvgm']}}, 09:12:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['damp', 'tps']}, 09:12:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'tests': ['damp', 'tps']}, 09:12:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['dromaeo_dom']}, 09:12:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['a11yr', 09:12:49 INFO - 'ts_paint', 09:12:49 INFO - 'tpaint', 09:12:49 INFO - 'sessionrestore', 09:12:49 INFO - 'sessionrestore_no_auto_restore', 09:12:49 INFO - 'tabpaint']}, 09:12:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:49 INFO - 'ts_paint', 09:12:49 INFO - 'tpaint', 09:12:49 INFO - 'sessionrestore', 09:12:49 INFO - 'sessionrestore_no_auto_restore', 09:12:49 INFO - 'tabpaint']}, 09:12:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:49 INFO - 'ts_paint', 09:12:49 INFO - 'tpaint', 09:12:49 INFO - 'sessionrestore', 09:12:49 INFO - 'sessionrestore_no_auto_restore', 09:12:49 INFO - 'tabpaint']}, 09:12:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:49 INFO - 'ts_paint', 09:12:49 INFO - 'tpaint', 09:12:49 INFO - 'sessionrestore', 09:12:49 INFO - 'sessionrestore_no_auto_restore', 09:12:49 INFO - 'tabpaint']}, 09:12:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['a11yr', 09:12:49 INFO - 'ts_paint', 09:12:49 INFO - 'tpaint', 09:12:49 INFO - 'sessionrestore', 09:12:49 INFO - 'sessionrestore_no_auto_restore', 09:12:49 INFO - 'tabpaint']}, 09:12:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['a11yr', 09:12:49 INFO - 'ts_paint', 09:12:49 INFO - 'tpaint', 09:12:49 INFO - 'sessionrestore', 09:12:49 INFO - 'sessionrestore_no_auto_restore', 09:12:49 INFO - 'tabpaint']}, 09:12:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['tsvgx', 09:12:49 INFO - 'tsvgr_opacity', 09:12:49 INFO - 'tart', 09:12:49 INFO - 'tscrollx', 09:12:49 INFO - 'cart']}, 09:12:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:49 INFO - 'tsvgr_opacity', 09:12:49 INFO - 'tart', 09:12:49 INFO - 'tscrollx', 09:12:49 INFO - 'cart']}, 09:12:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'talos_options': ['--disable-e10s'], 09:12:49 INFO - 'tests': ['tp5o']}, 09:12:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'tests': ['tp5o']}, 09:12:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'talos_options': ['--disable-e10s', 09:12:49 INFO - '--xperf_path', 09:12:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:49 INFO - 'tests': ['tp5n']}, 09:12:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:49 INFO - 'talos_options': ['--xperf_path', 09:12:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:49 INFO - 'tests': ['tp5n']}}, 09:12:49 INFO - 'talos.zip': {'path': '', 09:12:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:49 INFO - Running post-action listener: _resource_record_post_action 09:12:49 INFO - ##### 09:12:49 INFO - ##### Finished populate-webroot step (success) 09:12:49 INFO - ##### 09:12:49 INFO - ##### 09:12:49 INFO - ##### Running create-virtualenv step. 09:12:49 INFO - ##### 09:12:49 INFO - Running pre-action listener: _resource_record_pre_action 09:12:49 INFO - Running main action method: create_virtualenv 09:12:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12: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 09:12:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:49 INFO - Using real prefix '/usr' 09:12:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:50 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:52 INFO - Installing pip.................done. 09:12:52 INFO - Return code: 0 09:12:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12: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 09:12: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 09:12:52 INFO - Using env: {'DISPLAY': ':0', 09:12:52 INFO - 'HOME': '/home/cltbld', 09:12:52 INFO - 'LANG': 'en_US.UTF-8', 09:12:52 INFO - 'LANGUAGE': 'en_US:en', 09:12:52 INFO - 'LOGNAME': 'cltbld', 09:12:52 INFO - 'MAIL': '/var/mail/cltbld', 09:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:52 INFO - 'MOZ_NO_REMOTE': '1', 09:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:52 INFO - 'NO_EM_RESTART': '1', 09:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:52 INFO - 'PWD': '/builds/slave/test', 09:12:52 INFO - 'SHELL': '/bin/bash', 09:12:52 INFO - 'SHLVL': '1', 09:12:52 INFO - 'TERM': 'linux', 09:12:52 INFO - 'TMOUT': '86400', 09:12:52 INFO - 'USER': 'cltbld', 09:12:52 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:52 INFO - '_': '/tools/buildbot/bin/python'} 09:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12: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 09:12:52 INFO - Cleaning up... 09:12:52 INFO - Return code: 0 09:12:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12: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 09:12: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 09:12:52 INFO - Using env: {'DISPLAY': ':0', 09:12:52 INFO - 'HOME': '/home/cltbld', 09:12:52 INFO - 'LANG': 'en_US.UTF-8', 09:12:52 INFO - 'LANGUAGE': 'en_US:en', 09:12:52 INFO - 'LOGNAME': 'cltbld', 09:12:52 INFO - 'MAIL': '/var/mail/cltbld', 09:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:52 INFO - 'MOZ_NO_REMOTE': '1', 09:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:52 INFO - 'NO_EM_RESTART': '1', 09:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:52 INFO - 'PWD': '/builds/slave/test', 09:12:52 INFO - 'SHELL': '/bin/bash', 09:12:52 INFO - 'SHLVL': '1', 09:12:52 INFO - 'TERM': 'linux', 09:12:52 INFO - 'TMOUT': '86400', 09:12:52 INFO - 'USER': 'cltbld', 09:12:52 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:52 INFO - '_': '/tools/buildbot/bin/python'} 09:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:52 INFO - Downloading/unpacking psutil>=3.1.1 09:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:54 INFO - Installing collected packages: psutil 09:12:54 INFO - Running setup.py install for psutil 09:12:54 INFO - building 'psutil._psutil_linux' extension 09:12: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 09:12:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:12:54 INFO - building 'psutil._psutil_posix' extension 09:12:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:12: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 09:12:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:55 INFO - Successfully installed psutil 09:12:55 INFO - Cleaning up... 09:12:55 INFO - Return code: 0 09:12:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12: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 09:12: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 09:12:55 INFO - Using env: {'DISPLAY': ':0', 09:12:55 INFO - 'HOME': '/home/cltbld', 09:12:55 INFO - 'LANG': 'en_US.UTF-8', 09:12:55 INFO - 'LANGUAGE': 'en_US:en', 09:12:55 INFO - 'LOGNAME': 'cltbld', 09:12:55 INFO - 'MAIL': '/var/mail/cltbld', 09:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:55 INFO - 'MOZ_NO_REMOTE': '1', 09:12:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:55 INFO - 'NO_EM_RESTART': '1', 09:12:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:55 INFO - 'PWD': '/builds/slave/test', 09:12:55 INFO - 'SHELL': '/bin/bash', 09:12:55 INFO - 'SHLVL': '1', 09:12:55 INFO - 'TERM': 'linux', 09:12:55 INFO - 'TMOUT': '86400', 09:12:55 INFO - 'USER': 'cltbld', 09:12:55 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:55 INFO - '_': '/tools/buildbot/bin/python'} 09:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:12:57 INFO - Installing collected packages: mozsystemmonitor 09:12:57 INFO - Running setup.py install for mozsystemmonitor 09:12:57 INFO - Successfully installed mozsystemmonitor 09:12:57 INFO - Cleaning up... 09:12:57 INFO - Return code: 0 09:12:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12: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 09:12: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 09:12:57 INFO - Using env: {'DISPLAY': ':0', 09:12:57 INFO - 'HOME': '/home/cltbld', 09:12:57 INFO - 'LANG': 'en_US.UTF-8', 09:12:57 INFO - 'LANGUAGE': 'en_US:en', 09:12:57 INFO - 'LOGNAME': 'cltbld', 09:12:57 INFO - 'MAIL': '/var/mail/cltbld', 09:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:57 INFO - 'MOZ_NO_REMOTE': '1', 09:12:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:57 INFO - 'NO_EM_RESTART': '1', 09:12:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:57 INFO - 'PWD': '/builds/slave/test', 09:12:57 INFO - 'SHELL': '/bin/bash', 09:12:57 INFO - 'SHLVL': '1', 09:12:57 INFO - 'TERM': 'linux', 09:12:57 INFO - 'TMOUT': '86400', 09:12:57 INFO - 'USER': 'cltbld', 09:12:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:57 INFO - '_': '/tools/buildbot/bin/python'} 09:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12:59 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:00 INFO - Downloading docopt-0.6.1.tar.gz 09:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:00 INFO - Running setup.py install for blobuploader 09:13:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:00 INFO - Running setup.py install for requests 09:13:00 INFO - Running setup.py install for docopt 09:13:00 INFO - Successfully installed blobuploader requests docopt 09:13:00 INFO - Cleaning up... 09:13:00 INFO - Return code: 0 09:13:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:00 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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:00 INFO - Using env: {'DISPLAY': ':0', 09:13:00 INFO - 'HOME': '/home/cltbld', 09:13:00 INFO - 'LANG': 'en_US.UTF-8', 09:13:00 INFO - 'LANGUAGE': 'en_US:en', 09:13:00 INFO - 'LOGNAME': 'cltbld', 09:13:00 INFO - 'MAIL': '/var/mail/cltbld', 09:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:00 INFO - 'MOZ_NO_REMOTE': '1', 09:13:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:00 INFO - 'NO_EM_RESTART': '1', 09:13:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:00 INFO - 'PWD': '/builds/slave/test', 09:13:00 INFO - 'SHELL': '/bin/bash', 09:13:00 INFO - 'SHLVL': '1', 09:13:00 INFO - 'TERM': 'linux', 09:13:00 INFO - 'TMOUT': '86400', 09:13:00 INFO - 'USER': 'cltbld', 09:13:00 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:00 INFO - '_': '/tools/buildbot/bin/python'} 09:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:01 INFO - Running setup.py (path:/tmp/pip-sncIp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:01 INFO - Running setup.py (path:/tmp/pip-QQPa7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:01 INFO - Running setup.py (path:/tmp/pip-JwpIKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:01 INFO - Running setup.py (path:/tmp/pip-3NtaEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:01 INFO - Running setup.py (path:/tmp/pip-N2VIZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:01 INFO - Running setup.py (path:/tmp/pip-fdVOZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:01 INFO - Running setup.py (path:/tmp/pip-z6I2_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:01 INFO - Running setup.py (path:/tmp/pip-Xt9VS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:01 INFO - Running setup.py (path:/tmp/pip-Y8rJwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:01 INFO - Running setup.py (path:/tmp/pip-b2YplA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:01 INFO - Running setup.py (path:/tmp/pip-CLxGNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:01 INFO - Running setup.py (path:/tmp/pip-2M_snJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:02 INFO - Running setup.py (path:/tmp/pip-JA_csC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:02 INFO - Running setup.py (path:/tmp/pip-gzO8cG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:02 INFO - Running setup.py (path:/tmp/pip-MgLBHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:02 INFO - Running setup.py (path:/tmp/pip-1EL7Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:02 INFO - Running setup.py (path:/tmp/pip-XdzpCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:02 INFO - Running setup.py install for manifestparser 09:13:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:02 INFO - Running setup.py install for mozcrash 09:13:02 INFO - Running setup.py install for mozdebug 09:13:02 INFO - Running setup.py install for mozdevice 09:13:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:02 INFO - Running setup.py install for mozfile 09:13:02 INFO - Running setup.py install for mozhttpd 09:13:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Running setup.py install for mozinfo 09:13:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Running setup.py install for mozInstall 09:13:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Running setup.py install for mozleak 09:13:03 INFO - Running setup.py install for mozlog 09:13:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Running setup.py install for moznetwork 09:13:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Running setup.py install for mozprocess 09:13:03 INFO - Running setup.py install for mozprofile 09:13:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Running setup.py install for mozrunner 09:13:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:03 INFO - Running setup.py install for mozscreenshot 09:13:03 INFO - Running setup.py install for moztest 09:13:04 INFO - Running setup.py install for mozversion 09:13:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:04 INFO - Cleaning up... 09:13:04 INFO - Return code: 0 09:13:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13: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 09:13: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 09:13:04 INFO - Using env: {'DISPLAY': ':0', 09:13:04 INFO - 'HOME': '/home/cltbld', 09:13:04 INFO - 'LANG': 'en_US.UTF-8', 09:13:04 INFO - 'LANGUAGE': 'en_US:en', 09:13:04 INFO - 'LOGNAME': 'cltbld', 09:13:04 INFO - 'MAIL': '/var/mail/cltbld', 09:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:04 INFO - 'MOZ_NO_REMOTE': '1', 09:13:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:04 INFO - 'NO_EM_RESTART': '1', 09:13:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:04 INFO - 'PWD': '/builds/slave/test', 09:13:04 INFO - 'SHELL': '/bin/bash', 09:13:04 INFO - 'SHLVL': '1', 09:13:04 INFO - 'TERM': 'linux', 09:13:04 INFO - 'TMOUT': '86400', 09:13:04 INFO - 'USER': 'cltbld', 09:13:04 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:04 INFO - '_': '/tools/buildbot/bin/python'} 09:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:04 INFO - Running setup.py (path:/tmp/pip-JR9l1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13: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)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:04 INFO - Running setup.py (path:/tmp/pip-k2jfcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13: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)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:04 INFO - Running setup.py (path:/tmp/pip-SLTseg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13: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)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:04 INFO - Running setup.py (path:/tmp/pip-MuJzWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13: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)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:04 INFO - Running setup.py (path:/tmp/pip-N4cQL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13: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)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:04 INFO - Running setup.py (path:/tmp/pip-0rBlsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:04 INFO - Running setup.py (path:/tmp/pip-COuMCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:04 INFO - Running setup.py (path:/tmp/pip-CChH_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:04 INFO - Running setup.py (path:/tmp/pip-SGwm_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:05 INFO - Running setup.py (path:/tmp/pip-rsFO4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:05 INFO - Running setup.py (path:/tmp/pip-rWB6E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:05 INFO - Running setup.py (path:/tmp/pip-OLIZv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:05 INFO - Running setup.py (path:/tmp/pip-z6qLgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:05 INFO - Running setup.py (path:/tmp/pip-EuItOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:05 INFO - Running setup.py (path:/tmp/pip-yNjkpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:05 INFO - Running setup.py (path:/tmp/pip-5Nh4EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13: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)) 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:05 INFO - Running setup.py (path:/tmp/pip-5LodRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:07 INFO - Downloading blessings-1.6.tar.gz 09:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:13:07 INFO - Installing collected packages: blessings 09:13:07 INFO - Running setup.py install for blessings 09:13:07 INFO - Successfully installed blessings 09:13:07 INFO - Cleaning up... 09:13:07 INFO - Return code: 0 09:13:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:13:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:08 INFO - Reading from file tmpfile_stdout 09:13:08 INFO - Current package versions: 09:13:08 INFO - argparse == 1.2.1 09:13:08 INFO - blessings == 1.6 09:13:08 INFO - blobuploader == 1.2.4 09:13:08 INFO - docopt == 0.6.1 09:13:08 INFO - manifestparser == 1.1 09:13:08 INFO - mozInstall == 1.12 09:13:08 INFO - mozcrash == 0.17 09:13:08 INFO - mozdebug == 0.1 09:13:08 INFO - mozdevice == 0.48 09:13:08 INFO - mozfile == 1.2 09:13:08 INFO - mozhttpd == 0.7 09:13:08 INFO - mozinfo == 0.9 09:13:08 INFO - mozleak == 0.1 09:13:08 INFO - mozlog == 3.2 09:13:08 INFO - moznetwork == 0.27 09:13:08 INFO - mozprocess == 0.22 09:13:08 INFO - mozprofile == 0.28 09:13:08 INFO - mozrunner == 6.11 09:13:08 INFO - mozscreenshot == 0.1 09:13:08 INFO - mozsystemmonitor == 0.3 09:13:08 INFO - moztest == 0.7 09:13:08 INFO - mozversion == 1.4 09:13:08 INFO - psutil == 3.1.1 09:13:08 INFO - requests == 1.2.3 09:13:08 INFO - wsgiref == 0.1.2 09:13:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13: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 09:13: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 09:13:08 INFO - Using env: {'DISPLAY': ':0', 09:13:08 INFO - 'HOME': '/home/cltbld', 09:13:08 INFO - 'LANG': 'en_US.UTF-8', 09:13:08 INFO - 'LANGUAGE': 'en_US:en', 09:13:08 INFO - 'LOGNAME': 'cltbld', 09:13:08 INFO - 'MAIL': '/var/mail/cltbld', 09:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:08 INFO - 'MOZ_NO_REMOTE': '1', 09:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:08 INFO - 'NO_EM_RESTART': '1', 09:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:08 INFO - 'PWD': '/builds/slave/test', 09:13:08 INFO - 'SHELL': '/bin/bash', 09:13:08 INFO - 'SHLVL': '1', 09:13:08 INFO - 'TERM': 'linux', 09:13:08 INFO - 'TMOUT': '86400', 09:13:08 INFO - 'USER': 'cltbld', 09:13:08 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:08 INFO - '_': '/tools/buildbot/bin/python'} 09:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13: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)) 09:13:08 INFO - Cleaning up... 09:13:08 INFO - Return code: 0 09:13:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13: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 09:13: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 09:13:08 INFO - Using env: {'DISPLAY': ':0', 09:13:08 INFO - 'HOME': '/home/cltbld', 09:13:08 INFO - 'LANG': 'en_US.UTF-8', 09:13:08 INFO - 'LANGUAGE': 'en_US:en', 09:13:08 INFO - 'LOGNAME': 'cltbld', 09:13:08 INFO - 'MAIL': '/var/mail/cltbld', 09:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:08 INFO - 'MOZ_NO_REMOTE': '1', 09:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:08 INFO - 'NO_EM_RESTART': '1', 09:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:08 INFO - 'PWD': '/builds/slave/test', 09:13:08 INFO - 'SHELL': '/bin/bash', 09:13:08 INFO - 'SHLVL': '1', 09:13:08 INFO - 'TERM': 'linux', 09:13:08 INFO - 'TMOUT': '86400', 09:13:08 INFO - 'USER': 'cltbld', 09:13:08 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:08 INFO - '_': '/tools/buildbot/bin/python'} 09:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:08 INFO - Downloading/unpacking jsonschema 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:10 INFO - Downloading/unpacking functools32 (from jsonschema) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:10 INFO - warning: no files found matching '*.txt' 09:13:10 INFO - no previously-included directories found matching 'build' 09:13:10 INFO - no previously-included directories found matching 'dist' 09:13:10 INFO - no previously-included directories found matching '.git*' 09:13:10 INFO - Installing collected packages: jsonschema, functools32 09:13:10 INFO - Running setup.py install for functools32 09:13:10 INFO - warning: no files found matching '*.txt' 09:13:10 INFO - no previously-included directories found matching 'build' 09:13:10 INFO - no previously-included directories found matching 'dist' 09:13:10 INFO - no previously-included directories found matching '.git*' 09:13:10 INFO - Successfully installed jsonschema functools32 09:13:10 INFO - Cleaning up... 09:13:10 INFO - Return code: 0 09:13:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:10 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 0x7f25d862cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xec18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf8c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, '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': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:13:10 INFO - Using env: {'DISPLAY': ':0', 09:13:10 INFO - 'HOME': '/home/cltbld', 09:13:10 INFO - 'LANG': 'en_US.UTF-8', 09:13:10 INFO - 'LANGUAGE': 'en_US:en', 09:13:10 INFO - 'LOGNAME': 'cltbld', 09:13:10 INFO - 'MAIL': '/var/mail/cltbld', 09:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:10 INFO - 'MOZ_NO_REMOTE': '1', 09:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:10 INFO - 'NO_EM_RESTART': '1', 09:13:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:10 INFO - 'PWD': '/builds/slave/test', 09:13:10 INFO - 'SHELL': '/bin/bash', 09:13:10 INFO - 'SHLVL': '1', 09:13:10 INFO - 'TERM': 'linux', 09:13:10 INFO - 'TMOUT': '86400', 09:13:10 INFO - 'USER': 'cltbld', 09:13:10 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:10 INFO - '_': '/tools/buildbot/bin/python'} 09:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:11 INFO - Downloading/unpacking flake8 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:13:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:13:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:13:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:13 INFO - no previously-included directories found matching 'docs/_build' 09:13:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:13:13 INFO - Running setup.py install for pep8 09:13:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:13 INFO - no previously-included directories found matching 'docs/_build' 09:13:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:13:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:13:13 INFO - Cleaning up... 09:13:14 INFO - Return code: 0 09:13:14 INFO - Running post-action listener: _resource_record_post_action 09:13:14 INFO - Running post-action listener: _start_resource_monitoring 09:13:14 INFO - Starting resource monitoring. 09:13:14 INFO - ##### 09:13:14 INFO - ##### Finished create-virtualenv step (success) 09:13:14 INFO - ##### 09:13:14 INFO - ##### 09:13:14 INFO - ##### Running install step. 09:13:14 INFO - ##### 09:13:14 INFO - Running pre-action listener: _resource_record_pre_action 09:13:14 INFO - Running main action method: install 09:13:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:14 INFO - Reading from file tmpfile_stdout 09:13:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:13:14 INFO - Reading from file tmpfile_stdout 09:13:14 INFO - Output received: 09:13:14 INFO - Usage: mozinstall [options] installer 09:13:14 INFO - Options: 09:13:14 INFO - -h, --help show this help message and exit 09:13:14 INFO - -d DEST, --destination=DEST 09:13:14 INFO - Directory to install application into. [default: 09:13:14 INFO - "/builds/slave/test"] 09:13:14 INFO - --app=APP Application being installed. [default: firefox] 09:13:14 INFO - mkdir: /builds/slave/test/build/application 09:13: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'] 09:13: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 09:13:30 INFO - Reading from file tmpfile_stdout 09:13:30 INFO - Output received: 09:13:30 INFO - /builds/slave/test/build/application/firefox/firefox 09:13:30 INFO - Running post-action listener: _resource_record_post_action 09:13:30 INFO - ##### 09:13:30 INFO - ##### Finished install step (success) 09:13:30 INFO - ##### 09:13:30 INFO - ##### 09:13:30 INFO - ##### Running run-tests step. 09:13:30 INFO - ##### 09:13:30 INFO - Running pre-action listener: _resource_record_pre_action 09:13:30 INFO - Running main action method: run_tests 09:13:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:13:30 INFO - Python 2.7.3 09:13:30 INFO - Return code: 0 09:13:30 INFO - grabbing minidump binary from tooltool 09:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13: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 0x101b9c0>, '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 0x101c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101cd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:13: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 09:13: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 09:13: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 09:13:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:13:30 INFO - Return code: 0 09:13:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:13:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:13:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:13:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:13:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:13:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:13:31 INFO - Return code: 0 09:13: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/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:13: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/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:13:31 INFO - Using env: {'DISPLAY': ':0', 09:13:31 INFO - 'HOME': '/home/cltbld', 09:13:31 INFO - 'LANG': 'en_US.UTF-8', 09:13:31 INFO - 'LANGUAGE': 'en_US:en', 09:13:31 INFO - 'LOGNAME': 'cltbld', 09:13:31 INFO - 'MAIL': '/var/mail/cltbld', 09:13:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:31 INFO - 'MOZ_NO_REMOTE': '1', 09:13:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:31 INFO - 'NO_EM_RESTART': '1', 09:13:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:31 INFO - 'PWD': '/builds/slave/test', 09:13:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:31 INFO - 'SHELL': '/bin/bash', 09:13:31 INFO - 'SHLVL': '1', 09:13:31 INFO - 'TERM': 'linux', 09:13:31 INFO - 'TMOUT': '86400', 09:13:31 INFO - 'USER': 'cltbld', 09:13:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564', 09:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:31 INFO - '_': '/tools/buildbot/bin/python'} 09:13: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/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:13:31 INFO - mozversion application_buildid: 20160519082857 09:13:31 INFO - mozversion application_changeset: ebd649229181d40d9f5ae41ea4d2109c8f782fe8 09:13:31 INFO - mozversion application_display_name: Nightly 09:13:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:13:31 INFO - mozversion application_name: Firefox 09:13:31 INFO - mozversion application_remotingname: firefox 09:13:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:13:31 INFO - mozversion application_vendor: Mozilla 09:13:31 INFO - mozversion application_version: 49.0a1 09:13:31 INFO - mozversion platform_buildid: 20160519082857 09:13:31 INFO - mozversion platform_changeset: ebd649229181d40d9f5ae41ea4d2109c8f782fe8 09:13:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:13:31 INFO - mozversion platform_version: 49.0a1 09:13:31 INFO - using testdate: 1463674411 09:13:31 INFO - actual date: 1463674411 09:13:31 INFO - starting webserver on 'localhost:33531' 09:13:31 INFO - SUITE-START | Running 2 tests 09:13:31 INFO - TEST-START | dromaeo_css 09:13:31 INFO - operating with platform_type : linux_ 09:13:31 INFO - Initialising browser for dromaeo_css test... 09:13:31 INFO - TEST-INFO | started process 7179 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRhO951/profile http://localhost:33531/getInfo.html) 09:13:35 INFO - PROCESS | 7179 | __metrics Screen width/height:1600/1200 09:13:35 INFO - PROCESS | 7179 | colorDepth:24 09:13:35 INFO - PROCESS | 7179 | Browser inner width/height: 1024/697 09:13:35 INFO - PROCESS | 7179 | __metrics 09:13:36 ERROR - PROCESS | 7179 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:13:37 INFO - TEST-INFO | 7179: exit 0 09:13:37 INFO - Browser initialized. 09:13:37 INFO - Running cycle 1/1 for dromaeo_css test... 09:13:37 INFO - TEST-INFO | started process 7262 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRhO951/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:49 INFO - PROCESS | 7262 | RSS: Main: 154423296 09:13:49 INFO - PROCESS | 7262 | 09:14:41 INFO - PROCESS | 7262 | 1463674481311 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:16:53 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:33531/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33531/tests/dromaeo/cssquery-ext.html) 09:16:54 INFO - PROCESS | 7262 | RSS: Main: 246005760 09:16:54 INFO - PROCESS | 7262 | 09:19:56 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:33531/tests/dromaeo/cssquery-ext.html (next: http://localhost:33531/tests/dromaeo/cssquery-jquery.html) 09:19:57 INFO - PROCESS | 7262 | RSS: Main: 238841856 09:19:57 INFO - PROCESS | 7262 | 09:22:59 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:33531/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33531/tests/dromaeo/cssquery-mootools.html) 09:23:00 INFO - PROCESS | 7262 | RSS: Main: 232169472 09:23:00 INFO - PROCESS | 7262 | 09:26:02 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:33531/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33531/tests/dromaeo/cssquery-prototype.html) 09:26:03 INFO - PROCESS | 7262 | RSS: Main: 235212800 09:26:03 INFO - PROCESS | 7262 | 09:29:05 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:33531/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33531/tests/dromaeo/cssquery-yui.html) 09:29:06 INFO - PROCESS | 7262 | RSS: Main: 218701824 09:29:06 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:33531/tests/dromaeo/cssquery-yui.html (next: http://localhost:33531/tests/dromaeo/cssquery-dojo.html) 09:32:09 INFO - PROCESS | 7262 | RSS: Main: 269824000 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | __start_tp_report 09:32:09 INFO - PROCESS | 7262 | _x_x_mozilla_page_load 09:32:09 INFO - PROCESS | 7262 | _x_x_mozilla_page_load_details 09:32:09 INFO - PROCESS | 7262 | |i|pagename|runs| 09:32:09 INFO - PROCESS | 7262 | |0;dojo.html;2697.959530607041;2700.729927007298;2728.9454210915783;2732.207659778663;2755.297399163213;4667.929981050279;4737.313089602014;4745.833895813647;4791.209450440678;4861.756912154396;4749.572538471537;4794.304825800257;4800.159972004903;4800.183968725316;4868.318435419044;4340.609345158935;4366.934495982563;4414.580614841585;4477.417935668359;4541.750203738793;4450.443694538183;4489.169503641837;4503.887402814923;4508.2561377372695;4536.727796332214;4300.8709738707885;4367.170237654836;4375.278079116942;4408.955910440886;4430.9778451107695;4383.364412160251;4390.582894625006;4395.340698895159;4418;4432.84485043022;4404.361367601679;4407.471103467572;4410.558944105596;4451.176532341495;4462.8884277892985;4463.598276155161;4606.262997920317;4639;4736.431628204603;4775.116603428377;5128.358955130609;5147.7683504242395;5195.558377537914;5204.505571970657;5207.713575753332;5191.351081114861;5214.713190774544;5226.529612334908;5229.686218826844;5246.213068039787;4701.9529804701515;4750.786214620351;4801.039792041536;4806.206975849016;4873.561379475828;5253.133233016586;5273.288106105703;5317.202419637086;5340.412554618989;5345.037893179187;5057.317262169634;5079.8984020319385;5122.026814905231;5129.076766182123;5138.152204886152;5016.498350164954;5152.76812543429;5197.480251974772;5204.21936709489;5279.841604751864;5171.379434467888;5173.32746742921;5177.249298851645;5179.067767801756;5193.143131383355;5156.664816786897;5165.535101840853;5172.456892026358;5175.275461435402;5213.843584692389;4248.511421186541;4292.59220374064;4294.677899157513;4346.022145017346;4365.869023929287;3806.5812760596314;3831.885043448701;3833.616638336144;3846.1346197105427;3846.673032792244;4429.955700443019;4481.439820022497;4481.910361792746;4540.682152249311;4543.273076307775;5192.45479224676;5192.714400707921;5195.480451954774;5205.401378841406;5233.005728911495;4402.5597440255715;4425.137098265807;4447.999200179998;4448.043670610769;4490.7754612269255;4522.4346956630425;4561.566651168134;4600.5629465200755;4621.745803980813;4637.25803871379;4873.293372460902;4875.293082503088;4875.853724388274;4879.4876537963;4937.851864444073;5026.472220416804;5132.43543210254;5175.430702622633;5198.532132108128;5248.501392367719;4848.296996935354;4895.632827537877;4903.730294833818;4913.582345500672;4922.827701030446;5085.186370180753;5085.745712714275;5111.693298402108;5130.050940576006;5142.871428214325;4981.402231732215;5049.697018178921;5151.6908985461;5196.948030519647;5202.985417843484;4939.53074457926;5025.773840177128;5040.294358789699;5048.823291184791;5050.393952725696;5015.322931404359;5026.271190677258;5038.093143234107;5045.31888195089;5087.3386459759995;4793.18515852299;4880.902381952412;4923.458419573915;4976.377952755905;4990.975045124751;5058.367704036996;5080.415752188398;5082.491750824888;5085.974570127126;5122.538971492583;4973.52751488608;4973.602111830973;4976.2286845539;4980.775865086152;5013.298138260573 09:32:09 INFO - PROCESS | 7262 | |1;ext.html;582.5077809251184;610.2859654204583;616.2112496005113;620.6245221640908;631.4884943196008;42439;43051.35422968654;43234;43293.35059974099;47935.52064479354;43937.56062439374;44459.77770111145;44496;44512.55487445125;44532.77733611336;7947.801304967379;8192.672293108268;8195.46729462586;8195.67217311307;8199.877001844978;7992.48048876824;8075.313598344148;8083.70660694289;8086.433949623529;8088.716894908679;8101.5544145072;8144.4706094103985;8148.511089334629;8155.34757219424;8155.388345874054;8053;8098.109207987087;8100.878486822702;8104.149064348277;8109.4323397362205;7882.211778822129;8038.758837234892;8042.235987581171;8044.1553636867875;8048.436609437342;41067;41135.58864411347;41210.38184427236;41391;41512.58487415117;3251.6910893465083;3413.3173365326784;3483.9129021774406;3716.479692843004;3926.1269530860177;31160;31365.84317078423;31593;32128;33072.50391244133;3522.947155792665;3531.4526248431534;3534.169470174507;3558.1460449492192;3670.4861319415304;266178;266695;267260;267777;268240.999999998;49353.259701104515;56597.43402566004;57674;57741;57743.71128144332;60017.69991150103;61403;61698.38301617016;65963;65963.67018164879;15409.537713868602;15587.844121558639;15639;15648.92175539115;15650.9217453912;9989.500524973722;10850.348979061282;11675;11857.703557411134;11881.40592970348;17186.484405467854;17282.913585432245;17337.2198251079;17411.303547857944;17534.386296479563;5799.318197722698;5907.350191478934;5944.7027648617395;6041.516678665696;6095.512359011268;864.8616221404545;906.954652267384;962.1148543340004;1015.9441230732233;1076.316538997742;28377.148685539465;29100.41799164005;29281;29725.702742972728;29776;1014.2190513304862;1027.6403258859339;1049.1239814754765;1055.3931489393628;1097.3470784882982;15299.388024478896;15584.766228506582;15609.843901560838;15666.216689165496;15914.965527240693;413208;416519;416954;417009;419980.90105044516;52152.21771673428;52950.470495295325;53040.73479632577;53046;53206.20190697143;1143.9771204575861;1186.6024881664566;1256.208588589183;1262.8863402293837;1279.8720127987312;29015.274618134714;30228.546571801882;30360.84819575888;30445.391092178474;30826.53760193554;44736.32895506505;45392.77303613461;45450;45492;45522;318870;319626;320219;320363;320699;33096.834515827264;38162.42756358712;38261.42607860829;38541.22917541577;38759;43712.82183802332;44716.55283447124;45204;45706.54293457023;45858.77070614626;36433;38258;38502.22995540017;38675.6132438672;38800.41799373068;37465;37608.247835043694;37798.62201377951;38054.42918356171;38453.4231986526;33193;34378.312433751686;37866.8106659465;38019.23961520809;38219.61780382161;33088.1727956803;33260.50109248315;34213.48679769756;34427;34548;25510;25685;26022.73977260203;26298;26334.863734478535 09:32:09 INFO - PROCESS | 7262 | |2;jquery.html;364.8941806876006;379.15637706103905;382.2126419575674;385.3987779064659;405.60453557781165;16276.186190690463;16636.916815415905;17625.735613965786;17699.1150442478;17709.82290177098;16316;16737.916310418463;16886;17069.743953840672;17086.658266834656;5113.642045056848;5257.605679574028;5261.316028916254;5295.7881684732565;5299.28459657947;3659.7804131752046;3904.39481880309;5589.552835773149;5921.319048309435;5921.378255283176;5840.591158618899;5908.291005079396;5914.497267732227;5947.732352044147;5956.940430595703;5818.592698511085;5920.141579470953;5937.940620593782;5982.3120341160675;5991.2510936132985;3323.88366407177;3492.406290930548;3502.0194345583022;3525.365434221839;4835.08133454646;20485.692714609293;21213.681794773092;21323.57352852934;21361.67957480639;21370.679439808417;1941.38846263428;1977.3968939473455;2026.1490174126754;2234.3297010896667;2445.29086564896;12732.681682957907;13361.799573006412;13494.865051349558;13582.524611638546;13933.164010159422;1732.5235560220915;1815.4086159869198;1837.8345948864667;1845.492489565367;1853.898035608041;67676.3232367675;67799;68890.65554672194;69504.65247673831;70785.64607176982;9697.757556061013;10016.599336026624;10058.346207496636;10140.543675534618;10270.794584108276;13735.175889446666;13858.584242472742;13873.791893121612;13875.306234688225;13889.305534723426;7600.8479830403085;7605.429592780563;7720.262940838379;7765.611719414007;7770.922290777133;7296.197418283985;7327.487075904742;7341.375983041395;7453.329200371993;7478.551286922802;6322.747090116344;6445.613263204223;6447.871042579216;6482.708278127496;6554.377334153248;1641.7373220284696;1759.7624320716538;1811.80975997521;1829.954251143732;1846.6306738652054;444.72871548355477;924.3621900888365;938.2119020023214;949.4635530925143;1023.4933707814679;10039.397636141855;11011.559537618567;11015.83476247857;11045.116390688725;11261.493232804543;414.4301585320185;433.79177994562787;448.9528599497071;463.56656526148157;464.9000464900063;5849.7952571660635;5871.530277577784;5911.645301281937;6020.909686354708;6169.722362493699;297579;298002;398931;408362;408739;51099;52287;52663.473365266625;65827;79454.60272698599;950.2398081534883;996.6412091647002;1011.231464087299;1011.428542873289;1017.4302390661253;15843.049417035014;15863.444779432664;15933.20333983273;16013.359465621244;16398.672026559165;27293.317667058483;32059.35881282408;32449.86425475097;36358;36991.44512832256;50850.745746272514;51874.740626296625;53397.39807805617;53510.197347039044;54267;7050.682719277543;7255.746048888263;8002.279794818494;8016.879746803919;8178.959105204436;11676.299422034705;12248.265104093784;12332.07509436778;12342.568010119961;12642.2414655121;10337.37975721459;10450.790984180427;10467.371957682564;10678;10741.624043158452;10246.436445995243;10665.46672666348;10738.516766745359;10794.08250298702;10961.890381096087;10286.948565257126;10408.895911041096;10586.89413105859;10617.362958222531;10697.304675196086;7133.144022717307;7265.818354541178;7383.372413344711;7402.481826272109;7462.403007759258;9176.090468497578;9975.102240798364;10328.483575821028;10401.58393664245;10664.146868250366 09:32:09 INFO - PROCESS | 7262 | |3;mootools.html;1252.5929073051261;1439.3163247457455;1468.9853101468987;1583.5407731757791;1763.497403240076;7636.121845987706;7931.405144614156;7934.56359900206;8109.91890081099;8117.675292988288;7909.1299957004685;8038;8068.193180681928;8087.272145506903;8098.230668086537;2280.315905228429;2306.331163962449;2309.480366917447;2309.618912879373;2309.8036666883336;2275.5790178816887;2327.0691723310642;2329.5457385809773;2335.182686059874;2335.9532809343805;2246.034205291724;2252.3017864462067;2275.1468199425217;2293.9447853987103;2298.5517824024323;2218.134927378324;2239.2274665240466;2255.7180352455944;2258.796708296253;2260.638297872341;2187.0267730859773;2189.879556624385;2192.8574642648337;2193.5722534105857;2195.2755590655047;7855.921440785576;7903.3282171015535;7943.642536085832;7969;8120.918790812076;4727.314539391803;4853.757312134415;4855.32025516428;4860.756962151913;4885.511448855122;3007.428588568187;3018.4264989651897;3075.815451072943;3180.6819318068237;3183.602049743782;983.9950800246024;995.775950411159;1042.7112488088649;1048.6853943816825;1067.9679609611724;10795.190360722978;10858.61994830185;10907.672769816918;10911.727206819814;10919.672409827717;4294.398784170218;4372.213001659732;4534.047849951481;4544.318352247124;4563.566461186182;8669.913300867036;8715.346349023848;8732;8753.64985400577;8787.91212087871;4761.309610106515;4787.784549695272;4790.976045119752;4798.04039192163;4818.4217893852965;3665.138692407282;4029.9395509067385;4336.111097225062;4415.536368681306;4451.821927122944;3550.840212190458;3611.133328001261;3670.504481894963;3690.55713314404;3735.75717578362;1057.0539367266254;1075.4730182210817;1077.175960390302;1082.929609575375;1085.934843909368;805.8066064144563;814.5764202614725;820.7004443378244;822.2476434062766;831.4553967151526;5363.839084827461;5524.3370795504;5621.7189140542805;5810.82567522975;5814.76740930358;874.4578361415963;926.9582868770922;930.4510338900081;933.9579718912665;935.1256575102166;3762.1535154590547;3796.354619714655;3857.9228415431535;3881.4177873319227;3923.6076392360537;9420.387674801115;9541.188998935168;9730.270229732798;9752.756181095529;9782.51087445625;9586.808263834684;9675.080867317594;9684.806303874024;9700.175485083846;9719.611215551298;7452.552846829208;7508.662110205054;7687.461877668621;7689.884651730228;7708.381239939564;5541.612087153861;5556.888862222652;5618.438156184349;5645.830625081254;5962.761489540369;8732.301415886841;8769.12308769118;9000.009998900247;9030.729078127666;9037.954810225907;9005.954970225106;9260.583273752563;9288.164065233892;9319.021502742386;9359.391639543408;6083.361247068994;6243.313235544178;6247.96876015617;6387.393197646216;6880.139982502187;7350;7609.086909570887;7616.23837616234;7618.961905190438;7628.694852205849;6744.730210791513;6937.202221744564;7190;7198.136223653195;7357.337839594462;6582.605043697394;6782.1861376635115;6794.150731158606;6899.0341352209725;7081;5887.470127688529;6019.157317975574;6029.969850150721;6163.3528479509005;6170.6914654268;4291.120320334387;4324.308110702273;4346.217680817484;4400.097979914174;4404.647628189801;4396.120775844908;4430.224710675554;4433.911321773482;4447.799849006868;4469.508354081114 09:32:09 INFO - PROCESS | 7262 | |4;prototype.html;310.17338792119;311.24833527032206;314.94960806270996;315.77895473168786;317.7981981441785;4778.784954677039;4806.591439727627;4808.447028591713;4828.806847726091;4837.564619184277;4710.798746319692;4721.858344249669;4726.810927562893;4741.90516189676;4767.713937163764;3579.9463008054836;3662.6703596676293;3686.115332320237;3744.8876533704033;3751.1559899022773;3724.366857634195;3727.3477141500266;3731.3843215869347;3747.681447077002;3754.8122593870335;3528.311979164064;3709.684676802475;3725.9068523286865;3736.215394767102;3737.8504859805576;3587.497750314958;3742.3263812513874;3746.4380342948502;3762.473253744478;3777.112378591029;3355.2953879685297;3367.4275373186615;3368.0569440556683;3370.831458427069;3377.7973321600784;4581.190429780251;4581.335706322598;4587.42657167854;4606.838760643362;4613.700109492872;4222.282212023963;4337;4353.194658988097;4354.7604881731495;4369.541198174178;4982.508745627187;4983.202687570008;4996.1756310208775;5034.395872495324;5037.974810125926;3632.527771389729;3646.08847788053;3646.851241858806;3690.335739566877;3705.7961812100325;4940.357753492023;5156.071907056766;5234.8691282717855;5238;5274.709890955995;3744.6068162843053;3768.7361884668358;3777.905552361213;3780.79014715294;3783.5838057813617;3816.4084566892175;3820.8280627371837;3841.865534706272;3842.865499707497;3845.9230815383535;4286.764227967492;4293.74237545742;4300.333448315516;4304.203722311382;4309.116631090682;4718.811247550128;4985.825496107619;4990.925136122961;4995.17579599362;5008.223725322581;1268.8516892212563;1279.6161151654467;1284.0305569295308;1286.6718986658343;1286.8005459153846;825.82732519822;830.339879795564;864.7967727584013;886.5301390263171;890.3455959869491;4467.324753217544;4468.932966005513;4474.530174331713;4484.865454036384;4489.393931819228;5062.797488100435;5098.770555325019;5123.641345105882;5129.153689641242;5148.459411761786;4230.61924426803;4429.180601588717;4433.8226470941445;4449.755263460541;4478.753668548261;2708.8352008636475;2720.605512200759;2727.481778462086;2736.3569561153113;2745.3136715821047;4812.855614331575;4827.272590986082;4833.154198015333;4846.951530484721;4856.927146092811;5025.221090730943;5197.194434862602;5202;5210.374755029421;5226.5626952588345;4790.928136077961;4816.99999999993;4825.469198388175;4837.201861692852;4840.1287768200655;5077.669951453144;5084.161113416245;5091.541761241506;5115.974420127875;5133.409657889241;4741.336212930261;4925.778339974638;4969.503049695001;5001.449840517613;5003.399592048978;4752.52474752522;4756.788093536522;4782.856514304576;4784.928226076542;4794.832180873653;3643.854245830137;3645.1069487975615;3651.5983241843905;3659.505966694462;3665.376885929452;4905.2151655734915;5048.141815891234;5055.559165637776;5060.3927528696795;5080.542751152414;4834.510227233048;4910.582600478856;4930.644072879987;4930.778114984906;4935.210366341368;4870.683405578626;4887.706737595756;4893.143699852582;4899.632527560519;4907.435644900882;4574.588287054181;4836.733979631154;4840.782164802663;4842.297866809364;4845.2732090186755;4897.706137631753;4899.828506002415;4948.183549714401;4951.282064100613;4973.328600638867;4806.47128815823;4855.732934688626;4863.638181309239;4867.902641947212;4876.805182730253 09:32:09 INFO - PROCESS | 7262 | |5;yui.html;730.4740586777525;739.1536690489388;759.1876691939624;775.1357236681102;787.126289818302;12738.745225095492;13030.674233144176;13226;13300.534481293156;13354;12728.427220775064;12733.363331833418;12764.23414595125;12794.232346059243;12841;671.0873212431089;831.8876951611546;832.6169961817541;853.108501615813;854.6111519258753;849.3078141314809;855.7475544714324;856.1353033436212;856.7686724584358;857.087202129733;892.7277180459957;894.6242578117174;896.9641214351418;897.0625696147509;898.137787723786;697.0972590495321;861.1087524412203;886.1537231943479;886.8182022685367;886.8359353519555;655.2268323378412;655.367570294668;655.4134050025201;656.61587971037;657.1391477164882;12934.223946563143;12950.222986620833;12967.740645187137;12975.416106275146;12983.09118361715;11264.38045907483;12035.037197024218;12779.872201277962;12905.709429057018;13082.672933176651;10919.12646988239;10942.343459392461;10991.450427478594;11132.777344453065;11150.107991360754;4072.8981775455554;4135.503739551243;4202.306619407825;4231.280682284019;4271.700980931336;46704;46896.29655555135;46903;46950;47097.76451117757;9126.45241285525;9152.31357648179;9265;9274;9612.99063598326;16128.112953787655;16280.10459424743;16285.430009949594;16290.51128466148;16305.429309974332;6714.731410743613;6816.079829222991;7002.089728335284;7013.859722805515;7024.473164512682;3723.2181241938956;3836.5204349456317;3863.32391831434;3881.107345310594;3918.0596656802163;2772.7227277272514;2849.7292757188034;2854.7716182705335;2862.613547171147;2869.4404591104953;520.5627273090622;539.4578448659073;562.7580140539608;568.9601727879092;587.7120211096532;513.9203423469369;569.9344575373885;580.692233116449;593.519249407981;598.9431004054695;7290.927090729025;7575.267398975758;7588.241175882478;7740;7744;500.33955179163155;579.0410999791064;586.3696526234315;595.1043679262682;614.27822308787;3754.6433088856515;3785.6782173515007;3827.4258861170492;3864.71014673895;3895.610438956082;49613;51679;51949.22076168861;57403.13895291574;58159.12761308584;51132;51396.48603513917;51521.7423912878;51700.48299517032;51836.22245666318;47069.29396058993;47946.5205347942;48287.75856120767;48561.75719121452;49131.5086849127;8509.319254459504;8523.95738021306;8573.442726222776;8602.78493037679;8758.12418758119;31621.683783161872;32198.517022244218;32432;32621.18447038843;33325.83337083299;37506.81246593859;39288.803555982035;40955.795221023705;41119;42033.36949945692;10971.670849874517;11481.010448617308;11483.311001339946;11502.597409090977;11724.296542207494;11763.941180294043;12005.77658893434;12017.63947081554;12138.446700406894;12486.875131248935;9851.16265117473;9919.504024798587;10106.797864042825;10305.74235644115;11263.324200547993;10779.407132607468;10890.509927053143;11461.197716159788;11541.884581154081;11878.584249551224;10405.375677459377;10793.244472886823;11290.548378065114;11578.189526733384;11780.057595392176;9140.908590914005;9219.953900230455;9433.103855133751;9859.95070024674;9905.207583393167;8863.778405539913;8914.777130571787;9429.811403772022;9637.807243854942;9715.70852874415 09:32:09 INFO - PROCESS | 7262 | __end_tp_report 09:32:09 INFO - PROCESS | 7262 | __start_cc_report 09:32:09 INFO - PROCESS | 7262 | _x_x_mozilla_cycle_collect,939 09:32:09 INFO - PROCESS | 7262 | __end_cc_report 09:32:09 INFO - PROCESS | 7262 | __startTimestamp1463675529583__endTimestamp 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | ------- Summary: start ------- 09:32:09 INFO - PROCESS | 7262 | Number of tests: 6 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | [#0] dojo.html Cycles:165 Average:4765.90 Median:4895.63 stddev:504.54 (10.3%) stddev-sans-first:479.30 09:32:09 INFO - PROCESS | 7262 | Values: 2698.0 2700.7 2728.9 2732.2 2755.3 4667.9 4737.3 4745.8 4791.2 4861.8 4749.6 4794.3 4800.2 4800.2 4868.3 4340.6 4366.9 4414.6 4477.4 4541.8 4450.4 4489.2 4503.9 4508.3 4536.7 4300.9 4367.2 4375.3 4409.0 4431.0 4383.4 4390.6 4395.3 4418.0 4432.8 4404.4 4407.5 4410.6 4451.2 4462.9 4463.6 4606.3 4639.0 4736.4 4775.1 5128.4 5147.8 5195.6 5204.5 5207.7 5191.4 5214.7 5226.5 5229.7 5246.2 4702.0 4750.8 4801.0 4806.2 4873.6 5253.1 5273.3 5317.2 5340.4 5345.0 5057.3 5079.9 5122.0 5129.1 5138.2 5016.5 5152.8 5197.5 5204.2 5279.8 5171.4 5173.3 5177.2 5179.1 5193.1 5156.7 5165.5 5172.5 5175.3 5213.8 4248.5 4292.6 4294.7 4346.0 4365.9 3806.6 3831.9 3833.6 3846.1 3846.7 4430.0 4481.4 4481.9 4540.7 4543.3 5192.5 5192.7 5195.5 5205.4 5233.0 4402.6 4425.1 4448.0 4448.0 4490.8 4522.4 4561.6 4600.6 4621.7 4637.3 4873.3 4875.3 4875.9 4879.5 4937.9 5026.5 5132.4 5175.4 5198.5 5248.5 4848.3 4895.6 4903.7 4913.6 4922.8 5085.2 5085.7 5111.7 5130.1 5142.9 4981.4 5049.7 5151.7 5196.9 5203.0 4939.5 5025.8 5040.3 5048.8 5050.4 5015.3 5026.3 5038.1 5045.3 5087.3 4793.2 4880.9 4923.5 4976.4 4991.0 5058.4 5080.4 5082.5 5086.0 5122.5 4973.5 4973.6 4976.2 4980.8 5013.3 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | [#1] ext.html Cycles:180 Average:50380.56 Median:38521.73 stddev:89568.39 (232.5%) stddev-sans-first:89741.61 09:32:09 INFO - PROCESS | 7262 | Values: 582.5 610.3 616.2 620.6 631.5 42439.0 43051.4 43234.0 43293.4 47935.5 43937.6 44459.8 44496.0 44512.6 44532.8 7947.8 8192.7 8195.5 8195.7 8199.9 7992.5 8075.3 8083.7 8086.4 8088.7 8101.6 8144.5 8148.5 8155.3 8155.4 8053.0 8098.1 8100.9 8104.1 8109.4 7882.2 8038.8 8042.2 8044.2 8048.4 41067.0 41135.6 41210.4 41391.0 41512.6 3251.7 3413.3 3483.9 3716.5 3926.1 31160.0 31365.8 31593.0 32128.0 33072.5 3522.9 3531.5 3534.2 3558.1 3670.5 266178.0 266695.0 267260.0 267777.0 268241.0 49353.3 56597.4 57674.0 57741.0 57743.7 60017.7 61403.0 61698.4 65963.0 65963.7 15409.5 15587.8 15639.0 15648.9 15650.9 9989.5 10850.3 11675.0 11857.7 11881.4 17186.5 17282.9 17337.2 17411.3 17534.4 5799.3 5907.4 5944.7 6041.5 6095.5 864.9 907.0 962.1 1015.9 1076.3 28377.1 29100.4 29281.0 29725.7 29776.0 1014.2 1027.6 1049.1 1055.4 1097.3 15299.4 15584.8 15609.8 15666.2 15915.0 413208.0 416519.0 416954.0 417009.0 419980.9 52152.2 52950.5 53040.7 53046.0 53206.2 1144.0 1186.6 1256.2 1262.9 1279.9 29015.3 30228.5 30360.8 30445.4 30826.5 44736.3 45392.8 45450.0 45492.0 45522.0 318870.0 319626.0 320219.0 320363.0 320699.0 33096.8 38162.4 38261.4 38541.2 38759.0 43712.8 44716.6 45204.0 45706.5 45858.8 36433.0 38258.0 38502.2 38675.6 38800.4 37465.0 37608.2 37798.6 38054.4 38453.4 33193.0 34378.3 37866.8 38019.2 38219.6 33088.2 33260.5 34213.5 34427.0 34548.0 25510.0 25685.0 26022.7 26298.0 26334.9 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | [#2] jquery.html Cycles:180 Average:22986.58 Median:18361.45 stddev:60343.12 (328.6%) stddev-sans-first:60488.49 09:32:09 INFO - PROCESS | 7262 | Values: 364.9 379.2 382.2 385.4 405.6 16276.2 16636.9 17625.7 17699.1 17709.8 16316.0 16737.9 16886.0 17069.7 17086.7 5113.6 5257.6 5261.3 5295.8 5299.3 3659.8 3904.4 5589.6 5921.3 5921.4 5840.6 5908.3 5914.5 5947.7 5956.9 5818.6 5920.1 5937.9 5982.3 5991.3 3323.9 3492.4 3502.0 3525.4 4835.1 20485.7 21213.7 21323.6 21361.7 21370.7 1941.4 1977.4 2026.1 2234.3 2445.3 12732.7 13361.8 13494.9 13582.5 13933.2 1732.5 1815.4 1837.8 1845.5 1853.9 67676.3 67799.0 68890.7 69504.7 70785.6 9697.8 10016.6 10058.3 10140.5 10270.8 13735.2 13858.6 13873.8 13875.3 13889.3 7600.8 7605.4 7720.3 7765.6 7770.9 7296.2 7327.5 7341.4 7453.3 7478.6 6322.7 6445.6 6447.9 6482.7 6554.4 1641.7 1759.8 1811.8 1830.0 1846.6 444.7 924.4 938.2 949.5 1023.5 10039.4 11011.6 11015.8 11045.1 11261.5 414.4 433.8 449.0 463.6 464.9 5849.8 5871.5 5911.6 6020.9 6169.7 297579.0 298002.0 398931.0 408362.0 408739.0 51099.0 52287.0 52663.5 65827.0 79454.6 950.2 996.6 1011.2 1011.4 1017.4 15843.0 15863.4 15933.2 16013.4 16398.7 27293.3 32059.4 32449.9 36358.0 36991.4 50850.7 51874.7 53397.4 53510.2 54267.0 7050.7 7255.7 8002.3 8016.9 8179.0 11676.3 12248.3 12332.1 12342.6 12642.2 10337.4 10450.8 10467.4 10678.0 10741.6 10246.4 10665.5 10738.5 10794.1 10961.9 10286.9 10408.9 10586.9 10617.4 10697.3 7133.1 7265.8 7383.4 7402.5 7462.4 9176.1 9975.1 10328.5 10401.6 10664.1 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | [#3] mootools.html Cycles:180 Average:5226.75 Median:5549.25 stddev:2889.60 (52.1%) stddev-sans-first:2882.27 09:32:09 INFO - PROCESS | 7262 | Values: 1252.6 1439.3 1469.0 1583.5 1763.5 7636.1 7931.4 7934.6 8109.9 8117.7 7909.1 8038.0 8068.2 8087.3 8098.2 2280.3 2306.3 2309.5 2309.6 2309.8 2275.6 2327.1 2329.5 2335.2 2336.0 2246.0 2252.3 2275.1 2293.9 2298.6 2218.1 2239.2 2255.7 2258.8 2260.6 2187.0 2189.9 2192.9 2193.6 2195.3 7855.9 7903.3 7943.6 7969.0 8120.9 4727.3 4853.8 4855.3 4860.8 4885.5 3007.4 3018.4 3075.8 3180.7 3183.6 984.0 995.8 1042.7 1048.7 1068.0 10795.2 10858.6 10907.7 10911.7 10919.7 4294.4 4372.2 4534.0 4544.3 4563.6 8669.9 8715.3 8732.0 8753.6 8787.9 4761.3 4787.8 4791.0 4798.0 4818.4 3665.1 4029.9 4336.1 4415.5 4451.8 3550.8 3611.1 3670.5 3690.6 3735.8 1057.1 1075.5 1077.2 1082.9 1085.9 805.8 814.6 820.7 822.2 831.5 5363.8 5524.3 5621.7 5810.8 5814.8 874.5 927.0 930.5 934.0 935.1 3762.2 3796.4 3857.9 3881.4 3923.6 9420.4 9541.2 9730.3 9752.8 9782.5 9586.8 9675.1 9684.8 9700.2 9719.6 7452.6 7508.7 7687.5 7689.9 7708.4 5541.6 5556.9 5618.4 5645.8 5962.8 8732.3 8769.1 9000.0 9030.7 9038.0 9006.0 9260.6 9288.2 9319.0 9359.4 6083.4 6243.3 6248.0 6387.4 6880.1 7350.0 7609.1 7616.2 7619.0 7628.7 6744.7 6937.2 7190.0 7198.1 7357.3 6582.6 6782.2 6794.2 6899.0 7081.0 5887.5 6019.2 6030.0 6163.4 6170.7 4291.1 4324.3 4346.2 4400.1 4404.6 4396.1 4430.2 4433.9 4447.8 4469.5 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | [#4] prototype.html Cycles:180 Average:4145.00 Median:4741.62 stddev:1179.60 (24.9%) stddev-sans-first:1147.26 09:32:09 INFO - PROCESS | 7262 | Values: 310.2 311.2 314.9 315.8 317.8 4778.8 4806.6 4808.4 4828.8 4837.6 4710.8 4721.9 4726.8 4741.9 4767.7 3579.9 3662.7 3686.1 3744.9 3751.2 3724.4 3727.3 3731.4 3747.7 3754.8 3528.3 3709.7 3725.9 3736.2 3737.9 3587.5 3742.3 3746.4 3762.5 3777.1 3355.3 3367.4 3368.1 3370.8 3377.8 4581.2 4581.3 4587.4 4606.8 4613.7 4222.3 4337.0 4353.2 4354.8 4369.5 4982.5 4983.2 4996.2 5034.4 5038.0 3632.5 3646.1 3646.9 3690.3 3705.8 4940.4 5156.1 5234.9 5238.0 5274.7 3744.6 3768.7 3777.9 3780.8 3783.6 3816.4 3820.8 3841.9 3842.9 3845.9 4286.8 4293.7 4300.3 4304.2 4309.1 4718.8 4985.8 4990.9 4995.2 5008.2 1268.9 1279.6 1284.0 1286.7 1286.8 825.8 830.3 864.8 886.5 890.3 4467.3 4468.9 4474.5 4484.9 4489.4 5062.8 5098.8 5123.6 5129.2 5148.5 4230.6 4429.2 4433.8 4449.8 4478.8 2708.8 2720.6 2727.5 2736.4 2745.3 4812.9 4827.3 4833.2 4847.0 4856.9 5025.2 5197.2 5202.0 5210.4 5226.6 4790.9 4817.0 4825.5 4837.2 4840.1 5077.7 5084.2 5091.5 5116.0 5133.4 4741.3 4925.8 4969.5 5001.4 5003.4 4752.5 4756.8 4782.9 4784.9 4794.8 3643.9 3645.1 3651.6 3659.5 3665.4 4905.2 5048.1 5055.6 5060.4 5080.5 4834.5 4910.6 4930.6 4930.8 4935.2 4870.7 4887.7 4893.1 4899.6 4907.4 4574.6 4836.7 4840.8 4842.3 4845.3 4897.7 4899.8 4948.2 4951.3 4973.3 4806.5 4855.7 4863.6 4867.9 4876.8 09:32:09 INFO - PROCESS | 7262 | 09:32:09 INFO - PROCESS | 7262 | [#5] yui.html Cycles:180 Average:13340.99 Median:48424.76 stddev:15431.76 (31.9%) stddev-sans-first:15445.99 09:32:09 INFO - PROCESS | 7262 | Values: 730.5 739.2 759.2 775.1 787.1 12738.7 13030.7 13226.0 13300.5 13354.0 12728.4 12733.4 12764.2 12794.2 12841.0 671.1 831.9 832.6 853.1 854.6 849.3 855.7 856.1 856.8 857.1 892.7 894.6 897.0 897.1 898.1 697.1 861.1 886.2 886.8 886.8 655.2 655.4 655.4 656.6 657.1 12934.2 12950.2 12967.7 12975.4 12983.1 11264.4 12035.0 12779.9 12905.7 13082.7 10919.1 10942.3 10991.5 11132.8 11150.1 4072.9 4135.5 4202.3 4231.3 4271.7 46704.0 46896.3 46903.0 46950.0 47097.8 9126.5 9152.3 9265.0 9274.0 9613.0 16128.1 16280.1 16285.4 16290.5 16305.4 6714.7 6816.1 7002.1 7013.9 7024.5 3723.2 3836.5 3863.3 3881.1 3918.1 2772.7 2849.7 2854.8 2862.6 2869.4 520.6 539.5 562.8 569.0 587.7 513.9 569.9 580.7 593.5 598.9 7290.9 7575.3 7588.2 7740.0 7744.0 500.3 579.0 586.4 595.1 614.3 3754.6 3785.7 3827.4 3864.7 3895.6 49613.0 51679.0 51949.2 57403.1 58159.1 51132.0 51396.5 51521.7 51700.5 51836.2 47069.3 47946.5 48287.8 48561.8 49131.5 8509.3 8524.0 8573.4 8602.8 8758.1 31621.7 32198.5 32432.0 32621.2 33325.8 37506.8 39288.8 40955.8 41119.0 42033.4 10971.7 11481.0 11483.3 11502.6 11724.3 11763.9 12005.8 12017.6 12138.4 12486.9 9851.2 9919.5 10106.8 10305.7 11263.3 10779.4 10890.5 11461.2 11541.9 11878.6 10405.4 10793.2 11290.5 11578.2 11780.1 9140.9 9220.0 9433.1 9860.0 9905.2 8863.8 8914.8 9429.8 9637.8 9715.7 09:32:09 INFO - PROCESS | 7262 | -------- Summary: end -------- 09:32:09 INFO - PROCESS | 7262 | 09:32:10 INFO - TEST-INFO | 7262: exit 0 09:32:10 INFO - TEST-OK | dromaeo_css | took 1119013ms 09:32:10 INFO - TEST-START | kraken 09:32:10 INFO - operating with platform_type : linux_ 09:32:10 INFO - Initialising browser for kraken test... 09:32:10 INFO - TEST-INFO | started process 7446 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkdJ002/profile http://localhost:33531/getInfo.html) 09:32:14 INFO - PROCESS | 7446 | __metrics Screen width/height:1600/1200 09:32:14 INFO - PROCESS | 7446 | colorDepth:24 09:32:14 INFO - PROCESS | 7446 | Browser inner width/height: 1024/697 09:32:14 INFO - PROCESS | 7446 | __metrics 09:32:15 ERROR - PROCESS | 7446 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:32:16 INFO - TEST-INFO | 7446: exit 0 09:32:16 INFO - Browser initialized. 09:32:16 INFO - Running cycle 1/1 for kraken test... 09:32:16 INFO - TEST-INFO | started process 7528 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkdJ002/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:28 INFO - PROCESS | 7528 | RSS: Main: 154456064 09:32:28 INFO - PROCESS | 7528 | 09:32:31 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33531/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:32 INFO - PROCESS | 7528 | RSS: Main: 210276352 09:32:32 INFO - PROCESS | 7528 | 09:32:35 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33531/tests/kraken/driver.html?testName=audio-dft) 09:32:35 INFO - PROCESS | 7528 | RSS: Main: 198295552 09:32:35 INFO - PROCESS | 7528 | 09:32:39 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33531/tests/kraken/driver.html?testName=audio-fft) 09:32:39 INFO - PROCESS | 7528 | RSS: Main: 267493376 09:32:39 INFO - PROCESS | 7528 | 09:32:41 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33531/tests/kraken/driver.html?testName=audio-oscillator) 09:32:42 INFO - PROCESS | 7528 | RSS: Main: 175804416 09:32:42 INFO - PROCESS | 7528 | 09:32:44 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33531/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:32:45 INFO - PROCESS | 7528 | RSS: Main: 179601408 09:32:45 INFO - PROCESS | 7528 | 09:32:48 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33531/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:49 INFO - PROCESS | 7528 | RSS: Main: 216408064 09:32:49 INFO - PROCESS | 7528 | 09:32:53 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33531/tests/kraken/driver.html?testName=imaging-desaturate) 09:32:53 INFO - PROCESS | 7528 | RSS: Main: 214528000 09:32:53 INFO - PROCESS | 7528 | 09:32:56 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33531/tests/kraken/driver.html?testName=json-parse-financial) 09:32:57 INFO - PROCESS | 7528 | RSS: Main: 215445504 09:32:57 INFO - PROCESS | 7528 | 09:32:59 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33531/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:32:59 INFO - PROCESS | 7528 | RSS: Main: 207826944 09:32:59 INFO - PROCESS | 7528 | 09:33:01 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:02 INFO - PROCESS | 7528 | RSS: Main: 212271104 09:33:02 INFO - PROCESS | 7528 | 09:33:04 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:05 INFO - PROCESS | 7528 | RSS: Main: 216866816 09:33:05 INFO - PROCESS | 7528 | 09:33:08 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:08 INFO - PROCESS | 7528 | RSS: Main: 220164096 09:33:08 INFO - PROCESS | 7528 | 09:33:11 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:12 INFO - PROCESS | 7528 | RSS: Main: 218304512 09:33:12 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | Cycle 1(1): loaded http://localhost:33531/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33531/tests/kraken/driver.html?testName=ai-astar) 09:33:14 INFO - PROCESS | 7528 | RSS: Main: 265437184 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | __start_tp_report 09:33:14 INFO - PROCESS | 7528 | _x_x_mozilla_page_load 09:33:14 INFO - PROCESS | 7528 | _x_x_mozilla_page_load_details 09:33:14 INFO - PROCESS | 7528 | |i|pagename|runs| 09:33:14 INFO - PROCESS | 7528 | |0;ai-astar;113;108;113;113;114;113;113;113;114;113 09:33:14 INFO - PROCESS | 7528 | |1;audio-beat-detection;161;130;156;206;130;150;161;131;181;130 09:33:14 INFO - PROCESS | 7528 | |2;audio-dft;161;160;163;163;163;162;161;160;163;172 09:33:14 INFO - PROCESS | 7528 | |3;audio-fft;75;75;76;152;76;74;77;74;141;74 09:33:14 INFO - PROCESS | 7528 | |4;audio-oscillator;92;93;149;94;94;93;121;93;93;131 09:33:14 INFO - PROCESS | 7528 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;117;116;116 09:33:14 INFO - PROCESS | 7528 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;145;145 09:33:14 INFO - PROCESS | 7528 | |7;imaging-desaturate;94;95;95;95;94;94;94;94;94;95 09:33:14 INFO - PROCESS | 7528 | |8;json-parse-financial;65;65;64;64;64;64;63;66;63;64 09:33:14 INFO - PROCESS | 7528 | |9;json-stringify-tinderbox;65;66;66;65;66;65;65;65;66;65 09:33:14 INFO - PROCESS | 7528 | |10;stanford-crypto-aes;85;81;81;81;82;82;88;85;141;88 09:33:14 INFO - PROCESS | 7528 | |11;stanford-crypto-ccm;128;128;127;126;134;127;125;128;146;131 09:33:14 INFO - PROCESS | 7528 | |12;stanford-crypto-pbkdf2;171;170;173;172;172;171;171;171;173;171 09:33:14 INFO - PROCESS | 7528 | |13;stanford-crypto-sha256-iterative;68;66;67;66;66;65;67;65;65;65 09:33:14 INFO - PROCESS | 7528 | __end_tp_report 09:33:14 INFO - PROCESS | 7528 | __start_cc_report 09:33:14 INFO - PROCESS | 7528 | _x_x_mozilla_cycle_collect,2140 09:33:14 INFO - PROCESS | 7528 | __end_cc_report 09:33:14 INFO - PROCESS | 7528 | __startTimestamp1463675594368__endTimestamp 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | ------- Summary: start ------- 09:33:14 INFO - PROCESS | 7528 | Number of tests: 14 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:1.70 (1.5%) stddev-sans-first:1.80 09:33:14 INFO - PROCESS | 7528 | Values: 113.0 108.0 113.0 113.0 114.0 113.0 113.0 113.0 114.0 113.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#1] audio-beat-detection Cycles:10 Average:153.60 Median:158.50 stddev:25.40 (16.0%) stddev-sans-first:26.80 09:33:14 INFO - PROCESS | 7528 | Values: 161.0 130.0 156.0 206.0 130.0 150.0 161.0 131.0 181.0 130.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:3.46 (2.1%) stddev-sans-first:3.61 09:33:14 INFO - PROCESS | 7528 | Values: 161.0 160.0 163.0 163.0 163.0 162.0 161.0 160.0 163.0 172.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#3] audio-fft Cycles:10 Average:89.40 Median:75.00 stddev:30.22 (40.3%) stddev-sans-first:31.60 09:33:14 INFO - PROCESS | 7528 | Values: 75.0 75.0 76.0 152.0 76.0 74.0 77.0 74.0 141.0 74.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#4] audio-oscillator Cycles:10 Average:105.30 Median:93.00 stddev:20.69 (22.3%) stddev-sans-first:21.38 09:33:14 INFO - PROCESS | 7528 | Values: 92.0 93.0 149.0 94.0 94.0 93.0 121.0 93.0 93.0 131.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:33:14 INFO - PROCESS | 7528 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 116.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:33:14 INFO - PROCESS | 7528 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:33:14 INFO - PROCESS | 7528 | Values: 94.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 95.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 09:33:14 INFO - PROCESS | 7528 | Values: 65.0 65.0 64.0 64.0 64.0 64.0 63.0 66.0 63.0 64.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 09:33:14 INFO - PROCESS | 7528 | Values: 65.0 66.0 66.0 65.0 66.0 65.0 65.0 65.0 66.0 65.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:83.50 stddev:18.34 (22.0%) stddev-sans-first:19.38 09:33:14 INFO - PROCESS | 7528 | Values: 85.0 81.0 81.0 81.0 82.0 82.0 88.0 85.0 141.0 88.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#11] stanford-crypto-ccm Cycles:10 Average:130.00 Median:128.00 stddev:6.18 (4.8%) stddev-sans-first:6.51 09:33:14 INFO - PROCESS | 7528 | Values: 128.0 128.0 127.0 126.0 134.0 127.0 125.0 128.0 146.0 131.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:171.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 09:33:14 INFO - PROCESS | 7528 | Values: 171.0 170.0 173.0 172.0 172.0 171.0 171.0 171.0 173.0 171.0 09:33:14 INFO - PROCESS | 7528 | 09:33:14 INFO - PROCESS | 7528 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:1.05 (1.6%) stddev-sans-first:0.83 09:33:14 INFO - PROCESS | 7528 | Values: 68.0 66.0 67.0 66.0 66.0 65.0 67.0 65.0 65.0 65.0 09:33:14 INFO - PROCESS | 7528 | -------- Summary: end -------- 09:33:14 INFO - PROCESS | 7528 | 09:33:15 INFO - TEST-INFO | 7528: exit 0 09:33:15 INFO - TEST-OK | kraken | took 65047ms 09:33:15 INFO - SUITE-END | took 1184s 09:33:15 INFO - Completed test suite (00:19:44) 09:33:15 INFO - javascript benchmark 09:33:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2697.959530607041, 2700.729927007298, 2728.9454210915783, 2732.207659778663, 2755.297399163213, 4667.929981050279, 4737.313089602014, 4745.833895813647, 4791.209450440678, 4861.756912154396, 4749.572538471537, 4794.304825800257, 4800.159972004903, 4800.183968725316, 4868.318435419044, 4340.609345158935, 4366.934495982563, 4414.580614841585, 4477.417935668359, 4541.750203738793, 4450.443694538183, 4489.169503641837, 4503.887402814923, 4508.2561377372695, 4536.727796332214, 4300.8709738707885, 4367.170237654836, 4375.278079116942, 4408.955910440886, 4430.9778451107695, 4383.364412160251, 4390.582894625006, 4395.340698895159, 4418.0, 4432.84485043022, 4404.361367601679, 4407.471103467572, 4410.558944105596, 4451.176532341495, 4462.8884277892985, 4463.598276155161, 4606.262997920317, 4639.0, 4736.431628204603, 4775.116603428377, 5128.358955130609, 5147.7683504242395, 5195.558377537914, 5204.505571970657, 5207.713575753332, 5191.351081114861, 5214.713190774544, 5226.529612334908, 5229.686218826844, 5246.213068039787, 4701.9529804701515, 4750.786214620351, 4801.039792041536, 4806.206975849016, 4873.561379475828, 5253.133233016586, 5273.288106105703, 5317.202419637086, 5340.412554618989, 5345.037893179187, 5057.317262169634, 5079.8984020319385, 5122.026814905231, 5129.076766182123, 5138.152204886152, 5016.498350164954, 5152.76812543429, 5197.480251974772, 5204.21936709489, 5279.841604751864, 5171.379434467888, 5173.32746742921, 5177.249298851645, 5179.067767801756, 5193.143131383355, 5156.664816786897, 5165.535101840853, 5172.456892026358, 5175.275461435402, 5213.843584692389, 4248.511421186541, 4292.59220374064, 4294.677899157513, 4346.022145017346, 4365.869023929287, 3806.5812760596314, 3831.885043448701, 3833.616638336144, 3846.1346197105427, 3846.673032792244, 4429.955700443019, 4481.439820022497, 4481.910361792746, 4540.682152249311, 4543.273076307775, 5192.45479224676, 5192.714400707921, 5195.480451954774, 5205.401378841406, 5233.005728911495, 4402.5597440255715, 4425.137098265807, 4447.999200179998, 4448.043670610769, 4490.7754612269255, 4522.4346956630425, 4561.566651168134, 4600.5629465200755, 4621.745803980813, 4637.25803871379, 4873.293372460902, 4875.293082503088, 4875.853724388274, 4879.4876537963, 4937.851864444073, 5026.472220416804, 5132.43543210254, 5175.430702622633, 5198.532132108128, 5248.501392367719, 4848.296996935354, 4895.632827537877, 4903.730294833818, 4913.582345500672, 4922.827701030446, 5085.186370180753, 5085.745712714275, 5111.693298402108, 5130.050940576006, 5142.871428214325, 4981.402231732215, 5049.697018178921, 5151.6908985461, 5196.948030519647, 5202.985417843484, 4939.53074457926, 5025.773840177128, 5040.294358789699, 5048.823291184791, 5050.393952725696, 5015.322931404359, 5026.271190677258, 5038.093143234107, 5045.31888195089, 5087.3386459759995, 4793.18515852299, 4880.902381952412, 4923.458419573915, 4976.377952755905, 4990.975045124751, 5058.367704036996, 5080.415752188398, 5082.491750824888, 5085.974570127126, 5122.538971492583, 4973.52751488608, 4973.602111830973, 4976.2286845539, 4980.775865086152, 5013.298138260573], "value": 4733.810960408426, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.5077809251184, 610.2859654204583, 616.2112496005113, 620.6245221640908, 631.4884943196008, 42439.0, 43051.35422968654, 43234.0, 43293.35059974099, 47935.52064479354, 43937.56062439374, 44459.77770111145, 44496.0, 44512.55487445125, 44532.77733611336, 7947.801304967379, 8192.672293108268, 8195.46729462586, 8195.67217311307, 8199.877001844978, 7992.48048876824, 8075.313598344148, 8083.70660694289, 8086.433949623529, 8088.716894908679, 8101.5544145072, 8144.4706094103985, 8148.511089334629, 8155.34757219424, 8155.388345874054, 8053.0, 8098.109207987087, 8100.878486822702, 8104.149064348277, 8109.4323397362205, 7882.211778822129, 8038.758837234892, 8042.235987581171, 8044.1553636867875, 8048.436609437342, 41067.0, 41135.58864411347, 41210.38184427236, 41391.0, 41512.58487415117, 3251.6910893465083, 3413.3173365326784, 3483.9129021774406, 3716.479692843004, 3926.1269530860177, 31160.0, 31365.84317078423, 31593.0, 32128.0, 33072.50391244133, 3522.947155792665, 3531.4526248431534, 3534.169470174507, 3558.1460449492192, 3670.4861319415304, 266178.0, 266695.0, 267260.0, 267777.0, 268240.999999998, 49353.259701104515, 56597.43402566004, 57674.0, 57741.0, 57743.71128144332, 60017.69991150103, 61403.0, 61698.38301617016, 65963.0, 65963.67018164879, 15409.537713868602, 15587.844121558639, 15639.0, 15648.92175539115, 15650.9217453912, 9989.500524973722, 10850.348979061282, 11675.0, 11857.703557411134, 11881.40592970348, 17186.484405467854, 17282.913585432245, 17337.2198251079, 17411.303547857944, 17534.386296479563, 5799.318197722698, 5907.350191478934, 5944.7027648617395, 6041.516678665696, 6095.512359011268, 864.8616221404545, 906.954652267384, 962.1148543340004, 1015.9441230732233, 1076.316538997742, 28377.148685539465, 29100.41799164005, 29281.0, 29725.702742972728, 29776.0, 1014.2190513304862, 1027.6403258859339, 1049.1239814754765, 1055.3931489393628, 1097.3470784882982, 15299.388024478896, 15584.766228506582, 15609.843901560838, 15666.216689165496, 15914.965527240693, 413208.0, 416519.0, 416954.0, 417009.0, 419980.90105044516, 52152.21771673428, 52950.470495295325, 53040.73479632577, 53046.0, 53206.20190697143, 1143.9771204575861, 1186.6024881664566, 1256.208588589183, 1262.8863402293837, 1279.8720127987312, 29015.274618134714, 30228.546571801882, 30360.84819575888, 30445.391092178474, 30826.53760193554, 44736.32895506505, 45392.77303613461, 45450.0, 45492.0, 45522.0, 318870.0, 319626.0, 320219.0, 320363.0, 320699.0, 33096.834515827264, 38162.42756358712, 38261.42607860829, 38541.22917541577, 38759.0, 43712.82183802332, 44716.55283447124, 45204.0, 45706.54293457023, 45858.77070614626, 36433.0, 38258.0, 38502.22995540017, 38675.6132438672, 38800.41799373068, 37465.0, 37608.247835043694, 37798.62201377951, 38054.42918356171, 38453.4231986526, 33193.0, 34378.312433751686, 37866.8106659465, 38019.23961520809, 38219.61780382161, 33088.1727956803, 33260.50109248315, 34213.48679769756, 34427.0, 34548.0, 25510.0, 25685.0, 26022.73977260203, 26298.0, 26334.863734478535], "value": 18598.791765371192, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.8941806876006, 379.15637706103905, 382.2126419575674, 385.3987779064659, 405.60453557781165, 16276.186190690463, 16636.916815415905, 17625.735613965786, 17699.1150442478, 17709.82290177098, 16316.0, 16737.916310418463, 16886.0, 17069.743953840672, 17086.658266834656, 5113.642045056848, 5257.605679574028, 5261.316028916254, 5295.7881684732565, 5299.28459657947, 3659.7804131752046, 3904.39481880309, 5589.552835773149, 5921.319048309435, 5921.378255283176, 5840.591158618899, 5908.291005079396, 5914.497267732227, 5947.732352044147, 5956.940430595703, 5818.592698511085, 5920.141579470953, 5937.940620593782, 5982.3120341160675, 5991.2510936132985, 3323.88366407177, 3492.406290930548, 3502.0194345583022, 3525.365434221839, 4835.08133454646, 20485.692714609293, 21213.681794773092, 21323.57352852934, 21361.67957480639, 21370.679439808417, 1941.38846263428, 1977.3968939473455, 2026.1490174126754, 2234.3297010896667, 2445.29086564896, 12732.681682957907, 13361.799573006412, 13494.865051349558, 13582.524611638546, 13933.164010159422, 1732.5235560220915, 1815.4086159869198, 1837.8345948864667, 1845.492489565367, 1853.898035608041, 67676.3232367675, 67799.0, 68890.65554672194, 69504.65247673831, 70785.64607176982, 9697.757556061013, 10016.599336026624, 10058.346207496636, 10140.543675534618, 10270.794584108276, 13735.175889446666, 13858.584242472742, 13873.791893121612, 13875.306234688225, 13889.305534723426, 7600.8479830403085, 7605.429592780563, 7720.262940838379, 7765.611719414007, 7770.922290777133, 7296.197418283985, 7327.487075904742, 7341.375983041395, 7453.329200371993, 7478.551286922802, 6322.747090116344, 6445.613263204223, 6447.871042579216, 6482.708278127496, 6554.377334153248, 1641.7373220284696, 1759.7624320716538, 1811.80975997521, 1829.954251143732, 1846.6306738652054, 444.72871548355477, 924.3621900888365, 938.2119020023214, 949.4635530925143, 1023.4933707814679, 10039.397636141855, 11011.559537618567, 11015.83476247857, 11045.116390688725, 11261.493232804543, 414.4301585320185, 433.79177994562787, 448.9528599497071, 463.56656526148157, 464.9000464900063, 5849.7952571660635, 5871.530277577784, 5911.645301281937, 6020.909686354708, 6169.722362493699, 297579.0, 298002.0, 398931.0, 408362.0, 408739.0, 51099.0, 52287.0, 52663.473365266625, 65827.0, 79454.60272698599, 950.2398081534883, 996.6412091647002, 1011.231464087299, 1011.428542873289, 1017.4302390661253, 15843.049417035014, 15863.444779432664, 15933.20333983273, 16013.359465621244, 16398.672026559165, 27293.317667058483, 32059.35881282408, 32449.86425475097, 36358.0, 36991.44512832256, 50850.745746272514, 51874.740626296625, 53397.39807805617, 53510.197347039044, 54267.0, 7050.682719277543, 7255.746048888263, 8002.279794818494, 8016.879746803919, 8178.959105204436, 11676.299422034705, 12248.265104093784, 12332.07509436778, 12342.568010119961, 12642.2414655121, 10337.37975721459, 10450.790984180427, 10467.371957682564, 10678.0, 10741.624043158452, 10246.436445995243, 10665.46672666348, 10738.516766745359, 10794.08250298702, 10961.890381096087, 10286.948565257126, 10408.895911041096, 10586.89413105859, 10617.362958222531, 10697.304675196086, 7133.144022717307, 7265.818354541178, 7383.372413344711, 7402.481826272109, 7462.403007759258, 9176.090468497578, 9975.102240798364, 10328.483575821028, 10401.58393664245, 10664.146868250366], "value": 8000.951869990395, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1252.5929073051261, 1439.3163247457455, 1468.9853101468987, 1583.5407731757791, 1763.497403240076, 7636.121845987706, 7931.405144614156, 7934.56359900206, 8109.91890081099, 8117.675292988288, 7909.1299957004685, 8038.0, 8068.193180681928, 8087.272145506903, 8098.230668086537, 2280.315905228429, 2306.331163962449, 2309.480366917447, 2309.618912879373, 2309.8036666883336, 2275.5790178816887, 2327.0691723310642, 2329.5457385809773, 2335.182686059874, 2335.9532809343805, 2246.034205291724, 2252.3017864462067, 2275.1468199425217, 2293.9447853987103, 2298.5517824024323, 2218.134927378324, 2239.2274665240466, 2255.7180352455944, 2258.796708296253, 2260.638297872341, 2187.0267730859773, 2189.879556624385, 2192.8574642648337, 2193.5722534105857, 2195.2755590655047, 7855.921440785576, 7903.3282171015535, 7943.642536085832, 7969.0, 8120.918790812076, 4727.314539391803, 4853.757312134415, 4855.32025516428, 4860.756962151913, 4885.511448855122, 3007.428588568187, 3018.4264989651897, 3075.815451072943, 3180.6819318068237, 3183.602049743782, 983.9950800246024, 995.775950411159, 1042.7112488088649, 1048.6853943816825, 1067.9679609611724, 10795.190360722978, 10858.61994830185, 10907.672769816918, 10911.727206819814, 10919.672409827717, 4294.398784170218, 4372.213001659732, 4534.047849951481, 4544.318352247124, 4563.566461186182, 8669.913300867036, 8715.346349023848, 8732.0, 8753.64985400577, 8787.91212087871, 4761.309610106515, 4787.784549695272, 4790.976045119752, 4798.04039192163, 4818.4217893852965, 3665.138692407282, 4029.9395509067385, 4336.111097225062, 4415.536368681306, 4451.821927122944, 3550.840212190458, 3611.133328001261, 3670.504481894963, 3690.55713314404, 3735.75717578362, 1057.0539367266254, 1075.4730182210817, 1077.175960390302, 1082.929609575375, 1085.934843909368, 805.8066064144563, 814.5764202614725, 820.7004443378244, 822.2476434062766, 831.4553967151526, 5363.839084827461, 5524.3370795504, 5621.7189140542805, 5810.82567522975, 5814.76740930358, 874.4578361415963, 926.9582868770922, 930.4510338900081, 933.9579718912665, 935.1256575102166, 3762.1535154590547, 3796.354619714655, 3857.9228415431535, 3881.4177873319227, 3923.6076392360537, 9420.387674801115, 9541.188998935168, 9730.270229732798, 9752.756181095529, 9782.51087445625, 9586.808263834684, 9675.080867317594, 9684.806303874024, 9700.175485083846, 9719.611215551298, 7452.552846829208, 7508.662110205054, 7687.461877668621, 7689.884651730228, 7708.381239939564, 5541.612087153861, 5556.888862222652, 5618.438156184349, 5645.830625081254, 5962.761489540369, 8732.301415886841, 8769.12308769118, 9000.009998900247, 9030.729078127666, 9037.954810225907, 9005.954970225106, 9260.583273752563, 9288.164065233892, 9319.021502742386, 9359.391639543408, 6083.361247068994, 6243.313235544178, 6247.96876015617, 6387.393197646216, 6880.139982502187, 7350.0, 7609.086909570887, 7616.23837616234, 7618.961905190438, 7628.694852205849, 6744.730210791513, 6937.202221744564, 7190.0, 7198.136223653195, 7357.337839594462, 6582.605043697394, 6782.1861376635115, 6794.150731158606, 6899.0341352209725, 7081.0, 5887.470127688529, 6019.157317975574, 6029.969850150721, 6163.3528479509005, 6170.6914654268, 4291.120320334387, 4324.308110702273, 4346.217680817484, 4400.097979914174, 4404.647628189801, 4396.120775844908, 4430.224710675554, 4433.911321773482, 4447.799849006868, 4469.508354081114], "value": 4227.447757448565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.17338792119, 311.24833527032206, 314.94960806270996, 315.77895473168786, 317.7981981441785, 4778.784954677039, 4806.591439727627, 4808.447028591713, 4828.806847726091, 4837.564619184277, 4710.798746319692, 4721.858344249669, 4726.810927562893, 4741.90516189676, 4767.713937163764, 3579.9463008054836, 3662.6703596676293, 3686.115332320237, 3744.8876533704033, 3751.1559899022773, 3724.366857634195, 3727.3477141500266, 3731.3843215869347, 3747.681447077002, 3754.8122593870335, 3528.311979164064, 3709.684676802475, 3725.9068523286865, 3736.215394767102, 3737.8504859805576, 3587.497750314958, 3742.3263812513874, 3746.4380342948502, 3762.473253744478, 3777.112378591029, 3355.2953879685297, 3367.4275373186615, 3368.0569440556683, 3370.831458427069, 3377.7973321600784, 4581.190429780251, 4581.335706322598, 4587.42657167854, 4606.838760643362, 4613.700109492872, 4222.282212023963, 4337.0, 4353.194658988097, 4354.7604881731495, 4369.541198174178, 4982.508745627187, 4983.202687570008, 4996.1756310208775, 5034.395872495324, 5037.974810125926, 3632.527771389729, 3646.08847788053, 3646.851241858806, 3690.335739566877, 3705.7961812100325, 4940.357753492023, 5156.071907056766, 5234.8691282717855, 5238.0, 5274.709890955995, 3744.6068162843053, 3768.7361884668358, 3777.905552361213, 3780.79014715294, 3783.5838057813617, 3816.4084566892175, 3820.8280627371837, 3841.865534706272, 3842.865499707497, 3845.9230815383535, 4286.764227967492, 4293.74237545742, 4300.333448315516, 4304.203722311382, 4309.116631090682, 4718.811247550128, 4985.825496107619, 4990.925136122961, 4995.17579599362, 5008.223725322581, 1268.8516892212563, 1279.6161151654467, 1284.0305569295308, 1286.6718986658343, 1286.8005459153846, 825.82732519822, 830.339879795564, 864.7967727584013, 886.5301390263171, 890.3455959869491, 4467.324753217544, 4468.932966005513, 4474.530174331713, 4484.865454036384, 4489.393931819228, 5062.797488100435, 5098.770555325019, 5123.641345105882, 5129.153689641242, 5148.459411761786, 4230.61924426803, 4429.180601588717, 4433.8226470941445, 4449.755263460541, 4478.753668548261, 2708.8352008636475, 2720.605512200759, 2727.481778462086, 2736.3569561153113, 2745.3136715821047, 4812.855614331575, 4827.272590986082, 4833.154198015333, 4846.951530484721, 4856.927146092811, 5025.221090730943, 5197.194434862602, 5202.0, 5210.374755029421, 5226.5626952588345, 4790.928136077961, 4816.99999999993, 4825.469198388175, 4837.201861692852, 4840.1287768200655, 5077.669951453144, 5084.161113416245, 5091.541761241506, 5115.974420127875, 5133.409657889241, 4741.336212930261, 4925.778339974638, 4969.503049695001, 5001.449840517613, 5003.399592048978, 4752.52474752522, 4756.788093536522, 4782.856514304576, 4784.928226076542, 4794.832180873653, 3643.854245830137, 3645.1069487975615, 3651.5983241843905, 3659.505966694462, 3665.376885929452, 4905.2151655734915, 5048.141815891234, 5055.559165637776, 5060.3927528696795, 5080.542751152414, 4834.510227233048, 4910.582600478856, 4930.644072879987, 4930.778114984906, 4935.210366341368, 4870.683405578626, 4887.706737595756, 4893.143699852582, 4899.632527560519, 4907.435644900882, 4574.588287054181, 4836.733979631154, 4840.782164802663, 4842.297866809364, 4845.2732090186755, 4897.706137631753, 4899.828506002415, 4948.183549714401, 4951.282064100613, 4973.328600638867, 4806.47128815823, 4855.732934688626, 4863.638181309239, 4867.902641947212, 4876.805182730253], "value": 3774.8066823159875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.4740586777525, 739.1536690489388, 759.1876691939624, 775.1357236681102, 787.126289818302, 12738.745225095492, 13030.674233144176, 13226.0, 13300.534481293156, 13354.0, 12728.427220775064, 12733.363331833418, 12764.23414595125, 12794.232346059243, 12841.0, 671.0873212431089, 831.8876951611546, 832.6169961817541, 853.108501615813, 854.6111519258753, 849.3078141314809, 855.7475544714324, 856.1353033436212, 856.7686724584358, 857.087202129733, 892.7277180459957, 894.6242578117174, 896.9641214351418, 897.0625696147509, 898.137787723786, 697.0972590495321, 861.1087524412203, 886.1537231943479, 886.8182022685367, 886.8359353519555, 655.2268323378412, 655.367570294668, 655.4134050025201, 656.61587971037, 657.1391477164882, 12934.223946563143, 12950.222986620833, 12967.740645187137, 12975.416106275146, 12983.09118361715, 11264.38045907483, 12035.037197024218, 12779.872201277962, 12905.709429057018, 13082.672933176651, 10919.12646988239, 10942.343459392461, 10991.450427478594, 11132.777344453065, 11150.107991360754, 4072.8981775455554, 4135.503739551243, 4202.306619407825, 4231.280682284019, 4271.700980931336, 46704.0, 46896.29655555135, 46903.0, 46950.0, 47097.76451117757, 9126.45241285525, 9152.31357648179, 9265.0, 9274.0, 9612.99063598326, 16128.112953787655, 16280.10459424743, 16285.430009949594, 16290.51128466148, 16305.429309974332, 6714.731410743613, 6816.079829222991, 7002.089728335284, 7013.859722805515, 7024.473164512682, 3723.2181241938956, 3836.5204349456317, 3863.32391831434, 3881.107345310594, 3918.0596656802163, 2772.7227277272514, 2849.7292757188034, 2854.7716182705335, 2862.613547171147, 2869.4404591104953, 520.5627273090622, 539.4578448659073, 562.7580140539608, 568.9601727879092, 587.7120211096532, 513.9203423469369, 569.9344575373885, 580.692233116449, 593.519249407981, 598.9431004054695, 7290.927090729025, 7575.267398975758, 7588.241175882478, 7740.0, 7744.0, 500.33955179163155, 579.0410999791064, 586.3696526234315, 595.1043679262682, 614.27822308787, 3754.6433088856515, 3785.6782173515007, 3827.4258861170492, 3864.71014673895, 3895.610438956082, 49613.0, 51679.0, 51949.22076168861, 57403.13895291574, 58159.12761308584, 51132.0, 51396.48603513917, 51521.7423912878, 51700.48299517032, 51836.22245666318, 47069.29396058993, 47946.5205347942, 48287.75856120767, 48561.75719121452, 49131.5086849127, 8509.319254459504, 8523.95738021306, 8573.442726222776, 8602.78493037679, 8758.12418758119, 31621.683783161872, 32198.517022244218, 32432.0, 32621.18447038843, 33325.83337083299, 37506.81246593859, 39288.803555982035, 40955.795221023705, 41119.0, 42033.36949945692, 10971.670849874517, 11481.010448617308, 11483.311001339946, 11502.597409090977, 11724.296542207494, 11763.941180294043, 12005.77658893434, 12017.63947081554, 12138.446700406894, 12486.875131248935, 9851.16265117473, 9919.504024798587, 10106.797864042825, 10305.74235644115, 11263.324200547993, 10779.407132607468, 10890.509927053143, 11461.197716159788, 11541.884581154081, 11878.584249551224, 10405.375677459377, 10793.244472886823, 11290.548378065114, 11578.189526733384, 11780.057595392176, 9140.908590914005, 9219.953900230455, 9433.103855133751, 9859.95070024674, 9905.207583393167, 8863.778405539913, 8914.777130571787, 9429.811403772022, 9637.807243854942, 9715.70852874415], "value": 6127.862623677542, "unit": "score"}], "name": "dromaeo_css", "value": 6402.678866683782, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 130.0, 156.0, 206.0, 130.0, 150.0, 161.0, 131.0, 181.0, 130.0], "value": 153.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 163.0, 163.0, 163.0, 162.0, 161.0, 160.0, 163.0, 172.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 152.0, 76.0, 74.0, 77.0, 74.0, 141.0, 74.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 149.0, 94.0, 94.0, 93.0, 121.0, 93.0, 93.0, 131.0], "value": 105.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 63.0, 66.0, 63.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 81.0, 81.0, 82.0, 82.0, 88.0, 85.0, 141.0, 88.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 127.0, 126.0, 134.0, 127.0, 125.0, 128.0, 146.0, 131.0], "value": 130.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 173.0, 172.0, 172.0, 171.0, 171.0, 171.0, 173.0, 171.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 66.0, 65.0, 67.0, 65.0, 65.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1567.1000000000001, "alertThreshold": 2.0}]} 09:33:15 INFO - Return code: 0 09:33:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:33:15 ERROR - # TBPL SUCCESS # 09:33:15 INFO - Running post-action listener: _resource_record_post_action 09:33:15 INFO - ##### 09:33:15 INFO - ##### Finished run-tests step (success) 09:33:15 INFO - ##### 09:33:15 INFO - Running post-run listener: _resource_record_post_run 09:33:15 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 531447808; Read time: 0; Write time: 397024 09:33:15 INFO - TinderboxPrint: CPU usage
13.1% 09:33:15 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:33:15 INFO - TinderboxPrint: I/O write bytes / time
531,447,808 / 397,024 09:33:15 INFO - TinderboxPrint: CPU idle
8,330.0 (86.9%) 09:33:15 INFO - TinderboxPrint: CPU user
1,212.9 (12.7%) 09:33:15 INFO - TinderboxPrint: Swap in / out
0 / 0 09:33:15 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 236937216; Read time: 0; Write time: 190560 09:33:15 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 294510592; Read time: 0; Write time: 206464 09:33:16 INFO - Running post-run listener: _upload_blobber_files 09:33:16 INFO - Blob upload gear active. 09:33:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:33:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:33:16 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:33:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:20 INFO - (blobuploader) - INFO - Done attempting. 09:33:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:33:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:33:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:21 INFO - (blobuploader) - INFO - Done attempting. 09:33:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:33:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:33:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:22 INFO - (blobuploader) - INFO - Done attempting. 09:33:22 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:22 INFO - Return code: 0 09:33:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3b55e085b337ef0a04b9164137d1929ba29bd13e917f457703cd655dc756d500c38d657f189c9b11c72847cc1d4000af3e57551b86cd63fc386e0822bfd50965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3db943530d6bfd2ee893488564e49043316d27cbd4381e537ec77848ba2a749e080eb1b15c3de0f529040c104371193c0a7778d21f8b247a62ac0fdbb8188204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/54621737dcb3e1a846f48d2df108157ba00a7bcbb629fd1278fa97b32ca7d16dca071a9ae1d12ad175079356f2e049626c8c50ac0970c175e20312bd50eed3e6"} 09:33:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:22 INFO - Contents: 09:33:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3b55e085b337ef0a04b9164137d1929ba29bd13e917f457703cd655dc756d500c38d657f189c9b11c72847cc1d4000af3e57551b86cd63fc386e0822bfd50965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3db943530d6bfd2ee893488564e49043316d27cbd4381e537ec77848ba2a749e080eb1b15c3de0f529040c104371193c0a7778d21f8b247a62ac0fdbb8188204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/54621737dcb3e1a846f48d2df108157ba00a7bcbb629fd1278fa97b32ca7d16dca071a9ae1d12ad175079356f2e049626c8c50ac0970c175e20312bd50eed3e6"} 09:33:22 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:22 INFO - Copying logs to upload dir... 09:33:22 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.588184 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-05-19 09:33:21.967947) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 09:33:21.971045) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564 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/3b55e085b337ef0a04b9164137d1929ba29bd13e917f457703cd655dc756d500c38d657f189c9b11c72847cc1d4000af3e57551b86cd63fc386e0822bfd50965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3db943530d6bfd2ee893488564e49043316d27cbd4381e537ec77848ba2a749e080eb1b15c3de0f529040c104371193c0a7778d21f8b247a62ac0fdbb8188204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/54621737dcb3e1a846f48d2df108157ba00a7bcbb629fd1278fa97b32ca7d16dca071a9ae1d12ad175079356f2e049626c8c50ac0970c175e20312bd50eed3e6"} build_url:https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011371 build_url: 'https://queue.taskcluster.net/v1/task/Mi_t4JhgStK9kXdJf3lKWA/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/3b55e085b337ef0a04b9164137d1929ba29bd13e917f457703cd655dc756d500c38d657f189c9b11c72847cc1d4000af3e57551b86cd63fc386e0822bfd50965", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/3db943530d6bfd2ee893488564e49043316d27cbd4381e537ec77848ba2a749e080eb1b15c3de0f529040c104371193c0a7778d21f8b247a62ac0fdbb8188204", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/54621737dcb3e1a846f48d2df108157ba00a7bcbb629fd1278fa97b32ca7d16dca071a9ae1d12ad175079356f2e049626c8c50ac0970c175e20312bd50eed3e6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 09:33:21.998823) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:33:21.999200) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1463674358.246130-870698564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 09:33:22.083746) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 09:33:22.084077) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 09:33:22.084369) ========= ========= Total master_lag: 0.17 =========