builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1469556242.61 results: success (0) buildid: 20160726080520 builduid: ad63a90da0004e5db725c963b22000f8 revision: ceb63dec9267 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.612721) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.613255) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.613562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.634113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.634415) ========= 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=fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 11:04:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-07-26 11:04:03 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118335 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.768752) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.769107) ========= 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=fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020073 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.820050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:02.820449) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ceb63dec9267 --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 ceb63dec9267 --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=fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 11:04:03,284 Setting DEBUG logging. 2016-07-26 11:04:03,284 attempt 1/10 2016-07-26 11:04:03,284 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ceb63dec9267?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 11:04:03,588 unpacking tar archive at: mozilla-central-ceb63dec9267/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619179 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:03.454429) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:03.454820) ========= script_repo_revision: ceb63dec9267 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:03.455344) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:03.455708) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 11:04:03.465889) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-26 11:04:03.466220) ========= /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=fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:04:04 INFO - MultiFileLogger online at 20160726 11:04:04 in /builds/slave/test 11:04:04 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 11:04:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:04:04 INFO - {'append_to_log': False, 11:04:04 INFO - 'base_work_dir': '/builds/slave/test', 11:04:04 INFO - 'blob_upload_branch': 'Firefox', 11:04:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:04:04 INFO - 'branch': 'Firefox', 11:04:04 INFO - 'buildbot_json_path': 'buildprops.json', 11:04:04 INFO - 'config_files': ('talos/linux_config.py',), 11:04:04 INFO - 'default_actions': ('clobber', 11:04:04 INFO - 'read-buildbot-config', 11:04:04 INFO - 'download-and-extract', 11:04:04 INFO - 'populate-webroot', 11:04:04 INFO - 'create-virtualenv', 11:04:04 INFO - 'install', 11:04:04 INFO - 'run-tests'), 11:04:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:04:04 INFO - 'download_minidump_stackwalk': True, 11:04:04 INFO - 'download_symbols': 'ondemand', 11:04:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:04:04 INFO - 'tooltool.py': '/tools/tooltool.py', 11:04:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:04:04 INFO - '/tools/misc-python/virtualenv.py')}, 11:04:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:04:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:04:04 INFO - 'installer_path': 'installer.exe', 11:04:04 INFO - 'log_level': 'info', 11:04:04 INFO - 'log_name': 'talos', 11:04:04 INFO - 'log_to_console': True, 11:04:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:04:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:04:04 INFO - 'opt_config_files': (), 11:04:04 INFO - 'pip_index': False, 11:04:04 INFO - 'sps_profile': False, 11:04:04 INFO - 'sps_profile_interval': 0, 11:04:04 INFO - 'suite': 'dromaeojs', 11:04:04 INFO - 'system_bits': '32', 11:04:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:04:04 INFO - 'title': 'talos-linux64-ix-048', 11:04:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:04:04 INFO - 'use_talos_json': True, 11:04:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:04:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:04:04 INFO - 'work_dir': 'build'} 11:04:04 INFO - [mozharness: 2016-07-26 18:04:04.022192Z] Running clobber step. 11:04:04 INFO - Running pre-action listener: _resource_record_pre_action 11:04:04 INFO - Running main action method: clobber 11:04:04 INFO - rmtree: /builds/slave/test/build 11:04:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:04:04 INFO - Running post-action listener: _resource_record_post_action 11:04:04 INFO - [mozharness: 2016-07-26 18:04:04.989018Z] Finished clobber step (success) 11:04:04 INFO - [mozharness: 2016-07-26 18:04:04.989118Z] Running read-buildbot-config step. 11:04:04 INFO - Running pre-action listener: _resource_record_pre_action 11:04:04 INFO - Running main action method: read_buildbot_config 11:04:04 INFO - Using buildbot properties: 11:04:04 INFO - { 11:04:04 INFO - "project": "", 11:04:04 INFO - "product": "firefox", 11:04:04 INFO - "script_repo_revision": "production", 11:04:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 11:04:04 INFO - "repository": "", 11:04:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 11:04:04 INFO - "buildid": "20160726080520", 11:04:04 INFO - "stage_platform": "linux64-pgo", 11:04:04 INFO - "basedir": "/builds/slave/test", 11:04:04 INFO - "nightly_build": "True", 11:04:04 INFO - "buildnumber": 406, 11:04:04 INFO - "slavename": "talos-linux64-ix-048", 11:04:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:04:04 INFO - "platform": "ubuntu64_hw", 11:04:04 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 11:04:04 INFO - "branch": "mozilla-central", 11:04:04 INFO - "repo_path": "mozilla-central", 11:04:04 INFO - "revision": "ceb63dec9267", 11:04:04 INFO - "pgo_build": "True", 11:04:04 INFO - "builduid": "ad63a90da0004e5db725c963b22000f8", 11:04:04 INFO - "slavebuilddir": "test" 11:04:04 INFO - } 11:04:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:04:04 INFO - Running post-action listener: _resource_record_post_action 11:04:04 INFO - [mozharness: 2016-07-26 18:04:04.991642Z] Finished read-buildbot-config step (success) 11:04:04 INFO - [mozharness: 2016-07-26 18:04:04.991732Z] Running download-and-extract step. 11:04:04 INFO - Running pre-action listener: _resource_record_pre_action 11:04:04 INFO - Running main action method: download_and_extract 11:04:04 INFO - mkdir: /builds/slave/test/build/tests 11:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:04 INFO - https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:04:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:04:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:04:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:05 INFO - Downloaded 1361 bytes. 11:04:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:04:05 INFO - Using the following test package requirements: 11:04:05 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:04:05 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:04:05 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:04:05 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'jsshell-linux-x86_64.zip'], 11:04:05 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:04:05 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:04:05 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:04:05 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:04:05 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:04:05 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:04:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:04:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:05 INFO - https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:04:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:04:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:04:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:09 INFO - Downloaded 24313605 bytes. 11:04:09 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'] 11:04:09 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 11:04:11 INFO - Return code: 0 11:04:11 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 11:04: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')]} 11:04:11 INFO - https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:04:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:04:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:04:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:19 INFO - Downloaded 24313605 bytes. 11:04:19 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'] 11:04:19 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 11:04:20 INFO - Return code: 0 11:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:20 INFO - https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:04:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:04:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:04:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:21 INFO - Downloaded 14413725 bytes. 11:04:21 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'] 11:04:21 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 11:04:21 INFO - Return code: 0 11:04: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')]} 11:04:21 INFO - https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:04:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:04:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:04:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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 11:04:23 INFO - Downloaded 60934593 bytes. 11:04:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:04:23 INFO - mkdir: /builds/slave/test/properties 11:04:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:04:23 INFO - Writing to file /builds/slave/test/properties/build_url 11:04:23 INFO - Contents: 11:04:23 INFO - build_url:https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:04:23 INFO - Running post-action listener: _resource_record_post_action 11:04:23 INFO - Running post-action listener: set_extra_try_arguments 11:04:23 INFO - [mozharness: 2016-07-26 18:04:23.953160Z] Finished download-and-extract step (success) 11:04:23 INFO - [mozharness: 2016-07-26 18:04:23.953547Z] Running populate-webroot step. 11:04:23 INFO - Running pre-action listener: _resource_record_pre_action 11:04:23 INFO - Running main action method: populate_webroot 11:04:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:04:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:04:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:04:23 INFO - '--tppagecycles', 11:04:23 INFO - '7'], 11:04:23 INFO - 'tests': ['tsvgm']}}, 11:04:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:04:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:04:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:04:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:04:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['damp', 'tps']}, 11:04:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'tests': ['damp', 'tps']}, 11:04:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['dromaeo_dom']}, 11:04:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:04:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['basic_compositor_video']}, 11:04:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:04:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['a11yr', 11:04:23 INFO - 'ts_paint', 11:04:23 INFO - 'tpaint', 11:04:23 INFO - 'sessionrestore', 11:04:23 INFO - 'sessionrestore_no_auto_restore', 11:04:23 INFO - 'tabpaint']}, 11:04:23 INFO - 'other-e10s': {'tests': ['a11yr', 11:04:23 INFO - 'ts_paint', 11:04:23 INFO - 'tpaint', 11:04:23 INFO - 'sessionrestore', 11:04:23 INFO - 'sessionrestore_no_auto_restore', 11:04:23 INFO - 'tabpaint']}, 11:04:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:04:23 INFO - 'ts_paint', 11:04:23 INFO - 'tpaint', 11:04:23 INFO - 'sessionrestore', 11:04:23 INFO - 'sessionrestore_no_auto_restore', 11:04:23 INFO - 'tabpaint']}, 11:04:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:04:23 INFO - 'ts_paint', 11:04:23 INFO - 'tpaint', 11:04:23 INFO - 'sessionrestore', 11:04:23 INFO - 'sessionrestore_no_auto_restore', 11:04:23 INFO - 'tabpaint']}, 11:04:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['a11yr', 11:04:23 INFO - 'ts_paint', 11:04:23 INFO - 'tpaint', 11:04:23 INFO - 'sessionrestore', 11:04:23 INFO - 'sessionrestore_no_auto_restore', 11:04:23 INFO - 'tabpaint']}, 11:04:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['a11yr', 11:04:23 INFO - 'ts_paint', 11:04:23 INFO - 'tpaint', 11:04:23 INFO - 'sessionrestore', 11:04:23 INFO - 'sessionrestore_no_auto_restore', 11:04:23 INFO - 'tabpaint']}, 11:04:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['tsvgx', 11:04:23 INFO - 'tsvgr_opacity', 11:04:23 INFO - 'tart', 11:04:23 INFO - 'tscrollx', 11:04:23 INFO - 'cart']}, 11:04:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:04:23 INFO - 'tsvgr_opacity', 11:04:23 INFO - 'tart', 11:04:23 INFO - 'tscrollx', 11:04:23 INFO - 'cart']}, 11:04:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'talos_options': ['--disable-e10s'], 11:04:23 INFO - 'tests': ['tp5o']}, 11:04:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'tests': ['tp5o']}, 11:04:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'talos_options': ['--disable-e10s', 11:04:23 INFO - '--xperf_path', 11:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:23 INFO - 'tests': ['tp5n']}, 11:04:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:23 INFO - 'talos_options': ['--xperf_path', 11:04:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:23 INFO - 'tests': ['tp5n']}}, 11:04:23 INFO - 'talos.zip': {'path': '', 11:04:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:04:23 INFO - Running post-action listener: _resource_record_post_action 11:04:23 INFO - [mozharness: 2016-07-26 18:04:23.972917Z] Finished populate-webroot step (success) 11:04:23 INFO - [mozharness: 2016-07-26 18:04:23.973006Z] Running create-virtualenv step. 11:04:23 INFO - Running pre-action listener: _resource_record_pre_action 11:04:23 INFO - Running main action method: create_virtualenv 11:04:23 INFO - Creating virtualenv /builds/slave/test/build/venv 11:04:23 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 11:04:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:04:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:04:24 INFO - Using real prefix '/usr' 11:04:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:04:24 INFO - Installing distribute.............................................................................................................................................................................................done. 11:04:26 INFO - Installing pip.................done. 11:04:26 INFO - Return code: 0 11:04:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:04:26 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')]} 11:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:26 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')]} 11:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:26 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:26 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 11:04:26 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 11:04:26 INFO - Using env: {'DISPLAY': ':0', 11:04:26 INFO - 'HOME': '/home/cltbld', 11:04:26 INFO - 'LANG': 'en_US.UTF-8', 11:04:26 INFO - 'LANGUAGE': 'en_US:en', 11:04:26 INFO - 'LOGNAME': 'cltbld', 11:04:26 INFO - 'MAIL': '/var/mail/cltbld', 11:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:26 INFO - 'MOZ_NO_REMOTE': '1', 11:04:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:26 INFO - 'NO_EM_RESTART': '1', 11:04:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:26 INFO - 'PWD': '/builds/slave/test', 11:04:26 INFO - 'SHELL': '/bin/bash', 11:04:26 INFO - 'SHLVL': '1', 11:04:26 INFO - 'TERM': 'linux', 11:04:26 INFO - 'TMOUT': '86400', 11:04:26 INFO - 'USER': 'cltbld', 11:04:26 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:26 INFO - '_': '/tools/buildbot/bin/python'} 11:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:26 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 11:04:26 INFO - Cleaning up... 11:04:26 INFO - Return code: 0 11:04:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:04:26 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')]} 11:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:26 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')]} 11:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:26 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:26 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 11:04:26 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 11:04:26 INFO - Using env: {'DISPLAY': ':0', 11:04:26 INFO - 'HOME': '/home/cltbld', 11:04:26 INFO - 'LANG': 'en_US.UTF-8', 11:04:26 INFO - 'LANGUAGE': 'en_US:en', 11:04:26 INFO - 'LOGNAME': 'cltbld', 11:04:26 INFO - 'MAIL': '/var/mail/cltbld', 11:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:26 INFO - 'MOZ_NO_REMOTE': '1', 11:04:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:26 INFO - 'NO_EM_RESTART': '1', 11:04:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:26 INFO - 'PWD': '/builds/slave/test', 11:04:26 INFO - 'SHELL': '/bin/bash', 11:04:26 INFO - 'SHLVL': '1', 11:04:26 INFO - 'TERM': 'linux', 11:04:26 INFO - 'TMOUT': '86400', 11:04:26 INFO - 'USER': 'cltbld', 11:04:26 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:26 INFO - '_': '/tools/buildbot/bin/python'} 11:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:26 INFO - Downloading/unpacking psutil>=3.1.1 11:04: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 11:04: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 11:04: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 11:04: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 11:04: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 11:04: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 11:04:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:04:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:04:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:04:29 INFO - Installing collected packages: psutil 11:04:29 INFO - Running setup.py install for psutil 11:04:29 INFO - building 'psutil._psutil_linux' extension 11:04:29 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 11:04:29 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 11:04:29 INFO - building 'psutil._psutil_posix' extension 11:04:29 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 11:04:29 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 11:04:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:04:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:04:29 INFO - Successfully installed psutil 11:04:29 INFO - Cleaning up... 11:04:29 INFO - Return code: 0 11:04:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:29 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:29 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 11:04:29 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 11:04:29 INFO - Using env: {'DISPLAY': ':0', 11:04:29 INFO - 'HOME': '/home/cltbld', 11:04:29 INFO - 'LANG': 'en_US.UTF-8', 11:04:29 INFO - 'LANGUAGE': 'en_US:en', 11:04:29 INFO - 'LOGNAME': 'cltbld', 11:04:29 INFO - 'MAIL': '/var/mail/cltbld', 11:04:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:29 INFO - 'MOZ_NO_REMOTE': '1', 11:04:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:29 INFO - 'NO_EM_RESTART': '1', 11:04:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:29 INFO - 'PWD': '/builds/slave/test', 11:04:29 INFO - 'SHELL': '/bin/bash', 11:04:29 INFO - 'SHLVL': '1', 11:04:29 INFO - 'TERM': 'linux', 11:04:29 INFO - 'TMOUT': '86400', 11:04:29 INFO - 'USER': 'cltbld', 11:04:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:29 INFO - '_': '/tools/buildbot/bin/python'} 11:04:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:04:29 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 11:04:29 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 11:04:29 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 11:04:29 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 11:04:29 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 11:04:29 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 11:04:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:04:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:04:31 INFO - Installing collected packages: mozsystemmonitor 11:04:31 INFO - Running setup.py install for mozsystemmonitor 11:04:31 INFO - Successfully installed mozsystemmonitor 11:04:31 INFO - Cleaning up... 11:04:31 INFO - Return code: 0 11:04:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:04:31 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')]} 11:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:31 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')]} 11:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:31 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:31 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 11:04:31 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 11:04:31 INFO - Using env: {'DISPLAY': ':0', 11:04:31 INFO - 'HOME': '/home/cltbld', 11:04:31 INFO - 'LANG': 'en_US.UTF-8', 11:04:31 INFO - 'LANGUAGE': 'en_US:en', 11:04:31 INFO - 'LOGNAME': 'cltbld', 11:04:31 INFO - 'MAIL': '/var/mail/cltbld', 11:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:31 INFO - 'MOZ_NO_REMOTE': '1', 11:04:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:32 INFO - 'NO_EM_RESTART': '1', 11:04:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:32 INFO - 'PWD': '/builds/slave/test', 11:04:32 INFO - 'SHELL': '/bin/bash', 11:04:32 INFO - 'SHLVL': '1', 11:04:32 INFO - 'TERM': 'linux', 11:04:32 INFO - 'TMOUT': '86400', 11:04:32 INFO - 'USER': 'cltbld', 11:04:32 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:32 INFO - '_': '/tools/buildbot/bin/python'} 11:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:32 INFO - Downloading/unpacking jsonschema==2.5.1 11:04: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 11:04: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 11:04: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 11:04: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 11:04: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 11:04: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 11:04:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:04:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:04:34 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 11:04:34 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 11:04:34 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 11:04:34 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 11:04:34 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 11:04:34 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 11:04:34 INFO - Downloading functools32-3.2.3-2.tar.gz 11:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:04:34 INFO - warning: no files found matching '*.txt' 11:04:34 INFO - no previously-included directories found matching 'build' 11:04:34 INFO - no previously-included directories found matching 'dist' 11:04:34 INFO - no previously-included directories found matching '.git*' 11:04:34 INFO - Installing collected packages: jsonschema, functools32 11:04:34 INFO - Running setup.py install for functools32 11:04:34 INFO - warning: no files found matching '*.txt' 11:04:34 INFO - no previously-included directories found matching 'build' 11:04:34 INFO - no previously-included directories found matching 'dist' 11:04:34 INFO - no previously-included directories found matching '.git*' 11:04:34 INFO - Successfully installed jsonschema functools32 11:04:34 INFO - Cleaning up... 11:04:34 INFO - Return code: 0 11:04:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:04: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')]} 11:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04: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')]} 11:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04: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', '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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:04: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 functools32==3.2.3-2 11:04:34 INFO - Using env: {'DISPLAY': ':0', 11:04:34 INFO - 'HOME': '/home/cltbld', 11:04:34 INFO - 'LANG': 'en_US.UTF-8', 11:04:34 INFO - 'LANGUAGE': 'en_US:en', 11:04:34 INFO - 'LOGNAME': 'cltbld', 11:04:34 INFO - 'MAIL': '/var/mail/cltbld', 11:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:34 INFO - 'MOZ_NO_REMOTE': '1', 11:04:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:34 INFO - 'NO_EM_RESTART': '1', 11:04:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:34 INFO - 'PWD': '/builds/slave/test', 11:04:34 INFO - 'SHELL': '/bin/bash', 11:04:34 INFO - 'SHLVL': '1', 11:04:34 INFO - 'TERM': 'linux', 11:04:34 INFO - 'TMOUT': '86400', 11:04:34 INFO - 'USER': 'cltbld', 11:04:34 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:34 INFO - '_': '/tools/buildbot/bin/python'} 11:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:04:34 INFO - Cleaning up... 11:04:34 INFO - Return code: 0 11:04:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:04: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')]} 11:04:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04: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')]} 11:04:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04: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', '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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:04: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 blobuploader==1.2.4 11:04:34 INFO - Using env: {'DISPLAY': ':0', 11:04:34 INFO - 'HOME': '/home/cltbld', 11:04:34 INFO - 'LANG': 'en_US.UTF-8', 11:04:34 INFO - 'LANGUAGE': 'en_US:en', 11:04:34 INFO - 'LOGNAME': 'cltbld', 11:04:34 INFO - 'MAIL': '/var/mail/cltbld', 11:04:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:34 INFO - 'MOZ_NO_REMOTE': '1', 11:04:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:34 INFO - 'NO_EM_RESTART': '1', 11:04:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:34 INFO - 'PWD': '/builds/slave/test', 11:04:34 INFO - 'SHELL': '/bin/bash', 11:04:34 INFO - 'SHLVL': '1', 11:04:34 INFO - 'TERM': 'linux', 11:04:34 INFO - 'TMOUT': '86400', 11:04:34 INFO - 'USER': 'cltbld', 11:04:34 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:34 INFO - '_': '/tools/buildbot/bin/python'} 11:04:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:34 INFO - Downloading/unpacking blobuploader==1.2.4 11:04:34 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 11:04:34 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 11:04:34 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 11:04:34 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 11:04:34 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 11:04:34 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 11:04:36 INFO - Downloading blobuploader-1.2.4.tar.gz 11:04:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:04:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:04:36 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 11:04:36 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 11:04:36 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 11:04:36 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 11:04:36 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 11:04:36 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 11:04:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:04:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:04:37 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 11:04:37 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 11:04:37 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 11:04:37 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 11:04:37 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 11:04:37 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 11:04:37 INFO - Downloading docopt-0.6.1.tar.gz 11:04:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:04:37 INFO - Installing collected packages: blobuploader, requests, docopt 11:04:37 INFO - Running setup.py install for blobuploader 11:04:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:04:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:04:37 INFO - Running setup.py install for requests 11:04:38 INFO - Running setup.py install for docopt 11:04:38 INFO - Successfully installed blobuploader requests docopt 11:04:38 INFO - Cleaning up... 11:04:38 INFO - Return code: 0 11:04:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:38 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:38 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 11:04:38 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 11:04:38 INFO - Using env: {'DISPLAY': ':0', 11:04:38 INFO - 'HOME': '/home/cltbld', 11:04:38 INFO - 'LANG': 'en_US.UTF-8', 11:04:38 INFO - 'LANGUAGE': 'en_US:en', 11:04:38 INFO - 'LOGNAME': 'cltbld', 11:04:38 INFO - 'MAIL': '/var/mail/cltbld', 11:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:38 INFO - 'MOZ_NO_REMOTE': '1', 11:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:38 INFO - 'NO_EM_RESTART': '1', 11:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:38 INFO - 'PWD': '/builds/slave/test', 11:04:38 INFO - 'SHELL': '/bin/bash', 11:04:38 INFO - 'SHLVL': '1', 11:04:38 INFO - 'TERM': 'linux', 11:04:38 INFO - 'TMOUT': '86400', 11:04:38 INFO - 'USER': 'cltbld', 11:04:38 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:38 INFO - '_': '/tools/buildbot/bin/python'} 11:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:04:38 INFO - Running setup.py (path:/tmp/pip-VwmZ8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:04:38 INFO - Running setup.py (path:/tmp/pip-GyUS2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:04:38 INFO - Running setup.py (path:/tmp/pip-MiU5Bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:04:38 INFO - Running setup.py (path:/tmp/pip-j0BjLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:04:38 INFO - Running setup.py (path:/tmp/pip-b4SoO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:04:38 INFO - Running setup.py (path:/tmp/pip-4p2Rj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:04:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:04:38 INFO - Running setup.py (path:/tmp/pip-PTG74K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:04:39 INFO - Running setup.py (path:/tmp/pip-6T17QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:04:39 INFO - Running setup.py (path:/tmp/pip-ARK2WX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:04:39 INFO - Running setup.py (path:/tmp/pip-99dSj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:04:39 INFO - Running setup.py (path:/tmp/pip-vqi5bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:04:39 INFO - Running setup.py (path:/tmp/pip-F3fEeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:04:39 INFO - Running setup.py (path:/tmp/pip-X0fkSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:04:39 INFO - Running setup.py (path:/tmp/pip-8VO75e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:04:39 INFO - Running setup.py (path:/tmp/pip-bm6njL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:04:39 INFO - Running setup.py (path:/tmp/pip-RhmjuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:04:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:04:39 INFO - Running setup.py (path:/tmp/pip-r_cPUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:04:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:04:39 INFO - Running setup.py install for manifestparser 11:04:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:04:39 INFO - Running setup.py install for mozcrash 11:04:39 INFO - Running setup.py install for mozdebug 11:04:40 INFO - Running setup.py install for mozdevice 11:04:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Running setup.py install for mozfile 11:04:40 INFO - Running setup.py install for mozhttpd 11:04:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Running setup.py install for mozinfo 11:04:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Running setup.py install for mozInstall 11:04:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Running setup.py install for mozleak 11:04:40 INFO - Running setup.py install for mozlog 11:04:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Running setup.py install for moznetwork 11:04:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:04:40 INFO - Running setup.py install for mozprocess 11:04:40 INFO - Running setup.py install for mozprofile 11:04:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:04:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:04:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:04:41 INFO - Running setup.py install for mozrunner 11:04:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:04:41 INFO - Running setup.py install for mozscreenshot 11:04:41 INFO - Running setup.py install for moztest 11:04:41 INFO - Running setup.py install for mozversion 11:04:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:04:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:04:41 INFO - Cleaning up... 11:04:41 INFO - Return code: 0 11:04:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:41 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:41 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 11:04:41 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 11:04:41 INFO - Using env: {'DISPLAY': ':0', 11:04:41 INFO - 'HOME': '/home/cltbld', 11:04:41 INFO - 'LANG': 'en_US.UTF-8', 11:04:41 INFO - 'LANGUAGE': 'en_US:en', 11:04:41 INFO - 'LOGNAME': 'cltbld', 11:04:41 INFO - 'MAIL': '/var/mail/cltbld', 11:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:41 INFO - 'MOZ_NO_REMOTE': '1', 11:04:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:41 INFO - 'NO_EM_RESTART': '1', 11:04:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:41 INFO - 'PWD': '/builds/slave/test', 11:04:41 INFO - 'SHELL': '/bin/bash', 11:04:41 INFO - 'SHLVL': '1', 11:04:41 INFO - 'TERM': 'linux', 11:04:41 INFO - 'TMOUT': '86400', 11:04:41 INFO - 'USER': 'cltbld', 11:04:41 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:41 INFO - '_': '/tools/buildbot/bin/python'} 11:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:04:41 INFO - Running setup.py (path:/tmp/pip-qf8w2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:04:41 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)) 11:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:04:41 INFO - Running setup.py (path:/tmp/pip-_kaiyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:04:41 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)) 11:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:04:41 INFO - Running setup.py (path:/tmp/pip-tY33Qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:04:41 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)) 11:04:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:04:41 INFO - Running setup.py (path:/tmp/pip-2SBVIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:04:42 INFO - Running setup.py (path:/tmp/pip-lGJI2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:04:42 INFO - Running setup.py (path:/tmp/pip-ZVE6_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:04:42 INFO - Running setup.py (path:/tmp/pip-RBLiEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:04:42 INFO - Running setup.py (path:/tmp/pip-SvhwJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:04:42 INFO - Running setup.py (path:/tmp/pip-BsiBOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:04:42 INFO - Running setup.py (path:/tmp/pip-CZhLx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:04:42 INFO - Running setup.py (path:/tmp/pip-r9i4PS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:04:42 INFO - Running setup.py (path:/tmp/pip-prGMSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:04:42 INFO - Running setup.py (path:/tmp/pip-eCtB0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:04:42 INFO - Running setup.py (path:/tmp/pip-mq6oZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:04:42 INFO - Running setup.py (path:/tmp/pip-WOFdw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:04:42 INFO - Running setup.py (path:/tmp/pip-Hi37qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:04:42 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)) 11:04:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:04:42 INFO - Running setup.py (path:/tmp/pip-FF6vBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:04:42 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)) 11:04:42 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)) 11:04:42 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)) 11:04:42 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)) 11:04:42 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)) 11:04:42 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)) 11:04:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:04:42 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 11:04:42 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 11:04:42 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 11:04:42 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 11:04:42 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 11:04:42 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 11:04:44 INFO - Downloading blessings-1.6.tar.gz 11:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:04:44 INFO - Installing collected packages: blessings 11:04:44 INFO - Running setup.py install for blessings 11:04:45 INFO - Successfully installed blessings 11:04:45 INFO - Cleaning up... 11:04:45 INFO - Return code: 0 11:04:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:04:45 INFO - Reading from file tmpfile_stdout 11:04:45 INFO - Current package versions: 11:04:45 INFO - argparse == 1.2.1 11:04:45 INFO - blessings == 1.6 11:04:45 INFO - blobuploader == 1.2.4 11:04:45 INFO - docopt == 0.6.1 11:04:45 INFO - functools32 == 3.2.3-2 11:04:45 INFO - jsonschema == 2.5.1 11:04:45 INFO - manifestparser == 1.1 11:04:45 INFO - mozInstall == 1.12 11:04:45 INFO - mozcrash == 0.17 11:04:45 INFO - mozdebug == 0.1 11:04:45 INFO - mozdevice == 0.48 11:04:45 INFO - mozfile == 1.2 11:04:45 INFO - mozhttpd == 0.7 11:04:45 INFO - mozinfo == 0.9 11:04:45 INFO - mozleak == 0.1 11:04:45 INFO - mozlog == 3.3 11:04:45 INFO - moznetwork == 0.27 11:04:45 INFO - mozprocess == 0.23 11:04:45 INFO - mozprofile == 0.28 11:04:45 INFO - mozrunner == 6.12 11:04:45 INFO - mozscreenshot == 0.1 11:04:45 INFO - mozsystemmonitor == 0.3 11:04:45 INFO - moztest == 0.7 11:04:45 INFO - mozversion == 1.4 11:04:45 INFO - psutil == 3.1.1 11:04:45 INFO - requests == 1.2.3 11:04:45 INFO - wsgiref == 0.1.2 11:04:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:45 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:45 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 11:04:45 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 11:04:45 INFO - Using env: {'DISPLAY': ':0', 11:04:45 INFO - 'HOME': '/home/cltbld', 11:04:45 INFO - 'LANG': 'en_US.UTF-8', 11:04:45 INFO - 'LANGUAGE': 'en_US:en', 11:04:45 INFO - 'LOGNAME': 'cltbld', 11:04:45 INFO - 'MAIL': '/var/mail/cltbld', 11:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:45 INFO - 'MOZ_NO_REMOTE': '1', 11:04:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:45 INFO - 'NO_EM_RESTART': '1', 11:04:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:45 INFO - 'PWD': '/builds/slave/test', 11:04:45 INFO - 'SHELL': '/bin/bash', 11:04:45 INFO - 'SHLVL': '1', 11:04:45 INFO - 'TERM': 'linux', 11:04:45 INFO - 'TMOUT': '86400', 11:04:45 INFO - 'USER': 'cltbld', 11:04:45 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:45 INFO - '_': '/tools/buildbot/bin/python'} 11:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 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)) 11:04:45 INFO - Cleaning up... 11:04:45 INFO - Return code: 0 11:04:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:45 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 0x2a94030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d55180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, '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': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', '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 11:04:45 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 11:04:45 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 11:04:45 INFO - Using env: {'DISPLAY': ':0', 11:04:45 INFO - 'HOME': '/home/cltbld', 11:04:45 INFO - 'LANG': 'en_US.UTF-8', 11:04:45 INFO - 'LANGUAGE': 'en_US:en', 11:04:45 INFO - 'LOGNAME': 'cltbld', 11:04:45 INFO - 'MAIL': '/var/mail/cltbld', 11:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:45 INFO - 'MOZ_NO_REMOTE': '1', 11:04:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:04:45 INFO - 'NO_EM_RESTART': '1', 11:04:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:45 INFO - 'PWD': '/builds/slave/test', 11:04:45 INFO - 'SHELL': '/bin/bash', 11:04:45 INFO - 'SHLVL': '1', 11:04:45 INFO - 'TERM': 'linux', 11:04:45 INFO - 'TMOUT': '86400', 11:04:45 INFO - 'USER': 'cltbld', 11:04:45 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:45 INFO - '_': '/tools/buildbot/bin/python'} 11:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:04:45 INFO - Cleaning up... 11:04:45 INFO - Return code: 0 11:04:45 INFO - Running post-action listener: _resource_record_post_action 11:04:45 INFO - Running post-action listener: _start_resource_monitoring 11:04:45 INFO - Starting resource monitoring. 11:04:45 INFO - [mozharness: 2016-07-26 18:04:45.836072Z] Finished create-virtualenv step (success) 11:04:45 INFO - [mozharness: 2016-07-26 18:04:45.836553Z] Running install step. 11:04:45 INFO - Running pre-action listener: _resource_record_pre_action 11:04:45 INFO - Running main action method: install 11:04:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:04:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:04:46 INFO - Reading from file tmpfile_stdout 11:04:46 INFO - Detecting whether we're running mozinstall >=1.0... 11:04:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:04:46 INFO - Reading from file tmpfile_stdout 11:04:46 INFO - Output received: 11:04:46 INFO - Usage: mozinstall [options] installer 11:04:46 INFO - Options: 11:04:46 INFO - -h, --help show this help message and exit 11:04:46 INFO - -d DEST, --destination=DEST 11:04:46 INFO - Directory to install application into. [default: 11:04:46 INFO - "/builds/slave/test"] 11:04:46 INFO - --app=APP Application being installed. [default: firefox] 11:04:46 INFO - mkdir: /builds/slave/test/build/application 11:04:46 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'] 11:04:46 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 11:05:03 INFO - Reading from file tmpfile_stdout 11:05:03 INFO - Output received: 11:05:03 INFO - /builds/slave/test/build/application/firefox/firefox 11:05:03 INFO - Running post-action listener: _resource_record_post_action 11:05:03 INFO - [mozharness: 2016-07-26 18:05:03.414312Z] Finished install step (success) 11:05:03 INFO - [mozharness: 2016-07-26 18:05:03.414588Z] Running run-tests step. 11:05:03 INFO - Running pre-action listener: _resource_record_pre_action 11:05:03 INFO - Running main action method: run_tests 11:05:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:05:03 INFO - Python 2.7.3 11:05:03 INFO - Return code: 0 11:05:03 INFO - grabbing minidump binary from tooltool 11:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:03 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 0x2d533b0>, '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 0x2d542b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d54740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:05:03 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 11:05:03 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 11:05:03 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 11:05:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:05:03 INFO - Return code: 0 11:05:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:05:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:05:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:05:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:05:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:05:03 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/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 11:05:03 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/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 11:05:03 INFO - Using env: {'DISPLAY': ':0', 11:05:03 INFO - 'HOME': '/home/cltbld', 11:05:03 INFO - 'LANG': 'en_US.UTF-8', 11:05:03 INFO - 'LANGUAGE': 'en_US:en', 11:05:03 INFO - 'LOGNAME': 'cltbld', 11:05:03 INFO - 'MAIL': '/var/mail/cltbld', 11:05:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:05:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:03 INFO - 'MOZ_NO_REMOTE': '1', 11:05:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:05:03 INFO - 'NO_EM_RESTART': '1', 11:05:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:03 INFO - 'PWD': '/builds/slave/test', 11:05:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:05:03 INFO - 'SHELL': '/bin/bash', 11:05:03 INFO - 'SHLVL': '1', 11:05:03 INFO - 'TERM': 'linux', 11:05:03 INFO - 'TMOUT': '86400', 11:05:03 INFO - 'USER': 'cltbld', 11:05:03 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744', 11:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:03 INFO - '_': '/tools/buildbot/bin/python'} 11:05:03 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/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 11:05:03 INFO - mozversion application_buildid: 20160726080520 11:05:03 INFO - mozversion application_changeset: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac 11:05:03 INFO - mozversion application_display_name: Nightly 11:05:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:05:03 INFO - mozversion application_name: Firefox 11:05:03 INFO - mozversion application_remotingname: firefox 11:05:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:05:03 INFO - mozversion application_vendor: Mozilla 11:05:03 INFO - mozversion application_version: 50.0a1 11:05:03 INFO - mozversion platform_buildid: 20160726080520 11:05:03 INFO - mozversion platform_changeset: ceb63dec9267e9bb62f5e5e1f4c9d32d3ac1fbac 11:05:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:05:03 INFO - mozversion platform_version: 50.0a1 11:05:03 INFO - using testdate: 1469556303 11:05:03 INFO - actual date: 1469556303 11:05:03 INFO - starting webserver on 'localhost:43322' 11:05:03 INFO - SUITE-START | Running 2 tests 11:05:03 INFO - TEST-START | dromaeo_css 11:05:03 INFO - operating with platform_type : linux_ 11:05:03 INFO - Initialising browser for dromaeo_css test... 11:05:03 INFO - TEST-INFO | started process 12829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHW9Cyr/profile http://localhost:43322/getInfo.html) 11:05:07 INFO - PROCESS | 12829 | JavaScript error: , line 0: uncaught exception: undefined 11:05:07 INFO - PROCESS | 12829 | __metrics Screen width/height:1600/1200 11:05:07 INFO - PROCESS | 12829 | colorDepth:24 11:05:07 INFO - PROCESS | 12829 | Browser inner width/height: 1024/697 11:05:07 INFO - PROCESS | 12829 | __metrics 11:05:08 INFO - PROCESS | 12829 | 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" 11:05:08 INFO - PROCESS | 12829 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:05:09 INFO - TEST-INFO | 12829: exit 0 11:05:09 INFO - Browser initialized. 11:05:09 INFO - Running cycle 1/1 for dromaeo_css test... 11:05:09 INFO - TEST-INFO | started process 12910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHW9Cyr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:05:21 INFO - PROCESS | 12910 | RSS: Main: 157663232 11:05:21 INFO - PROCESS | 12910 | 11:06:13 INFO - PROCESS | 12910 | 1469556373188 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] 11:08:21 INFO - PROCESS | 12910 | Cycle 1(1): loaded http://localhost:43322/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43322/tests/dromaeo/cssquery-ext.html) 11:08:22 INFO - PROCESS | 12910 | RSS: Main: 257642496 11:08:22 INFO - PROCESS | 12910 | 11:11:24 INFO - PROCESS | 12910 | Cycle 1(1): loaded http://localhost:43322/tests/dromaeo/cssquery-ext.html (next: http://localhost:43322/tests/dromaeo/cssquery-jquery.html) 11:11:25 INFO - PROCESS | 12910 | RSS: Main: 242638848 11:11:25 INFO - PROCESS | 12910 | 11:14:27 INFO - PROCESS | 12910 | Cycle 1(1): loaded http://localhost:43322/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43322/tests/dromaeo/cssquery-mootools.html) 11:14:27 INFO - PROCESS | 12910 | RSS: Main: 219267072 11:14:27 INFO - PROCESS | 12910 | 11:17:29 INFO - PROCESS | 12910 | Cycle 1(1): loaded http://localhost:43322/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43322/tests/dromaeo/cssquery-prototype.html) 11:17:30 INFO - PROCESS | 12910 | RSS: Main: 184451072 11:17:30 INFO - PROCESS | 12910 | 11:20:32 INFO - PROCESS | 12910 | Cycle 1(1): loaded http://localhost:43322/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43322/tests/dromaeo/cssquery-yui.html) 11:20:33 INFO - PROCESS | 12910 | RSS: Main: 186503168 11:20:33 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | Cycle 1(1): loaded http://localhost:43322/tests/dromaeo/cssquery-yui.html (next: http://localhost:43322/tests/dromaeo/cssquery-dojo.html) 11:23:36 INFO - PROCESS | 12910 | RSS: Main: 276905984 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | __start_tp_report 11:23:36 INFO - PROCESS | 12910 | _x_x_mozilla_page_load 11:23:36 INFO - PROCESS | 12910 | _x_x_mozilla_page_load_details 11:23:36 INFO - PROCESS | 12910 | |i|pagename|runs| 11:23:36 INFO - PROCESS | 12910 | |0;dojo.html;3020.7432368248697;3055.8777648894047;3089.2431354318182;3098.271906102066;3101.922451938701;5168.22476628506;5258.868528286795;5261.684298942061;5337.89324213516;5348.946510534889;5297.788088476466;5338.866528336794;5359.08895487767;5365.356157261124;5401.405845357018;4753.1681955657705;4830.372051633282;4845.491223421543;4851.27230915362;5100;5008.949910500903;5023.899522009558;5048.242763585455;5059.089363914494;5068.518490743373;4742.976285118587;4767.1419144553965;4819.566239038469;4867.294242334877;4993.650444468889;4810.446798618169;4827.879303017417;4942.332785074041;4949.455559888409;4972.975135124301;4836.830710925136;4868.537488938545;4881.121398148367;4884.658073934826;4922.163232250526;5251.422343542207;5364.973175134139;5409.0534156522845;5419.403865574784;5422.322209723785;5691.715414229313;5755.482006619382;5761.366249712528;5826.708664566797;5831.037878750001;5829.737661805187;5831.562632802556;5844.795432159854;5867;5873.853153671193;5193.558547523465;5293.12663410533;5429.185622156669;5481.424450432727;5491.945080549183;5792.507636850914;5843.620164689281;5848.502877255396;5886.705664716833;5902.173695682608;5593.916091258634;5645.23789288449;5654.208410822406;5657.915131273035;5660.179274005246;5572.58205634579;5666.518345940559;5675.262215911905;5715.571332150105;5717.542596592346;5772.624779389326;5782.59521833476;5785.71071446426;5824.504917082096;5834.824955251349;5737.942620573825;5743.54051675865;5795.449432303924;5806.622569533051;5808.273965754281;4227.7463352198965;4309.741415515079;4438.889027774331;4442.444694413199;4487.506374298824;3869.903252418656;3907.4529565860803;3917.981324855558;3919.9020024499046;3990.06233535119;5481.068218402881;5483.424240454775;5492.395836457987;5496;5533.529649979716;5599.7480113394995;5797.333306669702;5802.912956305659;5814.825555233349;5840.415958404125;5420.864478388072;5459.672419654754;5466.753996070186;5524.723763811793;5534.695591742412;4998.850034498971;4999.175136102575;5000.374953130859;5004.899902001939;5036.194208926554;5946.732397042144;5996.310424301261;6004.83490683352;6010.27876654813;6058.757649694051;5590.2453168821685;5730.627509211888;5737.512311453574;5769.221155144001;5788.652680839163;6008.1588577598295;6008.639481631116;6011.188489553854;6018.548608854441;6044.788432404845;5629.014922388647;5681.573881958952;5697.772089116389;5722.599418040697;5730.255066841284;5541.113421852484;5619.550435965195;5653.689047102449;5653.830385088454;5806.535477161901;5525.530329921841;5574.470425309589;5577.163425486047;5582.888342233214;5618.69097199658;5564.4991950724625;5575.832725018256;5578.023845827045;5580.525655319343;5581.6930068846605;5345.465453454779;5556.444355564411;5572.136318870582;5603.943960560342;5634.295713035871;5756.222909907109;5803.709814509173;5813.215215945962;5816.796412125555;5817.069268916953;5621.213030175697;5624.268845050117;5629.690367029689;5630.042892708331;5637.802676906289 11:23:36 INFO - PROCESS | 12910 | |1;ext.html;603.167628672432;614.8001899382699;623.5074291309865;635.815613472093;638.7604648256904;42891.35662965054;44091.55908440915;44184.337234941464;44509.77745111274;48029;45462.545374546244;46274.30588541175;46282.30576541355;46329.305060424034;46362.53637463624;8866.999999999984;9092.408993415407;9098.909010909872;9100.590473428678;9106;9018.503982280972;9079.818403631956;9106.317026223027;9110;9131.72604821853;9098.408603440788;9152;9156;9156.130167634064;9158.358914875962;9025.232855207316;9080.273578113734;9082.591283392198;9090.454572725625;9092.408993415407;8926.062763409813;8979.68571100015;8997.730068097968;9013.233875120623;9018.86471702925;43316.78341608303;43772;43807.561924380665;43849;43864;3618.294432585647;3700.1674623209565;3832.9808350958347;3947.4473573699706;4081.0001549620238;32338.83830580832;32396.83801580977;32489.83755081233;33307.33385332304;34241;3996.3805610130476;4038.697097717683;4050.18996200761;4065.654419374327;4077.0826564023155;347503;350570;352159;352865;353414;57849.13226301609;58196;58243;58275.70862145662;58293.70853145707;36924;37659.05852353658;37737.245255095295;37743.62256377456;61658;17705.38031168903;17881.821181788277;17893.284268629108;17894.463166105037;17901;13502.729945401037;13509.2569908654;13513.391897364641;13596.320183990762;13600.795988060187;19632;19672.70490942637;19758.90120549388;19932.402027939184;19957.800421995886;7147.450436932652;7285;7310.157544777495;7421.480496365202;7540.396768258526;968.2157452463528;1016.7204018894863;1071.0414179309475;1102.7132945434084;1109.5561775289948;33063;33141.83429082839;33178.834105829315;33257.16857078592;33738.83130584331;1067.3382502848283;1076.9084627806724;1104;1104.690686607751;1123.4663534820895;17067.146642667816;17284.578632913293;17505.037222952604;17602.295908163785;17754.822451775315;549534;551058;553771;553889;557092;58576;59124;59183;59236.40763592395;59243.407565923786;1233.802591585342;1342.2684636872732;1389.3539504130629;1398.077269002474;1402.291842619491;31504.527432088078;33489.162770930925;33941.49087763735;34150.31699366048;34342.314336770265;50972.74513627408;51536;51544.22683659673;51642.483575163766;51654.48345516497;439448;439557;440188;441010;442354;25456.74543254544;27632.723672763015;28397.006104786233;28467.28831779222;28694.56958145671;45691.31463028124;46727.82268061658;46930.296045560026;47240.29139562978;49217.75391123021;40922.79538602288;41585.792071039454;41734.373984389655;42097;43683.78158109189;40829.38755918604;41185.58814411818;41234.38148427836;42443.57556424396;42612.5738742621;41704;41908.79045604752;42165.78917105395;42227.57772422236;42458.5754142467;34929.65070349264;36861.44707829331;37096.6290337104;37461.81269093637;37644.435333469475;28206.717932820407;28221.251092520914;28258.15225543237;28341.858290708413;28534.57198141988 11:23:36 INFO - PROCESS | 12910 | |2;jquery.html;382.7703377973218;395.1050869779949;400.95589485156626;401.8372559113559;418.49342367477084;14355.850849079638;17268.740968885446;18383.72424413633;20096.89951550242;20117.59764804703;15627.296771645275;18894.433167005012;19589.412317630493;20171.798282017175;20459.897700511476;5772.884542309152;5947.821565353046;5954.404559544054;5963.582549221556;5977.671228082454;5844.123381492767;5901.114832775075;5907.202527658776;5913.201717768111;5920.319163296233;5692.402297758717;5773.797917072903;5806.49025264273;5814.331351894522;5826.766929322822;5665.773369065232;5819.621724587888;5846.23998880145;5878.706064696748;5888.440598143169;5187.195984622352;5214.739263036871;5222.399424066243;5226.529612334908;5229.398619158769;23812.166574169994;24355.26934191977;24569.14008009711;24570.385740356454;24571.508569828504;2401.939951501209;2513.987430062856;2525.532776436365;2576.2786419802487;3008.699130086996;14283.428662853474;14802.851971480362;15114.470993515284;15442.382304707911;15637.687246255126;1947.5423275530238;1956.863019588615;1959.4415591556337;1980.762308522972;2002.709607106976;80869.59565202195;81218;81963.5901820487;82232.58883705603;83405.58297208475;10404.947975260075;10611;10667.19996000303;10937.890621093688;10974.341539507655;15101.622459438602;15107.244637768068;15136.243187840564;15148.924255378653;15158.621034474007;8025.919740802634;8292.751217463485;8398.790030249293;8465.576721163918;8536;8109.43233973628;8173.591320433955;8283.337333013344;8439.240468357877;8450.830983380421;5868.471837534642;6263.530235232376;6472.213754953311;6668;6849.075374824434;1844.4190080124717;1919.251491918153;1922.7404300419264;1936.1190658250744;2030.482227032127;979.8873129590046;986;1002.799440111966;1004.5489006823327;1072.2869291920786;20120.39638810838;20577.897110514652;20630.27794027202;20743.481412964797;20824;995.6701318913632;1045.7960697664034;1080.9945950270355;1083.1172594335592;1100.6697990602788;11404.315741055563;11563.479643416067;11772.646820595395;11823.822642660367;11848.644540663794;438748;449445.5499005105;464559;465144;465952.3833331702;61895;75846;80469.59765201136;82264;82351.5882420596;995.1491474788951;1004.6082028009082;1027.5581499955092;1029.361795686679;1030.4641586375044;17457.30170793154;17752.11239438049;17825.73261401106;17893.552661183574;18493.352732654294;41088.38367424551;43309.78345108254;44324;46700.7664961673;46718.76640616775;58693.41306586879;60024.69987650034;60507.69746151241;60792;61652;8073.35413166957;8319.043310019426;8668.436551624125;8865.246454051477;8968.461892286483;12926.676833079247;13882.583522494342;14026.579202623938;14052.227127508086;14208.218547979961;11597.88402115968;12036.338001409656;12200.633980980585;12598.748025039264;12705.491780328683;11560.826587601025;11625.302481851117;11691.123165762432;11955.342456164994;12498.12513124109;11914.225575337576;11988.100892432927;12517.436715348013;12599.307038112991;12926.095173337777;8536.146385361415;8607.913920860961;8624.913750862412;8746.212840844355;9034.367594268339;11656.18406711556;12332.32172230536;12332.691682708004;12407.131500794858;12451.049003112263 11:23:36 INFO - PROCESS | 12910 | |3;mootools.html;1360.6938438851253;1452.3972551391173;1529.755239161734;1629.8777591680628;1810.9366172183977;8509.085455772449;8876.511791851446;8960.372773905838;9153.450792952428;9171.266298696106;9119.452832830018;9192.678256261031;9204.815903681922;9212.078792120785;9212.309076819232;2296.1848543766932;2325.732540757809;2327.615943369342;2328.138588722167;2328.6507023946374;2364.893579788905;2409.4578719787996;2427.4052857050046;2429.769171928664;2430.902763889442;2364.2316247219637;2371.419002344428;2380.143148466551;2441.218809980807;2445.682061332024;2330.149495434171;2358.834881558283;2363.988180059106;2364.4207169243473;2382.630692242688;2269.205777977711;2295.5294164696197;2296.471811483368;2298.276043046452;2298.4139044543535;8978.371513994023;9027.368084234105;9080.409773364776;9114.680986165453;9115.954420227856;5333.813316533941;5559.166125081229;5561.77752889884;5564.248826408423;5567.053600887818;3307.140143562667;3342.1644588852787;3358.059743271888;3382.4080785862375;3399.201187720884;1041.317936751429;1065.6856227412932;2023.403096086658;2047.396018174642;2047.7645070816898;12851.100422970396;12883.484660613563;12972.805407918795;12977.156484828456;12980.415881285366;4409.162259170811;4557.908841823145;4573.954260457419;4670.6497012724185;4680.4149481314835;9954.203663706887;10050.798984020425;10053.145482633912;10078.949605251926;10085.747856303504;5379.381371142369;5380.112281473591;5383.892322153613;5386.48828361305;5389.056915039852;4165.541790403053;4424.46906371231;4567.703099298535;4976.527229913152;5045.066662667418;3766.133789228493;3784.7161462890394;3870.7096967727534;3873.6126387361037;3883.86406475772;1110.5446766825562;1139.0602752729033;1139.5327915554583;1142.7600203957095;1160.1646814293695;949.7388218240039;1008.0423597582179;1013.761765984993;1034.6585626743308;1035.5909415780725;6061;6186.4741496973265;6208.72060757267;6259.843503912439;6315.494760419156;454.926373757931;455.96124329431706;458.8875725447287;462.45199438665287;462.91204671113053;4282.700210985264;4283.057727299989;4298.613124818781;4405.911881762347;4414.646828253732;11203.327800331843;11515.30908145514;11613.651590452131;11615.593454229062;11619;10663.680089597325;11121.05471034971;11138.053265472525;11450.599229026944;11499;8365.749027529184;8540.871886921825;8555.529445880537;8592.527410992456;8595.355348348774;6154.138420621027;6215.451664817403;6424.614523128627;6448.613083215022;6522.34776522344;10253.948730256301;10469.424181670081;10488.842667359842;10528.473576321307;10618.787624247627;9591.664291749756;10668.89331106679;10897.891021090005;10919.071878890383;10924.12606991423;7787.610619469117;7976.880346794686;8046.678132874619;8184.263416292562;8191.303739182236;8696.956515217384;8758.167974042455;8804.515751633466;8820.91179088201;8828.823423531365;7865.252800983897;7920.603969801372;8034.799130021563;8048.959755201186;8162.877556836534;7847.489913155627;7855.60721963888;7892.881606775788;7954.721584744506;8002.239787220205;7281.708731650675;7288.854222915618;7323.560586364835;7332.340089391981;7335.4498412618195;5249.018804989279;5322.254884316276;5403.702796346239;5487.039768040497;5543.25166102571;5306.097963346318;5434.157705555645;5502.477264659851;5548.278723765885;5626.409227031267 11:23:36 INFO - PROCESS | 12910 | |4;prototype.html;331.32905865622115;338.8018009464463;341.8615460738401;348.54862952476543;349.3711319624675;5280.313559237298;5324.813631522898;5329.467053294668;5355.357357117142;5380.273663055496;5277.736113194344;5305.336832895888;5319.494648008433;5326.627136100465;5335.146376579738;3981.163955569327;4012.919741605166;4129.236091323115;4145.79271036447;4215.451991241134;4189.402570406337;4199.895002624929;4211.263028969934;4217.346311321735;4229.471316085489;4159.085001299724;4180.435641188446;4181.498220213579;4185.97907010466;4192.224438478891;3990.8004599770043;4010.578889216634;4138.772367519785;4159.625633692982;4200.768957707325;3680.6319368063246;3687.2625474905126;3690.907727306812;3691.427828686558;3697.8890633280776;5150.27896094547;5154.639175257734;5154.845354639367;5168.224766285088;5176.6117541184185;4431.202383570956;4650.25595904657;4668.719876807369;4703.64722645803;4706.717596944194;5477.904419116193;5489;5491.615586908918;5497.185928643538;5502.58730595203;3963.256167286385;3969;3978.900527486836;3997.72015958883;3999.5400528939253;5533.257024037415;5670.68811215383;5688.943110568883;5690.772369105231;5770.163326317703;4039.7374170678813;4042.4946881639794;4059.4519739835337;4060.7614677523047;4080.347144456932;4122.0107174277955;4127.525334586501;4128.545859955402;4130.173965206971;4130.814113364906;4606.239970404846;4674.976625116853;4680.274557443602;4693.366395536627;4729.148753224453;5505.26584125999;5517.586181036439;5520.696361700065;5529.142982837667;5617.859553511195;1393.477445957766;1418.4822539773115;1421.3319739722315;1430.4135304525096;1438.216172186161;858.5063588436674;893.1827377949234;923.752933539716;933.869258303838;938.1462868789368;5233.790648374023;5376.6020834582505;5382.542483888913;5384.596155288369;5385;5692;5692.23154874095;5713.857153571111;5723.6279641823985;5733.881893030903;5234;5367.75845086972;5395.325584301962;5398.352197736297;5416.83749487516;2991.865366058533;3022.2444388902773;3025;3025.13783571681;3036.711512406318;5897.76408943646;5916.112583112567;5918.763249469973;5931;5941.673207973603;5569.498745112959;5778.508826749689;5781;5796.739146738407;5805.1713710182;5805.680687562224;5823.330317013513;5831.94168058314;5837.241158649434;5839.532837373085;5473.042217611823;5563.525665698674;5638.718064096861;5653.886922261614;5662.292213473316;5619.775208991595;5629.536320556766;5630.605857590093;5632.098864181711;5633.295838020247;5870.148828419769;5883.764649414146;5884.852878677896;5895.970520147372;5922.318933322723;3891.3579259421876;3998.400239963912;4004.619561141687;4010.57888921659;4012.819423125908;5694.686792226467;5701.857453563694;5709.486146246858;5711.400302968129;5729.541636668973;5466.671999680032;5525.585581081516;5625.493705566519;5631.183478395692;5650.406707295675;5540.252065971043;5561.360443548881;5566.192902029264;5573.8606534835335;5576.86057848557;5404.243405923253;5454.127339625641;5465.289512363367;5485.369182544058;5498.697571633438;5265.157574788015;5380.381256155592;5523.25436066142;5554.1391084381985;5562.499375056264;5445.564354851682;5455.636090977256;5479.671219726829;5487.368952570506;5493.313335833021 11:23:36 INFO - PROCESS | 12910 | |5;yui.html;752.2439947852407;829.117902361639;840.1430540848337;850.4982060584258;858.7466697324289;17443.651126977453;18118.909405452956;18152.092395380212;18152.727709084356;18156.36452724155;16806.32774689014;16912.238949247283;16913;16963.49109526713;16968;820.4871955028123;840.5209030852417;841.5329492131864;841.9536925469097;842.1830824100613;664.7872680742164;801.6993627389729;826.5164878546028;830.6262182018086;834.9791255218607;859.042167982701;859.4070091636781;859.621766422772;859.9656013759442;860.371928492198;854.1159899504046;854.150120629974;854.3250831842837;854.9786255343604;855.2772906893665;511.4757373691995;613.7698363113832;631.0597210156833;634.2230767310054;639.8752243312556;15224.238788060664;15246.161461119635;15276.38894444221;15290.464833730766;15293.923530382388;14235.644108897257;14235.715285694228;14368.281585920766;14381.856181438156;14599.78100328496;13042.608721738363;13225.603231903058;13327.133736307205;13413.530526431528;13627.727445451135;4593.173228818811;4600.194965880957;4638.11875743611;4690.1557719610455;4845.466998630148;74248;75228.6238568809;75777.24222757758;75968.24031759668;76078.23921760767;11177.21759476845;11475.426228688531;11555.826662600068;11568.24806387582;11687;21014.579708405745;21019.78980210209;21037.15851365959;21218.78781212199;21312.893435532722;8262.256396924186;8369.49783013021;8620.353473489513;8874.29005679544;8884.333674974403;4893.290472881412;4977.104121258136;5102.285680004802;5326.493983071602;5328.973355133199;3593.8921832345072;3698.6301369863336;3705.629437056327;3710.7588006779474;3714.7213958953184;605.0923614578132;610.9083637454329;621.5462712220104;635.1362147479424;643.9388258115472;616.5745635511482;626.37988391493;685.6537448588431;691.6783695581579;704.503325155764;9064.728058158265;9539.643466867867;9668.371555848847;9675.612975480903;10010.295161274189;555.9332880054419;620.4973971083436;666.2837449741547;687.7317846040048;710.2613282186468;4583.839565615254;4896.877578060577;4925.60338392635;4925.655204135676;4994.700317980933;74820.25179748241;99528;114110;116413;121982;100953.49523252483;101708;101710;103651;105383.4730826341;86272;90324.99999999869;90443.54778226199;90792.54603726939;91252;9027.45835249887;9139.817203656023;9199.310051746012;9356.391834530734;9567.569459374206;46333.30500042435;50541.74729126331;51796.741016294676;51926.74036629944;52614;71452;71664.64167679129;73731;77897.61051194707;78665.60667196628;11270.380129092651;11289.096872250364;11340.262882912584;11430.428478575872;11680.123990700562;12375.443105060474;12462.376881155724;12475.563355282522;12785.488580456678;12864.614061578168;11147.498362573866;11181.385023823443;11199.21605487608;11314.943425282821;11540.422978851191;11966.341851198267;12046.216995895238;12648.304343261208;14448.439165902242;14993.100413975197;13444.798328025283;13898.16611003343;14072.226027568173;14137.43450261978;14641.853581464475;11556.768864622829;11726;11768.76462470763;11897.226680265756;12228.69428264265;10550.366977981346;11221.207307387009;12029;12405.813912791134;12521.499140034297 11:23:36 INFO - PROCESS | 12910 | __end_tp_report 11:23:36 INFO - PROCESS | 12910 | __start_cc_report 11:23:36 INFO - PROCESS | 12910 | _x_x_mozilla_cycle_collect,919 11:23:36 INFO - PROCESS | 12910 | __end_cc_report 11:23:36 INFO - PROCESS | 12910 | __startTimestamp1469557416852__endTimestamp 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | ------- Summary: start ------- 11:23:36 INFO - PROCESS | 12910 | Number of tests: 6 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | [#0] dojo.html Cycles:165 Average:5352.46 Median:5574.47 stddev:617.80 (11.1%) stddev-sans-first:591.99 11:23:36 INFO - PROCESS | 12910 | Values: 3020.7 3055.9 3089.2 3098.3 3101.9 5168.2 5258.9 5261.7 5337.9 5348.9 5297.8 5338.9 5359.1 5365.4 5401.4 4753.2 4830.4 4845.5 4851.3 5100.0 5008.9 5023.9 5048.2 5059.1 5068.5 4743.0 4767.1 4819.6 4867.3 4993.7 4810.4 4827.9 4942.3 4949.5 4973.0 4836.8 4868.5 4881.1 4884.7 4922.2 5251.4 5365.0 5409.1 5419.4 5422.3 5691.7 5755.5 5761.4 5826.7 5831.0 5829.7 5831.6 5844.8 5867.0 5873.9 5193.6 5293.1 5429.2 5481.4 5491.9 5792.5 5843.6 5848.5 5886.7 5902.2 5593.9 5645.2 5654.2 5657.9 5660.2 5572.6 5666.5 5675.3 5715.6 5717.5 5772.6 5782.6 5785.7 5824.5 5834.8 5737.9 5743.5 5795.4 5806.6 5808.3 4227.7 4309.7 4438.9 4442.4 4487.5 3869.9 3907.5 3918.0 3919.9 3990.1 5481.1 5483.4 5492.4 5496.0 5533.5 5599.7 5797.3 5802.9 5814.8 5840.4 5420.9 5459.7 5466.8 5524.7 5534.7 4998.9 4999.2 5000.4 5004.9 5036.2 5946.7 5996.3 6004.8 6010.3 6058.8 5590.2 5730.6 5737.5 5769.2 5788.7 6008.2 6008.6 6011.2 6018.5 6044.8 5629.0 5681.6 5697.8 5722.6 5730.3 5541.1 5619.6 5653.7 5653.8 5806.5 5525.5 5574.5 5577.2 5582.9 5618.7 5564.5 5575.8 5578.0 5580.5 5581.7 5345.5 5556.4 5572.1 5603.9 5634.3 5756.2 5803.7 5813.2 5816.8 5817.1 5621.2 5624.3 5629.7 5630.0 5637.8 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | [#1] ext.html Cycles:180 Average:60815.34 Median:42002.90 stddev:120880.27 (287.8%) stddev-sans-first:121134.83 11:23:36 INFO - PROCESS | 12910 | Values: 603.2 614.8 623.5 635.8 638.8 42891.4 44091.6 44184.3 44509.8 48029.0 45462.5 46274.3 46282.3 46329.3 46362.5 8867.0 9092.4 9098.9 9100.6 9106.0 9018.5 9079.8 9106.3 9110.0 9131.7 9098.4 9152.0 9156.0 9156.1 9158.4 9025.2 9080.3 9082.6 9090.5 9092.4 8926.1 8979.7 8997.7 9013.2 9018.9 43316.8 43772.0 43807.6 43849.0 43864.0 3618.3 3700.2 3833.0 3947.4 4081.0 32338.8 32396.8 32489.8 33307.3 34241.0 3996.4 4038.7 4050.2 4065.7 4077.1 347503.0 350570.0 352159.0 352865.0 353414.0 57849.1 58196.0 58243.0 58275.7 58293.7 36924.0 37659.1 37737.2 37743.6 61658.0 17705.4 17881.8 17893.3 17894.5 17901.0 13502.7 13509.3 13513.4 13596.3 13600.8 19632.0 19672.7 19758.9 19932.4 19957.8 7147.5 7285.0 7310.2 7421.5 7540.4 968.2 1016.7 1071.0 1102.7 1109.6 33063.0 33141.8 33178.8 33257.2 33738.8 1067.3 1076.9 1104.0 1104.7 1123.5 17067.1 17284.6 17505.0 17602.3 17754.8 549534.0 551058.0 553771.0 553889.0 557092.0 58576.0 59124.0 59183.0 59236.4 59243.4 1233.8 1342.3 1389.4 1398.1 1402.3 31504.5 33489.2 33941.5 34150.3 34342.3 50972.7 51536.0 51544.2 51642.5 51654.5 439448.0 439557.0 440188.0 441010.0 442354.0 25456.7 27632.7 28397.0 28467.3 28694.6 45691.3 46727.8 46930.3 47240.3 49217.8 40922.8 41585.8 41734.4 42097.0 43683.8 40829.4 41185.6 41234.4 42443.6 42612.6 41704.0 41908.8 42165.8 42227.6 42458.6 34929.7 36861.4 37096.6 37461.8 37644.4 28206.7 28221.3 28258.2 28341.9 28534.6 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | [#2] jquery.html Cycles:180 Average:28165.95 Median:24570.95 stddev:75206.93 (306.1%) stddev-sans-first:75388.98 11:23:36 INFO - PROCESS | 12910 | Values: 382.8 395.1 401.0 401.8 418.5 14355.9 17268.7 18383.7 20096.9 20117.6 15627.3 18894.4 19589.4 20171.8 20459.9 5772.9 5947.8 5954.4 5963.6 5977.7 5844.1 5901.1 5907.2 5913.2 5920.3 5692.4 5773.8 5806.5 5814.3 5826.8 5665.8 5819.6 5846.2 5878.7 5888.4 5187.2 5214.7 5222.4 5226.5 5229.4 23812.2 24355.3 24569.1 24570.4 24571.5 2401.9 2514.0 2525.5 2576.3 3008.7 14283.4 14802.9 15114.5 15442.4 15637.7 1947.5 1956.9 1959.4 1980.8 2002.7 80869.6 81218.0 81963.6 82232.6 83405.6 10404.9 10611.0 10667.2 10937.9 10974.3 15101.6 15107.2 15136.2 15148.9 15158.6 8025.9 8292.8 8398.8 8465.6 8536.0 8109.4 8173.6 8283.3 8439.2 8450.8 5868.5 6263.5 6472.2 6668.0 6849.1 1844.4 1919.3 1922.7 1936.1 2030.5 979.9 986.0 1002.8 1004.5 1072.3 20120.4 20577.9 20630.3 20743.5 20824.0 995.7 1045.8 1081.0 1083.1 1100.7 11404.3 11563.5 11772.6 11823.8 11848.6 438748.0 449445.5 464559.0 465144.0 465952.4 61895.0 75846.0 80469.6 82264.0 82351.6 995.1 1004.6 1027.6 1029.4 1030.5 17457.3 17752.1 17825.7 17893.6 18493.4 41088.4 43309.8 44324.0 46700.8 46718.8 58693.4 60024.7 60507.7 60792.0 61652.0 8073.4 8319.0 8668.4 8865.2 8968.5 12926.7 13882.6 14026.6 14052.2 14208.2 11597.9 12036.3 12200.6 12598.7 12705.5 11560.8 11625.3 11691.1 11955.3 12498.1 11914.2 11988.1 12517.4 12599.3 12926.1 8536.1 8607.9 8624.9 8746.2 9034.4 11656.2 12332.3 12332.7 12407.1 12451.0 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | [#3] mootools.html Cycles:180 Average:5977.37 Median:455.44 stddev:3439.76 (755.3%) stddev-sans-first:3431.91 11:23:36 INFO - PROCESS | 12910 | Values: 1360.7 1452.4 1529.8 1629.9 1810.9 8509.1 8876.5 8960.4 9153.5 9171.3 9119.5 9192.7 9204.8 9212.1 9212.3 2296.2 2325.7 2327.6 2328.1 2328.7 2364.9 2409.5 2427.4 2429.8 2430.9 2364.2 2371.4 2380.1 2441.2 2445.7 2330.1 2358.8 2364.0 2364.4 2382.6 2269.2 2295.5 2296.5 2298.3 2298.4 8978.4 9027.4 9080.4 9114.7 9116.0 5333.8 5559.2 5561.8 5564.2 5567.1 3307.1 3342.2 3358.1 3382.4 3399.2 1041.3 1065.7 2023.4 2047.4 2047.8 12851.1 12883.5 12972.8 12977.2 12980.4 4409.2 4557.9 4574.0 4670.6 4680.4 9954.2 10050.8 10053.1 10078.9 10085.7 5379.4 5380.1 5383.9 5386.5 5389.1 4165.5 4424.5 4567.7 4976.5 5045.1 3766.1 3784.7 3870.7 3873.6 3883.9 1110.5 1139.1 1139.5 1142.8 1160.2 949.7 1008.0 1013.8 1034.7 1035.6 6061.0 6186.5 6208.7 6259.8 6315.5 454.9 456.0 458.9 462.5 462.9 4282.7 4283.1 4298.6 4405.9 4414.6 11203.3 11515.3 11613.7 11615.6 11619.0 10663.7 11121.1 11138.1 11450.6 11499.0 8365.7 8540.9 8555.5 8592.5 8595.4 6154.1 6215.5 6424.6 6448.6 6522.3 10253.9 10469.4 10488.8 10528.5 10618.8 9591.7 10668.9 10897.9 10919.1 10924.1 7787.6 7976.9 8046.7 8184.3 8191.3 8697.0 8758.2 8804.5 8820.9 8828.8 7865.3 7920.6 8034.8 8049.0 8162.9 7847.5 7855.6 7892.9 7954.7 8002.2 7281.7 7288.9 7323.6 7332.3 7335.4 5249.0 5322.3 5403.7 5487.0 5543.3 5306.1 5434.2 5502.5 5548.3 5626.4 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | [#4] prototype.html Cycles:180 Average:4672.73 Median:5381.46 stddev:1372.20 (25.5%) stddev-sans-first:1336.80 11:23:36 INFO - PROCESS | 12910 | Values: 331.3 338.8 341.9 348.5 349.4 5280.3 5324.8 5329.5 5355.4 5380.3 5277.7 5305.3 5319.5 5326.6 5335.1 3981.2 4012.9 4129.2 4145.8 4215.5 4189.4 4199.9 4211.3 4217.3 4229.5 4159.1 4180.4 4181.5 4186.0 4192.2 3990.8 4010.6 4138.8 4159.6 4200.8 3680.6 3687.3 3690.9 3691.4 3697.9 5150.3 5154.6 5154.8 5168.2 5176.6 4431.2 4650.3 4668.7 4703.6 4706.7 5477.9 5489.0 5491.6 5497.2 5502.6 3963.3 3969.0 3978.9 3997.7 3999.5 5533.3 5670.7 5688.9 5690.8 5770.2 4039.7 4042.5 4059.5 4060.8 4080.3 4122.0 4127.5 4128.5 4130.2 4130.8 4606.2 4675.0 4680.3 4693.4 4729.1 5505.3 5517.6 5520.7 5529.1 5617.9 1393.5 1418.5 1421.3 1430.4 1438.2 858.5 893.2 923.8 933.9 938.1 5233.8 5376.6 5382.5 5384.6 5385.0 5692.0 5692.2 5713.9 5723.6 5733.9 5234.0 5367.8 5395.3 5398.4 5416.8 2991.9 3022.2 3025.0 3025.1 3036.7 5897.8 5916.1 5918.8 5931.0 5941.7 5569.5 5778.5 5781.0 5796.7 5805.2 5805.7 5823.3 5831.9 5837.2 5839.5 5473.0 5563.5 5638.7 5653.9 5662.3 5619.8 5629.5 5630.6 5632.1 5633.3 5870.1 5883.8 5884.9 5896.0 5922.3 3891.4 3998.4 4004.6 4010.6 4012.8 5694.7 5701.9 5709.5 5711.4 5729.5 5466.7 5525.6 5625.5 5631.2 5650.4 5540.3 5561.4 5566.2 5573.9 5576.9 5404.2 5454.1 5465.3 5485.4 5498.7 5265.2 5380.4 5523.3 5554.1 5562.5 5445.6 5455.6 5479.7 5487.4 5493.3 11:23:36 INFO - PROCESS | 12910 | 11:23:36 INFO - PROCESS | 12910 | [#5] yui.html Cycles:180 Average:20771.31 Median:4869.38 stddev:29683.09 (609.6%) stddev-sans-first:29728.30 11:23:36 INFO - PROCESS | 12910 | Values: 752.2 829.1 840.1 850.5 858.7 17443.7 18118.9 18152.1 18152.7 18156.4 16806.3 16912.2 16913.0 16963.5 16968.0 820.5 840.5 841.5 842.0 842.2 664.8 801.7 826.5 830.6 835.0 859.0 859.4 859.6 860.0 860.4 854.1 854.2 854.3 855.0 855.3 511.5 613.8 631.1 634.2 639.9 15224.2 15246.2 15276.4 15290.5 15293.9 14235.6 14235.7 14368.3 14381.9 14599.8 13042.6 13225.6 13327.1 13413.5 13627.7 4593.2 4600.2 4638.1 4690.2 4845.5 74248.0 75228.6 75777.2 75968.2 76078.2 11177.2 11475.4 11555.8 11568.2 11687.0 21014.6 21019.8 21037.2 21218.8 21312.9 8262.3 8369.5 8620.4 8874.3 8884.3 4893.3 4977.1 5102.3 5326.5 5329.0 3593.9 3698.6 3705.6 3710.8 3714.7 605.1 610.9 621.5 635.1 643.9 616.6 626.4 685.7 691.7 704.5 9064.7 9539.6 9668.4 9675.6 10010.3 555.9 620.5 666.3 687.7 710.3 4583.8 4896.9 4925.6 4925.7 4994.7 74820.3 99528.0 114110.0 116413.0 121982.0 100953.5 101708.0 101710.0 103651.0 105383.5 86272.0 90325.0 90443.5 90792.5 91252.0 9027.5 9139.8 9199.3 9356.4 9567.6 46333.3 50541.7 51796.7 51926.7 52614.0 71452.0 71664.6 73731.0 77897.6 78665.6 11270.4 11289.1 11340.3 11430.4 11680.1 12375.4 12462.4 12475.6 12785.5 12864.6 11147.5 11181.4 11199.2 11314.9 11540.4 11966.3 12046.2 12648.3 14448.4 14993.1 13444.8 13898.2 14072.2 14137.4 14641.9 11556.8 11726.0 11768.8 11897.2 12228.7 10550.4 11221.2 12029.0 12405.8 12521.5 11:23:36 INFO - PROCESS | 12910 | -------- Summary: end -------- 11:23:36 INFO - PROCESS | 12910 | 11:23:37 INFO - TEST-INFO | 12910: exit 0 11:23:37 INFO - TEST-OK | dromaeo_css | took 1113949ms 11:23:37 INFO - TEST-START | kraken 11:23:37 INFO - operating with platform_type : linux_ 11:23:37 INFO - Initialising browser for kraken test... 11:23:37 INFO - TEST-INFO | started process 13101 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmf43Il/profile http://localhost:43322/getInfo.html) 11:23:41 INFO - PROCESS | 13101 | JavaScript error: , line 0: uncaught exception: undefined 11:23:41 INFO - PROCESS | 13101 | __metrics Screen width/height:1600/1200 11:23:41 INFO - PROCESS | 13101 | colorDepth:24 11:23:41 INFO - PROCESS | 13101 | Browser inner width/height: 1024/697 11:23:41 INFO - PROCESS | 13101 | __metrics 11:23:42 INFO - PROCESS | 13101 | 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" 11:23:42 INFO - PROCESS | 13101 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:23:43 INFO - TEST-INFO | 13101: exit 0 11:23:43 INFO - Browser initialized. 11:23:43 INFO - Running cycle 1/1 for kraken test... 11:23:43 INFO - TEST-INFO | started process 13181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmf43Il/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:23:55 INFO - PROCESS | 13181 | RSS: Main: 157511680 11:23:55 INFO - PROCESS | 13181 | 11:23:58 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43322/tests/kraken/driver.html?testName=audio-beat-detection) 11:23:58 INFO - PROCESS | 13181 | RSS: Main: 214675456 11:23:58 INFO - PROCESS | 13181 | 11:24:01 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43322/tests/kraken/driver.html?testName=audio-dft) 11:24:02 INFO - PROCESS | 13181 | RSS: Main: 192966656 11:24:02 INFO - PROCESS | 13181 | 11:24:05 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43322/tests/kraken/driver.html?testName=audio-fft) 11:24:05 INFO - PROCESS | 13181 | RSS: Main: 269656064 11:24:05 INFO - PROCESS | 13181 | 11:24:07 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43322/tests/kraken/driver.html?testName=audio-oscillator) 11:24:08 INFO - PROCESS | 13181 | RSS: Main: 180686848 11:24:08 INFO - PROCESS | 13181 | 11:24:10 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43322/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:24:11 INFO - PROCESS | 13181 | RSS: Main: 185307136 11:24:11 INFO - PROCESS | 13181 | 11:24:14 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43322/tests/kraken/driver.html?testName=imaging-darkroom) 11:24:14 INFO - PROCESS | 13181 | RSS: Main: 220147712 11:24:14 INFO - PROCESS | 13181 | 11:24:18 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43322/tests/kraken/driver.html?testName=imaging-desaturate) 11:24:18 INFO - PROCESS | 13181 | RSS: Main: 220377088 11:24:18 INFO - PROCESS | 13181 | 11:24:21 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43322/tests/kraken/driver.html?testName=json-parse-financial) 11:24:22 INFO - PROCESS | 13181 | RSS: Main: 220446720 11:24:22 INFO - PROCESS | 13181 | 11:24:23 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43322/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:24:24 INFO - PROCESS | 13181 | RSS: Main: 185692160 11:24:24 INFO - PROCESS | 13181 | 11:24:26 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:24:26 INFO - PROCESS | 13181 | RSS: Main: 189603840 11:24:26 INFO - PROCESS | 13181 | 11:24:28 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:24:29 INFO - PROCESS | 13181 | RSS: Main: 194797568 11:24:29 INFO - PROCESS | 13181 | 11:24:31 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:24:32 INFO - PROCESS | 13181 | RSS: Main: 190185472 11:24:32 INFO - PROCESS | 13181 | 11:24:35 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:24:35 INFO - PROCESS | 13181 | RSS: Main: 193703936 11:24:35 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | Cycle 1(1): loaded http://localhost:43322/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43322/tests/kraken/driver.html?testName=ai-astar) 11:24:37 INFO - PROCESS | 13181 | RSS: Main: 244797440 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | __start_tp_report 11:24:37 INFO - PROCESS | 13181 | _x_x_mozilla_page_load 11:24:37 INFO - PROCESS | 13181 | _x_x_mozilla_page_load_details 11:24:37 INFO - PROCESS | 13181 | |i|pagename|runs| 11:24:37 INFO - PROCESS | 13181 | |0;ai-astar;102;111;111;111;112;112;109;111;111;111 11:24:37 INFO - PROCESS | 13181 | |1;audio-beat-detection;146;118;125;174;120;119;150;116;165;118 11:24:37 INFO - PROCESS | 13181 | |2;audio-dft;162;161;161;161;161;160;161;161;160;160 11:24:37 INFO - PROCESS | 13181 | |3;audio-fft;76;75;75;122;74;75;76;74;98;75 11:24:37 INFO - PROCESS | 13181 | |4;audio-oscillator;84;84;120;84;84;84;120;84;84;131 11:24:37 INFO - PROCESS | 13181 | |5;imaging-gaussian-blur;116;116;116;117;116;116;117;117;117;117 11:24:37 INFO - PROCESS | 13181 | |6;imaging-darkroom;145;145;146;146;147;145;146;145;145;146 11:24:37 INFO - PROCESS | 13181 | |7;imaging-desaturate;95;95;94;95;94;94;95;94;99;94 11:24:37 INFO - PROCESS | 13181 | |8;json-parse-financial;53;53;53;54;54;56;54;53;53;53 11:24:37 INFO - PROCESS | 13181 | |9;json-stringify-tinderbox;60;60;59;60;61;61;63;61;65;61 11:24:37 INFO - PROCESS | 13181 | |10;stanford-crypto-aes;76;76;76;77;85;76;76;85;82;75 11:24:37 INFO - PROCESS | 13181 | |11;stanford-crypto-ccm;120;122;119;121;120;121;126;131;157;123 11:24:37 INFO - PROCESS | 13181 | |12;stanford-crypto-pbkdf2;161;156;156;159;155;156;154;160;155;154 11:24:37 INFO - PROCESS | 13181 | |13;stanford-crypto-sha256-iterative;63;63;64;62;64;63;63;64;62;62 11:24:37 INFO - PROCESS | 13181 | __end_tp_report 11:24:37 INFO - PROCESS | 13181 | __start_cc_report 11:24:37 INFO - PROCESS | 13181 | _x_x_mozilla_cycle_collect,1684 11:24:37 INFO - PROCESS | 13181 | __end_cc_report 11:24:37 INFO - PROCESS | 13181 | __startTimestamp1469557477673__endTimestamp 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | ------- Summary: start ------- 11:24:37 INFO - PROCESS | 13181 | Number of tests: 14 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.96 (2.7%) stddev-sans-first:0.87 11:24:37 INFO - PROCESS | 13181 | Values: 102.0 111.0 111.0 111.0 112.0 112.0 109.0 111.0 111.0 111.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:135.50 stddev:21.82 (16.1%) stddev-sans-first:22.79 11:24:37 INFO - PROCESS | 13181 | Values: 146.0 118.0 125.0 174.0 120.0 119.0 150.0 116.0 165.0 118.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 11:24:37 INFO - PROCESS | 13181 | Values: 162.0 161.0 161.0 161.0 161.0 160.0 161.0 161.0 160.0 160.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:15.82 (21.1%) stddev-sans-first:16.63 11:24:37 INFO - PROCESS | 13181 | Values: 76.0 75.0 75.0 122.0 74.0 75.0 76.0 74.0 98.0 75.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:84.00 stddev:19.39 (23.1%) stddev-sans-first:20.09 11:24:37 INFO - PROCESS | 13181 | Values: 84.0 84.0 120.0 84.0 84.0 84.0 120.0 84.0 84.0 131.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 11:24:37 INFO - PROCESS | 13181 | Values: 116.0 116.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 11:24:37 INFO - PROCESS | 13181 | Values: 145.0 145.0 146.0 146.0 147.0 145.0 146.0 145.0 145.0 146.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:1.52 (1.6%) stddev-sans-first:1.62 11:24:37 INFO - PROCESS | 13181 | Values: 95.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 99.0 94.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.50 stddev:0.97 (1.8%) stddev-sans-first:1.00 11:24:37 INFO - PROCESS | 13181 | Values: 53.0 53.0 53.0 54.0 54.0 56.0 54.0 53.0 53.0 53.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:1.73 (2.8%) stddev-sans-first:1.79 11:24:37 INFO - PROCESS | 13181 | Values: 60.0 60.0 59.0 60.0 61.0 61.0 63.0 61.0 65.0 61.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:76.50 stddev:3.98 (5.2%) stddev-sans-first:4.12 11:24:37 INFO - PROCESS | 13181 | Values: 76.0 76.0 76.0 77.0 85.0 76.0 76.0 85.0 82.0 75.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:122.50 stddev:11.46 (9.4%) stddev-sans-first:11.95 11:24:37 INFO - PROCESS | 13181 | Values: 120.0 122.0 119.0 121.0 120.0 121.0 126.0 131.0 157.0 123.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.60 Median:156.00 stddev:2.50 (1.6%) stddev-sans-first:2.09 11:24:37 INFO - PROCESS | 13181 | Values: 161.0 156.0 156.0 159.0 155.0 156.0 154.0 160.0 155.0 154.0 11:24:37 INFO - PROCESS | 13181 | 11:24:37 INFO - PROCESS | 13181 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 11:24:37 INFO - PROCESS | 13181 | Values: 63.0 63.0 64.0 62.0 64.0 63.0 63.0 64.0 62.0 62.0 11:24:37 INFO - PROCESS | 13181 | -------- Summary: end -------- 11:24:37 INFO - PROCESS | 13181 | 11:24:38 INFO - TEST-INFO | 13181: exit 0 11:24:38 INFO - TEST-OK | kraken | took 61091ms 11:24:38 INFO - SUITE-END | took 1175s 11:24:38 INFO - Completed test suite (00:19:35) 11:24:38 INFO - javascript benchmark 11:24:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3020.7432368248697, 3055.8777648894047, 3089.2431354318182, 3098.271906102066, 3101.922451938701, 5168.22476628506, 5258.868528286795, 5261.684298942061, 5337.89324213516, 5348.946510534889, 5297.788088476466, 5338.866528336794, 5359.08895487767, 5365.356157261124, 5401.405845357018, 4753.1681955657705, 4830.372051633282, 4845.491223421543, 4851.27230915362, 5100.0, 5008.949910500903, 5023.899522009558, 5048.242763585455, 5059.089363914494, 5068.518490743373, 4742.976285118587, 4767.1419144553965, 4819.566239038469, 4867.294242334877, 4993.650444468889, 4810.446798618169, 4827.879303017417, 4942.332785074041, 4949.455559888409, 4972.975135124301, 4836.830710925136, 4868.537488938545, 4881.121398148367, 4884.658073934826, 4922.163232250526, 5251.422343542207, 5364.973175134139, 5409.0534156522845, 5419.403865574784, 5422.322209723785, 5691.715414229313, 5755.482006619382, 5761.366249712528, 5826.708664566797, 5831.037878750001, 5829.737661805187, 5831.562632802556, 5844.795432159854, 5867.0, 5873.853153671193, 5193.558547523465, 5293.12663410533, 5429.185622156669, 5481.424450432727, 5491.945080549183, 5792.507636850914, 5843.620164689281, 5848.502877255396, 5886.705664716833, 5902.173695682608, 5593.916091258634, 5645.23789288449, 5654.208410822406, 5657.915131273035, 5660.179274005246, 5572.58205634579, 5666.518345940559, 5675.262215911905, 5715.571332150105, 5717.542596592346, 5772.624779389326, 5782.59521833476, 5785.71071446426, 5824.504917082096, 5834.824955251349, 5737.942620573825, 5743.54051675865, 5795.449432303924, 5806.622569533051, 5808.273965754281, 4227.7463352198965, 4309.741415515079, 4438.889027774331, 4442.444694413199, 4487.506374298824, 3869.903252418656, 3907.4529565860803, 3917.981324855558, 3919.9020024499046, 3990.06233535119, 5481.068218402881, 5483.424240454775, 5492.395836457987, 5496.0, 5533.529649979716, 5599.7480113394995, 5797.333306669702, 5802.912956305659, 5814.825555233349, 5840.415958404125, 5420.864478388072, 5459.672419654754, 5466.753996070186, 5524.723763811793, 5534.695591742412, 4998.850034498971, 4999.175136102575, 5000.374953130859, 5004.899902001939, 5036.194208926554, 5946.732397042144, 5996.310424301261, 6004.83490683352, 6010.27876654813, 6058.757649694051, 5590.2453168821685, 5730.627509211888, 5737.512311453574, 5769.221155144001, 5788.652680839163, 6008.1588577598295, 6008.639481631116, 6011.188489553854, 6018.548608854441, 6044.788432404845, 5629.014922388647, 5681.573881958952, 5697.772089116389, 5722.599418040697, 5730.255066841284, 5541.113421852484, 5619.550435965195, 5653.689047102449, 5653.830385088454, 5806.535477161901, 5525.530329921841, 5574.470425309589, 5577.163425486047, 5582.888342233214, 5618.69097199658, 5564.4991950724625, 5575.832725018256, 5578.023845827045, 5580.525655319343, 5581.6930068846605, 5345.465453454779, 5556.444355564411, 5572.136318870582, 5603.943960560342, 5634.295713035871, 5756.222909907109, 5803.709814509173, 5813.215215945962, 5816.796412125555, 5817.069268916953, 5621.213030175697, 5624.268845050117, 5629.690367029689, 5630.042892708331, 5637.802676906289], "value": 5309.90479147322, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.167628672432, 614.8001899382699, 623.5074291309865, 635.815613472093, 638.7604648256904, 42891.35662965054, 44091.55908440915, 44184.337234941464, 44509.77745111274, 48029.0, 45462.545374546244, 46274.30588541175, 46282.30576541355, 46329.305060424034, 46362.53637463624, 8866.999999999984, 9092.408993415407, 9098.909010909872, 9100.590473428678, 9106.0, 9018.503982280972, 9079.818403631956, 9106.317026223027, 9110.0, 9131.72604821853, 9098.408603440788, 9152.0, 9156.0, 9156.130167634064, 9158.358914875962, 9025.232855207316, 9080.273578113734, 9082.591283392198, 9090.454572725625, 9092.408993415407, 8926.062763409813, 8979.68571100015, 8997.730068097968, 9013.233875120623, 9018.86471702925, 43316.78341608303, 43772.0, 43807.561924380665, 43849.0, 43864.0, 3618.294432585647, 3700.1674623209565, 3832.9808350958347, 3947.4473573699706, 4081.0001549620238, 32338.83830580832, 32396.83801580977, 32489.83755081233, 33307.33385332304, 34241.0, 3996.3805610130476, 4038.697097717683, 4050.18996200761, 4065.654419374327, 4077.0826564023155, 347503.0, 350570.0, 352159.0, 352865.0, 353414.0, 57849.13226301609, 58196.0, 58243.0, 58275.70862145662, 58293.70853145707, 36924.0, 37659.05852353658, 37737.245255095295, 37743.62256377456, 61658.0, 17705.38031168903, 17881.821181788277, 17893.284268629108, 17894.463166105037, 17901.0, 13502.729945401037, 13509.2569908654, 13513.391897364641, 13596.320183990762, 13600.795988060187, 19632.0, 19672.70490942637, 19758.90120549388, 19932.402027939184, 19957.800421995886, 7147.450436932652, 7285.0, 7310.157544777495, 7421.480496365202, 7540.396768258526, 968.2157452463528, 1016.7204018894863, 1071.0414179309475, 1102.7132945434084, 1109.5561775289948, 33063.0, 33141.83429082839, 33178.834105829315, 33257.16857078592, 33738.83130584331, 1067.3382502848283, 1076.9084627806724, 1104.0, 1104.690686607751, 1123.4663534820895, 17067.146642667816, 17284.578632913293, 17505.037222952604, 17602.295908163785, 17754.822451775315, 549534.0, 551058.0, 553771.0, 553889.0, 557092.0, 58576.0, 59124.0, 59183.0, 59236.40763592395, 59243.407565923786, 1233.802591585342, 1342.2684636872732, 1389.3539504130629, 1398.077269002474, 1402.291842619491, 31504.527432088078, 33489.162770930925, 33941.49087763735, 34150.31699366048, 34342.314336770265, 50972.74513627408, 51536.0, 51544.22683659673, 51642.483575163766, 51654.48345516497, 439448.0, 439557.0, 440188.0, 441010.0, 442354.0, 25456.74543254544, 27632.723672763015, 28397.006104786233, 28467.28831779222, 28694.56958145671, 45691.31463028124, 46727.82268061658, 46930.296045560026, 47240.29139562978, 49217.75391123021, 40922.79538602288, 41585.792071039454, 41734.373984389655, 42097.0, 43683.78158109189, 40829.38755918604, 41185.58814411818, 41234.38148427836, 42443.57556424396, 42612.5738742621, 41704.0, 41908.79045604752, 42165.78917105395, 42227.57772422236, 42458.5754142467, 34929.65070349264, 36861.44707829331, 37096.6290337104, 37461.81269093637, 37644.435333469475, 28206.717932820407, 28221.251092520914, 28258.15225543237, 28341.858290708413, 28534.57198141988], "value": 20319.716989056866, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.7703377973218, 395.1050869779949, 400.95589485156626, 401.8372559113559, 418.49342367477084, 14355.850849079638, 17268.740968885446, 18383.72424413633, 20096.89951550242, 20117.59764804703, 15627.296771645275, 18894.433167005012, 19589.412317630493, 20171.798282017175, 20459.897700511476, 5772.884542309152, 5947.821565353046, 5954.404559544054, 5963.582549221556, 5977.671228082454, 5844.123381492767, 5901.114832775075, 5907.202527658776, 5913.201717768111, 5920.319163296233, 5692.402297758717, 5773.797917072903, 5806.49025264273, 5814.331351894522, 5826.766929322822, 5665.773369065232, 5819.621724587888, 5846.23998880145, 5878.706064696748, 5888.440598143169, 5187.195984622352, 5214.739263036871, 5222.399424066243, 5226.529612334908, 5229.398619158769, 23812.166574169994, 24355.26934191977, 24569.14008009711, 24570.385740356454, 24571.508569828504, 2401.939951501209, 2513.987430062856, 2525.532776436365, 2576.2786419802487, 3008.699130086996, 14283.428662853474, 14802.851971480362, 15114.470993515284, 15442.382304707911, 15637.687246255126, 1947.5423275530238, 1956.863019588615, 1959.4415591556337, 1980.762308522972, 2002.709607106976, 80869.59565202195, 81218.0, 81963.5901820487, 82232.58883705603, 83405.58297208475, 10404.947975260075, 10611.0, 10667.19996000303, 10937.890621093688, 10974.341539507655, 15101.622459438602, 15107.244637768068, 15136.243187840564, 15148.924255378653, 15158.621034474007, 8025.919740802634, 8292.751217463485, 8398.790030249293, 8465.576721163918, 8536.0, 8109.43233973628, 8173.591320433955, 8283.337333013344, 8439.240468357877, 8450.830983380421, 5868.471837534642, 6263.530235232376, 6472.213754953311, 6668.0, 6849.075374824434, 1844.4190080124717, 1919.251491918153, 1922.7404300419264, 1936.1190658250744, 2030.482227032127, 979.8873129590046, 986.0, 1002.799440111966, 1004.5489006823327, 1072.2869291920786, 20120.39638810838, 20577.897110514652, 20630.27794027202, 20743.481412964797, 20824.0, 995.6701318913632, 1045.7960697664034, 1080.9945950270355, 1083.1172594335592, 1100.6697990602788, 11404.315741055563, 11563.479643416067, 11772.646820595395, 11823.822642660367, 11848.644540663794, 438748.0, 449445.5499005105, 464559.0, 465144.0, 465952.3833331702, 61895.0, 75846.0, 80469.59765201136, 82264.0, 82351.5882420596, 995.1491474788951, 1004.6082028009082, 1027.5581499955092, 1029.361795686679, 1030.4641586375044, 17457.30170793154, 17752.11239438049, 17825.73261401106, 17893.552661183574, 18493.352732654294, 41088.38367424551, 43309.78345108254, 44324.0, 46700.7664961673, 46718.76640616775, 58693.41306586879, 60024.69987650034, 60507.69746151241, 60792.0, 61652.0, 8073.35413166957, 8319.043310019426, 8668.436551624125, 8865.246454051477, 8968.461892286483, 12926.676833079247, 13882.583522494342, 14026.579202623938, 14052.227127508086, 14208.218547979961, 11597.88402115968, 12036.338001409656, 12200.633980980585, 12598.748025039264, 12705.491780328683, 11560.826587601025, 11625.302481851117, 11691.123165762432, 11955.342456164994, 12498.12513124109, 11914.225575337576, 11988.100892432927, 12517.436715348013, 12599.307038112991, 12926.095173337777, 8536.146385361415, 8607.913920860961, 8624.913750862412, 8746.212840844355, 9034.367594268339, 11656.18406711556, 12332.32172230536, 12332.691682708004, 12407.131500794858, 12451.049003112263], "value": 9535.64269351526, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.6938438851253, 1452.3972551391173, 1529.755239161734, 1629.8777591680628, 1810.9366172183977, 8509.085455772449, 8876.511791851446, 8960.372773905838, 9153.450792952428, 9171.266298696106, 9119.452832830018, 9192.678256261031, 9204.815903681922, 9212.078792120785, 9212.309076819232, 2296.1848543766932, 2325.732540757809, 2327.615943369342, 2328.138588722167, 2328.6507023946374, 2364.893579788905, 2409.4578719787996, 2427.4052857050046, 2429.769171928664, 2430.902763889442, 2364.2316247219637, 2371.419002344428, 2380.143148466551, 2441.218809980807, 2445.682061332024, 2330.149495434171, 2358.834881558283, 2363.988180059106, 2364.4207169243473, 2382.630692242688, 2269.205777977711, 2295.5294164696197, 2296.471811483368, 2298.276043046452, 2298.4139044543535, 8978.371513994023, 9027.368084234105, 9080.409773364776, 9114.680986165453, 9115.954420227856, 5333.813316533941, 5559.166125081229, 5561.77752889884, 5564.248826408423, 5567.053600887818, 3307.140143562667, 3342.1644588852787, 3358.059743271888, 3382.4080785862375, 3399.201187720884, 1041.317936751429, 1065.6856227412932, 2023.403096086658, 2047.396018174642, 2047.7645070816898, 12851.100422970396, 12883.484660613563, 12972.805407918795, 12977.156484828456, 12980.415881285366, 4409.162259170811, 4557.908841823145, 4573.954260457419, 4670.6497012724185, 4680.4149481314835, 9954.203663706887, 10050.798984020425, 10053.145482633912, 10078.949605251926, 10085.747856303504, 5379.381371142369, 5380.112281473591, 5383.892322153613, 5386.48828361305, 5389.056915039852, 4165.541790403053, 4424.46906371231, 4567.703099298535, 4976.527229913152, 5045.066662667418, 3766.133789228493, 3784.7161462890394, 3870.7096967727534, 3873.6126387361037, 3883.86406475772, 1110.5446766825562, 1139.0602752729033, 1139.5327915554583, 1142.7600203957095, 1160.1646814293695, 949.7388218240039, 1008.0423597582179, 1013.761765984993, 1034.6585626743308, 1035.5909415780725, 6061.0, 6186.4741496973265, 6208.72060757267, 6259.843503912439, 6315.494760419156, 454.926373757931, 455.96124329431706, 458.8875725447287, 462.45199438665287, 462.91204671113053, 4282.700210985264, 4283.057727299989, 4298.613124818781, 4405.911881762347, 4414.646828253732, 11203.327800331843, 11515.30908145514, 11613.651590452131, 11615.593454229062, 11619.0, 10663.680089597325, 11121.05471034971, 11138.053265472525, 11450.599229026944, 11499.0, 8365.749027529184, 8540.871886921825, 8555.529445880537, 8592.527410992456, 8595.355348348774, 6154.138420621027, 6215.451664817403, 6424.614523128627, 6448.613083215022, 6522.34776522344, 10253.948730256301, 10469.424181670081, 10488.842667359842, 10528.473576321307, 10618.787624247627, 9591.664291749756, 10668.89331106679, 10897.891021090005, 10919.071878890383, 10924.12606991423, 7787.610619469117, 7976.880346794686, 8046.678132874619, 8184.263416292562, 8191.303739182236, 8696.956515217384, 8758.167974042455, 8804.515751633466, 8820.91179088201, 8828.823423531365, 7865.252800983897, 7920.603969801372, 8034.799130021563, 8048.959755201186, 8162.877556836534, 7847.489913155627, 7855.60721963888, 7892.881606775788, 7954.721584744506, 8002.239787220205, 7281.708731650675, 7288.854222915618, 7323.560586364835, 7332.340089391981, 7335.4498412618195, 5249.018804989279, 5322.254884316276, 5403.702796346239, 5487.039768040497, 5543.25166102571, 5306.097963346318, 5434.157705555645, 5502.477264659851, 5548.278723765885, 5626.409227031267], "value": 4699.1623751978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.32905865622115, 338.8018009464463, 341.8615460738401, 348.54862952476543, 349.3711319624675, 5280.313559237298, 5324.813631522898, 5329.467053294668, 5355.357357117142, 5380.273663055496, 5277.736113194344, 5305.336832895888, 5319.494648008433, 5326.627136100465, 5335.146376579738, 3981.163955569327, 4012.919741605166, 4129.236091323115, 4145.79271036447, 4215.451991241134, 4189.402570406337, 4199.895002624929, 4211.263028969934, 4217.346311321735, 4229.471316085489, 4159.085001299724, 4180.435641188446, 4181.498220213579, 4185.97907010466, 4192.224438478891, 3990.8004599770043, 4010.578889216634, 4138.772367519785, 4159.625633692982, 4200.768957707325, 3680.6319368063246, 3687.2625474905126, 3690.907727306812, 3691.427828686558, 3697.8890633280776, 5150.27896094547, 5154.639175257734, 5154.845354639367, 5168.224766285088, 5176.6117541184185, 4431.202383570956, 4650.25595904657, 4668.719876807369, 4703.64722645803, 4706.717596944194, 5477.904419116193, 5489.0, 5491.615586908918, 5497.185928643538, 5502.58730595203, 3963.256167286385, 3969.0, 3978.900527486836, 3997.72015958883, 3999.5400528939253, 5533.257024037415, 5670.68811215383, 5688.943110568883, 5690.772369105231, 5770.163326317703, 4039.7374170678813, 4042.4946881639794, 4059.4519739835337, 4060.7614677523047, 4080.347144456932, 4122.0107174277955, 4127.525334586501, 4128.545859955402, 4130.173965206971, 4130.814113364906, 4606.239970404846, 4674.976625116853, 4680.274557443602, 4693.366395536627, 4729.148753224453, 5505.26584125999, 5517.586181036439, 5520.696361700065, 5529.142982837667, 5617.859553511195, 1393.477445957766, 1418.4822539773115, 1421.3319739722315, 1430.4135304525096, 1438.216172186161, 858.5063588436674, 893.1827377949234, 923.752933539716, 933.869258303838, 938.1462868789368, 5233.790648374023, 5376.6020834582505, 5382.542483888913, 5384.596155288369, 5385.0, 5692.0, 5692.23154874095, 5713.857153571111, 5723.6279641823985, 5733.881893030903, 5234.0, 5367.75845086972, 5395.325584301962, 5398.352197736297, 5416.83749487516, 2991.865366058533, 3022.2444388902773, 3025.0, 3025.13783571681, 3036.711512406318, 5897.76408943646, 5916.112583112567, 5918.763249469973, 5931.0, 5941.673207973603, 5569.498745112959, 5778.508826749689, 5781.0, 5796.739146738407, 5805.1713710182, 5805.680687562224, 5823.330317013513, 5831.94168058314, 5837.241158649434, 5839.532837373085, 5473.042217611823, 5563.525665698674, 5638.718064096861, 5653.886922261614, 5662.292213473316, 5619.775208991595, 5629.536320556766, 5630.605857590093, 5632.098864181711, 5633.295838020247, 5870.148828419769, 5883.764649414146, 5884.852878677896, 5895.970520147372, 5922.318933322723, 3891.3579259421876, 3998.400239963912, 4004.619561141687, 4010.57888921659, 4012.819423125908, 5694.686792226467, 5701.857453563694, 5709.486146246858, 5711.400302968129, 5729.541636668973, 5466.671999680032, 5525.585581081516, 5625.493705566519, 5631.183478395692, 5650.406707295675, 5540.252065971043, 5561.360443548881, 5566.192902029264, 5573.8606534835335, 5576.86057848557, 5404.243405923253, 5454.127339625641, 5465.289512363367, 5485.369182544058, 5498.697571633438, 5265.157574788015, 5380.381256155592, 5523.25436066142, 5554.1391084381985, 5562.499375056264, 5445.564354851682, 5455.636090977256, 5479.671219726829, 5487.368952570506, 5493.313335833021], "value": 4235.229454556114, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.2439947852407, 829.117902361639, 840.1430540848337, 850.4982060584258, 858.7466697324289, 17443.651126977453, 18118.909405452956, 18152.092395380212, 18152.727709084356, 18156.36452724155, 16806.32774689014, 16912.238949247283, 16913.0, 16963.49109526713, 16968.0, 820.4871955028123, 840.5209030852417, 841.5329492131864, 841.9536925469097, 842.1830824100613, 664.7872680742164, 801.6993627389729, 826.5164878546028, 830.6262182018086, 834.9791255218607, 859.042167982701, 859.4070091636781, 859.621766422772, 859.9656013759442, 860.371928492198, 854.1159899504046, 854.150120629974, 854.3250831842837, 854.9786255343604, 855.2772906893665, 511.4757373691995, 613.7698363113832, 631.0597210156833, 634.2230767310054, 639.8752243312556, 15224.238788060664, 15246.161461119635, 15276.38894444221, 15290.464833730766, 15293.923530382388, 14235.644108897257, 14235.715285694228, 14368.281585920766, 14381.856181438156, 14599.78100328496, 13042.608721738363, 13225.603231903058, 13327.133736307205, 13413.530526431528, 13627.727445451135, 4593.173228818811, 4600.194965880957, 4638.11875743611, 4690.1557719610455, 4845.466998630148, 74248.0, 75228.6238568809, 75777.24222757758, 75968.24031759668, 76078.23921760767, 11177.21759476845, 11475.426228688531, 11555.826662600068, 11568.24806387582, 11687.0, 21014.579708405745, 21019.78980210209, 21037.15851365959, 21218.78781212199, 21312.893435532722, 8262.256396924186, 8369.49783013021, 8620.353473489513, 8874.29005679544, 8884.333674974403, 4893.290472881412, 4977.104121258136, 5102.285680004802, 5326.493983071602, 5328.973355133199, 3593.8921832345072, 3698.6301369863336, 3705.629437056327, 3710.7588006779474, 3714.7213958953184, 605.0923614578132, 610.9083637454329, 621.5462712220104, 635.1362147479424, 643.9388258115472, 616.5745635511482, 626.37988391493, 685.6537448588431, 691.6783695581579, 704.503325155764, 9064.728058158265, 9539.643466867867, 9668.371555848847, 9675.612975480903, 10010.295161274189, 555.9332880054419, 620.4973971083436, 666.2837449741547, 687.7317846040048, 710.2613282186468, 4583.839565615254, 4896.877578060577, 4925.60338392635, 4925.655204135676, 4994.700317980933, 74820.25179748241, 99528.0, 114110.0, 116413.0, 121982.0, 100953.49523252483, 101708.0, 101710.0, 103651.0, 105383.4730826341, 86272.0, 90324.99999999869, 90443.54778226199, 90792.54603726939, 91252.0, 9027.45835249887, 9139.817203656023, 9199.310051746012, 9356.391834530734, 9567.569459374206, 46333.30500042435, 50541.74729126331, 51796.741016294676, 51926.74036629944, 52614.0, 71452.0, 71664.64167679129, 73731.0, 77897.61051194707, 78665.60667196628, 11270.380129092651, 11289.096872250364, 11340.262882912584, 11430.428478575872, 11680.123990700562, 12375.443105060474, 12462.376881155724, 12475.563355282522, 12785.488580456678, 12864.614061578168, 11147.498362573866, 11181.385023823443, 11199.21605487608, 11314.943425282821, 11540.422978851191, 11966.341851198267, 12046.216995895238, 12648.304343261208, 14448.439165902242, 14993.100413975197, 13444.798328025283, 13898.16611003343, 14072.226027568173, 14137.43450261978, 14641.853581464475, 11556.768864622829, 11726.0, 11768.76462470763, 11897.226680265756, 12228.69428264265, 10550.366977981346, 11221.207307387009, 12029.0, 12405.813912791134, 12521.499140034297], "value": 7612.495941562437, "unit": "score"}], "name": "dromaeo_css", "value": 7336.192589112343, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 111.0, 112.0, 112.0, 109.0, 111.0, 111.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 125.0, 174.0, 120.0, 119.0, 150.0, 116.0, 165.0, 118.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 160.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 122.0, 74.0, 75.0, 76.0, 74.0, 98.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 120.0, 84.0, 84.0, 84.0, 120.0, 84.0, 84.0, 131.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 99.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 54.0, 56.0, 54.0, 53.0, 53.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 61.0, 61.0, 63.0, 61.0, 65.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 77.0, 85.0, 76.0, 76.0, 85.0, 82.0, 75.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 122.0, 119.0, 121.0, 120.0, 121.0, 126.0, 131.0, 157.0, 123.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 156.0, 156.0, 159.0, 155.0, 156.0, 154.0, 160.0, 155.0, 154.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 62.0, 64.0, 63.0, 63.0, 64.0, 62.0, 62.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1479.6, "alertThreshold": 2.0}]} 11:24:38 INFO - Return code: 0 11:24:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:24:39 INFO - # TBPL SUCCESS # 11:24:39 INFO - Running post-action listener: _resource_record_post_action 11:24:39 INFO - [mozharness: 2016-07-26 18:24:39.003565Z] Finished run-tests step (success) 11:24:39 INFO - Running post-run listener: _resource_record_post_run 11:24:39 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 8192; Write bytes: 552046592; Read time: 328; Write time: 641100 11:24:39 INFO - TinderboxPrint: CPU usage
13.0% 11:24:39 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 328 11:24:39 INFO - TinderboxPrint: I/O write bytes / time
552,046,592 / 641,100 11:24:39 INFO - TinderboxPrint: CPU idle
8,282.5 (87.0%) 11:24:39 INFO - TinderboxPrint: CPU user
1,190.9 (12.5%) 11:24:39 INFO - TinderboxPrint: Swap in / out
0 / 0 11:24:39 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 257179648; Read time: 0; Write time: 346992 11:24:39 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 294686720; Read time: 328; Write time: 294088 11:24:39 INFO - Running post-run listener: _upload_blobber_files 11:24:39 INFO - Blob upload gear active. 11:24:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:24:39 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 11:24:39 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'] 11:24:39 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 11:24:40 INFO - (blobuploader) - INFO - Open directory for files ... 11:24:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:24:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:24:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:45 INFO - (blobuploader) - INFO - Done attempting. 11:24:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:24:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:24:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:46 INFO - (blobuploader) - INFO - Done attempting. 11:24:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:24:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:24:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:24:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:24:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:24:47 INFO - (blobuploader) - INFO - Done attempting. 11:24:47 INFO - (blobuploader) - INFO - Iteration through files over. 11:24:47 INFO - Return code: 0 11:24:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:24:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:24:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e16569fa47318a93409d80d6046e62f008f9a8540940fe07c221549ebf81335ed3692950051ae2ea658ddaac602702281488eb8caecd7d738678ff8b9cc8c3e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d8091eaac967fb8ed7628ac029e4667660f9439abcecf85b3783ada856838c234c0adcc968956373dfa27fa5489092eed56e0a0ad0a2f0422162b7e3b232f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db403c328d6afeb197b8f4424f61189cccee3bc435d81ca0601a41ccb3fbca22a39734ee2aeeb767456fe89c869c36965a86bb18ed8d63044ef72793a1ec7803"} 11:24:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:24:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:24:47 INFO - Contents: 11:24:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e16569fa47318a93409d80d6046e62f008f9a8540940fe07c221549ebf81335ed3692950051ae2ea658ddaac602702281488eb8caecd7d738678ff8b9cc8c3e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d8091eaac967fb8ed7628ac029e4667660f9439abcecf85b3783ada856838c234c0adcc968956373dfa27fa5489092eed56e0a0ad0a2f0422162b7e3b232f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db403c328d6afeb197b8f4424f61189cccee3bc435d81ca0601a41ccb3fbca22a39734ee2aeeb767456fe89c869c36965a86bb18ed8d63044ef72793a1ec7803"} 11:24:47 INFO - Running post-run listener: copy_logs_to_upload_dir 11:24:47 INFO - Copying logs to upload dir... 11:24:47 INFO - mkdir: /builds/slave/test/build/upload/logs 11:24:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.183695 ========= master_lag: 0.74 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-26 11:24:47.393087) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:47.400674) ========= 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=fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744 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/e16569fa47318a93409d80d6046e62f008f9a8540940fe07c221549ebf81335ed3692950051ae2ea658ddaac602702281488eb8caecd7d738678ff8b9cc8c3e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d8091eaac967fb8ed7628ac029e4667660f9439abcecf85b3783ada856838c234c0adcc968956373dfa27fa5489092eed56e0a0ad0a2f0422162b7e3b232f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db403c328d6afeb197b8f4424f61189cccee3bc435d81ca0601a41ccb3fbca22a39734ee2aeeb767456fe89c869c36965a86bb18ed8d63044ef72793a1ec7803"} build_url:https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/L7Ws5HraRRGAd_YkxwYK1Q/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/e16569fa47318a93409d80d6046e62f008f9a8540940fe07c221549ebf81335ed3692950051ae2ea658ddaac602702281488eb8caecd7d738678ff8b9cc8c3e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69d8091eaac967fb8ed7628ac029e4667660f9439abcecf85b3783ada856838c234c0adcc968956373dfa27fa5489092eed56e0a0ad0a2f0422162b7e3b232f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db403c328d6afeb197b8f4424f61189cccee3bc435d81ca0601a41ccb3fbca22a39734ee2aeeb767456fe89c869c36965a86bb18ed8d63044ef72793a1ec7803"}' ========= master_lag: 0.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:47.690332) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:47.690674) ========= 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=fa44defc139c4ba8574fe040000001db-1469555165.504733-1202619744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 11:24:47.710640) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 11:24:47.710938) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 11:24:47.711195) ========= ========= Total master_lag: 1.10 =========