builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1464401312.9 results: success (0) buildid: 20160527163016 builduid: e3a90a9ab7a94d4687c08932281d39ba revision: ea15028498ed95677844fb7f30be5efcaf8b2621 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:32.899932) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:32.900331) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:32.900627) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:32.917833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:32.918151) ========= 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=62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 19:08: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% 103M=0s 2016-05-27 19:08:33 (103 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.392767 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:33.328219) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:33.328511) ========= 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=62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016846 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:33.378685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:33.378988) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ea15028498ed95677844fb7f30be5efcaf8b2621 --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 ea15028498ed95677844fb7f30be5efcaf8b2621 --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=62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 19:08:33,603 truncating revision to first 12 chars 2016-05-27 19:08:33,603 Setting DEBUG logging. 2016-05-27 19:08:33,603 attempt 1/10 2016-05-27 19:08:33,603 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ea15028498ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 19:08:33,883 unpacking tar archive at: mozilla-central-ea15028498ed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.683203 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:34.075339) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:34.075668) ========= script_repo_revision: ea15028498ed95677844fb7f30be5efcaf8b2621 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:34.076059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:34.076333) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 19:08:34.086674) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-27 19:08:34.086954) ========= /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=62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:08:34 INFO - MultiFileLogger online at 20160527 19:08:34 in /builds/slave/test 19:08: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 19:08:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:08:34 INFO - {'append_to_log': False, 19:08:34 INFO - 'base_work_dir': '/builds/slave/test', 19:08:34 INFO - 'blob_upload_branch': 'Firefox', 19:08:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:08:34 INFO - 'branch': 'Firefox', 19:08:34 INFO - 'buildbot_json_path': 'buildprops.json', 19:08:34 INFO - 'config_files': ('talos/linux_config.py',), 19:08:34 INFO - 'default_actions': ('clobber', 19:08:34 INFO - 'read-buildbot-config', 19:08:34 INFO - 'download-and-extract', 19:08:34 INFO - 'populate-webroot', 19:08:34 INFO - 'create-virtualenv', 19:08:34 INFO - 'install', 19:08:34 INFO - 'run-tests'), 19:08:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:08:34 INFO - 'download_minidump_stackwalk': True, 19:08:34 INFO - 'download_symbols': 'ondemand', 19:08:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:08:34 INFO - 'tooltool.py': '/tools/tooltool.py', 19:08:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:08:34 INFO - '/tools/misc-python/virtualenv.py')}, 19:08:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:08:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:08:34 INFO - 'installer_path': 'installer.exe', 19:08:34 INFO - 'log_level': 'info', 19:08:34 INFO - 'log_name': 'talos', 19:08:34 INFO - 'log_to_console': True, 19:08:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:08:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:08:34 INFO - 'opt_config_files': (), 19:08:34 INFO - 'pip_index': False, 19:08:34 INFO - 'sps_profile': False, 19:08:34 INFO - 'sps_profile_interval': 0, 19:08:34 INFO - 'suite': 'dromaeojs', 19:08:34 INFO - 'system_bits': '32', 19:08:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:08:34 INFO - 'title': 'talos-linux64-ix-018', 19:08:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:08:34 INFO - 'use_talos_json': True, 19:08:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:08:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:08:34 INFO - 'work_dir': 'build'} 19:08:34 INFO - ##### 19:08:34 INFO - ##### Running clobber step. 19:08:34 INFO - ##### 19:08:34 INFO - Running pre-action listener: _resource_record_pre_action 19:08:34 INFO - Running main action method: clobber 19:08:34 INFO - rmtree: /builds/slave/test/build 19:08:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:08:34 INFO - Running post-action listener: _resource_record_post_action 19:08:34 INFO - ##### 19:08:34 INFO - ##### Finished clobber step (success) 19:08:34 INFO - ##### 19:08:34 INFO - ##### 19:08:34 INFO - ##### Running read-buildbot-config step. 19:08:34 INFO - ##### 19:08:34 INFO - Running pre-action listener: _resource_record_pre_action 19:08:34 INFO - Running main action method: read_buildbot_config 19:08:34 INFO - Using buildbot properties: 19:08:34 INFO - { 19:08:34 INFO - "project": "", 19:08:34 INFO - "product": "firefox", 19:08:34 INFO - "script_repo_revision": "production", 19:08:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:08:34 INFO - "repository": "", 19:08:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:08:34 INFO - "buildid": "20160527163016", 19:08:34 INFO - "pgo_build": "True", 19:08:34 INFO - "basedir": "/builds/slave/test", 19:08:34 INFO - "buildnumber": 459, 19:08:34 INFO - "slavename": "talos-linux64-ix-018", 19:08:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:08:34 INFO - "platform": "ubuntu64_hw", 19:08:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:08:34 INFO - "branch": "mozilla-central", 19:08:34 INFO - "repo_path": "mozilla-central", 19:08:34 INFO - "revision": "ea15028498ed95677844fb7f30be5efcaf8b2621", 19:08:34 INFO - "stage_platform": "linux64-pgo", 19:08:34 INFO - "builduid": "e3a90a9ab7a94d4687c08932281d39ba", 19:08:34 INFO - "slavebuilddir": "test" 19:08:34 INFO - } 19:08:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:08:34 INFO - Running post-action listener: _resource_record_post_action 19:08:34 INFO - ##### 19:08:34 INFO - ##### Finished read-buildbot-config step (success) 19:08:34 INFO - ##### 19:08:34 INFO - ##### 19:08:34 INFO - ##### Running download-and-extract step. 19:08:34 INFO - ##### 19:08:34 INFO - Running pre-action listener: _resource_record_pre_action 19:08:34 INFO - Running main action method: download_and_extract 19:08:34 INFO - mkdir: /builds/slave/test/build/tests 19:08: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')]} 19:08:34 INFO - https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:08:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:08:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:08:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:44 INFO - Downloaded 1361 bytes. 19:08:44 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:08:44 INFO - Using the following test package requirements: 19:08:44 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:08:44 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:08:44 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:08:44 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'jsshell-linux-x86_64.zip'], 19:08:44 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:08:44 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:08:44 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:08:44 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:08:44 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:08:44 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:08:44 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:08:44 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:44 INFO - https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:08:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:51 INFO - Downloaded 24410694 bytes. 19:08:51 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'] 19:08:51 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 19:08:53 INFO - Return code: 0 19:08:53 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 19:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:53 INFO - https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:08:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:08:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:54 INFO - Downloaded 24410694 bytes. 19:08:54 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'] 19:08:54 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 19:08:55 INFO - Return code: 0 19:08: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')]} 19:08:55 INFO - https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:08:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:08:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:08:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:56 INFO - Downloaded 10834034 bytes. 19:08:56 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'] 19:08:56 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 19:08:57 INFO - Return code: 0 19:08: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')]} 19:08:57 INFO - https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:08:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OSctqcqIQAG94_36ePbnPw/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 19:08:58 INFO - Downloaded 58743615 bytes. 19:08:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:58 INFO - mkdir: /builds/slave/test/properties 19:08:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:08:58 INFO - Writing to file /builds/slave/test/properties/build_url 19:08:58 INFO - Contents: 19:08:58 INFO - build_url:https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:08:59 INFO - Running post-action listener: _resource_record_post_action 19:08:59 INFO - Running post-action listener: set_extra_try_arguments 19:08:59 INFO - ##### 19:08:59 INFO - ##### Finished download-and-extract step (success) 19:08:59 INFO - ##### 19:08:59 INFO - ##### 19:08:59 INFO - ##### Running populate-webroot step. 19:08:59 INFO - ##### 19:08:59 INFO - Running pre-action listener: _resource_record_pre_action 19:08:59 INFO - Running main action method: populate_webroot 19:08:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:08:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:08:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:08:59 INFO - '--tppagecycles', 19:08:59 INFO - '7'], 19:08:59 INFO - 'tests': ['tsvgm']}}, 19:08:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:08:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:08:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:08:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:08:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['damp', 'tps']}, 19:08:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'tests': ['damp', 'tps']}, 19:08:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['dromaeo_dom']}, 19:08:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:08:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['a11yr', 19:08:59 INFO - 'ts_paint', 19:08:59 INFO - 'tpaint', 19:08:59 INFO - 'sessionrestore', 19:08:59 INFO - 'sessionrestore_no_auto_restore', 19:08:59 INFO - 'tabpaint']}, 19:08:59 INFO - 'other-e10s': {'tests': ['a11yr', 19:08:59 INFO - 'ts_paint', 19:08:59 INFO - 'tpaint', 19:08:59 INFO - 'sessionrestore', 19:08:59 INFO - 'sessionrestore_no_auto_restore', 19:08:59 INFO - 'tabpaint']}, 19:08:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:08:59 INFO - 'ts_paint', 19:08:59 INFO - 'tpaint', 19:08:59 INFO - 'sessionrestore', 19:08:59 INFO - 'sessionrestore_no_auto_restore', 19:08:59 INFO - 'tabpaint']}, 19:08:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:08:59 INFO - 'ts_paint', 19:08:59 INFO - 'tpaint', 19:08:59 INFO - 'sessionrestore', 19:08:59 INFO - 'sessionrestore_no_auto_restore', 19:08:59 INFO - 'tabpaint']}, 19:08:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['a11yr', 19:08:59 INFO - 'ts_paint', 19:08:59 INFO - 'tpaint', 19:08:59 INFO - 'sessionrestore', 19:08:59 INFO - 'sessionrestore_no_auto_restore', 19:08:59 INFO - 'tabpaint']}, 19:08:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['a11yr', 19:08:59 INFO - 'ts_paint', 19:08:59 INFO - 'tpaint', 19:08:59 INFO - 'sessionrestore', 19:08:59 INFO - 'sessionrestore_no_auto_restore', 19:08:59 INFO - 'tabpaint']}, 19:08:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['tsvgx', 19:08:59 INFO - 'tsvgr_opacity', 19:08:59 INFO - 'tart', 19:08:59 INFO - 'tscrollx', 19:08:59 INFO - 'cart']}, 19:08:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:08:59 INFO - 'tsvgr_opacity', 19:08:59 INFO - 'tart', 19:08:59 INFO - 'tscrollx', 19:08:59 INFO - 'cart']}, 19:08:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'talos_options': ['--disable-e10s'], 19:08:59 INFO - 'tests': ['tp5o']}, 19:08:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'tests': ['tp5o']}, 19:08:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'talos_options': ['--disable-e10s', 19:08:59 INFO - '--xperf_path', 19:08:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:59 INFO - 'tests': ['tp5n']}, 19:08:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:59 INFO - 'talos_options': ['--xperf_path', 19:08:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:59 INFO - 'tests': ['tp5n']}}, 19:08:59 INFO - 'talos.zip': {'path': '', 19:08:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:08:59 INFO - Running post-action listener: _resource_record_post_action 19:08:59 INFO - ##### 19:08:59 INFO - ##### Finished populate-webroot step (success) 19:08:59 INFO - ##### 19:08:59 INFO - ##### 19:08:59 INFO - ##### Running create-virtualenv step. 19:08:59 INFO - ##### 19:08:59 INFO - Running pre-action listener: _resource_record_pre_action 19:08:59 INFO - Running main action method: create_virtualenv 19:08:59 INFO - Creating virtualenv /builds/slave/test/build/venv 19:08:59 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 19:08:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:08:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:08:59 INFO - Using real prefix '/usr' 19:08:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:08:59 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:01 INFO - Installing pip.................done. 19:09:01 INFO - Return code: 0 19:09:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:01 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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09:01 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 19:09:01 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 19:09:01 INFO - Using env: {'DISPLAY': ':0', 19:09:01 INFO - 'HOME': '/home/cltbld', 19:09:01 INFO - 'LANG': 'en_US.UTF-8', 19:09:01 INFO - 'LANGUAGE': 'en_US:en', 19:09:01 INFO - 'LOGNAME': 'cltbld', 19:09:01 INFO - 'MAIL': '/var/mail/cltbld', 19:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:01 INFO - 'MOZ_NO_REMOTE': '1', 19:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:01 INFO - 'NO_EM_RESTART': '1', 19:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:01 INFO - 'PWD': '/builds/slave/test', 19:09:01 INFO - 'SHELL': '/bin/bash', 19:09:01 INFO - 'SHLVL': '1', 19:09:01 INFO - 'TERM': 'linux', 19:09:01 INFO - 'TMOUT': '86400', 19:09:01 INFO - 'USER': 'cltbld', 19:09:01 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:01 INFO - '_': '/tools/buildbot/bin/python'} 19:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:01 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 19:09:01 INFO - Cleaning up... 19:09:02 INFO - Return code: 0 19:09:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09: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 19:09: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 19:09:02 INFO - Using env: {'DISPLAY': ':0', 19:09:02 INFO - 'HOME': '/home/cltbld', 19:09:02 INFO - 'LANG': 'en_US.UTF-8', 19:09:02 INFO - 'LANGUAGE': 'en_US:en', 19:09:02 INFO - 'LOGNAME': 'cltbld', 19:09:02 INFO - 'MAIL': '/var/mail/cltbld', 19:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:02 INFO - 'MOZ_NO_REMOTE': '1', 19:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:02 INFO - 'NO_EM_RESTART': '1', 19:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:02 INFO - 'PWD': '/builds/slave/test', 19:09:02 INFO - 'SHELL': '/bin/bash', 19:09:02 INFO - 'SHLVL': '1', 19:09:02 INFO - 'TERM': 'linux', 19:09:02 INFO - 'TMOUT': '86400', 19:09:02 INFO - 'USER': 'cltbld', 19:09:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:02 INFO - '_': '/tools/buildbot/bin/python'} 19:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:02 INFO - Downloading/unpacking psutil>=3.1.1 19:09:02 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 19:09:02 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 19:09:02 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 19:09:02 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 19:09:02 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 19:09:02 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 19:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:04 INFO - Installing collected packages: psutil 19:09:04 INFO - Running setup.py install for psutil 19:09:04 INFO - building 'psutil._psutil_linux' extension 19:09:04 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 19:09:04 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 19:09:04 INFO - building 'psutil._psutil_posix' extension 19:09:04 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 19:09:04 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 19:09:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:04 INFO - Successfully installed psutil 19:09:04 INFO - Cleaning up... 19:09:04 INFO - Return code: 0 19:09:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:04 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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09:04 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 19:09:04 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 19:09:04 INFO - Using env: {'DISPLAY': ':0', 19:09:04 INFO - 'HOME': '/home/cltbld', 19:09:04 INFO - 'LANG': 'en_US.UTF-8', 19:09:04 INFO - 'LANGUAGE': 'en_US:en', 19:09:04 INFO - 'LOGNAME': 'cltbld', 19:09:04 INFO - 'MAIL': '/var/mail/cltbld', 19:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:04 INFO - 'MOZ_NO_REMOTE': '1', 19:09:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:04 INFO - 'NO_EM_RESTART': '1', 19:09:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:04 INFO - 'PWD': '/builds/slave/test', 19:09:04 INFO - 'SHELL': '/bin/bash', 19:09:04 INFO - 'SHLVL': '1', 19:09:04 INFO - 'TERM': 'linux', 19:09:04 INFO - 'TMOUT': '86400', 19:09:04 INFO - 'USER': 'cltbld', 19:09:04 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:04 INFO - '_': '/tools/buildbot/bin/python'} 19:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:09:06 INFO - Installing collected packages: mozsystemmonitor 19:09:06 INFO - Running setup.py install for mozsystemmonitor 19:09:07 INFO - Successfully installed mozsystemmonitor 19:09:07 INFO - Cleaning up... 19:09:07 INFO - Return code: 0 19:09:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09: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 19:09: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 19:09:07 INFO - Using env: {'DISPLAY': ':0', 19:09:07 INFO - 'HOME': '/home/cltbld', 19:09:07 INFO - 'LANG': 'en_US.UTF-8', 19:09:07 INFO - 'LANGUAGE': 'en_US:en', 19:09:07 INFO - 'LOGNAME': 'cltbld', 19:09:07 INFO - 'MAIL': '/var/mail/cltbld', 19:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:07 INFO - 'MOZ_NO_REMOTE': '1', 19:09:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:07 INFO - 'NO_EM_RESTART': '1', 19:09:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:07 INFO - 'PWD': '/builds/slave/test', 19:09:07 INFO - 'SHELL': '/bin/bash', 19:09:07 INFO - 'SHLVL': '1', 19:09:07 INFO - 'TERM': 'linux', 19:09:07 INFO - 'TMOUT': '86400', 19:09:07 INFO - 'USER': 'cltbld', 19:09:07 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:07 INFO - '_': '/tools/buildbot/bin/python'} 19:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:07 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:09 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:09 INFO - Downloading docopt-0.6.1.tar.gz 19:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:09 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:09 INFO - Running setup.py install for blobuploader 19:09:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:09 INFO - Running setup.py install for requests 19:09:10 INFO - Running setup.py install for docopt 19:09:10 INFO - Successfully installed blobuploader requests docopt 19:09:10 INFO - Cleaning up... 19:09:10 INFO - Return code: 0 19:09:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:10 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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09:10 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 19:09:10 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 19:09:10 INFO - Using env: {'DISPLAY': ':0', 19:09:10 INFO - 'HOME': '/home/cltbld', 19:09:10 INFO - 'LANG': 'en_US.UTF-8', 19:09:10 INFO - 'LANGUAGE': 'en_US:en', 19:09:10 INFO - 'LOGNAME': 'cltbld', 19:09:10 INFO - 'MAIL': '/var/mail/cltbld', 19:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:10 INFO - 'MOZ_NO_REMOTE': '1', 19:09:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:10 INFO - 'NO_EM_RESTART': '1', 19:09:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:10 INFO - 'PWD': '/builds/slave/test', 19:09:10 INFO - 'SHELL': '/bin/bash', 19:09:10 INFO - 'SHLVL': '1', 19:09:10 INFO - 'TERM': 'linux', 19:09:10 INFO - 'TMOUT': '86400', 19:09:10 INFO - 'USER': 'cltbld', 19:09:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:10 INFO - '_': '/tools/buildbot/bin/python'} 19:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:10 INFO - Running setup.py (path:/tmp/pip-rB1FbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:10 INFO - Running setup.py (path:/tmp/pip-KU60rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:10 INFO - Running setup.py (path:/tmp/pip-qeMW9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:10 INFO - Running setup.py (path:/tmp/pip-CqfCxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:10 INFO - Running setup.py (path:/tmp/pip-8f7omH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:11 INFO - Running setup.py (path:/tmp/pip-qVxID9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:11 INFO - Running setup.py (path:/tmp/pip-DwZK_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:11 INFO - Running setup.py (path:/tmp/pip-Ah1H6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:11 INFO - Running setup.py (path:/tmp/pip-BLIg7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:11 INFO - Running setup.py (path:/tmp/pip-yDFoQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:11 INFO - Running setup.py (path:/tmp/pip-F_2ICY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:11 INFO - Running setup.py (path:/tmp/pip-Qm5L1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:11 INFO - Running setup.py (path:/tmp/pip-fWbSyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:11 INFO - Running setup.py (path:/tmp/pip-GQ4Avu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:11 INFO - Running setup.py (path:/tmp/pip-XRFKX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:11 INFO - Running setup.py (path:/tmp/pip-5xiW7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:11 INFO - Running setup.py (path:/tmp/pip-3ND8Tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:11 INFO - Running setup.py install for manifestparser 19:09:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Running setup.py install for mozcrash 19:09:12 INFO - Running setup.py install for mozdebug 19:09:12 INFO - Running setup.py install for mozdevice 19:09:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Running setup.py install for mozfile 19:09:12 INFO - Running setup.py install for mozhttpd 19:09:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Running setup.py install for mozinfo 19:09:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Running setup.py install for mozInstall 19:09:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Running setup.py install for mozleak 19:09:12 INFO - Running setup.py install for mozlog 19:09:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:13 INFO - Running setup.py install for moznetwork 19:09:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:13 INFO - Running setup.py install for mozprocess 19:09:13 INFO - Running setup.py install for mozprofile 19:09:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:13 INFO - Running setup.py install for mozrunner 19:09:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:13 INFO - Running setup.py install for mozscreenshot 19:09:13 INFO - Running setup.py install for moztest 19:09:13 INFO - Running setup.py install for mozversion 19:09:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:13 INFO - Cleaning up... 19:09:13 INFO - Return code: 0 19:09:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:13 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')]} 19:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:13 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')]} 19:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:13 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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09:13 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 19:09:13 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 19:09:13 INFO - Using env: {'DISPLAY': ':0', 19:09:13 INFO - 'HOME': '/home/cltbld', 19:09:13 INFO - 'LANG': 'en_US.UTF-8', 19:09:13 INFO - 'LANGUAGE': 'en_US:en', 19:09:13 INFO - 'LOGNAME': 'cltbld', 19:09:13 INFO - 'MAIL': '/var/mail/cltbld', 19:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:13 INFO - 'MOZ_NO_REMOTE': '1', 19:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:13 INFO - 'NO_EM_RESTART': '1', 19:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:13 INFO - 'PWD': '/builds/slave/test', 19:09:13 INFO - 'SHELL': '/bin/bash', 19:09:13 INFO - 'SHLVL': '1', 19:09:13 INFO - 'TERM': 'linux', 19:09:13 INFO - 'TMOUT': '86400', 19:09:13 INFO - 'USER': 'cltbld', 19:09:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:13 INFO - '_': '/tools/buildbot/bin/python'} 19:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:13 INFO - Running setup.py (path:/tmp/pip-YmaaQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09: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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:14 INFO - Running setup.py (path:/tmp/pip-I7JR4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:14 INFO - Running setup.py (path:/tmp/pip-Uig8Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:14 INFO - Running setup.py (path:/tmp/pip-K40H0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:14 INFO - Running setup.py (path:/tmp/pip-k9BBcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:14 INFO - Running setup.py (path:/tmp/pip-KuwK6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:14 INFO - Running setup.py (path:/tmp/pip-XxrOR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:14 INFO - Running setup.py (path:/tmp/pip-KK0qWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:14 INFO - Running setup.py (path:/tmp/pip-pVbkcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:14 INFO - Running setup.py (path:/tmp/pip-v2FNpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:14 INFO - Running setup.py (path:/tmp/pip-svAh80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:14 INFO - Running setup.py (path:/tmp/pip-LOqTeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:14 INFO - Running setup.py (path:/tmp/pip-VHAVIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:14 INFO - Running setup.py (path:/tmp/pip-09Qdz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:14 INFO - Running setup.py (path:/tmp/pip-55tB5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:15 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)) 19:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:15 INFO - Running setup.py (path:/tmp/pip-ql4SoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:15 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)) 19:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:15 INFO - Running setup.py (path:/tmp/pip-nrO8Dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:15 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)) 19:09:15 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)) 19:09:15 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)) 19:09:15 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)) 19:09:15 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)) 19:09:15 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)) 19:09:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:15 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 19:09:15 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 19:09:15 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 19:09:15 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 19:09:15 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 19:09:15 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 19:09:17 INFO - Downloading blessings-1.6.tar.gz 19:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:17 INFO - Installing collected packages: blessings 19:09:17 INFO - Running setup.py install for blessings 19:09:17 INFO - Successfully installed blessings 19:09:17 INFO - Cleaning up... 19:09:17 INFO - Return code: 0 19:09:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:17 INFO - Reading from file tmpfile_stdout 19:09:17 INFO - Current package versions: 19:09:17 INFO - argparse == 1.2.1 19:09:17 INFO - blessings == 1.6 19:09:17 INFO - blobuploader == 1.2.4 19:09:17 INFO - docopt == 0.6.1 19:09:17 INFO - manifestparser == 1.1 19:09:17 INFO - mozInstall == 1.12 19:09:17 INFO - mozcrash == 0.17 19:09:17 INFO - mozdebug == 0.1 19:09:17 INFO - mozdevice == 0.48 19:09:17 INFO - mozfile == 1.2 19:09:17 INFO - mozhttpd == 0.7 19:09:17 INFO - mozinfo == 0.9 19:09:17 INFO - mozleak == 0.1 19:09:17 INFO - mozlog == 3.2 19:09:17 INFO - moznetwork == 0.27 19:09:17 INFO - mozprocess == 0.22 19:09:17 INFO - mozprofile == 0.28 19:09:17 INFO - mozrunner == 6.11 19:09:17 INFO - mozscreenshot == 0.1 19:09:17 INFO - mozsystemmonitor == 0.3 19:09:17 INFO - moztest == 0.7 19:09:17 INFO - mozversion == 1.4 19:09:17 INFO - psutil == 3.1.1 19:09:17 INFO - requests == 1.2.3 19:09:17 INFO - wsgiref == 0.1.2 19:09:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:17 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')]} 19:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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')]} 19:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09:17 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 19:09:17 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 19:09:17 INFO - Using env: {'DISPLAY': ':0', 19:09:17 INFO - 'HOME': '/home/cltbld', 19:09:17 INFO - 'LANG': 'en_US.UTF-8', 19:09:17 INFO - 'LANGUAGE': 'en_US:en', 19:09:17 INFO - 'LOGNAME': 'cltbld', 19:09:17 INFO - 'MAIL': '/var/mail/cltbld', 19:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:17 INFO - 'MOZ_NO_REMOTE': '1', 19:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:17 INFO - 'NO_EM_RESTART': '1', 19:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:17 INFO - 'PWD': '/builds/slave/test', 19:09:17 INFO - 'SHELL': '/bin/bash', 19:09:17 INFO - 'SHLVL': '1', 19:09:17 INFO - 'TERM': 'linux', 19:09:17 INFO - 'TMOUT': '86400', 19:09:17 INFO - 'USER': 'cltbld', 19:09:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:17 INFO - '_': '/tools/buildbot/bin/python'} 19:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 INFO - Cleaning up... 19:09:17 INFO - Return code: 0 19:09:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:17 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')]} 19:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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')]} 19:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09:17 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 19:09:17 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 19:09:17 INFO - Using env: {'DISPLAY': ':0', 19:09:17 INFO - 'HOME': '/home/cltbld', 19:09:17 INFO - 'LANG': 'en_US.UTF-8', 19:09:17 INFO - 'LANGUAGE': 'en_US:en', 19:09:17 INFO - 'LOGNAME': 'cltbld', 19:09:17 INFO - 'MAIL': '/var/mail/cltbld', 19:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:17 INFO - 'MOZ_NO_REMOTE': '1', 19:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:17 INFO - 'NO_EM_RESTART': '1', 19:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:17 INFO - 'PWD': '/builds/slave/test', 19:09:17 INFO - 'SHELL': '/bin/bash', 19:09:17 INFO - 'SHLVL': '1', 19:09:17 INFO - 'TERM': 'linux', 19:09:17 INFO - 'TMOUT': '86400', 19:09:17 INFO - 'USER': 'cltbld', 19:09:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:17 INFO - '_': '/tools/buildbot/bin/python'} 19:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:17 INFO - Downloading/unpacking jsonschema 19:09:17 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 19:09:17 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 19:09:17 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 19:09:17 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 19:09:17 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 19:09:17 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 19:09:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:19 INFO - Downloading/unpacking functools32 (from jsonschema) 19:09:19 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 19:09:19 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 19:09:19 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 19:09:19 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 19:09:19 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 19:09:19 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 19:09:20 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:20 INFO - warning: no files found matching '*.txt' 19:09:20 INFO - no previously-included directories found matching 'build' 19:09:20 INFO - no previously-included directories found matching 'dist' 19:09:20 INFO - no previously-included directories found matching '.git*' 19:09:20 INFO - Installing collected packages: jsonschema, functools32 19:09:20 INFO - Running setup.py install for functools32 19:09:20 INFO - warning: no files found matching '*.txt' 19:09:20 INFO - no previously-included directories found matching 'build' 19:09:20 INFO - no previously-included directories found matching 'dist' 19:09:20 INFO - no previously-included directories found matching '.git*' 19:09:20 INFO - Successfully installed jsonschema functools32 19:09:20 INFO - Cleaning up... 19:09:20 INFO - Return code: 0 19:09:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:09:20 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')]} 19:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:20 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')]} 19:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:20 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 0x7f775e6d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x161e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, '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': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', '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 19:09:20 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 19:09:20 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 19:09:20 INFO - Using env: {'DISPLAY': ':0', 19:09:20 INFO - 'HOME': '/home/cltbld', 19:09:20 INFO - 'LANG': 'en_US.UTF-8', 19:09:20 INFO - 'LANGUAGE': 'en_US:en', 19:09:20 INFO - 'LOGNAME': 'cltbld', 19:09:20 INFO - 'MAIL': '/var/mail/cltbld', 19:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:20 INFO - 'MOZ_NO_REMOTE': '1', 19:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:20 INFO - 'NO_EM_RESTART': '1', 19:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:20 INFO - 'PWD': '/builds/slave/test', 19:09:20 INFO - 'SHELL': '/bin/bash', 19:09:20 INFO - 'SHLVL': '1', 19:09:20 INFO - 'TERM': 'linux', 19:09:20 INFO - 'TMOUT': '86400', 19:09:20 INFO - 'USER': 'cltbld', 19:09:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:20 INFO - '_': '/tools/buildbot/bin/python'} 19:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:20 INFO - Downloading/unpacking flake8 19:09: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 19:09: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 19:09: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 19:09: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 19:09: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 19:09: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 19:09:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:09:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:09:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:22 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 19:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:09:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:23 INFO - no previously-included directories found matching 'docs/_build' 19:09:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:09:23 INFO - Running setup.py install for pep8 19:09:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:23 INFO - no previously-included directories found matching 'docs/_build' 19:09:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:09:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:09:23 INFO - Cleaning up... 19:09:23 INFO - Return code: 0 19:09:23 INFO - Running post-action listener: _resource_record_post_action 19:09:23 INFO - Running post-action listener: _start_resource_monitoring 19:09:23 INFO - Starting resource monitoring. 19:09:23 INFO - ##### 19:09:23 INFO - ##### Finished create-virtualenv step (success) 19:09:23 INFO - ##### 19:09:23 INFO - ##### 19:09:23 INFO - ##### Running install step. 19:09:23 INFO - ##### 19:09:23 INFO - Running pre-action listener: _resource_record_pre_action 19:09:23 INFO - Running main action method: install 19:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:23 INFO - Reading from file tmpfile_stdout 19:09:23 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:23 INFO - Reading from file tmpfile_stdout 19:09:23 INFO - Output received: 19:09:23 INFO - Usage: mozinstall [options] installer 19:09:23 INFO - Options: 19:09:23 INFO - -h, --help show this help message and exit 19:09:23 INFO - -d DEST, --destination=DEST 19:09:23 INFO - Directory to install application into. [default: 19:09:23 INFO - "/builds/slave/test"] 19:09:23 INFO - --app=APP Application being installed. [default: firefox] 19:09:23 INFO - mkdir: /builds/slave/test/build/application 19:09:23 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'] 19:09:23 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 19:09:40 INFO - Reading from file tmpfile_stdout 19:09:40 INFO - Output received: 19:09:40 INFO - /builds/slave/test/build/application/firefox/firefox 19:09:40 INFO - Running post-action listener: _resource_record_post_action 19:09:40 INFO - ##### 19:09:40 INFO - ##### Finished install step (success) 19:09:40 INFO - ##### 19:09:40 INFO - ##### 19:09:40 INFO - ##### Running run-tests step. 19:09:40 INFO - ##### 19:09:40 INFO - Running pre-action listener: _resource_record_pre_action 19:09:40 INFO - Running main action method: run_tests 19:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:09:40 INFO - Python 2.7.3 19:09:40 INFO - Return code: 0 19:09:40 INFO - grabbing minidump binary from tooltool 19:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:40 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 0x17789c0>, '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 0x17798c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1779d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:09:40 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 19:09:40 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 19:09:40 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 19:09:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:09:40 INFO - Return code: 0 19:09:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:09:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:09:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:09:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:09:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:09:41 INFO - Return code: 0 19:09:41 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/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 19:09:41 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/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 19:09:41 INFO - Using env: {'DISPLAY': ':0', 19:09:41 INFO - 'HOME': '/home/cltbld', 19:09:41 INFO - 'LANG': 'en_US.UTF-8', 19:09:41 INFO - 'LANGUAGE': 'en_US:en', 19:09:41 INFO - 'LOGNAME': 'cltbld', 19:09:41 INFO - 'MAIL': '/var/mail/cltbld', 19:09:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:09:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:41 INFO - 'MOZ_NO_REMOTE': '1', 19:09:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:41 INFO - 'NO_EM_RESTART': '1', 19:09:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:41 INFO - 'PWD': '/builds/slave/test', 19:09:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:09:41 INFO - 'SHELL': '/bin/bash', 19:09:41 INFO - 'SHLVL': '1', 19:09:41 INFO - 'TERM': 'linux', 19:09:41 INFO - 'TMOUT': '86400', 19:09:41 INFO - 'USER': 'cltbld', 19:09:41 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832', 19:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:41 INFO - '_': '/tools/buildbot/bin/python'} 19:09:41 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/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 19:09:41 INFO - mozversion application_buildid: 20160527163016 19:09:41 INFO - mozversion application_changeset: ea15028498ed95677844fb7f30be5efcaf8b2621 19:09:41 INFO - mozversion application_display_name: Nightly 19:09:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:09:41 INFO - mozversion application_name: Firefox 19:09:41 INFO - mozversion application_remotingname: firefox 19:09:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:09:41 INFO - mozversion application_vendor: Mozilla 19:09:41 INFO - mozversion application_version: 49.0a1 19:09:41 INFO - mozversion platform_buildid: 20160527163016 19:09:41 INFO - mozversion platform_changeset: ea15028498ed95677844fb7f30be5efcaf8b2621 19:09:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:09:41 INFO - mozversion platform_version: 49.0a1 19:09:41 INFO - using testdate: 1464401381 19:09:41 INFO - actual date: 1464401381 19:09:41 INFO - starting webserver on 'localhost:35216' 19:09:41 INFO - SUITE-START | Running 2 tests 19:09:41 INFO - TEST-START | dromaeo_css 19:09:41 INFO - operating with platform_type : linux_ 19:09:41 INFO - Initialising browser for dromaeo_css test... 19:09:41 INFO - TEST-INFO | started process 8819 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQwGE6u/profile http://localhost:35216/getInfo.html) 19:09:45 INFO - PROCESS | 8819 | __metrics Screen width/height:1600/1200 19:09:45 INFO - PROCESS | 8819 | colorDepth:24 19:09:45 INFO - PROCESS | 8819 | Browser inner width/height: 1024/697 19:09:45 INFO - PROCESS | 8819 | __metrics 19:09:46 ERROR - PROCESS | 8819 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:09:47 INFO - TEST-INFO | 8819: exit 0 19:09:47 INFO - Browser initialized. 19:09:47 INFO - Running cycle 1/1 for dromaeo_css test... 19:09:47 INFO - TEST-INFO | started process 8904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQwGE6u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:59 INFO - PROCESS | 8904 | RSS: Main: 155684864 19:09:59 INFO - PROCESS | 8904 | 19:10:50 INFO - PROCESS | 8904 | 1464401450086 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] 19:12:57 INFO - PROCESS | 8904 | Cycle 1(1): loaded http://localhost:35216/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35216/tests/dromaeo/cssquery-ext.html) 19:12:58 INFO - PROCESS | 8904 | RSS: Main: 258551808 19:12:58 INFO - PROCESS | 8904 | 19:16:01 INFO - PROCESS | 8904 | Cycle 1(1): loaded http://localhost:35216/tests/dromaeo/cssquery-ext.html (next: http://localhost:35216/tests/dromaeo/cssquery-jquery.html) 19:16:01 INFO - PROCESS | 8904 | RSS: Main: 240345088 19:16:01 INFO - PROCESS | 8904 | 19:19:03 INFO - PROCESS | 8904 | Cycle 1(1): loaded http://localhost:35216/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35216/tests/dromaeo/cssquery-mootools.html) 19:19:04 INFO - PROCESS | 8904 | RSS: Main: 243462144 19:19:04 INFO - PROCESS | 8904 | 19:22:06 INFO - PROCESS | 8904 | Cycle 1(1): loaded http://localhost:35216/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35216/tests/dromaeo/cssquery-prototype.html) 19:22:06 INFO - PROCESS | 8904 | RSS: Main: 227917824 19:22:06 INFO - PROCESS | 8904 | 19:25:09 INFO - PROCESS | 8904 | Cycle 1(1): loaded http://localhost:35216/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35216/tests/dromaeo/cssquery-yui.html) 19:25:10 INFO - PROCESS | 8904 | RSS: Main: 213217280 19:25:10 INFO - PROCESS | 8904 | 19:28:12 INFO - PROCESS | 8904 | Cycle 1(1): loaded http://localhost:35216/tests/dromaeo/cssquery-yui.html (next: http://localhost:35216/tests/dromaeo/cssquery-dojo.html) 19:28:13 INFO - PROCESS | 8904 | RSS: Main: 258506752 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | __start_tp_report 19:28:13 INFO - PROCESS | 8904 | _x_x_mozilla_page_load 19:28:13 INFO - PROCESS | 8904 | _x_x_mozilla_page_load_details 19:28:13 INFO - PROCESS | 8904 | |i|pagename|runs| 19:28:13 INFO - PROCESS | 8904 | |0;dojo.html;3113.8287394193326;3123.9843800780977;3140.277736120692;3172.825494597799;3192.4253634345823;5367.1680889462095;5414.242006119146;5442.401335853053;5534.16987451882;5571.387147413786;5419.187121931712;5423.755930983105;5439.864003399917;5440.646357986738;5498.120300751881;4881.5118488151165;4949.505049495057;4951.455339912607;4967.304577359173;5150.948490515103;5044.117279476095;5067.721275329855;5071.695698258098;5116.027954688598;5124.692518448886;4855.732934688591;4901.558859702608;4908.533689299511;4950.455449900526;4998.2752500887345;4901.509849015106;4910.165271903785;4952.051956609625;4974.10466116099;4988.950110498921;4853.611711063107;4931.137051016094;4941.579965702884;4951.03454826309;4953.777080031371;5284.471552844685;5352.223927530486;5360.785568577252;5404.378496472917;5413.512783849433;5852.4440178183;5855.502282306009;5864.530837532987;5872.442117998783;5876.588638795286;5799.33307669615;5803.9709801451145;5897.174395584621;5909.970450147722;5912.4974377178;5483.588730845203;5579.023670857624;5583.637063590894;5585.329760428694;5586.022446071962;6034.696529355178;6044.040691173443;6074.848128796815;6092.39076092396;6095.085737139376;5634.577406694432;5680.7727690892725;5726.885462290731;5846.532277417881;5857.4435428634215;5845.09401042839;5857.062869940789;5901.498372638288;5913.526917846562;5915.822525324161;5788.3343415506915;5794.072948328248;5836.182934389189;5838.854028649318;5841.766329346779;5676.68778217202;5758.62568933018;5826.300843898759;5827.213326200994;5838.503727183237;4433.977830110829;4439.844605438794;4440.200763862471;4441.311596702451;4480.955190448119;3930.8231129598666;3942.0539070623418;3969.2656858481273;3994.320965435883;4025.013871601418;5444.891102177934;5494.478024587658;5523.917141242885;5559.416261292506;5618.073017952074;5899.469047785634;5915.467607915309;5937.851553711105;5943.64338139713;6009.849753756191;5360.142377219626;5575.749091290902;5604.299462567179;5616.550675945915;5619.662820230799;4943.8022479101155;4988.27669987857;4992.051510213048;4998.225275082368;5012.774425150877;6026.487748541423;6026.608270462406;6027.638341699424;6028.397160284024;6046.153538504613;5770.971145144247;5788.971055144697;5827.096799996008;5831.912521312099;5839.270091238595;5997.070454079625;6081.087836824512;6094.8171554853425;6121.84695382619;6133.662648554372;5705.201271821865;5751.1373294006235;5753.683547404933;5753.769849205984;5754.53963682896;5722.084466485342;5794.739236734273;5880.059190529495;5880.323762767336;5882.558808089496;5669.177969194526;5709.115087161496;5714.285714285715;5732.484076433141;5767.019606666793;5687.232223649919;5690.487856092972;5694.572907031907;5704.344000440003;5708.058170401837;5435.10320797064;5510.0632892409185;5547.805826796042;5583.218349431164;5587.050201465842;5732.34078081026;5758.2514273144225;5765.048766953406;5778.335491418541;5780.479756821906;5557.527610153183;5604.271444712162;5620.04459241938;5628.155776633538;5650.378458369658 19:28:13 INFO - PROCESS | 8904 | |1;ext.html;601.0983524712931;628.9559730818843;642.6465444005796;651.7849109793767;655.2530115668142;32615.184620384473;35746.8212658937;35858;35861.64138358615;35907.2818543629;43768.343474847825;44803.55196448034;44955;49205.095388553425;49234.26148607774;8692.652293908237;8879.422837515573;8887;8887.822243555158;8890.422122562046;8638.740837774845;8757.168069033432;8758.386912916098;8760.080191579887;8772.12278772121;8773.429727067753;8783.604737786814;8821.206091451768;8826.293896488265;8840.38117331787;8734.257588105018;8782.516961567111;8790.384673072887;8791.428557143798;8791.472511649321;8668.046514883357;8782.516961567175;8821.867671984925;8825.558722063872;8826.382153249275;42700.359494607605;42717.35923961143;43128.78435607833;43157.568424315985;43168.56831431677;3517.191046059421;3607.7113830893463;3706.0179052551093;4135.0902801383645;4184;32570.837145814356;32805.17987050319;33208.3358332832;33453.83273083643;33668.98993030188;4428.822847086113;4442;4484.686071974963;4519.4802597701355;4608.4930657627965;350046;351399;351584;354058;354238;58743;59029.40970590239;59125;59191.70404148038;59234.703826481455;36165.09587260339;37033.444498332545;37201.44197837035;38810;61913.38086619076;17484.388046418568;17599.20803563843;17650.558736031446;17686.823131768775;17689.734653980202;13234.139780914209;13358.265295408846;13370.532031378854;13373.665658358424;13379.331033448289;19928.800711992986;20157.395278141677;20259.79740202579;20277.49306267355;20352;7118.466115041289;7326.560406375635;7425.405967522585;7489.363404110712;7506.512076714996;1009.5305682857506;1044.5038606661926;1145.7765735681462;1147.1854982962022;1181.7931861924128;30955.95145262248;33309.66690333114;33347.33305333928;33479.163020924185;33716.32567348639;1129.3111202166672;1154.1805318223987;1155.6475275041025;1204.3015051270243;1236.3076677060874;17021.148942552823;17213.7417938731;17214.913925430294;17282.13589320529;17327.480175594752;564237;568066;568789;569219;570494;59421;60763;60866.391336086075;60940;61025;1266.1137203957267;1373.684052667892;1396.3018490754623;1411.696485255675;1416.0158689710759;32150.83924580362;34605.653943460246;34605.82697086498;34732.47901281432;34806;50675;51039.489605103474;51144;51344.74327628338;51367;434899;435472;435819;436455;436972;24568.877155614107;25115.874420628512;25219.495610088063;25357.74642253628;25384.492310154063;48360;48738.9436693337;48843.26735098905;48849.26726099182;49136;40959.38560921529;41219.58780412278;41275;42030;42641.14717705567;40944.795276023426;40953.79523102365;41042;41618.167636647704;41811.16377672369;40678.59321406748;40950.795246023576;42011.36982945197;42164;42165.367519487845;35809;35909;36792;36858.44712329264;37190.62809371872;28090.57864131999;28212.435751284447;28359.57460638051;28479.715202847707;28586.285342866595 19:28:13 INFO - PROCESS | 8904 | |2;jquery.html;384.4963098341173;384.6211481690536;390.3773481297331;396.69652715672515;411.5534644910272;14430.422783088663;15781.84218157818;17575.47273581793;19116.33092841751;19122.13950372233;17835.46493605194;19054.809451905476;19384.806151938476;20287.49281267969;20305.898470507625;6165.136880836686;6280.371962803706;6540.215174179105;6550.213974323064;6551.737930482775;4566.452025756914;4631.837885674002;6414.743410263583;6446.226452825644;6446.419822215999;7263.491555591111;7392.741254056109;7410.962945185266;7420.962895185544;7425.1832298447125;7174.139103307584;7306.432392814211;7352.411807055422;7367.410607151469;7370.299821516974;6791.5925044497;7046.1192350956135;7055.118110236221;7089.042979197846;7105.680244389015;25083.87458062716;25408.506113238458;25858.741412585823;25927.22218333453;26206.73793262062;2290.278562252902;2322.1408079010876;2407;2519.6976362836394;2562.6668533090765;14860.939800262608;15135.848641513554;15191.392344306214;15396.999195052285;15921.124338161397;2030.4213299209803;2166.7724888886755;2213.690083388327;2231.34175418252;2240.641497360431;80989.59505202436;91072.99999999933;97231;97402.51298743532;97442.51278743561;17492;17705.911470442566;17920.670312859507;18160.727589086175;18349.17428715711;28247;28720.85639571789;32673.346533069205;32688.182795429828;32723.836380817942;9961.601535938626;10154.289199756095;10187.592496300065;10315.948420257851;10544.525496352682;8417.326613870875;8593.054763975957;8602.741917742478;8645.092265312052;8843.159899809507;8881.955590222007;8910.198082172636;8965.148310910454;9187.908120918839;9254.398464099811;3468.2196505785996;3502.5446691929887;3505.789652620851;3604.711623070148;3634.7819130852236;1057.1701214546697;1061.543536279392;1065.63235683689;1083.6748975307535;1110.6834552152598;18718.71921921172;18918.90540547316;19238.422847314603;19278.71081933772;19395.709064364044;988.6440881282732;1024.8718910136233;1032.1381646325253;1034.886162522122;1134;10374.948125259325;10897.509612067317;10944.23390362683;10947.397893115955;11033.117350611932;452156;452917;488870;492577;496280;86776;89075.55462222647;94303.52848235852;94737.57893631601;96310.51844740871;964.2045312617118;1016.7778251260992;1075.0378411321824;1075.9294501970433;1077.380506208933;15930.283137259083;16210.189490525663;16701.2484438203;17050.914745426195;17338.653226935643;41754;42109;43051.56948430601;43458;44901.326480103475;56536.15195772149;57007.42992570021;57591.712041439525;58565.414345856;58861.705691472984;8427.705030323908;8460.111688272642;8676.652933882575;8861.379703420698;8929.955350223207;9549.952250238704;12882;13423;13470.057096003186;13579.388927498483;11532.365719885487;11761.588344407905;11855;12132.75734485323;12168.695782605506;11550.82673759911;11658.825117623073;11765.11761617865;11821.70445738829;12072.818907716566;11094.167937404889;11282.43587820622;11777.587784427504;12185.817212741993;12305.138640295094;8179.345652347917;8231;8260.215279548433;8381.203785640355;8488.660453581788;10833.512491937725;11462.94268528652;11564.421778910853;11901.642950711492;12058.638240852788 19:28:13 INFO - PROCESS | 8904 | |3;mootools.html;1321.5242512695422;1490.932908019139;1520.97718534222;1674.6232097778002;1818.6362727454512;8076.39427042972;8443.11347308533;8461.407701460908;8670.479771213715;8678.869816952734;8385.580720963942;8579.52812595307;8605.139486051392;8614.35392345575;8615.483071015728;2303.09027933966;2326.9883650581723;2330.9417264568438;2331.2423462374713;2338.7427382987858;2276.9203077892275;2350.6709060731428;2352.9529409411803;2355.634876594122;2361.9291421257303;2329.5108027314286;2330.650402439631;2357.35172827473;2358.41039740065;2361.468669549346;2190.518086021073;2207.7129973103556;2209.4034610655112;2212.214663794362;2227.4208705736464;2186.0599942024924;2189.9343019709436;2190.8466407351493;2191.5726433345503;2192.232718548511;8196.713115040944;8372.079071302152;8425.452345597578;8431.40980131391;8439.071702112824;5098.59211263098;5208.036513245024;5212.113940630102;5220.791168353261;5221.086309895753;3333.3833240850277;3352.3630510202984;3391.6777906098882;3459.705924996378;3459.8616055357757;1123.0566324287638;1135.2167004766684;1137.1187329819375;1202.927824330543;1220.267839296424;11653.475593598308;11733.882661173364;11739.47172377245;11743.177977541576;11762.176647634755;4412.9117417652105;4481.596656300949;4549.363089167519;4571.1086338164405;4578.6794924355645;9284.814303713887;9302;9316.627334906663;9317.86023209639;9326.207272381924;5160.587153027824;5161.27742116104;5163.690178589297;5164.04465173944;5165.302684137669;4220.092680073799;4611.515790841913;4676.719396836201;4801.615870730333;4831.975840120777;3679.2457546202963;3732.3841566141828;3734.981325093357;3738.2149748553106;3827.942580861289;1109.6892869996414;1118.8657361116718;1141.298101667485;1143.108375467137;1155.7977353963022;756.6141267953384;927.4991504587447;960.3853533738413;967.3422072990294;984.3208186351395;5727.5131613812455;5774.653520788766;5932.8813423732145;5974.4324289192455;6018.142565707302;471.9528047195253;472.2561964905288;477.8733635586572;492.93838270216224;638.3425072175667;3981.960180398159;4155.085881106152;4163.875083747493;4164.8542301019315;4170.040890595119;10030.44832534203;10400.635977740894;10468.842967355497;10490.685279441628;10511.684649460529;10312.175025998053;10336.896631033744;10340.431276279876;10350.896491034993;10377.014183652542;7746.535207887658;7893.644785984645;7938.444308898322;7948.960255198687;7969.362451003905;5896.086106653475;5927.644341339534;5943.58394912352;6048.818535443944;6082.391760823883;9445.669401570913;9663.565139568876;9839.704808855746;9931.851022234529;9967.551460184453;9393.389429687048;9781.706548803548;9790.755231118994;9889.950550247202;9897.851532227167;6770.898436523357;7395.334419902235;7633.5419874807685;7665.271799179069;7826.491278066907;8446.535440550828;8486.872696909428;8515.19105684959;8525.829483410422;8543.188397102265;7262.891056634049;7422.406207503279;7628.38972882157;7726.845463090594;7751.186125456971;7463.888041679271;7542.698292068475;7574.697012119454;7630.7710768677025;7699.26856948589;6976.232614412309;6993;7026.508144429841;7029.824254393477;7037.824054398681;5050.848474545623;5188.7405629719115;5216.739163041757;5287.074761916572;5320.973395133;5090.694558326512;5223.112070947872;5258.237555554347;5270.314859068297;5346.091164501966 19:28:13 INFO - PROCESS | 8904 | |4;prototype.html;317.7505658058423;329.6028285915472;331.2150204016481;338.63765770625434;339.5348372729361;5265.868353291165;5323.281357016802;5323.8136665216725;5333.759980800864;5375.81184658537;5235.319408476893;5250;5254.500822421874;5259.631825772198;5279.706471914376;4167.229062623409;4174.770387628687;4183.581641835823;4186.35111557709;4192;3935.5474120476224;3981.9402708959387;4109.486314210723;4127.876163715078;4138.3999320098565;4027.4361589377363;4045;4074.857379991701;4090.877273681794;4108.691848111389;3898.3762597984187;4097.856575019875;4107.116969851481;4117.958820411817;4126.463559737245;3624.5650521937537;3678.6137455567045;3682.3739964206147;3704.444333349992;3711.3319602471543;5093.236014597803;5103.668261563023;5109.693418394908;5120.206368012964;5140.126178549655;4678.3216433616935;4722.976385118087;4745.857624271277;4752.976235118802;4756.619470442356;5466.945330546683;5493.670379777226;5503.3120859892515;5504.6421982571;5543.805966791183;4002.1595464952393;4040.4141399497203;4053.3717273822604;4056.290149223874;4072.8574499892657;5515.117581186991;5713.971430142823;5717.799262154953;5738.311402631669;5777.7977770777825;4148.605882441163;4158.896027599334;4169.520505141887;4170.582941705866;4173.603507666767;4207.674582506501;4222.472190976128;4225.429567008415;4230.428892099589;4242.493914660251;4737.8341758038305;4749.810007599726;4751.121042607128;4760;4777.737224452619;5339.813106541253;5504.366997795305;5556.944430555643;5593.098346562188;5595.468430499096;1446.9059511131743;1464.7403233219422;1471.2790732540977;1475.3655927951083;1478.977815332771;926.9397489163183;942.9292803039799;949.9810003799886;953.537534295866;959.1271942532262;4757.238841785367;4771.5228477152;5130;5289.68261904287;5299.099153143975;5516.420775818561;5532.75102620383;5546.861328466821;5698.772049118072;5724.4561766632105;5365.792696643302;5371.4897084776885;5383.353997520323;5426.593005524602;5427.592930530147;2900.579415984713;3030.1516075194113;3030.348475077825;3046.4211799757977;3057.2356910772305;5852.590318677737;5854.677992710356;5874.5620567464875;5901.557383196277;5915.49154965481;5636.548101575828;5685.3228297652295;5759.539236861041;5794.18881356602;5844.298684157929;5868.677222752704;5892.616979896293;5943.405659434022;5944.227250457327;5952.910706339322;5669.015844454573;5679.17651940458;5692.601517893708;5718.428157184415;5730.255066841451;5405.675659460445;5587.357453892854;5648.689322087324;5663.66018038919;5665.348484924287;5803.274590676166;5879.647681033472;5894.498967587631;5905.852353691193;5916.645001299764;3938.586448422874;4073.89815254621;4085.3871919212356;4090.877273681794;4101.343784994387;5668.773249069991;5734.2258795062135;5756.510696590837;5760.683162425939;5770.567207459541;5528;5535.280413546213;5543.473370029841;5546.861328466821;5547.8890422192135;5356.705381203916;5497.835064948058;5541.750621221974;5549.250851135206;5552.167174923794;5421.5933804964;5447.564194864481;5463.344398672185;5466.316710411199;5483.945160548344;5348.78604855817;5375.784968601369;5513.5037846594;5573.386927437898;5574;5445.292112025411;5451.318585176853;5458.9454105458435;5461.371942226536;5461.8361449155 19:28:13 INFO - PROCESS | 8904 | |5;yui.html;756.7767508584967;816.1471262530655;872.725091596147;873.1443185678036;881.6076845803616;15525.534233972985;16209.270582823772;16233.594160146002;16282.75575866363;16307.347706091758;14609.853901460981;14632.561023169323;14646.560603181895;14660.193689347081;14672.339744711488;675.9526833121683;788.2432864450135;812.1675282835082;819.0580832043169;826.3182874128838;815.8368326334756;820.7619790260817;823.3248736036453;823.5511646152861;824.2375802382772;873.0483772687769;873.6418068591879;875.1642181716445;875.3828550871652;875.5928493250638;711.7188710459392;839.1943734015322;859.4113032572708;860.096898256834;869.443556124081;626.408044398044;627.1690010735748;627.2473032361185;627.3412916437723;627.5669787846417;14301.28493575317;14359.78460323096;14423.92788036053;14588.27058647074;14685.265736713229;13872.4451021959;14286.499972500913;14288.714225715532;14290.071145375518;14366.13803171813;12635.620931371981;12707.301098439582;12718;12760.170588911691;12784;4421.447319085114;4444.73331600105;4463.352813841975;4465;4520.751358675271;52432.47567524314;54070.18894716582;54181.72909135429;59500.10749838713;60842;11528.711782205512;11783.823242651368;11851.585194518151;11987;12170.695732606755;18605.348812791486;19969.800301997086;20017.199312027355;20023.199072036954;20090.69863952042;8507.702230421906;8672.349573781992;8819.691310804219;8900.065493123258;9034.096590341045;4614.723116613014;4776.116418462596;4777.713337199779;4834.468208497063;5036.496350364934;3404.846781894771;3475;3485.5120283160377;3489.703375213135;3491.546099007113;598.7724664627414;643.5205771700114;661.5237029338869;667.3926726678699;687.4740823270203;686.7946753447767;722.8048426924701;729.580491217552;764.5833021003566;790.2768966395683;10317.84523232152;10377.792444151075;10522;10534.947325263478;10558.260921735558;771.1440301265591;789.4710543935462;793.3217099380063;807.6688557691435;810.3760104719333;4621.042532779024;4807.903841923142;4974.925376119361;4999.350084489066;5107.7446127693465;72406.63796680982;72814.63592682003;75742.24257757352;76198;76233.6188319066;66175.66912165505;66745;66765;68353.65823170952;70231;61036;61626.38373616206;61955.69022154951;62020.37979620146;62313.376866231665;9556.187724043533;9618.086281803497;9656.468894210886;9715.417074975525;9809.705708828747;39043.609563905135;39381;40257.99999999883;40713.778586642446;40941;49563.50436495589;53607;54429.45570544244;54543;54964.228755980046;11831.467583958914;12400.31798251105;12652;12723.936380318039;12778.552750653682;14641.144976729436;14732.116073035651;14923.253837307873;15330.156841373831;15380.307886144927;13321.666958325732;14167.832794673443;14178.787318190442;14493.347799348843;14622.41510339617;12789.488420463078;12836.358182091044;13088.345582721016;13155.342232888508;13524.526641567498;13037.608871733863;13549.796753048515;13901;13912.652183695489;13945.442182313;11456.427178641201;11524.769504609692;11573.710657233636;11769.646910592695;11878.227915185485;11397.650706576724;11420.428978550874;11562.306261623993;11630.360330181922;11776.764464710486 19:28:13 INFO - PROCESS | 8904 | __end_tp_report 19:28:13 INFO - PROCESS | 8904 | __start_cc_report 19:28:13 INFO - PROCESS | 8904 | _x_x_mozilla_cycle_collect,904 19:28:13 INFO - PROCESS | 8904 | __end_cc_report 19:28:13 INFO - PROCESS | 8904 | __startTimestamp1464402493013__endTimestamp 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | ------- Summary: start ------- 19:28:13 INFO - PROCESS | 8904 | Number of tests: 6 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | [#0] dojo.html Cycles:165 Average:5428.53 Median:5620.04 stddev:625.18 (11.1%) stddev-sans-first:600.14 19:28:13 INFO - PROCESS | 8904 | Values: 3113.8 3124.0 3140.3 3172.8 3192.4 5367.2 5414.2 5442.4 5534.2 5571.4 5419.2 5423.8 5439.9 5440.6 5498.1 4881.5 4949.5 4951.5 4967.3 5150.9 5044.1 5067.7 5071.7 5116.0 5124.7 4855.7 4901.6 4908.5 4950.5 4998.3 4901.5 4910.2 4952.1 4974.1 4989.0 4853.6 4931.1 4941.6 4951.0 4953.8 5284.5 5352.2 5360.8 5404.4 5413.5 5852.4 5855.5 5864.5 5872.4 5876.6 5799.3 5804.0 5897.2 5910.0 5912.5 5483.6 5579.0 5583.6 5585.3 5586.0 6034.7 6044.0 6074.8 6092.4 6095.1 5634.6 5680.8 5726.9 5846.5 5857.4 5845.1 5857.1 5901.5 5913.5 5915.8 5788.3 5794.1 5836.2 5838.9 5841.8 5676.7 5758.6 5826.3 5827.2 5838.5 4434.0 4439.8 4440.2 4441.3 4481.0 3930.8 3942.1 3969.3 3994.3 4025.0 5444.9 5494.5 5523.9 5559.4 5618.1 5899.5 5915.5 5937.9 5943.6 6009.8 5360.1 5575.7 5604.3 5616.6 5619.7 4943.8 4988.3 4992.1 4998.2 5012.8 6026.5 6026.6 6027.6 6028.4 6046.2 5771.0 5789.0 5827.1 5831.9 5839.3 5997.1 6081.1 6094.8 6121.8 6133.7 5705.2 5751.1 5753.7 5753.8 5754.5 5722.1 5794.7 5880.1 5880.3 5882.6 5669.2 5709.1 5714.3 5732.5 5767.0 5687.2 5690.5 5694.6 5704.3 5708.1 5435.1 5510.1 5547.8 5583.2 5587.1 5732.3 5758.3 5765.0 5778.3 5780.5 5557.5 5604.3 5620.0 5628.2 5650.4 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | [#1] ext.html Cycles:180 Average:60827.49 Median:22876.63 stddev:122358.10 (534.9%) stddev-sans-first:122617.79 19:28:13 INFO - PROCESS | 8904 | Values: 601.1 629.0 642.6 651.8 655.3 32615.2 35746.8 35858.0 35861.6 35907.3 43768.3 44803.6 44955.0 49205.1 49234.3 8692.7 8879.4 8887.0 8887.8 8890.4 8638.7 8757.2 8758.4 8760.1 8772.1 8773.4 8783.6 8821.2 8826.3 8840.4 8734.3 8782.5 8790.4 8791.4 8791.5 8668.0 8782.5 8821.9 8825.6 8826.4 42700.4 42717.4 43128.8 43157.6 43168.6 3517.2 3607.7 3706.0 4135.1 4184.0 32570.8 32805.2 33208.3 33453.8 33669.0 4428.8 4442.0 4484.7 4519.5 4608.5 350046.0 351399.0 351584.0 354058.0 354238.0 58743.0 59029.4 59125.0 59191.7 59234.7 36165.1 37033.4 37201.4 38810.0 61913.4 17484.4 17599.2 17650.6 17686.8 17689.7 13234.1 13358.3 13370.5 13373.7 13379.3 19928.8 20157.4 20259.8 20277.5 20352.0 7118.5 7326.6 7425.4 7489.4 7506.5 1009.5 1044.5 1145.8 1147.2 1181.8 30956.0 33309.7 33347.3 33479.2 33716.3 1129.3 1154.2 1155.6 1204.3 1236.3 17021.1 17213.7 17214.9 17282.1 17327.5 564237.0 568066.0 568789.0 569219.0 570494.0 59421.0 60763.0 60866.4 60940.0 61025.0 1266.1 1373.7 1396.3 1411.7 1416.0 32150.8 34605.7 34605.8 34732.5 34806.0 50675.0 51039.5 51144.0 51344.7 51367.0 434899.0 435472.0 435819.0 436455.0 436972.0 24568.9 25115.9 25219.5 25357.7 25384.5 48360.0 48738.9 48843.3 48849.3 49136.0 40959.4 41219.6 41275.0 42030.0 42641.1 40944.8 40953.8 41042.0 41618.2 41811.2 40678.6 40950.8 42011.4 42164.0 42165.4 35809.0 35909.0 36792.0 36858.4 37190.6 28090.6 28212.4 28359.6 28479.7 28586.3 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | [#2] jquery.html Cycles:180 Average:30151.18 Median:27226.87 stddev:78770.65 (289.3%) stddev-sans-first:78959.92 19:28:13 INFO - PROCESS | 8904 | Values: 384.5 384.6 390.4 396.7 411.6 14430.4 15781.8 17575.5 19116.3 19122.1 17835.5 19054.8 19384.8 20287.5 20305.9 6165.1 6280.4 6540.2 6550.2 6551.7 4566.5 4631.8 6414.7 6446.2 6446.4 7263.5 7392.7 7411.0 7421.0 7425.2 7174.1 7306.4 7352.4 7367.4 7370.3 6791.6 7046.1 7055.1 7089.0 7105.7 25083.9 25408.5 25858.7 25927.2 26206.7 2290.3 2322.1 2407.0 2519.7 2562.7 14860.9 15135.8 15191.4 15397.0 15921.1 2030.4 2166.8 2213.7 2231.3 2240.6 80989.6 91073.0 97231.0 97402.5 97442.5 17492.0 17705.9 17920.7 18160.7 18349.2 28247.0 28720.9 32673.3 32688.2 32723.8 9961.6 10154.3 10187.6 10315.9 10544.5 8417.3 8593.1 8602.7 8645.1 8843.2 8882.0 8910.2 8965.1 9187.9 9254.4 3468.2 3502.5 3505.8 3604.7 3634.8 1057.2 1061.5 1065.6 1083.7 1110.7 18718.7 18918.9 19238.4 19278.7 19395.7 988.6 1024.9 1032.1 1034.9 1134.0 10374.9 10897.5 10944.2 10947.4 11033.1 452156.0 452917.0 488870.0 492577.0 496280.0 86776.0 89075.6 94303.5 94737.6 96310.5 964.2 1016.8 1075.0 1075.9 1077.4 15930.3 16210.2 16701.2 17050.9 17338.7 41754.0 42109.0 43051.6 43458.0 44901.3 56536.2 57007.4 57591.7 58565.4 58861.7 8427.7 8460.1 8676.7 8861.4 8930.0 9550.0 12882.0 13423.0 13470.1 13579.4 11532.4 11761.6 11855.0 12132.8 12168.7 11550.8 11658.8 11765.1 11821.7 12072.8 11094.2 11282.4 11777.6 12185.8 12305.1 8179.3 8231.0 8260.2 8381.2 8488.7 10833.5 11462.9 11564.4 11901.6 12058.6 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | [#3] mootools.html Cycles:180 Average:5624.63 Median:5164.67 stddev:3151.40 (61.0%) stddev-sans-first:3143.64 19:28:13 INFO - PROCESS | 8904 | Values: 1321.5 1490.9 1521.0 1674.6 1818.6 8076.4 8443.1 8461.4 8670.5 8678.9 8385.6 8579.5 8605.1 8614.4 8615.5 2303.1 2327.0 2330.9 2331.2 2338.7 2276.9 2350.7 2353.0 2355.6 2361.9 2329.5 2330.7 2357.4 2358.4 2361.5 2190.5 2207.7 2209.4 2212.2 2227.4 2186.1 2189.9 2190.8 2191.6 2192.2 8196.7 8372.1 8425.5 8431.4 8439.1 5098.6 5208.0 5212.1 5220.8 5221.1 3333.4 3352.4 3391.7 3459.7 3459.9 1123.1 1135.2 1137.1 1202.9 1220.3 11653.5 11733.9 11739.5 11743.2 11762.2 4412.9 4481.6 4549.4 4571.1 4578.7 9284.8 9302.0 9316.6 9317.9 9326.2 5160.6 5161.3 5163.7 5164.0 5165.3 4220.1 4611.5 4676.7 4801.6 4832.0 3679.2 3732.4 3735.0 3738.2 3827.9 1109.7 1118.9 1141.3 1143.1 1155.8 756.6 927.5 960.4 967.3 984.3 5727.5 5774.7 5932.9 5974.4 6018.1 472.0 472.3 477.9 492.9 638.3 3982.0 4155.1 4163.9 4164.9 4170.0 10030.4 10400.6 10468.8 10490.7 10511.7 10312.2 10336.9 10340.4 10350.9 10377.0 7746.5 7893.6 7938.4 7949.0 7969.4 5896.1 5927.6 5943.6 6048.8 6082.4 9445.7 9663.6 9839.7 9931.9 9967.6 9393.4 9781.7 9790.8 9890.0 9897.9 6770.9 7395.3 7633.5 7665.3 7826.5 8446.5 8486.9 8515.2 8525.8 8543.2 7262.9 7422.4 7628.4 7726.8 7751.2 7463.9 7542.7 7574.7 7630.8 7699.3 6976.2 6993.0 7026.5 7029.8 7037.8 5050.8 5188.7 5216.7 5287.1 5321.0 5090.7 5223.1 5258.2 5270.3 5346.1 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | [#4] prototype.html Cycles:180 Average:4670.42 Median:5352.75 stddev:1361.66 (25.4%) stddev-sans-first:1325.71 19:28:13 INFO - PROCESS | 8904 | Values: 317.8 329.6 331.2 338.6 339.5 5265.9 5323.3 5323.8 5333.8 5375.8 5235.3 5250.0 5254.5 5259.6 5279.7 4167.2 4174.8 4183.6 4186.4 4192.0 3935.5 3981.9 4109.5 4127.9 4138.4 4027.4 4045.0 4074.9 4090.9 4108.7 3898.4 4097.9 4107.1 4118.0 4126.5 3624.6 3678.6 3682.4 3704.4 3711.3 5093.2 5103.7 5109.7 5120.2 5140.1 4678.3 4723.0 4745.9 4753.0 4756.6 5466.9 5493.7 5503.3 5504.6 5543.8 4002.2 4040.4 4053.4 4056.3 4072.9 5515.1 5714.0 5717.8 5738.3 5777.8 4148.6 4158.9 4169.5 4170.6 4173.6 4207.7 4222.5 4225.4 4230.4 4242.5 4737.8 4749.8 4751.1 4760.0 4777.7 5339.8 5504.4 5556.9 5593.1 5595.5 1446.9 1464.7 1471.3 1475.4 1479.0 926.9 942.9 950.0 953.5 959.1 4757.2 4771.5 5130.0 5289.7 5299.1 5516.4 5532.8 5546.9 5698.8 5724.5 5365.8 5371.5 5383.4 5426.6 5427.6 2900.6 3030.2 3030.3 3046.4 3057.2 5852.6 5854.7 5874.6 5901.6 5915.5 5636.5 5685.3 5759.5 5794.2 5844.3 5868.7 5892.6 5943.4 5944.2 5952.9 5669.0 5679.2 5692.6 5718.4 5730.3 5405.7 5587.4 5648.7 5663.7 5665.3 5803.3 5879.6 5894.5 5905.9 5916.6 3938.6 4073.9 4085.4 4090.9 4101.3 5668.8 5734.2 5756.5 5760.7 5770.6 5528.0 5535.3 5543.5 5546.9 5547.9 5356.7 5497.8 5541.8 5549.3 5552.2 5421.6 5447.6 5463.3 5466.3 5483.9 5348.8 5375.8 5513.5 5573.4 5574.0 5445.3 5451.3 5458.9 5461.4 5461.8 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - PROCESS | 8904 | [#5] yui.html Cycles:180 Average:16741.60 Median:4617.88 stddev:20245.13 (438.4%) stddev-sans-first:20266.34 19:28:13 INFO - PROCESS | 8904 | Values: 756.8 816.1 872.7 873.1 881.6 15525.5 16209.3 16233.6 16282.8 16307.3 14609.9 14632.6 14646.6 14660.2 14672.3 676.0 788.2 812.2 819.1 826.3 815.8 820.8 823.3 823.6 824.2 873.0 873.6 875.2 875.4 875.6 711.7 839.2 859.4 860.1 869.4 626.4 627.2 627.2 627.3 627.6 14301.3 14359.8 14423.9 14588.3 14685.3 13872.4 14286.5 14288.7 14290.1 14366.1 12635.6 12707.3 12718.0 12760.2 12784.0 4421.4 4444.7 4463.4 4465.0 4520.8 52432.5 54070.2 54181.7 59500.1 60842.0 11528.7 11783.8 11851.6 11987.0 12170.7 18605.3 19969.8 20017.2 20023.2 20090.7 8507.7 8672.3 8819.7 8900.1 9034.1 4614.7 4776.1 4777.7 4834.5 5036.5 3404.8 3475.0 3485.5 3489.7 3491.5 598.8 643.5 661.5 667.4 687.5 686.8 722.8 729.6 764.6 790.3 10317.8 10377.8 10522.0 10534.9 10558.3 771.1 789.5 793.3 807.7 810.4 4621.0 4807.9 4974.9 4999.4 5107.7 72406.6 72814.6 75742.2 76198.0 76233.6 66175.7 66745.0 66765.0 68353.7 70231.0 61036.0 61626.4 61955.7 62020.4 62313.4 9556.2 9618.1 9656.5 9715.4 9809.7 39043.6 39381.0 40258.0 40713.8 40941.0 49563.5 53607.0 54429.5 54543.0 54964.2 11831.5 12400.3 12652.0 12723.9 12778.6 14641.1 14732.1 14923.3 15330.2 15380.3 13321.7 14167.8 14178.8 14493.3 14622.4 12789.5 12836.4 13088.3 13155.3 13524.5 13037.6 13549.8 13901.0 13912.7 13945.4 11456.4 11524.8 11573.7 11769.6 11878.2 11397.7 11420.4 11562.3 11630.4 11776.8 19:28:13 INFO - PROCESS | 8904 | -------- Summary: end -------- 19:28:13 INFO - PROCESS | 8904 | 19:28:13 INFO - TEST-INFO | 8904: exit 0 19:28:13 INFO - TEST-OK | dromaeo_css | took 1112128ms 19:28:13 INFO - TEST-START | kraken 19:28:13 INFO - operating with platform_type : linux_ 19:28:13 INFO - Initialising browser for kraken test... 19:28:13 INFO - TEST-INFO | started process 9086 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBYawRd/profile http://localhost:35216/getInfo.html) 19:28:17 INFO - PROCESS | 9086 | __metrics Screen width/height:1600/1200 19:28:17 INFO - PROCESS | 9086 | colorDepth:24 19:28:17 INFO - PROCESS | 9086 | Browser inner width/height: 1024/697 19:28:17 INFO - PROCESS | 9086 | __metrics 19:28:18 ERROR - PROCESS | 9086 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:28:19 INFO - TEST-INFO | 9086: exit 0 19:28:19 INFO - Browser initialized. 19:28:19 INFO - Running cycle 1/1 for kraken test... 19:28:19 INFO - TEST-INFO | started process 9170 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBYawRd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:30 INFO - PROCESS | 9170 | RSS: Main: 156049408 19:28:30 INFO - PROCESS | 9170 | 19:28:33 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35216/tests/kraken/driver.html?testName=audio-beat-detection) 19:28:34 INFO - PROCESS | 9170 | RSS: Main: 214376448 19:28:34 INFO - PROCESS | 9170 | 19:28:36 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35216/tests/kraken/driver.html?testName=audio-dft) 19:28:37 INFO - PROCESS | 9170 | RSS: Main: 197693440 19:28:37 INFO - PROCESS | 9170 | 19:28:40 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35216/tests/kraken/driver.html?testName=audio-fft) 19:28:41 INFO - PROCESS | 9170 | RSS: Main: 268967936 19:28:41 INFO - PROCESS | 9170 | 19:28:43 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35216/tests/kraken/driver.html?testName=audio-oscillator) 19:28:43 INFO - PROCESS | 9170 | RSS: Main: 179027968 19:28:43 INFO - PROCESS | 9170 | 19:28:46 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35216/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:28:46 INFO - PROCESS | 9170 | RSS: Main: 183185408 19:28:46 INFO - PROCESS | 9170 | 19:28:49 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35216/tests/kraken/driver.html?testName=imaging-darkroom) 19:28:50 INFO - PROCESS | 9170 | RSS: Main: 217276416 19:28:50 INFO - PROCESS | 9170 | 19:28:53 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35216/tests/kraken/driver.html?testName=imaging-desaturate) 19:28:54 INFO - PROCESS | 9170 | RSS: Main: 219426816 19:28:54 INFO - PROCESS | 9170 | 19:28:57 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35216/tests/kraken/driver.html?testName=json-parse-financial) 19:28:57 INFO - PROCESS | 9170 | RSS: Main: 220250112 19:28:57 INFO - PROCESS | 9170 | 19:28:59 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35216/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:28:59 INFO - PROCESS | 9170 | RSS: Main: 207753216 19:28:59 INFO - PROCESS | 9170 | 19:29:01 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:02 INFO - PROCESS | 9170 | RSS: Main: 209694720 19:29:02 INFO - PROCESS | 9170 | 19:29:04 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:04 INFO - PROCESS | 9170 | RSS: Main: 216354816 19:29:04 INFO - PROCESS | 9170 | 19:29:07 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:08 INFO - PROCESS | 9170 | RSS: Main: 222371840 19:29:08 INFO - PROCESS | 9170 | 19:29:10 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:11 INFO - PROCESS | 9170 | RSS: Main: 221061120 19:29:11 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | Cycle 1(1): loaded http://localhost:35216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35216/tests/kraken/driver.html?testName=ai-astar) 19:29:13 INFO - PROCESS | 9170 | RSS: Main: 262803456 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | __start_tp_report 19:29:13 INFO - PROCESS | 9170 | _x_x_mozilla_page_load 19:29:13 INFO - PROCESS | 9170 | _x_x_mozilla_page_load_details 19:29:13 INFO - PROCESS | 9170 | |i|pagename|runs| 19:29:13 INFO - PROCESS | 9170 | |0;ai-astar;109;105;111;110;110;110;110;110;110;110 19:29:13 INFO - PROCESS | 9170 | |1;audio-beat-detection;153;123;132;172;124;158;150;125;185;127 19:29:13 INFO - PROCESS | 9170 | |2;audio-dft;160;160;159;160;159;162;160;160;159;162 19:29:13 INFO - PROCESS | 9170 | |3;audio-fft;75;76;74;128;75;75;75;75;97;74 19:29:13 INFO - PROCESS | 9170 | |4;audio-oscillator;82;84;115;84;84;84;121;83;82;124 19:29:13 INFO - PROCESS | 9170 | |5;imaging-gaussian-blur;116;117;117;117;116;117;117;118;117;117 19:29:13 INFO - PROCESS | 9170 | |6;imaging-darkroom;145;146;146;145;145;145;146;145;145;146 19:29:13 INFO - PROCESS | 9170 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;95;95 19:29:13 INFO - PROCESS | 9170 | |8;json-parse-financial;52;53;53;51;52;52;52;56;51;53 19:29:13 INFO - PROCESS | 9170 | |9;json-stringify-tinderbox;61;61;62;62;63;62;62;62;63;62 19:29:13 INFO - PROCESS | 9170 | |10;stanford-crypto-aes;80;78;79;80;79;81;80;80;116;79 19:29:13 INFO - PROCESS | 9170 | |11;stanford-crypto-ccm;127;127;124;126;119;119;129;129;132;137 19:29:13 INFO - PROCESS | 9170 | |12;stanford-crypto-pbkdf2;164;163;162;163;166;162;166;166;163;160 19:29:13 INFO - PROCESS | 9170 | |13;stanford-crypto-sha256-iterative;62;64;64;63;63;63;67;63;63;66 19:29:13 INFO - PROCESS | 9170 | __end_tp_report 19:29:13 INFO - PROCESS | 9170 | __start_cc_report 19:29:13 INFO - PROCESS | 9170 | _x_x_mozilla_cycle_collect,1612 19:29:13 INFO - PROCESS | 9170 | __end_cc_report 19:29:13 INFO - PROCESS | 9170 | __startTimestamp1464402553311__endTimestamp 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | ------- Summary: start ------- 19:29:13 INFO - PROCESS | 9170 | Number of tests: 14 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.65 (1.5%) stddev-sans-first:1.74 19:29:13 INFO - PROCESS | 9170 | Values: 109.0 105.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:151.50 stddev:22.12 (14.6%) stddev-sans-first:23.27 19:29:13 INFO - PROCESS | 9170 | Values: 153.0 123.0 132.0 172.0 124.0 158.0 150.0 125.0 185.0 127.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 19:29:13 INFO - PROCESS | 9170 | Values: 160.0 160.0 159.0 160.0 159.0 162.0 160.0 160.0 159.0 162.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:17.48 (23.3%) stddev-sans-first:18.33 19:29:13 INFO - PROCESS | 9170 | Values: 75.0 76.0 74.0 128.0 75.0 75.0 75.0 75.0 97.0 74.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:83.50 stddev:17.88 (21.4%) stddev-sans-first:18.41 19:29:13 INFO - PROCESS | 9170 | Values: 82.0 84.0 115.0 84.0 84.0 84.0 121.0 83.0 82.0 124.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 19:29:13 INFO - PROCESS | 9170 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 117.0 118.0 117.0 117.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:29:13 INFO - PROCESS | 9170 | Values: 145.0 146.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:29:13 INFO - PROCESS | 9170 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.43 (2.7%) stddev-sans-first:1.51 19:29:13 INFO - PROCESS | 9170 | Values: 52.0 53.0 53.0 51.0 52.0 52.0 52.0 56.0 51.0 53.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 19:29:13 INFO - PROCESS | 9170 | Values: 61.0 61.0 62.0 62.0 63.0 62.0 62.0 62.0 63.0 62.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:80.00 stddev:11.55 (14.4%) stddev-sans-first:12.20 19:29:13 INFO - PROCESS | 9170 | Values: 80.0 78.0 79.0 80.0 79.0 81.0 80.0 80.0 116.0 79.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:128.00 stddev:5.49 (4.3%) stddev-sans-first:5.82 19:29:13 INFO - PROCESS | 9170 | Values: 127.0 127.0 124.0 126.0 119.0 119.0 129.0 129.0 132.0 137.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:163.50 stddev:2.01 (1.2%) stddev-sans-first:2.13 19:29:13 INFO - PROCESS | 9170 | Values: 164.0 163.0 162.0 163.0 166.0 162.0 166.0 166.0 163.0 160.0 19:29:13 INFO - PROCESS | 9170 | 19:29:13 INFO - PROCESS | 9170 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:63.50 stddev:1.55 (2.4%) stddev-sans-first:1.50 19:29:13 INFO - PROCESS | 9170 | Values: 62.0 64.0 64.0 63.0 63.0 63.0 67.0 63.0 63.0 66.0 19:29:13 INFO - PROCESS | 9170 | -------- Summary: end -------- 19:29:13 INFO - PROCESS | 9170 | 19:29:14 INFO - TEST-INFO | 9170: exit 0 19:29:14 INFO - TEST-OK | kraken | took 60516ms 19:29:14 INFO - SUITE-END | took 1172s 19:29:14 INFO - Completed test suite (00:19:32) 19:29:14 INFO - javascript benchmark 19:29:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3113.8287394193326, 3123.9843800780977, 3140.277736120692, 3172.825494597799, 3192.4253634345823, 5367.1680889462095, 5414.242006119146, 5442.401335853053, 5534.16987451882, 5571.387147413786, 5419.187121931712, 5423.755930983105, 5439.864003399917, 5440.646357986738, 5498.120300751881, 4881.5118488151165, 4949.505049495057, 4951.455339912607, 4967.304577359173, 5150.948490515103, 5044.117279476095, 5067.721275329855, 5071.695698258098, 5116.027954688598, 5124.692518448886, 4855.732934688591, 4901.558859702608, 4908.533689299511, 4950.455449900526, 4998.2752500887345, 4901.509849015106, 4910.165271903785, 4952.051956609625, 4974.10466116099, 4988.950110498921, 4853.611711063107, 4931.137051016094, 4941.579965702884, 4951.03454826309, 4953.777080031371, 5284.471552844685, 5352.223927530486, 5360.785568577252, 5404.378496472917, 5413.512783849433, 5852.4440178183, 5855.502282306009, 5864.530837532987, 5872.442117998783, 5876.588638795286, 5799.33307669615, 5803.9709801451145, 5897.174395584621, 5909.970450147722, 5912.4974377178, 5483.588730845203, 5579.023670857624, 5583.637063590894, 5585.329760428694, 5586.022446071962, 6034.696529355178, 6044.040691173443, 6074.848128796815, 6092.39076092396, 6095.085737139376, 5634.577406694432, 5680.7727690892725, 5726.885462290731, 5846.532277417881, 5857.4435428634215, 5845.09401042839, 5857.062869940789, 5901.498372638288, 5913.526917846562, 5915.822525324161, 5788.3343415506915, 5794.072948328248, 5836.182934389189, 5838.854028649318, 5841.766329346779, 5676.68778217202, 5758.62568933018, 5826.300843898759, 5827.213326200994, 5838.503727183237, 4433.977830110829, 4439.844605438794, 4440.200763862471, 4441.311596702451, 4480.955190448119, 3930.8231129598666, 3942.0539070623418, 3969.2656858481273, 3994.320965435883, 4025.013871601418, 5444.891102177934, 5494.478024587658, 5523.917141242885, 5559.416261292506, 5618.073017952074, 5899.469047785634, 5915.467607915309, 5937.851553711105, 5943.64338139713, 6009.849753756191, 5360.142377219626, 5575.749091290902, 5604.299462567179, 5616.550675945915, 5619.662820230799, 4943.8022479101155, 4988.27669987857, 4992.051510213048, 4998.225275082368, 5012.774425150877, 6026.487748541423, 6026.608270462406, 6027.638341699424, 6028.397160284024, 6046.153538504613, 5770.971145144247, 5788.971055144697, 5827.096799996008, 5831.912521312099, 5839.270091238595, 5997.070454079625, 6081.087836824512, 6094.8171554853425, 6121.84695382619, 6133.662648554372, 5705.201271821865, 5751.1373294006235, 5753.683547404933, 5753.769849205984, 5754.53963682896, 5722.084466485342, 5794.739236734273, 5880.059190529495, 5880.323762767336, 5882.558808089496, 5669.177969194526, 5709.115087161496, 5714.285714285715, 5732.484076433141, 5767.019606666793, 5687.232223649919, 5690.487856092972, 5694.572907031907, 5704.344000440003, 5708.058170401837, 5435.10320797064, 5510.0632892409185, 5547.805826796042, 5583.218349431164, 5587.050201465842, 5732.34078081026, 5758.2514273144225, 5765.048766953406, 5778.335491418541, 5780.479756821906, 5557.527610153183, 5604.271444712162, 5620.04459241938, 5628.155776633538, 5650.378458369658], "value": 5385.693352168308, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.0983524712931, 628.9559730818843, 642.6465444005796, 651.7849109793767, 655.2530115668142, 32615.184620384473, 35746.8212658937, 35858.0, 35861.64138358615, 35907.2818543629, 43768.343474847825, 44803.55196448034, 44955.0, 49205.095388553425, 49234.26148607774, 8692.652293908237, 8879.422837515573, 8887.0, 8887.822243555158, 8890.422122562046, 8638.740837774845, 8757.168069033432, 8758.386912916098, 8760.080191579887, 8772.12278772121, 8773.429727067753, 8783.604737786814, 8821.206091451768, 8826.293896488265, 8840.38117331787, 8734.257588105018, 8782.516961567111, 8790.384673072887, 8791.428557143798, 8791.472511649321, 8668.046514883357, 8782.516961567175, 8821.867671984925, 8825.558722063872, 8826.382153249275, 42700.359494607605, 42717.35923961143, 43128.78435607833, 43157.568424315985, 43168.56831431677, 3517.191046059421, 3607.7113830893463, 3706.0179052551093, 4135.0902801383645, 4184.0, 32570.837145814356, 32805.17987050319, 33208.3358332832, 33453.83273083643, 33668.98993030188, 4428.822847086113, 4442.0, 4484.686071974963, 4519.4802597701355, 4608.4930657627965, 350046.0, 351399.0, 351584.0, 354058.0, 354238.0, 58743.0, 59029.40970590239, 59125.0, 59191.70404148038, 59234.703826481455, 36165.09587260339, 37033.444498332545, 37201.44197837035, 38810.0, 61913.38086619076, 17484.388046418568, 17599.20803563843, 17650.558736031446, 17686.823131768775, 17689.734653980202, 13234.139780914209, 13358.265295408846, 13370.532031378854, 13373.665658358424, 13379.331033448289, 19928.800711992986, 20157.395278141677, 20259.79740202579, 20277.49306267355, 20352.0, 7118.466115041289, 7326.560406375635, 7425.405967522585, 7489.363404110712, 7506.512076714996, 1009.5305682857506, 1044.5038606661926, 1145.7765735681462, 1147.1854982962022, 1181.7931861924128, 30955.95145262248, 33309.66690333114, 33347.33305333928, 33479.163020924185, 33716.32567348639, 1129.3111202166672, 1154.1805318223987, 1155.6475275041025, 1204.3015051270243, 1236.3076677060874, 17021.148942552823, 17213.7417938731, 17214.913925430294, 17282.13589320529, 17327.480175594752, 564237.0, 568066.0, 568789.0, 569219.0, 570494.0, 59421.0, 60763.0, 60866.391336086075, 60940.0, 61025.0, 1266.1137203957267, 1373.684052667892, 1396.3018490754623, 1411.696485255675, 1416.0158689710759, 32150.83924580362, 34605.653943460246, 34605.82697086498, 34732.47901281432, 34806.0, 50675.0, 51039.489605103474, 51144.0, 51344.74327628338, 51367.0, 434899.0, 435472.0, 435819.0, 436455.0, 436972.0, 24568.877155614107, 25115.874420628512, 25219.495610088063, 25357.74642253628, 25384.492310154063, 48360.0, 48738.9436693337, 48843.26735098905, 48849.26726099182, 49136.0, 40959.38560921529, 41219.58780412278, 41275.0, 42030.0, 42641.14717705567, 40944.795276023426, 40953.79523102365, 41042.0, 41618.167636647704, 41811.16377672369, 40678.59321406748, 40950.795246023576, 42011.36982945197, 42164.0, 42165.367519487845, 35809.0, 35909.0, 36792.0, 36858.44712329264, 37190.62809371872, 28090.57864131999, 28212.435751284447, 28359.57460638051, 28479.715202847707, 28586.285342866595], "value": 20206.569255578717, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.4963098341173, 384.6211481690536, 390.3773481297331, 396.69652715672515, 411.5534644910272, 14430.422783088663, 15781.84218157818, 17575.47273581793, 19116.33092841751, 19122.13950372233, 17835.46493605194, 19054.809451905476, 19384.806151938476, 20287.49281267969, 20305.898470507625, 6165.136880836686, 6280.371962803706, 6540.215174179105, 6550.213974323064, 6551.737930482775, 4566.452025756914, 4631.837885674002, 6414.743410263583, 6446.226452825644, 6446.419822215999, 7263.491555591111, 7392.741254056109, 7410.962945185266, 7420.962895185544, 7425.1832298447125, 7174.139103307584, 7306.432392814211, 7352.411807055422, 7367.410607151469, 7370.299821516974, 6791.5925044497, 7046.1192350956135, 7055.118110236221, 7089.042979197846, 7105.680244389015, 25083.87458062716, 25408.506113238458, 25858.741412585823, 25927.22218333453, 26206.73793262062, 2290.278562252902, 2322.1408079010876, 2407.0, 2519.6976362836394, 2562.6668533090765, 14860.939800262608, 15135.848641513554, 15191.392344306214, 15396.999195052285, 15921.124338161397, 2030.4213299209803, 2166.7724888886755, 2213.690083388327, 2231.34175418252, 2240.641497360431, 80989.59505202436, 91072.99999999933, 97231.0, 97402.51298743532, 97442.51278743561, 17492.0, 17705.911470442566, 17920.670312859507, 18160.727589086175, 18349.17428715711, 28247.0, 28720.85639571789, 32673.346533069205, 32688.182795429828, 32723.836380817942, 9961.601535938626, 10154.289199756095, 10187.592496300065, 10315.948420257851, 10544.525496352682, 8417.326613870875, 8593.054763975957, 8602.741917742478, 8645.092265312052, 8843.159899809507, 8881.955590222007, 8910.198082172636, 8965.148310910454, 9187.908120918839, 9254.398464099811, 3468.2196505785996, 3502.5446691929887, 3505.789652620851, 3604.711623070148, 3634.7819130852236, 1057.1701214546697, 1061.543536279392, 1065.63235683689, 1083.6748975307535, 1110.6834552152598, 18718.71921921172, 18918.90540547316, 19238.422847314603, 19278.71081933772, 19395.709064364044, 988.6440881282732, 1024.8718910136233, 1032.1381646325253, 1034.886162522122, 1134.0, 10374.948125259325, 10897.509612067317, 10944.23390362683, 10947.397893115955, 11033.117350611932, 452156.0, 452917.0, 488870.0, 492577.0, 496280.0, 86776.0, 89075.55462222647, 94303.52848235852, 94737.57893631601, 96310.51844740871, 964.2045312617118, 1016.7778251260992, 1075.0378411321824, 1075.9294501970433, 1077.380506208933, 15930.283137259083, 16210.189490525663, 16701.2484438203, 17050.914745426195, 17338.653226935643, 41754.0, 42109.0, 43051.56948430601, 43458.0, 44901.326480103475, 56536.15195772149, 57007.42992570021, 57591.712041439525, 58565.414345856, 58861.705691472984, 8427.705030323908, 8460.111688272642, 8676.652933882575, 8861.379703420698, 8929.955350223207, 9549.952250238704, 12882.0, 13423.0, 13470.057096003186, 13579.388927498483, 11532.365719885487, 11761.588344407905, 11855.0, 12132.75734485323, 12168.695782605506, 11550.82673759911, 11658.825117623073, 11765.11761617865, 11821.70445738829, 12072.818907716566, 11094.167937404889, 11282.43587820622, 11777.587784427504, 12185.817212741993, 12305.138640295094, 8179.345652347917, 8231.0, 8260.215279548433, 8381.203785640355, 8488.660453581788, 10833.512491937725, 11462.94268528652, 11564.421778910853, 11901.642950711492, 12058.638240852788], "value": 10405.558948478496, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.5242512695422, 1490.932908019139, 1520.97718534222, 1674.6232097778002, 1818.6362727454512, 8076.39427042972, 8443.11347308533, 8461.407701460908, 8670.479771213715, 8678.869816952734, 8385.580720963942, 8579.52812595307, 8605.139486051392, 8614.35392345575, 8615.483071015728, 2303.09027933966, 2326.9883650581723, 2330.9417264568438, 2331.2423462374713, 2338.7427382987858, 2276.9203077892275, 2350.6709060731428, 2352.9529409411803, 2355.634876594122, 2361.9291421257303, 2329.5108027314286, 2330.650402439631, 2357.35172827473, 2358.41039740065, 2361.468669549346, 2190.518086021073, 2207.7129973103556, 2209.4034610655112, 2212.214663794362, 2227.4208705736464, 2186.0599942024924, 2189.9343019709436, 2190.8466407351493, 2191.5726433345503, 2192.232718548511, 8196.713115040944, 8372.079071302152, 8425.452345597578, 8431.40980131391, 8439.071702112824, 5098.59211263098, 5208.036513245024, 5212.113940630102, 5220.791168353261, 5221.086309895753, 3333.3833240850277, 3352.3630510202984, 3391.6777906098882, 3459.705924996378, 3459.8616055357757, 1123.0566324287638, 1135.2167004766684, 1137.1187329819375, 1202.927824330543, 1220.267839296424, 11653.475593598308, 11733.882661173364, 11739.47172377245, 11743.177977541576, 11762.176647634755, 4412.9117417652105, 4481.596656300949, 4549.363089167519, 4571.1086338164405, 4578.6794924355645, 9284.814303713887, 9302.0, 9316.627334906663, 9317.86023209639, 9326.207272381924, 5160.587153027824, 5161.27742116104, 5163.690178589297, 5164.04465173944, 5165.302684137669, 4220.092680073799, 4611.515790841913, 4676.719396836201, 4801.615870730333, 4831.975840120777, 3679.2457546202963, 3732.3841566141828, 3734.981325093357, 3738.2149748553106, 3827.942580861289, 1109.6892869996414, 1118.8657361116718, 1141.298101667485, 1143.108375467137, 1155.7977353963022, 756.6141267953384, 927.4991504587447, 960.3853533738413, 967.3422072990294, 984.3208186351395, 5727.5131613812455, 5774.653520788766, 5932.8813423732145, 5974.4324289192455, 6018.142565707302, 471.9528047195253, 472.2561964905288, 477.8733635586572, 492.93838270216224, 638.3425072175667, 3981.960180398159, 4155.085881106152, 4163.875083747493, 4164.8542301019315, 4170.040890595119, 10030.44832534203, 10400.635977740894, 10468.842967355497, 10490.685279441628, 10511.684649460529, 10312.175025998053, 10336.896631033744, 10340.431276279876, 10350.896491034993, 10377.014183652542, 7746.535207887658, 7893.644785984645, 7938.444308898322, 7948.960255198687, 7969.362451003905, 5896.086106653475, 5927.644341339534, 5943.58394912352, 6048.818535443944, 6082.391760823883, 9445.669401570913, 9663.565139568876, 9839.704808855746, 9931.851022234529, 9967.551460184453, 9393.389429687048, 9781.706548803548, 9790.755231118994, 9889.950550247202, 9897.851532227167, 6770.898436523357, 7395.334419902235, 7633.5419874807685, 7665.271799179069, 7826.491278066907, 8446.535440550828, 8486.872696909428, 8515.19105684959, 8525.829483410422, 8543.188397102265, 7262.891056634049, 7422.406207503279, 7628.38972882157, 7726.845463090594, 7751.186125456971, 7463.888041679271, 7542.698292068475, 7574.697012119454, 7630.7710768677025, 7699.26856948589, 6976.232614412309, 6993.0, 7026.508144429841, 7029.824254393477, 7037.824054398681, 5050.848474545623, 5188.7405629719115, 5216.739163041757, 5287.074761916572, 5320.973395133, 5090.694558326512, 5223.112070947872, 5258.237555554347, 5270.314859068297, 5346.091164501966], "value": 4457.054337729342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.7505658058423, 329.6028285915472, 331.2150204016481, 338.63765770625434, 339.5348372729361, 5265.868353291165, 5323.281357016802, 5323.8136665216725, 5333.759980800864, 5375.81184658537, 5235.319408476893, 5250.0, 5254.500822421874, 5259.631825772198, 5279.706471914376, 4167.229062623409, 4174.770387628687, 4183.581641835823, 4186.35111557709, 4192.0, 3935.5474120476224, 3981.9402708959387, 4109.486314210723, 4127.876163715078, 4138.3999320098565, 4027.4361589377363, 4045.0, 4074.857379991701, 4090.877273681794, 4108.691848111389, 3898.3762597984187, 4097.856575019875, 4107.116969851481, 4117.958820411817, 4126.463559737245, 3624.5650521937537, 3678.6137455567045, 3682.3739964206147, 3704.444333349992, 3711.3319602471543, 5093.236014597803, 5103.668261563023, 5109.693418394908, 5120.206368012964, 5140.126178549655, 4678.3216433616935, 4722.976385118087, 4745.857624271277, 4752.976235118802, 4756.619470442356, 5466.945330546683, 5493.670379777226, 5503.3120859892515, 5504.6421982571, 5543.805966791183, 4002.1595464952393, 4040.4141399497203, 4053.3717273822604, 4056.290149223874, 4072.8574499892657, 5515.117581186991, 5713.971430142823, 5717.799262154953, 5738.311402631669, 5777.7977770777825, 4148.605882441163, 4158.896027599334, 4169.520505141887, 4170.582941705866, 4173.603507666767, 4207.674582506501, 4222.472190976128, 4225.429567008415, 4230.428892099589, 4242.493914660251, 4737.8341758038305, 4749.810007599726, 4751.121042607128, 4760.0, 4777.737224452619, 5339.813106541253, 5504.366997795305, 5556.944430555643, 5593.098346562188, 5595.468430499096, 1446.9059511131743, 1464.7403233219422, 1471.2790732540977, 1475.3655927951083, 1478.977815332771, 926.9397489163183, 942.9292803039799, 949.9810003799886, 953.537534295866, 959.1271942532262, 4757.238841785367, 4771.5228477152, 5130.0, 5289.68261904287, 5299.099153143975, 5516.420775818561, 5532.75102620383, 5546.861328466821, 5698.772049118072, 5724.4561766632105, 5365.792696643302, 5371.4897084776885, 5383.353997520323, 5426.593005524602, 5427.592930530147, 2900.579415984713, 3030.1516075194113, 3030.348475077825, 3046.4211799757977, 3057.2356910772305, 5852.590318677737, 5854.677992710356, 5874.5620567464875, 5901.557383196277, 5915.49154965481, 5636.548101575828, 5685.3228297652295, 5759.539236861041, 5794.18881356602, 5844.298684157929, 5868.677222752704, 5892.616979896293, 5943.405659434022, 5944.227250457327, 5952.910706339322, 5669.015844454573, 5679.17651940458, 5692.601517893708, 5718.428157184415, 5730.255066841451, 5405.675659460445, 5587.357453892854, 5648.689322087324, 5663.66018038919, 5665.348484924287, 5803.274590676166, 5879.647681033472, 5894.498967587631, 5905.852353691193, 5916.645001299764, 3938.586448422874, 4073.89815254621, 4085.3871919212356, 4090.877273681794, 4101.343784994387, 5668.773249069991, 5734.2258795062135, 5756.510696590837, 5760.683162425939, 5770.567207459541, 5528.0, 5535.280413546213, 5543.473370029841, 5546.861328466821, 5547.8890422192135, 5356.705381203916, 5497.835064948058, 5541.750621221974, 5549.250851135206, 5552.167174923794, 5421.5933804964, 5447.564194864481, 5463.344398672185, 5466.316710411199, 5483.945160548344, 5348.78604855817, 5375.784968601369, 5513.5037846594, 5573.386927437898, 5574.0, 5445.292112025411, 5451.318585176853, 5458.9454105458435, 5461.371942226536, 5461.8361449155], "value": 4237.622870479239, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.7767508584967, 816.1471262530655, 872.725091596147, 873.1443185678036, 881.6076845803616, 15525.534233972985, 16209.270582823772, 16233.594160146002, 16282.75575866363, 16307.347706091758, 14609.853901460981, 14632.561023169323, 14646.560603181895, 14660.193689347081, 14672.339744711488, 675.9526833121683, 788.2432864450135, 812.1675282835082, 819.0580832043169, 826.3182874128838, 815.8368326334756, 820.7619790260817, 823.3248736036453, 823.5511646152861, 824.2375802382772, 873.0483772687769, 873.6418068591879, 875.1642181716445, 875.3828550871652, 875.5928493250638, 711.7188710459392, 839.1943734015322, 859.4113032572708, 860.096898256834, 869.443556124081, 626.408044398044, 627.1690010735748, 627.2473032361185, 627.3412916437723, 627.5669787846417, 14301.28493575317, 14359.78460323096, 14423.92788036053, 14588.27058647074, 14685.265736713229, 13872.4451021959, 14286.499972500913, 14288.714225715532, 14290.071145375518, 14366.13803171813, 12635.620931371981, 12707.301098439582, 12718.0, 12760.170588911691, 12784.0, 4421.447319085114, 4444.73331600105, 4463.352813841975, 4465.0, 4520.751358675271, 52432.47567524314, 54070.18894716582, 54181.72909135429, 59500.10749838713, 60842.0, 11528.711782205512, 11783.823242651368, 11851.585194518151, 11987.0, 12170.695732606755, 18605.348812791486, 19969.800301997086, 20017.199312027355, 20023.199072036954, 20090.69863952042, 8507.702230421906, 8672.349573781992, 8819.691310804219, 8900.065493123258, 9034.096590341045, 4614.723116613014, 4776.116418462596, 4777.713337199779, 4834.468208497063, 5036.496350364934, 3404.846781894771, 3475.0, 3485.5120283160377, 3489.703375213135, 3491.546099007113, 598.7724664627414, 643.5205771700114, 661.5237029338869, 667.3926726678699, 687.4740823270203, 686.7946753447767, 722.8048426924701, 729.580491217552, 764.5833021003566, 790.2768966395683, 10317.84523232152, 10377.792444151075, 10522.0, 10534.947325263478, 10558.260921735558, 771.1440301265591, 789.4710543935462, 793.3217099380063, 807.6688557691435, 810.3760104719333, 4621.042532779024, 4807.903841923142, 4974.925376119361, 4999.350084489066, 5107.7446127693465, 72406.63796680982, 72814.63592682003, 75742.24257757352, 76198.0, 76233.6188319066, 66175.66912165505, 66745.0, 66765.0, 68353.65823170952, 70231.0, 61036.0, 61626.38373616206, 61955.69022154951, 62020.37979620146, 62313.376866231665, 9556.187724043533, 9618.086281803497, 9656.468894210886, 9715.417074975525, 9809.705708828747, 39043.609563905135, 39381.0, 40257.99999999883, 40713.778586642446, 40941.0, 49563.50436495589, 53607.0, 54429.45570544244, 54543.0, 54964.228755980046, 11831.467583958914, 12400.31798251105, 12652.0, 12723.936380318039, 12778.552750653682, 14641.144976729436, 14732.116073035651, 14923.253837307873, 15330.156841373831, 15380.307886144927, 13321.666958325732, 14167.832794673443, 14178.787318190442, 14493.347799348843, 14622.41510339617, 12789.488420463078, 12836.358182091044, 13088.345582721016, 13155.342232888508, 13524.526641567498, 13037.608871733863, 13549.796753048515, 13901.0, 13912.652183695489, 13945.442182313, 11456.427178641201, 11524.769504609692, 11573.710657233636, 11769.646910592695, 11878.227915185485, 11397.650706576724, 11420.428978550874, 11562.306261623993, 11630.360330181922, 11776.764464710486], "value": 7275.881347182948, "unit": "score"}], "name": "dromaeo_css", "value": 7334.16382135102, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 132.0, 172.0, 124.0, 158.0, 150.0, 125.0, 185.0, 127.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 160.0, 159.0, 162.0, 160.0, 160.0, 159.0, 162.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 128.0, 75.0, 75.0, 75.0, 75.0, 97.0, 74.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 115.0, 84.0, 84.0, 84.0, 121.0, 83.0, 82.0, 124.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 53.0, 51.0, 52.0, 52.0, 52.0, 56.0, 51.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 63.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 79.0, 80.0, 79.0, 81.0, 80.0, 80.0, 116.0, 79.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 124.0, 126.0, 119.0, 119.0, 129.0, 129.0, 132.0, 137.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 163.0, 166.0, 162.0, 166.0, 166.0, 163.0, 160.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 64.0, 63.0, 63.0, 63.0, 67.0, 63.0, 63.0, 66.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1500.3, "alertThreshold": 2.0}]} 19:29:14 INFO - Return code: 0 19:29:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:29:14 ERROR - # TBPL SUCCESS # 19:29:14 INFO - Running post-action listener: _resource_record_post_action 19:29:14 INFO - ##### 19:29:14 INFO - ##### Finished run-tests step (success) 19:29:14 INFO - ##### 19:29:14 INFO - Running post-run listener: _resource_record_post_run 19:29:14 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 541384704; Read time: 12; Write time: 540872 19:29:14 INFO - TinderboxPrint: CPU usage
13.1% 19:29:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:29:14 INFO - TinderboxPrint: I/O write bytes / time
541,384,704 / 540,872 19:29:14 INFO - TinderboxPrint: CPU idle
8,257.8 (86.9%) 19:29:14 INFO - TinderboxPrint: CPU user
1,203.2 (12.7%) 19:29:14 INFO - TinderboxPrint: Swap in / out
0 / 0 19:29:14 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247132160; Read time: 0; Write time: 320432 19:29:14 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293994496; Read time: 12; Write time: 220440 19:29:15 INFO - Running post-run listener: _upload_blobber_files 19:29:15 INFO - Blob upload gear active. 19:29:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:15 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 19:29:15 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'] 19:29:15 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 19:29:15 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:16 INFO - (blobuploader) - INFO - Done attempting. 19:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:17 INFO - (blobuploader) - INFO - Done attempting. 19:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:29:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:21 INFO - (blobuploader) - INFO - Done attempting. 19:29:21 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:21 INFO - Return code: 0 19:29:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d89c61e26431c8d3e26b37b0163e313191e5ffedcb8f701ec4e426088b39da7297f19162055f912f115fd924c741edc0007498902b4ab67da9c9ddbc48ec62e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a769dd4fbdd44e5f2392cf585fc8b0bcbbe5929052f2fcbe8cdbf78178a3e196576eea168becda453a604391bab9ab89dff1a177992094ca7aea34550b19689", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5b34bf3e598851c8057e98bde1170bbf69fb0b09a6f06e77dfc5c20f54af6d85c7140fc24c9fddd92caa3903d2379d0913d44ea7849dbb9e517ae307aad85de"} 19:29:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:21 INFO - Contents: 19:29:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d89c61e26431c8d3e26b37b0163e313191e5ffedcb8f701ec4e426088b39da7297f19162055f912f115fd924c741edc0007498902b4ab67da9c9ddbc48ec62e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a769dd4fbdd44e5f2392cf585fc8b0bcbbe5929052f2fcbe8cdbf78178a3e196576eea168becda453a604391bab9ab89dff1a177992094ca7aea34550b19689", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5b34bf3e598851c8057e98bde1170bbf69fb0b09a6f06e77dfc5c20f54af6d85c7140fc24c9fddd92caa3903d2379d0913d44ea7849dbb9e517ae307aad85de"} 19:29:21 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:21 INFO - Copying logs to upload dir... 19:29:21 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.580639 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-27 19:29:21.703696) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 19:29:21.711903) ========= 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=62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832 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/3d89c61e26431c8d3e26b37b0163e313191e5ffedcb8f701ec4e426088b39da7297f19162055f912f115fd924c741edc0007498902b4ab67da9c9ddbc48ec62e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a769dd4fbdd44e5f2392cf585fc8b0bcbbe5929052f2fcbe8cdbf78178a3e196576eea168becda453a604391bab9ab89dff1a177992094ca7aea34550b19689", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5b34bf3e598851c8057e98bde1170bbf69fb0b09a6f06e77dfc5c20f54af6d85c7140fc24c9fddd92caa3903d2379d0913d44ea7849dbb9e517ae307aad85de"} build_url:https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/OSctqcqIQAG94_36ePbnPw/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/3d89c61e26431c8d3e26b37b0163e313191e5ffedcb8f701ec4e426088b39da7297f19162055f912f115fd924c741edc0007498902b4ab67da9c9ddbc48ec62e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a769dd4fbdd44e5f2392cf585fc8b0bcbbe5929052f2fcbe8cdbf78178a3e196576eea168becda453a604391bab9ab89dff1a177992094ca7aea34550b19689", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5b34bf3e598851c8057e98bde1170bbf69fb0b09a6f06e77dfc5c20f54af6d85c7140fc24c9fddd92caa3903d2379d0913d44ea7849dbb9e517ae307aad85de"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 19:29:21.744034) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:29:21.744701) ========= 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=62c568580acbfdaac3eec7be000001dc-1464395281.776362-1116923832 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 19:29:21.820173) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 19:29:21.820755) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 19:29:21.821107) ========= ========= Total master_lag: 0.19 =========