builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1464328593.08 results: success (0) buildid: 20160526203033 builduid: ec42c199b77a4615b5d92b49bd65bc77 revision: f2125a888d23ff0a5b6df4c31bc66f0132610750 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.080878) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.081531) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.081973) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.110552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.110849) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-26 22:56: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% 222M=0s 2016-05-26 22:56:33 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115317 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.239628) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.239920) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018984 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:33.290540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 22:56:33.290934) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f2125a888d23ff0a5b6df4c31bc66f0132610750 --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 f2125a888d23ff0a5b6df4c31bc66f0132610750 --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=c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-26 22:56:33,961 truncating revision to first 12 chars 2016-05-26 22:56:33,961 Setting DEBUG logging. 2016-05-26 22:56:33,961 attempt 1/10 2016-05-26 22:56:33,961 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f2125a888d23?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 22:56:34,607 unpacking tar archive at: fx-team-f2125a888d23/testing/mozharness/ program finished with exit code 0 elapsedTime=1.020748 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 22:56:34.324961) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:34.325274) ========= script_repo_revision: f2125a888d23ff0a5b6df4c31bc66f0132610750 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:34.325718) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:34.326183) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 22:56:34.336780) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-26 22:56:34.337224) ========= /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=c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:56:35 INFO - MultiFileLogger online at 20160526 22:56:35 in /builds/slave/test 22:56: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 22:56:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:56:35 INFO - {'append_to_log': False, 22:56:35 INFO - 'base_work_dir': '/builds/slave/test', 22:56:35 INFO - 'blob_upload_branch': 'Fx-Team', 22:56:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:56:35 INFO - 'branch': 'Fx-Team', 22:56:35 INFO - 'buildbot_json_path': 'buildprops.json', 22:56:35 INFO - 'config_files': ('talos/linux_config.py',), 22:56:35 INFO - 'default_actions': ('clobber', 22:56:35 INFO - 'read-buildbot-config', 22:56:35 INFO - 'download-and-extract', 22:56:35 INFO - 'populate-webroot', 22:56:35 INFO - 'create-virtualenv', 22:56:35 INFO - 'install', 22:56:35 INFO - 'run-tests'), 22:56:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:56:35 INFO - 'download_minidump_stackwalk': True, 22:56:35 INFO - 'download_symbols': 'ondemand', 22:56:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:56:35 INFO - 'tooltool.py': '/tools/tooltool.py', 22:56:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:56:35 INFO - '/tools/misc-python/virtualenv.py')}, 22:56:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:56:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:56:35 INFO - 'installer_path': 'installer.exe', 22:56:35 INFO - 'log_level': 'info', 22:56:35 INFO - 'log_name': 'talos', 22:56:35 INFO - 'log_to_console': True, 22:56:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:56:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:56:35 INFO - 'opt_config_files': (), 22:56:35 INFO - 'pip_index': False, 22:56:35 INFO - 'sps_profile': False, 22:56:35 INFO - 'sps_profile_interval': 0, 22:56:35 INFO - 'suite': 'dromaeojs', 22:56:35 INFO - 'system_bits': '32', 22:56:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:56:35 INFO - 'title': 'talos-linux64-ix-004', 22:56:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:56:35 INFO - 'use_talos_json': True, 22:56:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:56:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:56:35 INFO - 'work_dir': 'build'} 22:56:35 INFO - ##### 22:56:35 INFO - ##### Running clobber step. 22:56:35 INFO - ##### 22:56:35 INFO - Running pre-action listener: _resource_record_pre_action 22:56:35 INFO - Running main action method: clobber 22:56:35 INFO - rmtree: /builds/slave/test/build 22:56:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:56:35 INFO - Running post-action listener: _resource_record_post_action 22:56:35 INFO - ##### 22:56:35 INFO - ##### Finished clobber step (success) 22:56:35 INFO - ##### 22:56:35 INFO - ##### 22:56:35 INFO - ##### Running read-buildbot-config step. 22:56:35 INFO - ##### 22:56:35 INFO - Running pre-action listener: _resource_record_pre_action 22:56:35 INFO - Running main action method: read_buildbot_config 22:56:35 INFO - Using buildbot properties: 22:56:35 INFO - { 22:56:35 INFO - "project": "", 22:56:35 INFO - "product": "firefox", 22:56:35 INFO - "script_repo_revision": "production", 22:56:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:56:35 INFO - "repository": "", 22:56:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:56:35 INFO - "buildid": "20160526203033", 22:56:35 INFO - "pgo_build": "True", 22:56:35 INFO - "basedir": "/builds/slave/test", 22:56:35 INFO - "buildnumber": 724, 22:56:35 INFO - "slavename": "talos-linux64-ix-004", 22:56:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:56:35 INFO - "platform": "ubuntu64_hw", 22:56:35 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:56:35 INFO - "branch": "fx-team", 22:56:35 INFO - "repo_path": "integration/fx-team", 22:56:35 INFO - "revision": "f2125a888d23ff0a5b6df4c31bc66f0132610750", 22:56:35 INFO - "stage_platform": "linux64-pgo", 22:56:35 INFO - "builduid": "ec42c199b77a4615b5d92b49bd65bc77", 22:56:35 INFO - "slavebuilddir": "test" 22:56:35 INFO - } 22:56:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:56:35 INFO - Running post-action listener: _resource_record_post_action 22:56:35 INFO - ##### 22:56:35 INFO - ##### Finished read-buildbot-config step (success) 22:56:35 INFO - ##### 22:56:35 INFO - ##### 22:56:35 INFO - ##### Running download-and-extract step. 22:56:35 INFO - ##### 22:56:35 INFO - Running pre-action listener: _resource_record_pre_action 22:56:35 INFO - Running main action method: download_and_extract 22:56:35 INFO - mkdir: /builds/slave/test/build/tests 22:56: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')]} 22:56:35 INFO - https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:56:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:56:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:56:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:37 INFO - Downloaded 1361 bytes. 22:56:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:56:37 INFO - Using the following test package requirements: 22:56:37 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:56:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:56:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:56:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'jsshell-linux-x86_64.zip'], 22:56:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:56:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:56:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:56:37 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:56:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:56:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:56:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:56:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:56:37 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')]} 22:56:37 INFO - https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:56:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:56:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:56:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:38 INFO - Downloaded 24410781 bytes. 22:56:38 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'] 22:56:38 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 22:56:39 INFO - Return code: 0 22:56:39 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 22:56:39 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')]} 22:56:39 INFO - https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:56:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:56:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:56:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:43 INFO - Downloaded 24410781 bytes. 22:56:43 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'] 22:56:43 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 22:56:44 INFO - Return code: 0 22:56: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')]} 22:56:44 INFO - https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:56:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:56:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:56:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:45 INFO - Downloaded 10834034 bytes. 22:56: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'] 22:56: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 22:56:45 INFO - Return code: 0 22:56: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')]} 22:56:45 INFO - https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:56:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:56:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:56:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JJttUPQVRKWpZSNCN0uiWw/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 22:56:47 INFO - Downloaded 58702468 bytes. 22:56:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:56:47 INFO - mkdir: /builds/slave/test/properties 22:56:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:56:47 INFO - Writing to file /builds/slave/test/properties/build_url 22:56:47 INFO - Contents: 22:56:47 INFO - build_url:https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:56:48 INFO - Running post-action listener: _resource_record_post_action 22:56:48 INFO - Running post-action listener: set_extra_try_arguments 22:56:48 INFO - ##### 22:56:48 INFO - ##### Finished download-and-extract step (success) 22:56:48 INFO - ##### 22:56:48 INFO - ##### 22:56:48 INFO - ##### Running populate-webroot step. 22:56:48 INFO - ##### 22:56:48 INFO - Running pre-action listener: _resource_record_pre_action 22:56:48 INFO - Running main action method: populate_webroot 22:56:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:56:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:56:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:56:48 INFO - '--tppagecycles', 22:56:48 INFO - '7'], 22:56:48 INFO - 'tests': ['tsvgm']}}, 22:56:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:56:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:56:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:56:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:56:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:56:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['damp', 'tps']}, 22:56:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'tests': ['damp', 'tps']}, 22:56:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['dromaeo_dom']}, 22:56:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:56:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['a11yr', 22:56:48 INFO - 'ts_paint', 22:56:48 INFO - 'tpaint', 22:56:48 INFO - 'sessionrestore', 22:56:48 INFO - 'sessionrestore_no_auto_restore', 22:56:48 INFO - 'tabpaint']}, 22:56:48 INFO - 'other-e10s': {'tests': ['a11yr', 22:56:48 INFO - 'ts_paint', 22:56:48 INFO - 'tpaint', 22:56:48 INFO - 'sessionrestore', 22:56:48 INFO - 'sessionrestore_no_auto_restore', 22:56:48 INFO - 'tabpaint']}, 22:56:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:56:48 INFO - 'ts_paint', 22:56:48 INFO - 'tpaint', 22:56:48 INFO - 'sessionrestore', 22:56:48 INFO - 'sessionrestore_no_auto_restore', 22:56:48 INFO - 'tabpaint']}, 22:56:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:56:48 INFO - 'ts_paint', 22:56:48 INFO - 'tpaint', 22:56:48 INFO - 'sessionrestore', 22:56:48 INFO - 'sessionrestore_no_auto_restore', 22:56:48 INFO - 'tabpaint']}, 22:56:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['a11yr', 22:56:48 INFO - 'ts_paint', 22:56:48 INFO - 'tpaint', 22:56:48 INFO - 'sessionrestore', 22:56:48 INFO - 'sessionrestore_no_auto_restore', 22:56:48 INFO - 'tabpaint']}, 22:56:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['a11yr', 22:56:48 INFO - 'ts_paint', 22:56:48 INFO - 'tpaint', 22:56:48 INFO - 'sessionrestore', 22:56:48 INFO - 'sessionrestore_no_auto_restore', 22:56:48 INFO - 'tabpaint']}, 22:56:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['tsvgx', 22:56:48 INFO - 'tsvgr_opacity', 22:56:48 INFO - 'tart', 22:56:48 INFO - 'tscrollx', 22:56:48 INFO - 'cart']}, 22:56:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:56:48 INFO - 'tsvgr_opacity', 22:56:48 INFO - 'tart', 22:56:48 INFO - 'tscrollx', 22:56:48 INFO - 'cart']}, 22:56:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'talos_options': ['--disable-e10s'], 22:56:48 INFO - 'tests': ['tp5o']}, 22:56:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'tests': ['tp5o']}, 22:56:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'talos_options': ['--disable-e10s', 22:56:48 INFO - '--xperf_path', 22:56:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:48 INFO - 'tests': ['tp5n']}, 22:56:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:56:48 INFO - 'talos_options': ['--xperf_path', 22:56:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:56:48 INFO - 'tests': ['tp5n']}}, 22:56:48 INFO - 'talos.zip': {'path': '', 22:56:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:56:48 INFO - Running post-action listener: _resource_record_post_action 22:56:48 INFO - ##### 22:56:48 INFO - ##### Finished populate-webroot step (success) 22:56:48 INFO - ##### 22:56:48 INFO - ##### 22:56:48 INFO - ##### Running create-virtualenv step. 22:56:48 INFO - ##### 22:56:48 INFO - Running pre-action listener: _resource_record_pre_action 22:56:48 INFO - Running main action method: create_virtualenv 22:56:48 INFO - Creating virtualenv /builds/slave/test/build/venv 22:56:48 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 22:56:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:56:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:56:48 INFO - Using real prefix '/usr' 22:56:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:56:49 INFO - Installing distribute.............................................................................................................................................................................................done. 22:56:51 INFO - Installing pip.................done. 22:56:51 INFO - Return code: 0 22:56:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:56:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:56:51 INFO - Using env: {'DISPLAY': ':0', 22:56:51 INFO - 'HOME': '/home/cltbld', 22:56:51 INFO - 'LANG': 'en_US.UTF-8', 22:56:51 INFO - 'LANGUAGE': 'en_US:en', 22:56:51 INFO - 'LOGNAME': 'cltbld', 22:56:51 INFO - 'MAIL': '/var/mail/cltbld', 22:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:51 INFO - 'MOZ_NO_REMOTE': '1', 22:56:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:51 INFO - 'NO_EM_RESTART': '1', 22:56:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:51 INFO - 'PWD': '/builds/slave/test', 22:56:51 INFO - 'SHELL': '/bin/bash', 22:56:51 INFO - 'SHLVL': '1', 22:56:51 INFO - 'TERM': 'linux', 22:56:51 INFO - 'TMOUT': '86400', 22:56:51 INFO - 'USER': 'cltbld', 22:56:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:51 INFO - '_': '/tools/buildbot/bin/python'} 22:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:51 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 22:56:51 INFO - Cleaning up... 22:56:51 INFO - Return code: 0 22:56:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:56:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:56:51 INFO - Using env: {'DISPLAY': ':0', 22:56:51 INFO - 'HOME': '/home/cltbld', 22:56:51 INFO - 'LANG': 'en_US.UTF-8', 22:56:51 INFO - 'LANGUAGE': 'en_US:en', 22:56:51 INFO - 'LOGNAME': 'cltbld', 22:56:51 INFO - 'MAIL': '/var/mail/cltbld', 22:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:51 INFO - 'MOZ_NO_REMOTE': '1', 22:56:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:51 INFO - 'NO_EM_RESTART': '1', 22:56:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:51 INFO - 'PWD': '/builds/slave/test', 22:56:51 INFO - 'SHELL': '/bin/bash', 22:56:51 INFO - 'SHLVL': '1', 22:56:51 INFO - 'TERM': 'linux', 22:56:51 INFO - 'TMOUT': '86400', 22:56:51 INFO - 'USER': 'cltbld', 22:56:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:51 INFO - '_': '/tools/buildbot/bin/python'} 22:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:51 INFO - Downloading/unpacking psutil>=3.1.1 22:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:56:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:53 INFO - Installing collected packages: psutil 22:56:53 INFO - Running setup.py install for psutil 22:56:53 INFO - building 'psutil._psutil_linux' extension 22:56:53 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 22:56:53 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 22:56:53 INFO - building 'psutil._psutil_posix' extension 22:56:53 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 22:56:53 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 22:56:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:56:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:56:54 INFO - Successfully installed psutil 22:56:54 INFO - Cleaning up... 22:56:54 INFO - Return code: 0 22:56:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:56:54 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')]} 22:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:54 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')]} 22:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:54 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 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:56:54 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 22:56:54 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 22:56:54 INFO - Using env: {'DISPLAY': ':0', 22:56:54 INFO - 'HOME': '/home/cltbld', 22:56:54 INFO - 'LANG': 'en_US.UTF-8', 22:56:54 INFO - 'LANGUAGE': 'en_US:en', 22:56:54 INFO - 'LOGNAME': 'cltbld', 22:56:54 INFO - 'MAIL': '/var/mail/cltbld', 22:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:54 INFO - 'MOZ_NO_REMOTE': '1', 22:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:54 INFO - 'NO_EM_RESTART': '1', 22:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:54 INFO - 'PWD': '/builds/slave/test', 22:56:54 INFO - 'SHELL': '/bin/bash', 22:56:54 INFO - 'SHLVL': '1', 22:56:54 INFO - 'TERM': 'linux', 22:56:54 INFO - 'TMOUT': '86400', 22:56:54 INFO - 'USER': 'cltbld', 22:56:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:54 INFO - '_': '/tools/buildbot/bin/python'} 22:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:56:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:56:56 INFO - Installing collected packages: mozsystemmonitor 22:56:56 INFO - Running setup.py install for mozsystemmonitor 22:56:56 INFO - Successfully installed mozsystemmonitor 22:56:56 INFO - Cleaning up... 22:56:56 INFO - Return code: 0 22:56:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:56:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:56:56 INFO - Using env: {'DISPLAY': ':0', 22:56:56 INFO - 'HOME': '/home/cltbld', 22:56:56 INFO - 'LANG': 'en_US.UTF-8', 22:56:56 INFO - 'LANGUAGE': 'en_US:en', 22:56:56 INFO - 'LOGNAME': 'cltbld', 22:56:56 INFO - 'MAIL': '/var/mail/cltbld', 22:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:56 INFO - 'MOZ_NO_REMOTE': '1', 22:56:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:56 INFO - 'NO_EM_RESTART': '1', 22:56:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:56 INFO - 'PWD': '/builds/slave/test', 22:56:56 INFO - 'SHELL': '/bin/bash', 22:56:56 INFO - 'SHLVL': '1', 22:56:56 INFO - 'TERM': 'linux', 22:56:56 INFO - 'TMOUT': '86400', 22:56:56 INFO - 'USER': 'cltbld', 22:56:56 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:56 INFO - '_': '/tools/buildbot/bin/python'} 22:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:56:56 INFO - Downloading/unpacking blobuploader==1.2.4 22:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:58 INFO - Downloading blobuploader-1.2.4.tar.gz 22:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:56:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:56:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:56:59 INFO - Downloading docopt-0.6.1.tar.gz 22:56:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:56:59 INFO - Installing collected packages: blobuploader, requests, docopt 22:56:59 INFO - Running setup.py install for blobuploader 22:56:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:56:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:56:59 INFO - Running setup.py install for requests 22:56:59 INFO - Running setup.py install for docopt 22:56:59 INFO - Successfully installed blobuploader requests docopt 22:56:59 INFO - Cleaning up... 22:56:59 INFO - Return code: 0 22:56:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:56:59 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')]} 22:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:59 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')]} 22:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:56:59 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 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:56:59 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 22:56:59 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 22:56:59 INFO - Using env: {'DISPLAY': ':0', 22:56:59 INFO - 'HOME': '/home/cltbld', 22:56:59 INFO - 'LANG': 'en_US.UTF-8', 22:56:59 INFO - 'LANGUAGE': 'en_US:en', 22:56:59 INFO - 'LOGNAME': 'cltbld', 22:56:59 INFO - 'MAIL': '/var/mail/cltbld', 22:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:56:59 INFO - 'MOZ_NO_REMOTE': '1', 22:56:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:56:59 INFO - 'NO_EM_RESTART': '1', 22:56:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:56:59 INFO - 'PWD': '/builds/slave/test', 22:56:59 INFO - 'SHELL': '/bin/bash', 22:56:59 INFO - 'SHLVL': '1', 22:56:59 INFO - 'TERM': 'linux', 22:56:59 INFO - 'TMOUT': '86400', 22:56:59 INFO - 'USER': 'cltbld', 22:56:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:56:59 INFO - '_': '/tools/buildbot/bin/python'} 22:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:57:00 INFO - Running setup.py (path:/tmp/pip-xFmvJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:57:00 INFO - Running setup.py (path:/tmp/pip-y5WRCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:57:00 INFO - Running setup.py (path:/tmp/pip-uE121M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:57:00 INFO - Running setup.py (path:/tmp/pip-asxkzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:57:00 INFO - Running setup.py (path:/tmp/pip-Eb8fX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:57:00 INFO - Running setup.py (path:/tmp/pip-yFAeiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:57:00 INFO - Running setup.py (path:/tmp/pip-OexMeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:57:00 INFO - Running setup.py (path:/tmp/pip-jMDWgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:57:00 INFO - Running setup.py (path:/tmp/pip-HXTR2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:57:00 INFO - Running setup.py (path:/tmp/pip-RbeaBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:57:00 INFO - Running setup.py (path:/tmp/pip-tzx6sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:57:00 INFO - Running setup.py (path:/tmp/pip-mlKKt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:57:00 INFO - Running setup.py (path:/tmp/pip-v3ABHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:57:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:57:01 INFO - Running setup.py (path:/tmp/pip-7QBfwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:01 INFO - Running setup.py (path:/tmp/pip-JSQh0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:57:01 INFO - Running setup.py (path:/tmp/pip-tZasdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:57:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:57:01 INFO - Running setup.py (path:/tmp/pip-zKIQbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:57:01 INFO - Running setup.py install for manifestparser 22:57:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for mozcrash 22:57:01 INFO - Running setup.py install for mozdebug 22:57:01 INFO - Running setup.py install for mozdevice 22:57:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for mozfile 22:57:01 INFO - Running setup.py install for mozhttpd 22:57:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for mozinfo 22:57:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:57:01 INFO - Running setup.py install for mozInstall 22:57:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Running setup.py install for mozleak 22:57:02 INFO - Running setup.py install for mozlog 22:57:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Running setup.py install for moznetwork 22:57:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Running setup.py install for mozprocess 22:57:02 INFO - Running setup.py install for mozprofile 22:57:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Running setup.py install for mozrunner 22:57:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:57:02 INFO - Running setup.py install for mozscreenshot 22:57:02 INFO - Running setup.py install for moztest 22:57:02 INFO - Running setup.py install for mozversion 22:57:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:57:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:57:03 INFO - Cleaning up... 22:57:03 INFO - Return code: 0 22:57:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57:03 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')]} 22:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:03 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')]} 22:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:03 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 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:57:03 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 22:57:03 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 22:57:03 INFO - Using env: {'DISPLAY': ':0', 22:57:03 INFO - 'HOME': '/home/cltbld', 22:57:03 INFO - 'LANG': 'en_US.UTF-8', 22:57:03 INFO - 'LANGUAGE': 'en_US:en', 22:57:03 INFO - 'LOGNAME': 'cltbld', 22:57:03 INFO - 'MAIL': '/var/mail/cltbld', 22:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:03 INFO - 'MOZ_NO_REMOTE': '1', 22:57:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:03 INFO - 'NO_EM_RESTART': '1', 22:57:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:03 INFO - 'PWD': '/builds/slave/test', 22:57:03 INFO - 'SHELL': '/bin/bash', 22:57:03 INFO - 'SHLVL': '1', 22:57:03 INFO - 'TERM': 'linux', 22:57:03 INFO - 'TMOUT': '86400', 22:57:03 INFO - 'USER': 'cltbld', 22:57:03 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:03 INFO - '_': '/tools/buildbot/bin/python'} 22:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:57:03 INFO - Running setup.py (path:/tmp/pip-G9QDTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:57:03 INFO - Running setup.py (path:/tmp/pip-ksoKer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:57:03 INFO - Running setup.py (path:/tmp/pip-bFjEmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:57:03 INFO - Running setup.py (path:/tmp/pip-0eyKp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:57:03 INFO - Running setup.py (path:/tmp/pip-ddexkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:57:03 INFO - Running setup.py (path:/tmp/pip-HhHbRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:57:03 INFO - Running setup.py (path:/tmp/pip-N6m5n4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:57:03 INFO - Running setup.py (path:/tmp/pip-nbh1pH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:57:03 INFO - Running setup.py (path:/tmp/pip-jcyw50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:57:03 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)) 22:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:57:03 INFO - Running setup.py (path:/tmp/pip-TUldrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:57:04 INFO - Running setup.py (path:/tmp/pip-7JUq3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:57:04 INFO - Running setup.py (path:/tmp/pip-N7kpiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:57:04 INFO - Running setup.py (path:/tmp/pip-yGpQxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:57:04 INFO - Running setup.py (path:/tmp/pip-DPVOGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:04 INFO - Running setup.py (path:/tmp/pip-T5eNNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:57:04 INFO - Running setup.py (path:/tmp/pip-v3r74h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:57:04 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)) 22:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:57:04 INFO - Running setup.py (path:/tmp/pip-ezqNUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 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)) 22:57:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:57:04 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 22:57:04 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 22:57:04 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 22:57:04 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 22:57:04 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 22:57:04 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 22:57:06 INFO - Downloading blessings-1.6.tar.gz 22:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:57:06 INFO - Installing collected packages: blessings 22:57:06 INFO - Running setup.py install for blessings 22:57:06 INFO - Successfully installed blessings 22:57:06 INFO - Cleaning up... 22:57:06 INFO - Return code: 0 22:57:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:57:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:06 INFO - Reading from file tmpfile_stdout 22:57:06 INFO - Current package versions: 22:57:06 INFO - argparse == 1.2.1 22:57:06 INFO - blessings == 1.6 22:57:06 INFO - blobuploader == 1.2.4 22:57:06 INFO - docopt == 0.6.1 22:57:06 INFO - manifestparser == 1.1 22:57:06 INFO - mozInstall == 1.12 22:57:06 INFO - mozcrash == 0.17 22:57:06 INFO - mozdebug == 0.1 22:57:06 INFO - mozdevice == 0.48 22:57:06 INFO - mozfile == 1.2 22:57:06 INFO - mozhttpd == 0.7 22:57:06 INFO - mozinfo == 0.9 22:57:06 INFO - mozleak == 0.1 22:57:06 INFO - mozlog == 3.2 22:57:06 INFO - moznetwork == 0.27 22:57:06 INFO - mozprocess == 0.22 22:57:06 INFO - mozprofile == 0.28 22:57:06 INFO - mozrunner == 6.11 22:57:06 INFO - mozscreenshot == 0.1 22:57:06 INFO - mozsystemmonitor == 0.3 22:57:06 INFO - moztest == 0.7 22:57:06 INFO - mozversion == 1.4 22:57:06 INFO - psutil == 3.1.1 22:57:06 INFO - requests == 1.2.3 22:57:06 INFO - wsgiref == 0.1.2 22:57:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:57: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')]} 22:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57: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')]} 22:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:06 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 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:57:06 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 22:57:06 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 22:57:06 INFO - Using env: {'DISPLAY': ':0', 22:57:06 INFO - 'HOME': '/home/cltbld', 22:57:06 INFO - 'LANG': 'en_US.UTF-8', 22:57:06 INFO - 'LANGUAGE': 'en_US:en', 22:57:06 INFO - 'LOGNAME': 'cltbld', 22:57:06 INFO - 'MAIL': '/var/mail/cltbld', 22:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:06 INFO - 'MOZ_NO_REMOTE': '1', 22:57:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:06 INFO - 'NO_EM_RESTART': '1', 22:57:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:06 INFO - 'PWD': '/builds/slave/test', 22:57:06 INFO - 'SHELL': '/bin/bash', 22:57:06 INFO - 'SHLVL': '1', 22:57:06 INFO - 'TERM': 'linux', 22:57:06 INFO - 'TMOUT': '86400', 22:57:06 INFO - 'USER': 'cltbld', 22:57:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:06 INFO - '_': '/tools/buildbot/bin/python'} 22:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 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)) 22:57:07 INFO - Cleaning up... 22:57:07 INFO - Return code: 0 22:57:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:57:07 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')]} 22:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:07 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')]} 22:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:07 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 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:57:07 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 22:57:07 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 22:57:07 INFO - Using env: {'DISPLAY': ':0', 22:57:07 INFO - 'HOME': '/home/cltbld', 22:57:07 INFO - 'LANG': 'en_US.UTF-8', 22:57:07 INFO - 'LANGUAGE': 'en_US:en', 22:57:07 INFO - 'LOGNAME': 'cltbld', 22:57:07 INFO - 'MAIL': '/var/mail/cltbld', 22:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:07 INFO - 'MOZ_NO_REMOTE': '1', 22:57:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:07 INFO - 'NO_EM_RESTART': '1', 22:57:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:07 INFO - 'PWD': '/builds/slave/test', 22:57:07 INFO - 'SHELL': '/bin/bash', 22:57:07 INFO - 'SHLVL': '1', 22:57:07 INFO - 'TERM': 'linux', 22:57:07 INFO - 'TMOUT': '86400', 22:57:07 INFO - 'USER': 'cltbld', 22:57:07 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:07 INFO - '_': '/tools/buildbot/bin/python'} 22:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:07 INFO - Downloading/unpacking jsonschema 22:57:07 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 22:57:07 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 22:57:07 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 22:57:07 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 22:57:07 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 22:57:07 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 22:57:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:57:09 INFO - Downloading/unpacking functools32 (from jsonschema) 22:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:09 INFO - Downloading functools32-3.2.3-2.tar.gz 22:57:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:57:09 INFO - warning: no files found matching '*.txt' 22:57:09 INFO - no previously-included directories found matching 'build' 22:57:09 INFO - no previously-included directories found matching 'dist' 22:57:09 INFO - no previously-included directories found matching '.git*' 22:57:09 INFO - Installing collected packages: jsonschema, functools32 22:57:09 INFO - Running setup.py install for functools32 22:57:09 INFO - warning: no files found matching '*.txt' 22:57:09 INFO - no previously-included directories found matching 'build' 22:57:09 INFO - no previously-included directories found matching 'dist' 22:57:09 INFO - no previously-included directories found matching '.git*' 22:57:09 INFO - Successfully installed jsonschema functools32 22:57:09 INFO - Cleaning up... 22:57:09 INFO - Return code: 0 22:57:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:57:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd23687f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ae1770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', '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 22:57:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:57:09 INFO - Using env: {'DISPLAY': ':0', 22:57:09 INFO - 'HOME': '/home/cltbld', 22:57:09 INFO - 'LANG': 'en_US.UTF-8', 22:57:09 INFO - 'LANGUAGE': 'en_US:en', 22:57:09 INFO - 'LOGNAME': 'cltbld', 22:57:09 INFO - 'MAIL': '/var/mail/cltbld', 22:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:09 INFO - 'MOZ_NO_REMOTE': '1', 22:57:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:09 INFO - 'NO_EM_RESTART': '1', 22:57:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:09 INFO - 'PWD': '/builds/slave/test', 22:57:09 INFO - 'SHELL': '/bin/bash', 22:57:09 INFO - 'SHLVL': '1', 22:57:09 INFO - 'TERM': 'linux', 22:57:09 INFO - 'TMOUT': '86400', 22:57:09 INFO - 'USER': 'cltbld', 22:57:09 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:09 INFO - '_': '/tools/buildbot/bin/python'} 22:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:57:09 INFO - Downloading/unpacking flake8 22:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:57:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57: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 22:57:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:57:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:57:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:57:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:57:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:57:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:57:12 INFO - no previously-included directories found matching 'docs/_build' 22:57:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:57:12 INFO - Running setup.py install for pep8 22:57:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:57:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:57:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:57:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:57:12 INFO - no previously-included directories found matching 'docs/_build' 22:57:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:57:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:57:12 INFO - Cleaning up... 22:57:12 INFO - Return code: 0 22:57:12 INFO - Running post-action listener: _resource_record_post_action 22:57:12 INFO - Running post-action listener: _start_resource_monitoring 22:57:12 INFO - Starting resource monitoring. 22:57:12 INFO - ##### 22:57:12 INFO - ##### Finished create-virtualenv step (success) 22:57:12 INFO - ##### 22:57:12 INFO - ##### 22:57:12 INFO - ##### Running install step. 22:57:12 INFO - ##### 22:57:12 INFO - Running pre-action listener: _resource_record_pre_action 22:57:12 INFO - Running main action method: install 22:57:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:57:13 INFO - Reading from file tmpfile_stdout 22:57:13 INFO - Detecting whether we're running mozinstall >=1.0... 22:57:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:57:13 INFO - Reading from file tmpfile_stdout 22:57:13 INFO - Output received: 22:57:13 INFO - Usage: mozinstall [options] installer 22:57:13 INFO - Options: 22:57:13 INFO - -h, --help show this help message and exit 22:57:13 INFO - -d DEST, --destination=DEST 22:57:13 INFO - Directory to install application into. [default: 22:57:13 INFO - "/builds/slave/test"] 22:57:13 INFO - --app=APP Application being installed. [default: firefox] 22:57:13 INFO - mkdir: /builds/slave/test/build/application 22:57:13 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'] 22:57:13 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 22:57:29 INFO - Reading from file tmpfile_stdout 22:57:29 INFO - Output received: 22:57:29 INFO - /builds/slave/test/build/application/firefox/firefox 22:57:29 INFO - Running post-action listener: _resource_record_post_action 22:57:29 INFO - ##### 22:57:29 INFO - ##### Finished install step (success) 22:57:29 INFO - ##### 22:57:29 INFO - ##### 22:57:29 INFO - ##### Running run-tests step. 22:57:29 INFO - ##### 22:57:29 INFO - Running pre-action listener: _resource_record_pre_action 22:57:29 INFO - Running main action method: run_tests 22:57:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:57:29 INFO - Python 2.7.3 22:57:29 INFO - Return code: 0 22:57:29 INFO - grabbing minidump binary from tooltool 22:57:29 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')]} 22:57:29 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 0x1b709c0>, '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 0x1b718c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b71d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:57:29 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 22:57:29 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 22:57:29 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 22:57:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:57:29 INFO - Return code: 0 22:57:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:57:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:57:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:57:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:57:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:57:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:57:30 INFO - Return code: 0 22:57:30 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/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 22:57:30 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/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 22:57:30 INFO - Using env: {'DISPLAY': ':0', 22:57:30 INFO - 'HOME': '/home/cltbld', 22:57:30 INFO - 'LANG': 'en_US.UTF-8', 22:57:30 INFO - 'LANGUAGE': 'en_US:en', 22:57:30 INFO - 'LOGNAME': 'cltbld', 22:57:30 INFO - 'MAIL': '/var/mail/cltbld', 22:57:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:57:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:57:30 INFO - 'MOZ_NO_REMOTE': '1', 22:57:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:57:30 INFO - 'NO_EM_RESTART': '1', 22:57:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:57:30 INFO - 'PWD': '/builds/slave/test', 22:57:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:57:30 INFO - 'SHELL': '/bin/bash', 22:57:30 INFO - 'SHLVL': '1', 22:57:30 INFO - 'TERM': 'linux', 22:57:30 INFO - 'TMOUT': '86400', 22:57:30 INFO - 'USER': 'cltbld', 22:57:30 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681', 22:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:57:30 INFO - '_': '/tools/buildbot/bin/python'} 22:57:30 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/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 22:57:31 INFO - mozversion application_buildid: 20160526203033 22:57:31 INFO - mozversion application_changeset: f2125a888d23ff0a5b6df4c31bc66f0132610750 22:57:31 INFO - mozversion application_display_name: Nightly 22:57:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:57:31 INFO - mozversion application_name: Firefox 22:57:31 INFO - mozversion application_remotingname: firefox 22:57:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:57:31 INFO - mozversion application_vendor: Mozilla 22:57:31 INFO - mozversion application_version: 49.0a1 22:57:31 INFO - mozversion platform_buildid: 20160526203033 22:57:31 INFO - mozversion platform_changeset: f2125a888d23ff0a5b6df4c31bc66f0132610750 22:57:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:57:31 INFO - mozversion platform_version: 49.0a1 22:57:31 INFO - using testdate: 1464328651 22:57:31 INFO - actual date: 1464328651 22:57:31 INFO - starting webserver on 'localhost:44235' 22:57:31 INFO - SUITE-START | Running 2 tests 22:57:31 INFO - TEST-START | dromaeo_css 22:57:31 INFO - operating with platform_type : linux_ 22:57:31 INFO - Initialising browser for dromaeo_css test... 22:57:31 INFO - TEST-INFO | started process 27715 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpntQaJp/profile http://localhost:44235/getInfo.html) 22:57:34 INFO - PROCESS | 27715 | __metrics Screen width/height:1600/1200 22:57:34 INFO - PROCESS | 27715 | colorDepth:24 22:57:34 INFO - PROCESS | 27715 | Browser inner width/height: 1024/697 22:57:34 INFO - PROCESS | 27715 | __metrics 22:57:36 ERROR - PROCESS | 27715 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:57:36 INFO - TEST-INFO | 27715: exit 0 22:57:36 INFO - Browser initialized. 22:57:36 INFO - Running cycle 1/1 for dromaeo_css test... 22:57:36 INFO - TEST-INFO | started process 27802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpntQaJp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:57:48 INFO - PROCESS | 27802 | RSS: Main: 155541504 22:57:48 INFO - PROCESS | 27802 | 22:58:39 INFO - PROCESS | 27802 | 1464328719827 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:00:47 INFO - PROCESS | 27802 | Cycle 1(1): loaded http://localhost:44235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44235/tests/dromaeo/cssquery-ext.html) 23:00:48 INFO - PROCESS | 27802 | RSS: Main: 248332288 23:00:48 INFO - PROCESS | 27802 | 23:03:50 INFO - PROCESS | 27802 | Cycle 1(1): loaded http://localhost:44235/tests/dromaeo/cssquery-ext.html (next: http://localhost:44235/tests/dromaeo/cssquery-jquery.html) 23:03:51 INFO - PROCESS | 27802 | RSS: Main: 247934976 23:03:51 INFO - PROCESS | 27802 | 23:06:53 INFO - PROCESS | 27802 | Cycle 1(1): loaded http://localhost:44235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44235/tests/dromaeo/cssquery-mootools.html) 23:06:53 INFO - PROCESS | 27802 | RSS: Main: 248619008 23:06:53 INFO - PROCESS | 27802 | 23:09:55 INFO - PROCESS | 27802 | Cycle 1(1): loaded http://localhost:44235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44235/tests/dromaeo/cssquery-prototype.html) 23:09:56 INFO - PROCESS | 27802 | RSS: Main: 241704960 23:09:56 INFO - PROCESS | 27802 | 23:12:59 INFO - PROCESS | 27802 | Cycle 1(1): loaded http://localhost:44235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44235/tests/dromaeo/cssquery-yui.html) 23:12:59 INFO - PROCESS | 27802 | RSS: Main: 228478976 23:12:59 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | Cycle 1(1): loaded http://localhost:44235/tests/dromaeo/cssquery-yui.html (next: http://localhost:44235/tests/dromaeo/cssquery-dojo.html) 23:16:02 INFO - PROCESS | 27802 | RSS: Main: 317415424 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | __start_tp_report 23:16:02 INFO - PROCESS | 27802 | _x_x_mozilla_page_load 23:16:02 INFO - PROCESS | 27802 | _x_x_mozilla_page_load_details 23:16:02 INFO - PROCESS | 27802 | |i|pagename|runs| 23:16:02 INFO - PROCESS | 27802 | |0;dojo.html;3132.702393272639;3159.9526007109885;3171.8096914185144;3195.5845740053787;3226.032190342898;5265.447128051557;5342.973285133569;5383.1656093305555;5451.890962180759;5482.232487451755;5386.48828361306;5398.757055932482;5406.432324605918;5448.727563621813;5464.480874316943;4826.131296366652;4902.166631672606;4914.04176185644;4927.137750893579;5110.616703747234;5010.348654674887;5051.444341122474;5067.442581316052;5082.79668813247;5129.94836058609;4832.9275060874115;4867.221244600847;4878.756062196911;4915.950840491585;4946.035523073002;4892.779824907888;4903.950960490385;4919.72941488218;4920.926186107211;4961.677490963076;4922.212446008657;4947.529984651453;4957.702537847741;4976.502349765031;5010.72441015744;5209.244659524347;5217.895642087175;5261.289725886994;5267.841964741064;5439.700816455093;5710.543156547466;5745.569082318842;5754.625949313323;5757.942420575824;5767.279090113736;5776.884462310731;5792.073268277056;5800.187973683687;5805.36141024487;5806.274215723035;5304;5320.228566857784;5377.059014672417;5392.379876314235;5450.809221677222;5934.792282270186;5940;5941.197938278364;5953.761849526057;5963.0459126539545;5520.089185284383;5696.829095127153;5699.572532060112;5703.144528320785;5703.7148142592705;5758.7696492140685;5807.274090738658;5807.361190269067;5833.7374818133285;5836.9124463133085;5811;5815.79644712433;5818.8254352369495;5820.650760954271;5823.388544202798;5629.887402252014;5780.624259423208;5791.681457519877;5817.912731309034;5819.243498345273;4403.339499075164;4405.955940440619;4415.072834704683;4459.5986361227;4465.866024019285;3936.881893543198;3943.231069941391;3947.348687466536;3960.32674445345;3996.1208534122447;5246.265522826808;5339.172428273624;5380.650257733235;5558.833235002957;5630.40880707528;5690;5736.6844823534275;5755.510781583528;5789.044807606782;5820.563457740602;5445.37378201504;5445.428230035868;5457.836264912059;5470.452954704498;5473.507384335429;4823.589994850477;4966;4972.950270497321;4997.300377947017;4999.950000499948;6060.939390606126;6084.482818960438;6094.664793436404;6096.481799047131;6110.480609148272;5880.529557635379;5884.293884733859;5886.911696324473;5903.970480147572;5907.675077870758;5872.794452194153;5995.070764031582;6025.126356678345;6046.123312119806;6054.7275372607455;5701.087825947829;5759.280089988752;5767.221425107557;5768.625039372428;5769.221155144001;5846.6784326862435;5863.2670916135485;5865.882682346244;5867.090600956859;5885.411458854081;5420.078586640359;5681.346645135863;5735.541156707537;5746.626469279484;5748.109043098327;5699.743511542073;5700.401457847032;5700.971495142498;5713.285839270091;5721.485066344049;5454.536364409069;5621.353544342453;5629.915551266652;5635.88728225425;5651;5621.831345059655;5653.915191272052;5679.403662615366;5734.082546792493;5785.3636100028125;5579.525740312119;5583.469570390806;5587.44125587438;5588.888222235614;5635.126555383922 23:16:02 INFO - PROCESS | 27802 | |1;ext.html;609.6037575575875;618.9845253868652;627.1627377451101;633.4299130782296;634.4448607468465;31838.20404489886;44838.77580612097;45713.54286457135;45837.77081114598;49473.25790113151;46095.53904460954;46413.76793116038;46582.53417465824;46630.300545491846;46687.29969050459;8593.656253749858;8746.387752857303;8748.168923952215;8753.255973242281;8755.605997730117;8708;8764.780880478007;8819.250363719091;8828.337874659393;8840.734777956672;8802.64789408423;8827.33794965377;8851.5574221289;8864.246539044188;8866.379353445262;8785.38502304839;8829.293656507463;8830.072842351588;8837.072107428692;8840.690575829847;8737.563121843883;8823.161799629024;8827.337949653802;8832.470051796849;8842;42882.35676464855;43155;43272;43280;43352.3497147543;3398.3092988562903;3586.8923932281814;4165.125323682;4170.979145104286;4173.499180098408;32846.17884552881;32994.3401131976;33383.83308083468;33385.6661433385;34678.6532134678;4241.533431322552;4445.599896009375;4469.061497085616;4504.144212599595;4512.165249428866;344208;344684;345220.2738986314;345370;346780;60409.39590604038;60698.39301607016;61067.389326107055;61096.69451652713;61194.38805611887;65100.34899651037;65126.67436662881;65256;65334.673326634016;65429;16799.580010499834;17041.82958170427;17058.48824535266;17064.2321095551;17067.573310667332;11932.821007684892;11937.940310298392;11948.462319195483;11955.581554645545;11961.684214736371;16689.49931502057;18337.17482713281;18437.631247375244;18523.259069637334;18585.907070464833;5972.954732921721;6615.17310336208;6642.966785166043;6832.180138383326;7012.091535924873;1027.9537420816082;1040.360178490223;1083.7182332593582;1085.858838351025;1218.3786269002694;32359.191020224684;33132.33735325328;33234.335313293595;33421.498677519856;33532.66467335344;1121.2207515776456;1146.2778449576713;1156.6356597671706;1169.1698893785338;1192.4037981009494;16258.47315813419;17005.23476443563;17050.147492625318;17103.657926841643;17111.91444042797;499314;502085;505526;505682;506257;59679.403205967385;59792;60010.39989600048;60041.39958600358;60281.39718602758;1170.0931777872217;1263.418827339434;1362.434589645306;1399.818023656918;1409.7673883809261;32610.673893260762;34044.31911361808;34519;34573;34638.82680586581;52165.7391713039;52431;52611.73694131658;52626.21060684016;52662.736686316326;417843;422188;423913;424690;425932;28325.150245492667;28521.42957140887;28944.7105528942;29053.854730726212;29196.562051568817;43140.13719725651;49706.75146624244;50158.247626286364;50179.74910125426;51372.22941655803;36935.26129477449;41073.794631026656;43713.56286437222;44604.33093503665;46080.539194608966;41907.371389428576;42446.787766060974;42550.361744574475;42608;43055;42645.14709705851;43547;43554.56445435505;43719;43967;35945.820270898475;37649.43525847169;37650.81174594109;38201.235975279786;38436.23127537489;28742.42515149727;28871.85564072166;28889.422211556073;29058.418831623672;29081.85459072691 23:16:02 INFO - PROCESS | 27802 | |2;jquery.html;397.70967193948417;400.99799501002525;408.8344220590662;409.5924555067707;412.81004272335247;18252;18458.353957611453;19620.803791962077;19694;19704.11331490081;18575.44273671792;19204;19393.806061939376;19943.30198443055;19951;5551.333839939213;5594.608377413582;5688.63023903445;5714.628549144313;5720.456556627114;5602.495775380215;5633.661980281176;5636.971815140919;5661;5661.631993920402;5499.642523235997;5510.972445137769;5640.8307750767335;5643.4074422185695;5643.435656434365;5519.282493275869;5527.364353099405;5556.444355564452;5565.165225216209;5592.245046918654;4957.107720610289;5052.7726252318735;5083.034223497561;5091.61812864033;5092.28707980883;22870.656940145913;23031.424214394607;23367.883160584257;23608;23631;2497.4505608766044;2552.8723563821736;3017.2456786059834;3118.4230917280147;3128.562001319817;13984.300784960711;14491.130532168103;14560.271986400638;15095.622609434742;15243.466478673194;1988.8309493693052;2034.6642803937332;2045.2841505473114;2094.780048094943;2156.482444213393;67396;80562.59718701428;82072;82823.58588207081;83136.58431707864;10389.37663740178;10713.571457141783;11043.392613406178;11103.444827758742;11235.101191904629;15184.01303915242;15259;15286.464973725866;15301.9234903827;15323.080615163126;8157.75526734199;8276.627551760184;8372.372072094617;8415.831683366421;8421.073681894986;7869.803254918673;7938.603069846485;7951.602419879098;8052.677892884219;8175.83648327042;6669.7665581704405;6825;6856.6228857413325;6865.862682746317;6902.689378977958;1934.1489744512369;1956.5206524401328;1965.3023176772326;1987.7515310586177;1994.8902810345571;979.5543027922279;999.2279146222921;1057.577558184241;1064.0049906425452;1100.4332768624165;18465.90767046183;19709.802901970797;20671.276505322243;20950.89524552398;20997.79002209989;977.8191034658611;1003.3277703938379;1013.1884021839454;1013.4122209118693;1021.5556233038652;10570.735731606772;11226.7193320166;11258.155638327158;11260.94369528147;11335.206535542433;525303;533279;536538;540107;540232;74064;87805;90171.54914225386;97077;98400;1009.6264382178641;1017.3946501831399;1041.6146025970288;1057.7408534908996;1058.2486434631344;17966.281348746426;18116.90941545284;18181;18392.724109138642;18598.07009649539;38245.80877095597;44382.55617443784;44778.77610611926;45152.32271515864;46503.53496465128;48556.75721621369;59755.70122149361;60335.39664603474;61021.38978610157;62250.68874655598;8417.368697347845;8634.611442484978;8653;8673.609687564202;8955.328350373878;9676.56455459492;10213.795724085625;12134.847189516982;12888.677783055498;13127.146735462164;11143.554257829921;11321.547138114383;11357.659270221906;11647.184697071123;11960.940195298968;11101.777964440504;11308.473356096822;11692.181547291608;11746.823797643214;11926.940365298118;11442.48508817122;11494.310341379543;11587.768244635228;11589.24669896454;11699.713031566691;7475.102987641518;8590.398672092893;8742.606582703665;8756.824863502567;8804.867926980973;9749.9025009749;11045.723856903642;11603.825942611036;11635.941820290844;11843.52625894989 23:16:02 INFO - PROCESS | 27802 | |3;mootools.html;1261.9684507887296;1449.8622630850068;1496.7380708376033;1617.951461456156;1808.7286906963961;8566.785830354243;8923.063078376774;8927.910720892798;9180.678676246333;9195.816083678339;8906.376553641232;9113.817723645523;9117.407368521042;9121.361504694674;9127.543622818866;1980.2870966452138;2278.9658155127686;2334.801541868939;2363.8108951283943;2372.3713215997773;2400.72391674957;2409.4578719788083;2411.0837881604966;2412.49337639096;2412.9276121716375;2312.225404489498;2359.516299158677;2362.893669784864;2363.432776133724;2368.111958015744;1973.437570292467;2236.9664455033267;2315.6526521021815;2318.2813327868416;2340.9297721068397;2275.8748268845206;2286.3598192506124;2293.9333210057403;2294.357579877637;2311.9884400578057;8776.956115219447;8853.69012084574;8879.689210877588;8892.421992570527;8995.55022248885;5221.425643179247;5385.057614917413;5390.434004429557;5391.514763671249;5392.0294347017525;3275.918102047444;3390.559227300435;3393.100828280483;3399.8640054397792;3449.5170676105367;1038.9013043760906;1068.8022715797601;1074.6292529077455;1088.7604726960135;1100.4827730966433;12601.30692811895;12630.49478020878;12696.492140314376;12720.109592328541;12725.681857953532;4561.156186105581;4672;4763.428388593391;4766.308885211625;4853.39332583427;10562.419066951237;10573.101286390589;10594.947025264824;10598.311109777995;10617.469126543796;4719.882002949954;5352.866178345573;5419.945800541944;5427;5433.755481003364;4771.665983381199;4937.703737775673;4953.034158339089;4989.575886049726;4995.225740010304;4033.838646454109;4073.490813648294;4110.753354798721;4266.741311313108;4290.4636920384955;1254.9749005019848;1306.8366454193226;1306.9019823513274;1313.0874042540534;1334.8197993270978;985.142925654685;1008.4856723071287;1011.413380239474;1019.3883669798211;1056.730533713898;6079.6960151992225;6150.985087460519;6159.611160257285;6160.10678451622;6253.1245625612455;437.82924659382866;443.5675216663727;445.2697575975403;447.11471286851565;454.4001917468909;4259.488861336659;4300.311950087971;4314.460692413449;4344.652427805768;4347.369631403429;10722.356658600509;11063.114950803916;11165.106791456665;11207.495662695199;11236;10579.259451838298;11164.497597607964;11180.664580062612;11188.104951603853;11197.888021119847;8041.236082572146;8349.081601023883;8398.622062007224;8414.284785793276;8441.324694024463;6028.487578555872;6379.904301435389;6400.445525108069;6410.294867564658;6454;10389.584416623553;10532;10593.470326483799;10644.414557199429;10658.200634952254;10313.432761198206;10522.421266830397;10817.621383251862;10823.134149268197;10827.891721083004;7085.787426377217;7605;7762.068551773823;7896.407769417431;8017.358611311198;8590.91409085901;8596.914030859862;8616.612252448529;8665.956670216608;8671.523066231417;7467.551946883204;7550.584717840571;7602.6198690066385;7627.351675107456;7739.110002349802;7430.517016393917;7435.330820226206;7464.141623713125;7489.176190619137;7603.619819009138;6721.193456785021;6764.93235067643;6799.7620083298825;6811.352921472452;6813.3527314905;4753.6434767393275;5129.384473863161;5285.260063591023;5287.68273903567;5342.75957581902;5294;5348.625596208383;5415.1335786274;5435.592330575144;5493.533049690821 23:16:02 INFO - PROCESS | 27802 | |4;prototype.html;333.77136661386953;334.6636630186662;341.9263512570529;343.70441420378455;345.19741600778195;5291.444398338172;5316.9734151329185;5324.307839980797;5337.279467271917;5375.408705042452;5261.500157485033;5268.209768534722;5301.5228629423345;5321.680699158053;5324.787008519665;3916.1384495411035;4045.8381664733374;4176.958230417703;4178.185253875496;4229.471316085489;4125.855595054174;4154.750714957112;4166.7708276044805;4182.142660754538;4184.832606695729;3913.9217215655813;4161.188568229197;4176.770277634729;4178.853740119096;4191.979040104795;4037.7173597848164;4163.458750362463;4186.832526698929;4191.706580539363;4192.496900371975;3684.7604905681046;3717.460968159629;3721.497597824286;3727.7949712765785;3737.4767532545466;5120;5132.281480592683;5158.40678321994;5159.871003224912;5162.741862906877;4492.158215131781;4675.836345727883;4682.836100736458;4720.929186062178;4750.572448479654;5460.31746031746;5479.479449452296;5494.532964698005;5495.175723641446;5555.166724991283;3981;3987.342088555385;3992.941870404345;3996.140829721624;4006.4390985262085;5540.002799496089;5744.942550574524;5757.654540727528;5785.595008349375;5791.913121303184;4070.247004304213;4080.7553696122372;4081.8775436736946;4083.734557253769;4100.1139475840855;4135.276326642826;4138.151678905817;4145.709800313949;4160.1471698301775;4188.4136220929095;4635.490096089427;4660.4174478190225;4742.430908291027;4750.809967601257;4770.952290477051;5582.106862901916;5627.143042795868;5642.915356269659;5645.5483561314995;5653.321601407857;1431.8067060946846;1436.2746812859648;1440.8271007478959;1443.9061461005;1447.6742732885016;866.2204016385303;889.3774357949461;923;972.4845831709206;973.3867663372031;5263;5269.815556455583;5274.551663108678;5299.258103865462;5308.973455132699;5582.469665381782;5589.720513974366;5640.266765320564;5643.943560564424;5667.234923285386;5116.513931176532;5282.5436172180125;5302.973485132549;5447;5450.3732070811575;3038.7872848900806;3044;3060.32672811981;3067.9846600766855;3091.165385345944;5711.771529138788;5938.198343223609;5945.235465508559;5948.434898684618;5999.640021598718;5801.187833703285;5801.448862358069;5802.6808525531505;5802.82591522255;5824.60209549705;5839.686070634159;5928.288605367297;5935.258092738408;5938.8812223755285;5970.8655355482315;5434.972825135849;5723.99660118955;5740.225069615549;5744.971275143597;5765;5604.618037334199;5609.24275222856;5635.408282130481;5678.677973385616;5694.743736531949;5781.913271300849;5891.49922256613;5940.198073260054;5976.133460648269;6022.819315420545;4022.8591999279884;4029.8843107368866;4046.7571945683353;4062.532808727034;4063.1670507546473;5783.6818974955095;5817.883642327214;5838.94161058384;5860.326062502867;5863.651360187096;5451.64564303319;5474.835754927359;5587.636803607753;5610.85972850682;5670.5180059695385;5553.888922221614;5571.275734154534;5588.077967135539;5588.133839254922;5589.189567512606;5286.444923283;5482.369527504388;5488.835334939959;5521.4478552144465;5534.252875861707;5473.31583552056;5559.499645031967;5562.749676264497;5576.609637325349;5645.802396916089;5482.835514934558;5498.642588231752;5552.722873738981;5558.944410556004;5562.414711807116 23:16:02 INFO - PROCESS | 27802 | |5;yui.html;700.5936556797059;797.8045378882175;799.6881216325634;814.2020819596797;818.7093581778465;14587.927060364696;14992.925035374808;15137.697246055071;15405.537833864988;15954.601134971632;14157.787633185484;14232.857671423282;14295.857041429583;14298.571042868705;14305.642358941031;690.2510775808254;803.618281316373;814.816666250095;825.6202147012351;829.0631586307494;832.1553623072587;832.4797001873828;835.5028757889046;835.7952301686096;836.5148214035875;861.8707193920931;865.2947847504296;868.2446271743592;868.3834477520968;868.4615538366222;710.939570136539;841.4362377207256;859.7248880358288;861.4658911474863;861.8448679237734;625.562106525434;625.9155014073087;625.9624422534617;626.2109741725413;626.3298270850192;14900;14951.700965980728;14965.70068598622;14968.101913885203;14975.475858345015;12739.808902866464;13184.208947463183;13228.537001204912;13386.866131338757;13587.660308492268;11371.46726682467;11482.483288251966;11595.652130436101;11702.648920532312;11723.062155027577;4235.343521754102;4252.574742525753;4258.467691538558;4423.491298500681;4488.438945131858;57330.426695732924;57433.42566574323;57629;57639;57822.71088644572;10266.281360304707;10443.530041148166;10447.529861156114;10478.109360704426;10488.423707690557;19907.203711851365;20041.899790501247;20054.498637533885;20121.195152194043;20133.295334663217;8284.792880177924;8403.201695838885;8833.33749968755;8865.822683546294;8866.64533418656;4178.247915375201;4235.407043013981;4263.339182426729;4575.760887926154;4672.696274742131;3232.69289417505;3300.488424294238;3302.867885284562;3324.1855745342546;3339.081752518077;627.8399008252958;640.9262934762469;645.8901986662279;650.5413683353319;664.4119953840789;627.6861569215392;635.939585739354;684.3156843156843;735.988960165598;739.5599618227146;9141.54292285383;9526.52367381642;9797.559109840075;9808.509574521388;9852;733.9339459448676;739.8742213823663;770.2490072179692;800.1663712257055;800.2117913854848;4318.534122977859;4421.447319085114;4513.097380523908;4525.366448697185;4533.637309015271;72251.63874180596;73034.63482682659;73341;73435.26564734284;73533.26466735371;61590.69204653948;62506.687466562376;64446.35553644497;65063.34936650573;67134.32865671378;57981;58190.709046454496;58687;58825.70587147037;59358.999999999134;8545.529995850287;8575.099614540384;8681.739547813575;8711.56442177876;8747.825043499222;38322.42516362313;38921.80539097286;39696.404553931134;39958.2008359837;40501.594984049785;53065.20402193893;53365.19952200643;53378;53387.733061334446;53903;12085.577004804789;12219.633410997685;12256.142070055368;12321.815172772236;12470.688232794253;12580.937095314464;12965.092443528862;13168;13406;13745.312734363442;12123.393830308625;12433.564825231435;12721.427535761099;12729.172603780724;12878.35608219604;11799.646010619696;12110.939445303069;12196.878031219929;12491.500339986662;12591.87408125907;12216.938915305367;12500.812487812509;12567.685807854878;12628.179168354027;13029.543965961146;8762.430442021248;8859.689910853089;9104.408213466368;9249.76875578089;9654.565544550653;9310.115539023782;9321.627134914528;9337.48643824569;9941.105300522699;9946.403215807075 23:16:02 INFO - PROCESS | 27802 | __end_tp_report 23:16:02 INFO - PROCESS | 27802 | __start_cc_report 23:16:02 INFO - PROCESS | 27802 | _x_x_mozilla_cycle_collect,922 23:16:02 INFO - PROCESS | 27802 | __end_cc_report 23:16:02 INFO - PROCESS | 27802 | __startTimestamp1464329762953__endTimestamp 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | ------- Summary: start ------- 23:16:02 INFO - PROCESS | 27802 | Number of tests: 6 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | [#0] dojo.html Cycles:165 Average:5388.00 Median:5621.83 stddev:612.14 (10.9%) stddev-sans-first:587.89 23:16:02 INFO - PROCESS | 27802 | Values: 3132.7 3160.0 3171.8 3195.6 3226.0 5265.4 5343.0 5383.2 5451.9 5482.2 5386.5 5398.8 5406.4 5448.7 5464.5 4826.1 4902.2 4914.0 4927.1 5110.6 5010.3 5051.4 5067.4 5082.8 5129.9 4832.9 4867.2 4878.8 4916.0 4946.0 4892.8 4904.0 4919.7 4920.9 4961.7 4922.2 4947.5 4957.7 4976.5 5010.7 5209.2 5217.9 5261.3 5267.8 5439.7 5710.5 5745.6 5754.6 5757.9 5767.3 5776.9 5792.1 5800.2 5805.4 5806.3 5304.0 5320.2 5377.1 5392.4 5450.8 5934.8 5940.0 5941.2 5953.8 5963.0 5520.1 5696.8 5699.6 5703.1 5703.7 5758.8 5807.3 5807.4 5833.7 5836.9 5811.0 5815.8 5818.8 5820.7 5823.4 5629.9 5780.6 5791.7 5817.9 5819.2 4403.3 4406.0 4415.1 4459.6 4465.9 3936.9 3943.2 3947.3 3960.3 3996.1 5246.3 5339.2 5380.7 5558.8 5630.4 5690.0 5736.7 5755.5 5789.0 5820.6 5445.4 5445.4 5457.8 5470.5 5473.5 4823.6 4966.0 4973.0 4997.3 5000.0 6060.9 6084.5 6094.7 6096.5 6110.5 5880.5 5884.3 5886.9 5904.0 5907.7 5872.8 5995.1 6025.1 6046.1 6054.7 5701.1 5759.3 5767.2 5768.6 5769.2 5846.7 5863.3 5865.9 5867.1 5885.4 5420.1 5681.3 5735.5 5746.6 5748.1 5699.7 5700.4 5701.0 5713.3 5721.5 5454.5 5621.4 5629.9 5635.9 5651.0 5621.8 5653.9 5679.4 5734.1 5785.4 5579.5 5583.5 5587.4 5588.9 5635.1 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | [#1] ext.html Cycles:180 Average:59562.16 Median:43097.57 stddev:113392.24 (263.1%) stddev-sans-first:113623.94 23:16:02 INFO - PROCESS | 27802 | Values: 609.6 619.0 627.2 633.4 634.4 31838.2 44838.8 45713.5 45837.8 49473.3 46095.5 46413.8 46582.5 46630.3 46687.3 8593.7 8746.4 8748.2 8753.3 8755.6 8708.0 8764.8 8819.3 8828.3 8840.7 8802.6 8827.3 8851.6 8864.2 8866.4 8785.4 8829.3 8830.1 8837.1 8840.7 8737.6 8823.2 8827.3 8832.5 8842.0 42882.4 43155.0 43272.0 43280.0 43352.3 3398.3 3586.9 4165.1 4171.0 4173.5 32846.2 32994.3 33383.8 33385.7 34678.7 4241.5 4445.6 4469.1 4504.1 4512.2 344208.0 344684.0 345220.3 345370.0 346780.0 60409.4 60698.4 61067.4 61096.7 61194.4 65100.3 65126.7 65256.0 65334.7 65429.0 16799.6 17041.8 17058.5 17064.2 17067.6 11932.8 11937.9 11948.5 11955.6 11961.7 16689.5 18337.2 18437.6 18523.3 18585.9 5973.0 6615.2 6643.0 6832.2 7012.1 1028.0 1040.4 1083.7 1085.9 1218.4 32359.2 33132.3 33234.3 33421.5 33532.7 1121.2 1146.3 1156.6 1169.2 1192.4 16258.5 17005.2 17050.1 17103.7 17111.9 499314.0 502085.0 505526.0 505682.0 506257.0 59679.4 59792.0 60010.4 60041.4 60281.4 1170.1 1263.4 1362.4 1399.8 1409.8 32610.7 34044.3 34519.0 34573.0 34638.8 52165.7 52431.0 52611.7 52626.2 52662.7 417843.0 422188.0 423913.0 424690.0 425932.0 28325.2 28521.4 28944.7 29053.9 29196.6 43140.1 49706.8 50158.2 50179.7 51372.2 36935.3 41073.8 43713.6 44604.3 46080.5 41907.4 42446.8 42550.4 42608.0 43055.0 42645.1 43547.0 43554.6 43719.0 43967.0 35945.8 37649.4 37650.8 38201.2 38436.2 28742.4 28871.9 28889.4 29058.4 29081.9 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | [#2] jquery.html Cycles:180 Average:30382.93 Median:2525.16 stddev:87923.95 (3481.9%) stddev-sans-first:88141.77 23:16:02 INFO - PROCESS | 27802 | Values: 397.7 401.0 408.8 409.6 412.8 18252.0 18458.4 19620.8 19694.0 19704.1 18575.4 19204.0 19393.8 19943.3 19951.0 5551.3 5594.6 5688.6 5714.6 5720.5 5602.5 5633.7 5637.0 5661.0 5661.6 5499.6 5511.0 5640.8 5643.4 5643.4 5519.3 5527.4 5556.4 5565.2 5592.2 4957.1 5052.8 5083.0 5091.6 5092.3 22870.7 23031.4 23367.9 23608.0 23631.0 2497.5 2552.9 3017.2 3118.4 3128.6 13984.3 14491.1 14560.3 15095.6 15243.5 1988.8 2034.7 2045.3 2094.8 2156.5 67396.0 80562.6 82072.0 82823.6 83136.6 10389.4 10713.6 11043.4 11103.4 11235.1 15184.0 15259.0 15286.5 15301.9 15323.1 8157.8 8276.6 8372.4 8415.8 8421.1 7869.8 7938.6 7951.6 8052.7 8175.8 6669.8 6825.0 6856.6 6865.9 6902.7 1934.1 1956.5 1965.3 1987.8 1994.9 979.6 999.2 1057.6 1064.0 1100.4 18465.9 19709.8 20671.3 20950.9 20997.8 977.8 1003.3 1013.2 1013.4 1021.6 10570.7 11226.7 11258.2 11260.9 11335.2 525303.0 533279.0 536538.0 540107.0 540232.0 74064.0 87805.0 90171.5 97077.0 98400.0 1009.6 1017.4 1041.6 1057.7 1058.2 17966.3 18116.9 18181.0 18392.7 18598.1 38245.8 44382.6 44778.8 45152.3 46503.5 48556.8 59755.7 60335.4 61021.4 62250.7 8417.4 8634.6 8653.0 8673.6 8955.3 9676.6 10213.8 12134.8 12888.7 13127.1 11143.6 11321.5 11357.7 11647.2 11960.9 11101.8 11308.5 11692.2 11746.8 11926.9 11442.5 11494.3 11587.8 11589.2 11699.7 7475.1 8590.4 8742.6 8756.8 8804.9 9749.9 11045.7 11603.8 11635.9 11843.5 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | [#3] mootools.html Cycles:180 Average:5892.96 Median:444.42 stddev:3404.32 (766.0%) stddev-sans-first:3396.08 23:16:02 INFO - PROCESS | 27802 | Values: 1262.0 1449.9 1496.7 1618.0 1808.7 8566.8 8923.1 8927.9 9180.7 9195.8 8906.4 9113.8 9117.4 9121.4 9127.5 1980.3 2279.0 2334.8 2363.8 2372.4 2400.7 2409.5 2411.1 2412.5 2412.9 2312.2 2359.5 2362.9 2363.4 2368.1 1973.4 2237.0 2315.7 2318.3 2340.9 2275.9 2286.4 2293.9 2294.4 2312.0 8777.0 8853.7 8879.7 8892.4 8995.6 5221.4 5385.1 5390.4 5391.5 5392.0 3275.9 3390.6 3393.1 3399.9 3449.5 1038.9 1068.8 1074.6 1088.8 1100.5 12601.3 12630.5 12696.5 12720.1 12725.7 4561.2 4672.0 4763.4 4766.3 4853.4 10562.4 10573.1 10594.9 10598.3 10617.5 4719.9 5352.9 5419.9 5427.0 5433.8 4771.7 4937.7 4953.0 4989.6 4995.2 4033.8 4073.5 4110.8 4266.7 4290.5 1255.0 1306.8 1306.9 1313.1 1334.8 985.1 1008.5 1011.4 1019.4 1056.7 6079.7 6151.0 6159.6 6160.1 6253.1 437.8 443.6 445.3 447.1 454.4 4259.5 4300.3 4314.5 4344.7 4347.4 10722.4 11063.1 11165.1 11207.5 11236.0 10579.3 11164.5 11180.7 11188.1 11197.9 8041.2 8349.1 8398.6 8414.3 8441.3 6028.5 6379.9 6400.4 6410.3 6454.0 10389.6 10532.0 10593.5 10644.4 10658.2 10313.4 10522.4 10817.6 10823.1 10827.9 7085.8 7605.0 7762.1 7896.4 8017.4 8590.9 8596.9 8616.6 8666.0 8671.5 7467.6 7550.6 7602.6 7627.4 7739.1 7430.5 7435.3 7464.1 7489.2 7603.6 6721.2 6764.9 6799.8 6811.4 6813.4 4753.6 5129.4 5285.3 5287.7 5342.8 5294.0 5348.6 5415.1 5435.6 5493.5 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | [#4] prototype.html Cycles:180 Average:4693.05 Median:5324.55 stddev:1377.83 (25.9%) stddev-sans-first:1342.28 23:16:02 INFO - PROCESS | 27802 | Values: 333.8 334.7 341.9 343.7 345.2 5291.4 5317.0 5324.3 5337.3 5375.4 5261.5 5268.2 5301.5 5321.7 5324.8 3916.1 4045.8 4177.0 4178.2 4229.5 4125.9 4154.8 4166.8 4182.1 4184.8 3913.9 4161.2 4176.8 4178.9 4192.0 4037.7 4163.5 4186.8 4191.7 4192.5 3684.8 3717.5 3721.5 3727.8 3737.5 5120.0 5132.3 5158.4 5159.9 5162.7 4492.2 4675.8 4682.8 4720.9 4750.6 5460.3 5479.5 5494.5 5495.2 5555.2 3981.0 3987.3 3992.9 3996.1 4006.4 5540.0 5744.9 5757.7 5785.6 5791.9 4070.2 4080.8 4081.9 4083.7 4100.1 4135.3 4138.2 4145.7 4160.1 4188.4 4635.5 4660.4 4742.4 4750.8 4771.0 5582.1 5627.1 5642.9 5645.5 5653.3 1431.8 1436.3 1440.8 1443.9 1447.7 866.2 889.4 923.0 972.5 973.4 5263.0 5269.8 5274.6 5299.3 5309.0 5582.5 5589.7 5640.3 5643.9 5667.2 5116.5 5282.5 5303.0 5447.0 5450.4 3038.8 3044.0 3060.3 3068.0 3091.2 5711.8 5938.2 5945.2 5948.4 5999.6 5801.2 5801.4 5802.7 5802.8 5824.6 5839.7 5928.3 5935.3 5938.9 5970.9 5435.0 5724.0 5740.2 5745.0 5765.0 5604.6 5609.2 5635.4 5678.7 5694.7 5781.9 5891.5 5940.2 5976.1 6022.8 4022.9 4029.9 4046.8 4062.5 4063.2 5783.7 5817.9 5838.9 5860.3 5863.7 5451.6 5474.8 5587.6 5610.9 5670.5 5553.9 5571.3 5588.1 5588.1 5589.2 5286.4 5482.4 5488.8 5521.4 5534.3 5473.3 5559.5 5562.7 5576.6 5645.8 5482.8 5498.6 5552.7 5558.9 5562.4 23:16:02 INFO - PROCESS | 27802 | 23:16:02 INFO - PROCESS | 27802 | [#5] yui.html Cycles:180 Average:16050.97 Median:57381.93 stddev:19855.81 (34.6%) stddev-sans-first:19878.05 23:16:02 INFO - PROCESS | 27802 | Values: 700.6 797.8 799.7 814.2 818.7 14587.9 14992.9 15137.7 15405.5 15954.6 14157.8 14232.9 14295.9 14298.6 14305.6 690.3 803.6 814.8 825.6 829.1 832.2 832.5 835.5 835.8 836.5 861.9 865.3 868.2 868.4 868.5 710.9 841.4 859.7 861.5 861.8 625.6 625.9 626.0 626.2 626.3 14900.0 14951.7 14965.7 14968.1 14975.5 12739.8 13184.2 13228.5 13386.9 13587.7 11371.5 11482.5 11595.7 11702.6 11723.1 4235.3 4252.6 4258.5 4423.5 4488.4 57330.4 57433.4 57629.0 57639.0 57822.7 10266.3 10443.5 10447.5 10478.1 10488.4 19907.2 20041.9 20054.5 20121.2 20133.3 8284.8 8403.2 8833.3 8865.8 8866.6 4178.2 4235.4 4263.3 4575.8 4672.7 3232.7 3300.5 3302.9 3324.2 3339.1 627.8 640.9 645.9 650.5 664.4 627.7 635.9 684.3 736.0 739.6 9141.5 9526.5 9797.6 9808.5 9852.0 733.9 739.9 770.2 800.2 800.2 4318.5 4421.4 4513.1 4525.4 4533.6 72251.6 73034.6 73341.0 73435.3 73533.3 61590.7 62506.7 64446.4 65063.3 67134.3 57981.0 58190.7 58687.0 58825.7 59359.0 8545.5 8575.1 8681.7 8711.6 8747.8 38322.4 38921.8 39696.4 39958.2 40501.6 53065.2 53365.2 53378.0 53387.7 53903.0 12085.6 12219.6 12256.1 12321.8 12470.7 12580.9 12965.1 13168.0 13406.0 13745.3 12123.4 12433.6 12721.4 12729.2 12878.4 11799.6 12110.9 12196.9 12491.5 12591.9 12216.9 12500.8 12567.7 12628.2 13029.5 8762.4 8859.7 9104.4 9249.8 9654.6 9310.1 9321.6 9337.5 9941.1 9946.4 23:16:02 INFO - PROCESS | 27802 | -------- Summary: end -------- 23:16:02 INFO - PROCESS | 27802 | 23:16:03 INFO - TEST-INFO | 27802: exit 0 23:16:03 INFO - TEST-OK | dromaeo_css | took 1112609ms 23:16:03 INFO - TEST-START | kraken 23:16:03 INFO - operating with platform_type : linux_ 23:16:03 INFO - Initialising browser for kraken test... 23:16:03 INFO - TEST-INFO | started process 27982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgyErGm/profile http://localhost:44235/getInfo.html) 23:16:07 INFO - PROCESS | 27982 | __metrics Screen width/height:1600/1200 23:16:07 INFO - PROCESS | 27982 | colorDepth:24 23:16:07 INFO - PROCESS | 27982 | Browser inner width/height: 1024/697 23:16:07 INFO - PROCESS | 27982 | __metrics 23:16:08 ERROR - PROCESS | 27982 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:16:09 INFO - TEST-INFO | 27982: exit 0 23:16:09 INFO - Browser initialized. 23:16:09 INFO - Running cycle 1/1 for kraken test... 23:16:09 INFO - TEST-INFO | started process 28063 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgyErGm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:16:21 INFO - PROCESS | 28063 | RSS: Main: 155418624 23:16:21 INFO - PROCESS | 28063 | 23:16:23 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44235/tests/kraken/driver.html?testName=audio-beat-detection) 23:16:24 INFO - PROCESS | 28063 | RSS: Main: 213893120 23:16:24 INFO - PROCESS | 28063 | 23:16:27 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44235/tests/kraken/driver.html?testName=audio-dft) 23:16:27 INFO - PROCESS | 28063 | RSS: Main: 199667712 23:16:27 INFO - PROCESS | 28063 | 23:16:30 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44235/tests/kraken/driver.html?testName=audio-fft) 23:16:31 INFO - PROCESS | 28063 | RSS: Main: 268185600 23:16:31 INFO - PROCESS | 28063 | 23:16:33 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44235/tests/kraken/driver.html?testName=audio-oscillator) 23:16:34 INFO - PROCESS | 28063 | RSS: Main: 178483200 23:16:34 INFO - PROCESS | 28063 | 23:16:36 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:16:36 INFO - PROCESS | 28063 | RSS: Main: 183570432 23:16:36 INFO - PROCESS | 28063 | 23:16:40 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44235/tests/kraken/driver.html?testName=imaging-darkroom) 23:16:40 INFO - PROCESS | 28063 | RSS: Main: 217702400 23:16:40 INFO - PROCESS | 28063 | 23:16:43 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44235/tests/kraken/driver.html?testName=imaging-desaturate) 23:16:44 INFO - PROCESS | 28063 | RSS: Main: 219869184 23:16:44 INFO - PROCESS | 28063 | 23:16:47 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44235/tests/kraken/driver.html?testName=json-parse-financial) 23:16:47 INFO - PROCESS | 28063 | RSS: Main: 220114944 23:16:47 INFO - PROCESS | 28063 | 23:16:49 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:16:50 INFO - PROCESS | 28063 | RSS: Main: 209006592 23:16:50 INFO - PROCESS | 28063 | 23:16:51 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:16:52 INFO - PROCESS | 28063 | RSS: Main: 211054592 23:16:52 INFO - PROCESS | 28063 | 23:16:54 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:16:55 INFO - PROCESS | 28063 | RSS: Main: 216764416 23:16:55 INFO - PROCESS | 28063 | 23:16:57 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:16:58 INFO - PROCESS | 28063 | RSS: Main: 221581312 23:16:58 INFO - PROCESS | 28063 | 23:17:01 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:17:01 INFO - PROCESS | 28063 | RSS: Main: 221433856 23:17:01 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | Cycle 1(1): loaded http://localhost:44235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44235/tests/kraken/driver.html?testName=ai-astar) 23:17:03 INFO - PROCESS | 28063 | RSS: Main: 263536640 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | __start_tp_report 23:17:03 INFO - PROCESS | 28063 | _x_x_mozilla_page_load 23:17:03 INFO - PROCESS | 28063 | _x_x_mozilla_page_load_details 23:17:03 INFO - PROCESS | 28063 | |i|pagename|runs| 23:17:03 INFO - PROCESS | 28063 | |0;ai-astar;112;107;110;111;111;113;111;111;111;111 23:17:03 INFO - PROCESS | 28063 | |1;audio-beat-detection;149;124;126;182;125;125;155;125;167;124 23:17:03 INFO - PROCESS | 28063 | |2;audio-dft;160;161;160;162;162;161;162;161;163;162 23:17:03 INFO - PROCESS | 28063 | |3;audio-fft;75;74;75;115;75;75;75;74;98;75 23:17:03 INFO - PROCESS | 28063 | |4;audio-oscillator;84;83;122;84;85;86;121;83;84;126 23:17:03 INFO - PROCESS | 28063 | |5;imaging-gaussian-blur;118;118;118;118;118;118;119;118;118;118 23:17:03 INFO - PROCESS | 28063 | |6;imaging-darkroom;145;146;145;146;145;145;145;147;146;146 23:17:03 INFO - PROCESS | 28063 | |7;imaging-desaturate;96;95;95;95;95;94;95;95;94;95 23:17:03 INFO - PROCESS | 28063 | |8;json-parse-financial;53;54;55;55;53;54;55;53;54;54 23:17:03 INFO - PROCESS | 28063 | |9;json-stringify-tinderbox;61;62;61;61;61;61;60;62;61;61 23:17:03 INFO - PROCESS | 28063 | |10;stanford-crypto-aes;80;78;80;79;79;80;80;82;109;80 23:17:03 INFO - PROCESS | 28063 | |11;stanford-crypto-ccm;125;138;123;122;129;126;136;125;134;129 23:17:03 INFO - PROCESS | 28063 | |12;stanford-crypto-pbkdf2;162;167;163;169;159;159;159;160;159;165 23:17:03 INFO - PROCESS | 28063 | |13;stanford-crypto-sha256-iterative;64;64;67;65;64;62;65;63;63;62 23:17:03 INFO - PROCESS | 28063 | __end_tp_report 23:17:03 INFO - PROCESS | 28063 | __start_cc_report 23:17:03 INFO - PROCESS | 28063 | _x_x_mozilla_cycle_collect,1630 23:17:03 INFO - PROCESS | 28063 | __end_cc_report 23:17:03 INFO - PROCESS | 28063 | __startTimestamp1464329823594__endTimestamp 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | ------- Summary: start ------- 23:17:03 INFO - PROCESS | 28063 | Number of tests: 14 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:1.55 (1.4%) stddev-sans-first:1.58 23:17:03 INFO - PROCESS | 28063 | Values: 112.0 107.0 110.0 111.0 111.0 113.0 111.0 111.0 111.0 111.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:137.50 stddev:21.56 (15.7%) stddev-sans-first:22.63 23:17:03 INFO - PROCESS | 28063 | Values: 149.0 124.0 126.0 182.0 125.0 125.0 155.0 125.0 167.0 124.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 23:17:03 INFO - PROCESS | 28063 | Values: 160.0 161.0 160.0 162.0 162.0 161.0 162.0 161.0 163.0 162.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:13.98 (18.6%) stddev-sans-first:14.65 23:17:03 INFO - PROCESS | 28063 | Values: 75.0 74.0 75.0 115.0 75.0 75.0 75.0 74.0 98.0 75.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:84.00 stddev:18.83 (22.4%) stddev-sans-first:19.48 23:17:03 INFO - PROCESS | 28063 | Values: 84.0 83.0 122.0 84.0 85.0 86.0 121.0 83.0 84.0 126.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:17:03 INFO - PROCESS | 28063 | Values: 118.0 118.0 118.0 118.0 118.0 118.0 119.0 118.0 118.0 118.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 23:17:03 INFO - PROCESS | 28063 | Values: 145.0 146.0 145.0 146.0 145.0 145.0 145.0 147.0 146.0 146.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 23:17:03 INFO - PROCESS | 28063 | Values: 96.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.78 23:17:03 INFO - PROCESS | 28063 | Values: 53.0 54.0 55.0 55.0 53.0 54.0 55.0 53.0 54.0 54.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 23:17:03 INFO - PROCESS | 28063 | Values: 61.0 62.0 61.0 61.0 61.0 61.0 60.0 62.0 61.0 61.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:80.00 stddev:9.30 (11.6%) stddev-sans-first:9.81 23:17:03 INFO - PROCESS | 28063 | Values: 80.0 78.0 80.0 79.0 79.0 80.0 80.0 82.0 109.0 80.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:129.00 stddev:5.58 (4.3%) stddev-sans-first:5.75 23:17:03 INFO - PROCESS | 28063 | Values: 125.0 138.0 123.0 122.0 129.0 126.0 136.0 125.0 134.0 129.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:162.50 stddev:3.71 (2.3%) stddev-sans-first:3.93 23:17:03 INFO - PROCESS | 28063 | Values: 162.0 167.0 163.0 169.0 159.0 159.0 159.0 160.0 159.0 165.0 23:17:03 INFO - PROCESS | 28063 | 23:17:03 INFO - PROCESS | 28063 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:1.52 (2.4%) stddev-sans-first:1.62 23:17:03 INFO - PROCESS | 28063 | Values: 64.0 64.0 67.0 65.0 64.0 62.0 65.0 63.0 63.0 62.0 23:17:03 INFO - PROCESS | 28063 | -------- Summary: end -------- 23:17:03 INFO - PROCESS | 28063 | 23:17:04 INFO - TEST-INFO | 28063: exit 0 23:17:04 INFO - TEST-OK | kraken | took 60900ms 23:17:04 INFO - SUITE-END | took 1173s 23:17:04 INFO - Completed test suite (00:19:33) 23:17:04 INFO - javascript benchmark 23:17:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3132.702393272639, 3159.9526007109885, 3171.8096914185144, 3195.5845740053787, 3226.032190342898, 5265.447128051557, 5342.973285133569, 5383.1656093305555, 5451.890962180759, 5482.232487451755, 5386.48828361306, 5398.757055932482, 5406.432324605918, 5448.727563621813, 5464.480874316943, 4826.131296366652, 4902.166631672606, 4914.04176185644, 4927.137750893579, 5110.616703747234, 5010.348654674887, 5051.444341122474, 5067.442581316052, 5082.79668813247, 5129.94836058609, 4832.9275060874115, 4867.221244600847, 4878.756062196911, 4915.950840491585, 4946.035523073002, 4892.779824907888, 4903.950960490385, 4919.72941488218, 4920.926186107211, 4961.677490963076, 4922.212446008657, 4947.529984651453, 4957.702537847741, 4976.502349765031, 5010.72441015744, 5209.244659524347, 5217.895642087175, 5261.289725886994, 5267.841964741064, 5439.700816455093, 5710.543156547466, 5745.569082318842, 5754.625949313323, 5757.942420575824, 5767.279090113736, 5776.884462310731, 5792.073268277056, 5800.187973683687, 5805.36141024487, 5806.274215723035, 5304.0, 5320.228566857784, 5377.059014672417, 5392.379876314235, 5450.809221677222, 5934.792282270186, 5940.0, 5941.197938278364, 5953.761849526057, 5963.0459126539545, 5520.089185284383, 5696.829095127153, 5699.572532060112, 5703.144528320785, 5703.7148142592705, 5758.7696492140685, 5807.274090738658, 5807.361190269067, 5833.7374818133285, 5836.9124463133085, 5811.0, 5815.79644712433, 5818.8254352369495, 5820.650760954271, 5823.388544202798, 5629.887402252014, 5780.624259423208, 5791.681457519877, 5817.912731309034, 5819.243498345273, 4403.339499075164, 4405.955940440619, 4415.072834704683, 4459.5986361227, 4465.866024019285, 3936.881893543198, 3943.231069941391, 3947.348687466536, 3960.32674445345, 3996.1208534122447, 5246.265522826808, 5339.172428273624, 5380.650257733235, 5558.833235002957, 5630.40880707528, 5690.0, 5736.6844823534275, 5755.510781583528, 5789.044807606782, 5820.563457740602, 5445.37378201504, 5445.428230035868, 5457.836264912059, 5470.452954704498, 5473.507384335429, 4823.589994850477, 4966.0, 4972.950270497321, 4997.300377947017, 4999.950000499948, 6060.939390606126, 6084.482818960438, 6094.664793436404, 6096.481799047131, 6110.480609148272, 5880.529557635379, 5884.293884733859, 5886.911696324473, 5903.970480147572, 5907.675077870758, 5872.794452194153, 5995.070764031582, 6025.126356678345, 6046.123312119806, 6054.7275372607455, 5701.087825947829, 5759.280089988752, 5767.221425107557, 5768.625039372428, 5769.221155144001, 5846.6784326862435, 5863.2670916135485, 5865.882682346244, 5867.090600956859, 5885.411458854081, 5420.078586640359, 5681.346645135863, 5735.541156707537, 5746.626469279484, 5748.109043098327, 5699.743511542073, 5700.401457847032, 5700.971495142498, 5713.285839270091, 5721.485066344049, 5454.536364409069, 5621.353544342453, 5629.915551266652, 5635.88728225425, 5651.0, 5621.831345059655, 5653.915191272052, 5679.403662615366, 5734.082546792493, 5785.3636100028125, 5579.525740312119, 5583.469570390806, 5587.44125587438, 5588.888222235614, 5635.126555383922], "value": 5347.065866932131, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.6037575575875, 618.9845253868652, 627.1627377451101, 633.4299130782296, 634.4448607468465, 31838.20404489886, 44838.77580612097, 45713.54286457135, 45837.77081114598, 49473.25790113151, 46095.53904460954, 46413.76793116038, 46582.53417465824, 46630.300545491846, 46687.29969050459, 8593.656253749858, 8746.387752857303, 8748.168923952215, 8753.255973242281, 8755.605997730117, 8708.0, 8764.780880478007, 8819.250363719091, 8828.337874659393, 8840.734777956672, 8802.64789408423, 8827.33794965377, 8851.5574221289, 8864.246539044188, 8866.379353445262, 8785.38502304839, 8829.293656507463, 8830.072842351588, 8837.072107428692, 8840.690575829847, 8737.563121843883, 8823.161799629024, 8827.337949653802, 8832.470051796849, 8842.0, 42882.35676464855, 43155.0, 43272.0, 43280.0, 43352.3497147543, 3398.3092988562903, 3586.8923932281814, 4165.125323682, 4170.979145104286, 4173.499180098408, 32846.17884552881, 32994.3401131976, 33383.83308083468, 33385.6661433385, 34678.6532134678, 4241.533431322552, 4445.599896009375, 4469.061497085616, 4504.144212599595, 4512.165249428866, 344208.0, 344684.0, 345220.2738986314, 345370.0, 346780.0, 60409.39590604038, 60698.39301607016, 61067.389326107055, 61096.69451652713, 61194.38805611887, 65100.34899651037, 65126.67436662881, 65256.0, 65334.673326634016, 65429.0, 16799.580010499834, 17041.82958170427, 17058.48824535266, 17064.2321095551, 17067.573310667332, 11932.821007684892, 11937.940310298392, 11948.462319195483, 11955.581554645545, 11961.684214736371, 16689.49931502057, 18337.17482713281, 18437.631247375244, 18523.259069637334, 18585.907070464833, 5972.954732921721, 6615.17310336208, 6642.966785166043, 6832.180138383326, 7012.091535924873, 1027.9537420816082, 1040.360178490223, 1083.7182332593582, 1085.858838351025, 1218.3786269002694, 32359.191020224684, 33132.33735325328, 33234.335313293595, 33421.498677519856, 33532.66467335344, 1121.2207515776456, 1146.2778449576713, 1156.6356597671706, 1169.1698893785338, 1192.4037981009494, 16258.47315813419, 17005.23476443563, 17050.147492625318, 17103.657926841643, 17111.91444042797, 499314.0, 502085.0, 505526.0, 505682.0, 506257.0, 59679.403205967385, 59792.0, 60010.39989600048, 60041.39958600358, 60281.39718602758, 1170.0931777872217, 1263.418827339434, 1362.434589645306, 1399.818023656918, 1409.7673883809261, 32610.673893260762, 34044.31911361808, 34519.0, 34573.0, 34638.82680586581, 52165.7391713039, 52431.0, 52611.73694131658, 52626.21060684016, 52662.736686316326, 417843.0, 422188.0, 423913.0, 424690.0, 425932.0, 28325.150245492667, 28521.42957140887, 28944.7105528942, 29053.854730726212, 29196.562051568817, 43140.13719725651, 49706.75146624244, 50158.247626286364, 50179.74910125426, 51372.22941655803, 36935.26129477449, 41073.794631026656, 43713.56286437222, 44604.33093503665, 46080.539194608966, 41907.371389428576, 42446.787766060974, 42550.361744574475, 42608.0, 43055.0, 42645.14709705851, 43547.0, 43554.56445435505, 43719.0, 43967.0, 35945.820270898475, 37649.43525847169, 37650.81174594109, 38201.235975279786, 38436.23127537489, 28742.42515149727, 28871.85564072166, 28889.422211556073, 29058.418831623672, 29081.85459072691], "value": 20413.281989027986, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.70967193948417, 400.99799501002525, 408.8344220590662, 409.5924555067707, 412.81004272335247, 18252.0, 18458.353957611453, 19620.803791962077, 19694.0, 19704.11331490081, 18575.44273671792, 19204.0, 19393.806061939376, 19943.30198443055, 19951.0, 5551.333839939213, 5594.608377413582, 5688.63023903445, 5714.628549144313, 5720.456556627114, 5602.495775380215, 5633.661980281176, 5636.971815140919, 5661.0, 5661.631993920402, 5499.642523235997, 5510.972445137769, 5640.8307750767335, 5643.4074422185695, 5643.435656434365, 5519.282493275869, 5527.364353099405, 5556.444355564452, 5565.165225216209, 5592.245046918654, 4957.107720610289, 5052.7726252318735, 5083.034223497561, 5091.61812864033, 5092.28707980883, 22870.656940145913, 23031.424214394607, 23367.883160584257, 23608.0, 23631.0, 2497.4505608766044, 2552.8723563821736, 3017.2456786059834, 3118.4230917280147, 3128.562001319817, 13984.300784960711, 14491.130532168103, 14560.271986400638, 15095.622609434742, 15243.466478673194, 1988.8309493693052, 2034.6642803937332, 2045.2841505473114, 2094.780048094943, 2156.482444213393, 67396.0, 80562.59718701428, 82072.0, 82823.58588207081, 83136.58431707864, 10389.37663740178, 10713.571457141783, 11043.392613406178, 11103.444827758742, 11235.101191904629, 15184.01303915242, 15259.0, 15286.464973725866, 15301.9234903827, 15323.080615163126, 8157.75526734199, 8276.627551760184, 8372.372072094617, 8415.831683366421, 8421.073681894986, 7869.803254918673, 7938.603069846485, 7951.602419879098, 8052.677892884219, 8175.83648327042, 6669.7665581704405, 6825.0, 6856.6228857413325, 6865.862682746317, 6902.689378977958, 1934.1489744512369, 1956.5206524401328, 1965.3023176772326, 1987.7515310586177, 1994.8902810345571, 979.5543027922279, 999.2279146222921, 1057.577558184241, 1064.0049906425452, 1100.4332768624165, 18465.90767046183, 19709.802901970797, 20671.276505322243, 20950.89524552398, 20997.79002209989, 977.8191034658611, 1003.3277703938379, 1013.1884021839454, 1013.4122209118693, 1021.5556233038652, 10570.735731606772, 11226.7193320166, 11258.155638327158, 11260.94369528147, 11335.206535542433, 525303.0, 533279.0, 536538.0, 540107.0, 540232.0, 74064.0, 87805.0, 90171.54914225386, 97077.0, 98400.0, 1009.6264382178641, 1017.3946501831399, 1041.6146025970288, 1057.7408534908996, 1058.2486434631344, 17966.281348746426, 18116.90941545284, 18181.0, 18392.724109138642, 18598.07009649539, 38245.80877095597, 44382.55617443784, 44778.77610611926, 45152.32271515864, 46503.53496465128, 48556.75721621369, 59755.70122149361, 60335.39664603474, 61021.38978610157, 62250.68874655598, 8417.368697347845, 8634.611442484978, 8653.0, 8673.609687564202, 8955.328350373878, 9676.56455459492, 10213.795724085625, 12134.847189516982, 12888.677783055498, 13127.146735462164, 11143.554257829921, 11321.547138114383, 11357.659270221906, 11647.184697071123, 11960.940195298968, 11101.777964440504, 11308.473356096822, 11692.181547291608, 11746.823797643214, 11926.940365298118, 11442.48508817122, 11494.310341379543, 11587.768244635228, 11589.24669896454, 11699.713031566691, 7475.102987641518, 8590.398672092893, 8742.606582703665, 8756.824863502567, 8804.867926980973, 9749.9025009749, 11045.723856903642, 11603.825942611036, 11635.941820290844, 11843.52625894989], "value": 9475.592133637776, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1261.9684507887296, 1449.8622630850068, 1496.7380708376033, 1617.951461456156, 1808.7286906963961, 8566.785830354243, 8923.063078376774, 8927.910720892798, 9180.678676246333, 9195.816083678339, 8906.376553641232, 9113.817723645523, 9117.407368521042, 9121.361504694674, 9127.543622818866, 1980.2870966452138, 2278.9658155127686, 2334.801541868939, 2363.8108951283943, 2372.3713215997773, 2400.72391674957, 2409.4578719788083, 2411.0837881604966, 2412.49337639096, 2412.9276121716375, 2312.225404489498, 2359.516299158677, 2362.893669784864, 2363.432776133724, 2368.111958015744, 1973.437570292467, 2236.9664455033267, 2315.6526521021815, 2318.2813327868416, 2340.9297721068397, 2275.8748268845206, 2286.3598192506124, 2293.9333210057403, 2294.357579877637, 2311.9884400578057, 8776.956115219447, 8853.69012084574, 8879.689210877588, 8892.421992570527, 8995.55022248885, 5221.425643179247, 5385.057614917413, 5390.434004429557, 5391.514763671249, 5392.0294347017525, 3275.918102047444, 3390.559227300435, 3393.100828280483, 3399.8640054397792, 3449.5170676105367, 1038.9013043760906, 1068.8022715797601, 1074.6292529077455, 1088.7604726960135, 1100.4827730966433, 12601.30692811895, 12630.49478020878, 12696.492140314376, 12720.109592328541, 12725.681857953532, 4561.156186105581, 4672.0, 4763.428388593391, 4766.308885211625, 4853.39332583427, 10562.419066951237, 10573.101286390589, 10594.947025264824, 10598.311109777995, 10617.469126543796, 4719.882002949954, 5352.866178345573, 5419.945800541944, 5427.0, 5433.755481003364, 4771.665983381199, 4937.703737775673, 4953.034158339089, 4989.575886049726, 4995.225740010304, 4033.838646454109, 4073.490813648294, 4110.753354798721, 4266.741311313108, 4290.4636920384955, 1254.9749005019848, 1306.8366454193226, 1306.9019823513274, 1313.0874042540534, 1334.8197993270978, 985.142925654685, 1008.4856723071287, 1011.413380239474, 1019.3883669798211, 1056.730533713898, 6079.6960151992225, 6150.985087460519, 6159.611160257285, 6160.10678451622, 6253.1245625612455, 437.82924659382866, 443.5675216663727, 445.2697575975403, 447.11471286851565, 454.4001917468909, 4259.488861336659, 4300.311950087971, 4314.460692413449, 4344.652427805768, 4347.369631403429, 10722.356658600509, 11063.114950803916, 11165.106791456665, 11207.495662695199, 11236.0, 10579.259451838298, 11164.497597607964, 11180.664580062612, 11188.104951603853, 11197.888021119847, 8041.236082572146, 8349.081601023883, 8398.622062007224, 8414.284785793276, 8441.324694024463, 6028.487578555872, 6379.904301435389, 6400.445525108069, 6410.294867564658, 6454.0, 10389.584416623553, 10532.0, 10593.470326483799, 10644.414557199429, 10658.200634952254, 10313.432761198206, 10522.421266830397, 10817.621383251862, 10823.134149268197, 10827.891721083004, 7085.787426377217, 7605.0, 7762.068551773823, 7896.407769417431, 8017.358611311198, 8590.91409085901, 8596.914030859862, 8616.612252448529, 8665.956670216608, 8671.523066231417, 7467.551946883204, 7550.584717840571, 7602.6198690066385, 7627.351675107456, 7739.110002349802, 7430.517016393917, 7435.330820226206, 7464.141623713125, 7489.176190619137, 7603.619819009138, 6721.193456785021, 6764.93235067643, 6799.7620083298825, 6811.352921472452, 6813.3527314905, 4753.6434767393275, 5129.384473863161, 5285.260063591023, 5287.68273903567, 5342.75957581902, 5294.0, 5348.625596208383, 5415.1335786274, 5435.592330575144, 5493.533049690821], "value": 4611.10870871232, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.77136661386953, 334.6636630186662, 341.9263512570529, 343.70441420378455, 345.19741600778195, 5291.444398338172, 5316.9734151329185, 5324.307839980797, 5337.279467271917, 5375.408705042452, 5261.500157485033, 5268.209768534722, 5301.5228629423345, 5321.680699158053, 5324.787008519665, 3916.1384495411035, 4045.8381664733374, 4176.958230417703, 4178.185253875496, 4229.471316085489, 4125.855595054174, 4154.750714957112, 4166.7708276044805, 4182.142660754538, 4184.832606695729, 3913.9217215655813, 4161.188568229197, 4176.770277634729, 4178.853740119096, 4191.979040104795, 4037.7173597848164, 4163.458750362463, 4186.832526698929, 4191.706580539363, 4192.496900371975, 3684.7604905681046, 3717.460968159629, 3721.497597824286, 3727.7949712765785, 3737.4767532545466, 5120.0, 5132.281480592683, 5158.40678321994, 5159.871003224912, 5162.741862906877, 4492.158215131781, 4675.836345727883, 4682.836100736458, 4720.929186062178, 4750.572448479654, 5460.31746031746, 5479.479449452296, 5494.532964698005, 5495.175723641446, 5555.166724991283, 3981.0, 3987.342088555385, 3992.941870404345, 3996.140829721624, 4006.4390985262085, 5540.002799496089, 5744.942550574524, 5757.654540727528, 5785.595008349375, 5791.913121303184, 4070.247004304213, 4080.7553696122372, 4081.8775436736946, 4083.734557253769, 4100.1139475840855, 4135.276326642826, 4138.151678905817, 4145.709800313949, 4160.1471698301775, 4188.4136220929095, 4635.490096089427, 4660.4174478190225, 4742.430908291027, 4750.809967601257, 4770.952290477051, 5582.106862901916, 5627.143042795868, 5642.915356269659, 5645.5483561314995, 5653.321601407857, 1431.8067060946846, 1436.2746812859648, 1440.8271007478959, 1443.9061461005, 1447.6742732885016, 866.2204016385303, 889.3774357949461, 923.0, 972.4845831709206, 973.3867663372031, 5263.0, 5269.815556455583, 5274.551663108678, 5299.258103865462, 5308.973455132699, 5582.469665381782, 5589.720513974366, 5640.266765320564, 5643.943560564424, 5667.234923285386, 5116.513931176532, 5282.5436172180125, 5302.973485132549, 5447.0, 5450.3732070811575, 3038.7872848900806, 3044.0, 3060.32672811981, 3067.9846600766855, 3091.165385345944, 5711.771529138788, 5938.198343223609, 5945.235465508559, 5948.434898684618, 5999.640021598718, 5801.187833703285, 5801.448862358069, 5802.6808525531505, 5802.82591522255, 5824.60209549705, 5839.686070634159, 5928.288605367297, 5935.258092738408, 5938.8812223755285, 5970.8655355482315, 5434.972825135849, 5723.99660118955, 5740.225069615549, 5744.971275143597, 5765.0, 5604.618037334199, 5609.24275222856, 5635.408282130481, 5678.677973385616, 5694.743736531949, 5781.913271300849, 5891.49922256613, 5940.198073260054, 5976.133460648269, 6022.819315420545, 4022.8591999279884, 4029.8843107368866, 4046.7571945683353, 4062.532808727034, 4063.1670507546473, 5783.6818974955095, 5817.883642327214, 5838.94161058384, 5860.326062502867, 5863.651360187096, 5451.64564303319, 5474.835754927359, 5587.636803607753, 5610.85972850682, 5670.5180059695385, 5553.888922221614, 5571.275734154534, 5588.077967135539, 5588.133839254922, 5589.189567512606, 5286.444923283, 5482.369527504388, 5488.835334939959, 5521.4478552144465, 5534.252875861707, 5473.31583552056, 5559.499645031967, 5562.749676264497, 5576.609637325349, 5645.802396916089, 5482.835514934558, 5498.642588231752, 5552.722873738981, 5558.944410556004, 5562.414711807116], "value": 4254.529697413229, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.5936556797059, 797.8045378882175, 799.6881216325634, 814.2020819596797, 818.7093581778465, 14587.927060364696, 14992.925035374808, 15137.697246055071, 15405.537833864988, 15954.601134971632, 14157.787633185484, 14232.857671423282, 14295.857041429583, 14298.571042868705, 14305.642358941031, 690.2510775808254, 803.618281316373, 814.816666250095, 825.6202147012351, 829.0631586307494, 832.1553623072587, 832.4797001873828, 835.5028757889046, 835.7952301686096, 836.5148214035875, 861.8707193920931, 865.2947847504296, 868.2446271743592, 868.3834477520968, 868.4615538366222, 710.939570136539, 841.4362377207256, 859.7248880358288, 861.4658911474863, 861.8448679237734, 625.562106525434, 625.9155014073087, 625.9624422534617, 626.2109741725413, 626.3298270850192, 14900.0, 14951.700965980728, 14965.70068598622, 14968.101913885203, 14975.475858345015, 12739.808902866464, 13184.208947463183, 13228.537001204912, 13386.866131338757, 13587.660308492268, 11371.46726682467, 11482.483288251966, 11595.652130436101, 11702.648920532312, 11723.062155027577, 4235.343521754102, 4252.574742525753, 4258.467691538558, 4423.491298500681, 4488.438945131858, 57330.426695732924, 57433.42566574323, 57629.0, 57639.0, 57822.71088644572, 10266.281360304707, 10443.530041148166, 10447.529861156114, 10478.109360704426, 10488.423707690557, 19907.203711851365, 20041.899790501247, 20054.498637533885, 20121.195152194043, 20133.295334663217, 8284.792880177924, 8403.201695838885, 8833.33749968755, 8865.822683546294, 8866.64533418656, 4178.247915375201, 4235.407043013981, 4263.339182426729, 4575.760887926154, 4672.696274742131, 3232.69289417505, 3300.488424294238, 3302.867885284562, 3324.1855745342546, 3339.081752518077, 627.8399008252958, 640.9262934762469, 645.8901986662279, 650.5413683353319, 664.4119953840789, 627.6861569215392, 635.939585739354, 684.3156843156843, 735.988960165598, 739.5599618227146, 9141.54292285383, 9526.52367381642, 9797.559109840075, 9808.509574521388, 9852.0, 733.9339459448676, 739.8742213823663, 770.2490072179692, 800.1663712257055, 800.2117913854848, 4318.534122977859, 4421.447319085114, 4513.097380523908, 4525.366448697185, 4533.637309015271, 72251.63874180596, 73034.63482682659, 73341.0, 73435.26564734284, 73533.26466735371, 61590.69204653948, 62506.687466562376, 64446.35553644497, 65063.34936650573, 67134.32865671378, 57981.0, 58190.709046454496, 58687.0, 58825.70587147037, 59358.999999999134, 8545.529995850287, 8575.099614540384, 8681.739547813575, 8711.56442177876, 8747.825043499222, 38322.42516362313, 38921.80539097286, 39696.404553931134, 39958.2008359837, 40501.594984049785, 53065.20402193893, 53365.19952200643, 53378.0, 53387.733061334446, 53903.0, 12085.577004804789, 12219.633410997685, 12256.142070055368, 12321.815172772236, 12470.688232794253, 12580.937095314464, 12965.092443528862, 13168.0, 13406.0, 13745.312734363442, 12123.393830308625, 12433.564825231435, 12721.427535761099, 12729.172603780724, 12878.35608219604, 11799.646010619696, 12110.939445303069, 12196.878031219929, 12491.500339986662, 12591.87408125907, 12216.938915305367, 12500.812487812509, 12567.685807854878, 12628.179168354027, 13029.543965961146, 8762.430442021248, 8859.689910853089, 9104.408213466368, 9249.76875578089, 9654.565544550653, 9310.115539023782, 9321.627134914528, 9337.48643824569, 9941.105300522699, 9946.403215807075], "value": 6893.826546693187, "unit": "score"}], "name": "dromaeo_css", "value": 7204.983422453658, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 110.0, 111.0, 111.0, 113.0, 111.0, 111.0, 111.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 126.0, 182.0, 125.0, 125.0, 155.0, 125.0, 167.0, 124.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 162.0, 162.0, 161.0, 162.0, 161.0, 163.0, 162.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 115.0, 75.0, 75.0, 75.0, 74.0, 98.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 122.0, 84.0, 85.0, 86.0, 121.0, 83.0, 84.0, 126.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 118.0, 118.0, 119.0, 118.0, 118.0, 118.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 147.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 55.0, 55.0, 53.0, 54.0, 55.0, 53.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 60.0, 62.0, 61.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 80.0, 79.0, 79.0, 80.0, 80.0, 82.0, 109.0, 80.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 138.0, 123.0, 122.0, 129.0, 126.0, 136.0, 125.0, 134.0, 129.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 167.0, 163.0, 169.0, 159.0, 159.0, 159.0, 160.0, 159.0, 165.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 67.0, 65.0, 64.0, 62.0, 65.0, 63.0, 63.0, 62.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1500.5000000000002, "alertThreshold": 2.0}]} 23:17:04 INFO - Return code: 0 23:17:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:17:04 ERROR - # TBPL SUCCESS # 23:17:04 INFO - Running post-action listener: _resource_record_post_action 23:17:04 INFO - ##### 23:17:04 INFO - ##### Finished run-tests step (success) 23:17:04 INFO - ##### 23:17:04 INFO - Running post-run listener: _resource_record_post_run 23:17:04 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 549163008; Read time: 16; Write time: 464408 23:17:04 INFO - TinderboxPrint: CPU usage
13.1% 23:17:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 23:17:04 INFO - TinderboxPrint: I/O write bytes / time
549,163,008 / 464,408 23:17:04 INFO - TinderboxPrint: CPU idle
8,258.6 (86.9%) 23:17:04 INFO - TinderboxPrint: CPU user
1,204.3 (12.7%) 23:17:04 INFO - TinderboxPrint: Swap in / out
0 / 0 23:17:04 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 234962944; Read time: 0; Write time: 244572 23:17:04 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292356096; Read time: 16; Write time: 214740 23:17:05 INFO - Running post-run listener: _upload_blobber_files 23:17:05 INFO - Blob upload gear active. 23:17:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:17:05 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:17:05 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:17:05 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:17:05 INFO - (blobuploader) - INFO - Open directory for files ... 23:17:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:17:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:17:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:06 INFO - (blobuploader) - INFO - Done attempting. 23:17:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:17:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:17:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:07 INFO - (blobuploader) - INFO - Done attempting. 23:17:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:17:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:17:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:17:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:17:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:17:13 INFO - (blobuploader) - INFO - Done attempting. 23:17:13 INFO - (blobuploader) - INFO - Iteration through files over. 23:17:13 INFO - Return code: 0 23:17:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:17:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:17:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7da15cd616291e002f57758647b06c59147f846959856047404e0e62fe118d0b3f23241e4173ad8620f5d61dc62b9bae690f7745003de8d740bff36e0b3bf147", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60a312abbd93e35cd5239b3f8b6f542f1d021f476c09997306e47e80fde10fa4b88631a6da1b166e85a82b374c6b29327d3998109b0b97a449ee74aecf51f4fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9c2276f5c08cb4629d41c34506d1f0e6671cfe22946ecd54b21fe98bb617089bbd8917d1b94853c7a35d491f6958d0b4e4773adf773630d2a58c31a1bcc2e83"} 23:17:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:17:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:17:13 INFO - Contents: 23:17:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7da15cd616291e002f57758647b06c59147f846959856047404e0e62fe118d0b3f23241e4173ad8620f5d61dc62b9bae690f7745003de8d740bff36e0b3bf147", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60a312abbd93e35cd5239b3f8b6f542f1d021f476c09997306e47e80fde10fa4b88631a6da1b166e85a82b374c6b29327d3998109b0b97a449ee74aecf51f4fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9c2276f5c08cb4629d41c34506d1f0e6671cfe22946ecd54b21fe98bb617089bbd8917d1b94853c7a35d491f6958d0b4e4773adf773630d2a58c31a1bcc2e83"} 23:17:13 INFO - Running post-run listener: copy_logs_to_upload_dir 23:17:13 INFO - Copying logs to upload dir... 23:17:13 INFO - mkdir: /builds/slave/test/build/upload/logs 23:17:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.376869 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-26 23:17:12.737079) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 23:17:12.745043) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681 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/7da15cd616291e002f57758647b06c59147f846959856047404e0e62fe118d0b3f23241e4173ad8620f5d61dc62b9bae690f7745003de8d740bff36e0b3bf147", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60a312abbd93e35cd5239b3f8b6f542f1d021f476c09997306e47e80fde10fa4b88631a6da1b166e85a82b374c6b29327d3998109b0b97a449ee74aecf51f4fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9c2276f5c08cb4629d41c34506d1f0e6671cfe22946ecd54b21fe98bb617089bbd8917d1b94853c7a35d491f6958d0b4e4773adf773630d2a58c31a1bcc2e83"} build_url:https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011496 build_url: 'https://queue.taskcluster.net/v1/task/JJttUPQVRKWpZSNCN0uiWw/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/7da15cd616291e002f57758647b06c59147f846959856047404e0e62fe118d0b3f23241e4173ad8620f5d61dc62b9bae690f7745003de8d740bff36e0b3bf147", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60a312abbd93e35cd5239b3f8b6f542f1d021f476c09997306e47e80fde10fa4b88631a6da1b166e85a82b374c6b29327d3998109b0b97a449ee74aecf51f4fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9c2276f5c08cb4629d41c34506d1f0e6671cfe22946ecd54b21fe98bb617089bbd8917d1b94853c7a35d491f6958d0b4e4773adf773630d2a58c31a1bcc2e83"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 23:17:12.778246) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 23:17:12.778641) ========= 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=c6927cf182745ad25b1d38b9000001fe-1464325747.212851-1949741681 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 23:17:12.858341) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 23:17:12.858667) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 23:17:12.858963) ========= ========= Total master_lag: 0.18 =========