builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1469650316.23 results: success (0) buildid: 20160727103103 builduid: 2092d995505549febb39202a6f5e9dbe revision: fef429fba4c64c5b9c0c823a6ab713edbbcd4220 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.228511) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.229067) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.229407) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.263797) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.264403) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 13:11:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.6M=0s 2016-07-27 13:11:56 (35.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132222 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.411617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.411985) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015734 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.461883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:56.462251) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fef429fba4c64c5b9c0c823a6ab713edbbcd4220 --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 fef429fba4c64c5b9c0c823a6ab713edbbcd4220 --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=7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 13:11:56,598 truncating revision to first 12 chars 2016-07-27 13:11:56,598 Setting DEBUG logging. 2016-07-27 13:11:56,598 attempt 1/10 2016-07-27 13:11:56,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fef429fba4c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 13:11:56,920 unpacking tar archive at: mozilla-central-fef429fba4c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.652175 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:57.130398) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:57.131052) ========= script_repo_revision: fef429fba4c64c5b9c0c823a6ab713edbbcd4220 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:57.131762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:57.132249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 13:11:57.145308) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-27 13:11:57.145960) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:11:57 INFO - MultiFileLogger online at 20160727 13:11:57 in /builds/slave/test 13:11:57 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 13:11:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:11:57 INFO - {'append_to_log': False, 13:11:57 INFO - 'base_work_dir': '/builds/slave/test', 13:11:57 INFO - 'blob_upload_branch': 'Firefox', 13:11:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:11:57 INFO - 'branch': 'Firefox', 13:11:57 INFO - 'buildbot_json_path': 'buildprops.json', 13:11:57 INFO - 'config_files': ('talos/linux_config.py',), 13:11:57 INFO - 'default_actions': ('clobber', 13:11:57 INFO - 'read-buildbot-config', 13:11:57 INFO - 'download-and-extract', 13:11:57 INFO - 'populate-webroot', 13:11:57 INFO - 'create-virtualenv', 13:11:57 INFO - 'install', 13:11:57 INFO - 'run-tests'), 13:11:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:11:57 INFO - 'download_minidump_stackwalk': True, 13:11:57 INFO - 'download_symbols': 'ondemand', 13:11:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:11:57 INFO - 'tooltool.py': '/tools/tooltool.py', 13:11:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:11:57 INFO - '/tools/misc-python/virtualenv.py')}, 13:11:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:11:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:11:57 INFO - 'installer_path': 'installer.exe', 13:11:57 INFO - 'log_level': 'info', 13:11:57 INFO - 'log_name': 'talos', 13:11:57 INFO - 'log_to_console': True, 13:11:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:11:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:11:57 INFO - 'opt_config_files': (), 13:11:57 INFO - 'pip_index': False, 13:11:57 INFO - 'sps_profile': False, 13:11:57 INFO - 'sps_profile_interval': 0, 13:11:57 INFO - 'suite': 'dromaeojs', 13:11:57 INFO - 'system_bits': '32', 13:11:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:11:57 INFO - 'title': 'talos-linux64-ix-005', 13:11:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:11:57 INFO - 'use_talos_json': True, 13:11:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:11:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:11:57 INFO - 'work_dir': 'build'} 13:11:57 INFO - [mozharness: 2016-07-27 20:11:57.373094Z] Running clobber step. 13:11:57 INFO - Running pre-action listener: _resource_record_pre_action 13:11:57 INFO - Running main action method: clobber 13:11:57 INFO - rmtree: /builds/slave/test/build 13:11:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:11:57 INFO - Running post-action listener: _resource_record_post_action 13:11:57 INFO - [mozharness: 2016-07-27 20:11:57.842829Z] Finished clobber step (success) 13:11:57 INFO - [mozharness: 2016-07-27 20:11:57.842929Z] Running read-buildbot-config step. 13:11:57 INFO - Running pre-action listener: _resource_record_pre_action 13:11:57 INFO - Running main action method: read_buildbot_config 13:11:57 INFO - Using buildbot properties: 13:11:57 INFO - { 13:11:57 INFO - "project": "", 13:11:57 INFO - "product": "firefox", 13:11:57 INFO - "script_repo_revision": "production", 13:11:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 13:11:57 INFO - "repository": "", 13:11:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 13:11:57 INFO - "buildid": "20160727103103", 13:11:57 INFO - "pgo_build": "True", 13:11:57 INFO - "basedir": "/builds/slave/test", 13:11:57 INFO - "buildnumber": 511, 13:11:57 INFO - "slavename": "talos-linux64-ix-005", 13:11:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:11:57 INFO - "platform": "ubuntu64_hw", 13:11:57 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 13:11:57 INFO - "branch": "mozilla-central", 13:11:57 INFO - "repo_path": "mozilla-central", 13:11:57 INFO - "revision": "fef429fba4c64c5b9c0c823a6ab713edbbcd4220", 13:11:57 INFO - "stage_platform": "linux64-pgo", 13:11:57 INFO - "builduid": "2092d995505549febb39202a6f5e9dbe", 13:11:57 INFO - "slavebuilddir": "test" 13:11:57 INFO - } 13:11:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 13:11:57 INFO - Running post-action listener: _resource_record_post_action 13:11:57 INFO - [mozharness: 2016-07-27 20:11:57.845368Z] Finished read-buildbot-config step (success) 13:11:57 INFO - [mozharness: 2016-07-27 20:11:57.845458Z] Running download-and-extract step. 13:11:57 INFO - Running pre-action listener: _resource_record_pre_action 13:11:57 INFO - Running main action method: download_and_extract 13:11:57 INFO - mkdir: /builds/slave/test/build/tests 13:11: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')]} 13:11:57 INFO - https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:11:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:11:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:11:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:11:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:12:01 INFO - Downloaded 1361 bytes. 13:12:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 13:12:01 INFO - Using the following test package requirements: 13:12:01 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:12:01 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:12:01 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:12:01 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'jsshell-linux-x86_64.zip'], 13:12:01 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:12:01 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 13:12:01 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:12:01 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:12:01 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:12:01 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 13:12:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:12:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:12: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')]} 13:12:01 INFO - https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:12:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:12:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:12:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:12:05 INFO - Downloaded 24318262 bytes. 13:12:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:12:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:12:06 INFO - Return code: 0 13:12:06 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:06 INFO - https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:12:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:12:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:12:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 13:12:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:12:08 INFO - Downloaded 24318262 bytes. 13:12:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:12:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:12:10 INFO - Return code: 0 13:12: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')]} 13:12:10 INFO - https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:12:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:12:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:12:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 13:12:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:12:11 INFO - Downloaded 14413725 bytes. 13:12:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:12:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 13:12:11 INFO - Return code: 0 13:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:11 INFO - https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:12:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:12:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:12:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:12:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:12:12 INFO - Downloaded 60993181 bytes. 13:12:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:12:12 INFO - mkdir: /builds/slave/test/properties 13:12:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:12:12 INFO - Writing to file /builds/slave/test/properties/build_url 13:12:12 INFO - Contents: 13:12:12 INFO - build_url:https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 13:12:13 INFO - Running post-action listener: _resource_record_post_action 13:12:13 INFO - Running post-action listener: set_extra_try_arguments 13:12:13 INFO - [mozharness: 2016-07-27 20:12:13.376138Z] Finished download-and-extract step (success) 13:12:13 INFO - [mozharness: 2016-07-27 20:12:13.376371Z] Running populate-webroot step. 13:12:13 INFO - Running pre-action listener: _resource_record_pre_action 13:12:13 INFO - Running main action method: populate_webroot 13:12:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:12:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:12:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:12:13 INFO - '--tppagecycles', 13:12:13 INFO - '7'], 13:12:13 INFO - 'tests': ['tsvgm']}}, 13:12:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:12:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:12:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:12:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:12:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['damp', 'tps']}, 13:12:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'tests': ['damp', 'tps']}, 13:12:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['dromaeo_dom']}, 13:12:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:12:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['basic_compositor_video']}, 13:12:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:12:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['a11yr', 13:12:13 INFO - 'ts_paint', 13:12:13 INFO - 'tpaint', 13:12:13 INFO - 'sessionrestore', 13:12:13 INFO - 'sessionrestore_no_auto_restore', 13:12:13 INFO - 'tabpaint']}, 13:12:13 INFO - 'other-e10s': {'tests': ['a11yr', 13:12:13 INFO - 'ts_paint', 13:12:13 INFO - 'tpaint', 13:12:13 INFO - 'sessionrestore', 13:12:13 INFO - 'sessionrestore_no_auto_restore', 13:12:13 INFO - 'tabpaint']}, 13:12:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:12:13 INFO - 'ts_paint', 13:12:13 INFO - 'tpaint', 13:12:13 INFO - 'sessionrestore', 13:12:13 INFO - 'sessionrestore_no_auto_restore', 13:12:13 INFO - 'tabpaint']}, 13:12:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:12:13 INFO - 'ts_paint', 13:12:13 INFO - 'tpaint', 13:12:13 INFO - 'sessionrestore', 13:12:13 INFO - 'sessionrestore_no_auto_restore', 13:12:13 INFO - 'tabpaint']}, 13:12:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['a11yr', 13:12:13 INFO - 'ts_paint', 13:12:13 INFO - 'tpaint', 13:12:13 INFO - 'sessionrestore', 13:12:13 INFO - 'sessionrestore_no_auto_restore', 13:12:13 INFO - 'tabpaint']}, 13:12:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['a11yr', 13:12:13 INFO - 'ts_paint', 13:12:13 INFO - 'tpaint', 13:12:13 INFO - 'sessionrestore', 13:12:13 INFO - 'sessionrestore_no_auto_restore', 13:12:13 INFO - 'tabpaint']}, 13:12:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['tsvgx', 13:12:13 INFO - 'tsvgr_opacity', 13:12:13 INFO - 'tart', 13:12:13 INFO - 'tscrollx', 13:12:13 INFO - 'cart']}, 13:12:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:12:13 INFO - 'tsvgr_opacity', 13:12:13 INFO - 'tart', 13:12:13 INFO - 'tscrollx', 13:12:13 INFO - 'cart']}, 13:12:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'talos_options': ['--disable-e10s'], 13:12:13 INFO - 'tests': ['tp5o']}, 13:12:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'tests': ['tp5o']}, 13:12:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'talos_options': ['--disable-e10s', 13:12:13 INFO - '--xperf_path', 13:12:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:13 INFO - 'tests': ['tp5n']}, 13:12:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:13 INFO - 'talos_options': ['--xperf_path', 13:12:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:13 INFO - 'tests': ['tp5n']}}, 13:12:13 INFO - 'talos.zip': {'path': '', 13:12:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:12:13 INFO - Running post-action listener: _resource_record_post_action 13:12:13 INFO - [mozharness: 2016-07-27 20:12:13.397795Z] Finished populate-webroot step (success) 13:12:13 INFO - [mozharness: 2016-07-27 20:12:13.397885Z] Running create-virtualenv step. 13:12:13 INFO - Running pre-action listener: _resource_record_pre_action 13:12:13 INFO - Running main action method: create_virtualenv 13:12:13 INFO - Creating virtualenv /builds/slave/test/build/venv 13:12:13 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 13:12:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:12:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:12:13 INFO - Using real prefix '/usr' 13:12:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:12:14 INFO - Installing distribute.............................................................................................................................................................................................done. 13:12:16 INFO - Installing pip.................done. 13:12:16 INFO - Return code: 0 13:12:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:12:16 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')]} 13:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:16 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')]} 13:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:16 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:16 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 13:12:16 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 13:12:16 INFO - Using env: {'DISPLAY': ':0', 13:12:16 INFO - 'HOME': '/home/cltbld', 13:12:16 INFO - 'LANG': 'en_US.UTF-8', 13:12:16 INFO - 'LANGUAGE': 'en_US:en', 13:12:16 INFO - 'LOGNAME': 'cltbld', 13:12:16 INFO - 'MAIL': '/var/mail/cltbld', 13:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:16 INFO - 'MOZ_NO_REMOTE': '1', 13:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:16 INFO - 'NO_EM_RESTART': '1', 13:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:16 INFO - 'PWD': '/builds/slave/test', 13:12:16 INFO - 'SHELL': '/bin/bash', 13:12:16 INFO - 'SHLVL': '1', 13:12:16 INFO - 'TERM': 'linux', 13:12:16 INFO - 'TMOUT': '86400', 13:12:16 INFO - 'USER': 'cltbld', 13:12:16 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:16 INFO - '_': '/tools/buildbot/bin/python'} 13:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:16 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 13:12:16 INFO - Cleaning up... 13:12:16 INFO - Return code: 0 13:12:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:12:16 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')]} 13:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:16 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')]} 13:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:16 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:16 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 13:12:16 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 13:12:16 INFO - Using env: {'DISPLAY': ':0', 13:12:16 INFO - 'HOME': '/home/cltbld', 13:12:16 INFO - 'LANG': 'en_US.UTF-8', 13:12:16 INFO - 'LANGUAGE': 'en_US:en', 13:12:16 INFO - 'LOGNAME': 'cltbld', 13:12:16 INFO - 'MAIL': '/var/mail/cltbld', 13:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:16 INFO - 'MOZ_NO_REMOTE': '1', 13:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:16 INFO - 'NO_EM_RESTART': '1', 13:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:16 INFO - 'PWD': '/builds/slave/test', 13:12:16 INFO - 'SHELL': '/bin/bash', 13:12:16 INFO - 'SHLVL': '1', 13:12:16 INFO - 'TERM': 'linux', 13:12:16 INFO - 'TMOUT': '86400', 13:12:16 INFO - 'USER': 'cltbld', 13:12:16 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:16 INFO - '_': '/tools/buildbot/bin/python'} 13:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:16 INFO - Downloading/unpacking psutil>=3.1.1 13:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:12:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:18 INFO - Installing collected packages: psutil 13:12:18 INFO - Running setup.py install for psutil 13:12:18 INFO - building 'psutil._psutil_linux' extension 13:12:18 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 13:12:18 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 13:12:18 INFO - building 'psutil._psutil_posix' extension 13:12:18 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 13:12:18 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 13:12:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:18 INFO - Successfully installed psutil 13:12:18 INFO - Cleaning up... 13:12:19 INFO - Return code: 0 13:12:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:12:19 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')]} 13:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:19 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')]} 13:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:19 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:19 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 13:12:19 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 13:12:19 INFO - Using env: {'DISPLAY': ':0', 13:12:19 INFO - 'HOME': '/home/cltbld', 13:12:19 INFO - 'LANG': 'en_US.UTF-8', 13:12:19 INFO - 'LANGUAGE': 'en_US:en', 13:12:19 INFO - 'LOGNAME': 'cltbld', 13:12:19 INFO - 'MAIL': '/var/mail/cltbld', 13:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:19 INFO - 'MOZ_NO_REMOTE': '1', 13:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:19 INFO - 'NO_EM_RESTART': '1', 13:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:19 INFO - 'PWD': '/builds/slave/test', 13:12:19 INFO - 'SHELL': '/bin/bash', 13:12:19 INFO - 'SHLVL': '1', 13:12:19 INFO - 'TERM': 'linux', 13:12:19 INFO - 'TMOUT': '86400', 13:12:19 INFO - 'USER': 'cltbld', 13:12:19 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:19 INFO - '_': '/tools/buildbot/bin/python'} 13:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:12:21 INFO - Installing collected packages: mozsystemmonitor 13:12:21 INFO - Running setup.py install for mozsystemmonitor 13:12:21 INFO - Successfully installed mozsystemmonitor 13:12:21 INFO - Cleaning up... 13:12:21 INFO - Return code: 0 13:12:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:12:21 INFO - Using env: {'DISPLAY': ':0', 13:12:21 INFO - 'HOME': '/home/cltbld', 13:12:21 INFO - 'LANG': 'en_US.UTF-8', 13:12:21 INFO - 'LANGUAGE': 'en_US:en', 13:12:21 INFO - 'LOGNAME': 'cltbld', 13:12:21 INFO - 'MAIL': '/var/mail/cltbld', 13:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:21 INFO - 'MOZ_NO_REMOTE': '1', 13:12:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:21 INFO - 'NO_EM_RESTART': '1', 13:12:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:21 INFO - 'PWD': '/builds/slave/test', 13:12:21 INFO - 'SHELL': '/bin/bash', 13:12:21 INFO - 'SHLVL': '1', 13:12:21 INFO - 'TERM': 'linux', 13:12:21 INFO - 'TMOUT': '86400', 13:12:21 INFO - 'USER': 'cltbld', 13:12:21 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:21 INFO - '_': '/tools/buildbot/bin/python'} 13:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:21 INFO - Downloading/unpacking jsonschema==2.5.1 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:12:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:23 INFO - Downloading functools32-3.2.3-2.tar.gz 13:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:12:23 INFO - warning: no files found matching '*.txt' 13:12:23 INFO - no previously-included directories found matching 'build' 13:12:23 INFO - no previously-included directories found matching 'dist' 13:12:23 INFO - no previously-included directories found matching '.git*' 13:12:23 INFO - Installing collected packages: jsonschema, functools32 13:12:23 INFO - Running setup.py install for functools32 13:12:23 INFO - warning: no files found matching '*.txt' 13:12:23 INFO - no previously-included directories found matching 'build' 13:12:23 INFO - no previously-included directories found matching 'dist' 13:12:23 INFO - no previously-included directories found matching '.git*' 13:12:23 INFO - Successfully installed jsonschema functools32 13:12:23 INFO - Cleaning up... 13:12:23 INFO - Return code: 0 13:12:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:12:23 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')]} 13:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:23 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')]} 13:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:23 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:23 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:12:23 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 functools32==3.2.3-2 13:12:23 INFO - Using env: {'DISPLAY': ':0', 13:12:23 INFO - 'HOME': '/home/cltbld', 13:12:23 INFO - 'LANG': 'en_US.UTF-8', 13:12:23 INFO - 'LANGUAGE': 'en_US:en', 13:12:23 INFO - 'LOGNAME': 'cltbld', 13:12:23 INFO - 'MAIL': '/var/mail/cltbld', 13:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:23 INFO - 'MOZ_NO_REMOTE': '1', 13:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:23 INFO - 'NO_EM_RESTART': '1', 13:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:23 INFO - 'PWD': '/builds/slave/test', 13:12:23 INFO - 'SHELL': '/bin/bash', 13:12:23 INFO - 'SHLVL': '1', 13:12:23 INFO - 'TERM': 'linux', 13:12:23 INFO - 'TMOUT': '86400', 13:12:23 INFO - 'USER': 'cltbld', 13:12:23 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:23 INFO - '_': '/tools/buildbot/bin/python'} 13:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:12:24 INFO - Cleaning up... 13:12:24 INFO - Return code: 0 13:12:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:12:24 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')]} 13:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:24 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')]} 13:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:24 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:24 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 13:12:24 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 13:12:24 INFO - Using env: {'DISPLAY': ':0', 13:12:24 INFO - 'HOME': '/home/cltbld', 13:12:24 INFO - 'LANG': 'en_US.UTF-8', 13:12:24 INFO - 'LANGUAGE': 'en_US:en', 13:12:24 INFO - 'LOGNAME': 'cltbld', 13:12:24 INFO - 'MAIL': '/var/mail/cltbld', 13:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:24 INFO - 'MOZ_NO_REMOTE': '1', 13:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:24 INFO - 'NO_EM_RESTART': '1', 13:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:24 INFO - 'PWD': '/builds/slave/test', 13:12:24 INFO - 'SHELL': '/bin/bash', 13:12:24 INFO - 'SHLVL': '1', 13:12:24 INFO - 'TERM': 'linux', 13:12:24 INFO - 'TMOUT': '86400', 13:12:24 INFO - 'USER': 'cltbld', 13:12:24 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:24 INFO - '_': '/tools/buildbot/bin/python'} 13:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:24 INFO - Downloading/unpacking blobuploader==1.2.4 13:12:24 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 13:12:24 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 13:12:24 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 13:12:24 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 13:12:24 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 13:12:24 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 13:12:26 INFO - Downloading blobuploader-1.2.4.tar.gz 13:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:12:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:12:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 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 13:12:26 INFO - Downloading docopt-0.6.1.tar.gz 13:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:12:27 INFO - Installing collected packages: blobuploader, requests, docopt 13:12:27 INFO - Running setup.py install for blobuploader 13:12:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:12:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:12:27 INFO - Running setup.py install for requests 13:12:27 INFO - Running setup.py install for docopt 13:12:27 INFO - Successfully installed blobuploader requests docopt 13:12:27 INFO - Cleaning up... 13:12:27 INFO - Return code: 0 13:12:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:27 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')]} 13:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:27 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')]} 13:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:27 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:27 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 13:12:27 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 13:12:27 INFO - Using env: {'DISPLAY': ':0', 13:12:27 INFO - 'HOME': '/home/cltbld', 13:12:27 INFO - 'LANG': 'en_US.UTF-8', 13:12:27 INFO - 'LANGUAGE': 'en_US:en', 13:12:27 INFO - 'LOGNAME': 'cltbld', 13:12:27 INFO - 'MAIL': '/var/mail/cltbld', 13:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:27 INFO - 'MOZ_NO_REMOTE': '1', 13:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:27 INFO - 'NO_EM_RESTART': '1', 13:12:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:27 INFO - 'PWD': '/builds/slave/test', 13:12:27 INFO - 'SHELL': '/bin/bash', 13:12:27 INFO - 'SHLVL': '1', 13:12:27 INFO - 'TERM': 'linux', 13:12:27 INFO - 'TMOUT': '86400', 13:12:27 INFO - 'USER': 'cltbld', 13:12:27 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:27 INFO - '_': '/tools/buildbot/bin/python'} 13:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:27 INFO - Running setup.py (path:/tmp/pip-dQAl2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:27 INFO - Running setup.py (path:/tmp/pip-4GvIje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:27 INFO - Running setup.py (path:/tmp/pip-3VSX79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:28 INFO - Running setup.py (path:/tmp/pip-CYqJsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:28 INFO - Running setup.py (path:/tmp/pip-oGBBMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:28 INFO - Running setup.py (path:/tmp/pip-0sdfzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:28 INFO - Running setup.py (path:/tmp/pip-R_H8_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:28 INFO - Running setup.py (path:/tmp/pip-gxTqn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:28 INFO - Running setup.py (path:/tmp/pip-awDJfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:28 INFO - Running setup.py (path:/tmp/pip-G0u8ZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:28 INFO - Running setup.py (path:/tmp/pip-OBrr2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:28 INFO - Running setup.py (path:/tmp/pip-YZ6Ga5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:28 INFO - Running setup.py (path:/tmp/pip-k_FnqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:28 INFO - Running setup.py (path:/tmp/pip-iazzEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:28 INFO - Running setup.py (path:/tmp/pip-oRgNM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:28 INFO - Running setup.py (path:/tmp/pip-CWIZ5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:28 INFO - Running setup.py (path:/tmp/pip-pyhTBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:12:29 INFO - Running setup.py install for manifestparser 13:12:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozcrash 13:12:29 INFO - Running setup.py install for mozdebug 13:12:29 INFO - Running setup.py install for mozdevice 13:12:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozfile 13:12:29 INFO - Running setup.py install for mozhttpd 13:12:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozinfo 13:12:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozInstall 13:12:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:12:29 INFO - Running setup.py install for mozleak 13:12:29 INFO - Running setup.py install for mozlog 13:12:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Running setup.py install for moznetwork 13:12:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Running setup.py install for mozprocess 13:12:30 INFO - Running setup.py install for mozprofile 13:12:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Running setup.py install for mozrunner 13:12:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Running setup.py install for mozscreenshot 13:12:30 INFO - Running setup.py install for moztest 13:12:30 INFO - Running setup.py install for mozversion 13:12:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:12:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:12:30 INFO - Cleaning up... 13:12:30 INFO - Return code: 0 13:12:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:30 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:30 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 13:12:30 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 13:12:30 INFO - Using env: {'DISPLAY': ':0', 13:12:30 INFO - 'HOME': '/home/cltbld', 13:12:30 INFO - 'LANG': 'en_US.UTF-8', 13:12:30 INFO - 'LANGUAGE': 'en_US:en', 13:12:30 INFO - 'LOGNAME': 'cltbld', 13:12:30 INFO - 'MAIL': '/var/mail/cltbld', 13:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:30 INFO - 'MOZ_NO_REMOTE': '1', 13:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:30 INFO - 'NO_EM_RESTART': '1', 13:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:30 INFO - 'PWD': '/builds/slave/test', 13:12:30 INFO - 'SHELL': '/bin/bash', 13:12:30 INFO - 'SHLVL': '1', 13:12:30 INFO - 'TERM': 'linux', 13:12:30 INFO - 'TMOUT': '86400', 13:12:30 INFO - 'USER': 'cltbld', 13:12:30 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:30 INFO - '_': '/tools/buildbot/bin/python'} 13:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:31 INFO - Running setup.py (path:/tmp/pip-Ud3SAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:31 INFO - Running setup.py (path:/tmp/pip-fnD_Lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:31 INFO - Running setup.py (path:/tmp/pip-tcUxtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:31 INFO - Running setup.py (path:/tmp/pip-zoTXni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:31 INFO - Running setup.py (path:/tmp/pip-KwIqqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:31 INFO - Running setup.py (path:/tmp/pip-oEbbQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:31 INFO - Running setup.py (path:/tmp/pip-oMGuZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:31 INFO - Running setup.py (path:/tmp/pip-GQqoR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:31 INFO - Running setup.py (path:/tmp/pip-QVKw1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:31 INFO - Running setup.py (path:/tmp/pip-Jy85cV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:31 INFO - Running setup.py (path:/tmp/pip-DbmCFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:31 INFO - Running setup.py (path:/tmp/pip-R4MWF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:31 INFO - Running setup.py (path:/tmp/pip-aoNTNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:31 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)) 13:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:31 INFO - Running setup.py (path:/tmp/pip-WuKgnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 13:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:32 INFO - Running setup.py (path:/tmp/pip-m5h750-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:32 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)) 13:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:32 INFO - Running setup.py (path:/tmp/pip-qYzUgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:32 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)) 13:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:32 INFO - Running setup.py (path:/tmp/pip-tTo7cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 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)) 13:12:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:12:32 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 13:12:32 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 13:12:32 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 13:12:32 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 13:12:32 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 13:12:32 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 13:12:34 INFO - Downloading blessings-1.6.tar.gz 13:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:12:34 INFO - Installing collected packages: blessings 13:12:34 INFO - Running setup.py install for blessings 13:12:34 INFO - Successfully installed blessings 13:12:34 INFO - Cleaning up... 13:12:34 INFO - Return code: 0 13:12:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:12:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:34 INFO - Reading from file tmpfile_stdout 13:12:34 INFO - Current package versions: 13:12:34 INFO - argparse == 1.2.1 13:12:34 INFO - blessings == 1.6 13:12:34 INFO - blobuploader == 1.2.4 13:12:34 INFO - docopt == 0.6.1 13:12:34 INFO - functools32 == 3.2.3-2 13:12:34 INFO - jsonschema == 2.5.1 13:12:34 INFO - manifestparser == 1.1 13:12:34 INFO - mozInstall == 1.12 13:12:34 INFO - mozcrash == 0.17 13:12:34 INFO - mozdebug == 0.1 13:12:34 INFO - mozdevice == 0.48 13:12:34 INFO - mozfile == 1.2 13:12:34 INFO - mozhttpd == 0.7 13:12:34 INFO - mozinfo == 0.9 13:12:34 INFO - mozleak == 0.1 13:12:34 INFO - mozlog == 3.3 13:12:34 INFO - moznetwork == 0.27 13:12:34 INFO - mozprocess == 0.23 13:12:34 INFO - mozprofile == 0.28 13:12:34 INFO - mozrunner == 6.12 13:12:34 INFO - mozscreenshot == 0.1 13:12:34 INFO - mozsystemmonitor == 0.3 13:12:34 INFO - moztest == 0.7 13:12:34 INFO - mozversion == 1.4 13:12:34 INFO - psutil == 3.1.1 13:12:34 INFO - requests == 1.2.3 13:12:34 INFO - wsgiref == 0.1.2 13:12:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12: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')]} 13:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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')]} 13:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:34 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:34 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 13:12:34 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 13:12:34 INFO - Using env: {'DISPLAY': ':0', 13:12:34 INFO - 'HOME': '/home/cltbld', 13:12:34 INFO - 'LANG': 'en_US.UTF-8', 13:12:34 INFO - 'LANGUAGE': 'en_US:en', 13:12:34 INFO - 'LOGNAME': 'cltbld', 13:12:34 INFO - 'MAIL': '/var/mail/cltbld', 13:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:34 INFO - 'MOZ_NO_REMOTE': '1', 13:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:34 INFO - 'NO_EM_RESTART': '1', 13:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:34 INFO - 'PWD': '/builds/slave/test', 13:12:34 INFO - 'SHELL': '/bin/bash', 13:12:34 INFO - 'SHLVL': '1', 13:12:34 INFO - 'TERM': 'linux', 13:12:34 INFO - 'TMOUT': '86400', 13:12:34 INFO - 'USER': 'cltbld', 13:12:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:34 INFO - '_': '/tools/buildbot/bin/python'} 13:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 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)) 13:12:34 INFO - Cleaning up... 13:12:34 INFO - Return code: 0 13:12:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:12: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')]} 13:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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')]} 13:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:34 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 0x2443030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2704180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', '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 13:12:34 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 13:12:34 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 13:12:34 INFO - Using env: {'DISPLAY': ':0', 13:12:34 INFO - 'HOME': '/home/cltbld', 13:12:34 INFO - 'LANG': 'en_US.UTF-8', 13:12:34 INFO - 'LANGUAGE': 'en_US:en', 13:12:34 INFO - 'LOGNAME': 'cltbld', 13:12:34 INFO - 'MAIL': '/var/mail/cltbld', 13:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:34 INFO - 'MOZ_NO_REMOTE': '1', 13:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:34 INFO - 'NO_EM_RESTART': '1', 13:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:34 INFO - 'PWD': '/builds/slave/test', 13:12:34 INFO - 'SHELL': '/bin/bash', 13:12:34 INFO - 'SHLVL': '1', 13:12:34 INFO - 'TERM': 'linux', 13:12:34 INFO - 'TMOUT': '86400', 13:12:34 INFO - 'USER': 'cltbld', 13:12:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:34 INFO - '_': '/tools/buildbot/bin/python'} 13:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:12:35 INFO - Cleaning up... 13:12:35 INFO - Return code: 0 13:12:35 INFO - Running post-action listener: _resource_record_post_action 13:12:35 INFO - Running post-action listener: _start_resource_monitoring 13:12:35 INFO - Starting resource monitoring. 13:12:35 INFO - [mozharness: 2016-07-27 20:12:35.111292Z] Finished create-virtualenv step (success) 13:12:35 INFO - [mozharness: 2016-07-27 20:12:35.111770Z] Running install step. 13:12:35 INFO - Running pre-action listener: _resource_record_pre_action 13:12:35 INFO - Running main action method: install 13:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:35 INFO - Reading from file tmpfile_stdout 13:12:35 INFO - Detecting whether we're running mozinstall >=1.0... 13:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:12:35 INFO - Reading from file tmpfile_stdout 13:12:35 INFO - Output received: 13:12:35 INFO - Usage: mozinstall [options] installer 13:12:35 INFO - Options: 13:12:35 INFO - -h, --help show this help message and exit 13:12:35 INFO - -d DEST, --destination=DEST 13:12:35 INFO - Directory to install application into. [default: 13:12:35 INFO - "/builds/slave/test"] 13:12:35 INFO - --app=APP Application being installed. [default: firefox] 13:12:35 INFO - mkdir: /builds/slave/test/build/application 13:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:12:52 INFO - Reading from file tmpfile_stdout 13:12:52 INFO - Output received: 13:12:52 INFO - /builds/slave/test/build/application/firefox/firefox 13:12:52 INFO - Running post-action listener: _resource_record_post_action 13:12:52 INFO - [mozharness: 2016-07-27 20:12:52.690260Z] Finished install step (success) 13:12:52 INFO - [mozharness: 2016-07-27 20:12:52.690409Z] Running run-tests step. 13:12:52 INFO - Running pre-action listener: _resource_record_pre_action 13:12:52 INFO - Running main action method: run_tests 13:12:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:12:52 INFO - Python 2.7.3 13:12:52 INFO - Return code: 0 13:12:52 INFO - grabbing minidump binary from tooltool 13:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:52 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 0x27023b0>, '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 0x27032b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2703740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:12:52 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 13:12:52 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 13:12:52 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 13:12:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:12:52 INFO - Return code: 0 13:12:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:12:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:12:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:12:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:12:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:12:52 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/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 13:12:52 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/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 13:12:52 INFO - Using env: {'DISPLAY': ':0', 13:12:52 INFO - 'HOME': '/home/cltbld', 13:12:52 INFO - 'LANG': 'en_US.UTF-8', 13:12:52 INFO - 'LANGUAGE': 'en_US:en', 13:12:52 INFO - 'LOGNAME': 'cltbld', 13:12:52 INFO - 'MAIL': '/var/mail/cltbld', 13:12:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:12:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:52 INFO - 'MOZ_NO_REMOTE': '1', 13:12:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:52 INFO - 'NO_EM_RESTART': '1', 13:12:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:52 INFO - 'PWD': '/builds/slave/test', 13:12:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:12:52 INFO - 'SHELL': '/bin/bash', 13:12:52 INFO - 'SHLVL': '1', 13:12:52 INFO - 'TERM': 'linux', 13:12:52 INFO - 'TMOUT': '86400', 13:12:52 INFO - 'USER': 'cltbld', 13:12:52 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471', 13:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:52 INFO - '_': '/tools/buildbot/bin/python'} 13:12:52 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/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 13:12:52 INFO - mozversion application_buildid: 20160727103103 13:12:52 INFO - mozversion application_changeset: fef429fba4c64c5b9c0c823a6ab713edbbcd4220 13:12:52 INFO - mozversion application_display_name: Nightly 13:12:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:12:52 INFO - mozversion application_name: Firefox 13:12:52 INFO - mozversion application_remotingname: firefox 13:12:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:12:52 INFO - mozversion application_vendor: Mozilla 13:12:52 INFO - mozversion application_version: 50.0a1 13:12:52 INFO - mozversion platform_buildid: 20160727103103 13:12:52 INFO - mozversion platform_changeset: fef429fba4c64c5b9c0c823a6ab713edbbcd4220 13:12:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:12:52 INFO - mozversion platform_version: 50.0a1 13:12:52 INFO - using testdate: 1469650372 13:12:52 INFO - actual date: 1469650372 13:12:52 INFO - starting webserver on 'localhost:49048' 13:12:52 INFO - SUITE-START | Running 2 tests 13:12:52 INFO - TEST-START | dromaeo_css 13:12:52 INFO - operating with platform_type : linux_ 13:12:52 INFO - Initialising browser for dromaeo_css test... 13:12:52 INFO - TEST-INFO | started process 10652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDKj4ms/profile http://localhost:49048/getInfo.html) 13:12:56 INFO - PROCESS | 10652 | JavaScript error: , line 0: uncaught exception: undefined 13:12:56 INFO - PROCESS | 10652 | __metrics Screen width/height:1600/1200 13:12:56 INFO - PROCESS | 10652 | colorDepth:24 13:12:56 INFO - PROCESS | 10652 | Browser inner width/height: 1024/697 13:12:56 INFO - PROCESS | 10652 | __metrics 13:12:57 INFO - PROCESS | 10652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:12:57 INFO - PROCESS | 10652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:12:58 INFO - TEST-INFO | 10652: exit 0 13:12:58 INFO - Browser initialized. 13:12:58 INFO - Running cycle 1/1 for dromaeo_css test... 13:12:58 INFO - TEST-INFO | started process 10731 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDKj4ms/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:13:10 INFO - PROCESS | 10731 | RSS: Main: 157904896 13:13:10 INFO - PROCESS | 10731 | 13:14:01 INFO - PROCESS | 10731 | 1469650441379 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] 13:16:10 INFO - PROCESS | 10731 | Cycle 1(1): loaded http://localhost:49048/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49048/tests/dromaeo/cssquery-ext.html) 13:16:11 INFO - PROCESS | 10731 | RSS: Main: 248467456 13:16:11 INFO - PROCESS | 10731 | 13:19:13 INFO - PROCESS | 10731 | Cycle 1(1): loaded http://localhost:49048/tests/dromaeo/cssquery-ext.html (next: http://localhost:49048/tests/dromaeo/cssquery-jquery.html) 13:19:14 INFO - PROCESS | 10731 | RSS: Main: 249311232 13:19:14 INFO - PROCESS | 10731 | 13:22:16 INFO - PROCESS | 10731 | Cycle 1(1): loaded http://localhost:49048/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49048/tests/dromaeo/cssquery-mootools.html) 13:22:17 INFO - PROCESS | 10731 | RSS: Main: 185094144 13:22:17 INFO - PROCESS | 10731 | 13:25:19 INFO - PROCESS | 10731 | Cycle 1(1): loaded http://localhost:49048/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49048/tests/dromaeo/cssquery-prototype.html) 13:25:19 INFO - PROCESS | 10731 | RSS: Main: 185208832 13:25:19 INFO - PROCESS | 10731 | 13:28:22 INFO - PROCESS | 10731 | Cycle 1(1): loaded http://localhost:49048/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49048/tests/dromaeo/cssquery-yui.html) 13:28:22 INFO - PROCESS | 10731 | RSS: Main: 186118144 13:28:22 INFO - PROCESS | 10731 | 13:31:25 INFO - PROCESS | 10731 | Cycle 1(1): loaded http://localhost:49048/tests/dromaeo/cssquery-yui.html (next: http://localhost:49048/tests/dromaeo/cssquery-dojo.html) 13:31:26 INFO - PROCESS | 10731 | RSS: Main: 276176896 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | __start_tp_report 13:31:26 INFO - PROCESS | 10731 | _x_x_mozilla_page_load 13:31:26 INFO - PROCESS | 10731 | _x_x_mozilla_page_load_details 13:31:26 INFO - PROCESS | 10731 | |i|pagename|runs| 13:31:26 INFO - PROCESS | 10731 | |0;dojo.html;3097.5818264534287;3111.533270009497;3132.201288671389;3136.278655909141;3156.1951702315905;5282.286891269678;5378.435264297251;5384.596155288355;5429.972850135754;5492.231087647728;5490.846922146354;5507.614466987313;5512.173174023898;5564.610477266583;5577.107662773957;4944.060628480586;4953.851384458472;5017.0467611153945;5040.269160971675;5168.431472538018;5072.923906141411;5097.949020509803;5123.692578445287;5142;5146.356705411824;4914.70511769295;4967.304577359173;4970;5029.245613158019;5046.6467347285525;4948.356713627206;4958.231474121517;4972.055309491221;5069.619778516626;5101.438841727407;5014.047331007097;5018.774155163026;5026.949730502684;5032.974835125801;5043.924341134886;5280.445553216895;5379.1662292344745;5535.944640553583;5582.106862901916;5586.944130558683;5725.627834190765;5868.853278668024;5870.501007414375;5875.1187321901625;5890.145928840337;5854.121881717734;5859.560532960045;5925.762969481259;5927.140564618149;5932.377100404482;5342.652727572696;5388.515033646912;5410.756515956791;5465.836024919259;5514.310711161105;6021;6023.518118550505;6023.608465449732;6027.819165425044;6029.909551356734;5734.541236701054;5736.254286942671;5778.7688492460675;5784.421557844266;5789.421057894261;5690.857728556819;5810.389909059488;5860.912086318709;5861.736221870026;5878.118282257696;5716.914246286309;5764.5676574257095;5769.653820770767;5789.768409263583;5840.561957853178;5766.452187042224;5768.221290125779;5805.825825225165;5811.651300921873;5812.680302583314;4379.1022840317655;4437.800298986554;4438.489573699001;4446.444194475691;4481.887952801206;3910.7458015228917;3948.0919388540797;3976.363781794899;3978.1844721831953;4055.270051390778;5571.1086226203615;5571.164325351229;5576.888462230814;5579.944200558024;5580.888382232332;5899.882002359929;5909.881802363929;5916.763329466859;5916.852078697981;5954.761809527657;5311.893762124736;5412.485813847678;5447.291852059287;5461.972690136524;5527.613067085346;5016.423111342169;5021.046001259749;5021.347224860818;5022.020705962303;5055.9241611375855;5953.821385358447;5993.2508436446315;5994.520438364921;5994.790182343597;6013.30846952606;5635.802746904003;5779.133130030529;5799.246098007232;5832.387599302012;5906.0255057914965;6032.668203248688;6037.758489660365;6038.094285856981;6078.939210607838;6087.817365479043;5696.20253164549;5708.400617935057;5749.252597162342;5758;5761.740721667451;5671.744771485375;5823.242978412779;5838;5869.618474799125;5876.471117599436;5705.115707065412;5760.654360738369;5765.423457654201;5796.681182535001;5810.6513609183585;5729.197912292199;5741.540676745767;5742.942570574241;5749.568782341257;5765.942340576708;5541.33503979525;5656.519195868397;5688.658680479184;5694.17434471991;5717.399673034438;5826.475617194473;5889.587728858939;5891.057430811221;5891.587588868738;5910.970445147746;5680.176374425768;5680.289963754531;5680.630759000652;5684.261046063985;5686.545076393962 13:31:26 INFO - PROCESS | 10731 | |1;ext.html;605.0651743056976;608.254887762491;631.0691729698696;632.2318383164454;640.3660376227537;32299.67700322996;35078.824605876966;42591;43770.781146094225;43770.781146094305;39475;39937.40093898594;39972.80013599935;40014.7999260004;40034.599654003454;8845.425047371917;9039.909600903973;9043.09569043097;9044.8643270351;9047.683331083414;8863.335249856254;8889.73330800077;8919;8962.641494340218;8965.058668839776;8906.955465222665;8951.507667078307;8953.05992870749;8953.149450802164;8955.238804701608;8849.778755531132;8889.822203555925;8890.688825891095;8902.910970890272;8966.506842123681;8783.077776833405;8840.38117331787;8842.115788421172;8843;8845.557722113868;37481.25037499262;37537;37737.81131094354;37770.43344349837;37779;3300.884469043572;3305.6033276007033;3334.416477116495;3556.1287484566446;3664.9633503665154;32825.6717432825;32913.506297405555;32944.34111317784;33375.6662433375;34595.827020864985;4325.286327755916;4369.126174765028;4386.978065109686;4473.150101480741;4549.454065512126;357680;357867;358695;359045;361001.9999999974;55625.721871390386;56102.15846762302;56113.43886561082;56160;56186.7190664044;31861.362772744415;33152.83423582867;33182.502262466085;57562.71218643964;57868;14547.3453694584;15034.097954122788;15059.247037648074;15073.472428464951;15075.170865602278;10724.356538607708;10874.67375978722;10886.129109671207;10910.945445272722;10920.18098642605;19012.714809277873;19058.42824715261;19217.61564768697;19280.4215873524;19321.806781932282;6211;6245.718942647592;6353.968230158912;6463.64449955257;6574.178227721535;964.7491652170346;1037.8131936251395;1082.6156714366443;1113.6714669172613;1180.7107258721496;32248.516272255936;32358.514622280683;32589.674103258665;32647.347053059282;33229.836955706916;905.6965916417942;1068.6900798768424;1088.7169335972703;1092.7706330378323;1107.479484642217;15745.911053027703;16526.421575244807;16707.83292167087;16736.665266694843;16878.74681879773;568426;569191;572500;573271;573757;57237;57731.42268577345;57776;57998;58034.419655803744;1126.3636045634191;1242.8384310039637;1323.2788130468919;1335.4057444437326;1364.3996641477718;30085.398292034475;31701.841490793322;33085.83457082699;34601.653983459844;35146;55354.446455534926;55956;56009.15986260291;56051;56250;511707;515594;516112;517299;518540;27639.447211055263;28575.857120714263;28805.42389152247;30053.69946300509;39891.40162897617;46613.30080048734;46617;47128.29307560458;47417.76291118522;47851.76074119607;36215.63784362228;36337.81831090934;37230.469822101135;37535.43696844495;37563.81218093892;41317.793411032755;41491.37762933619;41586;42106.578934210265;43069;39518;39627.20745585015;40613.79693101515;40621.390679139244;40622.79688601538;33172.668273317926;37098.44352334663;37166.44250336193;37818;37964.24071518499;27653.723462765116;28163.155105346872;28294;28345.716542834307;28492.57261141126 13:31:26 INFO - PROCESS | 10731 | |2;jquery.html;364.4314868804663;375.9003863976045;378.4758110017626;387.4672325552365;412.0276148290037;14832.851671483295;18237.36169234077;19706.605867882652;20598.794012059876;20813.687794683094;18307.816921830778;18435.72346414805;18695.719564206513;18824.623507529843;18838.05809709516;5710.486056254947;5948.048312270038;5955.017422125344;5956.404359564052;5966.134910437984;5725.398833122525;5845.707714614274;5886.823395298148;5888.087346461284;5898.115282707586;5842.357340692521;5848.210491583624;5851.970740146293;5853.560982926277;5856.736446859902;5725.7995970141055;5747.683877386742;5878.706064696791;5878.823635290948;5892.852678683024;5146.562542183919;5252.395974462909;5253.816116435906;5255.6583822051825;5258.3689957205;22989.655155172684;23578.410539736473;23679;25095.87452062728;25862.22413327603;2484.0808900706675;2491.9750802491926;2649.7880169586388;2729.767969722576;3066.4940284852973;14864.479743208936;15220.238988050553;16027.839721602752;16086.517404477885;16093.275802588912;1896.3647178195322;2058.475088852348;2077.626027315083;2236.932892013242;2450.1669432393073;96128;100107;101145;101804.49097754539;102378;9397.01331360211;9561.235101192027;9890.554925028247;9983.954893757475;18170.45488635343;13413.865861341457;13467.326633668277;13471.393787279596;13487.460501580023;13494;7186.964065179641;7385.409167266605;7478.813029674302;7581.469297149257;7676.57778822159;6900.965495172492;7090.149182098181;7147.106611673541;7420.257974202536;7443.404527637776;4109.97945010273;4110.403991421227;4128.050548373891;4164.083901541656;4187.14163596462;1903.1816318982694;1952.8730632509125;1971.4578490914826;1981.5937732764748;1982.306192832526;894.3873446689039;997.7705127820642;1022.754538910656;1028.6502589119734;1046.874375074996;20065.699014514794;20544.28095016667;20713.171473140905;20719.585608288053;20941.267055652977;944.0983860413289;944.6552008516842;976.097110173087;979.6816034788723;983.3313346924017;9405.811883762286;10865.348079115278;11302.434878256054;11356.148288878367;11372.772544549063;495375;501292;503928;505497;509260;64965;87504;94668;95503;95540;981.9836469254287;1000.9549570269355;1030.7886883188928;1031.654395777423;1043.3062013760916;17753.378631747826;18274;18446.446606601825;18809;18949.14728837178;41601.583984160985;42740.572594274905;44031.779841100586;44627.55372446234;44892.77553612211;53348;61391.693041534505;62091.06863397143;63252;66379.66810165918;8297.419180657296;8479.872801907853;8637;8656.956715216636;9081.273498120003;12230.327331996825;12893.226406415644;13261.867381326063;13656.93171534136;13932.721345572829;10699.786004280026;11256.718582035515;11286.887131128584;11669.241499302518;11739.06087513014;11184.496697648789;11186.105111591216;11811.704707382385;11900.40497975115;12118.939405302917;11405.315681059163;11494.94252528732;11851.348175850411;11963.162578619413;12094.153409261267;8461.830763384822;8518.872216916627;8569.357298202784;8627.22354988054;8750.737477875675;10225.795484090426;11679.591214307458;11744.119191060534;11865.110116741107;11903.107266955187 13:31:26 INFO - PROCESS | 10731 | |3;mootools.html;1431.6277767780377;1498.8351281359048;1587.1350114187767;1733.4366330942446;1750.5011071844526;8443.240108390244;9023.323250756195;9035.277177825774;9048.276137908968;9055.094490550959;7607.087149542049;7843.215678432154;7857.882131768028;8009.2391222833885;8740.169683880036;2262.230841513885;2341.672165896776;2352.3178278299274;2356.976430235693;2363.385519764865;2293.575688497625;2340.8946597403074;2351.7177938647387;2369.099742098;2372.41875740443;2329.056732023533;2340.754220806816;2345.2260753951155;2346.3430239532872;2347.6830627865274;2293.1286111277773;2294.0823670531836;2297.3911913342977;2300.1029598456616;2300.1949317738827;2237.8097861681777;2283.1438210671;2290.4617414907484;2290.484640955788;2295;8701.260392866614;8812.471251724917;8825.382223244376;8837.160469755363;8837.646494140226;4991.7753701083175;5073.289739436482;5103.770330335106;5119.513646203604;5124.461931497176;3184.3949649566507;3315.602127744662;3345.2640419107756;3354.563906692139;3358.9160270993175;1130.9604163854226;1178.298912147271;1184.8696643369221;1201.0451690905663;1204.7951848185742;12102;12136.939315303456;12148.514059437612;12156.756864862653;12171.817422738577;4340.088581397942;4455.309427038814;4574.977125114419;4600.309953506934;4603.263477843596;10254.28220024606;10264.897351026544;10270.178385729123;10275.229357798195;10300.742481438025;5068.543831055223;5104.821331253388;5107.336046313955;5107.948920510822;5108.361454818148;3768.9623103768613;3902.180542086137;4704.317873908332;4705.811767529329;4705.835295764632;3881.9029524261555;3914.3345631242187;3922.8234729437245;3974.7813870237414;4010.2981978153707;1237.412229191127;1238.7646347194004;1241.118805648;1247.95008199671;1255.3911352993791;910.8952470465849;934.5327336331834;958.8274110660149;968.3608818180109;972.7324985629009;5943.910841337383;5957.463828255478;6025.3372129065765;6120.510359171256;6182.598131121463;452.26958462084156;453.77538118631486;457.74121166791326;460.35090522363305;461.18830857690705;3950.940735888964;4068.8575899843363;4090.386442033659;4120.711550191458;4160.084781348099;10542.47287635615;10884.945575272073;10967.50646220922;10972.670819875417;10986.395748233925;10842.349459032483;10844.945775271231;10845.83731244032;10858.294210876425;10881.183911206534;7830.2952734254195;8057;8154.306883914815;8187.26314631686;8190.7133250335955;6241.407066328691;6246.375362463899;6363.872722545616;6385.96807015962;6395.616263024233;9793.902060979584;10099.94950025245;10102.747431313983;10130.29087963865;10184.338018028804;8903.821923561363;10327.225458090523;10342.948285258526;10347.43089130105;10360.637377692046;7467.253274672489;7571.205023472677;7673.194314596887;7819.921800781919;7834.569098699626;8284;8313.12712165214;8359.164083591591;8391.874121888055;8447.8732819009;7651.617419129133;7797.571133587707;7814;7832.882506762289;7836.764897053097;7668.769936901902;7689.577073261024;7702.4608277422285;7708.421868359784;7827.608619569112;7008.159020917523;7057.576545407292;7116.288371162842;7160.606166660884;7170.89243661356;4618.355281772273;4622.953770462253;4683.789229484613;4695.600873925618;4748.881277968078;4944;5063.87340316495;5089.821856235014;5231.529162375405;5305.098133317269 13:31:26 INFO - PROCESS | 10731 | |4;prototype.html;319.38996516653197;324.373958260557;327.53980657176675;330;337.3102006396918;5447.80148367359;5448.91826622601;5468.6171967962255;5470.753816078276;5472.5621950243985;5320.802819365641;5355.892882142355;5399.9730001349935;5409.513143817056;5442;4066.451029111069;4071.5928407159345;4163.3130533461945;4165;4233.640140588053;4239.300515414953;4243.405923170759;4255.148970205955;4279.593438623341;4280.694388211594;4201.663866890642;4215.388768628547;4226.281532139544;4232.809523571431;4256.595623415786;4043.6765058795227;4052.8986775330704;4207.915841683149;4245.448091748053;4250.702450828443;3739.3456145174487;3746.2882052410228;3747.306748251582;3755.1738617504107;3756.1924186300075;5221.947780522184;5229.241759944786;5260.447652996419;5278.287431196778;5291.3650361956425;4682.438107427097;4822.204336284509;4829.879253018667;4865.975670121662;4880.585150262232;5586.6368686035685;5590.888182236333;5601.271834661468;5607.299087614048;5615.747291371898;4091.734037287567;4092.0997380576227;4099.262132816092;4129.525104612978;4136.751794892316;5699.458551437607;5755.4244575542525;5768.192453056576;5805.709714514257;5862.592977685367;4206.20082184384;4225.239456897788;4228.703990720682;4233.534311225762;4250.171216612793;4270.316749320156;4277.230098582223;4285.485741711014;4289.592488713567;4293.162833247487;4661.86014419568;4785.688930219525;4788.928166077511;4797.40032495938;4840.29815676732;5581.967336042845;5598.3282006159525;5602.831915042555;5604.8318550443555;5626.156076588544;1456.2500312339155;1469.4489566412594;1471.9696212651188;1474.0639693794517;1478.6672998575234;944.7118628818276;948.9003654616304;962.3648392061345;963.9612220154463;978.6036655154674;5314.468553144655;5421.539169170585;5425.755840987165;5463.644863083887;5464.644798088111;5747.770089196469;5784.363719990798;5793.942060579425;5801.897639448492;5810.1865738796605;5273.419923808378;5416.539594134464;5426.321709786277;5431.64208947763;5463.890722185534;3011.48804703201;3042.4067306874945;3053.3435311408152;3064.6169228846393;3065.708757668018;5775.6823374714795;5908.459362474091;5934.952441407708;5941.613795103304;5952.821415357546;5827.79602713903;5831.1544826000845;5864;5874.152430158253;5879.176915231786;5846.152307915413;5856.824295271149;5857.414258574108;5880.323762767251;5883.470487656046;5483.506484416422;5652.208690783212;5698.116791897262;5707.400722924033;5733.8566535836935;5625.746841392065;5663.348714897839;5671.149327600893;5689.7439615218245;5698.316202055614;5805.445011701832;5867.237259156283;5895.322037965689;5934.554908381802;5966.612170208922;4052.250333688277;4161.563035881189;4165.12532368206;4200.9369859452745;4233.132207897312;5747.310322761128;5755.309362876482;5761.567882408753;5808.4442845148;5824.271966004249;5387.973060134675;5665.971670141623;5703.433454945208;5709.857253568528;5741.741621626953;5639.6334238274385;5655.264815573949;5655.830325090254;5670.8015219467125;5673.461021202979;5595.272614560244;5598.944010560005;5616.66300022;5646.915296270641;5708.429157084258;5528.336599607912;5533.5849811265125;5714.685692286964;5730.799422020209;5743.8564035899435;5543.805966791143;5558.332000639634;5568.832935011957;5584;5628.296462942132 13:31:26 INFO - PROCESS | 10731 | |5;yui.html;776.7514395393476;799.6081919859271;807.2653884964682;841.4656692999945;876.1807709791342;14022.579322620324;14937.477188298411;14943.476978305762;14945.476908308197;15008.849911500882;14569.635759106028;14863.479778207766;14880.702385952274;14883.553493395188;14896.25518724065;651.4560342114316;769.4421544380335;771.7028943856614;794.2534018023068;799.2966189753009;791.1416113516841;796.3390385979623;799.1209669363683;799.1568894815966;799.436397339874;834.6494472321609;836.2390224895316;837.7026155714725;837.8073043200068;838.2413915406567;833.8582440985017;833.9791505212357;834.1491678487969;834.8497270491309;835.1940377535677;499.1158377126377;588.5438784941662;600.1987346891905;600.8858316919815;607.7599348257413;14712.337944792404;14715.337809798584;14738.705225895423;14749.041312314665;14763.114213147246;14071;14137.858621413758;14328;14540.127592344494;14675.63310917225;12621.310953782511;12706.174098683648;12734.426950787145;12803.48786048557;12924.96600271976;4444.090157237083;4565.748883811389;4578.198815207359;4600.861974140781;4655.930161047587;73919;76759;77502.61248693777;82159;82284.17715822825;11456.427178641035;11687.649370518899;11878.584249551224;11973.281603103842;12019.278843269258;22193.889030555067;22385.664215036788;22419;22431.43921401978;22576.548469030527;8777.210051095431;8907.465552066897;8924.330675199386;9056.909430905738;9346.906530934739;4957.132501812169;5053.873653158627;5082.898342033139;5086.847394578094;5341.092014357569;3163.8418079095954;3204.7115759581748;3242.05980265725;3244.5944256967878;3249.8862539810993;555.9054960656698;560.3080195957989;569.2713326941522;592.7895597063067;593.0718425663822;630.3507387390995;695.2908033805592;696.4532841719226;702.9613371264527;724.246783345315;9213.35506514551;9691.418514889128;9802.95098524517;9884.505774711235;9969.700908972598;595.8957182493133;678.4640134293951;699.2133849419403;711.9928800711926;727.5889122645689;4524.16302983949;4977.726225057584;4981.775820088104;5045.949540504548;5164.354455693038;35362;35495.82252088723;35580.28839423197;93365.5995160073;115351.42324288325;93448.53275733578;93743.53128234314;94127.52936235275;94511.52744236235;94719;83837.58081209555;90086.54956725305;92097.53951230335;92206.53896730473;92508;9040.954795225982;9155.954220228856;9308.813823723623;9330.626774928927;9343.626254949726;51085.23372149495;51329.845650047166;51886.481135188165;52124.569411598924;52533.737331313096;66795.66602166957;74494;74868;76650.61674691814;77294;11921.52313907434;12230.327331996825;12238.755224895274;12245.75508489843;12276.877231227574;14307.356168972214;14313.856861431252;14346.569602911512;14597.635059123606;14751.114933104049;13539.11995720275;13606.152347148103;13656.726865462437;13664.111832731238;13796.448142074205;9133.588988495667;14354.282285885873;14461.85538144605;14495.275236237934;14636.634084147556;13660.726785464434;13667.111637743516;13705.109167904055;13837.446502139801;14481.348339324544;11709.882901170879;11994.220375675553;12024.699382515157;12419.316937568521;12627.242365458102;11455.713607159887;12396.132270740962;12478;12645.241285522898;12664.430100645677 13:31:26 INFO - PROCESS | 10731 | __end_tp_report 13:31:26 INFO - PROCESS | 10731 | __start_cc_report 13:31:26 INFO - PROCESS | 10731 | _x_x_mozilla_cycle_collect,982 13:31:26 INFO - PROCESS | 10731 | __end_cc_report 13:31:26 INFO - PROCESS | 10731 | __startTimestamp1469651486074__endTimestamp 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | ------- Summary: start ------- 13:31:26 INFO - PROCESS | 10731 | Number of tests: 6 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | [#0] dojo.html Cycles:165 Average:5440.21 Median:5684.26 stddev:619.78 (10.9%) stddev-sans-first:593.81 13:31:26 INFO - PROCESS | 10731 | Values: 3097.6 3111.5 3132.2 3136.3 3156.2 5282.3 5378.4 5384.6 5430.0 5492.2 5490.8 5507.6 5512.2 5564.6 5577.1 4944.1 4953.9 5017.0 5040.3 5168.4 5072.9 5097.9 5123.7 5142.0 5146.4 4914.7 4967.3 4970.0 5029.2 5046.6 4948.4 4958.2 4972.1 5069.6 5101.4 5014.0 5018.8 5026.9 5033.0 5043.9 5280.4 5379.2 5535.9 5582.1 5586.9 5725.6 5868.9 5870.5 5875.1 5890.1 5854.1 5859.6 5925.8 5927.1 5932.4 5342.7 5388.5 5410.8 5465.8 5514.3 6021.0 6023.5 6023.6 6027.8 6029.9 5734.5 5736.3 5778.8 5784.4 5789.4 5690.9 5810.4 5860.9 5861.7 5878.1 5716.9 5764.6 5769.7 5789.8 5840.6 5766.5 5768.2 5805.8 5811.7 5812.7 4379.1 4437.8 4438.5 4446.4 4481.9 3910.7 3948.1 3976.4 3978.2 4055.3 5571.1 5571.2 5576.9 5579.9 5580.9 5899.9 5909.9 5916.8 5916.9 5954.8 5311.9 5412.5 5447.3 5462.0 5527.6 5016.4 5021.0 5021.3 5022.0 5055.9 5953.8 5993.3 5994.5 5994.8 6013.3 5635.8 5779.1 5799.2 5832.4 5906.0 6032.7 6037.8 6038.1 6078.9 6087.8 5696.2 5708.4 5749.3 5758.0 5761.7 5671.7 5823.2 5838.0 5869.6 5876.5 5705.1 5760.7 5765.4 5796.7 5810.7 5729.2 5741.5 5742.9 5749.6 5765.9 5541.3 5656.5 5688.7 5694.2 5717.4 5826.5 5889.6 5891.1 5891.6 5911.0 5680.2 5680.3 5680.6 5684.3 5686.5 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | [#1] ext.html Cycles:180 Average:62803.18 Median:39914.40 stddev:130577.39 (327.1%) stddev-sans-first:130860.18 13:31:26 INFO - PROCESS | 10731 | Values: 605.1 608.3 631.1 632.2 640.4 32299.7 35078.8 42591.0 43770.8 43770.8 39475.0 39937.4 39972.8 40014.8 40034.6 8845.4 9039.9 9043.1 9044.9 9047.7 8863.3 8889.7 8919.0 8962.6 8965.1 8907.0 8951.5 8953.1 8953.1 8955.2 8849.8 8889.8 8890.7 8902.9 8966.5 8783.1 8840.4 8842.1 8843.0 8845.6 37481.3 37537.0 37737.8 37770.4 37779.0 3300.9 3305.6 3334.4 3556.1 3665.0 32825.7 32913.5 32944.3 33375.7 34595.8 4325.3 4369.1 4387.0 4473.2 4549.5 357680.0 357867.0 358695.0 359045.0 361002.0 55625.7 56102.2 56113.4 56160.0 56186.7 31861.4 33152.8 33182.5 57562.7 57868.0 14547.3 15034.1 15059.2 15073.5 15075.2 10724.4 10874.7 10886.1 10910.9 10920.2 19012.7 19058.4 19217.6 19280.4 19321.8 6211.0 6245.7 6354.0 6463.6 6574.2 964.7 1037.8 1082.6 1113.7 1180.7 32248.5 32358.5 32589.7 32647.3 33229.8 905.7 1068.7 1088.7 1092.8 1107.5 15745.9 16526.4 16707.8 16736.7 16878.7 568426.0 569191.0 572500.0 573271.0 573757.0 57237.0 57731.4 57776.0 57998.0 58034.4 1126.4 1242.8 1323.3 1335.4 1364.4 30085.4 31701.8 33085.8 34601.7 35146.0 55354.4 55956.0 56009.2 56051.0 56250.0 511707.0 515594.0 516112.0 517299.0 518540.0 27639.4 28575.9 28805.4 30053.7 39891.4 46613.3 46617.0 47128.3 47417.8 47851.8 36215.6 36337.8 37230.5 37535.4 37563.8 41317.8 41491.4 41586.0 42106.6 43069.0 39518.0 39627.2 40613.8 40621.4 40622.8 33172.7 37098.4 37166.4 37818.0 37964.2 27653.7 28163.2 28294.0 28345.7 28492.6 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | [#2] jquery.html Cycles:180 Average:30062.91 Median:1715.46 stddev:83237.42 (4852.2%) stddev-sans-first:83441.05 13:31:26 INFO - PROCESS | 10731 | Values: 364.4 375.9 378.5 387.5 412.0 14832.9 18237.4 19706.6 20598.8 20813.7 18307.8 18435.7 18695.7 18824.6 18838.1 5710.5 5948.0 5955.0 5956.4 5966.1 5725.4 5845.7 5886.8 5888.1 5898.1 5842.4 5848.2 5852.0 5853.6 5856.7 5725.8 5747.7 5878.7 5878.8 5892.9 5146.6 5252.4 5253.8 5255.7 5258.4 22989.7 23578.4 23679.0 25095.9 25862.2 2484.1 2492.0 2649.8 2729.8 3066.5 14864.5 15220.2 16027.8 16086.5 16093.3 1896.4 2058.5 2077.6 2236.9 2450.2 96128.0 100107.0 101145.0 101804.5 102378.0 9397.0 9561.2 9890.6 9984.0 18170.5 13413.9 13467.3 13471.4 13487.5 13494.0 7187.0 7385.4 7478.8 7581.5 7676.6 6901.0 7090.1 7147.1 7420.3 7443.4 4110.0 4110.4 4128.1 4164.1 4187.1 1903.2 1952.9 1971.5 1981.6 1982.3 894.4 997.8 1022.8 1028.7 1046.9 20065.7 20544.3 20713.2 20719.6 20941.3 944.1 944.7 976.1 979.7 983.3 9405.8 10865.3 11302.4 11356.1 11372.8 495375.0 501292.0 503928.0 505497.0 509260.0 64965.0 87504.0 94668.0 95503.0 95540.0 982.0 1001.0 1030.8 1031.7 1043.3 17753.4 18274.0 18446.4 18809.0 18949.1 41601.6 42740.6 44031.8 44627.6 44892.8 53348.0 61391.7 62091.1 63252.0 66379.7 8297.4 8479.9 8637.0 8657.0 9081.3 12230.3 12893.2 13261.9 13656.9 13932.7 10699.8 11256.7 11286.9 11669.2 11739.1 11184.5 11186.1 11811.7 11900.4 12118.9 11405.3 11494.9 11851.3 11963.2 12094.2 8461.8 8518.9 8569.4 8627.2 8750.7 10225.8 11679.6 11744.1 11865.1 11903.1 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | [#3] mootools.html Cycles:180 Average:5730.79 Median:2517.66 stddev:3294.48 (130.9%) stddev-sans-first:3287.88 13:31:26 INFO - PROCESS | 10731 | Values: 1431.6 1498.8 1587.1 1733.4 1750.5 8443.2 9023.3 9035.3 9048.3 9055.1 7607.1 7843.2 7857.9 8009.2 8740.2 2262.2 2341.7 2352.3 2357.0 2363.4 2293.6 2340.9 2351.7 2369.1 2372.4 2329.1 2340.8 2345.2 2346.3 2347.7 2293.1 2294.1 2297.4 2300.1 2300.2 2237.8 2283.1 2290.5 2290.5 2295.0 8701.3 8812.5 8825.4 8837.2 8837.6 4991.8 5073.3 5103.8 5119.5 5124.5 3184.4 3315.6 3345.3 3354.6 3358.9 1131.0 1178.3 1184.9 1201.0 1204.8 12102.0 12136.9 12148.5 12156.8 12171.8 4340.1 4455.3 4575.0 4600.3 4603.3 10254.3 10264.9 10270.2 10275.2 10300.7 5068.5 5104.8 5107.3 5107.9 5108.4 3769.0 3902.2 4704.3 4705.8 4705.8 3881.9 3914.3 3922.8 3974.8 4010.3 1237.4 1238.8 1241.1 1248.0 1255.4 910.9 934.5 958.8 968.4 972.7 5943.9 5957.5 6025.3 6120.5 6182.6 452.3 453.8 457.7 460.4 461.2 3950.9 4068.9 4090.4 4120.7 4160.1 10542.5 10884.9 10967.5 10972.7 10986.4 10842.3 10844.9 10845.8 10858.3 10881.2 7830.3 8057.0 8154.3 8187.3 8190.7 6241.4 6246.4 6363.9 6386.0 6395.6 9793.9 10099.9 10102.7 10130.3 10184.3 8903.8 10327.2 10342.9 10347.4 10360.6 7467.3 7571.2 7673.2 7819.9 7834.6 8284.0 8313.1 8359.2 8391.9 8447.9 7651.6 7797.6 7814.0 7832.9 7836.8 7668.8 7689.6 7702.5 7708.4 7827.6 7008.2 7057.6 7116.3 7160.6 7170.9 4618.4 4623.0 4683.8 4695.6 4748.9 4944.0 5063.9 5089.8 5231.5 5305.1 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | [#4] prototype.html Cycles:180 Average:4755.29 Median:5463.77 stddev:1383.22 (25.3%) stddev-sans-first:1346.44 13:31:26 INFO - PROCESS | 10731 | Values: 319.4 324.4 327.5 330.0 337.3 5447.8 5448.9 5468.6 5470.8 5472.6 5320.8 5355.9 5400.0 5409.5 5442.0 4066.5 4071.6 4163.3 4165.0 4233.6 4239.3 4243.4 4255.1 4279.6 4280.7 4201.7 4215.4 4226.3 4232.8 4256.6 4043.7 4052.9 4207.9 4245.4 4250.7 3739.3 3746.3 3747.3 3755.2 3756.2 5221.9 5229.2 5260.4 5278.3 5291.4 4682.4 4822.2 4829.9 4866.0 4880.6 5586.6 5590.9 5601.3 5607.3 5615.7 4091.7 4092.1 4099.3 4129.5 4136.8 5699.5 5755.4 5768.2 5805.7 5862.6 4206.2 4225.2 4228.7 4233.5 4250.2 4270.3 4277.2 4285.5 4289.6 4293.2 4661.9 4785.7 4788.9 4797.4 4840.3 5582.0 5598.3 5602.8 5604.8 5626.2 1456.3 1469.4 1472.0 1474.1 1478.7 944.7 948.9 962.4 964.0 978.6 5314.5 5421.5 5425.8 5463.6 5464.6 5747.8 5784.4 5793.9 5801.9 5810.2 5273.4 5416.5 5426.3 5431.6 5463.9 3011.5 3042.4 3053.3 3064.6 3065.7 5775.7 5908.5 5935.0 5941.6 5952.8 5827.8 5831.2 5864.0 5874.2 5879.2 5846.2 5856.8 5857.4 5880.3 5883.5 5483.5 5652.2 5698.1 5707.4 5733.9 5625.7 5663.3 5671.1 5689.7 5698.3 5805.4 5867.2 5895.3 5934.6 5966.6 4052.3 4161.6 4165.1 4200.9 4233.1 5747.3 5755.3 5761.6 5808.4 5824.3 5388.0 5666.0 5703.4 5709.9 5741.7 5639.6 5655.3 5655.8 5670.8 5673.5 5595.3 5598.9 5616.7 5646.9 5708.4 5528.3 5533.6 5714.7 5730.8 5743.9 5543.8 5558.3 5568.8 5584.0 5628.3 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - PROCESS | 10731 | [#5] yui.html Cycles:180 Average:19491.35 Median:5084.87 stddev:26981.99 (530.6%) stddev-sans-first:27021.08 13:31:26 INFO - PROCESS | 10731 | Values: 776.8 799.6 807.3 841.5 876.2 14022.6 14937.5 14943.5 14945.5 15008.8 14569.6 14863.5 14880.7 14883.6 14896.3 651.5 769.4 771.7 794.3 799.3 791.1 796.3 799.1 799.2 799.4 834.6 836.2 837.7 837.8 838.2 833.9 834.0 834.1 834.8 835.2 499.1 588.5 600.2 600.9 607.8 14712.3 14715.3 14738.7 14749.0 14763.1 14071.0 14137.9 14328.0 14540.1 14675.6 12621.3 12706.2 12734.4 12803.5 12925.0 4444.1 4565.7 4578.2 4600.9 4655.9 73919.0 76759.0 77502.6 82159.0 82284.2 11456.4 11687.6 11878.6 11973.3 12019.3 22193.9 22385.7 22419.0 22431.4 22576.5 8777.2 8907.5 8924.3 9056.9 9346.9 4957.1 5053.9 5082.9 5086.8 5341.1 3163.8 3204.7 3242.1 3244.6 3249.9 555.9 560.3 569.3 592.8 593.1 630.4 695.3 696.5 703.0 724.2 9213.4 9691.4 9803.0 9884.5 9969.7 595.9 678.5 699.2 712.0 727.6 4524.2 4977.7 4981.8 5045.9 5164.4 35362.0 35495.8 35580.3 93365.6 115351.4 93448.5 93743.5 94127.5 94511.5 94719.0 83837.6 90086.5 92097.5 92206.5 92508.0 9041.0 9156.0 9308.8 9330.6 9343.6 51085.2 51329.8 51886.5 52124.6 52533.7 66795.7 74494.0 74868.0 76650.6 77294.0 11921.5 12230.3 12238.8 12245.8 12276.9 14307.4 14313.9 14346.6 14597.6 14751.1 13539.1 13606.2 13656.7 13664.1 13796.4 9133.6 14354.3 14461.9 14495.3 14636.6 13660.7 13667.1 13705.1 13837.4 14481.3 11709.9 11994.2 12024.7 12419.3 12627.2 11455.7 12396.1 12478.0 12645.2 12664.4 13:31:26 INFO - PROCESS | 10731 | -------- Summary: end -------- 13:31:26 INFO - PROCESS | 10731 | 13:31:26 INFO - TEST-INFO | 10731: exit 0 13:31:26 INFO - TEST-OK | dromaeo_css | took 1113941ms 13:31:26 INFO - TEST-START | kraken 13:31:26 INFO - operating with platform_type : linux_ 13:31:26 INFO - Initialising browser for kraken test... 13:31:26 INFO - TEST-INFO | started process 10925 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPbsV7T/profile http://localhost:49048/getInfo.html) 13:31:30 INFO - PROCESS | 10925 | JavaScript error: , line 0: uncaught exception: undefined 13:31:30 INFO - PROCESS | 10925 | __metrics Screen width/height:1600/1200 13:31:30 INFO - PROCESS | 10925 | colorDepth:24 13:31:30 INFO - PROCESS | 10925 | Browser inner width/height: 1024/697 13:31:30 INFO - PROCESS | 10925 | __metrics 13:31:31 INFO - PROCESS | 10925 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:31:31 INFO - PROCESS | 10925 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:31:32 INFO - TEST-INFO | 10925: exit 0 13:31:32 INFO - Browser initialized. 13:31:32 INFO - Running cycle 1/1 for kraken test... 13:31:32 INFO - TEST-INFO | started process 11004 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPbsV7T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:31:44 INFO - PROCESS | 11004 | RSS: Main: 158474240 13:31:44 INFO - PROCESS | 11004 | 13:31:46 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49048/tests/kraken/driver.html?testName=audio-beat-detection) 13:31:47 INFO - PROCESS | 11004 | RSS: Main: 214659072 13:31:47 INFO - PROCESS | 11004 | 13:31:50 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49048/tests/kraken/driver.html?testName=audio-dft) 13:31:50 INFO - PROCESS | 11004 | RSS: Main: 193486848 13:31:50 INFO - PROCESS | 11004 | 13:31:54 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49048/tests/kraken/driver.html?testName=audio-fft) 13:31:54 INFO - PROCESS | 11004 | RSS: Main: 270462976 13:31:54 INFO - PROCESS | 11004 | 13:31:56 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49048/tests/kraken/driver.html?testName=audio-oscillator) 13:31:57 INFO - PROCESS | 11004 | RSS: Main: 181141504 13:31:57 INFO - PROCESS | 11004 | 13:31:59 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49048/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:32:00 INFO - PROCESS | 11004 | RSS: Main: 185810944 13:32:00 INFO - PROCESS | 11004 | 13:32:03 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49048/tests/kraken/driver.html?testName=imaging-darkroom) 13:32:03 INFO - PROCESS | 11004 | RSS: Main: 220221440 13:32:03 INFO - PROCESS | 11004 | 13:32:07 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49048/tests/kraken/driver.html?testName=imaging-desaturate) 13:32:07 INFO - PROCESS | 11004 | RSS: Main: 220749824 13:32:07 INFO - PROCESS | 11004 | 13:32:10 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49048/tests/kraken/driver.html?testName=json-parse-financial) 13:32:11 INFO - PROCESS | 11004 | RSS: Main: 221696000 13:32:11 INFO - PROCESS | 11004 | 13:32:12 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49048/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:32:13 INFO - PROCESS | 11004 | RSS: Main: 186200064 13:32:13 INFO - PROCESS | 11004 | 13:32:14 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:32:15 INFO - PROCESS | 11004 | RSS: Main: 191688704 13:32:15 INFO - PROCESS | 11004 | 13:32:17 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:32:18 INFO - PROCESS | 11004 | RSS: Main: 195813376 13:32:18 INFO - PROCESS | 11004 | 13:32:20 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:32:21 INFO - PROCESS | 11004 | RSS: Main: 190087168 13:32:21 INFO - PROCESS | 11004 | 13:32:24 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:32:24 INFO - PROCESS | 11004 | RSS: Main: 194215936 13:32:24 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | Cycle 1(1): loaded http://localhost:49048/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49048/tests/kraken/driver.html?testName=ai-astar) 13:32:26 INFO - PROCESS | 11004 | RSS: Main: 245137408 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | __start_tp_report 13:32:26 INFO - PROCESS | 11004 | _x_x_mozilla_page_load 13:32:26 INFO - PROCESS | 11004 | _x_x_mozilla_page_load_details 13:32:26 INFO - PROCESS | 11004 | |i|pagename|runs| 13:32:26 INFO - PROCESS | 11004 | |0;ai-astar;102;111;110;111;110;110;110;110;109;111 13:32:26 INFO - PROCESS | 11004 | |1;audio-beat-detection;149;122;119;169;130;118;149;117;153;119 13:32:26 INFO - PROCESS | 11004 | |2;audio-dft;161;163;160;160;161;161;159;160;161;161 13:32:26 INFO - PROCESS | 11004 | |3;audio-fft;74;76;73;108;75;74;74;74;100;74 13:32:26 INFO - PROCESS | 11004 | |4;audio-oscillator;83;84;117;84;85;83;122;84;83;129 13:32:26 INFO - PROCESS | 11004 | |5;imaging-gaussian-blur;117;117;116;116;116;117;117;119;117;116 13:32:26 INFO - PROCESS | 11004 | |6;imaging-darkroom;146;145;146;146;145;145;146;146;145;146 13:32:26 INFO - PROCESS | 11004 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;95 13:32:26 INFO - PROCESS | 11004 | |8;json-parse-financial;51;52;52;52;51;52;57;49;51;51 13:32:26 INFO - PROCESS | 11004 | |9;json-stringify-tinderbox;63;64;63;63;64;64;63;63;63;63 13:32:26 INFO - PROCESS | 11004 | |10;stanford-crypto-aes;75;74;84;74;77;75;77;81;74;80 13:32:26 INFO - PROCESS | 11004 | |11;stanford-crypto-ccm;121;124;136;120;121;119;121;119;161;120 13:32:26 INFO - PROCESS | 11004 | |12;stanford-crypto-pbkdf2;161;163;159;156;158;159;158;156;159;157 13:32:26 INFO - PROCESS | 11004 | |13;stanford-crypto-sha256-iterative;65;63;63;63;63;63;62;61;74;61 13:32:26 INFO - PROCESS | 11004 | __end_tp_report 13:32:26 INFO - PROCESS | 11004 | __start_cc_report 13:32:26 INFO - PROCESS | 11004 | _x_x_mozilla_cycle_collect,1640 13:32:26 INFO - PROCESS | 11004 | __end_cc_report 13:32:26 INFO - PROCESS | 11004 | __startTimestamp1469651546552__endTimestamp 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | ------- Summary: start ------- 13:32:26 INFO - PROCESS | 11004 | Number of tests: 14 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:2.67 (2.4%) stddev-sans-first:0.67 13:32:26 INFO - PROCESS | 11004 | Values: 102.0 111.0 110.0 111.0 110.0 110.0 110.0 110.0 109.0 111.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:139.50 stddev:18.82 (13.5%) stddev-sans-first:19.22 13:32:26 INFO - PROCESS | 11004 | Values: 149.0 122.0 119.0 169.0 130.0 118.0 149.0 117.0 153.0 119.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 13:32:26 INFO - PROCESS | 11004 | Values: 161.0 163.0 160.0 160.0 161.0 161.0 159.0 160.0 161.0 161.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#3] audio-fft Cycles:10 Average:80.20 Median:74.00 stddev:12.71 (17.2%) stddev-sans-first:13.28 13:32:26 INFO - PROCESS | 11004 | Values: 74.0 76.0 73.0 108.0 75.0 74.0 74.0 74.0 100.0 74.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:83.50 stddev:19.04 (22.8%) stddev-sans-first:19.66 13:32:26 INFO - PROCESS | 11004 | Values: 83.0 84.0 117.0 84.0 85.0 83.0 122.0 84.0 83.0 129.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 13:32:26 INFO - PROCESS | 11004 | Values: 117.0 117.0 116.0 116.0 116.0 117.0 117.0 119.0 117.0 116.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 13:32:26 INFO - PROCESS | 11004 | Values: 146.0 145.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 146.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:32:26 INFO - PROCESS | 11004 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:2.04 (3.9%) stddev-sans-first:2.15 13:32:26 INFO - PROCESS | 11004 | Values: 51.0 52.0 52.0 52.0 51.0 52.0 57.0 49.0 51.0 51.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#9] json-stringify-tinderbox Cycles:10 Average:63.30 Median:63.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 13:32:26 INFO - PROCESS | 11004 | Values: 63.0 64.0 63.0 63.0 64.0 64.0 63.0 63.0 63.0 63.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#10] stanford-crypto-aes Cycles:10 Average:77.10 Median:77.00 stddev:3.48 (4.5%) stddev-sans-first:3.61 13:32:26 INFO - PROCESS | 11004 | Values: 75.0 74.0 84.0 74.0 77.0 75.0 77.0 81.0 74.0 80.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:121.00 stddev:13.22 (10.9%) stddev-sans-first:13.89 13:32:26 INFO - PROCESS | 11004 | Values: 121.0 124.0 136.0 120.0 121.0 119.0 121.0 119.0 161.0 120.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.60 Median:159.00 stddev:2.17 (1.4%) stddev-sans-first:2.12 13:32:26 INFO - PROCESS | 11004 | Values: 161.0 163.0 159.0 156.0 158.0 159.0 158.0 156.0 159.0 157.0 13:32:26 INFO - PROCESS | 11004 | 13:32:26 INFO - PROCESS | 11004 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:63.00 stddev:3.77 (6.0%) stddev-sans-first:3.97 13:32:26 INFO - PROCESS | 11004 | Values: 65.0 63.0 63.0 63.0 63.0 63.0 62.0 61.0 74.0 61.0 13:32:26 INFO - PROCESS | 11004 | -------- Summary: end -------- 13:32:26 INFO - PROCESS | 11004 | 13:32:27 INFO - TEST-INFO | 11004: exit 0 13:32:27 INFO - TEST-OK | kraken | took 60646ms 13:32:27 INFO - SUITE-END | took 1174s 13:32:27 INFO - Completed test suite (00:19:34) 13:32:27 INFO - javascript benchmark 13:32:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3097.5818264534287, 3111.533270009497, 3132.201288671389, 3136.278655909141, 3156.1951702315905, 5282.286891269678, 5378.435264297251, 5384.596155288355, 5429.972850135754, 5492.231087647728, 5490.846922146354, 5507.614466987313, 5512.173174023898, 5564.610477266583, 5577.107662773957, 4944.060628480586, 4953.851384458472, 5017.0467611153945, 5040.269160971675, 5168.431472538018, 5072.923906141411, 5097.949020509803, 5123.692578445287, 5142.0, 5146.356705411824, 4914.70511769295, 4967.304577359173, 4970.0, 5029.245613158019, 5046.6467347285525, 4948.356713627206, 4958.231474121517, 4972.055309491221, 5069.619778516626, 5101.438841727407, 5014.047331007097, 5018.774155163026, 5026.949730502684, 5032.974835125801, 5043.924341134886, 5280.445553216895, 5379.1662292344745, 5535.944640553583, 5582.106862901916, 5586.944130558683, 5725.627834190765, 5868.853278668024, 5870.501007414375, 5875.1187321901625, 5890.145928840337, 5854.121881717734, 5859.560532960045, 5925.762969481259, 5927.140564618149, 5932.377100404482, 5342.652727572696, 5388.515033646912, 5410.756515956791, 5465.836024919259, 5514.310711161105, 6021.0, 6023.518118550505, 6023.608465449732, 6027.819165425044, 6029.909551356734, 5734.541236701054, 5736.254286942671, 5778.7688492460675, 5784.421557844266, 5789.421057894261, 5690.857728556819, 5810.389909059488, 5860.912086318709, 5861.736221870026, 5878.118282257696, 5716.914246286309, 5764.5676574257095, 5769.653820770767, 5789.768409263583, 5840.561957853178, 5766.452187042224, 5768.221290125779, 5805.825825225165, 5811.651300921873, 5812.680302583314, 4379.1022840317655, 4437.800298986554, 4438.489573699001, 4446.444194475691, 4481.887952801206, 3910.7458015228917, 3948.0919388540797, 3976.363781794899, 3978.1844721831953, 4055.270051390778, 5571.1086226203615, 5571.164325351229, 5576.888462230814, 5579.944200558024, 5580.888382232332, 5899.882002359929, 5909.881802363929, 5916.763329466859, 5916.852078697981, 5954.761809527657, 5311.893762124736, 5412.485813847678, 5447.291852059287, 5461.972690136524, 5527.613067085346, 5016.423111342169, 5021.046001259749, 5021.347224860818, 5022.020705962303, 5055.9241611375855, 5953.821385358447, 5993.2508436446315, 5994.520438364921, 5994.790182343597, 6013.30846952606, 5635.802746904003, 5779.133130030529, 5799.246098007232, 5832.387599302012, 5906.0255057914965, 6032.668203248688, 6037.758489660365, 6038.094285856981, 6078.939210607838, 6087.817365479043, 5696.20253164549, 5708.400617935057, 5749.252597162342, 5758.0, 5761.740721667451, 5671.744771485375, 5823.242978412779, 5838.0, 5869.618474799125, 5876.471117599436, 5705.115707065412, 5760.654360738369, 5765.423457654201, 5796.681182535001, 5810.6513609183585, 5729.197912292199, 5741.540676745767, 5742.942570574241, 5749.568782341257, 5765.942340576708, 5541.33503979525, 5656.519195868397, 5688.658680479184, 5694.17434471991, 5717.399673034438, 5826.475617194473, 5889.587728858939, 5891.057430811221, 5891.587588868738, 5910.970445147746, 5680.176374425768, 5680.289963754531, 5680.630759000652, 5684.261046063985, 5686.545076393962], "value": 5397.751744673233, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.0651743056976, 608.254887762491, 631.0691729698696, 632.2318383164454, 640.3660376227537, 32299.67700322996, 35078.824605876966, 42591.0, 43770.781146094225, 43770.781146094305, 39475.0, 39937.40093898594, 39972.80013599935, 40014.7999260004, 40034.599654003454, 8845.425047371917, 9039.909600903973, 9043.09569043097, 9044.8643270351, 9047.683331083414, 8863.335249856254, 8889.73330800077, 8919.0, 8962.641494340218, 8965.058668839776, 8906.955465222665, 8951.507667078307, 8953.05992870749, 8953.149450802164, 8955.238804701608, 8849.778755531132, 8889.822203555925, 8890.688825891095, 8902.910970890272, 8966.506842123681, 8783.077776833405, 8840.38117331787, 8842.115788421172, 8843.0, 8845.557722113868, 37481.25037499262, 37537.0, 37737.81131094354, 37770.43344349837, 37779.0, 3300.884469043572, 3305.6033276007033, 3334.416477116495, 3556.1287484566446, 3664.9633503665154, 32825.6717432825, 32913.506297405555, 32944.34111317784, 33375.6662433375, 34595.827020864985, 4325.286327755916, 4369.126174765028, 4386.978065109686, 4473.150101480741, 4549.454065512126, 357680.0, 357867.0, 358695.0, 359045.0, 361001.9999999974, 55625.721871390386, 56102.15846762302, 56113.43886561082, 56160.0, 56186.7190664044, 31861.362772744415, 33152.83423582867, 33182.502262466085, 57562.71218643964, 57868.0, 14547.3453694584, 15034.097954122788, 15059.247037648074, 15073.472428464951, 15075.170865602278, 10724.356538607708, 10874.67375978722, 10886.129109671207, 10910.945445272722, 10920.18098642605, 19012.714809277873, 19058.42824715261, 19217.61564768697, 19280.4215873524, 19321.806781932282, 6211.0, 6245.718942647592, 6353.968230158912, 6463.64449955257, 6574.178227721535, 964.7491652170346, 1037.8131936251395, 1082.6156714366443, 1113.6714669172613, 1180.7107258721496, 32248.516272255936, 32358.514622280683, 32589.674103258665, 32647.347053059282, 33229.836955706916, 905.6965916417942, 1068.6900798768424, 1088.7169335972703, 1092.7706330378323, 1107.479484642217, 15745.911053027703, 16526.421575244807, 16707.83292167087, 16736.665266694843, 16878.74681879773, 568426.0, 569191.0, 572500.0, 573271.0, 573757.0, 57237.0, 57731.42268577345, 57776.0, 57998.0, 58034.419655803744, 1126.3636045634191, 1242.8384310039637, 1323.2788130468919, 1335.4057444437326, 1364.3996641477718, 30085.398292034475, 31701.841490793322, 33085.83457082699, 34601.653983459844, 35146.0, 55354.446455534926, 55956.0, 56009.15986260291, 56051.0, 56250.0, 511707.0, 515594.0, 516112.0, 517299.0, 518540.0, 27639.447211055263, 28575.857120714263, 28805.42389152247, 30053.69946300509, 39891.40162897617, 46613.30080048734, 46617.0, 47128.29307560458, 47417.76291118522, 47851.76074119607, 36215.63784362228, 36337.81831090934, 37230.469822101135, 37535.43696844495, 37563.81218093892, 41317.793411032755, 41491.37762933619, 41586.0, 42106.578934210265, 43069.0, 39518.0, 39627.20745585015, 40613.79693101515, 40621.390679139244, 40622.79688601538, 33172.668273317926, 37098.44352334663, 37166.44250336193, 37818.0, 37964.24071518499, 27653.723462765116, 28163.155105346872, 28294.0, 28345.716542834307, 28492.57261141126], "value": 19722.99438902232, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.4314868804663, 375.9003863976045, 378.4758110017626, 387.4672325552365, 412.0276148290037, 14832.851671483295, 18237.36169234077, 19706.605867882652, 20598.794012059876, 20813.687794683094, 18307.816921830778, 18435.72346414805, 18695.719564206513, 18824.623507529843, 18838.05809709516, 5710.486056254947, 5948.048312270038, 5955.017422125344, 5956.404359564052, 5966.134910437984, 5725.398833122525, 5845.707714614274, 5886.823395298148, 5888.087346461284, 5898.115282707586, 5842.357340692521, 5848.210491583624, 5851.970740146293, 5853.560982926277, 5856.736446859902, 5725.7995970141055, 5747.683877386742, 5878.706064696791, 5878.823635290948, 5892.852678683024, 5146.562542183919, 5252.395974462909, 5253.816116435906, 5255.6583822051825, 5258.3689957205, 22989.655155172684, 23578.410539736473, 23679.0, 25095.87452062728, 25862.22413327603, 2484.0808900706675, 2491.9750802491926, 2649.7880169586388, 2729.767969722576, 3066.4940284852973, 14864.479743208936, 15220.238988050553, 16027.839721602752, 16086.517404477885, 16093.275802588912, 1896.3647178195322, 2058.475088852348, 2077.626027315083, 2236.932892013242, 2450.1669432393073, 96128.0, 100107.0, 101145.0, 101804.49097754539, 102378.0, 9397.01331360211, 9561.235101192027, 9890.554925028247, 9983.954893757475, 18170.45488635343, 13413.865861341457, 13467.326633668277, 13471.393787279596, 13487.460501580023, 13494.0, 7186.964065179641, 7385.409167266605, 7478.813029674302, 7581.469297149257, 7676.57778822159, 6900.965495172492, 7090.149182098181, 7147.106611673541, 7420.257974202536, 7443.404527637776, 4109.97945010273, 4110.403991421227, 4128.050548373891, 4164.083901541656, 4187.14163596462, 1903.1816318982694, 1952.8730632509125, 1971.4578490914826, 1981.5937732764748, 1982.306192832526, 894.3873446689039, 997.7705127820642, 1022.754538910656, 1028.6502589119734, 1046.874375074996, 20065.699014514794, 20544.28095016667, 20713.171473140905, 20719.585608288053, 20941.267055652977, 944.0983860413289, 944.6552008516842, 976.097110173087, 979.6816034788723, 983.3313346924017, 9405.811883762286, 10865.348079115278, 11302.434878256054, 11356.148288878367, 11372.772544549063, 495375.0, 501292.0, 503928.0, 505497.0, 509260.0, 64965.0, 87504.0, 94668.0, 95503.0, 95540.0, 981.9836469254287, 1000.9549570269355, 1030.7886883188928, 1031.654395777423, 1043.3062013760916, 17753.378631747826, 18274.0, 18446.446606601825, 18809.0, 18949.14728837178, 41601.583984160985, 42740.572594274905, 44031.779841100586, 44627.55372446234, 44892.77553612211, 53348.0, 61391.693041534505, 62091.06863397143, 63252.0, 66379.66810165918, 8297.419180657296, 8479.872801907853, 8637.0, 8656.956715216636, 9081.273498120003, 12230.327331996825, 12893.226406415644, 13261.867381326063, 13656.93171534136, 13932.721345572829, 10699.786004280026, 11256.718582035515, 11286.887131128584, 11669.241499302518, 11739.06087513014, 11184.496697648789, 11186.105111591216, 11811.704707382385, 11900.40497975115, 12118.939405302917, 11405.315681059163, 11494.94252528732, 11851.348175850411, 11963.162578619413, 12094.153409261267, 8461.830763384822, 8518.872216916627, 8569.357298202784, 8627.22354988054, 8750.737477875675, 10225.795484090426, 11679.591214307458, 11744.119191060534, 11865.110116741107, 11903.107266955187], "value": 9384.19705630364, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.6277767780377, 1498.8351281359048, 1587.1350114187767, 1733.4366330942446, 1750.5011071844526, 8443.240108390244, 9023.323250756195, 9035.277177825774, 9048.276137908968, 9055.094490550959, 7607.087149542049, 7843.215678432154, 7857.882131768028, 8009.2391222833885, 8740.169683880036, 2262.230841513885, 2341.672165896776, 2352.3178278299274, 2356.976430235693, 2363.385519764865, 2293.575688497625, 2340.8946597403074, 2351.7177938647387, 2369.099742098, 2372.41875740443, 2329.056732023533, 2340.754220806816, 2345.2260753951155, 2346.3430239532872, 2347.6830627865274, 2293.1286111277773, 2294.0823670531836, 2297.3911913342977, 2300.1029598456616, 2300.1949317738827, 2237.8097861681777, 2283.1438210671, 2290.4617414907484, 2290.484640955788, 2295.0, 8701.260392866614, 8812.471251724917, 8825.382223244376, 8837.160469755363, 8837.646494140226, 4991.7753701083175, 5073.289739436482, 5103.770330335106, 5119.513646203604, 5124.461931497176, 3184.3949649566507, 3315.602127744662, 3345.2640419107756, 3354.563906692139, 3358.9160270993175, 1130.9604163854226, 1178.298912147271, 1184.8696643369221, 1201.0451690905663, 1204.7951848185742, 12102.0, 12136.939315303456, 12148.514059437612, 12156.756864862653, 12171.817422738577, 4340.088581397942, 4455.309427038814, 4574.977125114419, 4600.309953506934, 4603.263477843596, 10254.28220024606, 10264.897351026544, 10270.178385729123, 10275.229357798195, 10300.742481438025, 5068.543831055223, 5104.821331253388, 5107.336046313955, 5107.948920510822, 5108.361454818148, 3768.9623103768613, 3902.180542086137, 4704.317873908332, 4705.811767529329, 4705.835295764632, 3881.9029524261555, 3914.3345631242187, 3922.8234729437245, 3974.7813870237414, 4010.2981978153707, 1237.412229191127, 1238.7646347194004, 1241.118805648, 1247.95008199671, 1255.3911352993791, 910.8952470465849, 934.5327336331834, 958.8274110660149, 968.3608818180109, 972.7324985629009, 5943.910841337383, 5957.463828255478, 6025.3372129065765, 6120.510359171256, 6182.598131121463, 452.26958462084156, 453.77538118631486, 457.74121166791326, 460.35090522363305, 461.18830857690705, 3950.940735888964, 4068.8575899843363, 4090.386442033659, 4120.711550191458, 4160.084781348099, 10542.47287635615, 10884.945575272073, 10967.50646220922, 10972.670819875417, 10986.395748233925, 10842.349459032483, 10844.945775271231, 10845.83731244032, 10858.294210876425, 10881.183911206534, 7830.2952734254195, 8057.0, 8154.306883914815, 8187.26314631686, 8190.7133250335955, 6241.407066328691, 6246.375362463899, 6363.872722545616, 6385.96807015962, 6395.616263024233, 9793.902060979584, 10099.94950025245, 10102.747431313983, 10130.29087963865, 10184.338018028804, 8903.821923561363, 10327.225458090523, 10342.948285258526, 10347.43089130105, 10360.637377692046, 7467.253274672489, 7571.205023472677, 7673.194314596887, 7819.921800781919, 7834.569098699626, 8284.0, 8313.12712165214, 8359.164083591591, 8391.874121888055, 8447.8732819009, 7651.617419129133, 7797.571133587707, 7814.0, 7832.882506762289, 7836.764897053097, 7668.769936901902, 7689.577073261024, 7702.4608277422285, 7708.421868359784, 7827.608619569112, 7008.159020917523, 7057.576545407292, 7116.288371162842, 7160.606166660884, 7170.89243661356, 4618.355281772273, 4622.953770462253, 4683.789229484613, 4695.600873925618, 4748.881277968078, 4944.0, 5063.87340316495, 5089.821856235014, 5231.529162375405, 5305.098133317269], "value": 4509.791335282814, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.38996516653197, 324.373958260557, 327.53980657176675, 330.0, 337.3102006396918, 5447.80148367359, 5448.91826622601, 5468.6171967962255, 5470.753816078276, 5472.5621950243985, 5320.802819365641, 5355.892882142355, 5399.9730001349935, 5409.513143817056, 5442.0, 4066.451029111069, 4071.5928407159345, 4163.3130533461945, 4165.0, 4233.640140588053, 4239.300515414953, 4243.405923170759, 4255.148970205955, 4279.593438623341, 4280.694388211594, 4201.663866890642, 4215.388768628547, 4226.281532139544, 4232.809523571431, 4256.595623415786, 4043.6765058795227, 4052.8986775330704, 4207.915841683149, 4245.448091748053, 4250.702450828443, 3739.3456145174487, 3746.2882052410228, 3747.306748251582, 3755.1738617504107, 3756.1924186300075, 5221.947780522184, 5229.241759944786, 5260.447652996419, 5278.287431196778, 5291.3650361956425, 4682.438107427097, 4822.204336284509, 4829.879253018667, 4865.975670121662, 4880.585150262232, 5586.6368686035685, 5590.888182236333, 5601.271834661468, 5607.299087614048, 5615.747291371898, 4091.734037287567, 4092.0997380576227, 4099.262132816092, 4129.525104612978, 4136.751794892316, 5699.458551437607, 5755.4244575542525, 5768.192453056576, 5805.709714514257, 5862.592977685367, 4206.20082184384, 4225.239456897788, 4228.703990720682, 4233.534311225762, 4250.171216612793, 4270.316749320156, 4277.230098582223, 4285.485741711014, 4289.592488713567, 4293.162833247487, 4661.86014419568, 4785.688930219525, 4788.928166077511, 4797.40032495938, 4840.29815676732, 5581.967336042845, 5598.3282006159525, 5602.831915042555, 5604.8318550443555, 5626.156076588544, 1456.2500312339155, 1469.4489566412594, 1471.9696212651188, 1474.0639693794517, 1478.6672998575234, 944.7118628818276, 948.9003654616304, 962.3648392061345, 963.9612220154463, 978.6036655154674, 5314.468553144655, 5421.539169170585, 5425.755840987165, 5463.644863083887, 5464.644798088111, 5747.770089196469, 5784.363719990798, 5793.942060579425, 5801.897639448492, 5810.1865738796605, 5273.419923808378, 5416.539594134464, 5426.321709786277, 5431.64208947763, 5463.890722185534, 3011.48804703201, 3042.4067306874945, 3053.3435311408152, 3064.6169228846393, 3065.708757668018, 5775.6823374714795, 5908.459362474091, 5934.952441407708, 5941.613795103304, 5952.821415357546, 5827.79602713903, 5831.1544826000845, 5864.0, 5874.152430158253, 5879.176915231786, 5846.152307915413, 5856.824295271149, 5857.414258574108, 5880.323762767251, 5883.470487656046, 5483.506484416422, 5652.208690783212, 5698.116791897262, 5707.400722924033, 5733.8566535836935, 5625.746841392065, 5663.348714897839, 5671.149327600893, 5689.7439615218245, 5698.316202055614, 5805.445011701832, 5867.237259156283, 5895.322037965689, 5934.554908381802, 5966.612170208922, 4052.250333688277, 4161.563035881189, 4165.12532368206, 4200.9369859452745, 4233.132207897312, 5747.310322761128, 5755.309362876482, 5761.567882408753, 5808.4442845148, 5824.271966004249, 5387.973060134675, 5665.971670141623, 5703.433454945208, 5709.857253568528, 5741.741621626953, 5639.6334238274385, 5655.264815573949, 5655.830325090254, 5670.8015219467125, 5673.461021202979, 5595.272614560244, 5598.944010560005, 5616.66300022, 5646.915296270641, 5708.429157084258, 5528.336599607912, 5533.5849811265125, 5714.685692286964, 5730.799422020209, 5743.8564035899435, 5543.805966791143, 5558.332000639634, 5568.832935011957, 5584.0, 5628.296462942132], "value": 4310.648095841524, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.7514395393476, 799.6081919859271, 807.2653884964682, 841.4656692999945, 876.1807709791342, 14022.579322620324, 14937.477188298411, 14943.476978305762, 14945.476908308197, 15008.849911500882, 14569.635759106028, 14863.479778207766, 14880.702385952274, 14883.553493395188, 14896.25518724065, 651.4560342114316, 769.4421544380335, 771.7028943856614, 794.2534018023068, 799.2966189753009, 791.1416113516841, 796.3390385979623, 799.1209669363683, 799.1568894815966, 799.436397339874, 834.6494472321609, 836.2390224895316, 837.7026155714725, 837.8073043200068, 838.2413915406567, 833.8582440985017, 833.9791505212357, 834.1491678487969, 834.8497270491309, 835.1940377535677, 499.1158377126377, 588.5438784941662, 600.1987346891905, 600.8858316919815, 607.7599348257413, 14712.337944792404, 14715.337809798584, 14738.705225895423, 14749.041312314665, 14763.114213147246, 14071.0, 14137.858621413758, 14328.0, 14540.127592344494, 14675.63310917225, 12621.310953782511, 12706.174098683648, 12734.426950787145, 12803.48786048557, 12924.96600271976, 4444.090157237083, 4565.748883811389, 4578.198815207359, 4600.861974140781, 4655.930161047587, 73919.0, 76759.0, 77502.61248693777, 82159.0, 82284.17715822825, 11456.427178641035, 11687.649370518899, 11878.584249551224, 11973.281603103842, 12019.278843269258, 22193.889030555067, 22385.664215036788, 22419.0, 22431.43921401978, 22576.548469030527, 8777.210051095431, 8907.465552066897, 8924.330675199386, 9056.909430905738, 9346.906530934739, 4957.132501812169, 5053.873653158627, 5082.898342033139, 5086.847394578094, 5341.092014357569, 3163.8418079095954, 3204.7115759581748, 3242.05980265725, 3244.5944256967878, 3249.8862539810993, 555.9054960656698, 560.3080195957989, 569.2713326941522, 592.7895597063067, 593.0718425663822, 630.3507387390995, 695.2908033805592, 696.4532841719226, 702.9613371264527, 724.246783345315, 9213.35506514551, 9691.418514889128, 9802.95098524517, 9884.505774711235, 9969.700908972598, 595.8957182493133, 678.4640134293951, 699.2133849419403, 711.9928800711926, 727.5889122645689, 4524.16302983949, 4977.726225057584, 4981.775820088104, 5045.949540504548, 5164.354455693038, 35362.0, 35495.82252088723, 35580.28839423197, 93365.5995160073, 115351.42324288325, 93448.53275733578, 93743.53128234314, 94127.52936235275, 94511.52744236235, 94719.0, 83837.58081209555, 90086.54956725305, 92097.53951230335, 92206.53896730473, 92508.0, 9040.954795225982, 9155.954220228856, 9308.813823723623, 9330.626774928927, 9343.626254949726, 51085.23372149495, 51329.845650047166, 51886.481135188165, 52124.569411598924, 52533.737331313096, 66795.66602166957, 74494.0, 74868.0, 76650.61674691814, 77294.0, 11921.52313907434, 12230.327331996825, 12238.755224895274, 12245.75508489843, 12276.877231227574, 14307.356168972214, 14313.856861431252, 14346.569602911512, 14597.635059123606, 14751.114933104049, 13539.11995720275, 13606.152347148103, 13656.726865462437, 13664.111832731238, 13796.448142074205, 9133.588988495667, 14354.282285885873, 14461.85538144605, 14495.275236237934, 14636.634084147556, 13660.726785464434, 13667.111637743516, 13705.109167904055, 13837.446502139801, 14481.348339324544, 11709.882901170879, 11994.220375675553, 12024.699382515157, 12419.316937568521, 12627.242365458102, 11455.713607159887, 12396.132270740962, 12478.0, 12645.241285522898, 12664.430100645677], "value": 7470.937045926745, "unit": "score"}], "name": "dromaeo_css", "value": 7249.091352756394, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 109.0, 111.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 119.0, 169.0, 130.0, 118.0, 149.0, 117.0, 153.0, 119.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 160.0, 160.0, 161.0, 161.0, 159.0, 160.0, 161.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 73.0, 108.0, 75.0, 74.0, 74.0, 74.0, 100.0, 74.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 117.0, 84.0, 85.0, 83.0, 122.0, 84.0, 83.0, 129.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 119.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.6, "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, 95.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 52.0, 51.0, 52.0, 57.0, 49.0, 51.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0], "value": 63.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 84.0, 74.0, 77.0, 75.0, 77.0, 81.0, 74.0, 80.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 124.0, 136.0, 120.0, 121.0, 119.0, 121.0, 119.0, 161.0, 120.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 159.0, 156.0, 158.0, 159.0, 158.0, 156.0, 159.0, 157.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0, 61.0, 74.0, 61.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1478.4999999999998, "alertThreshold": 2.0}]} 13:32:27 INFO - Return code: 0 13:32:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:32:27 INFO - # TBPL SUCCESS # 13:32:27 INFO - Running post-action listener: _resource_record_post_action 13:32:27 INFO - [mozharness: 2016-07-27 20:32:27.864468Z] Finished run-tests step (success) 13:32:27 INFO - Running post-run listener: _resource_record_post_run 13:32:27 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 565772288; Read time: 4; Write time: 394252 13:32:27 INFO - TinderboxPrint: CPU usage
13.0% 13:32:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 13:32:27 INFO - TinderboxPrint: I/O write bytes / time
565,772,288 / 394,252 13:32:27 INFO - TinderboxPrint: CPU idle
8,276.1 (87.0%) 13:32:27 INFO - TinderboxPrint: CPU user
1,194.9 (12.6%) 13:32:27 INFO - TinderboxPrint: Swap in / out
0 / 0 13:32:27 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 268861440; Read time: 0; Write time: 223388 13:32:27 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 296296448; Read time: 0; Write time: 170864 13:32:28 INFO - Running post-run listener: _upload_blobber_files 13:32:28 INFO - Blob upload gear active. 13:32:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:32:28 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 13:32:28 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'] 13:32:28 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 13:32:28 INFO - (blobuploader) - INFO - Open directory for files ... 13:32:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:32:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:30 INFO - (blobuploader) - INFO - Done attempting. 13:32:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:31 INFO - (blobuploader) - INFO - Done attempting. 13:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:32:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:32:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:32:49 INFO - (blobuploader) - INFO - Done attempting. 13:32:49 INFO - (blobuploader) - INFO - Iteration through files over. 13:32:49 INFO - Return code: 0 13:32:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:32:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:32:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc5ac624a57648568aa9a558bf531daf1320bd64dddae7015e60ccf51b7d63e8c3896a9e97d16646a3e998194a01cbe44ba7a53e769fa01b951b11e425d98f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/157a1f907a87eb489a671d5faf26aa22b0060785bacad3c47978e5a8a1b5676e629dcc5c42f4af40549090a974be08352bd4e368d67d5215d3d3519d550e9f4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c897efc810f62ec0cb2a75557dea2e86deb7223a468ddd7db4115216a7227ba3511fc3c0fa548dfce064b8be8fbe7fe31bdf33ce355806345abc04fb630d2587"} 13:32:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:32:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:32:49 INFO - Contents: 13:32:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc5ac624a57648568aa9a558bf531daf1320bd64dddae7015e60ccf51b7d63e8c3896a9e97d16646a3e998194a01cbe44ba7a53e769fa01b951b11e425d98f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/157a1f907a87eb489a671d5faf26aa22b0060785bacad3c47978e5a8a1b5676e629dcc5c42f4af40549090a974be08352bd4e368d67d5215d3d3519d550e9f4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c897efc810f62ec0cb2a75557dea2e86deb7223a468ddd7db4115216a7227ba3511fc3c0fa548dfce064b8be8fbe7fe31bdf33ce355806345abc04fb630d2587"} 13:32:49 INFO - Running post-run listener: copy_logs_to_upload_dir 13:32:49 INFO - Copying logs to upload dir... 13:32:49 INFO - mkdir: /builds/slave/test/build/upload/logs 13:32:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.689473 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-27 13:32:49.852254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 13:32:49.855556) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471 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/ffc5ac624a57648568aa9a558bf531daf1320bd64dddae7015e60ccf51b7d63e8c3896a9e97d16646a3e998194a01cbe44ba7a53e769fa01b951b11e425d98f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/157a1f907a87eb489a671d5faf26aa22b0060785bacad3c47978e5a8a1b5676e629dcc5c42f4af40549090a974be08352bd4e368d67d5215d3d3519d550e9f4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c897efc810f62ec0cb2a75557dea2e86deb7223a468ddd7db4115216a7227ba3511fc3c0fa548dfce064b8be8fbe7fe31bdf33ce355806345abc04fb630d2587"} build_url:https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/UtHYRJymSV-9aKdQG9BULA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc5ac624a57648568aa9a558bf531daf1320bd64dddae7015e60ccf51b7d63e8c3896a9e97d16646a3e998194a01cbe44ba7a53e769fa01b951b11e425d98f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/157a1f907a87eb489a671d5faf26aa22b0060785bacad3c47978e5a8a1b5676e629dcc5c42f4af40549090a974be08352bd4e368d67d5215d3d3519d550e9f4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c897efc810f62ec0cb2a75557dea2e86deb7223a468ddd7db4115216a7227ba3511fc3c0fa548dfce064b8be8fbe7fe31bdf33ce355806345abc04fb630d2587"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 13:32:49.881834) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:32:49.882234) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1469650242.920300-1054383471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004386 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 13:32:49.968459) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 13:32:49.968826) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 13:32:49.969228) ========= ========= Total master_lag: 0.18 =========