builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1464454832.85 results: success (0) buildid: 20160528073029 builduid: f37ed4bef6a44b8abb86cec236b02141 revision: 2c7440e46d8786b2c82a1d2004e2b6d9d13f4046 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:32.848611) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:32.849462) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:32.849776) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:32.913383) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:32.913702) ========= 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=00204705a9daa57ed339a427000001b9-1464451546.592070-541981297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-28 10:00: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.8M=0s 2016-05-28 10:00:33 (40.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.030090) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.030414) ========= 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=00204705a9daa57ed339a427000001b9-1464451546.592070-541981297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016988 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.088228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.088590) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2c7440e46d8786b2c82a1d2004e2b6d9d13f4046 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2c7440e46d8786b2c82a1d2004e2b6d9d13f4046 --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=00204705a9daa57ed339a427000001b9-1464451546.592070-541981297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-28 10:00:33,379 truncating revision to first 12 chars 2016-05-28 10:00:33,379 Setting DEBUG logging. 2016-05-28 10:00:33,379 attempt 1/10 2016-05-28 10:00:33,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2c7440e46d87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 10:00:33,690 unpacking tar archive at: mozilla-central-2c7440e46d87/testing/mozharness/ program finished with exit code 0 elapsedTime=0.705848 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.813742) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.814422) ========= script_repo_revision: 2c7440e46d8786b2c82a1d2004e2b6d9d13f4046 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.815404) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.816538) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 10:00:33.827567) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-28 10:00:33.828218) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=00204705a9daa57ed339a427000001b9-1464451546.592070-541981297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:00:34 INFO - MultiFileLogger online at 20160528 10:00:34 in /builds/slave/test 10:00:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:00:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:00:34 INFO - {'append_to_log': False, 10:00:34 INFO - 'base_work_dir': '/builds/slave/test', 10:00:34 INFO - 'blob_upload_branch': 'Firefox', 10:00:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:00:34 INFO - 'branch': 'Firefox', 10:00:34 INFO - 'buildbot_json_path': 'buildprops.json', 10:00:34 INFO - 'config_files': ('talos/linux_config.py',), 10:00:34 INFO - 'default_actions': ('clobber', 10:00:34 INFO - 'read-buildbot-config', 10:00:34 INFO - 'download-and-extract', 10:00:34 INFO - 'populate-webroot', 10:00:34 INFO - 'create-virtualenv', 10:00:34 INFO - 'install', 10:00:34 INFO - 'run-tests'), 10:00:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:00:34 INFO - 'download_minidump_stackwalk': True, 10:00:34 INFO - 'download_symbols': 'ondemand', 10:00:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:00:34 INFO - 'tooltool.py': '/tools/tooltool.py', 10:00:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:00:34 INFO - '/tools/misc-python/virtualenv.py')}, 10:00:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:00:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:00:34 INFO - 'installer_path': 'installer.exe', 10:00:34 INFO - 'log_level': 'info', 10:00:34 INFO - 'log_name': 'talos', 10:00:34 INFO - 'log_to_console': True, 10:00:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:00:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:00:34 INFO - 'opt_config_files': (), 10:00:34 INFO - 'pip_index': False, 10:00:34 INFO - 'sps_profile': False, 10:00:34 INFO - 'sps_profile_interval': 0, 10:00:34 INFO - 'suite': 'dromaeojs', 10:00:34 INFO - 'system_bits': '32', 10:00:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:00:34 INFO - 'title': 'talos-linux64-ix-016', 10:00:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:00:34 INFO - 'use_talos_json': True, 10:00:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:00:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:00:34 INFO - 'work_dir': 'build'} 10:00:34 INFO - ##### 10:00:34 INFO - ##### Running clobber step. 10:00:34 INFO - ##### 10:00:34 INFO - Running pre-action listener: _resource_record_pre_action 10:00:34 INFO - Running main action method: clobber 10:00:34 INFO - rmtree: /builds/slave/test/build 10:00:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:00:34 INFO - Running post-action listener: _resource_record_post_action 10:00:34 INFO - ##### 10:00:34 INFO - ##### Finished clobber step (success) 10:00:34 INFO - ##### 10:00:34 INFO - ##### 10:00:34 INFO - ##### Running read-buildbot-config step. 10:00:34 INFO - ##### 10:00:34 INFO - Running pre-action listener: _resource_record_pre_action 10:00:34 INFO - Running main action method: read_buildbot_config 10:00:34 INFO - Using buildbot properties: 10:00:34 INFO - { 10:00:34 INFO - "project": "", 10:00:34 INFO - "product": "firefox", 10:00:34 INFO - "script_repo_revision": "production", 10:00:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:00:34 INFO - "repository": "", 10:00:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 10:00:34 INFO - "buildid": "20160528073029", 10:00:34 INFO - "pgo_build": "True", 10:00:34 INFO - "basedir": "/builds/slave/test", 10:00:34 INFO - "buildnumber": 461, 10:00:34 INFO - "slavename": "talos-linux64-ix-016", 10:00:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:00:34 INFO - "platform": "ubuntu64_hw", 10:00:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 10:00:34 INFO - "branch": "mozilla-central", 10:00:34 INFO - "repo_path": "mozilla-central", 10:00:34 INFO - "revision": "2c7440e46d8786b2c82a1d2004e2b6d9d13f4046", 10:00:34 INFO - "stage_platform": "linux64-pgo", 10:00:34 INFO - "builduid": "f37ed4bef6a44b8abb86cec236b02141", 10:00:34 INFO - "slavebuilddir": "test" 10:00:34 INFO - } 10:00:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:00:34 INFO - Running post-action listener: _resource_record_post_action 10:00:34 INFO - ##### 10:00:34 INFO - ##### Finished read-buildbot-config step (success) 10:00:34 INFO - ##### 10:00:34 INFO - ##### 10:00:34 INFO - ##### Running download-and-extract step. 10:00:34 INFO - ##### 10:00:34 INFO - Running pre-action listener: _resource_record_pre_action 10:00:34 INFO - Running main action method: download_and_extract 10:00:34 INFO - mkdir: /builds/slave/test/build/tests 10:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:34 INFO - https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:00:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:00:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:00:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:41 INFO - Downloaded 1361 bytes. 10:00:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:00:41 INFO - Using the following test package requirements: 10:00:41 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:00:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:00:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:00:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'jsshell-linux-x86_64.zip'], 10:00:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:00:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:00:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:00:41 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:00:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:00:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:00:41 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:00:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:41 INFO - https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:00:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:00:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:00:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:45 INFO - Downloaded 24410716 bytes. 10:00:45 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'] 10:00:45 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 10:00:47 INFO - Return code: 0 10:00:47 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 10:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:47 INFO - https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:53 INFO - Downloaded 24410716 bytes. 10:00:53 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'] 10:00:53 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 10:00:55 INFO - Return code: 0 10:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:55 INFO - https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:00:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:00:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:00:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:57 INFO - Downloaded 10834034 bytes. 10:00:57 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'] 10:00:57 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 10:00:57 INFO - Return code: 0 10:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:57 INFO - https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:00:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:00:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:00:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U-ntC1eJRoKvQVol3jHrDg/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 10:00:59 INFO - Downloaded 58746831 bytes. 10:00:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:00:59 INFO - mkdir: /builds/slave/test/properties 10:00:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:00:59 INFO - Writing to file /builds/slave/test/properties/build_url 10:00:59 INFO - Contents: 10:00:59 INFO - build_url:https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:01:00 INFO - Running post-action listener: _resource_record_post_action 10:01:00 INFO - Running post-action listener: set_extra_try_arguments 10:01:00 INFO - ##### 10:01:00 INFO - ##### Finished download-and-extract step (success) 10:01:00 INFO - ##### 10:01:00 INFO - ##### 10:01:00 INFO - ##### Running populate-webroot step. 10:01:00 INFO - ##### 10:01:00 INFO - Running pre-action listener: _resource_record_pre_action 10:01:00 INFO - Running main action method: populate_webroot 10:01:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:01:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:01:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:01:00 INFO - '--tppagecycles', 10:01:00 INFO - '7'], 10:01:00 INFO - 'tests': ['tsvgm']}}, 10:01:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:01:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:01:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:01:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:01:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['damp', 'tps']}, 10:01:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'tests': ['damp', 'tps']}, 10:01:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['dromaeo_dom']}, 10:01:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:01:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other-e10s': {'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['a11yr', 10:01:00 INFO - 'ts_paint', 10:01:00 INFO - 'tpaint', 10:01:00 INFO - 'sessionrestore', 10:01:00 INFO - 'sessionrestore_no_auto_restore', 10:01:00 INFO - 'tabpaint']}, 10:01:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tsvgx', 10:01:00 INFO - 'tsvgr_opacity', 10:01:00 INFO - 'tart', 10:01:00 INFO - 'tscrollx', 10:01:00 INFO - 'cart']}, 10:01:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:01:00 INFO - 'tsvgr_opacity', 10:01:00 INFO - 'tart', 10:01:00 INFO - 'tscrollx', 10:01:00 INFO - 'cart']}, 10:01:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s'], 10:01:00 INFO - 'tests': ['tp5o']}, 10:01:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'tests': ['tp5o']}, 10:01:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'talos_options': ['--disable-e10s', 10:01:00 INFO - '--xperf_path', 10:01:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:00 INFO - 'tests': ['tp5n']}, 10:01:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:00 INFO - 'talos_options': ['--xperf_path', 10:01:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:00 INFO - 'tests': ['tp5n']}}, 10:01:00 INFO - 'talos.zip': {'path': '', 10:01:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:01:00 INFO - Running post-action listener: _resource_record_post_action 10:01:00 INFO - ##### 10:01:00 INFO - ##### Finished populate-webroot step (success) 10:01:00 INFO - ##### 10:01:00 INFO - ##### 10:01:00 INFO - ##### Running create-virtualenv step. 10:01:00 INFO - ##### 10:01:00 INFO - Running pre-action listener: _resource_record_pre_action 10:01:00 INFO - Running main action method: create_virtualenv 10:01:00 INFO - Creating virtualenv /builds/slave/test/build/venv 10:01:00 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 10:01:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:01:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:01:00 INFO - Using real prefix '/usr' 10:01:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:01:00 INFO - Installing distribute.............................................................................................................................................................................................done. 10:01:02 INFO - Installing pip.................done. 10:01:02 INFO - Return code: 0 10:01:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:02 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:02 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 10:01:02 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 10:01:02 INFO - Using env: {'DISPLAY': ':0', 10:01:02 INFO - 'HOME': '/home/cltbld', 10:01:02 INFO - 'LANG': 'en_US.UTF-8', 10:01:02 INFO - 'LANGUAGE': 'en_US:en', 10:01:02 INFO - 'LOGNAME': 'cltbld', 10:01:02 INFO - 'MAIL': '/var/mail/cltbld', 10:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:02 INFO - 'MOZ_NO_REMOTE': '1', 10:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:02 INFO - 'NO_EM_RESTART': '1', 10:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:02 INFO - 'PWD': '/builds/slave/test', 10:01:02 INFO - 'SHELL': '/bin/bash', 10:01:02 INFO - 'SHLVL': '1', 10:01:02 INFO - 'TERM': 'linux', 10:01:02 INFO - 'TMOUT': '86400', 10:01:02 INFO - 'USER': 'cltbld', 10:01:02 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:02 INFO - '_': '/tools/buildbot/bin/python'} 10:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:02 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 10:01:02 INFO - Cleaning up... 10:01:02 INFO - Return code: 0 10:01:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:02 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:02 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 10:01:02 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 10:01:02 INFO - Using env: {'DISPLAY': ':0', 10:01:02 INFO - 'HOME': '/home/cltbld', 10:01:02 INFO - 'LANG': 'en_US.UTF-8', 10:01:02 INFO - 'LANGUAGE': 'en_US:en', 10:01:02 INFO - 'LOGNAME': 'cltbld', 10:01:02 INFO - 'MAIL': '/var/mail/cltbld', 10:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:02 INFO - 'MOZ_NO_REMOTE': '1', 10:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:02 INFO - 'NO_EM_RESTART': '1', 10:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:02 INFO - 'PWD': '/builds/slave/test', 10:01:02 INFO - 'SHELL': '/bin/bash', 10:01:02 INFO - 'SHLVL': '1', 10:01:02 INFO - 'TERM': 'linux', 10:01:02 INFO - 'TMOUT': '86400', 10:01:02 INFO - 'USER': 'cltbld', 10:01:02 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:02 INFO - '_': '/tools/buildbot/bin/python'} 10:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:03 INFO - Downloading/unpacking psutil>=3.1.1 10:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:01:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:05 INFO - Installing collected packages: psutil 10:01:05 INFO - Running setup.py install for psutil 10:01:05 INFO - building 'psutil._psutil_linux' extension 10:01:05 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 10:01:05 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 10:01:05 INFO - building 'psutil._psutil_posix' extension 10:01:05 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 10:01:05 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 10:01:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:05 INFO - Successfully installed psutil 10:01:05 INFO - Cleaning up... 10:01:05 INFO - Return code: 0 10:01:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:05 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:05 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 10:01:05 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 10:01:05 INFO - Using env: {'DISPLAY': ':0', 10:01:05 INFO - 'HOME': '/home/cltbld', 10:01:05 INFO - 'LANG': 'en_US.UTF-8', 10:01:05 INFO - 'LANGUAGE': 'en_US:en', 10:01:05 INFO - 'LOGNAME': 'cltbld', 10:01:05 INFO - 'MAIL': '/var/mail/cltbld', 10:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:05 INFO - 'MOZ_NO_REMOTE': '1', 10:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:05 INFO - 'NO_EM_RESTART': '1', 10:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:05 INFO - 'PWD': '/builds/slave/test', 10:01:05 INFO - 'SHELL': '/bin/bash', 10:01:05 INFO - 'SHLVL': '1', 10:01:05 INFO - 'TERM': 'linux', 10:01:05 INFO - 'TMOUT': '86400', 10:01:05 INFO - 'USER': 'cltbld', 10:01:05 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:05 INFO - '_': '/tools/buildbot/bin/python'} 10:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:01:07 INFO - Installing collected packages: mozsystemmonitor 10:01:07 INFO - Running setup.py install for mozsystemmonitor 10:01:07 INFO - Successfully installed mozsystemmonitor 10:01:07 INFO - Cleaning up... 10:01:07 INFO - Return code: 0 10:01:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:01: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')]} 10:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01: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')]} 10:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01: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', '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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:01: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 blobuploader==1.2.4 10:01:07 INFO - Using env: {'DISPLAY': ':0', 10:01:07 INFO - 'HOME': '/home/cltbld', 10:01:07 INFO - 'LANG': 'en_US.UTF-8', 10:01:07 INFO - 'LANGUAGE': 'en_US:en', 10:01:07 INFO - 'LOGNAME': 'cltbld', 10:01:07 INFO - 'MAIL': '/var/mail/cltbld', 10:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:07 INFO - 'MOZ_NO_REMOTE': '1', 10:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:07 INFO - 'NO_EM_RESTART': '1', 10:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:07 INFO - 'PWD': '/builds/slave/test', 10:01:07 INFO - 'SHELL': '/bin/bash', 10:01:07 INFO - 'SHLVL': '1', 10:01:07 INFO - 'TERM': 'linux', 10:01:07 INFO - 'TMOUT': '86400', 10:01:07 INFO - 'USER': 'cltbld', 10:01:07 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:07 INFO - '_': '/tools/buildbot/bin/python'} 10:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:08 INFO - Downloading/unpacking blobuploader==1.2.4 10:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:10 INFO - Downloading blobuploader-1.2.4.tar.gz 10:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:01:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:01:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:10 INFO - Downloading docopt-0.6.1.tar.gz 10:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:01:10 INFO - Installing collected packages: blobuploader, requests, docopt 10:01:10 INFO - Running setup.py install for blobuploader 10:01:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:01:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:01:10 INFO - Running setup.py install for requests 10:01:11 INFO - Running setup.py install for docopt 10:01:11 INFO - Successfully installed blobuploader requests docopt 10:01:11 INFO - Cleaning up... 10:01:11 INFO - Return code: 0 10:01:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:11 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:11 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 10:01:11 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 10:01:11 INFO - Using env: {'DISPLAY': ':0', 10:01:11 INFO - 'HOME': '/home/cltbld', 10:01:11 INFO - 'LANG': 'en_US.UTF-8', 10:01:11 INFO - 'LANGUAGE': 'en_US:en', 10:01:11 INFO - 'LOGNAME': 'cltbld', 10:01:11 INFO - 'MAIL': '/var/mail/cltbld', 10:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:11 INFO - 'MOZ_NO_REMOTE': '1', 10:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:11 INFO - 'NO_EM_RESTART': '1', 10:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:11 INFO - 'PWD': '/builds/slave/test', 10:01:11 INFO - 'SHELL': '/bin/bash', 10:01:11 INFO - 'SHLVL': '1', 10:01:11 INFO - 'TERM': 'linux', 10:01:11 INFO - 'TMOUT': '86400', 10:01:11 INFO - 'USER': 'cltbld', 10:01:11 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:11 INFO - '_': '/tools/buildbot/bin/python'} 10:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:11 INFO - Running setup.py (path:/tmp/pip-tj7_3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:11 INFO - Running setup.py (path:/tmp/pip-4zPc53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:11 INFO - Running setup.py (path:/tmp/pip-fHtN6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:11 INFO - Running setup.py (path:/tmp/pip-nO9MTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:11 INFO - Running setup.py (path:/tmp/pip-I4RbPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:12 INFO - Running setup.py (path:/tmp/pip-iOZTLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:12 INFO - Running setup.py (path:/tmp/pip-MrW3a_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:12 INFO - Running setup.py (path:/tmp/pip-v3czQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:12 INFO - Running setup.py (path:/tmp/pip-nXHDQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:12 INFO - Running setup.py (path:/tmp/pip-O3wO95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:12 INFO - Running setup.py (path:/tmp/pip-DLltcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:12 INFO - Running setup.py (path:/tmp/pip-hX8v7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:12 INFO - Running setup.py (path:/tmp/pip-CkhZ8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:12 INFO - Running setup.py (path:/tmp/pip-jjVtJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:12 INFO - Running setup.py (path:/tmp/pip-e9NpYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:01:12 INFO - Running setup.py (path:/tmp/pip-Drjh5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:01:12 INFO - Running setup.py (path:/tmp/pip-MeDgNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:01:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:01:12 INFO - Running setup.py install for manifestparser 10:01:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:01:12 INFO - Running setup.py install for mozcrash 10:01:13 INFO - Running setup.py install for mozdebug 10:01:13 INFO - Running setup.py install for mozdevice 10:01:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Running setup.py install for mozfile 10:01:13 INFO - Running setup.py install for mozhttpd 10:01:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Running setup.py install for mozinfo 10:01:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Running setup.py install for mozInstall 10:01:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Running setup.py install for mozleak 10:01:13 INFO - Running setup.py install for mozlog 10:01:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:01:13 INFO - Running setup.py install for moznetwork 10:01:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:01:14 INFO - Running setup.py install for mozprocess 10:01:14 INFO - Running setup.py install for mozprofile 10:01:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:01:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:01:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:01:14 INFO - Running setup.py install for mozrunner 10:01:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:01:14 INFO - Running setup.py install for mozscreenshot 10:01:14 INFO - Running setup.py install for moztest 10:01:14 INFO - Running setup.py install for mozversion 10:01:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:01:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:01:14 INFO - Cleaning up... 10:01:14 INFO - Return code: 0 10:01:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:14 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')]} 10:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:14 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')]} 10:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:14 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:14 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 10:01:14 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 10:01:14 INFO - Using env: {'DISPLAY': ':0', 10:01:14 INFO - 'HOME': '/home/cltbld', 10:01:14 INFO - 'LANG': 'en_US.UTF-8', 10:01:14 INFO - 'LANGUAGE': 'en_US:en', 10:01:14 INFO - 'LOGNAME': 'cltbld', 10:01:14 INFO - 'MAIL': '/var/mail/cltbld', 10:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:14 INFO - 'MOZ_NO_REMOTE': '1', 10:01:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:14 INFO - 'NO_EM_RESTART': '1', 10:01:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:14 INFO - 'PWD': '/builds/slave/test', 10:01:14 INFO - 'SHELL': '/bin/bash', 10:01:14 INFO - 'SHLVL': '1', 10:01:14 INFO - 'TERM': 'linux', 10:01:14 INFO - 'TMOUT': '86400', 10:01:14 INFO - 'USER': 'cltbld', 10:01:14 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:14 INFO - '_': '/tools/buildbot/bin/python'} 10:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:14 INFO - Running setup.py (path:/tmp/pip-lwFpob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:14 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)) 10:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:14 INFO - Running setup.py (path:/tmp/pip-6cZf6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:15 INFO - Running setup.py (path:/tmp/pip-9j6jQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:15 INFO - Running setup.py (path:/tmp/pip-dF7lpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:15 INFO - Running setup.py (path:/tmp/pip-fEdjTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:15 INFO - Running setup.py (path:/tmp/pip-6m2zEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:15 INFO - Running setup.py (path:/tmp/pip-Ze21zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:15 INFO - Running setup.py (path:/tmp/pip-Vp5FkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:15 INFO - Running setup.py (path:/tmp/pip-oP3sC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:15 INFO - Running setup.py (path:/tmp/pip-Wk2dUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:15 INFO - Running setup.py (path:/tmp/pip-3nsq0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:15 INFO - Running setup.py (path:/tmp/pip-Y4hRA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:15 INFO - Running setup.py (path:/tmp/pip-sCZAWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:15 INFO - Running setup.py (path:/tmp/pip-c8lcIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:01:15 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)) 10:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:15 INFO - Running setup.py (path:/tmp/pip-xE6wkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:16 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)) 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:01:16 INFO - Running setup.py (path:/tmp/pip-EpJB3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:01:16 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)) 10:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:01:16 INFO - Running setup.py (path:/tmp/pip-uD1K24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:01:16 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)) 10:01:16 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)) 10:01:16 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)) 10:01:16 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)) 10:01:16 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)) 10:01:16 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)) 10:01:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:01:16 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 10:01:16 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 10:01:16 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 10:01:16 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 10:01:16 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 10:01:16 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 10:01:18 INFO - Downloading blessings-1.6.tar.gz 10:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:01:18 INFO - Installing collected packages: blessings 10:01:18 INFO - Running setup.py install for blessings 10:01:18 INFO - Successfully installed blessings 10:01:18 INFO - Cleaning up... 10:01:18 INFO - Return code: 0 10:01:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:01:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:01:18 INFO - Reading from file tmpfile_stdout 10:01:18 INFO - Current package versions: 10:01:18 INFO - argparse == 1.2.1 10:01:18 INFO - blessings == 1.6 10:01:18 INFO - blobuploader == 1.2.4 10:01:18 INFO - docopt == 0.6.1 10:01:18 INFO - manifestparser == 1.1 10:01:18 INFO - mozInstall == 1.12 10:01:18 INFO - mozcrash == 0.17 10:01:18 INFO - mozdebug == 0.1 10:01:18 INFO - mozdevice == 0.48 10:01:18 INFO - mozfile == 1.2 10:01:18 INFO - mozhttpd == 0.7 10:01:18 INFO - mozinfo == 0.9 10:01:18 INFO - mozleak == 0.1 10:01:18 INFO - mozlog == 3.2 10:01:18 INFO - moznetwork == 0.27 10:01:18 INFO - mozprocess == 0.22 10:01:18 INFO - mozprofile == 0.28 10:01:18 INFO - mozrunner == 6.11 10:01:18 INFO - mozscreenshot == 0.1 10:01:18 INFO - mozsystemmonitor == 0.3 10:01:18 INFO - moztest == 0.7 10:01:18 INFO - mozversion == 1.4 10:01:18 INFO - psutil == 3.1.1 10:01:18 INFO - requests == 1.2.3 10:01:18 INFO - wsgiref == 0.1.2 10:01:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:18 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')]} 10:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:18 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')]} 10:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:18 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:18 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 10:01:18 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 10:01:18 INFO - Using env: {'DISPLAY': ':0', 10:01:18 INFO - 'HOME': '/home/cltbld', 10:01:18 INFO - 'LANG': 'en_US.UTF-8', 10:01:18 INFO - 'LANGUAGE': 'en_US:en', 10:01:18 INFO - 'LOGNAME': 'cltbld', 10:01:18 INFO - 'MAIL': '/var/mail/cltbld', 10:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:18 INFO - 'MOZ_NO_REMOTE': '1', 10:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:18 INFO - 'NO_EM_RESTART': '1', 10:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:18 INFO - 'PWD': '/builds/slave/test', 10:01:18 INFO - 'SHELL': '/bin/bash', 10:01:18 INFO - 'SHLVL': '1', 10:01:18 INFO - 'TERM': 'linux', 10:01:18 INFO - 'TMOUT': '86400', 10:01:18 INFO - 'USER': 'cltbld', 10:01:18 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:18 INFO - '_': '/tools/buildbot/bin/python'} 10:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 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)) 10:01:18 INFO - Cleaning up... 10:01:18 INFO - Return code: 0 10:01:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:01:18 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')]} 10:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:18 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')]} 10:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:18 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:18 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 10:01:18 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 10:01:18 INFO - Using env: {'DISPLAY': ':0', 10:01:18 INFO - 'HOME': '/home/cltbld', 10:01:18 INFO - 'LANG': 'en_US.UTF-8', 10:01:18 INFO - 'LANGUAGE': 'en_US:en', 10:01:18 INFO - 'LOGNAME': 'cltbld', 10:01:18 INFO - 'MAIL': '/var/mail/cltbld', 10:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:18 INFO - 'MOZ_NO_REMOTE': '1', 10:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:18 INFO - 'NO_EM_RESTART': '1', 10:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:18 INFO - 'PWD': '/builds/slave/test', 10:01:18 INFO - 'SHELL': '/bin/bash', 10:01:18 INFO - 'SHLVL': '1', 10:01:18 INFO - 'TERM': 'linux', 10:01:18 INFO - 'TMOUT': '86400', 10:01:18 INFO - 'USER': 'cltbld', 10:01:18 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:18 INFO - '_': '/tools/buildbot/bin/python'} 10:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:18 INFO - Downloading/unpacking jsonschema 10:01:18 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 10:01:18 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 10:01:18 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 10:01:18 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 10:01:18 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 10:01:18 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 10:01:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:01:20 INFO - Downloading/unpacking functools32 (from jsonschema) 10:01:20 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 10:01:20 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 10:01:20 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 10:01:20 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 10:01:20 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 10:01:20 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 10:01:21 INFO - Downloading functools32-3.2.3-2.tar.gz 10:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:01:21 INFO - warning: no files found matching '*.txt' 10:01:21 INFO - no previously-included directories found matching 'build' 10:01:21 INFO - no previously-included directories found matching 'dist' 10:01:21 INFO - no previously-included directories found matching '.git*' 10:01:21 INFO - Installing collected packages: jsonschema, functools32 10:01:21 INFO - Running setup.py install for functools32 10:01:21 INFO - warning: no files found matching '*.txt' 10:01:21 INFO - no previously-included directories found matching 'build' 10:01:21 INFO - no previously-included directories found matching 'dist' 10:01:21 INFO - no previously-included directories found matching '.git*' 10:01:21 INFO - Successfully installed jsonschema functools32 10:01:21 INFO - Cleaning up... 10:01:21 INFO - Return code: 0 10:01:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:01:21 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')]} 10:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:21 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')]} 10:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:21 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 0x7f7ce62fbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d50770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, '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': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', '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 10:01:21 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 10:01:21 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 10:01:21 INFO - Using env: {'DISPLAY': ':0', 10:01:21 INFO - 'HOME': '/home/cltbld', 10:01:21 INFO - 'LANG': 'en_US.UTF-8', 10:01:21 INFO - 'LANGUAGE': 'en_US:en', 10:01:21 INFO - 'LOGNAME': 'cltbld', 10:01:21 INFO - 'MAIL': '/var/mail/cltbld', 10:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:21 INFO - 'MOZ_NO_REMOTE': '1', 10:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:21 INFO - 'NO_EM_RESTART': '1', 10:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:21 INFO - 'PWD': '/builds/slave/test', 10:01:21 INFO - 'SHELL': '/bin/bash', 10:01:21 INFO - 'SHLVL': '1', 10:01:21 INFO - 'TERM': 'linux', 10:01:21 INFO - 'TMOUT': '86400', 10:01:21 INFO - 'USER': 'cltbld', 10:01:21 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:21 INFO - '_': '/tools/buildbot/bin/python'} 10:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:21 INFO - Downloading/unpacking flake8 10:01:21 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 10:01:21 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 10:01:21 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 10:01:21 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 10:01:21 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 10:01:21 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 10:01:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:01:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:01:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:23 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 10:01:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:01:24 INFO - no previously-included directories found matching 'docs/_build' 10:01:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:01:24 INFO - Running setup.py install for pep8 10:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:01:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:01:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:01:24 INFO - no previously-included directories found matching 'docs/_build' 10:01:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:01:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:01:24 INFO - Cleaning up... 10:01:24 INFO - Return code: 0 10:01:24 INFO - Running post-action listener: _resource_record_post_action 10:01:24 INFO - Running post-action listener: _start_resource_monitoring 10:01:24 INFO - Starting resource monitoring. 10:01:24 INFO - ##### 10:01:24 INFO - ##### Finished create-virtualenv step (success) 10:01:24 INFO - ##### 10:01:24 INFO - ##### 10:01:24 INFO - ##### Running install step. 10:01:24 INFO - ##### 10:01:24 INFO - Running pre-action listener: _resource_record_pre_action 10:01:24 INFO - Running main action method: install 10:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:01:24 INFO - Reading from file tmpfile_stdout 10:01:24 INFO - Detecting whether we're running mozinstall >=1.0... 10:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:01:24 INFO - Reading from file tmpfile_stdout 10:01:24 INFO - Output received: 10:01:24 INFO - Usage: mozinstall [options] installer 10:01:24 INFO - Options: 10:01:24 INFO - -h, --help show this help message and exit 10:01:24 INFO - -d DEST, --destination=DEST 10:01:24 INFO - Directory to install application into. [default: 10:01:24 INFO - "/builds/slave/test"] 10:01:24 INFO - --app=APP Application being installed. [default: firefox] 10:01:24 INFO - mkdir: /builds/slave/test/build/application 10:01:24 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'] 10:01:24 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 10:01:41 INFO - Reading from file tmpfile_stdout 10:01:41 INFO - Output received: 10:01:41 INFO - /builds/slave/test/build/application/firefox/firefox 10:01:41 INFO - Running post-action listener: _resource_record_post_action 10:01:41 INFO - ##### 10:01:41 INFO - ##### Finished install step (success) 10:01:41 INFO - ##### 10:01:41 INFO - ##### 10:01:41 INFO - ##### Running run-tests step. 10:01:41 INFO - ##### 10:01:41 INFO - Running pre-action listener: _resource_record_pre_action 10:01:41 INFO - Running main action method: run_tests 10:01:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:01:41 INFO - Python 2.7.3 10:01:41 INFO - Return code: 0 10:01:41 INFO - grabbing minidump binary from tooltool 10:01:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:41 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 0x1ddf9c0>, '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 0x1de08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de0d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:01:41 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 10:01:41 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 10:01:41 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 10:01:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:01:41 INFO - Return code: 0 10:01:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:01:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:01:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:01:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:01:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:01:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:01:42 INFO - Return code: 0 10:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 10:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 10:01:42 INFO - Using env: {'DISPLAY': ':0', 10:01:42 INFO - 'HOME': '/home/cltbld', 10:01:42 INFO - 'LANG': 'en_US.UTF-8', 10:01:42 INFO - 'LANGUAGE': 'en_US:en', 10:01:42 INFO - 'LOGNAME': 'cltbld', 10:01:42 INFO - 'MAIL': '/var/mail/cltbld', 10:01:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:01:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:42 INFO - 'MOZ_NO_REMOTE': '1', 10:01:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:01:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:01:42 INFO - 'NO_EM_RESTART': '1', 10:01:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:42 INFO - 'PWD': '/builds/slave/test', 10:01:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:01:42 INFO - 'SHELL': '/bin/bash', 10:01:42 INFO - 'SHLVL': '1', 10:01:42 INFO - 'TERM': 'linux', 10:01:42 INFO - 'TMOUT': '86400', 10:01:42 INFO - 'USER': 'cltbld', 10:01:42 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1464451546.592070-541981297', 10:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:42 INFO - '_': '/tools/buildbot/bin/python'} 10:01:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 10:01:42 INFO - mozversion application_buildid: 20160528073029 10:01:42 INFO - mozversion application_changeset: 2c7440e46d8786b2c82a1d2004e2b6d9d13f4046 10:01:42 INFO - mozversion application_display_name: Nightly 10:01:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:01:42 INFO - mozversion application_name: Firefox 10:01:42 INFO - mozversion application_remotingname: firefox 10:01:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:01:42 INFO - mozversion application_vendor: Mozilla 10:01:42 INFO - mozversion application_version: 49.0a1 10:01:42 INFO - mozversion platform_buildid: 20160528073029 10:01:42 INFO - mozversion platform_changeset: 2c7440e46d8786b2c82a1d2004e2b6d9d13f4046 10:01:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:01:42 INFO - mozversion platform_version: 49.0a1 10:01:42 INFO - using testdate: 1464454902 10:01:42 INFO - actual date: 1464454902 10:01:42 INFO - starting webserver on 'localhost:52717' 10:01:42 INFO - SUITE-START | Running 2 tests 10:01:42 INFO - TEST-START | dromaeo_css 10:01:42 INFO - operating with platform_type : linux_ 10:01:42 INFO - Initialising browser for dromaeo_css test... 10:01:42 INFO - TEST-INFO | started process 15091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuT74pn/profile http://localhost:52717/getInfo.html) 10:01:46 INFO - PROCESS | 15091 | __metrics Screen width/height:1600/1200 10:01:46 INFO - PROCESS | 15091 | colorDepth:24 10:01:46 INFO - PROCESS | 15091 | Browser inner width/height: 1024/697 10:01:46 INFO - PROCESS | 15091 | __metrics 10:01:47 ERROR - PROCESS | 15091 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:01:48 INFO - TEST-INFO | 15091: exit 0 10:01:48 INFO - Browser initialized. 10:01:48 INFO - Running cycle 1/1 for dromaeo_css test... 10:01:48 INFO - TEST-INFO | started process 15174 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuT74pn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:02:00 INFO - PROCESS | 15174 | RSS: Main: 155971584 10:02:00 INFO - PROCESS | 15174 | 10:02:51 INFO - PROCESS | 15174 | 1464454971168 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] 10:04:59 INFO - PROCESS | 15174 | Cycle 1(1): loaded http://localhost:52717/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52717/tests/dromaeo/cssquery-ext.html) 10:05:00 INFO - PROCESS | 15174 | RSS: Main: 262828032 10:05:00 INFO - PROCESS | 15174 | 10:08:02 INFO - PROCESS | 15174 | Cycle 1(1): loaded http://localhost:52717/tests/dromaeo/cssquery-ext.html (next: http://localhost:52717/tests/dromaeo/cssquery-jquery.html) 10:08:03 INFO - PROCESS | 15174 | RSS: Main: 236580864 10:08:03 INFO - PROCESS | 15174 | 10:11:04 INFO - PROCESS | 15174 | Cycle 1(1): loaded http://localhost:52717/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52717/tests/dromaeo/cssquery-mootools.html) 10:11:05 INFO - PROCESS | 15174 | RSS: Main: 244539392 10:11:05 INFO - PROCESS | 15174 | 10:14:07 INFO - PROCESS | 15174 | Cycle 1(1): loaded http://localhost:52717/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52717/tests/dromaeo/cssquery-prototype.html) 10:14:08 INFO - PROCESS | 15174 | RSS: Main: 236597248 10:14:08 INFO - PROCESS | 15174 | 10:17:10 INFO - PROCESS | 15174 | Cycle 1(1): loaded http://localhost:52717/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52717/tests/dromaeo/cssquery-yui.html) 10:17:11 INFO - PROCESS | 15174 | RSS: Main: 211169280 10:17:11 INFO - PROCESS | 15174 | 10:20:13 INFO - PROCESS | 15174 | Cycle 1(1): loaded http://localhost:52717/tests/dromaeo/cssquery-yui.html (next: http://localhost:52717/tests/dromaeo/cssquery-dojo.html) 10:20:14 INFO - PROCESS | 15174 | RSS: Main: 273887232 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | __start_tp_report 10:20:14 INFO - PROCESS | 15174 | _x_x_mozilla_page_load 10:20:14 INFO - PROCESS | 15174 | _x_x_mozilla_page_load_details 10:20:14 INFO - PROCESS | 15174 | |i|pagename|runs| 10:20:14 INFO - PROCESS | 15174 | |0;dojo.html;3117.984410077949;3121.859516321766;3151.7951333163355;3158.9842050789744;3177.6663450337714;5277.366715994077;5403.243545903571;5420.972895135528;5501.752421141048;5504.091824848895;5398.78404863805;5410.783568657249;5447.210154527591;5470.370907345657;5517.337919449662;4818.879528011801;4907.043126590316;4909.140900342443;4911.287863259825;5139.177731562951;5004.074246264435;5023.32185155003;5055.721935293557;5071.467495912932;5079;4796.856094317159;4848.442429120643;4903.26451032348;4920.966597014632;4931.605471562267;4908.361912951329;4926.876828079291;4987.875303117415;4994.126027945095;5023.849284521433;4863;4956.2565615157655;4965.652404331699;4970.627202959757;4992.176290911995;5341.572674186055;5414.269073675043;5416.8104116356135;5484.506394424521;5523.751431185566;5942.881142377171;5950;5955.34491205967;5981.312149102865;5983.880322393527;5721.513671337942;5806.941930580682;5822.068469044975;5854.941450585482;5881.323647780518;5491;5513.117901135799;5523.088690366085;5540.667559946416;5542.140968149944;6034.739158884043;6076.088586712029;6082.635041897412;6088.391160883876;6096;5543.25166102571;5644.604877658607;5672.829815105553;5707.74315155819;5805.82582522525;5846.386129456431;5894.292684877756;5897.911531326948;5906.379830117861;5909.556783241274;5820.039693450618;5820.126980952891;5821.970890145522;5826.504747096545;5827.300723913073;5660.179274005246;5816.70916454184;5844.064949608042;5863.941360586339;5890.087036509348;4428.933565996513;4433.7561434121435;4436.157130145262;4437.312216606431;4472.418585583919;3879.049632839972;3930.8034598269974;3970.741901776433;3986.880393588197;4018.2967980603275;5446.428125046813;5469.398366179717;5472.589555783332;5506.531944784738;5507.256520369779;5909.734061967221;5919.526437884959;5923.052311630205;5930.28836539618;5977.252843394575;5357.116075847442;5416.945830541644;5510.91733623988;5520.282363292747;5522.861928451821;4993.375828021497;5039.496050395004;5050.3434553507805;5051.292819005343;5086.059079070384;6059.121427393004;6061.54538409621;6062.514998800086;6062.514998800086;6066.302375226817;5659.688717120598;5851.590388672752;5864.120381942743;5883.646981180972;5920.674362910081;6169.444749972524;6169.598976066542;6179.876402471836;6183.598066125687;6184.5980011299125;5809.070548712186;5853.882922341444;5867.794627188028;5872.794452194153;5912.231409916855;5642.520385767256;5718.428157184248;5732.971335143465;5774.653520788766;5949.107633854784;5649.265595472562;5651.406602306863;5653.632513886584;5654;5656.519195868233;5578.944210557843;5626.746796394254;5635.774569017193;5644.209810626598;5649.039663257338;5478.096114141122;5481.369642491165;5484.862878427912;5575.609707320448;5591.217229587943;5705.686187259741;5724;5742.741576628978;5753.942460575341;5760.539156867524;5495.203195536705;5554.222408862682;5607.607467477237;5620.437956204511;5625.071863142535 10:20:14 INFO - PROCESS | 15174 | |1;ext.html;577.9162021506882;615.9784407545736;626.4080443980438;638.2691817868542;638.3967151042261;31635.20911977202;32316.192095197603;34597;34603;43638.78180609101;43625.34561981573;47446.52553474473;47511.287330690066;47527;47653.52346476534;8640.135986401358;8806.735797926072;8824.205821476065;8826.42628229163;8829.16122968317;8663.783405414884;8818.470891746516;8820.911790882074;8821.823563528726;8823.338249631272;8838.204561589455;8853;8879.245264152554;8888.866667;8897.288216942661;8811.251043661296;8853.291736661116;8877.600507977157;8879.689210877621;8882.06738292483;8657.393982421234;8750.518721470316;8755.124487551258;8762.474251544927;8768.123187681245;41732;42134;42159.36760948588;42164.367534487006;42309.78845105786;3434.4848272759036;3527.7354198434964;3559.5906470755936;3563.4832949222478;3569.94645080324;32224.516632250536;32334.514982275286;32808;32883.6711632883;33055.338893222244;4190.706650534464;4249.978750106261;4254.659627229814;4271.145770845812;4324.870253892388;344728;348135;349343;460812;464671.676641618;57557.71221143867;58081.70959145177;58346.12480812706;58406.12390814141;58426.4157358421;56608.150877736865;61672.38327616666;61791;63545.36454635394;63545.36454635487;15084.170370629721;15144.242787860563;15158.090514568941;15163.924180379248;15230.46693365727;12279.508819647293;12392.504299828086;12408.56570020045;12408.62774116778;12478.43847026886;18850.245990160514;19262.518437039187;19391.806081939;19820.493269743634;19828.801711982986;6917.169939607279;7160.892586611205;7195.136583609896;7231.638418079075;7288.416926645856;1032.566322144701;1098.4058753324143;1137.2039572299423;1146.9047060057626;1147.8106112491346;32910.67089329124;33284.667153328155;33524.49713254303;33548.496772547944;33603.311735061136;1069.2141276162015;1069.3583849690124;1140.6121918547733;1198.1313547677864;1254.9498020079277;17027.91486042562;17460.388886388915;17621;17717.468475945483;17864.732029019575;532182;816480;835957;838176;838977;59514.70242648759;59570.80858382808;59945.700271499234;59973.400265997654;60033.39966600366;1277.8211050452944;1317.795741660025;1337.4249072898747;1343.8051482534977;1345.6837643153851;31542;33371.49942750812;33435.498467523495;33668.32663346768;34232;48409.75795121002;48752.5124748748;49075;49087.26369104395;49129;412708;415922;416001;416454;417284;23143.421414464774;27897;28431.857840710665;28445.146645600664;29651;48096.51903480921;48231.27653085277;48695.26957095575;49215.50784492109;49509.50490495049;39393.60606394014;39413.649839763944;39976.200475990896;40309.59690403176;41280.79359603183;39978.2004359917;40330;40445.1910961785;40877.79561102294;41516.79241603773;40078;40313.395299071126;40704.796476018615;40962;41297.1740565181;34510.48234276538;35682.82158589191;36275;36277.637223627426;36491.27017459689;27752.44495110046;28050;28409.57385639176;28460.43079138447;28479 10:20:14 INFO - PROCESS | 15174 | |2;jquery.html;401.47607372379053;402.3903785764567;408.7077739416317;411.56990944463035;421.2543797478463;12777;17819.732704009435;18752;19376.515587110312;19416.708749368772;14462.855371446283;16622.25199866006;17836;18515;18873.622527549476;5513.283273174482;5684.403137670526;5698.202251684767;5709;5726.570507211955;5645.096784514479;5677.801276955307;5681;5682.914756278659;5689.089745640698;4052.3516237402023;5982.611130276518;6338.366163383648;6352.872942541147;6407.070974708688;6091.11678806575;6302.873942521124;6366.045093236005;6376.26672932614;6404.615723056609;5474.589405794582;5484.314460692413;5522.22688823561;5531.806386776484;5559.305086864142;21051.68422473664;21273.14907403702;22092.668609970864;22161.113555457763;22237.666435003488;2262.7624099469485;2312.861228326306;2335.0309621507054;2356.634721618152;2359.5280943811135;9271.629134834599;13509.59471215865;13982.300884955814;14156.858431415658;14406;1760.1639221369746;1811.3660218923403;1857.2385322018042;1881.4825922871182;1930.0639189992983;85125.99999999937;85744;86960;87342.56328718379;88814;10776.78446431067;10861.891381086247;10892;10959.83560246597;11042.834357484644;15290.694186116216;15324.846751532343;15447.922760386351;15478.148701821283;15500.30248638814;7786.922130778619;8056.113827478973;8075.555844428495;8156.877646835303;8162.061362943219;7608.695652173851;7636.31273185416;7673.424493162924;7787.415943804237;7807.102183248999;4231.597998190167;4521.570450807168;4533.342665313511;4631.536846315409;4743.596794272526;1821.9979457866339;1823.0975667044897;1847.8798878072882;1984.5435549823621;1984.7701307055722;932.911373419524;971.4705485510414;976.7655762617063;986.6300137448457;1020.0768304684273;17462.30150793954;18095.909520452577;18153.364632237808;18707.34524291643;18860.622787544173;985.5909797434128;1006;1033.89661033896;1039.365986748083;1063.2557209953063;10155.847662285072;10528.789424211625;10590.311629744043;10628.36229826213;10639.308444951053;348182;356635;357013;462238;504001;97174.5141274289;100877.99999999853;102355.48822255842;104789;105677;1029.3926583315879;1041.0474415909357;1051.8264486359817;1066.0352381095122;1066.8026415113075;16301.755473668141;16574.337026518806;16958.745618815974;17189;17271.827281727525;43128.35307470328;43516.78241608771;44499.77750111229;44802.55197447984;45176.548234517235;56598.43401566096;57711.71144144252;61232.38767612267;61829;62273.68863155655;6012.518998480198;8328.583570821556;8423.957880210559;8435.32517398619;8459.450135741157;12798.68003299925;12849.935750321189;12965.41655625518;13043.152195107288;13139.934300328438;11470.71323216893;11560.248583842023;11564.826527601925;11667.59163429276;11672.066234701033;11308.264962777394;11338.319700817647;11379.54481820718;11412;11453.770924581628;11647.941760291144;11809.586664466702;11948.581799636971;11983.520659173884;12040.43474348329;8216.21945915137;8260.545669988209;8400.663973440995;8470.534120623424;8513.91486085156;11475.196736228718;11718.472668730099;11752.059835213333;11859.644210673694;11874.76250475003 10:20:14 INFO - PROCESS | 15174 | |3;mootools.html;1323.9536616218434;1460.9999999999998;1501.9399224031038;1621.5783896186995;1822.835944764971;8324.167583241673;8635.91364086359;8674.04585495595;8910.198082172603;8922.598483068261;8577.828443431143;8849.734507964755;8855.91144088559;8867.68963086292;8885.378023538355;2344.120954642005;2369.514249578841;2372.5136347048815;2372.857628542284;2374.5013547155118;2335.719713634366;2356.6582845487396;2363.4918492524102;2363.7990770784504;2373.8694371809543;2320.489492311697;2322.7444981052154;2345.5543446745064;2349.447879748258;2352.529668956902;2309.2956648222284;2317;2317.6291793313158;2360.7875291223786;2361.8582885026867;2229.9777002230094;2230.308604332662;2232.386093824195;2233.553289342122;2241.0363543676212;8297.460665056813;8504.23461888433;8630.266427353683;8770.868436973451;8775.385722999392;5353.678779273256;5461.0716178249795;5470.972645136789;5482.095454250044;5484.2047903053835;3312.2381852174135;3335.7498187636024;3387.762856600039;3389.406853800575;3436.484527320897;1187.068151501076;1203.0195390756505;1204.048801446856;1206.8551773787115;1211.7818792617325;12129.696757581043;12153.574624888175;12163.756724865452;12238.204516706386;12240.81638775419;4546.954530454653;4560.612347950461;4661.114388266286;4666.160091183619;4666.370040044619;9656.613735450645;9685.806283874283;9702.126808587262;9703.514824258758;9718.611255549698;5248.317718696545;5310.256564081032;5318.601104917147;5321.414644389114;5325.307709997675;4211.136716973075;4403.5376285489565;4538.387317712132;4943.505649435027;4998.475160108209;3838.481804956351;3917.138229589486;3934.09515811365;3973.443717879499;3982.8605998789903;1144.8855114488651;1183.3373310945897;1186.1578279421708;1188.7206506470973;1202.8857258560422;1004.1514919892679;1015.7714514234239;1022.9335093218917;1035.8756949165997;1039.61534232333;6007.219061522062;6156.907646385308;6163.291221509494;6179;6248.781292654735;466.710639403572;467.3550500309551;473.3278744183268;474.1938704202869;475.9595434388116;4159.480064991876;4353.4122893408985;4408.338749187584;4441.755703436342;4469.128519938581;10233;10509.159267258601;10571.2600117991;10582.894171058344;10585.735356616146;10752.40861752611;11035;11056.170787190993;11108.777824443467;11182.832257516144;8231.629576669064;8232.847401363813;8236.917630823735;8298.70954516589;8386.203310685476;6116.633002019893;6209.068639704081;6227.532935029982;6298.401651843067;6308.116863639002;10175.270204065178;10221.33561318512;10272.383656980604;10367.118794902517;10374.688759337232;10092.444915529717;10366.378017318984;10544.15646748243;10583.94708026455;10631.734206644587;6816.863662726817;7253.746118885813;7379.409647228316;7382.704691812267;7577.431692622965;8316.62575184106;8320.459170153921;8371.665133394596;8393.454425462325;8397.244248017463;7504.887426688495;7663.425243106678;7775.883361749465;7875.84248315042;7895.328897043815;7601.087869455479;7688.269614386624;7725.072991241087;7811.453198276294;7813.882791758242;7025.894611580728;7030.789076327718;7093.113360829896;7101;7112.50212485121;5230.581553475637;5231.1107111790325;5274.789008439773;5328.254044433705;5336.653117547347;5137.101007323628;5160.845174644766;5275.340582427197;5442.0204363215;5470.972645136749 10:20:14 INFO - PROCESS | 15174 | |4;prototype.html;328.9194147433879;338.402719200611;344.94825776133575;349.60843854882535;351.88036067736965;5377.516023557879;5392.379876314225;5411.512963833255;5422.9728851355785;5432.375276843164;5330.840074797753;5343.973280133594;5369.7583608737605;5377.623566350357;5384.865378365543;3969.4045893115976;4155.439015732883;4198.916021679564;4243.490781106264;4258.936115958248;4109.630133288004;4140.648044916185;4161.127492628314;4165.187788381251;4217.578242175773;4143.979280103596;4160.9375859362135;4165.56261592533;4168.812403441852;4204.369344598304;3922.3724204127348;4013.3578627419765;4131.855385061538;4150.750954942713;4162.604552567501;3600.5499312585926;3611.819409029564;3615.439606860941;3650.689691376236;3661.3958696814993;4893.730844803535;5118.539331460186;5119.769610367543;5153.716545589991;5171.7672704728375;4597.724136551784;4722.409698787652;4723.055388922229;4732.266498692674;4747.52524747526;5488.890222195574;5493.450654934514;5508.283923090013;5533.612647114704;5539.944600553983;3936.153726948691;4027.6708686133798;4028.7582745035393;4034.1931613677384;4063.674906007512;5812.157237200625;5814.273215848019;5835.620684655484;5849.824505264849;5850.0932355484965;4097.672186225154;4100.610442008005;4103.835846566164;4120.464339635828;4136.358864376027;4073.124278280184;4155.605217504332;4173.9982404222765;4174.290370636999;4178.247915375262;4707.764611769467;4732.479427262998;4747.548982846624;4752.928706069411;4781.306710526954;5567.164925261162;5574.498295153375;5574.860628484239;5603.579731520153;5626.887462250732;1398.3357904995046;1400.208881981677;1437.2310813714612;1442.776369662704;1443.6968236670416;890.799570096736;914.8061779377842;917.857732051533;920.6271460058687;930.432436213909;4729.408823897013;4839.879003024882;4873.610111191097;4883.243097319921;4896.608271338285;4900.656954013256;5087.3386459759995;5098.821541246039;5099.796008159707;5101.51535604117;4811.542903424169;5189.325387699575;5236.947630523646;5406.270153529302;5470.890582188334;3049.7255247027874;3050.740687041582;3057.4955132402906;3065.2796592800673;3074.6771588983283;5686.289213848269;5950.095969289872;5950.256217972754;5956.821295361146;5996.402158704829;5833.279182641131;5881.529477641779;5883.764649413975;5887.646741195456;5893.823185304448;5900.380460051719;5943.167956486095;5952.196453478811;5958.480587450345;5970;5419.647722897999;5690.260266165372;5769.134629805562;5785.768569257182;5787;5662.2639056922335;5717.485426311652;5739.253896993364;5760.971195144165;5777.313549199643;5747.655140691572;5943.375945525658;5967.986166985645;6008.519318454426;6045.75816967334;4116.485439320085;4124.649404800505;4135.2425219281085;4145.958540414678;4147.937780933349;5830.5044071254415;5843.883122337615;5858.209141765978;5859.589828711949;5870.47165755067;5417.458254174551;5688.914666279927;5692.658440493584;5710.4004079572705;5738;5578.832635020955;5606.18710286998;5618.325800903917;5661.952538780339;5692.292312306209;5544.279243698294;5564.33228012641;5573.832785016456;5581.441855814386;5583.8883222336135;5486.915550545564;5678.488935995781;5683.658980461186;5688.317401911631;5697.6296540724725;5331.760070796746;5488.56091512686;5535.640183388067;5554.1391084381985;5557.110582402002 10:20:14 INFO - PROCESS | 15174 | |5;yui.html;780.9219078092187;805.3516918880301;823.7816978500698;844.3751623798391;863.8661007543833;14232.288385580732;14713.705725885477;14783.556493305217;14837.18395488249;14869.182194979272;13806.102603330777;13839.169649821019;13910.51313204038;13957.23235222065;13971;684.1755684400285;807.0476837331961;807.3299161695808;821.0844907927648;832.1719888710742;826.6404114210308;828.1056459024239;830.9335253179731;831.0110967948152;831.5883637599396;857.9575815384306;860.8665656823171;861.6208868098048;861.7371701631031;861.9525926074064;701.1866235167207;831.9542425166583;853.4751128056263;854.3976496569903;854.4275335525182;624.2290770897937;624.2758400255682;624.2945471617089;624.322609968185;624.865653884417;14204.218767967757;14272.571822845332;14290;14313.212773297464;14313.642158946006;12371.443285052193;12639.115261931669;12647.873521264853;12721.30032848193;12769.48922043117;11608.535658573646;11793.469293881795;11809.468573914195;11911.047116230768;12008.399580020963;4446.911061778778;4488.7531185784765;4531.207038768202;4562.064776720765;4652.395188625457;57672.13491797626;57676;57839.42160578383;59919.10121348183;60387.99999999956;10826.458677066115;10950.233483656228;11017.669469915916;11100.333979961228;11153.330800152016;19805.60388792216;19822.20711171537;19943;19976.60046799056;19977.300794472416;7884.802879927933;7896.644650990719;7909.090454597795;7910.406719496061;8170.509769413854;4911.16510193268;4941.332920055818;4949;4963.057019166346;4999.800007999639;3327.068420842168;3403.642617525124;3406.386850366908;3409.522666826597;3410.1645096951406;655.7180412422608;659.5053709717712;686.7047169716969;700.264722041854;705.1643802094534;705.6401235369896;706.4242642246553;718.615540685741;721.6644260418931;726.4841962206892;9608.567614457366;9975.950120249352;10029.398236105857;10218.131458825934;10283.640072597424;640.4556127291858;666.3702800853147;726.8291951391419;741.5958302725028;749.2470067582154;4220.725652832556;4293.4203882476095;4313.729407886232;4419.734815911055;4666.60333871618;74364;75097.62451187709;77021;77092;77737.61131194422;68112.3188768116;68895.65552172207;69648;70052;71216.64391678009;57052.144217836765;57236.71381643065;57528;57713.71143144257;58051;8701.390902637007;8907;8913.688020919237;8971.596278167628;8995.550222488719;37441.25117497689;37602;38188.809055954545;38929.221415570966;39036;48962.75518622384;51021.48978510167;51809.48190518197;51936.22095668643;52387.47612523826;12222.633321000385;12330.260184388966;12414.193077449936;12631.557895473614;12644.936775316433;11963.282203067844;13244.867551324363;13385.799213012007;13528.188308701509;13816.240106794223;11301.491432885705;12226.449809758404;12790.936045319713;12835.807462887877;13250.469981200644;13121.015923805944;13329.600111996655;13371.53199638008;13414;13511.05422620407;12980.610581682564;13154.60536183916;13270.80093798613;13396.665083372603;13483.393247303698;9739.659111931049;11227.66317010491;11405.600803971822;11553.826692599785;11888;11161.553537858395;11283.887161128283;11545.82681259765;11608.535658573563;11653.126015548698 10:20:14 INFO - PROCESS | 15174 | __end_tp_report 10:20:14 INFO - PROCESS | 15174 | __start_cc_report 10:20:14 INFO - PROCESS | 15174 | _x_x_mozilla_cycle_collect,869 10:20:14 INFO - PROCESS | 15174 | __end_cc_report 10:20:14 INFO - PROCESS | 15174 | __startTimestamp1464456014031__endTimestamp 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | ------- Summary: start ------- 10:20:14 INFO - PROCESS | 15174 | Number of tests: 6 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | [#0] dojo.html Cycles:165 Average:5423.00 Median:5578.94 stddev:631.00 (11.3%) stddev-sans-first:606.48 10:20:14 INFO - PROCESS | 15174 | Values: 3118.0 3121.9 3151.8 3159.0 3177.7 5277.4 5403.2 5421.0 5501.8 5504.1 5398.8 5410.8 5447.2 5470.4 5517.3 4818.9 4907.0 4909.1 4911.3 5139.2 5004.1 5023.3 5055.7 5071.5 5079.0 4796.9 4848.4 4903.3 4921.0 4931.6 4908.4 4926.9 4987.9 4994.1 5023.8 4863.0 4956.3 4965.7 4970.6 4992.2 5341.6 5414.3 5416.8 5484.5 5523.8 5942.9 5950.0 5955.3 5981.3 5983.9 5721.5 5806.9 5822.1 5854.9 5881.3 5491.0 5513.1 5523.1 5540.7 5542.1 6034.7 6076.1 6082.6 6088.4 6096.0 5543.3 5644.6 5672.8 5707.7 5805.8 5846.4 5894.3 5897.9 5906.4 5909.6 5820.0 5820.1 5822.0 5826.5 5827.3 5660.2 5816.7 5844.1 5863.9 5890.1 4428.9 4433.8 4436.2 4437.3 4472.4 3879.0 3930.8 3970.7 3986.9 4018.3 5446.4 5469.4 5472.6 5506.5 5507.3 5909.7 5919.5 5923.1 5930.3 5977.3 5357.1 5416.9 5510.9 5520.3 5522.9 4993.4 5039.5 5050.3 5051.3 5086.1 6059.1 6061.5 6062.5 6062.5 6066.3 5659.7 5851.6 5864.1 5883.6 5920.7 6169.4 6169.6 6179.9 6183.6 6184.6 5809.1 5853.9 5867.8 5872.8 5912.2 5642.5 5718.4 5733.0 5774.7 5949.1 5649.3 5651.4 5653.6 5654.0 5656.5 5578.9 5626.7 5635.8 5644.2 5649.0 5478.1 5481.4 5484.9 5575.6 5591.2 5705.7 5724.0 5742.7 5753.9 5760.5 5495.2 5554.2 5607.6 5620.4 5625.1 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | [#1] ext.html Cycles:180 Average:67299.68 Median:41288.98 stddev:150617.69 (364.8%) stddev-sans-first:150956.90 10:20:14 INFO - PROCESS | 15174 | Values: 577.9 616.0 626.4 638.3 638.4 31635.2 32316.2 34597.0 34603.0 43638.8 43625.3 47446.5 47511.3 47527.0 47653.5 8640.1 8806.7 8824.2 8826.4 8829.2 8663.8 8818.5 8820.9 8821.8 8823.3 8838.2 8853.0 8879.2 8888.9 8897.3 8811.3 8853.3 8877.6 8879.7 8882.1 8657.4 8750.5 8755.1 8762.5 8768.1 41732.0 42134.0 42159.4 42164.4 42309.8 3434.5 3527.7 3559.6 3563.5 3569.9 32224.5 32334.5 32808.0 32883.7 33055.3 4190.7 4250.0 4254.7 4271.1 4324.9 344728.0 348135.0 349343.0 460812.0 464671.7 57557.7 58081.7 58346.1 58406.1 58426.4 56608.2 61672.4 61791.0 63545.4 63545.4 15084.2 15144.2 15158.1 15163.9 15230.5 12279.5 12392.5 12408.6 12408.6 12478.4 18850.2 19262.5 19391.8 19820.5 19828.8 6917.2 7160.9 7195.1 7231.6 7288.4 1032.6 1098.4 1137.2 1146.9 1147.8 32910.7 33284.7 33524.5 33548.5 33603.3 1069.2 1069.4 1140.6 1198.1 1254.9 17027.9 17460.4 17621.0 17717.5 17864.7 532182.0 816480.0 835957.0 838176.0 838977.0 59514.7 59570.8 59945.7 59973.4 60033.4 1277.8 1317.8 1337.4 1343.8 1345.7 31542.0 33371.5 33435.5 33668.3 34232.0 48409.8 48752.5 49075.0 49087.3 49129.0 412708.0 415922.0 416001.0 416454.0 417284.0 23143.4 27897.0 28431.9 28445.1 29651.0 48096.5 48231.3 48695.3 49215.5 49509.5 39393.6 39413.6 39976.2 40309.6 41280.8 39978.2 40330.0 40445.2 40877.8 41516.8 40078.0 40313.4 40704.8 40962.0 41297.2 34510.5 35682.8 36275.0 36277.6 36491.3 27752.4 28050.0 28409.6 28460.4 28479.0 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | [#2] jquery.html Cycles:180 Average:27084.75 Median:22126.89 stddev:68781.32 (310.8%) stddev-sans-first:68945.09 10:20:14 INFO - PROCESS | 15174 | Values: 401.5 402.4 408.7 411.6 421.3 12777.0 17819.7 18752.0 19376.5 19416.7 14462.9 16622.3 17836.0 18515.0 18873.6 5513.3 5684.4 5698.2 5709.0 5726.6 5645.1 5677.8 5681.0 5682.9 5689.1 4052.4 5982.6 6338.4 6352.9 6407.1 6091.1 6302.9 6366.0 6376.3 6404.6 5474.6 5484.3 5522.2 5531.8 5559.3 21051.7 21273.1 22092.7 22161.1 22237.7 2262.8 2312.9 2335.0 2356.6 2359.5 9271.6 13509.6 13982.3 14156.9 14406.0 1760.2 1811.4 1857.2 1881.5 1930.1 85126.0 85744.0 86960.0 87342.6 88814.0 10776.8 10861.9 10892.0 10959.8 11042.8 15290.7 15324.8 15447.9 15478.1 15500.3 7786.9 8056.1 8075.6 8156.9 8162.1 7608.7 7636.3 7673.4 7787.4 7807.1 4231.6 4521.6 4533.3 4631.5 4743.6 1822.0 1823.1 1847.9 1984.5 1984.8 932.9 971.5 976.8 986.6 1020.1 17462.3 18095.9 18153.4 18707.3 18860.6 985.6 1006.0 1033.9 1039.4 1063.3 10155.8 10528.8 10590.3 10628.4 10639.3 348182.0 356635.0 357013.0 462238.0 504001.0 97174.5 100878.0 102355.5 104789.0 105677.0 1029.4 1041.0 1051.8 1066.0 1066.8 16301.8 16574.3 16958.7 17189.0 17271.8 43128.4 43516.8 44499.8 44802.6 45176.5 56598.4 57711.7 61232.4 61829.0 62273.7 6012.5 8328.6 8424.0 8435.3 8459.5 12798.7 12849.9 12965.4 13043.2 13139.9 11470.7 11560.2 11564.8 11667.6 11672.1 11308.3 11338.3 11379.5 11412.0 11453.8 11647.9 11809.6 11948.6 11983.5 12040.4 8216.2 8260.5 8400.7 8470.5 8513.9 11475.2 11718.5 11752.1 11859.6 11874.8 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | [#3] mootools.html Cycles:180 Average:5787.93 Median:2563.91 stddev:3275.96 (127.8%) stddev-sans-first:3267.97 10:20:14 INFO - PROCESS | 15174 | Values: 1324.0 1461.0 1501.9 1621.6 1822.8 8324.2 8635.9 8674.0 8910.2 8922.6 8577.8 8849.7 8855.9 8867.7 8885.4 2344.1 2369.5 2372.5 2372.9 2374.5 2335.7 2356.7 2363.5 2363.8 2373.9 2320.5 2322.7 2345.6 2349.4 2352.5 2309.3 2317.0 2317.6 2360.8 2361.9 2230.0 2230.3 2232.4 2233.6 2241.0 8297.5 8504.2 8630.3 8770.9 8775.4 5353.7 5461.1 5471.0 5482.1 5484.2 3312.2 3335.7 3387.8 3389.4 3436.5 1187.1 1203.0 1204.0 1206.9 1211.8 12129.7 12153.6 12163.8 12238.2 12240.8 4547.0 4560.6 4661.1 4666.2 4666.4 9656.6 9685.8 9702.1 9703.5 9718.6 5248.3 5310.3 5318.6 5321.4 5325.3 4211.1 4403.5 4538.4 4943.5 4998.5 3838.5 3917.1 3934.1 3973.4 3982.9 1144.9 1183.3 1186.2 1188.7 1202.9 1004.2 1015.8 1022.9 1035.9 1039.6 6007.2 6156.9 6163.3 6179.0 6248.8 466.7 467.4 473.3 474.2 476.0 4159.5 4353.4 4408.3 4441.8 4469.1 10233.0 10509.2 10571.3 10582.9 10585.7 10752.4 11035.0 11056.2 11108.8 11182.8 8231.6 8232.8 8236.9 8298.7 8386.2 6116.6 6209.1 6227.5 6298.4 6308.1 10175.3 10221.3 10272.4 10367.1 10374.7 10092.4 10366.4 10544.2 10583.9 10631.7 6816.9 7253.7 7379.4 7382.7 7577.4 8316.6 8320.5 8371.7 8393.5 8397.2 7504.9 7663.4 7775.9 7875.8 7895.3 7601.1 7688.3 7725.1 7811.5 7813.9 7025.9 7030.8 7093.1 7101.0 7112.5 5230.6 5231.1 5274.8 5328.3 5336.7 5137.1 5160.8 5275.3 5442.0 5471.0 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | [#4] prototype.html Cycles:180 Average:4684.99 Median:5356.87 stddev:1381.71 (25.8%) stddev-sans-first:1346.35 10:20:14 INFO - PROCESS | 15174 | Values: 328.9 338.4 344.9 349.6 351.9 5377.5 5392.4 5411.5 5423.0 5432.4 5330.8 5344.0 5369.8 5377.6 5384.9 3969.4 4155.4 4198.9 4243.5 4258.9 4109.6 4140.6 4161.1 4165.2 4217.6 4144.0 4160.9 4165.6 4168.8 4204.4 3922.4 4013.4 4131.9 4150.8 4162.6 3600.5 3611.8 3615.4 3650.7 3661.4 4893.7 5118.5 5119.8 5153.7 5171.8 4597.7 4722.4 4723.1 4732.3 4747.5 5488.9 5493.5 5508.3 5533.6 5539.9 3936.2 4027.7 4028.8 4034.2 4063.7 5812.2 5814.3 5835.6 5849.8 5850.1 4097.7 4100.6 4103.8 4120.5 4136.4 4073.1 4155.6 4174.0 4174.3 4178.2 4707.8 4732.5 4747.5 4752.9 4781.3 5567.2 5574.5 5574.9 5603.6 5626.9 1398.3 1400.2 1437.2 1442.8 1443.7 890.8 914.8 917.9 920.6 930.4 4729.4 4839.9 4873.6 4883.2 4896.6 4900.7 5087.3 5098.8 5099.8 5101.5 4811.5 5189.3 5236.9 5406.3 5470.9 3049.7 3050.7 3057.5 3065.3 3074.7 5686.3 5950.1 5950.3 5956.8 5996.4 5833.3 5881.5 5883.8 5887.6 5893.8 5900.4 5943.2 5952.2 5958.5 5970.0 5419.6 5690.3 5769.1 5785.8 5787.0 5662.3 5717.5 5739.3 5761.0 5777.3 5747.7 5943.4 5968.0 6008.5 6045.8 4116.5 4124.6 4135.2 4146.0 4147.9 5830.5 5843.9 5858.2 5859.6 5870.5 5417.5 5688.9 5692.7 5710.4 5738.0 5578.8 5606.2 5618.3 5662.0 5692.3 5544.3 5564.3 5573.8 5581.4 5583.9 5486.9 5678.5 5683.7 5688.3 5697.6 5331.8 5488.6 5535.6 5554.1 5557.1 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - PROCESS | 15174 | [#5] yui.html Cycles:180 Average:16335.70 Median:4659.50 stddev:20295.92 (435.6%) stddev-sans-first:20319.25 10:20:14 INFO - PROCESS | 15174 | Values: 780.9 805.4 823.8 844.4 863.9 14232.3 14713.7 14783.6 14837.2 14869.2 13806.1 13839.2 13910.5 13957.2 13971.0 684.2 807.0 807.3 821.1 832.2 826.6 828.1 830.9 831.0 831.6 858.0 860.9 861.6 861.7 862.0 701.2 832.0 853.5 854.4 854.4 624.2 624.3 624.3 624.3 624.9 14204.2 14272.6 14290.0 14313.2 14313.6 12371.4 12639.1 12647.9 12721.3 12769.5 11608.5 11793.5 11809.5 11911.0 12008.4 4446.9 4488.8 4531.2 4562.1 4652.4 57672.1 57676.0 57839.4 59919.1 60388.0 10826.5 10950.2 11017.7 11100.3 11153.3 19805.6 19822.2 19943.0 19976.6 19977.3 7884.8 7896.6 7909.1 7910.4 8170.5 4911.2 4941.3 4949.0 4963.1 4999.8 3327.1 3403.6 3406.4 3409.5 3410.2 655.7 659.5 686.7 700.3 705.2 705.6 706.4 718.6 721.7 726.5 9608.6 9976.0 10029.4 10218.1 10283.6 640.5 666.4 726.8 741.6 749.2 4220.7 4293.4 4313.7 4419.7 4666.6 74364.0 75097.6 77021.0 77092.0 77737.6 68112.3 68895.7 69648.0 70052.0 71216.6 57052.1 57236.7 57528.0 57713.7 58051.0 8701.4 8907.0 8913.7 8971.6 8995.6 37441.3 37602.0 38188.8 38929.2 39036.0 48962.8 51021.5 51809.5 51936.2 52387.5 12222.6 12330.3 12414.2 12631.6 12644.9 11963.3 13244.9 13385.8 13528.2 13816.2 11301.5 12226.4 12790.9 12835.8 13250.5 13121.0 13329.6 13371.5 13414.0 13511.1 12980.6 13154.6 13270.8 13396.7 13483.4 9739.7 11227.7 11405.6 11553.8 11888.0 11161.6 11283.9 11545.8 11608.5 11653.1 10:20:14 INFO - PROCESS | 15174 | -------- Summary: end -------- 10:20:14 INFO - PROCESS | 15174 | 10:20:14 INFO - TEST-INFO | 15174: exit 0 10:20:14 INFO - TEST-OK | dromaeo_css | took 1112175ms 10:20:14 INFO - TEST-START | kraken 10:20:14 INFO - operating with platform_type : linux_ 10:20:14 INFO - Initialising browser for kraken test... 10:20:14 INFO - TEST-INFO | started process 15357 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUJ1iRK/profile http://localhost:52717/getInfo.html) 10:20:18 INFO - PROCESS | 15357 | __metrics Screen width/height:1600/1200 10:20:18 INFO - PROCESS | 15357 | colorDepth:24 10:20:18 INFO - PROCESS | 15357 | Browser inner width/height: 1024/697 10:20:18 INFO - PROCESS | 15357 | __metrics 10:20:19 ERROR - PROCESS | 15357 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:20:20 INFO - TEST-INFO | 15357: exit 0 10:20:20 INFO - Browser initialized. 10:20:20 INFO - Running cycle 1/1 for kraken test... 10:20:20 INFO - TEST-INFO | started process 15440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUJ1iRK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:20:32 INFO - PROCESS | 15440 | RSS: Main: 155693056 10:20:32 INFO - PROCESS | 15440 | 10:20:34 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52717/tests/kraken/driver.html?testName=audio-beat-detection) 10:20:35 INFO - PROCESS | 15440 | RSS: Main: 213778432 10:20:35 INFO - PROCESS | 15440 | 10:20:38 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52717/tests/kraken/driver.html?testName=audio-dft) 10:20:38 INFO - PROCESS | 15440 | RSS: Main: 198529024 10:20:38 INFO - PROCESS | 15440 | 10:20:41 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52717/tests/kraken/driver.html?testName=audio-fft) 10:20:42 INFO - PROCESS | 15440 | RSS: Main: 268742656 10:20:42 INFO - PROCESS | 15440 | 10:20:44 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52717/tests/kraken/driver.html?testName=audio-oscillator) 10:20:45 INFO - PROCESS | 15440 | RSS: Main: 179212288 10:20:45 INFO - PROCESS | 15440 | 10:20:47 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52717/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:20:47 INFO - PROCESS | 15440 | RSS: Main: 184307712 10:20:47 INFO - PROCESS | 15440 | 10:20:50 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52717/tests/kraken/driver.html?testName=imaging-darkroom) 10:20:51 INFO - PROCESS | 15440 | RSS: Main: 217829376 10:20:51 INFO - PROCESS | 15440 | 10:20:54 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52717/tests/kraken/driver.html?testName=imaging-desaturate) 10:20:55 INFO - PROCESS | 15440 | RSS: Main: 220495872 10:20:55 INFO - PROCESS | 15440 | 10:20:58 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52717/tests/kraken/driver.html?testName=json-parse-financial) 10:20:58 INFO - PROCESS | 15440 | RSS: Main: 219889664 10:20:58 INFO - PROCESS | 15440 | 10:21:00 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52717/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:21:01 INFO - PROCESS | 15440 | RSS: Main: 206790656 10:21:01 INFO - PROCESS | 15440 | 10:21:02 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:21:03 INFO - PROCESS | 15440 | RSS: Main: 210464768 10:21:03 INFO - PROCESS | 15440 | 10:21:05 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:21:06 INFO - PROCESS | 15440 | RSS: Main: 216432640 10:21:06 INFO - PROCESS | 15440 | 10:21:08 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:21:09 INFO - PROCESS | 15440 | RSS: Main: 223080448 10:21:09 INFO - PROCESS | 15440 | 10:21:11 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:21:12 INFO - PROCESS | 15440 | RSS: Main: 223039488 10:21:12 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | Cycle 1(1): loaded http://localhost:52717/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52717/tests/kraken/driver.html?testName=ai-astar) 10:21:14 INFO - PROCESS | 15440 | RSS: Main: 265490432 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | __start_tp_report 10:21:14 INFO - PROCESS | 15440 | _x_x_mozilla_page_load 10:21:14 INFO - PROCESS | 15440 | _x_x_mozilla_page_load_details 10:21:14 INFO - PROCESS | 15440 | |i|pagename|runs| 10:21:14 INFO - PROCESS | 15440 | |0;ai-astar;111;105;110;109;110;112;111;110;112;110 10:21:14 INFO - PROCESS | 15440 | |1;audio-beat-detection;156;130;126;180;130;133;165;128;175;131 10:21:14 INFO - PROCESS | 15440 | |2;audio-dft;161;159;161;161;162;162;160;162;161;161 10:21:14 INFO - PROCESS | 15440 | |3;audio-fft;74;75;75;127;75;75;75;81;97;74 10:21:14 INFO - PROCESS | 15440 | |4;audio-oscillator;83;83;125;83;83;84;120;84;83;123 10:21:14 INFO - PROCESS | 15440 | |5;imaging-gaussian-blur;117;116;116;117;116;117;117;117;116;117 10:21:14 INFO - PROCESS | 15440 | |6;imaging-darkroom;147;145;145;146;146;147;145;145;145;145 10:21:14 INFO - PROCESS | 15440 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 10:21:14 INFO - PROCESS | 15440 | |8;json-parse-financial;53;53;53;53;53;52;52;52;53;53 10:21:14 INFO - PROCESS | 15440 | |9;json-stringify-tinderbox;61;62;62;63;62;61;62;63;63;62 10:21:14 INFO - PROCESS | 15440 | |10;stanford-crypto-aes;79;79;79;84;89;79;88;79;108;80 10:21:14 INFO - PROCESS | 15440 | |11;stanford-crypto-ccm;121;120;120;118;117;120;119;129;127;121 10:21:14 INFO - PROCESS | 15440 | |12;stanford-crypto-pbkdf2;163;163;160;159;159;163;162;158;159;161 10:21:14 INFO - PROCESS | 15440 | |13;stanford-crypto-sha256-iterative;63;63;64;62;63;63;65;62;61;60 10:21:14 INFO - PROCESS | 15440 | __end_tp_report 10:21:14 INFO - PROCESS | 15440 | __start_cc_report 10:21:14 INFO - PROCESS | 15440 | _x_x_mozilla_cycle_collect,1641 10:21:14 INFO - PROCESS | 15440 | __end_cc_report 10:21:14 INFO - PROCESS | 15440 | __startTimestamp1464456074443__endTimestamp 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | ------- Summary: start ------- 10:21:14 INFO - PROCESS | 15440 | Number of tests: 14 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.50 stddev:2.00 (1.8%) stddev-sans-first:2.09 10:21:14 INFO - PROCESS | 15440 | Values: 111.0 105.0 110.0 109.0 110.0 112.0 111.0 110.0 112.0 110.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:144.50 stddev:21.30 (14.7%) stddev-sans-first:22.25 10:21:14 INFO - PROCESS | 15440 | Values: 156.0 130.0 126.0 180.0 130.0 133.0 165.0 128.0 175.0 131.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 10:21:14 INFO - PROCESS | 15440 | Values: 161.0 159.0 161.0 161.0 162.0 162.0 160.0 162.0 161.0 161.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.00 stddev:17.05 (22.7%) stddev-sans-first:17.79 10:21:14 INFO - PROCESS | 15440 | Values: 74.0 75.0 75.0 127.0 75.0 75.0 75.0 81.0 97.0 74.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:83.00 stddev:19.06 (23.0%) stddev-sans-first:19.71 10:21:14 INFO - PROCESS | 15440 | Values: 83.0 83.0 125.0 83.0 83.0 84.0 120.0 84.0 83.0 123.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:21:14 INFO - PROCESS | 15440 | Values: 117.0 116.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.73 10:21:14 INFO - PROCESS | 15440 | Values: 147.0 145.0 145.0 146.0 146.0 147.0 145.0 145.0 145.0 145.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 10:21:14 INFO - PROCESS | 15440 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 10:21:14 INFO - PROCESS | 15440 | Values: 53.0 53.0 53.0 53.0 53.0 52.0 52.0 52.0 53.0 53.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 10:21:14 INFO - PROCESS | 15440 | Values: 61.0 62.0 62.0 63.0 62.0 61.0 62.0 63.0 63.0 62.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:79.50 stddev:9.17 (11.5%) stddev-sans-first:9.51 10:21:14 INFO - PROCESS | 15440 | Values: 79.0 79.0 79.0 84.0 89.0 79.0 88.0 79.0 108.0 80.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#11] stanford-crypto-ccm Cycles:10 Average:121.20 Median:120.50 stddev:3.82 (3.2%) stddev-sans-first:4.06 10:21:14 INFO - PROCESS | 15440 | Values: 121.0 120.0 120.0 118.0 117.0 120.0 119.0 129.0 127.0 121.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:161.50 stddev:1.95 (1.2%) stddev-sans-first:1.88 10:21:14 INFO - PROCESS | 15440 | Values: 163.0 163.0 160.0 159.0 159.0 163.0 162.0 158.0 159.0 161.0 10:21:14 INFO - PROCESS | 15440 | 10:21:14 INFO - PROCESS | 15440 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:1.43 (2.3%) stddev-sans-first:1.51 10:21:14 INFO - PROCESS | 15440 | Values: 63.0 63.0 64.0 62.0 63.0 63.0 65.0 62.0 61.0 60.0 10:21:14 INFO - PROCESS | 15440 | -------- Summary: end -------- 10:21:14 INFO - PROCESS | 15440 | 10:21:15 INFO - TEST-INFO | 15440: exit 0 10:21:15 INFO - TEST-OK | kraken | took 60634ms 10:21:15 INFO - SUITE-END | took 1172s 10:21:15 INFO - Completed test suite (00:19:33) 10:21:15 INFO - javascript benchmark 10:21:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3117.984410077949, 3121.859516321766, 3151.7951333163355, 3158.9842050789744, 3177.6663450337714, 5277.366715994077, 5403.243545903571, 5420.972895135528, 5501.752421141048, 5504.091824848895, 5398.78404863805, 5410.783568657249, 5447.210154527591, 5470.370907345657, 5517.337919449662, 4818.879528011801, 4907.043126590316, 4909.140900342443, 4911.287863259825, 5139.177731562951, 5004.074246264435, 5023.32185155003, 5055.721935293557, 5071.467495912932, 5079.0, 4796.856094317159, 4848.442429120643, 4903.26451032348, 4920.966597014632, 4931.605471562267, 4908.361912951329, 4926.876828079291, 4987.875303117415, 4994.126027945095, 5023.849284521433, 4863.0, 4956.2565615157655, 4965.652404331699, 4970.627202959757, 4992.176290911995, 5341.572674186055, 5414.269073675043, 5416.8104116356135, 5484.506394424521, 5523.751431185566, 5942.881142377171, 5950.0, 5955.34491205967, 5981.312149102865, 5983.880322393527, 5721.513671337942, 5806.941930580682, 5822.068469044975, 5854.941450585482, 5881.323647780518, 5491.0, 5513.117901135799, 5523.088690366085, 5540.667559946416, 5542.140968149944, 6034.739158884043, 6076.088586712029, 6082.635041897412, 6088.391160883876, 6096.0, 5543.25166102571, 5644.604877658607, 5672.829815105553, 5707.74315155819, 5805.82582522525, 5846.386129456431, 5894.292684877756, 5897.911531326948, 5906.379830117861, 5909.556783241274, 5820.039693450618, 5820.126980952891, 5821.970890145522, 5826.504747096545, 5827.300723913073, 5660.179274005246, 5816.70916454184, 5844.064949608042, 5863.941360586339, 5890.087036509348, 4428.933565996513, 4433.7561434121435, 4436.157130145262, 4437.312216606431, 4472.418585583919, 3879.049632839972, 3930.8034598269974, 3970.741901776433, 3986.880393588197, 4018.2967980603275, 5446.428125046813, 5469.398366179717, 5472.589555783332, 5506.531944784738, 5507.256520369779, 5909.734061967221, 5919.526437884959, 5923.052311630205, 5930.28836539618, 5977.252843394575, 5357.116075847442, 5416.945830541644, 5510.91733623988, 5520.282363292747, 5522.861928451821, 4993.375828021497, 5039.496050395004, 5050.3434553507805, 5051.292819005343, 5086.059079070384, 6059.121427393004, 6061.54538409621, 6062.514998800086, 6062.514998800086, 6066.302375226817, 5659.688717120598, 5851.590388672752, 5864.120381942743, 5883.646981180972, 5920.674362910081, 6169.444749972524, 6169.598976066542, 6179.876402471836, 6183.598066125687, 6184.5980011299125, 5809.070548712186, 5853.882922341444, 5867.794627188028, 5872.794452194153, 5912.231409916855, 5642.520385767256, 5718.428157184248, 5732.971335143465, 5774.653520788766, 5949.107633854784, 5649.265595472562, 5651.406602306863, 5653.632513886584, 5654.0, 5656.519195868233, 5578.944210557843, 5626.746796394254, 5635.774569017193, 5644.209810626598, 5649.039663257338, 5478.096114141122, 5481.369642491165, 5484.862878427912, 5575.609707320448, 5591.217229587943, 5705.686187259741, 5724.0, 5742.741576628978, 5753.942460575341, 5760.539156867524, 5495.203195536705, 5554.222408862682, 5607.607467477237, 5620.437956204511, 5625.071863142535], "value": 5379.563145496576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.9162021506882, 615.9784407545736, 626.4080443980438, 638.2691817868542, 638.3967151042261, 31635.20911977202, 32316.192095197603, 34597.0, 34603.0, 43638.78180609101, 43625.34561981573, 47446.52553474473, 47511.287330690066, 47527.0, 47653.52346476534, 8640.135986401358, 8806.735797926072, 8824.205821476065, 8826.42628229163, 8829.16122968317, 8663.783405414884, 8818.470891746516, 8820.911790882074, 8821.823563528726, 8823.338249631272, 8838.204561589455, 8853.0, 8879.245264152554, 8888.866667, 8897.288216942661, 8811.251043661296, 8853.291736661116, 8877.600507977157, 8879.689210877621, 8882.06738292483, 8657.393982421234, 8750.518721470316, 8755.124487551258, 8762.474251544927, 8768.123187681245, 41732.0, 42134.0, 42159.36760948588, 42164.367534487006, 42309.78845105786, 3434.4848272759036, 3527.7354198434964, 3559.5906470755936, 3563.4832949222478, 3569.94645080324, 32224.516632250536, 32334.514982275286, 32808.0, 32883.6711632883, 33055.338893222244, 4190.706650534464, 4249.978750106261, 4254.659627229814, 4271.145770845812, 4324.870253892388, 344728.0, 348135.0, 349343.0, 460812.0, 464671.676641618, 57557.71221143867, 58081.70959145177, 58346.12480812706, 58406.12390814141, 58426.4157358421, 56608.150877736865, 61672.38327616666, 61791.0, 63545.36454635394, 63545.36454635487, 15084.170370629721, 15144.242787860563, 15158.090514568941, 15163.924180379248, 15230.46693365727, 12279.508819647293, 12392.504299828086, 12408.56570020045, 12408.62774116778, 12478.43847026886, 18850.245990160514, 19262.518437039187, 19391.806081939, 19820.493269743634, 19828.801711982986, 6917.169939607279, 7160.892586611205, 7195.136583609896, 7231.638418079075, 7288.416926645856, 1032.566322144701, 1098.4058753324143, 1137.2039572299423, 1146.9047060057626, 1147.8106112491346, 32910.67089329124, 33284.667153328155, 33524.49713254303, 33548.496772547944, 33603.311735061136, 1069.2141276162015, 1069.3583849690124, 1140.6121918547733, 1198.1313547677864, 1254.9498020079277, 17027.91486042562, 17460.388886388915, 17621.0, 17717.468475945483, 17864.732029019575, 532182.0, 816480.0, 835957.0, 838176.0, 838977.0, 59514.70242648759, 59570.80858382808, 59945.700271499234, 59973.400265997654, 60033.39966600366, 1277.8211050452944, 1317.795741660025, 1337.4249072898747, 1343.8051482534977, 1345.6837643153851, 31542.0, 33371.49942750812, 33435.498467523495, 33668.32663346768, 34232.0, 48409.75795121002, 48752.5124748748, 49075.0, 49087.26369104395, 49129.0, 412708.0, 415922.0, 416001.0, 416454.0, 417284.0, 23143.421414464774, 27897.0, 28431.857840710665, 28445.146645600664, 29651.0, 48096.51903480921, 48231.27653085277, 48695.26957095575, 49215.50784492109, 49509.50490495049, 39393.60606394014, 39413.649839763944, 39976.200475990896, 40309.59690403176, 41280.79359603183, 39978.2004359917, 40330.0, 40445.1910961785, 40877.79561102294, 41516.79241603773, 40078.0, 40313.395299071126, 40704.796476018615, 40962.0, 41297.1740565181, 34510.48234276538, 35682.82158589191, 36275.0, 36277.637223627426, 36491.27017459689, 27752.44495110046, 28050.0, 28409.57385639176, 28460.43079138447, 28479.0], "value": 20287.88930070887, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.47607372379053, 402.3903785764567, 408.7077739416317, 411.56990944463035, 421.2543797478463, 12777.0, 17819.732704009435, 18752.0, 19376.515587110312, 19416.708749368772, 14462.855371446283, 16622.25199866006, 17836.0, 18515.0, 18873.622527549476, 5513.283273174482, 5684.403137670526, 5698.202251684767, 5709.0, 5726.570507211955, 5645.096784514479, 5677.801276955307, 5681.0, 5682.914756278659, 5689.089745640698, 4052.3516237402023, 5982.611130276518, 6338.366163383648, 6352.872942541147, 6407.070974708688, 6091.11678806575, 6302.873942521124, 6366.045093236005, 6376.26672932614, 6404.615723056609, 5474.589405794582, 5484.314460692413, 5522.22688823561, 5531.806386776484, 5559.305086864142, 21051.68422473664, 21273.14907403702, 22092.668609970864, 22161.113555457763, 22237.666435003488, 2262.7624099469485, 2312.861228326306, 2335.0309621507054, 2356.634721618152, 2359.5280943811135, 9271.629134834599, 13509.59471215865, 13982.300884955814, 14156.858431415658, 14406.0, 1760.1639221369746, 1811.3660218923403, 1857.2385322018042, 1881.4825922871182, 1930.0639189992983, 85125.99999999937, 85744.0, 86960.0, 87342.56328718379, 88814.0, 10776.78446431067, 10861.891381086247, 10892.0, 10959.83560246597, 11042.834357484644, 15290.694186116216, 15324.846751532343, 15447.922760386351, 15478.148701821283, 15500.30248638814, 7786.922130778619, 8056.113827478973, 8075.555844428495, 8156.877646835303, 8162.061362943219, 7608.695652173851, 7636.31273185416, 7673.424493162924, 7787.415943804237, 7807.102183248999, 4231.597998190167, 4521.570450807168, 4533.342665313511, 4631.536846315409, 4743.596794272526, 1821.9979457866339, 1823.0975667044897, 1847.8798878072882, 1984.5435549823621, 1984.7701307055722, 932.911373419524, 971.4705485510414, 976.7655762617063, 986.6300137448457, 1020.0768304684273, 17462.30150793954, 18095.909520452577, 18153.364632237808, 18707.34524291643, 18860.622787544173, 985.5909797434128, 1006.0, 1033.89661033896, 1039.365986748083, 1063.2557209953063, 10155.847662285072, 10528.789424211625, 10590.311629744043, 10628.36229826213, 10639.308444951053, 348182.0, 356635.0, 357013.0, 462238.0, 504001.0, 97174.5141274289, 100877.99999999853, 102355.48822255842, 104789.0, 105677.0, 1029.3926583315879, 1041.0474415909357, 1051.8264486359817, 1066.0352381095122, 1066.8026415113075, 16301.755473668141, 16574.337026518806, 16958.745618815974, 17189.0, 17271.827281727525, 43128.35307470328, 43516.78241608771, 44499.77750111229, 44802.55197447984, 45176.548234517235, 56598.43401566096, 57711.71144144252, 61232.38767612267, 61829.0, 62273.68863155655, 6012.518998480198, 8328.583570821556, 8423.957880210559, 8435.32517398619, 8459.450135741157, 12798.68003299925, 12849.935750321189, 12965.41655625518, 13043.152195107288, 13139.934300328438, 11470.71323216893, 11560.248583842023, 11564.826527601925, 11667.59163429276, 11672.066234701033, 11308.264962777394, 11338.319700817647, 11379.54481820718, 11412.0, 11453.770924581628, 11647.941760291144, 11809.586664466702, 11948.581799636971, 11983.520659173884, 12040.43474348329, 8216.21945915137, 8260.545669988209, 8400.663973440995, 8470.534120623424, 8513.91486085156, 11475.196736228718, 11718.472668730099, 11752.059835213333, 11859.644210673694, 11874.76250475003], "value": 9179.23231245722, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.9536616218434, 1460.9999999999998, 1501.9399224031038, 1621.5783896186995, 1822.835944764971, 8324.167583241673, 8635.91364086359, 8674.04585495595, 8910.198082172603, 8922.598483068261, 8577.828443431143, 8849.734507964755, 8855.91144088559, 8867.68963086292, 8885.378023538355, 2344.120954642005, 2369.514249578841, 2372.5136347048815, 2372.857628542284, 2374.5013547155118, 2335.719713634366, 2356.6582845487396, 2363.4918492524102, 2363.7990770784504, 2373.8694371809543, 2320.489492311697, 2322.7444981052154, 2345.5543446745064, 2349.447879748258, 2352.529668956902, 2309.2956648222284, 2317.0, 2317.6291793313158, 2360.7875291223786, 2361.8582885026867, 2229.9777002230094, 2230.308604332662, 2232.386093824195, 2233.553289342122, 2241.0363543676212, 8297.460665056813, 8504.23461888433, 8630.266427353683, 8770.868436973451, 8775.385722999392, 5353.678779273256, 5461.0716178249795, 5470.972645136789, 5482.095454250044, 5484.2047903053835, 3312.2381852174135, 3335.7498187636024, 3387.762856600039, 3389.406853800575, 3436.484527320897, 1187.068151501076, 1203.0195390756505, 1204.048801446856, 1206.8551773787115, 1211.7818792617325, 12129.696757581043, 12153.574624888175, 12163.756724865452, 12238.204516706386, 12240.81638775419, 4546.954530454653, 4560.612347950461, 4661.114388266286, 4666.160091183619, 4666.370040044619, 9656.613735450645, 9685.806283874283, 9702.126808587262, 9703.514824258758, 9718.611255549698, 5248.317718696545, 5310.256564081032, 5318.601104917147, 5321.414644389114, 5325.307709997675, 4211.136716973075, 4403.5376285489565, 4538.387317712132, 4943.505649435027, 4998.475160108209, 3838.481804956351, 3917.138229589486, 3934.09515811365, 3973.443717879499, 3982.8605998789903, 1144.8855114488651, 1183.3373310945897, 1186.1578279421708, 1188.7206506470973, 1202.8857258560422, 1004.1514919892679, 1015.7714514234239, 1022.9335093218917, 1035.8756949165997, 1039.61534232333, 6007.219061522062, 6156.907646385308, 6163.291221509494, 6179.0, 6248.781292654735, 466.710639403572, 467.3550500309551, 473.3278744183268, 474.1938704202869, 475.9595434388116, 4159.480064991876, 4353.4122893408985, 4408.338749187584, 4441.755703436342, 4469.128519938581, 10233.0, 10509.159267258601, 10571.2600117991, 10582.894171058344, 10585.735356616146, 10752.40861752611, 11035.0, 11056.170787190993, 11108.777824443467, 11182.832257516144, 8231.629576669064, 8232.847401363813, 8236.917630823735, 8298.70954516589, 8386.203310685476, 6116.633002019893, 6209.068639704081, 6227.532935029982, 6298.401651843067, 6308.116863639002, 10175.270204065178, 10221.33561318512, 10272.383656980604, 10367.118794902517, 10374.688759337232, 10092.444915529717, 10366.378017318984, 10544.15646748243, 10583.94708026455, 10631.734206644587, 6816.863662726817, 7253.746118885813, 7379.409647228316, 7382.704691812267, 7577.431692622965, 8316.62575184106, 8320.459170153921, 8371.665133394596, 8393.454425462325, 8397.244248017463, 7504.887426688495, 7663.425243106678, 7775.883361749465, 7875.84248315042, 7895.328897043815, 7601.087869455479, 7688.269614386624, 7725.072991241087, 7811.453198276294, 7813.882791758242, 7025.894611580728, 7030.789076327718, 7093.113360829896, 7101.0, 7112.50212485121, 5230.581553475637, 5231.1107111790325, 5274.789008439773, 5328.254044433705, 5336.653117547347, 5137.101007323628, 5160.845174644766, 5275.340582427197, 5442.0204363215, 5470.972645136749], "value": 4569.85858705472, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.9194147433879, 338.402719200611, 344.94825776133575, 349.60843854882535, 351.88036067736965, 5377.516023557879, 5392.379876314225, 5411.512963833255, 5422.9728851355785, 5432.375276843164, 5330.840074797753, 5343.973280133594, 5369.7583608737605, 5377.623566350357, 5384.865378365543, 3969.4045893115976, 4155.439015732883, 4198.916021679564, 4243.490781106264, 4258.936115958248, 4109.630133288004, 4140.648044916185, 4161.127492628314, 4165.187788381251, 4217.578242175773, 4143.979280103596, 4160.9375859362135, 4165.56261592533, 4168.812403441852, 4204.369344598304, 3922.3724204127348, 4013.3578627419765, 4131.855385061538, 4150.750954942713, 4162.604552567501, 3600.5499312585926, 3611.819409029564, 3615.439606860941, 3650.689691376236, 3661.3958696814993, 4893.730844803535, 5118.539331460186, 5119.769610367543, 5153.716545589991, 5171.7672704728375, 4597.724136551784, 4722.409698787652, 4723.055388922229, 4732.266498692674, 4747.52524747526, 5488.890222195574, 5493.450654934514, 5508.283923090013, 5533.612647114704, 5539.944600553983, 3936.153726948691, 4027.6708686133798, 4028.7582745035393, 4034.1931613677384, 4063.674906007512, 5812.157237200625, 5814.273215848019, 5835.620684655484, 5849.824505264849, 5850.0932355484965, 4097.672186225154, 4100.610442008005, 4103.835846566164, 4120.464339635828, 4136.358864376027, 4073.124278280184, 4155.605217504332, 4173.9982404222765, 4174.290370636999, 4178.247915375262, 4707.764611769467, 4732.479427262998, 4747.548982846624, 4752.928706069411, 4781.306710526954, 5567.164925261162, 5574.498295153375, 5574.860628484239, 5603.579731520153, 5626.887462250732, 1398.3357904995046, 1400.208881981677, 1437.2310813714612, 1442.776369662704, 1443.6968236670416, 890.799570096736, 914.8061779377842, 917.857732051533, 920.6271460058687, 930.432436213909, 4729.408823897013, 4839.879003024882, 4873.610111191097, 4883.243097319921, 4896.608271338285, 4900.656954013256, 5087.3386459759995, 5098.821541246039, 5099.796008159707, 5101.51535604117, 4811.542903424169, 5189.325387699575, 5236.947630523646, 5406.270153529302, 5470.890582188334, 3049.7255247027874, 3050.740687041582, 3057.4955132402906, 3065.2796592800673, 3074.6771588983283, 5686.289213848269, 5950.095969289872, 5950.256217972754, 5956.821295361146, 5996.402158704829, 5833.279182641131, 5881.529477641779, 5883.764649413975, 5887.646741195456, 5893.823185304448, 5900.380460051719, 5943.167956486095, 5952.196453478811, 5958.480587450345, 5970.0, 5419.647722897999, 5690.260266165372, 5769.134629805562, 5785.768569257182, 5787.0, 5662.2639056922335, 5717.485426311652, 5739.253896993364, 5760.971195144165, 5777.313549199643, 5747.655140691572, 5943.375945525658, 5967.986166985645, 6008.519318454426, 6045.75816967334, 4116.485439320085, 4124.649404800505, 4135.2425219281085, 4145.958540414678, 4147.937780933349, 5830.5044071254415, 5843.883122337615, 5858.209141765978, 5859.589828711949, 5870.47165755067, 5417.458254174551, 5688.914666279927, 5692.658440493584, 5710.4004079572705, 5738.0, 5578.832635020955, 5606.18710286998, 5618.325800903917, 5661.952538780339, 5692.292312306209, 5544.279243698294, 5564.33228012641, 5573.832785016456, 5581.441855814386, 5583.8883222336135, 5486.915550545564, 5678.488935995781, 5683.658980461186, 5688.317401911631, 5697.6296540724725, 5331.760070796746, 5488.56091512686, 5535.640183388067, 5554.1391084381985, 5557.110582402002], "value": 4245.953494559029, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.9219078092187, 805.3516918880301, 823.7816978500698, 844.3751623798391, 863.8661007543833, 14232.288385580732, 14713.705725885477, 14783.556493305217, 14837.18395488249, 14869.182194979272, 13806.102603330777, 13839.169649821019, 13910.51313204038, 13957.23235222065, 13971.0, 684.1755684400285, 807.0476837331961, 807.3299161695808, 821.0844907927648, 832.1719888710742, 826.6404114210308, 828.1056459024239, 830.9335253179731, 831.0110967948152, 831.5883637599396, 857.9575815384306, 860.8665656823171, 861.6208868098048, 861.7371701631031, 861.9525926074064, 701.1866235167207, 831.9542425166583, 853.4751128056263, 854.3976496569903, 854.4275335525182, 624.2290770897937, 624.2758400255682, 624.2945471617089, 624.322609968185, 624.865653884417, 14204.218767967757, 14272.571822845332, 14290.0, 14313.212773297464, 14313.642158946006, 12371.443285052193, 12639.115261931669, 12647.873521264853, 12721.30032848193, 12769.48922043117, 11608.535658573646, 11793.469293881795, 11809.468573914195, 11911.047116230768, 12008.399580020963, 4446.911061778778, 4488.7531185784765, 4531.207038768202, 4562.064776720765, 4652.395188625457, 57672.13491797626, 57676.0, 57839.42160578383, 59919.10121348183, 60387.99999999956, 10826.458677066115, 10950.233483656228, 11017.669469915916, 11100.333979961228, 11153.330800152016, 19805.60388792216, 19822.20711171537, 19943.0, 19976.60046799056, 19977.300794472416, 7884.802879927933, 7896.644650990719, 7909.090454597795, 7910.406719496061, 8170.509769413854, 4911.16510193268, 4941.332920055818, 4949.0, 4963.057019166346, 4999.800007999639, 3327.068420842168, 3403.642617525124, 3406.386850366908, 3409.522666826597, 3410.1645096951406, 655.7180412422608, 659.5053709717712, 686.7047169716969, 700.264722041854, 705.1643802094534, 705.6401235369896, 706.4242642246553, 718.615540685741, 721.6644260418931, 726.4841962206892, 9608.567614457366, 9975.950120249352, 10029.398236105857, 10218.131458825934, 10283.640072597424, 640.4556127291858, 666.3702800853147, 726.8291951391419, 741.5958302725028, 749.2470067582154, 4220.725652832556, 4293.4203882476095, 4313.729407886232, 4419.734815911055, 4666.60333871618, 74364.0, 75097.62451187709, 77021.0, 77092.0, 77737.61131194422, 68112.3188768116, 68895.65552172207, 69648.0, 70052.0, 71216.64391678009, 57052.144217836765, 57236.71381643065, 57528.0, 57713.71143144257, 58051.0, 8701.390902637007, 8907.0, 8913.688020919237, 8971.596278167628, 8995.550222488719, 37441.25117497689, 37602.0, 38188.809055954545, 38929.221415570966, 39036.0, 48962.75518622384, 51021.48978510167, 51809.48190518197, 51936.22095668643, 52387.47612523826, 12222.633321000385, 12330.260184388966, 12414.193077449936, 12631.557895473614, 12644.936775316433, 11963.282203067844, 13244.867551324363, 13385.799213012007, 13528.188308701509, 13816.240106794223, 11301.491432885705, 12226.449809758404, 12790.936045319713, 12835.807462887877, 13250.469981200644, 13121.015923805944, 13329.600111996655, 13371.53199638008, 13414.0, 13511.05422620407, 12980.610581682564, 13154.60536183916, 13270.80093798613, 13396.665083372603, 13483.393247303698, 9739.659111931049, 11227.66317010491, 11405.600803971822, 11553.826692599785, 11888.0, 11161.553537858395, 11283.887161128283, 11545.82681259765, 11608.535658573563, 11653.126015548698], "value": 7026.203514631209, "unit": "score"}], "name": "dromaeo_css", "value": 7176.385116511498, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 109.0, 110.0, 112.0, 111.0, 110.0, 112.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 130.0, 126.0, 180.0, 130.0, 133.0, 165.0, 128.0, 175.0, 131.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 161.0, 161.0, 162.0, 162.0, 160.0, 162.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 127.0, 75.0, 75.0, 75.0, 81.0, 97.0, 74.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 125.0, 83.0, 83.0, 84.0, 120.0, 84.0, 83.0, 123.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 146.0, 146.0, 147.0, 145.0, 145.0, 145.0, 145.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, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 53.0, 52.0, 52.0, 52.0, 53.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 63.0, 62.0, 61.0, 62.0, 63.0, 63.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 84.0, 89.0, 79.0, 88.0, 79.0, 108.0, 80.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 120.0, 118.0, 117.0, 120.0, 119.0, 129.0, 127.0, 121.0], "value": 121.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 160.0, 159.0, 159.0, 163.0, 162.0, 158.0, 159.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 62.0, 63.0, 63.0, 65.0, 62.0, 61.0, 60.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1495.3000000000002, "alertThreshold": 2.0}]} 10:21:15 INFO - Return code: 0 10:21:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:21:15 ERROR - # TBPL SUCCESS # 10:21:15 INFO - Running post-action listener: _resource_record_post_action 10:21:15 INFO - ##### 10:21:15 INFO - ##### Finished run-tests step (success) 10:21:15 INFO - ##### 10:21:15 INFO - Running post-run listener: _resource_record_post_run 10:21:15 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 540688384; Read time: 16; Write time: 365508 10:21:15 INFO - TinderboxPrint: CPU usage
13.1% 10:21:15 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 10:21:15 INFO - TinderboxPrint: I/O write bytes / time
540,688,384 / 365,508 10:21:15 INFO - TinderboxPrint: CPU idle
8,259.9 (86.9%) 10:21:15 INFO - TinderboxPrint: CPU user
1,201.9 (12.6%) 10:21:15 INFO - TinderboxPrint: Swap in / out
0 / 0 10:21:15 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247398400; Read time: 0; Write time: 215256 10:21:15 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293289984; Read time: 16; Write time: 150252 10:21:16 INFO - Running post-run listener: _upload_blobber_files 10:21:16 INFO - Blob upload gear active. 10:21:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:21:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:21:16 INFO - (blobuploader) - INFO - Open directory for files ... 10:21:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:21:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:21:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:17 INFO - (blobuploader) - INFO - Done attempting. 10:21:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:21:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:21:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:22 INFO - (blobuploader) - INFO - Done attempting. 10:21:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:21:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:21:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:23 INFO - (blobuploader) - INFO - Done attempting. 10:21:23 INFO - (blobuploader) - INFO - Iteration through files over. 10:21:23 INFO - Return code: 0 10:21:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:21:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:21:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5adc93351fb0f6b384d928b598c3f4c76ab9f17345da6bda98e82cf3118e57c27e3e28997725bda47192c4b1484d411a3e4c0d0feac83e0cc1a904916f7bcddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a992485078d604a9eef8017be430758e8777031d0eff1c33f4acefd929e5726e0e76d768bfb6ebb37637d65c70561d6cf0d06424fb40f3f8271dfa4c7f7782e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429c09e36b8ce322bbf522224d1661d1de829ccab7c99ac8c2d3845eb46045cd27694c40d294c053c22993eea845450751bee7d9121d478f266f93648c010083"} 10:21:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:21:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:21:23 INFO - Contents: 10:21:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5adc93351fb0f6b384d928b598c3f4c76ab9f17345da6bda98e82cf3118e57c27e3e28997725bda47192c4b1484d411a3e4c0d0feac83e0cc1a904916f7bcddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a992485078d604a9eef8017be430758e8777031d0eff1c33f4acefd929e5726e0e76d768bfb6ebb37637d65c70561d6cf0d06424fb40f3f8271dfa4c7f7782e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429c09e36b8ce322bbf522224d1661d1de829ccab7c99ac8c2d3845eb46045cd27694c40d294c053c22993eea845450751bee7d9121d478f266f93648c010083"} 10:21:23 INFO - Running post-run listener: copy_logs_to_upload_dir 10:21:23 INFO - Copying logs to upload dir... 10:21:23 INFO - mkdir: /builds/slave/test/build/upload/logs 10:21:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.142163 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-05-28 10:21:22.996253) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 10:21:22.999865) ========= 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=00204705a9daa57ed339a427000001b9-1464451546.592070-541981297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5adc93351fb0f6b384d928b598c3f4c76ab9f17345da6bda98e82cf3118e57c27e3e28997725bda47192c4b1484d411a3e4c0d0feac83e0cc1a904916f7bcddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a992485078d604a9eef8017be430758e8777031d0eff1c33f4acefd929e5726e0e76d768bfb6ebb37637d65c70561d6cf0d06424fb40f3f8271dfa4c7f7782e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429c09e36b8ce322bbf522224d1661d1de829ccab7c99ac8c2d3845eb46045cd27694c40d294c053c22993eea845450751bee7d9121d478f266f93648c010083"} build_url:https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011219 build_url: 'https://queue.taskcluster.net/v1/task/U-ntC1eJRoKvQVol3jHrDg/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/Firefox/sha512/5adc93351fb0f6b384d928b598c3f4c76ab9f17345da6bda98e82cf3118e57c27e3e28997725bda47192c4b1484d411a3e4c0d0feac83e0cc1a904916f7bcddb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a992485078d604a9eef8017be430758e8777031d0eff1c33f4acefd929e5726e0e76d768bfb6ebb37637d65c70561d6cf0d06424fb40f3f8271dfa4c7f7782e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/429c09e36b8ce322bbf522224d1661d1de829ccab7c99ac8c2d3845eb46045cd27694c40d294c053c22993eea845450751bee7d9121d478f266f93648c010083"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 10:21:23.026596) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:21:23.026951) ========= 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=00204705a9daa57ed339a427000001b9-1464451546.592070-541981297 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004504 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 10:21:23.108525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 10:21:23.108874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 10:21:23.109184) ========= ========= Total master_lag: 0.20 =========