builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1463552673.07 results: success (0) buildid: 20160517203031 builduid: f2a1465055b5422fb52729c005890790 revision: d81b1964bb6b4ae973597d3fafa71dd962f2040b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.070382) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.070827) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.071272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.091237) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.091772) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 23:24:33-- 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% 214M=0s 2016-05-17 23:24:33 (214 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146391 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.255467) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.256201) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014132 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:33.313125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 23:24:33.313863) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d81b1964bb6b4ae973597d3fafa71dd962f2040b --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 d81b1964bb6b4ae973597d3fafa71dd962f2040b --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=d6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 23:24:33,956 truncating revision to first 12 chars 2016-05-17 23:24:33,957 Setting DEBUG logging. 2016-05-17 23:24:33,957 attempt 1/10 2016-05-17 23:24:33,957 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d81b1964bb6b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 23:24:34,998 unpacking tar archive at: fx-team-d81b1964bb6b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.327527 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 23:24:34.657960) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:34.658322) ========= script_repo_revision: d81b1964bb6b4ae973597d3fafa71dd962f2040b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:34.658826) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:34.659488) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 23:24:34.669432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-05-17 23:24:34.669822) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:24:35 INFO - MultiFileLogger online at 20160517 23:24:35 in /builds/slave/test 23:24:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:24:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:24:35 INFO - {'append_to_log': False, 23:24:35 INFO - 'base_work_dir': '/builds/slave/test', 23:24:35 INFO - 'blob_upload_branch': 'Fx-Team', 23:24:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:24:35 INFO - 'branch': 'Fx-Team', 23:24:35 INFO - 'buildbot_json_path': 'buildprops.json', 23:24:35 INFO - 'config_files': ('talos/linux_config.py',), 23:24:35 INFO - 'default_actions': ('clobber', 23:24:35 INFO - 'read-buildbot-config', 23:24:35 INFO - 'download-and-extract', 23:24:35 INFO - 'populate-webroot', 23:24:35 INFO - 'create-virtualenv', 23:24:35 INFO - 'install', 23:24:35 INFO - 'run-tests'), 23:24:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:24:35 INFO - 'download_minidump_stackwalk': True, 23:24:35 INFO - 'download_symbols': 'ondemand', 23:24:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:24:35 INFO - 'tooltool.py': '/tools/tooltool.py', 23:24:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:24:35 INFO - '/tools/misc-python/virtualenv.py')}, 23:24:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:24:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:24:35 INFO - 'installer_path': 'installer.exe', 23:24:35 INFO - 'log_level': 'info', 23:24:35 INFO - 'log_name': 'talos', 23:24:35 INFO - 'log_to_console': True, 23:24:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:24:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:24:35 INFO - 'opt_config_files': (), 23:24:35 INFO - 'pip_index': False, 23:24:35 INFO - 'sps_profile': False, 23:24:35 INFO - 'sps_profile_interval': 0, 23:24:35 INFO - 'suite': 'dromaeojs', 23:24:35 INFO - 'system_bits': '32', 23:24:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:24:35 INFO - 'title': 'talos-linux64-ix-034', 23:24:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:24:35 INFO - 'use_talos_json': True, 23:24:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:24:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:24:35 INFO - 'work_dir': 'build'} 23:24:35 INFO - ##### 23:24:35 INFO - ##### Running clobber step. 23:24:35 INFO - ##### 23:24:35 INFO - Running pre-action listener: _resource_record_pre_action 23:24:35 INFO - Running main action method: clobber 23:24:35 INFO - rmtree: /builds/slave/test/build 23:24:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:24:35 INFO - Running post-action listener: _resource_record_post_action 23:24:35 INFO - ##### 23:24:35 INFO - ##### Finished clobber step (success) 23:24:35 INFO - ##### 23:24:35 INFO - ##### 23:24:35 INFO - ##### Running read-buildbot-config step. 23:24:35 INFO - ##### 23:24:35 INFO - Running pre-action listener: _resource_record_pre_action 23:24:35 INFO - Running main action method: read_buildbot_config 23:24:35 INFO - Using buildbot properties: 23:24:35 INFO - { 23:24:35 INFO - "project": "", 23:24:35 INFO - "product": "firefox", 23:24:35 INFO - "script_repo_revision": "production", 23:24:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:24:35 INFO - "repository": "", 23:24:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:24:35 INFO - "buildid": "20160517203031", 23:24:35 INFO - "pgo_build": "True", 23:24:35 INFO - "basedir": "/builds/slave/test", 23:24:35 INFO - "buildnumber": 694, 23:24:35 INFO - "slavename": "talos-linux64-ix-034", 23:24:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:24:35 INFO - "platform": "ubuntu64_hw", 23:24:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:24:35 INFO - "branch": "fx-team", 23:24:35 INFO - "repo_path": "integration/fx-team", 23:24:35 INFO - "revision": "d81b1964bb6b4ae973597d3fafa71dd962f2040b", 23:24:35 INFO - "stage_platform": "linux64-pgo", 23:24:35 INFO - "builduid": "f2a1465055b5422fb52729c005890790", 23:24:35 INFO - "slavebuilddir": "test" 23:24:35 INFO - } 23:24:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:24:35 INFO - Running post-action listener: _resource_record_post_action 23:24:35 INFO - ##### 23:24:35 INFO - ##### Finished read-buildbot-config step (success) 23:24:35 INFO - ##### 23:24:35 INFO - ##### 23:24:35 INFO - ##### Running download-and-extract step. 23:24:35 INFO - ##### 23:24:35 INFO - Running pre-action listener: _resource_record_pre_action 23:24:35 INFO - Running main action method: download_and_extract 23:24:35 INFO - mkdir: /builds/slave/test/build/tests 23:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:35 INFO - https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:38 INFO - Downloaded 1361 bytes. 23:24:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:24:38 INFO - Using the following test package requirements: 23:24:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:24:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:24:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:24:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'jsshell-linux-x86_64.zip'], 23:24:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:24:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:24:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:24:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:24:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:24:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:24:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:24:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:38 INFO - https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:24:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:24:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:24:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:39 INFO - Downloaded 24063714 bytes. 23:24:39 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'] 23:24:39 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 23:24:41 INFO - Return code: 0 23:24:41 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 23:24: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')]} 23:24:41 INFO - https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:24:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:24:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:24:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:42 INFO - Downloaded 24063714 bytes. 23:24: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'] 23:24: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 23:24:44 INFO - Return code: 0 23:24:44 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')]} 23:24:44 INFO - https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:24:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:24:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:24:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:45 INFO - Downloaded 10840194 bytes. 23:24:45 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'] 23:24:45 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 23:24:45 INFO - Return code: 0 23:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:45 INFO - https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:24:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:24:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:24:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eq8WVKzNTlyPA-bmWDSolA/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 23:24:46 INFO - Downloaded 58381028 bytes. 23:24:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:24:46 INFO - mkdir: /builds/slave/test/properties 23:24:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:24:46 INFO - Writing to file /builds/slave/test/properties/build_url 23:24:46 INFO - Contents: 23:24:46 INFO - build_url:https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:24:47 INFO - Running post-action listener: _resource_record_post_action 23:24:47 INFO - Running post-action listener: set_extra_try_arguments 23:24:47 INFO - ##### 23:24:47 INFO - ##### Finished download-and-extract step (success) 23:24:47 INFO - ##### 23:24:47 INFO - ##### 23:24:47 INFO - ##### Running populate-webroot step. 23:24:47 INFO - ##### 23:24:47 INFO - Running pre-action listener: _resource_record_pre_action 23:24:47 INFO - Running main action method: populate_webroot 23:24:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:24:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:24:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:24:47 INFO - '--tppagecycles', 23:24:47 INFO - '7'], 23:24:47 INFO - 'tests': ['tsvgm']}}, 23:24:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:24:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:24:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:24:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:24:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:24:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:24:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['damp', 'tps']}, 23:24:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'tests': ['damp', 'tps']}, 23:24:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['dromaeo_dom']}, 23:24:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:24:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['a11yr', 23:24:47 INFO - 'ts_paint', 23:24:47 INFO - 'tpaint', 23:24:47 INFO - 'sessionrestore', 23:24:47 INFO - 'sessionrestore_no_auto_restore', 23:24:47 INFO - 'tabpaint']}, 23:24:47 INFO - 'other-e10s': {'tests': ['a11yr', 23:24:47 INFO - 'ts_paint', 23:24:47 INFO - 'tpaint', 23:24:47 INFO - 'sessionrestore', 23:24:47 INFO - 'sessionrestore_no_auto_restore', 23:24:47 INFO - 'tabpaint']}, 23:24:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:24:47 INFO - 'ts_paint', 23:24:47 INFO - 'tpaint', 23:24:47 INFO - 'sessionrestore', 23:24:47 INFO - 'sessionrestore_no_auto_restore', 23:24:47 INFO - 'tabpaint']}, 23:24:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:24:47 INFO - 'ts_paint', 23:24:47 INFO - 'tpaint', 23:24:47 INFO - 'sessionrestore', 23:24:47 INFO - 'sessionrestore_no_auto_restore', 23:24:47 INFO - 'tabpaint']}, 23:24:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['a11yr', 23:24:47 INFO - 'ts_paint', 23:24:47 INFO - 'tpaint', 23:24:47 INFO - 'sessionrestore', 23:24:47 INFO - 'sessionrestore_no_auto_restore', 23:24:47 INFO - 'tabpaint']}, 23:24:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['a11yr', 23:24:47 INFO - 'ts_paint', 23:24:47 INFO - 'tpaint', 23:24:47 INFO - 'sessionrestore', 23:24:47 INFO - 'sessionrestore_no_auto_restore', 23:24:47 INFO - 'tabpaint']}, 23:24:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['tsvgx', 23:24:47 INFO - 'tsvgr_opacity', 23:24:47 INFO - 'tart', 23:24:47 INFO - 'tscrollx', 23:24:47 INFO - 'cart']}, 23:24:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:24:47 INFO - 'tsvgr_opacity', 23:24:47 INFO - 'tart', 23:24:47 INFO - 'tscrollx', 23:24:47 INFO - 'cart']}, 23:24:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'talos_options': ['--disable-e10s'], 23:24:47 INFO - 'tests': ['tp5o']}, 23:24:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'tests': ['tp5o']}, 23:24:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'talos_options': ['--disable-e10s', 23:24:47 INFO - '--xperf_path', 23:24:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:24:47 INFO - 'tests': ['tp5n']}, 23:24:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:24:47 INFO - 'talos_options': ['--xperf_path', 23:24:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:24:47 INFO - 'tests': ['tp5n']}}, 23:24:47 INFO - 'talos.zip': {'path': '', 23:24:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:24:47 INFO - Running post-action listener: _resource_record_post_action 23:24:47 INFO - ##### 23:24:47 INFO - ##### Finished populate-webroot step (success) 23:24:47 INFO - ##### 23:24:47 INFO - ##### 23:24:47 INFO - ##### Running create-virtualenv step. 23:24:47 INFO - ##### 23:24:47 INFO - Running pre-action listener: _resource_record_pre_action 23:24:47 INFO - Running main action method: create_virtualenv 23:24:47 INFO - Creating virtualenv /builds/slave/test/build/venv 23:24:47 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 23:24:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:24:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:24:47 INFO - Using real prefix '/usr' 23:24:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:24:48 INFO - Installing distribute.............................................................................................................................................................................................done. 23:24:50 INFO - Installing pip.................done. 23:24:50 INFO - Return code: 0 23:24:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:24:50 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')]} 23:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:50 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')]} 23:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:50 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 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:24:50 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 23:24:50 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 23:24:50 INFO - Using env: {'DISPLAY': ':0', 23:24:50 INFO - 'HOME': '/home/cltbld', 23:24:50 INFO - 'LANG': 'en_US.UTF-8', 23:24:50 INFO - 'LANGUAGE': 'en_US:en', 23:24:50 INFO - 'LOGNAME': 'cltbld', 23:24:50 INFO - 'MAIL': '/var/mail/cltbld', 23:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:50 INFO - 'MOZ_NO_REMOTE': '1', 23:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:24:50 INFO - 'NO_EM_RESTART': '1', 23:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:50 INFO - 'PWD': '/builds/slave/test', 23:24:50 INFO - 'SHELL': '/bin/bash', 23:24:50 INFO - 'SHLVL': '1', 23:24:50 INFO - 'TERM': 'linux', 23:24:50 INFO - 'TMOUT': '86400', 23:24:50 INFO - 'USER': 'cltbld', 23:24:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:50 INFO - '_': '/tools/buildbot/bin/python'} 23:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:50 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 23:24:50 INFO - Cleaning up... 23:24:50 INFO - Return code: 0 23:24:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:24:50 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')]} 23:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:50 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')]} 23:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:50 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 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:24:50 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 23:24:50 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 23:24:50 INFO - Using env: {'DISPLAY': ':0', 23:24:50 INFO - 'HOME': '/home/cltbld', 23:24:50 INFO - 'LANG': 'en_US.UTF-8', 23:24:50 INFO - 'LANGUAGE': 'en_US:en', 23:24:50 INFO - 'LOGNAME': 'cltbld', 23:24:50 INFO - 'MAIL': '/var/mail/cltbld', 23:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:50 INFO - 'MOZ_NO_REMOTE': '1', 23:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:24:50 INFO - 'NO_EM_RESTART': '1', 23:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:50 INFO - 'PWD': '/builds/slave/test', 23:24:50 INFO - 'SHELL': '/bin/bash', 23:24:50 INFO - 'SHLVL': '1', 23:24:50 INFO - 'TERM': 'linux', 23:24:50 INFO - 'TMOUT': '86400', 23:24:50 INFO - 'USER': 'cltbld', 23:24:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:50 INFO - '_': '/tools/buildbot/bin/python'} 23:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:50 INFO - Downloading/unpacking psutil>=3.1.1 23:24:50 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 23:24:50 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 23:24:50 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 23:24:50 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 23:24:50 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 23:24:50 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 23:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:24:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:24:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:24:52 INFO - Installing collected packages: psutil 23:24:52 INFO - Running setup.py install for psutil 23:24:52 INFO - building 'psutil._psutil_linux' extension 23:24:52 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 23:24:52 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 23:24:52 INFO - building 'psutil._psutil_posix' extension 23:24:52 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 23:24:52 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 23:24:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:24:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:24:53 INFO - Successfully installed psutil 23:24:53 INFO - Cleaning up... 23:24:53 INFO - Return code: 0 23:24:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:24:53 INFO - Using env: {'DISPLAY': ':0', 23:24:53 INFO - 'HOME': '/home/cltbld', 23:24:53 INFO - 'LANG': 'en_US.UTF-8', 23:24:53 INFO - 'LANGUAGE': 'en_US:en', 23:24:53 INFO - 'LOGNAME': 'cltbld', 23:24:53 INFO - 'MAIL': '/var/mail/cltbld', 23:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:53 INFO - 'MOZ_NO_REMOTE': '1', 23:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:24:53 INFO - 'NO_EM_RESTART': '1', 23:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:53 INFO - 'PWD': '/builds/slave/test', 23:24:53 INFO - 'SHELL': '/bin/bash', 23:24:53 INFO - 'SHLVL': '1', 23:24:53 INFO - 'TERM': 'linux', 23:24:53 INFO - 'TMOUT': '86400', 23:24:53 INFO - 'USER': 'cltbld', 23:24:53 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:53 INFO - '_': '/tools/buildbot/bin/python'} 23:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:24:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:24:55 INFO - Installing collected packages: mozsystemmonitor 23:24:55 INFO - Running setup.py install for mozsystemmonitor 23:24:55 INFO - Successfully installed mozsystemmonitor 23:24:55 INFO - Cleaning up... 23:24:55 INFO - Return code: 0 23:24:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:24: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')]} 23:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24: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')]} 23:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24: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', '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 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:24: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:24: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 blobuploader==1.2.4 23:24:55 INFO - Using env: {'DISPLAY': ':0', 23:24:55 INFO - 'HOME': '/home/cltbld', 23:24:55 INFO - 'LANG': 'en_US.UTF-8', 23:24:55 INFO - 'LANGUAGE': 'en_US:en', 23:24:55 INFO - 'LOGNAME': 'cltbld', 23:24:55 INFO - 'MAIL': '/var/mail/cltbld', 23:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:55 INFO - 'MOZ_NO_REMOTE': '1', 23:24:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:24:55 INFO - 'NO_EM_RESTART': '1', 23:24:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:55 INFO - 'PWD': '/builds/slave/test', 23:24:55 INFO - 'SHELL': '/bin/bash', 23:24:55 INFO - 'SHLVL': '1', 23:24:55 INFO - 'TERM': 'linux', 23:24:55 INFO - 'TMOUT': '86400', 23:24:55 INFO - 'USER': 'cltbld', 23:24:55 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:55 INFO - '_': '/tools/buildbot/bin/python'} 23:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:55 INFO - Downloading/unpacking blobuploader==1.2.4 23:24: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 23:24: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 23:24: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 23:24: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 23:24: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 23:24: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 23:24:57 INFO - Downloading blobuploader-1.2.4.tar.gz 23:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:24:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:24: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 23:24: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 23:24: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 23:24: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 23:24: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 23:24: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 23:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:24:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:24: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 23:24: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 23:24: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 23:24: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 23:24: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 23:24: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 23:24:58 INFO - Downloading docopt-0.6.1.tar.gz 23:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:24:58 INFO - Installing collected packages: blobuploader, requests, docopt 23:24:58 INFO - Running setup.py install for blobuploader 23:24:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:24:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:24:58 INFO - Running setup.py install for requests 23:24:58 INFO - Running setup.py install for docopt 23:24:58 INFO - Successfully installed blobuploader requests docopt 23:24:58 INFO - Cleaning up... 23:24:58 INFO - Return code: 0 23:24:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:58 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 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:24:58 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 23:24:58 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 23:24:58 INFO - Using env: {'DISPLAY': ':0', 23:24:58 INFO - 'HOME': '/home/cltbld', 23:24:58 INFO - 'LANG': 'en_US.UTF-8', 23:24:58 INFO - 'LANGUAGE': 'en_US:en', 23:24:58 INFO - 'LOGNAME': 'cltbld', 23:24:58 INFO - 'MAIL': '/var/mail/cltbld', 23:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:58 INFO - 'MOZ_NO_REMOTE': '1', 23:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:24:58 INFO - 'NO_EM_RESTART': '1', 23:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:58 INFO - 'PWD': '/builds/slave/test', 23:24:58 INFO - 'SHELL': '/bin/bash', 23:24:58 INFO - 'SHLVL': '1', 23:24:58 INFO - 'TERM': 'linux', 23:24:58 INFO - 'TMOUT': '86400', 23:24:58 INFO - 'USER': 'cltbld', 23:24:58 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:58 INFO - '_': '/tools/buildbot/bin/python'} 23:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:24:59 INFO - Running setup.py (path:/tmp/pip-AMyGnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:24:59 INFO - Running setup.py (path:/tmp/pip-yWgAi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:24:59 INFO - Running setup.py (path:/tmp/pip-Arf19V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:24:59 INFO - Running setup.py (path:/tmp/pip-HJGaQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:24:59 INFO - Running setup.py (path:/tmp/pip-e9m5NB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:24:59 INFO - Running setup.py (path:/tmp/pip-6F8Ev0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:24:59 INFO - Running setup.py (path:/tmp/pip-4tBAU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:24:59 INFO - Running setup.py (path:/tmp/pip-Z1ZPwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:24:59 INFO - Running setup.py (path:/tmp/pip-3pPaKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:24:59 INFO - Running setup.py (path:/tmp/pip-ajUZFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:24:59 INFO - Running setup.py (path:/tmp/pip-iD46Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:24:59 INFO - Running setup.py (path:/tmp/pip-n6MvFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:24:59 INFO - Running setup.py (path:/tmp/pip-o8_sK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:25:00 INFO - Running setup.py (path:/tmp/pip-Iv2u9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:25:00 INFO - Running setup.py (path:/tmp/pip-2mRVmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:25:00 INFO - Running setup.py (path:/tmp/pip-egNjqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:25:00 INFO - Running setup.py (path:/tmp/pip-LSrt7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:25:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:25:00 INFO - Running setup.py install for manifestparser 23:25:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:25:00 INFO - Running setup.py install for mozcrash 23:25:00 INFO - Running setup.py install for mozdebug 23:25:00 INFO - Running setup.py install for mozdevice 23:25:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:25:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:25:00 INFO - Running setup.py install for mozfile 23:25:00 INFO - Running setup.py install for mozhttpd 23:25:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:25:00 INFO - Running setup.py install for mozinfo 23:25:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Running setup.py install for mozInstall 23:25:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Running setup.py install for mozleak 23:25:01 INFO - Running setup.py install for mozlog 23:25:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Running setup.py install for moznetwork 23:25:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Running setup.py install for mozprocess 23:25:01 INFO - Running setup.py install for mozprofile 23:25:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Running setup.py install for mozrunner 23:25:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:25:01 INFO - Running setup.py install for mozscreenshot 23:25:01 INFO - Running setup.py install for moztest 23:25:01 INFO - Running setup.py install for mozversion 23:25:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:25:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:25:02 INFO - Cleaning up... 23:25:02 INFO - Return code: 0 23:25:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25:02 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 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:25:02 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 23:25:02 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 23:25:02 INFO - Using env: {'DISPLAY': ':0', 23:25:02 INFO - 'HOME': '/home/cltbld', 23:25:02 INFO - 'LANG': 'en_US.UTF-8', 23:25:02 INFO - 'LANGUAGE': 'en_US:en', 23:25:02 INFO - 'LOGNAME': 'cltbld', 23:25:02 INFO - 'MAIL': '/var/mail/cltbld', 23:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:25:02 INFO - 'MOZ_NO_REMOTE': '1', 23:25:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:25:02 INFO - 'NO_EM_RESTART': '1', 23:25:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:25:02 INFO - 'PWD': '/builds/slave/test', 23:25:02 INFO - 'SHELL': '/bin/bash', 23:25:02 INFO - 'SHLVL': '1', 23:25:02 INFO - 'TERM': 'linux', 23:25:02 INFO - 'TMOUT': '86400', 23:25:02 INFO - 'USER': 'cltbld', 23:25:02 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:25:02 INFO - '_': '/tools/buildbot/bin/python'} 23:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:25:02 INFO - Running setup.py (path:/tmp/pip-KyRZE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:25:02 INFO - Running setup.py (path:/tmp/pip-X5nlro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:25:02 INFO - Running setup.py (path:/tmp/pip-EKA2vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:25:02 INFO - Running setup.py (path:/tmp/pip-ARcVyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:25:02 INFO - Running setup.py (path:/tmp/pip-sRNzqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:25:02 INFO - Running setup.py (path:/tmp/pip-qDTDWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:25:02 INFO - Running setup.py (path:/tmp/pip-W3HPc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:25:02 INFO - Running setup.py (path:/tmp/pip-KoQvfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:25:02 INFO - Running setup.py (path:/tmp/pip-B5W9tF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:25:02 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)) 23:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:25:02 INFO - Running setup.py (path:/tmp/pip-j5lu4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:25:03 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)) 23:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:25:03 INFO - Running setup.py (path:/tmp/pip-Te0FIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:25:03 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)) 23:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:25:03 INFO - Running setup.py (path:/tmp/pip-HjHqpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:25:03 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)) 23:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:25:03 INFO - Running setup.py (path:/tmp/pip-l1nLb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:25:03 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)) 23:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:25:03 INFO - Running setup.py (path:/tmp/pip-odNAZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:25:03 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)) 23:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:25:03 INFO - Running setup.py (path:/tmp/pip-A8E04G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:25:03 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)) 23:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:25:03 INFO - Running setup.py (path:/tmp/pip-q5Bin7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:25:03 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)) 23:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:25:03 INFO - Running setup.py (path:/tmp/pip-veKmWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:25:03 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)) 23:25:03 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)) 23:25:03 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)) 23:25:03 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)) 23:25:03 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)) 23:25:03 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)) 23:25:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:25:03 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 23:25:03 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 23:25:03 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 23:25:03 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 23:25:03 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 23:25:03 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 23:25:05 INFO - Downloading blessings-1.6.tar.gz 23:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:25:05 INFO - Installing collected packages: blessings 23:25:05 INFO - Running setup.py install for blessings 23:25:05 INFO - Successfully installed blessings 23:25:05 INFO - Cleaning up... 23:25:05 INFO - Return code: 0 23:25:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:25:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:25:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:25:05 INFO - Reading from file tmpfile_stdout 23:25:05 INFO - Current package versions: 23:25:05 INFO - argparse == 1.2.1 23:25:05 INFO - blessings == 1.6 23:25:05 INFO - blobuploader == 1.2.4 23:25:05 INFO - docopt == 0.6.1 23:25:05 INFO - manifestparser == 1.1 23:25:05 INFO - mozInstall == 1.12 23:25:05 INFO - mozcrash == 0.17 23:25:05 INFO - mozdebug == 0.1 23:25:05 INFO - mozdevice == 0.48 23:25:05 INFO - mozfile == 1.2 23:25:05 INFO - mozhttpd == 0.7 23:25:05 INFO - mozinfo == 0.9 23:25:05 INFO - mozleak == 0.1 23:25:05 INFO - mozlog == 3.2 23:25:05 INFO - moznetwork == 0.27 23:25:05 INFO - mozprocess == 0.22 23:25:05 INFO - mozprofile == 0.28 23:25:05 INFO - mozrunner == 6.11 23:25:05 INFO - mozscreenshot == 0.1 23:25:05 INFO - mozsystemmonitor == 0.3 23:25:05 INFO - moztest == 0.7 23:25:05 INFO - mozversion == 1.4 23:25:05 INFO - psutil == 3.1.1 23:25:05 INFO - requests == 1.2.3 23:25:05 INFO - wsgiref == 0.1.2 23:25:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25:05 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 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:25:05 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 23:25:05 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 23:25:05 INFO - Using env: {'DISPLAY': ':0', 23:25:05 INFO - 'HOME': '/home/cltbld', 23:25:05 INFO - 'LANG': 'en_US.UTF-8', 23:25:05 INFO - 'LANGUAGE': 'en_US:en', 23:25:05 INFO - 'LOGNAME': 'cltbld', 23:25:05 INFO - 'MAIL': '/var/mail/cltbld', 23:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:25:05 INFO - 'MOZ_NO_REMOTE': '1', 23:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:25:05 INFO - 'NO_EM_RESTART': '1', 23:25:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:25:05 INFO - 'PWD': '/builds/slave/test', 23:25:05 INFO - 'SHELL': '/bin/bash', 23:25:05 INFO - 'SHLVL': '1', 23:25:05 INFO - 'TERM': 'linux', 23:25:05 INFO - 'TMOUT': '86400', 23:25:05 INFO - 'USER': 'cltbld', 23:25:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:25:05 INFO - '_': '/tools/buildbot/bin/python'} 23:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 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)) 23:25:06 INFO - Cleaning up... 23:25:06 INFO - Return code: 0 23:25:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:25:06 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')]} 23:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25:06 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')]} 23:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25:06 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 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:25:06 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 23:25:06 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 23:25:06 INFO - Using env: {'DISPLAY': ':0', 23:25:06 INFO - 'HOME': '/home/cltbld', 23:25:06 INFO - 'LANG': 'en_US.UTF-8', 23:25:06 INFO - 'LANGUAGE': 'en_US:en', 23:25:06 INFO - 'LOGNAME': 'cltbld', 23:25:06 INFO - 'MAIL': '/var/mail/cltbld', 23:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:25:06 INFO - 'MOZ_NO_REMOTE': '1', 23:25:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:25:06 INFO - 'NO_EM_RESTART': '1', 23:25:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:25:06 INFO - 'PWD': '/builds/slave/test', 23:25:06 INFO - 'SHELL': '/bin/bash', 23:25:06 INFO - 'SHLVL': '1', 23:25:06 INFO - 'TERM': 'linux', 23:25:06 INFO - 'TMOUT': '86400', 23:25:06 INFO - 'USER': 'cltbld', 23:25:06 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:25:06 INFO - '_': '/tools/buildbot/bin/python'} 23:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:25:06 INFO - Downloading/unpacking jsonschema 23:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:25:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:25:08 INFO - Downloading/unpacking functools32 (from jsonschema) 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25:08 INFO - Downloading functools32-3.2.3-2.tar.gz 23:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:25:08 INFO - warning: no files found matching '*.txt' 23:25:08 INFO - no previously-included directories found matching 'build' 23:25:08 INFO - no previously-included directories found matching 'dist' 23:25:08 INFO - no previously-included directories found matching '.git*' 23:25:08 INFO - Installing collected packages: jsonschema, functools32 23:25:08 INFO - Running setup.py install for functools32 23:25:08 INFO - warning: no files found matching '*.txt' 23:25:08 INFO - no previously-included directories found matching 'build' 23:25:08 INFO - no previously-included directories found matching 'dist' 23:25:08 INFO - no previously-included directories found matching '.git*' 23:25:08 INFO - Successfully installed jsonschema functools32 23:25:08 INFO - Cleaning up... 23:25:08 INFO - Return code: 0 23:25:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:25: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')]} 23:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25: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')]} 23:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:25: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95b20e0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', '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 23:25: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', 'flake8'] in /builds/slave/test/build 23:25: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 flake8 23:25:08 INFO - Using env: {'DISPLAY': ':0', 23:25:08 INFO - 'HOME': '/home/cltbld', 23:25:08 INFO - 'LANG': 'en_US.UTF-8', 23:25:08 INFO - 'LANGUAGE': 'en_US:en', 23:25:08 INFO - 'LOGNAME': 'cltbld', 23:25:08 INFO - 'MAIL': '/var/mail/cltbld', 23:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:25:08 INFO - 'MOZ_NO_REMOTE': '1', 23:25:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:25:08 INFO - 'NO_EM_RESTART': '1', 23:25:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:25:08 INFO - 'PWD': '/builds/slave/test', 23:25:08 INFO - 'SHELL': '/bin/bash', 23:25:08 INFO - 'SHLVL': '1', 23:25:08 INFO - 'TERM': 'linux', 23:25:08 INFO - 'TMOUT': '86400', 23:25:08 INFO - 'USER': 'cltbld', 23:25:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:25:08 INFO - '_': '/tools/buildbot/bin/python'} 23:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:25:08 INFO - Downloading/unpacking flake8 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:25:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:25:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25: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 23:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:25:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:25:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:25:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:25:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:25:11 INFO - no previously-included directories found matching 'docs/_build' 23:25:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:25:11 INFO - Running setup.py install for pep8 23:25:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:25:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:25:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:25:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:25:11 INFO - no previously-included directories found matching 'docs/_build' 23:25:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:25:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:25:11 INFO - Cleaning up... 23:25:11 INFO - Return code: 0 23:25:11 INFO - Running post-action listener: _resource_record_post_action 23:25:11 INFO - Running post-action listener: _start_resource_monitoring 23:25:11 INFO - Starting resource monitoring. 23:25:11 INFO - ##### 23:25:11 INFO - ##### Finished create-virtualenv step (success) 23:25:11 INFO - ##### 23:25:11 INFO - ##### 23:25:11 INFO - ##### Running install step. 23:25:11 INFO - ##### 23:25:11 INFO - Running pre-action listener: _resource_record_pre_action 23:25:11 INFO - Running main action method: install 23:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:25:12 INFO - Reading from file tmpfile_stdout 23:25:12 INFO - Detecting whether we're running mozinstall >=1.0... 23:25:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:25:12 INFO - Reading from file tmpfile_stdout 23:25:12 INFO - Output received: 23:25:12 INFO - Usage: mozinstall [options] installer 23:25:12 INFO - Options: 23:25:12 INFO - -h, --help show this help message and exit 23:25:12 INFO - -d DEST, --destination=DEST 23:25:12 INFO - Directory to install application into. [default: 23:25:12 INFO - "/builds/slave/test"] 23:25:12 INFO - --app=APP Application being installed. [default: firefox] 23:25:12 INFO - mkdir: /builds/slave/test/build/application 23:25:12 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'] 23:25:12 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 23:25:28 INFO - Reading from file tmpfile_stdout 23:25:28 INFO - Output received: 23:25:28 INFO - /builds/slave/test/build/application/firefox/firefox 23:25:28 INFO - Running post-action listener: _resource_record_post_action 23:25:28 INFO - ##### 23:25:28 INFO - ##### Finished install step (success) 23:25:28 INFO - ##### 23:25:28 INFO - ##### 23:25:28 INFO - ##### Running run-tests step. 23:25:28 INFO - ##### 23:25:28 INFO - Running pre-action listener: _resource_record_pre_action 23:25:28 INFO - Running main action method: run_tests 23:25:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:25:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:25:28 INFO - Python 2.7.3 23:25:28 INFO - Return code: 0 23:25:28 INFO - grabbing minidump binary from tooltool 23:25:28 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')]} 23:25:28 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 0x1d8a9c0>, '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 0x1d8b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d8bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:25:28 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 23:25:28 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 23:25:28 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 23:25:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:25:28 INFO - Return code: 0 23:25:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:25:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:25:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:25:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:25:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:25:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:25:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:25:29 INFO - Return code: 0 23:25:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 23:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 23:25:29 INFO - Using env: {'DISPLAY': ':0', 23:25:29 INFO - 'HOME': '/home/cltbld', 23:25:29 INFO - 'LANG': 'en_US.UTF-8', 23:25:29 INFO - 'LANGUAGE': 'en_US:en', 23:25:29 INFO - 'LOGNAME': 'cltbld', 23:25:29 INFO - 'MAIL': '/var/mail/cltbld', 23:25:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:25:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:25:29 INFO - 'MOZ_NO_REMOTE': '1', 23:25:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:25:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:25:29 INFO - 'NO_EM_RESTART': '1', 23:25:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:25:29 INFO - 'PWD': '/builds/slave/test', 23:25:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:25:29 INFO - 'SHELL': '/bin/bash', 23:25:29 INFO - 'SHLVL': '1', 23:25:29 INFO - 'TERM': 'linux', 23:25:29 INFO - 'TMOUT': '86400', 23:25:29 INFO - 'USER': 'cltbld', 23:25:29 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759', 23:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:25:29 INFO - '_': '/tools/buildbot/bin/python'} 23:25:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 23:25:29 INFO - mozversion application_buildid: 20160517203031 23:25:29 INFO - mozversion application_changeset: d81b1964bb6b4ae973597d3fafa71dd962f2040b 23:25:29 INFO - mozversion application_display_name: Nightly 23:25:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:25:29 INFO - mozversion application_name: Firefox 23:25:29 INFO - mozversion application_remotingname: firefox 23:25:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:25:29 INFO - mozversion application_vendor: Mozilla 23:25:29 INFO - mozversion application_version: 49.0a1 23:25:29 INFO - mozversion platform_buildid: 20160517203031 23:25:29 INFO - mozversion platform_changeset: d81b1964bb6b4ae973597d3fafa71dd962f2040b 23:25:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:25:29 INFO - mozversion platform_version: 49.0a1 23:25:29 INFO - using testdate: 1463552729 23:25:29 INFO - actual date: 1463552729 23:25:29 INFO - starting webserver on 'localhost:57786' 23:25:29 INFO - SUITE-START | Running 2 tests 23:25:29 INFO - TEST-START | dromaeo_css 23:25:29 INFO - operating with platform_type : linux_ 23:25:29 INFO - Initialising browser for dromaeo_css test... 23:25:29 INFO - TEST-INFO | started process 30568 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ7c_8E/profile http://localhost:57786/getInfo.html) 23:25:33 INFO - PROCESS | 30568 | __metrics Screen width/height:1600/1200 23:25:33 INFO - PROCESS | 30568 | colorDepth:24 23:25:33 INFO - PROCESS | 30568 | Browser inner width/height: 1024/697 23:25:33 INFO - PROCESS | 30568 | __metrics 23:25:35 ERROR - PROCESS | 30568 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:25:35 INFO - TEST-INFO | 30568: exit 0 23:25:35 INFO - Browser initialized. 23:25:35 INFO - Running cycle 1/1 for dromaeo_css test... 23:25:35 INFO - TEST-INFO | started process 30654 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ7c_8E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:25:47 INFO - PROCESS | 30654 | RSS: Main: 153108480 23:25:47 INFO - PROCESS | 30654 | 23:26:38 INFO - PROCESS | 30654 | 1463552798901 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] 23:28:47 INFO - PROCESS | 30654 | Cycle 1(1): loaded http://localhost:57786/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57786/tests/dromaeo/cssquery-ext.html) 23:28:48 INFO - PROCESS | 30654 | RSS: Main: 256172032 23:28:48 INFO - PROCESS | 30654 | 23:31:50 INFO - PROCESS | 30654 | Cycle 1(1): loaded http://localhost:57786/tests/dromaeo/cssquery-ext.html (next: http://localhost:57786/tests/dromaeo/cssquery-jquery.html) 23:31:51 INFO - PROCESS | 30654 | RSS: Main: 237559808 23:31:51 INFO - PROCESS | 30654 | 23:34:52 INFO - PROCESS | 30654 | Cycle 1(1): loaded http://localhost:57786/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57786/tests/dromaeo/cssquery-mootools.html) 23:34:53 INFO - PROCESS | 30654 | RSS: Main: 246771712 23:34:53 INFO - PROCESS | 30654 | 23:37:55 INFO - PROCESS | 30654 | Cycle 1(1): loaded http://localhost:57786/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57786/tests/dromaeo/cssquery-prototype.html) 23:37:56 INFO - PROCESS | 30654 | RSS: Main: 232079360 23:37:56 INFO - PROCESS | 30654 | 23:40:58 INFO - PROCESS | 30654 | Cycle 1(1): loaded http://localhost:57786/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57786/tests/dromaeo/cssquery-yui.html) 23:40:59 INFO - PROCESS | 30654 | RSS: Main: 201699328 23:40:59 INFO - PROCESS | 30654 | 23:44:01 INFO - PROCESS | 30654 | Cycle 1(1): loaded http://localhost:57786/tests/dromaeo/cssquery-yui.html (next: http://localhost:57786/tests/dromaeo/cssquery-dojo.html) 23:44:02 INFO - PROCESS | 30654 | RSS: Main: 319344640 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | __start_tp_report 23:44:02 INFO - PROCESS | 30654 | _x_x_mozilla_page_load 23:44:02 INFO - PROCESS | 30654 | _x_x_mozilla_page_load_details 23:44:02 INFO - PROCESS | 30654 | |i|pagename|runs| 23:44:02 INFO - PROCESS | 30654 | |0;dojo.html;3175.7459403247744;3180.2367431816356;3207.214232513035;3219.5009773485117;3239.303549736808;5497.835064948048;5521.0062188806;5549.4728000839905;5646.068398714212;5668.461496157864;5448.945510544893;5462.426445223254;5485.040117979348;5574.888502229963;5577.274954255941;4893.486183950678;5002.474740152286;5013.598912087043;5034.144195486758;5204.375474943012;5114.974425127869;5134.64057515974;5167.328247327842;5180.507851754077;5186.507281808241;4915.803367865281;4962.305277261168;5016.724080175589;5034.446210916814;5045.243213517965;5007.349044624212;5055.317532133152;5127.897442051175;5137.357830271216;5177.042247184283;4925;5020.949790502122;5037.596992240648;5053.343065401511;5061.848144555669;5389.865253368658;5461.344638643388;5461.617686761928;5549.417311182348;5582.553395728332;5980.342162362137;6052.425019623172;6063.302720187146;6064.757409703607;6065.120557519135;5880.794172203952;5903.232579764646;6088.391160883921;6094.412456491652;6095.56092819221;5547.389787123413;5620.072688006515;5672.943270567324;5677.659340439587;5680.829575112753;6112.406112406109;6122.908156377658;6125.632462052291;6135.877282454326;6150;5689.459501347365;5820.738066787005;5848.4151584841175;5959.314678811992;5976.940230597638;5931.911021334683;5986;6018.097285407224;6036.6679832609625;6040.003399439043;5920.37836027211;5950.315713692894;5991.610545314541;6001.399860013964;6006.639601623917;5615.073512870413;5904.675242861684;5979.222701048836;5994.670293133832;6001.489873360726;4543.250363689954;4544.113897789965;4546.1589605922345;4547.1815073287125;4575.26795712691;4032.516098068251;4040.030392705788;4051.5160975416775;4093.8567150150197;4094.959050409517;5519;5581.2186293918885;5596.468335508121;5630.521405680481;5643.153526970905;6069.878602427927;6071.301800292935;6077.696115194222;6079.604825686316;6080.179175811298;5680.091185410314;5688.687122208318;5695.629784064023;5695.800646977419;5697;4892.755362231874;4955.529224723646;5002.824901128516;5008.924866127011;5009.624278179152;6160.168377269101;6194.876102477925;6209.316975132731;6210.689465526705;6211.81364559064;5910.468057874812;5932.16949627044;6024.9397506026135;6042.274927008787;6067.514598831995;6111.04482360895;6114.844865410725;6117.694115294128;6217.595856269328;6221.253449586078;5828.125781132864;5866.882662346644;5870.26621672291;5907.143464197753;5919.792807251898;5839.678817665069;5910.911336329872;6000.189974353407;6001.459868611846;6018.277806663228;5830.067189249699;5878.617889837147;5881.676507792113;5881.911771323348;5940.79207227745;5870.55970802183;5875.50058245054;5882.088276317178;5883.94116058834;5884.08796636522;5565.5825813063375;5686.203931449518;5752.7698892043845;5769.711514424346;5777.653340799566;5944.286685597756;5969.880602388015;5987.401259874152;5989.251343582052;5996.100584912124;5750.396208398225;5778.508826749773;5784.248047753933;5786;5806.477417032487 23:44:02 INFO - PROCESS | 30654 | |1;ext.html;608.3551435478394;623.7785586116928;636.0967426254717;639.0318667219162;655.9409653131218;31779.04662860115;42320.51878184264;42413;42544;42609.360859587134;45584.54415455845;45803.083938321215;45833.312500312524;45946;46018.30972535415;8740.300775937942;8870.822583548324;8877.51173685447;8878.822423551524;8880.73357799267;8679.913200868004;8730.563471826415;8766.912330876674;8792.34057445691;8800.691975780848;8850.336224783136;8884.466931984069;8887.822243555125;8888;8889.73330800077;8782.25350845179;8809.955950220208;8815.074417186199;8821.338399620054;8822.558872056403;8614.05245423003;8672.696455624086;8676.652933882637;8680.956595217047;8683;43065;43294.56705432937;43335.56664433347;43363;43417.34873976893;3423.5378223939697;3785.318642644323;3803.5055442792536;3929.5873933236885;4003.539592946819;32158;32213.355732885444;32401.189970250933;32457.83771081153;33280;4309.482862056573;4314.61168494837;4355.1725172217175;4361.738295702268;4534.478534968488;350250.2487487572;350963;352339;355450;355465;35168.47247291293;57963.13055304089;58225.417745822844;58277.4172258272;58379.70810145922;35629.46555801665;37890;37924;37977.43033854439;38019.429708554395;15738.213089345487;15871.20643967797;15871.28579213938;15872.841271587367;15877.603059923595;13210.07529472947;13256.668583285302;13320.400581973641;13322.267275299757;13327.733445331232;8759.649614015496;8785.209331160226;8788.95605521981;8847.380683352103;8848.601812918434;3357.815320157366;3376.74674399421;3382.5771778527683;3414.5902491701154;3428.3486137633768;1028.9794204116026;1039.974969083849;1060.8090543702053;1060.8143574874366;1162.7616338650557;33635.495467568006;33810.30948452568;33835.5472348519;34097.65902340945;34350.65649343475;1116.9553217871357;1119.9496022679;1139.1911742662803;1167.2471256039807;1174.2719513901436;17368.39210627622;17778.733319000225;17879.46361609154;17991.820081799277;18029.368972085915;559960;563589;564208;564706;564810;58547.4145258542;60602.39397606056;60629.78740425255;60673.69663151656;60701.69649151726;1274.796032634774;1376.3531140364014;1396.1483495068;1422.907511011781;1427.800107984883;32875;35025.64974350326;35172.47241291332;35201.1199720009;35493.29013419769;49597;50195;50262.74868625634;50284;50371.74814125906;393093;393383;393654;394580;395794;24313.75686243115;25018.37454063663;27802.304942376602;29050.709492905647;29654.406911862072;48713.5128648709;48797.02938391383;48894.26658600053;49223;49823;41028.589714102476;41050.38424423696;44248.55751442444;44636.10727785361;46558;42325.576744233396;42419.5758042428;42883.7855810719;43786;43818.34272485979;42496.15007699891;43146.62693551885;43196;43894;44168.116637666426;35041.474377883846;35735.82132089323;36513.269734604626;36608;36630.63369366272;28519.28701782472;29176;29247.7075229245;29268.268293292836;29429.26426839346 23:44:02 INFO - PROCESS | 30654 | |2;jquery.html;412.15713865145773;412.19210347718473;412.28468606966914;413.8592878421338;427.9101388708371;13908.513202037931;17876.91061544692;18885.71671424926;19418.70871936922;19434.028298585068;13815.240161791098;17607.735883961752;17627.471175864714;18171.364002259892;18859.15133818978;5736.741846616902;5894.410558944092;5909.940900591004;5915.556333275;5936.109583562457;5841.8831623367505;5855;5887.205227294303;5897.675627840467;5901.439363260483;4211.4946206455115;4438.42300500936;6191.164192833978;6479.1901012373455;6489.31862154474;6415.486761059151;6505.804825855232;6509.772157974472;6510.18622672166;6515.120458738104;5652.971735141298;5719.313682358102;5756;5792.304923409218;5797.072468405035;20864;21220.89389553058;21227.46931326714;21470.248541300978;21513.892430537748;2337.100216416675;2372.5254949010096;2384.308550520347;2557.258395065429;2560.9615855762177;13102.672433189151;13907.721845563134;14534.05528640635;14558.854411455855;14707.55877323682;1900.6388786130592;1950.4928718533142;2003.5091402606454;2059.3101311060814;2108.704781330615;82622;82685.34291123615;82692;82980.58509707473;83517;10646.201534884915;10752.301100428447;10957.50691218897;11072.667819965414;11076.058535024453;15321.080735155927;15491.225438728019;15509.689806204038;15532.378704851679;15539.145347005797;8216.589170541569;8442.197991190827;8559.657613695383;8580.313574914117;8638.740837774876;8290.958545207355;8305.584720763938;8338.791530211673;8506.31949444043;8540.121173341855;5875.11873219012;6663.566868153554;6706.027625994204;6729;6835.350641689031;1828.542864283929;1908.8759230649964;1976.723258743777;1987.9006049697457;2033.1460786469718;958.4393130019017;1049.9002594753485;1050.7425680708275;1108.392682032937;1117.474786850179;19152.138153783115;19479.805201948082;19568.90215548913;19614.607707845764;19870.205191792455;1021.1626466297713;1081.259337353915;1105.3754628634956;1147.913906457019;1169.5380324771668;11001.00990910806;11148.665540033811;11206.663800086011;11244.887551124548;11315.321080735182;420779;424915;439328;455332;457126;49828.7645550125;61939;80680;81703.5914820422;81726;1061.766411389493;1064.6380230721593;1074.2856000759407;1079.2660990526517;1084.577014964149;16787.244573993954;16909.49271521807;17237.741433878753;17274;17606.647867042844;38960;39261.214775704895;39697;39826;39963.40054899121;48322;49705.50294497009;50928;52752.736236318575;52978.470215297355;7863.646135923783;8223;8295.958520207601;8825.91174088251;9076.18476304755;8186.222308880647;9860.753481163028;12284.815727763911;13151.356080489939;13662.385192666552;11821.349825759666;11867.228630139014;12081.51673933068;12086.939565302117;12129.45417456199;11276.097912166842;11332.206745527734;11628.360440175871;11735.178537502294;11950.34273114987;11837.76324473523;12045.457954392248;12072.577459788865;12200.87799122033;12213.206141600767;7961;8071.4753541019645;8101.635426405708;8105.675772969015;8119.675212991414;11754.118441117122;11843.763124737285;12020.158588899043;12033;12261.203021803554 23:44:02 INFO - PROCESS | 30654 | |3;mootools.html;1369.3221855181687;1529.128396813816;1535.5239875638551;1708.6326439815437;1857.9814201857978;8397.49615023099;8665.090165532623;8670.52312122833;8965.731028069169;9027.864582031274;8773.824523509526;8979.461232326048;8986.2810975122;9000.774980625487;9006.189442950134;2090.529630833058;2342.8711420871846;2373.655819906117;2414.770596793302;2425.3936515871032;2421.8667973261463;2430.1251549442186;2430.8419952703107;2431.890564924574;2434.7686969737847;2326.4183954011496;2370.300761275419;2372.193454225563;2372.217168334454;2373.608354621494;2086.478380404899;2320.211128216406;2379.262428647125;2387.85672859629;2389.1637926725507;2298.7356454007836;2301.528186721718;2304.7119110111234;2331.720193576765;2384.988075059631;9057.456552606865;9197;9208.171264586219;9209.401388909699;9219.677311294072;5472.7263636818;5643.435656434365;5643.520300774439;5651.350094739117;5654.63244889085;3544.043108360728;3571.14292569784;3625.329314076904;3637.0725465006517;3657.0674478008204;1226.6687994241504;1249.2254802022712;2190.539986602815;2200.3508964855246;2210.5910406574676;12095.93952030243;12364.876351236462;12393.194442361219;12430.813537796941;12470.875291247063;4787.3537072495465;4801.4718380978065;4806.44725856524;4821.855344339676;4916.631252656065;10111.898881011242;10113.342632728849;10113.94943025295;10129.392236465836;10144.340617859816;5025.773840177128;5481.040817856939;5485.61600687956;5487.42382049887;5491.258680078138;4808.687435316693;4872.342233798392;4897.951020489821;4922.212446008622;4935.506449355035;3853.325668008087;3904.472896159039;3949.565547789741;3952.110775075585;3984.920301593952;1146.9827952580717;1174.864890537582;1206.7465832175167;1216.5498765456837;1226.0436859249799;902.6163880350824;930.7998780262276;956.1012648110753;1003.0721582536271;1027.1584274812149;5968.940310596925;6048.848778780478;6062.272527296753;6065.5450841187085;6069.423404776539;490.629574671121;493.55333423252017;496.79879648742315;1058.7564860082225;1067.124957534814;4218.725782824106;4259.318509038539;4261.190373828963;4268.061026574149;4269.935950960738;11417.543298267976;11511.136664750176;11773.823392649118;11781.057515398748;11781.882181178249;11604.361760103107;11611.88388116125;11617.709557260967;11619.128565357632;11631.88368116325;8186.304164145995;8460.534670593053;8480.618372173267;8497.957510212409;8681.131886811268;5999.430054144849;6195.132681424694;6303.905441418474;6411.487081033413;6542.476601871934;10375.481225938522;10544.52549635253;10823.56705731762;10826.67519974402;10924.617638382619;10854.837177442187;10857.18571107186;11040.834387484034;11068.612598559012;11097.112231021338;7369.299916507923;7852.293293603603;7854.960725196337;7973.44185906981;8058.516489010678;8545.2309292164;8800.427972181787;8809.414305425085;8812.779680508038;8835.911640883509;7847.607619619111;7962.283394494523;8031.718889838828;8141.552214628252;8159.020917489939;7916.366690664848;8090.838183236185;8098.79753006156;8118.188181181835;8149.348052155695;6914.827129321807;7188.317109874553;7337.1929087799235;7381.188069312479;7400.962995184989;5285.810692594136;5287.127623942007;5522.337319521683;5556.833295001156;5565.4991050804;5352;5354.2771725818075;5372.328458942632;5455.18172274162;5525.198846167364 23:44:02 INFO - PROCESS | 30654 | |4;prototype.html;303.9407315573463;308.1863879358493;313.0920331039984;328.77971758921535;329.4350189425135;5162.845114646557;5308.787648494065;5393.379761327444;5469.234307196991;5487.423820498845;5229.058769421503;5291.947080529194;5296.3379577552805;5327.61482014676;5388.353397592285;4159.2721273777115;4168.395582640515;4187.979060104711;4195.85314513992;4232.851850185244;4042;4083.081306705982;4135.958640413603;4227.287948380896;4239.91520169598;4131.752094874317;4136.9793151034355;4143.337066069429;4146.564610715876;4150.584941505856;4130.772807495587;4162.583741625844;4176.2691528982305;4177.164567086595;4182.100848317596;3631.963680363189;3693.0028892199225;3693.316736403774;3695.450139403803;3708.4622729704042;5148.330717006766;5152.5105115014385;5160.974195129038;5174.611904107207;5187.584993200573;4709.340692303081;4734.431868175807;4805.486271824364;4806.182948898696;4837.105135549899;5321.707306098163;5563.527100196488;5567.663760697404;5571.997040532702;5576.609637325389;3977.3238549446373;4003.8188734323444;4006.6794656427414;4012.8394864205397;4013.7993100344866;5785.2189954356045;5789.334226563957;5805.82582522525;5848.619839710405;5859.359379373739;3847.2882516733935;4100.01599616096;4105.507339119325;4108.835646574164;4146.688998325138;4067.1255680028935;4086.3053280942313;4091.6317531422314;4115.403266526337;4117.814698338582;4670.579647831712;4682.321063445781;4705.270683044134;4712.340272361872;4734.597559207437;5480.314960629921;5577.972110139423;5585.888282234332;5598.832035038874;5644.153376993484;1403.862871074433;1415.808865803124;1420.1195258939524;1426.486464872645;1432.4914655297218;872.1496540872598;882.1178821178821;883.7878909061778;888.1739981816972;896.3277541843618;4839.830605928775;4885.65800393969;4910.361652985161;4913.926291105637;4928.260760885824;5138.897222055538;5153.330067091254;5158.38099428071;5184.533391994739;5235.188545775411;4692.718436893797;4847.40035788189;4896.730679812645;4913.434954980152;4931.161702510582;2761.7376349247193;2762.047093752652;2762.2818067302646;2778.4582006508535;2782.3322402623226;5305.469453054741;5329.866753331198;5346.5138710499805;5393;5402.621816472888;5223.268742376071;5229.52934235913;5480.698561579072;5980.936576880969;5997.970010149921;5995.940040599538;6034.2155519782145;6057.969710151421;6073.878522429615;6077.513798896078;5653.321601407857;5836.416358364129;5876.647401155944;5886.676232807236;5904.734286957011;5678.716064196857;5719.713064560607;5728.570357223308;5740.368559458373;5782.306123265067;5968.910466343095;5969.582129251085;5975.774966131846;6053.667048312385;6056.454919057306;3791;3941.507311586052;4051.9392209117477;4091.3453847385467;4103;5692.402297758841;5716.971415143064;5733.541316694738;5758.740856661377;5759.452851979056;5609.102543593006;5610.887782244414;5645.209670646031;5646.463585959327;5672.568096301843;5335.359756829205;5582.581306402118;5624.156376543551;5724.341700704473;5745.425457454221;5614.522765564972;5620.381758006534;5639.154126881001;5643.294588176478;5671.120976248688;5634;5634.915476267941;5694.373618901835;5737.426257374229;5806.5064469518875;5565.30433695788;5599.496045355938;5608.887822243614;5609.859753506195;5653.604247702786 23:44:02 INFO - PROCESS | 30654 | |5;yui.html;770.7302444144549;788.928996390325;793.9523628582285;834.370050611788;834.4159088637955;12773.169743966648;13621.387037583308;13625.386857591408;13658;13681.452741890325;12554.874451255508;12594.68513287166;12604.873951260484;12616;12697.238165710041;685.5201359048662;813.60540138033;828.8218033122878;835.9707410240643;836.9078352749659;809.5142914251431;811.926926576608;814.6212011414691;814.7352110564061;818.0102076487457;721.754603434832;876.1720174435161;876.447837862146;877.3288434347697;877.749841295231;849.5964416901984;871.1288711288711;872.2455076358967;872.9170728780756;873.1661263493412;636.2841802971657;636.4876274598946;638.3807706524664;638.6838514935137;639.5619000984341;13084;13118.47526098955;13152.408141633554;13156.144850584682;13157.144785588906;14068.296585170701;14233.146011239358;14293.714125717428;14375.640608984755;14481.637959051002;11685.766284674344;12102.152849300552;12203.45084471201;12325.322107284095;12442.626721198378;4404.22925987954;4428.247197976351;4431.534688857688;4433.578810013044;4438.911221775546;58438.415615843725;58559;59271;59950.70024649849;60126;11558.479868405942;11593.826092608617;11627.767444651228;11708.531658733726;11738.178327517162;19739.605207895762;19762.407127786188;19785.10967006488;19879.503012424804;21354.039068241967;8394.958025209957;8465.534395608178;8473.83052338962;8502.44734092282;8534.06125326226;4591.77041147948;4627.884302892455;4629.65277604181;4742.691725037862;4789.161896668069;3462.255615042778;3471.4445688690193;3541.256336169382;3542.433210686278;3543.858245670196;590.7991282963727;591.7130191856946;624.7500999600196;627.1721327847289;664.299164381582;645.0904225042667;659.6075335175562;666.73997141115;674.642439507062;674.9730010799611;10174.287799854088;10403.94798026005;10454.163666906628;10470.73823154412;10540.367577945348;658.8385845467891;690.7049282595134;770.818857568471;777.525709317316;805.4160733468188;4707.364505791743;4724.71651700899;4837.516248375135;4860.3681521401995;4871.975640121847;69035.30964690288;70448.64775676088;71939;71989.6400517994;72197.2780272201;62861.68569157125;63014.36985630177;63881.36118638847;64313.67843160848;64817.67591162014;57923.4207657918;58088.41911580915;58192.41807581954;58327.125093123635;58445.70777146087;9499.905000949902;9538.666146684833;9567.61729530811;9665.855012174683;9711.902880971382;37930.81034594809;38893;38953.610463895;39798.104614894386;39803.80098099491;45868.54131458643;47077.764611176724;47119.05249948706;51612.74193629008;51957.740211298704;11637.825432618349;11681.766364672829;11758.470868810753;12078;12222;12635.1787133836;12982.221066736027;13196.670083247996;13466.730665386833;13492.107117336076;12349.444275007467;12391.814122787984;12775.872241277468;12799.168054076456;12843.165194262343;12296.50813967467;12360.81458778137;12824.422900969665;12887.42006609686;13230.4046317914;12242.387880605756;12258.203216791237;12976.870231297566;12991;13000.479980801041;9880.35777674449;9942.801143977225;10093.596256149673;10205.948970255102;10313.22650801178;9684.32209745311;9795.33328024212;10417.467632652257;10556.524956376828;10574.2598018138 23:44:02 INFO - PROCESS | 30654 | __end_tp_report 23:44:02 INFO - PROCESS | 30654 | __start_cc_report 23:44:02 INFO - PROCESS | 30654 | _x_x_mozilla_cycle_collect,908 23:44:02 INFO - PROCESS | 30654 | __end_cc_report 23:44:02 INFO - PROCESS | 30654 | __startTimestamp1463553842204__endTimestamp 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | ------- Summary: start ------- 23:44:02 INFO - PROCESS | 30654 | Number of tests: 6 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | [#0] dojo.html Cycles:165 Average:5545.60 Median:5777.65 stddev:648.93 (11.2%) stddev-sans-first:623.73 23:44:02 INFO - PROCESS | 30654 | Values: 3175.7 3180.2 3207.2 3219.5 3239.3 5497.8 5521.0 5549.5 5646.1 5668.5 5448.9 5462.4 5485.0 5574.9 5577.3 4893.5 5002.5 5013.6 5034.1 5204.4 5115.0 5134.6 5167.3 5180.5 5186.5 4915.8 4962.3 5016.7 5034.4 5045.2 5007.3 5055.3 5127.9 5137.4 5177.0 4925.0 5020.9 5037.6 5053.3 5061.8 5389.9 5461.3 5461.6 5549.4 5582.6 5980.3 6052.4 6063.3 6064.8 6065.1 5880.8 5903.2 6088.4 6094.4 6095.6 5547.4 5620.1 5672.9 5677.7 5680.8 6112.4 6122.9 6125.6 6135.9 6150.0 5689.5 5820.7 5848.4 5959.3 5976.9 5931.9 5986.0 6018.1 6036.7 6040.0 5920.4 5950.3 5991.6 6001.4 6006.6 5615.1 5904.7 5979.2 5994.7 6001.5 4543.3 4544.1 4546.2 4547.2 4575.3 4032.5 4040.0 4051.5 4093.9 4095.0 5519.0 5581.2 5596.5 5630.5 5643.2 6069.9 6071.3 6077.7 6079.6 6080.2 5680.1 5688.7 5695.6 5695.8 5697.0 4892.8 4955.5 5002.8 5008.9 5009.6 6160.2 6194.9 6209.3 6210.7 6211.8 5910.5 5932.2 6024.9 6042.3 6067.5 6111.0 6114.8 6117.7 6217.6 6221.3 5828.1 5866.9 5870.3 5907.1 5919.8 5839.7 5910.9 6000.2 6001.5 6018.3 5830.1 5878.6 5881.7 5881.9 5940.8 5870.6 5875.5 5882.1 5883.9 5884.1 5565.6 5686.2 5752.8 5769.7 5777.7 5944.3 5969.9 5987.4 5989.3 5996.1 5750.4 5778.5 5784.2 5786.0 5806.5 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | [#1] ext.html Cycles:180 Average:59152.62 Median:42369.29 stddev:118547.62 (279.8%) stddev-sans-first:118798.68 23:44:02 INFO - PROCESS | 30654 | Values: 608.4 623.8 636.1 639.0 655.9 31779.0 42320.5 42413.0 42544.0 42609.4 45584.5 45803.1 45833.3 45946.0 46018.3 8740.3 8870.8 8877.5 8878.8 8880.7 8679.9 8730.6 8766.9 8792.3 8800.7 8850.3 8884.5 8887.8 8888.0 8889.7 8782.3 8810.0 8815.1 8821.3 8822.6 8614.1 8672.7 8676.7 8681.0 8683.0 43065.0 43294.6 43335.6 43363.0 43417.3 3423.5 3785.3 3803.5 3929.6 4003.5 32158.0 32213.4 32401.2 32457.8 33280.0 4309.5 4314.6 4355.2 4361.7 4534.5 350250.2 350963.0 352339.0 355450.0 355465.0 35168.5 57963.1 58225.4 58277.4 58379.7 35629.5 37890.0 37924.0 37977.4 38019.4 15738.2 15871.2 15871.3 15872.8 15877.6 13210.1 13256.7 13320.4 13322.3 13327.7 8759.6 8785.2 8789.0 8847.4 8848.6 3357.8 3376.7 3382.6 3414.6 3428.3 1029.0 1040.0 1060.8 1060.8 1162.8 33635.5 33810.3 33835.5 34097.7 34350.7 1117.0 1119.9 1139.2 1167.2 1174.3 17368.4 17778.7 17879.5 17991.8 18029.4 559960.0 563589.0 564208.0 564706.0 564810.0 58547.4 60602.4 60629.8 60673.7 60701.7 1274.8 1376.4 1396.1 1422.9 1427.8 32875.0 35025.6 35172.5 35201.1 35493.3 49597.0 50195.0 50262.7 50284.0 50371.7 393093.0 393383.0 393654.0 394580.0 395794.0 24313.8 25018.4 27802.3 29050.7 29654.4 48713.5 48797.0 48894.3 49223.0 49823.0 41028.6 41050.4 44248.6 44636.1 46558.0 42325.6 42419.6 42883.8 43786.0 43818.3 42496.2 43146.6 43196.0 43894.0 44168.1 35041.5 35735.8 36513.3 36608.0 36630.6 28519.3 29176.0 29247.7 29268.3 29429.3 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | [#2] jquery.html Cycles:180 Average:26874.82 Median:21492.07 stddev:72332.85 (336.6%) stddev-sans-first:72508.47 23:44:02 INFO - PROCESS | 30654 | Values: 412.2 412.2 412.3 413.9 427.9 13908.5 17876.9 18885.7 19418.7 19434.0 13815.2 17607.7 17627.5 18171.4 18859.2 5736.7 5894.4 5909.9 5915.6 5936.1 5841.9 5855.0 5887.2 5897.7 5901.4 4211.5 4438.4 6191.2 6479.2 6489.3 6415.5 6505.8 6509.8 6510.2 6515.1 5653.0 5719.3 5756.0 5792.3 5797.1 20864.0 21220.9 21227.5 21470.2 21513.9 2337.1 2372.5 2384.3 2557.3 2561.0 13102.7 13907.7 14534.1 14558.9 14707.6 1900.6 1950.5 2003.5 2059.3 2108.7 82622.0 82685.3 82692.0 82980.6 83517.0 10646.2 10752.3 10957.5 11072.7 11076.1 15321.1 15491.2 15509.7 15532.4 15539.1 8216.6 8442.2 8559.7 8580.3 8638.7 8291.0 8305.6 8338.8 8506.3 8540.1 5875.1 6663.6 6706.0 6729.0 6835.4 1828.5 1908.9 1976.7 1987.9 2033.1 958.4 1049.9 1050.7 1108.4 1117.5 19152.1 19479.8 19568.9 19614.6 19870.2 1021.2 1081.3 1105.4 1147.9 1169.5 11001.0 11148.7 11206.7 11244.9 11315.3 420779.0 424915.0 439328.0 455332.0 457126.0 49828.8 61939.0 80680.0 81703.6 81726.0 1061.8 1064.6 1074.3 1079.3 1084.6 16787.2 16909.5 17237.7 17274.0 17606.6 38960.0 39261.2 39697.0 39826.0 39963.4 48322.0 49705.5 50928.0 52752.7 52978.5 7863.6 8223.0 8296.0 8825.9 9076.2 8186.2 9860.8 12284.8 13151.4 13662.4 11821.3 11867.2 12081.5 12086.9 12129.5 11276.1 11332.2 11628.4 11735.2 11950.3 11837.8 12045.5 12072.6 12200.9 12213.2 7961.0 8071.5 8101.6 8105.7 8119.7 11754.1 11843.8 12020.2 12033.0 12261.2 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | [#3] mootools.html Cycles:180 Average:6012.41 Median:4803.96 stddev:3419.08 (71.2%) stddev-sans-first:3410.86 23:44:02 INFO - PROCESS | 30654 | Values: 1369.3 1529.1 1535.5 1708.6 1858.0 8397.5 8665.1 8670.5 8965.7 9027.9 8773.8 8979.5 8986.3 9000.8 9006.2 2090.5 2342.9 2373.7 2414.8 2425.4 2421.9 2430.1 2430.8 2431.9 2434.8 2326.4 2370.3 2372.2 2372.2 2373.6 2086.5 2320.2 2379.3 2387.9 2389.2 2298.7 2301.5 2304.7 2331.7 2385.0 9057.5 9197.0 9208.2 9209.4 9219.7 5472.7 5643.4 5643.5 5651.4 5654.6 3544.0 3571.1 3625.3 3637.1 3657.1 1226.7 1249.2 2190.5 2200.4 2210.6 12095.9 12364.9 12393.2 12430.8 12470.9 4787.4 4801.5 4806.4 4821.9 4916.6 10111.9 10113.3 10113.9 10129.4 10144.3 5025.8 5481.0 5485.6 5487.4 5491.3 4808.7 4872.3 4898.0 4922.2 4935.5 3853.3 3904.5 3949.6 3952.1 3984.9 1147.0 1174.9 1206.7 1216.5 1226.0 902.6 930.8 956.1 1003.1 1027.2 5968.9 6048.8 6062.3 6065.5 6069.4 490.6 493.6 496.8 1058.8 1067.1 4218.7 4259.3 4261.2 4268.1 4269.9 11417.5 11511.1 11773.8 11781.1 11781.9 11604.4 11611.9 11617.7 11619.1 11631.9 8186.3 8460.5 8480.6 8498.0 8681.1 5999.4 6195.1 6303.9 6411.5 6542.5 10375.5 10544.5 10823.6 10826.7 10924.6 10854.8 10857.2 11040.8 11068.6 11097.1 7369.3 7852.3 7855.0 7973.4 8058.5 8545.2 8800.4 8809.4 8812.8 8835.9 7847.6 7962.3 8031.7 8141.6 8159.0 7916.4 8090.8 8098.8 8118.2 8149.3 6914.8 7188.3 7337.2 7381.2 7401.0 5285.8 5287.1 5522.3 5556.8 5565.5 5352.0 5354.3 5372.3 5455.2 5525.2 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | [#4] prototype.html Cycles:180 Average:4652.77 Median:5226.16 stddev:1387.41 (26.5%) stddev-sans-first:1352.36 23:44:02 INFO - PROCESS | 30654 | Values: 303.9 308.2 313.1 328.8 329.4 5162.8 5308.8 5393.4 5469.2 5487.4 5229.1 5291.9 5296.3 5327.6 5388.4 4159.3 4168.4 4188.0 4195.9 4232.9 4042.0 4083.1 4136.0 4227.3 4239.9 4131.8 4137.0 4143.3 4146.6 4150.6 4130.8 4162.6 4176.3 4177.2 4182.1 3632.0 3693.0 3693.3 3695.5 3708.5 5148.3 5152.5 5161.0 5174.6 5187.6 4709.3 4734.4 4805.5 4806.2 4837.1 5321.7 5563.5 5567.7 5572.0 5576.6 3977.3 4003.8 4006.7 4012.8 4013.8 5785.2 5789.3 5805.8 5848.6 5859.4 3847.3 4100.0 4105.5 4108.8 4146.7 4067.1 4086.3 4091.6 4115.4 4117.8 4670.6 4682.3 4705.3 4712.3 4734.6 5480.3 5578.0 5585.9 5598.8 5644.2 1403.9 1415.8 1420.1 1426.5 1432.5 872.1 882.1 883.8 888.2 896.3 4839.8 4885.7 4910.4 4913.9 4928.3 5138.9 5153.3 5158.4 5184.5 5235.2 4692.7 4847.4 4896.7 4913.4 4931.2 2761.7 2762.0 2762.3 2778.5 2782.3 5305.5 5329.9 5346.5 5393.0 5402.6 5223.3 5229.5 5480.7 5980.9 5998.0 5995.9 6034.2 6058.0 6073.9 6077.5 5653.3 5836.4 5876.6 5886.7 5904.7 5678.7 5719.7 5728.6 5740.4 5782.3 5968.9 5969.6 5975.8 6053.7 6056.5 3791.0 3941.5 4051.9 4091.3 4103.0 5692.4 5717.0 5733.5 5758.7 5759.5 5609.1 5610.9 5645.2 5646.5 5672.6 5335.4 5582.6 5624.2 5724.3 5745.4 5614.5 5620.4 5639.2 5643.3 5671.1 5634.0 5634.9 5694.4 5737.4 5806.5 5565.3 5599.5 5608.9 5609.9 5653.6 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - PROCESS | 30654 | [#5] yui.html Cycles:180 Average:15934.36 Median:25892.56 stddev:19460.95 (75.2%) stddev-sans-first:19482.23 23:44:02 INFO - PROCESS | 30654 | Values: 770.7 788.9 794.0 834.4 834.4 12773.2 13621.4 13625.4 13658.0 13681.5 12554.9 12594.7 12604.9 12616.0 12697.2 685.5 813.6 828.8 836.0 836.9 809.5 811.9 814.6 814.7 818.0 721.8 876.2 876.4 877.3 877.7 849.6 871.1 872.2 872.9 873.2 636.3 636.5 638.4 638.7 639.6 13084.0 13118.5 13152.4 13156.1 13157.1 14068.3 14233.1 14293.7 14375.6 14481.6 11685.8 12102.2 12203.5 12325.3 12442.6 4404.2 4428.2 4431.5 4433.6 4438.9 58438.4 58559.0 59271.0 59950.7 60126.0 11558.5 11593.8 11627.8 11708.5 11738.2 19739.6 19762.4 19785.1 19879.5 21354.0 8395.0 8465.5 8473.8 8502.4 8534.1 4591.8 4627.9 4629.7 4742.7 4789.2 3462.3 3471.4 3541.3 3542.4 3543.9 590.8 591.7 624.8 627.2 664.3 645.1 659.6 666.7 674.6 675.0 10174.3 10403.9 10454.2 10470.7 10540.4 658.8 690.7 770.8 777.5 805.4 4707.4 4724.7 4837.5 4860.4 4872.0 69035.3 70448.6 71939.0 71989.6 72197.3 62861.7 63014.4 63881.4 64313.7 64817.7 57923.4 58088.4 58192.4 58327.1 58445.7 9499.9 9538.7 9567.6 9665.9 9711.9 37930.8 38893.0 38953.6 39798.1 39803.8 45868.5 47077.8 47119.1 51612.7 51957.7 11637.8 11681.8 11758.5 12078.0 12222.0 12635.2 12982.2 13196.7 13466.7 13492.1 12349.4 12391.8 12775.9 12799.2 12843.2 12296.5 12360.8 12824.4 12887.4 13230.4 12242.4 12258.2 12976.9 12991.0 13000.5 9880.4 9942.8 10093.6 10205.9 10313.2 9684.3 9795.3 10417.5 10556.5 10574.3 23:44:02 INFO - PROCESS | 30654 | -------- Summary: end -------- 23:44:02 INFO - PROCESS | 30654 | 23:44:02 INFO - TEST-INFO | 30654: exit 0 23:44:02 INFO - TEST-OK | dromaeo_css | took 1113087ms 23:44:02 INFO - TEST-START | kraken 23:44:02 INFO - operating with platform_type : linux_ 23:44:02 INFO - Initialising browser for kraken test... 23:44:02 INFO - TEST-INFO | started process 30838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSYKKXh/profile http://localhost:57786/getInfo.html) 23:44:06 INFO - PROCESS | 30838 | __metrics Screen width/height:1600/1200 23:44:06 INFO - PROCESS | 30838 | colorDepth:24 23:44:06 INFO - PROCESS | 30838 | Browser inner width/height: 1024/697 23:44:06 INFO - PROCESS | 30838 | __metrics 23:44:08 ERROR - PROCESS | 30838 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:44:08 INFO - TEST-INFO | 30838: exit 0 23:44:08 INFO - Browser initialized. 23:44:08 INFO - Running cycle 1/1 for kraken test... 23:44:08 INFO - TEST-INFO | started process 30921 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSYKKXh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:44:20 INFO - PROCESS | 30921 | RSS: Main: 153280512 23:44:20 INFO - PROCESS | 30921 | 23:44:23 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57786/tests/kraken/driver.html?testName=audio-beat-detection) 23:44:24 INFO - PROCESS | 30921 | RSS: Main: 208965632 23:44:24 INFO - PROCESS | 30921 | 23:44:26 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57786/tests/kraken/driver.html?testName=audio-dft) 23:44:27 INFO - PROCESS | 30921 | RSS: Main: 197439488 23:44:27 INFO - PROCESS | 30921 | 23:44:30 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57786/tests/kraken/driver.html?testName=audio-fft) 23:44:31 INFO - PROCESS | 30921 | RSS: Main: 265371648 23:44:31 INFO - PROCESS | 30921 | 23:44:33 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57786/tests/kraken/driver.html?testName=audio-oscillator) 23:44:33 INFO - PROCESS | 30921 | RSS: Main: 174272512 23:44:33 INFO - PROCESS | 30921 | 23:44:36 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57786/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:44:36 INFO - PROCESS | 30921 | RSS: Main: 179372032 23:44:36 INFO - PROCESS | 30921 | 23:44:40 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57786/tests/kraken/driver.html?testName=imaging-darkroom) 23:44:40 INFO - PROCESS | 30921 | RSS: Main: 214642688 23:44:40 INFO - PROCESS | 30921 | 23:44:44 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57786/tests/kraken/driver.html?testName=imaging-desaturate) 23:44:44 INFO - PROCESS | 30921 | RSS: Main: 212144128 23:44:44 INFO - PROCESS | 30921 | 23:44:47 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57786/tests/kraken/driver.html?testName=json-parse-financial) 23:44:48 INFO - PROCESS | 30921 | RSS: Main: 214384640 23:44:48 INFO - PROCESS | 30921 | 23:44:50 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57786/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:44:50 INFO - PROCESS | 30921 | RSS: Main: 203825152 23:44:50 INFO - PROCESS | 30921 | 23:44:52 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:44:53 INFO - PROCESS | 30921 | RSS: Main: 209354752 23:44:53 INFO - PROCESS | 30921 | 23:44:55 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:44:55 INFO - PROCESS | 30921 | RSS: Main: 216088576 23:44:55 INFO - PROCESS | 30921 | 23:44:58 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:44:59 INFO - PROCESS | 30921 | RSS: Main: 223764480 23:44:59 INFO - PROCESS | 30921 | 23:45:01 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:45:02 INFO - PROCESS | 30921 | RSS: Main: 220934144 23:45:02 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | Cycle 1(1): loaded http://localhost:57786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57786/tests/kraken/driver.html?testName=ai-astar) 23:45:04 INFO - PROCESS | 30921 | RSS: Main: 265981952 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | __start_tp_report 23:45:04 INFO - PROCESS | 30921 | _x_x_mozilla_page_load 23:45:04 INFO - PROCESS | 30921 | _x_x_mozilla_page_load_details 23:45:04 INFO - PROCESS | 30921 | |i|pagename|runs| 23:45:04 INFO - PROCESS | 30921 | |0;ai-astar;111;105;110;109;109;110;109;110;109;110 23:45:04 INFO - PROCESS | 30921 | |1;audio-beat-detection;163;123;152;207;132;153;167;126;180;127 23:45:04 INFO - PROCESS | 30921 | |2;audio-dft;161;161;163;159;162;162;161;163;161;161 23:45:04 INFO - PROCESS | 30921 | |3;audio-fft;75;75;76;154;75;74;75;75;131;74 23:45:04 INFO - PROCESS | 30921 | |4;audio-oscillator;92;93;145;91;90;92;120;91;91;118 23:45:04 INFO - PROCESS | 30921 | |5;imaging-gaussian-blur;116;117;117;116;117;117;117;117;116;117 23:45:04 INFO - PROCESS | 30921 | |6;imaging-darkroom;146;145;146;145;146;145;146;148;146;145 23:45:04 INFO - PROCESS | 30921 | |7;imaging-desaturate;96;95;94;94;94;95;95;96;94;95 23:45:04 INFO - PROCESS | 30921 | |8;json-parse-financial;56;55;55;54;56;56;55;108;53;56 23:45:04 INFO - PROCESS | 30921 | |9;json-stringify-tinderbox;60;59;59;60;60;59;59;59;59;59 23:45:04 INFO - PROCESS | 30921 | |10;stanford-crypto-aes;79;78;78;78;80;78;79;80;80;80 23:45:04 INFO - PROCESS | 30921 | |11;stanford-crypto-ccm;120;122;126;130;126;128;119;127;188;119 23:45:04 INFO - PROCESS | 30921 | |12;stanford-crypto-pbkdf2;163;156;157;161;156;161;158;158;157;159 23:45:04 INFO - PROCESS | 30921 | |13;stanford-crypto-sha256-iterative;61;66;63;63;62;64;61;61;61;62 23:45:04 INFO - PROCESS | 30921 | __end_tp_report 23:45:04 INFO - PROCESS | 30921 | __start_cc_report 23:45:04 INFO - PROCESS | 30921 | _x_x_mozilla_cycle_collect,1883 23:45:04 INFO - PROCESS | 30921 | __end_cc_report 23:45:04 INFO - PROCESS | 30921 | __startTimestamp1463553904392__endTimestamp 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | ------- Summary: start ------- 23:45:04 INFO - PROCESS | 30921 | Number of tests: 14 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.00 stddev:1.62 (1.5%) stddev-sans-first:1.58 23:45:04 INFO - PROCESS | 30921 | Values: 111.0 105.0 110.0 109.0 109.0 110.0 109.0 110.0 109.0 110.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#1] audio-beat-detection Cycles:10 Average:153.00 Median:158.00 stddev:27.26 (17.3%) stddev-sans-first:28.67 23:45:04 INFO - PROCESS | 30921 | Values: 163.0 123.0 152.0 207.0 132.0 153.0 167.0 126.0 180.0 127.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 23:45:04 INFO - PROCESS | 30921 | Values: 161.0 161.0 163.0 159.0 162.0 162.0 161.0 163.0 161.0 161.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#3] audio-fft Cycles:10 Average:88.40 Median:75.00 stddev:29.03 (38.7%) stddev-sans-first:30.38 23:45:04 INFO - PROCESS | 30921 | Values: 75.0 75.0 76.0 154.0 75.0 74.0 75.0 75.0 131.0 74.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#4] audio-oscillator Cycles:10 Average:102.30 Median:91.00 stddev:18.90 (20.8%) stddev-sans-first:19.68 23:45:04 INFO - PROCESS | 30921 | Values: 92.0 93.0 145.0 91.0 90.0 92.0 120.0 91.0 91.0 118.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 23:45:04 INFO - PROCESS | 30921 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 23:45:04 INFO - PROCESS | 30921 | Values: 146.0 145.0 146.0 145.0 146.0 145.0 146.0 148.0 146.0 145.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.71 23:45:04 INFO - PROCESS | 30921 | Values: 96.0 95.0 94.0 94.0 94.0 95.0 95.0 96.0 94.0 95.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:55.50 stddev:16.75 (30.2%) stddev-sans-first:17.69 23:45:04 INFO - PROCESS | 30921 | Values: 56.0 55.0 55.0 54.0 56.0 56.0 55.0 108.0 53.0 56.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 23:45:04 INFO - PROCESS | 30921 | Values: 60.0 59.0 59.0 60.0 60.0 59.0 59.0 59.0 59.0 59.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.50 stddev:0.94 (1.2%) stddev-sans-first:1.00 23:45:04 INFO - PROCESS | 30921 | Values: 79.0 78.0 78.0 78.0 80.0 78.0 79.0 80.0 80.0 80.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:126.50 stddev:20.58 (16.3%) stddev-sans-first:21.48 23:45:04 INFO - PROCESS | 30921 | Values: 120.0 122.0 126.0 130.0 126.0 128.0 119.0 127.0 188.0 119.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.60 Median:158.50 stddev:2.37 (1.5%) stddev-sans-first:1.90 23:45:04 INFO - PROCESS | 30921 | Values: 163.0 156.0 157.0 161.0 156.0 161.0 158.0 158.0 157.0 159.0 23:45:04 INFO - PROCESS | 30921 | 23:45:04 INFO - PROCESS | 30921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.50 stddev:1.65 (2.6%) stddev-sans-first:1.67 23:45:04 INFO - PROCESS | 30921 | Values: 61.0 66.0 63.0 63.0 62.0 64.0 61.0 61.0 61.0 62.0 23:45:04 INFO - PROCESS | 30921 | -------- Summary: end -------- 23:45:04 INFO - PROCESS | 30921 | 23:45:05 INFO - TEST-INFO | 30921: exit 0 23:45:05 INFO - TEST-OK | kraken | took 62445ms 23:45:05 INFO - SUITE-END | took 1175s 23:45:05 INFO - Completed test suite (00:19:35) 23:45:05 INFO - javascript benchmark 23:45:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3175.7459403247744, 3180.2367431816356, 3207.214232513035, 3219.5009773485117, 3239.303549736808, 5497.835064948048, 5521.0062188806, 5549.4728000839905, 5646.068398714212, 5668.461496157864, 5448.945510544893, 5462.426445223254, 5485.040117979348, 5574.888502229963, 5577.274954255941, 4893.486183950678, 5002.474740152286, 5013.598912087043, 5034.144195486758, 5204.375474943012, 5114.974425127869, 5134.64057515974, 5167.328247327842, 5180.507851754077, 5186.507281808241, 4915.803367865281, 4962.305277261168, 5016.724080175589, 5034.446210916814, 5045.243213517965, 5007.349044624212, 5055.317532133152, 5127.897442051175, 5137.357830271216, 5177.042247184283, 4925.0, 5020.949790502122, 5037.596992240648, 5053.343065401511, 5061.848144555669, 5389.865253368658, 5461.344638643388, 5461.617686761928, 5549.417311182348, 5582.553395728332, 5980.342162362137, 6052.425019623172, 6063.302720187146, 6064.757409703607, 6065.120557519135, 5880.794172203952, 5903.232579764646, 6088.391160883921, 6094.412456491652, 6095.56092819221, 5547.389787123413, 5620.072688006515, 5672.943270567324, 5677.659340439587, 5680.829575112753, 6112.406112406109, 6122.908156377658, 6125.632462052291, 6135.877282454326, 6150.0, 5689.459501347365, 5820.738066787005, 5848.4151584841175, 5959.314678811992, 5976.940230597638, 5931.911021334683, 5986.0, 6018.097285407224, 6036.6679832609625, 6040.003399439043, 5920.37836027211, 5950.315713692894, 5991.610545314541, 6001.399860013964, 6006.639601623917, 5615.073512870413, 5904.675242861684, 5979.222701048836, 5994.670293133832, 6001.489873360726, 4543.250363689954, 4544.113897789965, 4546.1589605922345, 4547.1815073287125, 4575.26795712691, 4032.516098068251, 4040.030392705788, 4051.5160975416775, 4093.8567150150197, 4094.959050409517, 5519.0, 5581.2186293918885, 5596.468335508121, 5630.521405680481, 5643.153526970905, 6069.878602427927, 6071.301800292935, 6077.696115194222, 6079.604825686316, 6080.179175811298, 5680.091185410314, 5688.687122208318, 5695.629784064023, 5695.800646977419, 5697.0, 4892.755362231874, 4955.529224723646, 5002.824901128516, 5008.924866127011, 5009.624278179152, 6160.168377269101, 6194.876102477925, 6209.316975132731, 6210.689465526705, 6211.81364559064, 5910.468057874812, 5932.16949627044, 6024.9397506026135, 6042.274927008787, 6067.514598831995, 6111.04482360895, 6114.844865410725, 6117.694115294128, 6217.595856269328, 6221.253449586078, 5828.125781132864, 5866.882662346644, 5870.26621672291, 5907.143464197753, 5919.792807251898, 5839.678817665069, 5910.911336329872, 6000.189974353407, 6001.459868611846, 6018.277806663228, 5830.067189249699, 5878.617889837147, 5881.676507792113, 5881.911771323348, 5940.79207227745, 5870.55970802183, 5875.50058245054, 5882.088276317178, 5883.94116058834, 5884.08796636522, 5565.5825813063375, 5686.203931449518, 5752.7698892043845, 5769.711514424346, 5777.653340799566, 5944.286685597756, 5969.880602388015, 5987.401259874152, 5989.251343582052, 5996.100584912124, 5750.396208398225, 5778.508826749773, 5784.248047753933, 5786.0, 5806.477417032487], "value": 5500.619801986304, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.3551435478394, 623.7785586116928, 636.0967426254717, 639.0318667219162, 655.9409653131218, 31779.04662860115, 42320.51878184264, 42413.0, 42544.0, 42609.360859587134, 45584.54415455845, 45803.083938321215, 45833.312500312524, 45946.0, 46018.30972535415, 8740.300775937942, 8870.822583548324, 8877.51173685447, 8878.822423551524, 8880.73357799267, 8679.913200868004, 8730.563471826415, 8766.912330876674, 8792.34057445691, 8800.691975780848, 8850.336224783136, 8884.466931984069, 8887.822243555125, 8888.0, 8889.73330800077, 8782.25350845179, 8809.955950220208, 8815.074417186199, 8821.338399620054, 8822.558872056403, 8614.05245423003, 8672.696455624086, 8676.652933882637, 8680.956595217047, 8683.0, 43065.0, 43294.56705432937, 43335.56664433347, 43363.0, 43417.34873976893, 3423.5378223939697, 3785.318642644323, 3803.5055442792536, 3929.5873933236885, 4003.539592946819, 32158.0, 32213.355732885444, 32401.189970250933, 32457.83771081153, 33280.0, 4309.482862056573, 4314.61168494837, 4355.1725172217175, 4361.738295702268, 4534.478534968488, 350250.2487487572, 350963.0, 352339.0, 355450.0, 355465.0, 35168.47247291293, 57963.13055304089, 58225.417745822844, 58277.4172258272, 58379.70810145922, 35629.46555801665, 37890.0, 37924.0, 37977.43033854439, 38019.429708554395, 15738.213089345487, 15871.20643967797, 15871.28579213938, 15872.841271587367, 15877.603059923595, 13210.07529472947, 13256.668583285302, 13320.400581973641, 13322.267275299757, 13327.733445331232, 8759.649614015496, 8785.209331160226, 8788.95605521981, 8847.380683352103, 8848.601812918434, 3357.815320157366, 3376.74674399421, 3382.5771778527683, 3414.5902491701154, 3428.3486137633768, 1028.9794204116026, 1039.974969083849, 1060.8090543702053, 1060.8143574874366, 1162.7616338650557, 33635.495467568006, 33810.30948452568, 33835.5472348519, 34097.65902340945, 34350.65649343475, 1116.9553217871357, 1119.9496022679, 1139.1911742662803, 1167.2471256039807, 1174.2719513901436, 17368.39210627622, 17778.733319000225, 17879.46361609154, 17991.820081799277, 18029.368972085915, 559960.0, 563589.0, 564208.0, 564706.0, 564810.0, 58547.4145258542, 60602.39397606056, 60629.78740425255, 60673.69663151656, 60701.69649151726, 1274.796032634774, 1376.3531140364014, 1396.1483495068, 1422.907511011781, 1427.800107984883, 32875.0, 35025.64974350326, 35172.47241291332, 35201.1199720009, 35493.29013419769, 49597.0, 50195.0, 50262.74868625634, 50284.0, 50371.74814125906, 393093.0, 393383.0, 393654.0, 394580.0, 395794.0, 24313.75686243115, 25018.37454063663, 27802.304942376602, 29050.709492905647, 29654.406911862072, 48713.5128648709, 48797.02938391383, 48894.26658600053, 49223.0, 49823.0, 41028.589714102476, 41050.38424423696, 44248.55751442444, 44636.10727785361, 46558.0, 42325.576744233396, 42419.5758042428, 42883.7855810719, 43786.0, 43818.34272485979, 42496.15007699891, 43146.62693551885, 43196.0, 43894.0, 44168.116637666426, 35041.474377883846, 35735.82132089323, 36513.269734604626, 36608.0, 36630.63369366272, 28519.28701782472, 29176.0, 29247.7075229245, 29268.268293292836, 29429.26426839346], "value": 19251.51717399852, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.15713865145773, 412.19210347718473, 412.28468606966914, 413.8592878421338, 427.9101388708371, 13908.513202037931, 17876.91061544692, 18885.71671424926, 19418.70871936922, 19434.028298585068, 13815.240161791098, 17607.735883961752, 17627.471175864714, 18171.364002259892, 18859.15133818978, 5736.741846616902, 5894.410558944092, 5909.940900591004, 5915.556333275, 5936.109583562457, 5841.8831623367505, 5855.0, 5887.205227294303, 5897.675627840467, 5901.439363260483, 4211.4946206455115, 4438.42300500936, 6191.164192833978, 6479.1901012373455, 6489.31862154474, 6415.486761059151, 6505.804825855232, 6509.772157974472, 6510.18622672166, 6515.120458738104, 5652.971735141298, 5719.313682358102, 5756.0, 5792.304923409218, 5797.072468405035, 20864.0, 21220.89389553058, 21227.46931326714, 21470.248541300978, 21513.892430537748, 2337.100216416675, 2372.5254949010096, 2384.308550520347, 2557.258395065429, 2560.9615855762177, 13102.672433189151, 13907.721845563134, 14534.05528640635, 14558.854411455855, 14707.55877323682, 1900.6388786130592, 1950.4928718533142, 2003.5091402606454, 2059.3101311060814, 2108.704781330615, 82622.0, 82685.34291123615, 82692.0, 82980.58509707473, 83517.0, 10646.201534884915, 10752.301100428447, 10957.50691218897, 11072.667819965414, 11076.058535024453, 15321.080735155927, 15491.225438728019, 15509.689806204038, 15532.378704851679, 15539.145347005797, 8216.589170541569, 8442.197991190827, 8559.657613695383, 8580.313574914117, 8638.740837774876, 8290.958545207355, 8305.584720763938, 8338.791530211673, 8506.31949444043, 8540.121173341855, 5875.11873219012, 6663.566868153554, 6706.027625994204, 6729.0, 6835.350641689031, 1828.542864283929, 1908.8759230649964, 1976.723258743777, 1987.9006049697457, 2033.1460786469718, 958.4393130019017, 1049.9002594753485, 1050.7425680708275, 1108.392682032937, 1117.474786850179, 19152.138153783115, 19479.805201948082, 19568.90215548913, 19614.607707845764, 19870.205191792455, 1021.1626466297713, 1081.259337353915, 1105.3754628634956, 1147.913906457019, 1169.5380324771668, 11001.00990910806, 11148.665540033811, 11206.663800086011, 11244.887551124548, 11315.321080735182, 420779.0, 424915.0, 439328.0, 455332.0, 457126.0, 49828.7645550125, 61939.0, 80680.0, 81703.5914820422, 81726.0, 1061.766411389493, 1064.6380230721593, 1074.2856000759407, 1079.2660990526517, 1084.577014964149, 16787.244573993954, 16909.49271521807, 17237.741433878753, 17274.0, 17606.647867042844, 38960.0, 39261.214775704895, 39697.0, 39826.0, 39963.40054899121, 48322.0, 49705.50294497009, 50928.0, 52752.736236318575, 52978.470215297355, 7863.646135923783, 8223.0, 8295.958520207601, 8825.91174088251, 9076.18476304755, 8186.222308880647, 9860.753481163028, 12284.815727763911, 13151.356080489939, 13662.385192666552, 11821.349825759666, 11867.228630139014, 12081.51673933068, 12086.939565302117, 12129.45417456199, 11276.097912166842, 11332.206745527734, 11628.360440175871, 11735.178537502294, 11950.34273114987, 11837.76324473523, 12045.457954392248, 12072.577459788865, 12200.87799122033, 12213.206141600767, 7961.0, 8071.4753541019645, 8101.635426405708, 8105.675772969015, 8119.675212991414, 11754.118441117122, 11843.763124737285, 12020.158588899043, 12033.0, 12261.203021803554], "value": 9323.955554196004, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.3221855181687, 1529.128396813816, 1535.5239875638551, 1708.6326439815437, 1857.9814201857978, 8397.49615023099, 8665.090165532623, 8670.52312122833, 8965.731028069169, 9027.864582031274, 8773.824523509526, 8979.461232326048, 8986.2810975122, 9000.774980625487, 9006.189442950134, 2090.529630833058, 2342.8711420871846, 2373.655819906117, 2414.770596793302, 2425.3936515871032, 2421.8667973261463, 2430.1251549442186, 2430.8419952703107, 2431.890564924574, 2434.7686969737847, 2326.4183954011496, 2370.300761275419, 2372.193454225563, 2372.217168334454, 2373.608354621494, 2086.478380404899, 2320.211128216406, 2379.262428647125, 2387.85672859629, 2389.1637926725507, 2298.7356454007836, 2301.528186721718, 2304.7119110111234, 2331.720193576765, 2384.988075059631, 9057.456552606865, 9197.0, 9208.171264586219, 9209.401388909699, 9219.677311294072, 5472.7263636818, 5643.435656434365, 5643.520300774439, 5651.350094739117, 5654.63244889085, 3544.043108360728, 3571.14292569784, 3625.329314076904, 3637.0725465006517, 3657.0674478008204, 1226.6687994241504, 1249.2254802022712, 2190.539986602815, 2200.3508964855246, 2210.5910406574676, 12095.93952030243, 12364.876351236462, 12393.194442361219, 12430.813537796941, 12470.875291247063, 4787.3537072495465, 4801.4718380978065, 4806.44725856524, 4821.855344339676, 4916.631252656065, 10111.898881011242, 10113.342632728849, 10113.94943025295, 10129.392236465836, 10144.340617859816, 5025.773840177128, 5481.040817856939, 5485.61600687956, 5487.42382049887, 5491.258680078138, 4808.687435316693, 4872.342233798392, 4897.951020489821, 4922.212446008622, 4935.506449355035, 3853.325668008087, 3904.472896159039, 3949.565547789741, 3952.110775075585, 3984.920301593952, 1146.9827952580717, 1174.864890537582, 1206.7465832175167, 1216.5498765456837, 1226.0436859249799, 902.6163880350824, 930.7998780262276, 956.1012648110753, 1003.0721582536271, 1027.1584274812149, 5968.940310596925, 6048.848778780478, 6062.272527296753, 6065.5450841187085, 6069.423404776539, 490.629574671121, 493.55333423252017, 496.79879648742315, 1058.7564860082225, 1067.124957534814, 4218.725782824106, 4259.318509038539, 4261.190373828963, 4268.061026574149, 4269.935950960738, 11417.543298267976, 11511.136664750176, 11773.823392649118, 11781.057515398748, 11781.882181178249, 11604.361760103107, 11611.88388116125, 11617.709557260967, 11619.128565357632, 11631.88368116325, 8186.304164145995, 8460.534670593053, 8480.618372173267, 8497.957510212409, 8681.131886811268, 5999.430054144849, 6195.132681424694, 6303.905441418474, 6411.487081033413, 6542.476601871934, 10375.481225938522, 10544.52549635253, 10823.56705731762, 10826.67519974402, 10924.617638382619, 10854.837177442187, 10857.18571107186, 11040.834387484034, 11068.612598559012, 11097.112231021338, 7369.299916507923, 7852.293293603603, 7854.960725196337, 7973.44185906981, 8058.516489010678, 8545.2309292164, 8800.427972181787, 8809.414305425085, 8812.779680508038, 8835.911640883509, 7847.607619619111, 7962.283394494523, 8031.718889838828, 8141.552214628252, 8159.020917489939, 7916.366690664848, 8090.838183236185, 8098.79753006156, 8118.188181181835, 8149.348052155695, 6914.827129321807, 7188.317109874553, 7337.1929087799235, 7381.188069312479, 7400.962995184989, 5285.810692594136, 5287.127623942007, 5522.337319521683, 5556.833295001156, 5565.4991050804, 5352.0, 5354.2771725818075, 5372.328458942632, 5455.18172274162, 5525.198846167364], "value": 4796.652371128933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.9407315573463, 308.1863879358493, 313.0920331039984, 328.77971758921535, 329.4350189425135, 5162.845114646557, 5308.787648494065, 5393.379761327444, 5469.234307196991, 5487.423820498845, 5229.058769421503, 5291.947080529194, 5296.3379577552805, 5327.61482014676, 5388.353397592285, 4159.2721273777115, 4168.395582640515, 4187.979060104711, 4195.85314513992, 4232.851850185244, 4042.0, 4083.081306705982, 4135.958640413603, 4227.287948380896, 4239.91520169598, 4131.752094874317, 4136.9793151034355, 4143.337066069429, 4146.564610715876, 4150.584941505856, 4130.772807495587, 4162.583741625844, 4176.2691528982305, 4177.164567086595, 4182.100848317596, 3631.963680363189, 3693.0028892199225, 3693.316736403774, 3695.450139403803, 3708.4622729704042, 5148.330717006766, 5152.5105115014385, 5160.974195129038, 5174.611904107207, 5187.584993200573, 4709.340692303081, 4734.431868175807, 4805.486271824364, 4806.182948898696, 4837.105135549899, 5321.707306098163, 5563.527100196488, 5567.663760697404, 5571.997040532702, 5576.609637325389, 3977.3238549446373, 4003.8188734323444, 4006.6794656427414, 4012.8394864205397, 4013.7993100344866, 5785.2189954356045, 5789.334226563957, 5805.82582522525, 5848.619839710405, 5859.359379373739, 3847.2882516733935, 4100.01599616096, 4105.507339119325, 4108.835646574164, 4146.688998325138, 4067.1255680028935, 4086.3053280942313, 4091.6317531422314, 4115.403266526337, 4117.814698338582, 4670.579647831712, 4682.321063445781, 4705.270683044134, 4712.340272361872, 4734.597559207437, 5480.314960629921, 5577.972110139423, 5585.888282234332, 5598.832035038874, 5644.153376993484, 1403.862871074433, 1415.808865803124, 1420.1195258939524, 1426.486464872645, 1432.4914655297218, 872.1496540872598, 882.1178821178821, 883.7878909061778, 888.1739981816972, 896.3277541843618, 4839.830605928775, 4885.65800393969, 4910.361652985161, 4913.926291105637, 4928.260760885824, 5138.897222055538, 5153.330067091254, 5158.38099428071, 5184.533391994739, 5235.188545775411, 4692.718436893797, 4847.40035788189, 4896.730679812645, 4913.434954980152, 4931.161702510582, 2761.7376349247193, 2762.047093752652, 2762.2818067302646, 2778.4582006508535, 2782.3322402623226, 5305.469453054741, 5329.866753331198, 5346.5138710499805, 5393.0, 5402.621816472888, 5223.268742376071, 5229.52934235913, 5480.698561579072, 5980.936576880969, 5997.970010149921, 5995.940040599538, 6034.2155519782145, 6057.969710151421, 6073.878522429615, 6077.513798896078, 5653.321601407857, 5836.416358364129, 5876.647401155944, 5886.676232807236, 5904.734286957011, 5678.716064196857, 5719.713064560607, 5728.570357223308, 5740.368559458373, 5782.306123265067, 5968.910466343095, 5969.582129251085, 5975.774966131846, 6053.667048312385, 6056.454919057306, 3791.0, 3941.507311586052, 4051.9392209117477, 4091.3453847385467, 4103.0, 5692.402297758841, 5716.971415143064, 5733.541316694738, 5758.740856661377, 5759.452851979056, 5609.102543593006, 5610.887782244414, 5645.209670646031, 5646.463585959327, 5672.568096301843, 5335.359756829205, 5582.581306402118, 5624.156376543551, 5724.341700704473, 5745.425457454221, 5614.522765564972, 5620.381758006534, 5639.154126881001, 5643.294588176478, 5671.120976248688, 5634.0, 5634.915476267941, 5694.373618901835, 5737.426257374229, 5806.5064469518875, 5565.30433695788, 5599.496045355938, 5608.887822243614, 5609.859753506195, 5653.604247702786], "value": 4202.560128739024, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.7302444144549, 788.928996390325, 793.9523628582285, 834.370050611788, 834.4159088637955, 12773.169743966648, 13621.387037583308, 13625.386857591408, 13658.0, 13681.452741890325, 12554.874451255508, 12594.68513287166, 12604.873951260484, 12616.0, 12697.238165710041, 685.5201359048662, 813.60540138033, 828.8218033122878, 835.9707410240643, 836.9078352749659, 809.5142914251431, 811.926926576608, 814.6212011414691, 814.7352110564061, 818.0102076487457, 721.754603434832, 876.1720174435161, 876.447837862146, 877.3288434347697, 877.749841295231, 849.5964416901984, 871.1288711288711, 872.2455076358967, 872.9170728780756, 873.1661263493412, 636.2841802971657, 636.4876274598946, 638.3807706524664, 638.6838514935137, 639.5619000984341, 13084.0, 13118.47526098955, 13152.408141633554, 13156.144850584682, 13157.144785588906, 14068.296585170701, 14233.146011239358, 14293.714125717428, 14375.640608984755, 14481.637959051002, 11685.766284674344, 12102.152849300552, 12203.45084471201, 12325.322107284095, 12442.626721198378, 4404.22925987954, 4428.247197976351, 4431.534688857688, 4433.578810013044, 4438.911221775546, 58438.415615843725, 58559.0, 59271.0, 59950.70024649849, 60126.0, 11558.479868405942, 11593.826092608617, 11627.767444651228, 11708.531658733726, 11738.178327517162, 19739.605207895762, 19762.407127786188, 19785.10967006488, 19879.503012424804, 21354.039068241967, 8394.958025209957, 8465.534395608178, 8473.83052338962, 8502.44734092282, 8534.06125326226, 4591.77041147948, 4627.884302892455, 4629.65277604181, 4742.691725037862, 4789.161896668069, 3462.255615042778, 3471.4445688690193, 3541.256336169382, 3542.433210686278, 3543.858245670196, 590.7991282963727, 591.7130191856946, 624.7500999600196, 627.1721327847289, 664.299164381582, 645.0904225042667, 659.6075335175562, 666.73997141115, 674.642439507062, 674.9730010799611, 10174.287799854088, 10403.94798026005, 10454.163666906628, 10470.73823154412, 10540.367577945348, 658.8385845467891, 690.7049282595134, 770.818857568471, 777.525709317316, 805.4160733468188, 4707.364505791743, 4724.71651700899, 4837.516248375135, 4860.3681521401995, 4871.975640121847, 69035.30964690288, 70448.64775676088, 71939.0, 71989.6400517994, 72197.2780272201, 62861.68569157125, 63014.36985630177, 63881.36118638847, 64313.67843160848, 64817.67591162014, 57923.4207657918, 58088.41911580915, 58192.41807581954, 58327.125093123635, 58445.70777146087, 9499.905000949902, 9538.666146684833, 9567.61729530811, 9665.855012174683, 9711.902880971382, 37930.81034594809, 38893.0, 38953.610463895, 39798.104614894386, 39803.80098099491, 45868.54131458643, 47077.764611176724, 47119.05249948706, 51612.74193629008, 51957.740211298704, 11637.825432618349, 11681.766364672829, 11758.470868810753, 12078.0, 12222.0, 12635.1787133836, 12982.221066736027, 13196.670083247996, 13466.730665386833, 13492.107117336076, 12349.444275007467, 12391.814122787984, 12775.872241277468, 12799.168054076456, 12843.165194262343, 12296.50813967467, 12360.81458778137, 12824.422900969665, 12887.42006609686, 13230.4046317914, 12242.387880605756, 12258.203216791237, 12976.870231297566, 12991.0, 13000.479980801041, 9880.35777674449, 9942.801143977225, 10093.596256149673, 10205.948970255102, 10313.22650801178, 9684.32209745311, 9795.33328024212, 10417.467632652257, 10556.524956376828, 10574.2598018138], "value": 6943.1909884341585, "unit": "score"}], "name": "dromaeo_css", "value": 7190.432807225908, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 123.0, 152.0, 207.0, 132.0, 153.0, 167.0, 126.0, 180.0, 127.0], "value": 153.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 159.0, 162.0, 162.0, 161.0, 163.0, 161.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 154.0, 75.0, 74.0, 75.0, 75.0, 131.0, 74.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 145.0, 91.0, 90.0, 92.0, 120.0, 91.0, 91.0, 118.0], "value": 102.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 148.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 96.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 56.0, 56.0, 55.0, 108.0, 53.0, 56.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 80.0, 78.0, 79.0, 80.0, 80.0, 80.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 122.0, 126.0, 130.0, 126.0, 128.0, 119.0, 127.0, 188.0, 119.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 156.0, 157.0, 161.0, 156.0, 161.0, 158.0, 158.0, 157.0, 159.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 63.0, 63.0, 62.0, 64.0, 61.0, 61.0, 61.0, 62.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1521.8, "alertThreshold": 2.0}]} 23:45:05 INFO - Return code: 0 23:45:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:45:05 ERROR - # TBPL SUCCESS # 23:45:05 INFO - Running post-action listener: _resource_record_post_action 23:45:05 INFO - ##### 23:45:05 INFO - ##### Finished run-tests step (success) 23:45:05 INFO - ##### 23:45:05 INFO - Running post-run listener: _resource_record_post_run 23:45:05 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 544845824; Read time: 12; Write time: 474448 23:45:05 INFO - TinderboxPrint: CPU usage
13.1% 23:45:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:45:05 INFO - TinderboxPrint: I/O write bytes / time
544,845,824 / 474,448 23:45:05 INFO - TinderboxPrint: CPU idle
8,269.7 (86.9%) 23:45:05 INFO - TinderboxPrint: CPU user
1,205.5 (12.7%) 23:45:05 INFO - TinderboxPrint: Swap in / out
0 / 0 23:45:05 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 255725568; Read time: 0; Write time: 224552 23:45:05 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289120256; Read time: 12; Write time: 249896 23:45:06 INFO - Running post-run listener: _upload_blobber_files 23:45:06 INFO - Blob upload gear active. 23:45:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:45:06 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 23:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:45:06 INFO - (blobuploader) - INFO - Open directory for files ... 23:45:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:45:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:45:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:45:07 INFO - (blobuploader) - INFO - Done attempting. 23:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:45:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:45:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:45:08 INFO - (blobuploader) - INFO - Done attempting. 23:45:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:45:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:45:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:45:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:45:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:45:12 INFO - (blobuploader) - INFO - Done attempting. 23:45:12 INFO - (blobuploader) - INFO - Iteration through files over. 23:45:12 INFO - Return code: 0 23:45:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:45:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:45:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de170438edcbed010b4fd92fbf3a8538526c48f68993aca68d2d006ae44441ccacf87ffbe84b160554b1b2cc8d57b7a8a4bbe0b7885ca3be02122c5d27b7e086", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46255ca3263eb661c4b67847e595d6e1ccdddafcaf42e13fc30ff201d1e2ccda8fcee48ac51bd2eab6e05d9e6dea430f1c4253ebf3bb3dd74803b996ff5b3619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c8f284a912f42bf78089aecadbddaf49a24abccf99c05dffbaab52fc8b771b04b7fb9d97149f7d1cc55c225856cd929224dd607f0b55f782ac6b9b8924e2a62"} 23:45:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:45:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:45:12 INFO - Contents: 23:45:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de170438edcbed010b4fd92fbf3a8538526c48f68993aca68d2d006ae44441ccacf87ffbe84b160554b1b2cc8d57b7a8a4bbe0b7885ca3be02122c5d27b7e086", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46255ca3263eb661c4b67847e595d6e1ccdddafcaf42e13fc30ff201d1e2ccda8fcee48ac51bd2eab6e05d9e6dea430f1c4253ebf3bb3dd74803b996ff5b3619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c8f284a912f42bf78089aecadbddaf49a24abccf99c05dffbaab52fc8b771b04b7fb9d97149f7d1cc55c225856cd929224dd607f0b55f782ac6b9b8924e2a62"} 23:45:12 INFO - Running post-run listener: copy_logs_to_upload_dir 23:45:12 INFO - Copying logs to upload dir... 23:45:12 INFO - mkdir: /builds/slave/test/build/upload/logs 23:45:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.259587 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-05-17 23:45:11.937640) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 23:45:11.940869) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de170438edcbed010b4fd92fbf3a8538526c48f68993aca68d2d006ae44441ccacf87ffbe84b160554b1b2cc8d57b7a8a4bbe0b7885ca3be02122c5d27b7e086", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46255ca3263eb661c4b67847e595d6e1ccdddafcaf42e13fc30ff201d1e2ccda8fcee48ac51bd2eab6e05d9e6dea430f1c4253ebf3bb3dd74803b996ff5b3619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c8f284a912f42bf78089aecadbddaf49a24abccf99c05dffbaab52fc8b771b04b7fb9d97149f7d1cc55c225856cd929224dd607f0b55f782ac6b9b8924e2a62"} build_url:https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011135 build_url: 'https://queue.taskcluster.net/v1/task/eq8WVKzNTlyPA-bmWDSolA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de170438edcbed010b4fd92fbf3a8538526c48f68993aca68d2d006ae44441ccacf87ffbe84b160554b1b2cc8d57b7a8a4bbe0b7885ca3be02122c5d27b7e086", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46255ca3263eb661c4b67847e595d6e1ccdddafcaf42e13fc30ff201d1e2ccda8fcee48ac51bd2eab6e05d9e6dea430f1c4253ebf3bb3dd74803b996ff5b3619", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c8f284a912f42bf78089aecadbddaf49a24abccf99c05dffbaab52fc8b771b04b7fb9d97149f7d1cc55c225856cd929224dd607f0b55f782ac6b9b8924e2a62"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 23:45:11.964944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 23:45:11.965280) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1463551099.579642-297917759 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 23:45:12.049311) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 23:45:12.049631) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 23:45:12.049929) ========= ========= Total master_lag: 0.18 =========