builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1481823208.56 results: success (0) buildid: 20161215075301 builduid: 861c590d42c349a1a69bce6502fc1942 revision: 5aec4a29e94f25ddf86b24ddb3619e4b18d16dbc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.556446) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.556896) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.557179) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.571685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.571944) ========= 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=17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-15 09:33:28-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.4M=0.001s 2016-12-15 09:33:28 (20.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138610 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.724390) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.724716) ========= 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=17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014110 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.775205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:28.776060) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5aec4a29e94f25ddf86b24ddb3619e4b18d16dbc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5aec4a29e94f25ddf86b24ddb3619e4b18d16dbc --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=17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-15 09:33:29,071 truncating revision to first 12 chars 2016-12-15 09:33:29,071 Setting DEBUG logging. 2016-12-15 09:33:29,071 attempt 1/10 2016-12-15 09:33:29,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5aec4a29e94f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 09:33:29,386 unpacking tar archive at: mozilla-beta-5aec4a29e94f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.684875 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:29.475910) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:29.476236) ========= script_repo_revision: 5aec4a29e94f25ddf86b24ddb3619e4b18d16dbc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:29.476633) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:29.476892) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 09:33:29.487293) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-12-15 09:33:29.487561) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:33:29 INFO - MultiFileLogger online at 20161215 09:33:29 in /builds/slave/test 09:33:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:33:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:29 INFO - {'append_to_log': False, 09:33:29 INFO - 'base_work_dir': '/builds/slave/test', 09:33:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:33:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:29 INFO - 'branch': 'Mozilla-Beta', 09:33:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:29 INFO - 'config_files': ('talos/linux_config.py',), 09:33:29 INFO - 'default_actions': ('clobber', 09:33:29 INFO - 'read-buildbot-config', 09:33:29 INFO - 'download-and-extract', 09:33:29 INFO - 'populate-webroot', 09:33:29 INFO - 'create-virtualenv', 09:33:29 INFO - 'install', 09:33:29 INFO - 'run-tests'), 09:33:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:29 INFO - 'download_minidump_stackwalk': True, 09:33:29 INFO - 'download_symbols': 'ondemand', 09:33:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:29 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:29 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:29 INFO - 'installer_path': 'installer.exe', 09:33:29 INFO - 'log_level': 'info', 09:33:29 INFO - 'log_name': 'talos', 09:33:29 INFO - 'log_to_console': True, 09:33:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:33:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:33:29 INFO - 'opt_config_files': (), 09:33:29 INFO - 'pip_index': False, 09:33:29 INFO - 'sps_profile': False, 09:33:29 INFO - 'sps_profile_interval': 0, 09:33:29 INFO - 'suite': 'dromaeojs', 09:33:29 INFO - 'system_bits': '32', 09:33:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:29 INFO - 'title': 'talos-linux64-ix-009', 09:33:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:29 INFO - 'use_talos_json': True, 09:33:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:29 INFO - 'work_dir': 'build'} 09:33:29 INFO - [mozharness: 2016-12-15 17:33:29.877674Z] Running clobber step. 09:33:29 INFO - Running pre-action listener: _resource_record_pre_action 09:33:29 INFO - Running main action method: clobber 09:33:29 INFO - rmtree: /builds/slave/test/build 09:33:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:30 INFO - Running post-action listener: _resource_record_post_action 09:33:30 INFO - [mozharness: 2016-12-15 17:33:30.335481Z] Finished clobber step (success) 09:33:30 INFO - [mozharness: 2016-12-15 17:33:30.335583Z] Running read-buildbot-config step. 09:33:30 INFO - Running pre-action listener: _resource_record_pre_action 09:33:30 INFO - Running main action method: read_buildbot_config 09:33:30 INFO - Using buildbot properties: 09:33:30 INFO - { 09:33:30 INFO - "project": "", 09:33:30 INFO - "product": "firefox", 09:33:30 INFO - "script_repo_revision": "production", 09:33:30 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:33:30 INFO - "repository": "", 09:33:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:33:30 INFO - "buildid": "20161215075301", 09:33:30 INFO - "pgo_build": "True", 09:33:30 INFO - "basedir": "/builds/slave/test", 09:33:30 INFO - "buildnumber": 25, 09:33:30 INFO - "slavename": "talos-linux64-ix-009", 09:33:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:33:30 INFO - "platform": "ubuntu64_hw", 09:33:30 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:33:30 INFO - "branch": "mozilla-beta", 09:33:30 INFO - "repo_path": "releases/mozilla-beta", 09:33:30 INFO - "revision": "5aec4a29e94f25ddf86b24ddb3619e4b18d16dbc", 09:33:30 INFO - "stage_platform": "linux64-pgo", 09:33:30 INFO - "builduid": "861c590d42c349a1a69bce6502fc1942", 09:33:30 INFO - "slavebuilddir": "test" 09:33:30 INFO - } 09:33:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 09:33:30 INFO - Running post-action listener: _resource_record_post_action 09:33:30 INFO - [mozharness: 2016-12-15 17:33:30.338018Z] Finished read-buildbot-config step (success) 09:33:30 INFO - [mozharness: 2016-12-15 17:33:30.338108Z] Running download-and-extract step. 09:33:30 INFO - Running pre-action listener: _resource_record_pre_action 09:33:30 INFO - Running main action method: download_and_extract 09:33:30 INFO - mkdir: /builds/slave/test/build/tests 09:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:30 INFO - trying https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:33:30 INFO - Downloading https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:33:30 INFO - Downloaded 1327 bytes. 09:33:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:33:30 INFO - Using the following test package requirements: 09:33:30 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 09:33:30 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:33:30 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 09:33:30 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'jsshell-linux-x86_64.zip'], 09:33:30 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:33:30 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 09:33:30 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 09:33:30 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 09:33:30 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:33:30 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:33:30 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:33:30 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:33:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 09:33:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:30 INFO - Fetch https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 09:33:31 INFO - Expected file size: 21963307 09:33:35 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:33:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 09:33:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:35 INFO - Fetch https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 09:33:36 INFO - Expected file size: 21963307 09:33:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 09:33:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:33:40 INFO - Fetch https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 09:33:41 INFO - Expected file size: 14414089 09:33: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:41 INFO - trying https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:33:41 INFO - Downloading https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:33:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:33:42 INFO - Downloaded 56967798 bytes. 09:33:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:33:42 INFO - mkdir: /builds/slave/test/properties 09:33:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:42 INFO - Contents: 09:33:42 INFO - build_url:https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:33:43 INFO - Running post-action listener: _resource_record_post_action 09:33:43 INFO - Running post-action listener: set_extra_try_arguments 09:33:43 INFO - [mozharness: 2016-12-15 17:33:43.670730Z] Finished download-and-extract step (success) 09:33:43 INFO - [mozharness: 2016-12-15 17:33:43.670957Z] Running populate-webroot step. 09:33:43 INFO - Running pre-action listener: _resource_record_pre_action 09:33:43 INFO - Running main action method: populate_webroot 09:33:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:43 INFO - '--tppagecycles', 09:33:43 INFO - '7'], 09:33:43 INFO - 'tests': ['tsvgm']}}, 09:33:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['damp', 'tps']}, 09:33:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'tests': ['damp', 'tps']}, 09:33:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['dromaeo_dom']}, 09:33:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['basic_compositor_video']}, 09:33:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:33:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['a11yr', 09:33:43 INFO - 'ts_paint', 09:33:43 INFO - 'tpaint', 09:33:43 INFO - 'sessionrestore', 09:33:43 INFO - 'sessionrestore_no_auto_restore', 09:33:43 INFO - 'tabpaint']}, 09:33:43 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:43 INFO - 'ts_paint', 09:33:43 INFO - 'tpaint', 09:33:43 INFO - 'sessionrestore', 09:33:43 INFO - 'sessionrestore_no_auto_restore', 09:33:43 INFO - 'tabpaint']}, 09:33:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:43 INFO - 'ts_paint', 09:33:43 INFO - 'tpaint', 09:33:43 INFO - 'sessionrestore', 09:33:43 INFO - 'sessionrestore_no_auto_restore', 09:33:43 INFO - 'tabpaint']}, 09:33:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:43 INFO - 'ts_paint', 09:33:43 INFO - 'tpaint', 09:33:43 INFO - 'sessionrestore', 09:33:43 INFO - 'sessionrestore_no_auto_restore', 09:33:43 INFO - 'tabpaint']}, 09:33:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['a11yr', 09:33:43 INFO - 'ts_paint', 09:33:43 INFO - 'tpaint', 09:33:43 INFO - 'sessionrestore', 09:33:43 INFO - 'sessionrestore_no_auto_restore', 09:33:43 INFO - 'tabpaint']}, 09:33:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['a11yr', 09:33:43 INFO - 'ts_paint', 09:33:43 INFO - 'tpaint', 09:33:43 INFO - 'sessionrestore', 09:33:43 INFO - 'sessionrestore_no_auto_restore', 09:33:43 INFO - 'tabpaint']}, 09:33:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['tsvgx', 09:33:43 INFO - 'tsvgr_opacity', 09:33:43 INFO - 'tart', 09:33:43 INFO - 'tscrollx', 09:33:43 INFO - 'cart']}, 09:33:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:43 INFO - 'tsvgr_opacity', 09:33:43 INFO - 'tart', 09:33:43 INFO - 'tscrollx', 09:33:43 INFO - 'cart']}, 09:33:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'talos_options': ['--disable-e10s'], 09:33:43 INFO - 'tests': ['tp5o']}, 09:33:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'tests': ['tp5o']}, 09:33:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'talos_options': ['--disable-e10s', 09:33:43 INFO - '--xperf_path', 09:33:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:43 INFO - 'tests': ['tp5n']}, 09:33:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:43 INFO - 'talos_options': ['--xperf_path', 09:33:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:43 INFO - 'tests': ['tp5n']}}, 09:33:43 INFO - 'talos.zip': {'path': '', 09:33:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:43 INFO - Running post-action listener: _resource_record_post_action 09:33:43 INFO - [mozharness: 2016-12-15 17:33:43.687165Z] Finished populate-webroot step (success) 09:33:43 INFO - [mozharness: 2016-12-15 17:33:43.687258Z] Running create-virtualenv step. 09:33:43 INFO - Running pre-action listener: _resource_record_pre_action 09:33:43 INFO - Running main action method: create_virtualenv 09:33:43 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:43 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 09:33:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:43 INFO - Using real prefix '/usr' 09:33:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:44 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:46 INFO - Installing pip.................done. 09:33:46 INFO - Return code: 0 09:33:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:46 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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:33:46 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 09:33:46 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 09:33:46 INFO - Using env: {'DISPLAY': ':0', 09:33:46 INFO - 'HOME': '/home/cltbld', 09:33:46 INFO - 'LANG': 'en_US.UTF-8', 09:33:46 INFO - 'LANGUAGE': 'en_US:en', 09:33:46 INFO - 'LOGNAME': 'cltbld', 09:33:46 INFO - 'MAIL': '/var/mail/cltbld', 09:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:46 INFO - 'MOZ_NO_REMOTE': '1', 09:33:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:46 INFO - 'NO_EM_RESTART': '1', 09:33:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:46 INFO - 'PWD': '/builds/slave/test', 09:33:46 INFO - 'SHELL': '/bin/bash', 09:33:46 INFO - 'SHLVL': '1', 09:33:46 INFO - 'TERM': 'linux', 09:33:46 INFO - 'TMOUT': '86400', 09:33:46 INFO - 'USER': 'cltbld', 09:33:46 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:46 INFO - '_': '/tools/buildbot/bin/python'} 09:33:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:46 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 09:33:46 INFO - Cleaning up... 09:33:46 INFO - Return code: 0 09:33:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:46 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:46 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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:33:46 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 09:33:46 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 09:33:46 INFO - Using env: {'DISPLAY': ':0', 09:33:46 INFO - 'HOME': '/home/cltbld', 09:33:46 INFO - 'LANG': 'en_US.UTF-8', 09:33:46 INFO - 'LANGUAGE': 'en_US:en', 09:33:46 INFO - 'LOGNAME': 'cltbld', 09:33:46 INFO - 'MAIL': '/var/mail/cltbld', 09:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:46 INFO - 'MOZ_NO_REMOTE': '1', 09:33:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:46 INFO - 'NO_EM_RESTART': '1', 09:33:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:46 INFO - 'PWD': '/builds/slave/test', 09:33:46 INFO - 'SHELL': '/bin/bash', 09:33:46 INFO - 'SHLVL': '1', 09:33:46 INFO - 'TERM': 'linux', 09:33:46 INFO - 'TMOUT': '86400', 09:33:46 INFO - 'USER': 'cltbld', 09:33:46 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:46 INFO - '_': '/tools/buildbot/bin/python'} 09:33:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:46 INFO - Downloading/unpacking psutil>=3.1.1 09:33:46 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 09:33:46 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 09:33:46 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 09:33:46 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 09:33:46 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 09:33:46 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 09:33:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:48 INFO - Installing collected packages: psutil 09:33:48 INFO - Running setup.py install for psutil 09:33:48 INFO - building 'psutil._psutil_linux' extension 09:33:48 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 09:33:49 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 09:33:49 INFO - building 'psutil._psutil_posix' extension 09:33:49 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 09:33:49 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 09:33:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:49 INFO - Successfully installed psutil 09:33:49 INFO - Cleaning up... 09:33:49 INFO - Return code: 0 09:33:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:49 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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:33:49 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 09:33:49 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 09:33:49 INFO - Using env: {'DISPLAY': ':0', 09:33:49 INFO - 'HOME': '/home/cltbld', 09:33:49 INFO - 'LANG': 'en_US.UTF-8', 09:33:49 INFO - 'LANGUAGE': 'en_US:en', 09:33:49 INFO - 'LOGNAME': 'cltbld', 09:33:49 INFO - 'MAIL': '/var/mail/cltbld', 09:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:49 INFO - 'MOZ_NO_REMOTE': '1', 09:33:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:49 INFO - 'NO_EM_RESTART': '1', 09:33:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:49 INFO - 'PWD': '/builds/slave/test', 09:33:49 INFO - 'SHELL': '/bin/bash', 09:33:49 INFO - 'SHLVL': '1', 09:33:49 INFO - 'TERM': 'linux', 09:33:49 INFO - 'TMOUT': '86400', 09:33:49 INFO - 'USER': 'cltbld', 09:33:49 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:49 INFO - '_': '/tools/buildbot/bin/python'} 09:33:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:33:49 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 09:33:49 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 09:33:49 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 09:33:49 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 09:33:49 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 09:33:49 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 09:33:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:33:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:33:51 INFO - Installing collected packages: mozsystemmonitor 09:33:51 INFO - Running setup.py install for mozsystemmonitor 09:33:51 INFO - Successfully installed mozsystemmonitor 09:33:51 INFO - Cleaning up... 09:33:51 INFO - Return code: 0 09:33:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:33:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:33:51 INFO - Using env: {'DISPLAY': ':0', 09:33:51 INFO - 'HOME': '/home/cltbld', 09:33:51 INFO - 'LANG': 'en_US.UTF-8', 09:33:51 INFO - 'LANGUAGE': 'en_US:en', 09:33:51 INFO - 'LOGNAME': 'cltbld', 09:33:51 INFO - 'MAIL': '/var/mail/cltbld', 09:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:51 INFO - 'MOZ_NO_REMOTE': '1', 09:33:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:51 INFO - 'NO_EM_RESTART': '1', 09:33:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:51 INFO - 'PWD': '/builds/slave/test', 09:33:51 INFO - 'SHELL': '/bin/bash', 09:33:51 INFO - 'SHLVL': '1', 09:33:51 INFO - 'TERM': 'linux', 09:33:51 INFO - 'TMOUT': '86400', 09:33:51 INFO - 'USER': 'cltbld', 09:33:51 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:51 INFO - '_': '/tools/buildbot/bin/python'} 09:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:51 INFO - Downloading/unpacking jsonschema==2.5.1 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:33:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:33:53 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 09:33:53 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 09:33:53 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 09:33:53 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 09:33:53 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 09:33:53 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 09:33:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:33:54 INFO - warning: no files found matching '*.txt' 09:33:54 INFO - no previously-included directories found matching 'build' 09:33:54 INFO - no previously-included directories found matching 'dist' 09:33:54 INFO - no previously-included directories found matching '.git*' 09:33:54 INFO - Installing collected packages: jsonschema, functools32 09:33:54 INFO - Running setup.py install for functools32 09:33:54 INFO - warning: no files found matching '*.txt' 09:33:54 INFO - no previously-included directories found matching 'build' 09:33:54 INFO - no previously-included directories found matching 'dist' 09:33:54 INFO - no previously-included directories found matching '.git*' 09:33:54 INFO - Successfully installed jsonschema functools32 09:33:54 INFO - Cleaning up... 09:33:54 INFO - Return code: 0 09:33:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:33:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:33:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:33:54 INFO - Using env: {'DISPLAY': ':0', 09:33:54 INFO - 'HOME': '/home/cltbld', 09:33:54 INFO - 'LANG': 'en_US.UTF-8', 09:33:54 INFO - 'LANGUAGE': 'en_US:en', 09:33:54 INFO - 'LOGNAME': 'cltbld', 09:33:54 INFO - 'MAIL': '/var/mail/cltbld', 09:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:54 INFO - 'MOZ_NO_REMOTE': '1', 09:33:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:54 INFO - 'NO_EM_RESTART': '1', 09:33:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:54 INFO - 'PWD': '/builds/slave/test', 09:33:54 INFO - 'SHELL': '/bin/bash', 09:33:54 INFO - 'SHLVL': '1', 09:33:54 INFO - 'TERM': 'linux', 09:33:54 INFO - 'TMOUT': '86400', 09:33:54 INFO - 'USER': 'cltbld', 09:33:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:54 INFO - '_': '/tools/buildbot/bin/python'} 09:33:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:33:54 INFO - Cleaning up... 09:33:54 INFO - Return code: 0 09:33:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:33:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:33:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:33:54 INFO - Using env: {'DISPLAY': ':0', 09:33:54 INFO - 'HOME': '/home/cltbld', 09:33:54 INFO - 'LANG': 'en_US.UTF-8', 09:33:54 INFO - 'LANGUAGE': 'en_US:en', 09:33:54 INFO - 'LOGNAME': 'cltbld', 09:33:54 INFO - 'MAIL': '/var/mail/cltbld', 09:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:54 INFO - 'MOZ_NO_REMOTE': '1', 09:33:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:54 INFO - 'NO_EM_RESTART': '1', 09:33:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:54 INFO - 'PWD': '/builds/slave/test', 09:33:54 INFO - 'SHELL': '/bin/bash', 09:33:54 INFO - 'SHLVL': '1', 09:33:54 INFO - 'TERM': 'linux', 09:33:54 INFO - 'TMOUT': '86400', 09:33:54 INFO - 'USER': 'cltbld', 09:33:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:54 INFO - '_': '/tools/buildbot/bin/python'} 09:33:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:54 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:57 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 09:33:57 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 09:33:57 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 09:33:57 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 09:33:57 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 09:33:57 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 09:33:57 INFO - Downloading docopt-0.6.1.tar.gz 09:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:57 INFO - Running setup.py install for blobuploader 09:33:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:57 INFO - Running setup.py install for requests 09:33:58 INFO - Running setup.py install for docopt 09:33:58 INFO - Successfully installed blobuploader requests docopt 09:33:58 INFO - Cleaning up... 09:33:58 INFO - Return code: 0 09:33:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:58 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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:33:58 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 09:33:58 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 09:33:58 INFO - Using env: {'DISPLAY': ':0', 09:33:58 INFO - 'HOME': '/home/cltbld', 09:33:58 INFO - 'LANG': 'en_US.UTF-8', 09:33:58 INFO - 'LANGUAGE': 'en_US:en', 09:33:58 INFO - 'LOGNAME': 'cltbld', 09:33:58 INFO - 'MAIL': '/var/mail/cltbld', 09:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:58 INFO - 'MOZ_NO_REMOTE': '1', 09:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:58 INFO - 'NO_EM_RESTART': '1', 09:33:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:58 INFO - 'PWD': '/builds/slave/test', 09:33:58 INFO - 'SHELL': '/bin/bash', 09:33:58 INFO - 'SHLVL': '1', 09:33:58 INFO - 'TERM': 'linux', 09:33:58 INFO - 'TMOUT': '86400', 09:33:58 INFO - 'USER': 'cltbld', 09:33:58 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:58 INFO - '_': '/tools/buildbot/bin/python'} 09:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:58 INFO - Running setup.py (path:/tmp/pip-AbRyJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:58 INFO - Running setup.py (path:/tmp/pip-ayh7ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:58 INFO - Running setup.py (path:/tmp/pip-TPnGK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:58 INFO - Running setup.py (path:/tmp/pip-9jpsUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:58 INFO - Running setup.py (path:/tmp/pip-cCq1GN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:58 INFO - Running setup.py (path:/tmp/pip-LZXCba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:58 INFO - Running setup.py (path:/tmp/pip-4NRUVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:58 INFO - Running setup.py (path:/tmp/pip-gHmejy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:58 INFO - Running setup.py (path:/tmp/pip-Wgldin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:59 INFO - Running setup.py (path:/tmp/pip-7GjZOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:59 INFO - Running setup.py (path:/tmp/pip-aHgW_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:59 INFO - Running setup.py (path:/tmp/pip-m_rdyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:59 INFO - Running setup.py (path:/tmp/pip-7F9w1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:59 INFO - Running setup.py (path:/tmp/pip-O3OFwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:59 INFO - Running setup.py (path:/tmp/pip-MJ53yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:59 INFO - Running setup.py (path:/tmp/pip-a7ki4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:59 INFO - Running setup.py (path:/tmp/pip-ttG5SJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:59 INFO - Running setup.py install for manifestparser 09:33:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozcrash 09:33:59 INFO - Running setup.py install for mozdebug 09:33:59 INFO - Running setup.py install for mozdevice 09:34:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozfile 09:34:00 INFO - Running setup.py install for mozhttpd 09:34:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozinfo 09:34:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozInstall 09:34:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozleak 09:34:00 INFO - Running setup.py install for mozlog 09:34:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for moznetwork 09:34:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozprocess 09:34:00 INFO - Running setup.py install for mozprofile 09:34:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozrunner 09:34:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:01 INFO - Running setup.py install for mozscreenshot 09:34:01 INFO - Running setup.py install for moztest 09:34:01 INFO - Running setup.py install for mozversion 09:34:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:01 INFO - Cleaning up... 09:34:01 INFO - Return code: 0 09:34:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:34:01 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 09:34:01 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 09:34:01 INFO - Using env: {'DISPLAY': ':0', 09:34:01 INFO - 'HOME': '/home/cltbld', 09:34:01 INFO - 'LANG': 'en_US.UTF-8', 09:34:01 INFO - 'LANGUAGE': 'en_US:en', 09:34:01 INFO - 'LOGNAME': 'cltbld', 09:34:01 INFO - 'MAIL': '/var/mail/cltbld', 09:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:01 INFO - 'MOZ_NO_REMOTE': '1', 09:34:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:01 INFO - 'NO_EM_RESTART': '1', 09:34:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:01 INFO - 'PWD': '/builds/slave/test', 09:34:01 INFO - 'SHELL': '/bin/bash', 09:34:01 INFO - 'SHLVL': '1', 09:34:01 INFO - 'TERM': 'linux', 09:34:01 INFO - 'TMOUT': '86400', 09:34:01 INFO - 'USER': 'cltbld', 09:34:01 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:01 INFO - '_': '/tools/buildbot/bin/python'} 09:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:01 INFO - Running setup.py (path:/tmp/pip-FkaPkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:01 INFO - Running setup.py (path:/tmp/pip-0XxWin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:01 INFO - Running setup.py (path:/tmp/pip-2NGTFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:01 INFO - Running setup.py (path:/tmp/pip-u7Wg8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:01 INFO - Running setup.py (path:/tmp/pip-OA4NEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:01 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)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:01 INFO - Running setup.py (path:/tmp/pip-neUG7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:02 INFO - Running setup.py (path:/tmp/pip-9ETw6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:02 INFO - Running setup.py (path:/tmp/pip-wPl4cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:02 INFO - Running setup.py (path:/tmp/pip-hrygVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:02 INFO - Running setup.py (path:/tmp/pip-FC99oL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:02 INFO - Running setup.py (path:/tmp/pip-VpMToF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:02 INFO - Running setup.py (path:/tmp/pip-2AnoFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:02 INFO - Running setup.py (path:/tmp/pip-Ayewoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:02 INFO - Running setup.py (path:/tmp/pip-L_PEXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:02 INFO - Running setup.py (path:/tmp/pip-4MYlIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:02 INFO - Running setup.py (path:/tmp/pip-OozcWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:02 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)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:02 INFO - Running setup.py (path:/tmp/pip-561iCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:02 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)) 09:34:02 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)) 09:34:02 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)) 09:34:02 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)) 09:34:02 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)) 09:34:02 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)) 09:34:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:04 INFO - Downloading blessings-1.6.tar.gz 09:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:04 INFO - Installing collected packages: blessings 09:34:04 INFO - Running setup.py install for blessings 09:34:05 INFO - Successfully installed blessings 09:34:05 INFO - Cleaning up... 09:34:05 INFO - Return code: 0 09:34:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:05 INFO - Reading from file tmpfile_stdout 09:34:05 INFO - Current package versions: 09:34:05 INFO - argparse == 1.2.1 09:34:05 INFO - blessings == 1.6 09:34:05 INFO - blobuploader == 1.2.4 09:34:05 INFO - docopt == 0.6.1 09:34:05 INFO - functools32 == 3.2.3-2 09:34:05 INFO - jsonschema == 2.5.1 09:34:05 INFO - manifestparser == 1.1 09:34:05 INFO - mozInstall == 1.12 09:34:05 INFO - mozcrash == 0.17 09:34:05 INFO - mozdebug == 0.1 09:34:05 INFO - mozdevice == 0.48 09:34:05 INFO - mozfile == 1.2 09:34:05 INFO - mozhttpd == 0.7 09:34:05 INFO - mozinfo == 0.9 09:34:05 INFO - mozleak == 0.1 09:34:05 INFO - mozlog == 3.4 09:34:05 INFO - moznetwork == 0.27 09:34:05 INFO - mozprocess == 0.23 09:34:05 INFO - mozprofile == 0.28 09:34:05 INFO - mozrunner == 6.12 09:34:05 INFO - mozscreenshot == 0.1 09:34:05 INFO - mozsystemmonitor == 0.3 09:34:05 INFO - moztest == 0.7 09:34:05 INFO - mozversion == 1.4 09:34:05 INFO - psutil == 3.1.1 09:34:05 INFO - requests == 1.2.3 09:34:05 INFO - wsgiref == 0.1.2 09:34:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:05 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 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:34:05 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 09:34:05 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 09:34:05 INFO - Using env: {'DISPLAY': ':0', 09:34:05 INFO - 'HOME': '/home/cltbld', 09:34:05 INFO - 'LANG': 'en_US.UTF-8', 09:34:05 INFO - 'LANGUAGE': 'en_US:en', 09:34:05 INFO - 'LOGNAME': 'cltbld', 09:34:05 INFO - 'MAIL': '/var/mail/cltbld', 09:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:05 INFO - 'MOZ_NO_REMOTE': '1', 09:34:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:05 INFO - 'NO_EM_RESTART': '1', 09:34:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:05 INFO - 'PWD': '/builds/slave/test', 09:34:05 INFO - 'SHELL': '/bin/bash', 09:34:05 INFO - 'SHLVL': '1', 09:34:05 INFO - 'TERM': 'linux', 09:34:05 INFO - 'TMOUT': '86400', 09:34:05 INFO - 'USER': 'cltbld', 09:34:05 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:05 INFO - '_': '/tools/buildbot/bin/python'} 09:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 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)) 09:34:05 INFO - Cleaning up... 09:34:05 INFO - Return code: 0 09:34:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f24090def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', '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 09:34:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:34:05 INFO - Using env: {'DISPLAY': ':0', 09:34:05 INFO - 'HOME': '/home/cltbld', 09:34:05 INFO - 'LANG': 'en_US.UTF-8', 09:34:05 INFO - 'LANGUAGE': 'en_US:en', 09:34:05 INFO - 'LOGNAME': 'cltbld', 09:34:05 INFO - 'MAIL': '/var/mail/cltbld', 09:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:05 INFO - 'MOZ_NO_REMOTE': '1', 09:34:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:05 INFO - 'NO_EM_RESTART': '1', 09:34:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:05 INFO - 'PWD': '/builds/slave/test', 09:34:05 INFO - 'SHELL': '/bin/bash', 09:34:05 INFO - 'SHLVL': '1', 09:34:05 INFO - 'TERM': 'linux', 09:34:05 INFO - 'TMOUT': '86400', 09:34:05 INFO - 'USER': 'cltbld', 09:34:05 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:05 INFO - '_': '/tools/buildbot/bin/python'} 09:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:05 INFO - Cleaning up... 09:34:05 INFO - Return code: 0 09:34:05 INFO - Running post-action listener: _resource_record_post_action 09:34:05 INFO - Running post-action listener: _start_resource_monitoring 09:34:05 INFO - Starting resource monitoring. 09:34:05 INFO - [mozharness: 2016-12-15 17:34:05.795242Z] Finished create-virtualenv step (success) 09:34:05 INFO - [mozharness: 2016-12-15 17:34:05.795825Z] Running install step. 09:34:05 INFO - Running pre-action listener: _resource_record_pre_action 09:34:05 INFO - Running main action method: install 09:34:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:06 INFO - Reading from file tmpfile_stdout 09:34:06 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:06 INFO - Reading from file tmpfile_stdout 09:34:06 INFO - Output received: 09:34:06 INFO - Usage: mozinstall [options] installer 09:34:06 INFO - Options: 09:34:06 INFO - -h, --help show this help message and exit 09:34:06 INFO - -d DEST, --destination=DEST 09:34:06 INFO - Directory to install application into. [default: 09:34:06 INFO - "/builds/slave/test"] 09:34:06 INFO - --app=APP Application being installed. [default: firefox] 09:34:06 INFO - mkdir: /builds/slave/test/build/application 09:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:34:19 INFO - Reading from file tmpfile_stdout 09:34:19 INFO - Output received: 09:34:19 INFO - /builds/slave/test/build/application/firefox/firefox 09:34:19 INFO - Running post-action listener: _resource_record_post_action 09:34:19 INFO - [mozharness: 2016-12-15 17:34:19.573062Z] Finished install step (success) 09:34:19 INFO - [mozharness: 2016-12-15 17:34:19.573306Z] Running run-tests step. 09:34:19 INFO - Running pre-action listener: _resource_record_pre_action 09:34:19 INFO - Running main action method: run_tests 09:34:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:19 INFO - Python 2.7.3 09:34:19 INFO - Return code: 0 09:34:19 INFO - grabbing minidump binary from tooltool 09:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:19 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 0x1599f60>, '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 0x159ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x159b1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:19 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 09:34:19 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 09:34:19 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 09:34:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:19 INFO - Return code: 0 09:34:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:34:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:34:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 09:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 09:34:19 INFO - Using env: {'DISPLAY': ':0', 09:34:19 INFO - 'HOME': '/home/cltbld', 09:34:19 INFO - 'LANG': 'en_US.UTF-8', 09:34:19 INFO - 'LANGUAGE': 'en_US:en', 09:34:19 INFO - 'LOGNAME': 'cltbld', 09:34:19 INFO - 'MAIL': '/var/mail/cltbld', 09:34:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:19 INFO - 'MOZ_NO_REMOTE': '1', 09:34:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:34:19 INFO - 'NO_EM_RESTART': '1', 09:34:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:19 INFO - 'PWD': '/builds/slave/test', 09:34:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:19 INFO - 'SHELL': '/bin/bash', 09:34:19 INFO - 'SHLVL': '1', 09:34:19 INFO - 'TERM': 'linux', 09:34:19 INFO - 'TMOUT': '86400', 09:34:19 INFO - 'USER': 'cltbld', 09:34:19 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937', 09:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:19 INFO - '_': '/tools/buildbot/bin/python'} 09:34:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 09:34:19 INFO - mozversion application_buildid: 20161215075301 09:34:19 INFO - mozversion application_changeset: 5aec4a29e94f25ddf86b24ddb3619e4b18d16dbc 09:34:19 INFO - mozversion application_display_name: Firefox 09:34:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:19 INFO - mozversion application_name: Firefox 09:34:19 INFO - mozversion application_remotingname: firefox 09:34:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:34:19 INFO - mozversion application_vendor: Mozilla 09:34:19 INFO - mozversion application_version: 51.0 09:34:19 INFO - mozversion platform_buildid: 20161215075301 09:34:19 INFO - mozversion platform_changeset: 5aec4a29e94f25ddf86b24ddb3619e4b18d16dbc 09:34:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:34:19 INFO - mozversion platform_version: 51.0 09:34:19 INFO - using testdate: 1481823259 09:34:19 INFO - actual date: 1481823259 09:34:19 INFO - starting webserver on 'localhost:36604' 09:34:19 INFO - SUITE-START | Running 2 tests 09:34:19 INFO - TEST-START | dromaeo_css 09:34:19 INFO - operating with platform_type : linux_ 09:34:19 INFO - Initialising browser for dromaeo_css test... 09:34:19 INFO - TEST-INFO | started process 10065 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2V5zqM/profile http://localhost:36604/getInfo.html) 09:34:23 INFO - PROCESS | 10065 | __metrics Screen width/height:1600/1200 09:34:23 INFO - PROCESS | 10065 | colorDepth:24 09:34:23 INFO - PROCESS | 10065 | Browser inner width/height: 1024/697 09:34:23 INFO - PROCESS | 10065 | __metrics 09:34:24 INFO - PROCESS | 10065 | 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" 09:34:24 INFO - PROCESS | 10065 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:24 INFO - PROCESS | 10065 | console.error: PushService: 09:34:24 INFO - PROCESS | 10065 | stateChangeProcessEnqueue: Error transitioning state 09:34:24 INFO - PROCESS | 10065 | UnknownError 09:34:25 INFO - TEST-INFO | 10065: exit 0 09:34:25 INFO - Browser initialized. 09:34:25 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:25 INFO - TEST-INFO | started process 10157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2V5zqM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:37 INFO - PROCESS | 10157 | RSS: Main: 143532032 09:34:37 INFO - PROCESS | 10157 | 09:35:28 INFO - PROCESS | 10157 | 1481823328203 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] 09:37:37 INFO - PROCESS | 10157 | Cycle 1(1): loaded http://localhost:36604/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36604/tests/dromaeo/cssquery-ext.html) 09:37:38 INFO - PROCESS | 10157 | RSS: Main: 241815552 09:37:38 INFO - PROCESS | 10157 | 09:40:40 INFO - PROCESS | 10157 | Cycle 1(1): loaded http://localhost:36604/tests/dromaeo/cssquery-ext.html (next: http://localhost:36604/tests/dromaeo/cssquery-jquery.html) 09:40:41 INFO - PROCESS | 10157 | RSS: Main: 230260736 09:40:41 INFO - PROCESS | 10157 | 09:43:43 INFO - PROCESS | 10157 | Cycle 1(1): loaded http://localhost:36604/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36604/tests/dromaeo/cssquery-mootools.html) 09:43:44 INFO - PROCESS | 10157 | RSS: Main: 175542272 09:43:44 INFO - PROCESS | 10157 | 09:46:46 INFO - PROCESS | 10157 | Cycle 1(1): loaded http://localhost:36604/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36604/tests/dromaeo/cssquery-prototype.html) 09:46:46 INFO - PROCESS | 10157 | RSS: Main: 229486592 09:46:46 INFO - PROCESS | 10157 | 09:49:49 INFO - PROCESS | 10157 | Cycle 1(1): loaded http://localhost:36604/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36604/tests/dromaeo/cssquery-yui.html) 09:49:49 INFO - PROCESS | 10157 | RSS: Main: 175808512 09:49:49 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | Cycle 1(1): loaded http://localhost:36604/tests/dromaeo/cssquery-yui.html (next: http://localhost:36604/tests/dromaeo/cssquery-dojo.html) 09:52:52 INFO - PROCESS | 10157 | RSS: Main: 263110656 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | __start_tp_report 09:52:52 INFO - PROCESS | 10157 | _x_x_mozilla_page_load 09:52:52 INFO - PROCESS | 10157 | _x_x_mozilla_page_load_details 09:52:52 INFO - PROCESS | 10157 | |i|pagename|runs| 09:52:52 INFO - PROCESS | 10157 | |0;dojo.html;3119.610048743907;3160.036188962367;3168.9049328520136;3189.8245596492184;3200.455922493176;5538.446155384459;5600.215969764236;5649.689267090308;5654.123610840321;5664.745086471108;5656;5689.4879460848515;5691.573132015094;5694.060480020791;5696.316442026952;5132.8460146195675;5172.791991544865;5179.844604661847;5184.662996905208;5236.764345604438;5068.771905264253;5246.422893481733;5261.158214685651;5270.947290527103;5299.046171689094;5032.144535428967;5182.4299327073995;5187.533122019017;5201.167813149897;5233.73831308435;5188.584913206934;5287.7884884604955;5292.153255479144;5294.41761406245;5297.814576489824;5115.0025744979375;5152;5246.344206974129;5249.1338929076655;5278.9208161877605;5480.588955828329;5500.202470641774;5510.1459273812625;5543.334799824007;5544.722763861791;5909.379515150933;5936.436038576371;5942.316633587149;6003.699815009232;6029.125776762345;6034.396560343975;6042.184305118797;6057.151998720183;6058.636481811105;6059.7273122709585;5524.309461317335;5585.469380408855;5619.241402410663;5654.773809047674;5701.2018317435595;6231.937680623136;6260;6278.277998030194;6278.843028924313;6301.369863013662;6015.699215039231;6025.969870150621;6038.305594856648;6039.214902062704;6071.574989750675;5801.6519008859605;5873.559483038789;5972.910406343908;6025.548083893726;6029.3970602940235;6016.127661489059;6040.214772079602;6040.758369665252;6041.939580604226;6068.8786224276155;6015.819525414245;6059.424354686298;6076.665783381868;6078.635281883013;6100.115483254903;4549.044700612908;4579.152856721517;4605.102005109038;4648.093621743795;4676.111538807616;4100.466939297932;4108.4453598764385;4132.152908653718;4155.58444155588;4171.8957026074595;5768.077107662753;5776;5777.19119323295;5779.624324418899;5781.132830075522;5862.443067908627;5947.405259474104;6042.758289668452;6086.847828804315;6098.786542470993;5766.192733017381;5846.473817356458;5848.5321174305955;5878.441548052928;5880.970595146997;5320.441353657888;5323.733813309319;5330.600204984642;5331.30693009914;5333.040052790535;6087.391260873877;6113.567524283718;6309.116723658692;6354.364563543609;6369.267534233622;5949.970250148722;6041.305249896318;6051.334353221054;6091.177691011834;6130.172426722335;6244.219472565929;6282.340354262919;6282.465990390868;6284.465820405134;6286.874262514815;5803.82588522345;5969.970150149222;5979.162917191509;5983.132445795248;6024.307204671519;5896.115582662464;6018.849528761641;6054.243219597551;6076.3012253591405;6145.40219542931;5933.139694744152;5982.700864956648;5983.850403739942;5993.70031498432;6018.067199584071;5971.820845374646;5981.7607295707685;5987.311459182076;5988.251468566429;5991.400859913973;5651.943480565142;5768.365479797303;5839.386864379179;5868.94131058684;5890.617109887844;6012.428819262163;6020.849478762891;6024.3975602439405;6033.12519684652;6034.034554471439;5686.402927692533;5849.3858144894175;5849.94150058494;5854.414558544111;5865.090910908816 09:52:52 INFO - PROCESS | 10157 | |1;ext.html;595.4135176850801;609.92071030766;627.1188979483827;640.9679516024198;655.1679367203653;34744.65255347449;46019.76990115049;46442.767786161065;46475.535244647544;46571;47673.76163119188;48001.51998480023;48061.51938480614;48176.27735583969;48181.27728084082;8331.416800823914;9139.817203655923;9429.151376376125;9434.716958491255;9440.81118377632;8283.875741863876;11043.779124417506;11222.66332010037;11274.887251127466;11611.709707257301;11345.943270283678;11400.714982125472;11403.315801051922;11412.087033037378;11423.828642570368;11126.109911207084;11175.161862860317;11181.49683264247;11197.384143872085;11202.775944481105;11076.667699969013;11137.387443690593;11139.498722557424;11145.609903653332;11149.052330551913;44787.328190077176;45376;45390.77304613489;45463.318050229274;45500.77249613731;3771.3400154973046;3861.073342397832;3871.477350557695;3905.1994341159993;3919.608039196086;34108;34298.82850585731;34717.47923781145;35739.106522336886;35835.28329433423;4506.459224893001;4530.161920044769;4615.284630882219;4623.398958135421;4761.59526440253;350105;352374;353953;354421;356791.2160439207;59754.40245597575;59823;60056;60065;60112.39887601155;40490.1901961965;41492.58507414947;41501.1699766003;43443.782781085894;43587.782061090125;17605;17754.822451775315;17765.289388424575;17773.7333939991;17774.91112544455;12127.878721212852;12244;13039.087263891437;13480.865191348157;13544.45822167122;19723.40829775109;20847.687284690743;20896.582068358854;20922.267720629705;21048.684269735968;7237.493375463667;7249.927500725031;7511.399088072941;7609.201033891472;7648.42636802242;907.228855472856;1016.4714348538719;1022.2537547590152;1071.23406764164;1108.0415440643947;34047.31905361879;34658.30683386318;35079;35235.11912202215;35372.469412958824;1070.4594179939081;1086.2124959404368;1119.9034669139835;1123.2193625430411;1159.3855256713955;16534.32605294962;17218.655626887394;17306.82693173052;17395.304187832346;17657.55856103582;540601;542576;543385;544838;545690;59371;59819.700901496086;59957.40042599606;60042;60097.699511502164;1257.6855786053486;1275.591810620611;1323.5632241360329;1333.5865881576935;1361.884239839605;30468;33931.3213735719;35036.299274013865;35550.46674299836;35702.64297357097;52615.21077183769;53297.46702532925;53366.46633533615;53428;53432.46567534275;441741;445872;445993;446685;446708;27378.31554211081;28136.577951331157;47294.29058564193;48146.518534814204;48408;48458;51605.48394516157;51686;51881.48118518917;52357.47642523526;43321.35017974796;43331.566684332756;43817.56182438135;44221.55778442303;45398.31902521531;42694.146117078104;44026.33960490659;44056.77971610121;44064.82348954507;45269;38720.419193711554;39266.01834954149;39779.60220397875;39797.40303895386;39869.40195897122;37353;39208.60791392164;39421.80289098536;40050.79974600225;40843;29748.810047597854;29897.082164569027;30068.24829379283;30084;30207.395852083275 09:52:52 INFO - PROCESS | 10157 | |2;jquery.html;410.1325696152636;414.1406581343713;415.10544776007697;420.5794205794207;442.6171361772068;18841.62316753664;19791.41668666505;19809.40571782849;19991.900040499815;20535;19640.901795491;19757.703634445494;20468.69296960547;20598.38204853853;20666;5849.590528662995;6138.048602466625;6146.84632884177;6147.569670123094;6150.169727086831;4281.35779633056;4506.324051392285;4562.794674239651;6066.454019138277;6071.666058366788;6766.391024807766;6836.282190370014;6872.828179295508;6883.380495755381;6884.7934561962975;6561.540692151551;6790.626515541643;6809.250982391933;6819.522633415663;6861.313868613149;5870.823875283749;6055.091736239555;6066.575339726221;6078.635281883101;6083.209182806207;23915.16296929599;24037.639435408484;24289.27132186037;24298.0189061242;24596.631050534255;2525.1162093267208;2547.337692200041;2552.8851201695784;2619.6856377234853;3010.4882170031146;14971.775423368657;15228.923855380652;15367.846321536754;15721.528354149394;15778.76331855023;1975.9703604445945;2013.3054096336741;2078.293380250722;2091.466675997626;2115.048228297257;61079;65724.2766149245;83782;84394;84852;11110.61112861046;11170.888291117148;11183.161262905314;11273.492692828842;11567.88432115685;15966.920165399099;15996.600084997968;15998.280077396545;16031.038137711774;16055.518334449986;8352.749417517483;8403.15968403167;8472.237498625153;8542.530160841214;8736.519491428033;8204.138565450661;8231.506109633441;8254.54599996994;8410;8478.23695867375;6909.7581584644295;6917.817803810303;6970.163580370287;6982.825429364205;7008.649567521604;1918.2806447582157;1984.3947595983363;1988.6917527782928;1998.5603167303461;2037.032409605455;918.2745630951607;963.2679163835395;975.8731364922514;1062.4634559547533;1091.6724982505216;21064.684029739565;21401.892990534947;21420.464488387915;21946.341609751733;21971;1021.5454122915231;1030.438411065956;1061.2253055269541;1066.632011955874;1082.9928166804948;11633.825492617618;11922.284662920252;12097.758044839054;12146.210496317712;12267.202631828903;171674;171909;172222;395850.0207498944;515142;82272.58863705643;82809.58595206986;82829.17170828335;82874;83098.58450707707;1034.234666346913;1054.056619325699;1081.216118314216;1083.4420273559124;1090.4384242115316;17718.46844594664;17939.192736326637;17987.460376188734;18305.17626706775;18745.156467958703;41577;41924.790376047924;41944.161116776886;41959.370609441496;42562.57437425586;54492.727536362065;54666.72666636641;55005.174922375394;56342.71828640831;56441.71779141078;9218.492982885737;9293.907060929305;9364.063593640582;9395.15443610051;9491;12881.742365152457;13785.103968242032;14008.089474184146;14257.287135643384;14770.48303309379;9309.394889332172;11242.325460472397;11557.826632600685;11757.764844702888;12063.81904271419;11755.88244117548;12334.75330493403;12396.442160102637;12682.556110536087;12686.555970540987;11634.36011019402;11640.53437862476;11702.765944681229;12088.274703517816;12446.128770986306;10900.330628491576;11648.184627076023;12014.399280035788;12825.422855971317;13380.799288010883;11632.418379080842;12146.210496317712;12362.567310144103;12435.440405181607;12868.742625147257 09:52:52 INFO - PROCESS | 10157 | |3;mootools.html;1356.3150608942483;1516.0524672079953;1542.7917231173792;1688.738245571937;1822.2073398071839;7864.095629002659;8752.91247087529;8973.910260897397;9000.189982901538;9262.536873156332;9196.49419281941;9259.583318750656;9276.118768716979;9295.39579927306;9297.628094876214;2485.2916918678134;2503;2506.4987002599555;2514.9371265718414;2515.7735803777655;2432.8783560821976;2517.609770485578;2522.331582130737;2523.3817714659936;2525.8863351149153;2435.2694191742494;2448.5592593333195;2514.0572285392977;2519.937001574957;2522.6216067589826;2440.670509481224;2450.2281781238944;2461.4584791345874;2483.2674361063528;2499.350168956066;2405.615101583756;2422.5033868057008;2437.524682686877;2437.9878100609562;2442.68245128134;9140;9316.347855650107;9325.673601423985;9339.813203735886;9358.204552613037;5429.945700542983;5619.887602247973;5632.690202038886;5691.402402747734;5696.829095127153;3481.3733527964955;3485.7734247274097;3528.153243221634;3545.7872527648424;3576.445650924111;1203.8856308650663;1204.6205445284706;1217.6347095871204;1238.876112388763;2143.164165975271;12505.812412813724;12768.361581920866;12802.551910683174;12839.935800321033;12852.550160744422;4699.943859021538;4804.783784729696;4832.95167048332;4906.632002599819;4919.458859525449;10191.082802547806;10200.030997055268;10201.693949181536;10213.182945364353;10218.897811021943;5569.721513924369;5585.273914391184;5589.5807814413265;5597;5608.635438696471;4258.467691538558;4395.934060989088;4952.554270115635;5102.795888164432;5218.947810521922;4042.7776472293717;4070.5115386153852;4132.793360331972;4134.193832202752;4164.06308580573;1185.8102703567388;1187.9406029698653;1219.2867172704068;1225.3383173086434;1229.8954588859867;891.8127193289482;1020.7499162705036;1033.8862725100232;1035.5081336365167;1036.7667274863247;6000.729967151488;6216.906746398808;6333.68331583419;6356.745730170742;6417.326180751047;499.92001279795056;504.56607317706744;511.86179731472293;520.274217466632;524.111630785822;4032.0810805418414;4392.714473559208;4399.906130009959;4444.933326000112;4504.729716216972;11207.719807004727;11520.481578328996;11579.536818527165;11613.593524226611;11640.476178571984;11168.720781980353;11343;11480.024197943101;11497.885021149681;11501.942490287662;8656.480611163224;8742.25690816275;8747.343949203836;8752.8687069694;8754.299656027375;6053.667048312209;6160.907586386297;6268.655223962726;6366.4588509977175;6399.648019358794;10495.632652857113;10655.30740501865;10694.358338499715;10804.135669146292;10868.78262434731;10540.156787456832;10612.310199837295;10724.249302549058;10755.247132700635;10965.890341096487;7268.818279543054;7497.437692173;7903.328217101611;7972.521648701097;8134.308583770209;8900.06549312313;9197.126273004054;9208.171264586219;9217.078292170729;9219.262459003397;7764.22357764219;7869.3311068559815;7869.724559640385;8111.472754270953;8240.83518329618;7894.526328420313;8022.318102961313;8047.0745864226365;8108.189181081844;8117.431779775359;6940.340667636567;7292.927070729437;7379;7384.298491643285;7389.150247721581;5353.304070470814;5388.838334849961;5442.945570544244;5459.563234941115;5461.098918678374;5318.867028324323;5326.334208223972;5336.9466305338;5361.061814182424;5421.783128674809 09:52:52 INFO - PROCESS | 10157 | |4;prototype.html;337.3489165909793;345.9066052165915;355.0431586873377;355.89672016748085;359.1900264902644;5463.672179669223;5569.359523654781;5580.358258800239;5599.300087489064;5608.242887210231;5089.109405853979;5235.450277720842;5247.501487358695;5335.973320133404;5408.323959505062;4220.788960551976;4285.100128972919;4287.978560107211;4301.440812694345;4304.720193187449;4206.621404073633;4222.282212023948;4225.197212529625;4232.280512312899;4268.530461649216;4159.209750147463;4282.400463935051;4304.268274393345;4307.375430562566;4319.848805291816;4254.595813397722;4271.31658934569;4274.1451709658195;4290.648445739582;4301.720388174775;3812.370958791824;3819.1788765415567;3835.290471262825;3841.5582208046153;3843.13529455873;5066.594672426197;5291.862249616351;5307.3896501902;5310.336207974004;5317.840464786024;4816.157172494834;4825.7587120644175;4827.662063655545;4837.758112094381;4842.6368022398465;5645.955498232799;5650.0959846424785;5655.236543066674;5673.205751194836;5691.51622112121;3976.701747368959;4053.5338436079933;4085.55058943516;4096.467459230311;4100.220958017967;5820.885826083026;5858.707064646751;5873.353931067579;5880.8235752927485;5889.322727886305;4205.486025030987;4217.40956266123;4224.486430573804;4225.556316586714;4225.640820530228;4123.793810309532;4230.64039556635;4253.340732186516;4259.148170365939;4265.573442655772;4812.700570845922;4823.734694591761;4826;4834.854954351375;4844.491328410537;5705.001624715658;5707.258056452634;5726.5418766499415;5731.475142787156;5736.881308144872;1391.200059965524;1454.4836583012877;1463.1367493179075;1480.3042569992301;1485.7919891215238;912.89083763228;934.131257930118;941.75985123793;946.5267366316841;946.5835032585641;4866.416030076342;4871;4872.439669437989;4877.804887804448;4882.877928051827;5102.897942041139;5104.566111880457;5128;5129.692218466904;5137.174553995288;4452.532484089189;4830.227163653869;4874.612848436364;4875.24433712775;4898.828541001048;2688.637034000424;2689.367998520346;2734.726527347289;2759.199832048683;2760.144355249879;5193.714345710947;5248.553872920845;5298.70857102863;5303.522682958553;5319;4849.563539281482;5142.922856157161;5205.297284866495;5215.478452154754;5234.476552344735;5175.767090480937;5242.475752424803;5279.967212623091;5292.914952434741;5303.231031500488;4991;5023.723695196799;5041.773120209526;5105.923411148761;5106.66806657566;4825.648818330873;4961.82633607822;5027.522385373383;5052.267421223975;5059.671121377099;5169.276301317894;5178.197379406198;5210.270562121382;5213.739313034257;5232.555232805102;3765.585785563531;3794.6774524165753;3796.3166630006863;3814.351560234822;3824.3307421201735;5578.633234857486;5722.513586345207;5736.5984381094595;5777.988851950976;5855.506845754306;5671.149327600893;5674.2623458950075;5697.259356283656;5702.315722113207;5713.685747283939;5529.364123125892;5646.237757902631;5691;5713.8285851424525;5745.529422348431;5653.547716182778;5676.545876329801;5677.51741102011;5689.687067211177;5695;5702.315722113373;5704.629199102211;5708.514776244066;5717.8570535736935;5747.252857128547;5371.039570556797;5513.917291240555;5542.3349198094875;5553.222548843246;5562.805301814579 09:52:52 INFO - PROCESS | 10157 | |5;yui.html;828.6064119543217;830.7383174300095;840.7729912923511;847.2628812932747;918.4626993208973;16443.342266309348;17637.47087587371;17850.10749462525;17920.641587168247;17937.551561210978;16056;16256.18719064045;16350.918245408786;16371.099589522571;16877.915610421962;882.386741214856;894.8702438146465;900.3607438718518;901.9729408117767;902.1069141549851;725.4450345485708;752.1275320628073;861.750092473182;899.5322432335182;905.9365844390895;941.4069136444031;946.2808265718035;947.4220725357526;951.2770294576103;953.2326477185898;943.4527973775193;943.481085403029;943.8065196634674;943.8914524829664;944.2587568758526;697.8150790040643;698.0367093411111;698.2110215456553;698.6087790837147;698.6367089113633;15882.76175857363;15903.727701783946;15926.124063176521;15971.680566388608;16048.197590120564;14688.339024743913;14711.63220919475;14780.334884930204;15156.848431515655;16305.266263018075;14465.566033019026;14542.345594448276;14775.630609234748;14795.334209960578;15187;4755.239161734106;4771.785269662873;4771.928421073687;4970.751462426864;5009.7495125243595;91626.54186729023;92370.5381473095;93073.06926930712;95305.52347238289;95655;12181.573644922562;12415.255084694947;12529.248245105322;12743.553975610808;12782;23154.536909261722;23193.420164496023;23220.65169022466;23226.070957161523;23252.442231354536;8639.222469977733;8647.654093836176;8695.956520217485;8745.956270218609;8985.865212021825;5162.483751624883;5271.78912843482;5331.1470164773455;5334.039872822852;5501.312335958005;3619.1856832212543;3723.1250656095795;3726.2920045191327;3729.030452082478;3730.720195985312;623.7847942459844;623.8066199478176;630.8233694565529;645.122633218822;649.5388274325283;599.3437186281016;613.6287546034674;697.4281089506555;713.1299814226635;795.5942469340678;9300.395474294284;10252.948735256276;10303.072723454925;10376.325538839952;10608.045275925204;706.5336877660719;713.5968177979529;737.2885165815014;747.5066456138923;762.4205603741196;4865.416150062015;4878.438979517401;5006.324146240284;5037.924431133536;5073.365829271341;128829.03060437276;129277;129811.35094324467;130496.3475182637;132735;111786;112516.43741781403;113596.43201783938;114019.42990285161;116535.41732291284;99650.50174749225;100243.49878250562;101173.49413252887;102960;105471.47264263783;9332;9554.187894029086;9641.807163856824;9675.951620242135;9677.419354838732;61445;61599;61864;62639.373606263354;63403.682981584796;78747;80951.59524202341;86299;88261;89255;12668;13846.515371961934;13901.30493475302;13931.442742290195;15072.095674259579;16670.416535421686;16793.32826686919;16865.578360541083;18156.36452724201;18214.635707285513;14596.343164557407;15338.309776059881;15596.92201538985;16107.67784644324;16689.415870444474;15391.1534865579;15407.61480962985;15480;15851.603709907344;15859.286332114852;14833.332500037308;15172.317245723749;15193.088414695154;15293.464728734441;15701.214939252763;11878.287302761863;12187;12420.875791241971;12754.744905102032;13578.524751633646;12451.75096498083;12621.81067283973;12676.302803345905;12765;13011.414486347947 09:52:52 INFO - PROCESS | 10157 | __end_tp_report 09:52:52 INFO - PROCESS | 10157 | __start_cc_report 09:52:52 INFO - PROCESS | 10157 | _x_x_mozilla_cycle_collect,901 09:52:52 INFO - PROCESS | 10157 | __end_cc_report 09:52:52 INFO - PROCESS | 10157 | __startTimestamp1481824372578__endTimestamp 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | ------- Summary: start ------- 09:52:52 INFO - PROCESS | 10157 | Number of tests: 6 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | [#0] dojo.html Cycles:165 Average:5633.95 Median:5862.44 stddev:645.63 (11.0%) stddev-sans-first:616.75 09:52:52 INFO - PROCESS | 10157 | Values: 3119.6 3160.0 3168.9 3189.8 3200.5 5538.4 5600.2 5649.7 5654.1 5664.7 5656.0 5689.5 5691.6 5694.1 5696.3 5132.8 5172.8 5179.8 5184.7 5236.8 5068.8 5246.4 5261.2 5270.9 5299.0 5032.1 5182.4 5187.5 5201.2 5233.7 5188.6 5287.8 5292.2 5294.4 5297.8 5115.0 5152.0 5246.3 5249.1 5278.9 5480.6 5500.2 5510.1 5543.3 5544.7 5909.4 5936.4 5942.3 6003.7 6029.1 6034.4 6042.2 6057.2 6058.6 6059.7 5524.3 5585.5 5619.2 5654.8 5701.2 6231.9 6260.0 6278.3 6278.8 6301.4 6015.7 6026.0 6038.3 6039.2 6071.6 5801.7 5873.6 5972.9 6025.5 6029.4 6016.1 6040.2 6040.8 6041.9 6068.9 6015.8 6059.4 6076.7 6078.6 6100.1 4549.0 4579.2 4605.1 4648.1 4676.1 4100.5 4108.4 4132.2 4155.6 4171.9 5768.1 5776.0 5777.2 5779.6 5781.1 5862.4 5947.4 6042.8 6086.8 6098.8 5766.2 5846.5 5848.5 5878.4 5881.0 5320.4 5323.7 5330.6 5331.3 5333.0 6087.4 6113.6 6309.1 6354.4 6369.3 5950.0 6041.3 6051.3 6091.2 6130.2 6244.2 6282.3 6282.5 6284.5 6286.9 5803.8 5970.0 5979.2 5983.1 6024.3 5896.1 6018.8 6054.2 6076.3 6145.4 5933.1 5982.7 5983.9 5993.7 6018.1 5971.8 5981.8 5987.3 5988.3 5991.4 5651.9 5768.4 5839.4 5868.9 5890.6 6012.4 6020.8 6024.4 6033.1 6034.0 5686.4 5849.4 5849.9 5854.4 5865.1 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | [#1] ext.html Cycles:180 Average:61913.23 Median:39600.70 stddev:120111.04 (303.3%) stddev-sans-first:120359.75 09:52:52 INFO - PROCESS | 10157 | Values: 595.4 609.9 627.1 641.0 655.2 34744.7 46019.8 46442.8 46475.5 46571.0 47673.8 48001.5 48061.5 48176.3 48181.3 8331.4 9139.8 9429.2 9434.7 9440.8 8283.9 11043.8 11222.7 11274.9 11611.7 11345.9 11400.7 11403.3 11412.1 11423.8 11126.1 11175.2 11181.5 11197.4 11202.8 11076.7 11137.4 11139.5 11145.6 11149.1 44787.3 45376.0 45390.8 45463.3 45500.8 3771.3 3861.1 3871.5 3905.2 3919.6 34108.0 34298.8 34717.5 35739.1 35835.3 4506.5 4530.2 4615.3 4623.4 4761.6 350105.0 352374.0 353953.0 354421.0 356791.2 59754.4 59823.0 60056.0 60065.0 60112.4 40490.2 41492.6 41501.2 43443.8 43587.8 17605.0 17754.8 17765.3 17773.7 17774.9 12127.9 12244.0 13039.1 13480.9 13544.5 19723.4 20847.7 20896.6 20922.3 21048.7 7237.5 7249.9 7511.4 7609.2 7648.4 907.2 1016.5 1022.3 1071.2 1108.0 34047.3 34658.3 35079.0 35235.1 35372.5 1070.5 1086.2 1119.9 1123.2 1159.4 16534.3 17218.7 17306.8 17395.3 17657.6 540601.0 542576.0 543385.0 544838.0 545690.0 59371.0 59819.7 59957.4 60042.0 60097.7 1257.7 1275.6 1323.6 1333.6 1361.9 30468.0 33931.3 35036.3 35550.5 35702.6 52615.2 53297.5 53366.5 53428.0 53432.5 441741.0 445872.0 445993.0 446685.0 446708.0 27378.3 28136.6 47294.3 48146.5 48408.0 48458.0 51605.5 51686.0 51881.5 52357.5 43321.4 43331.6 43817.6 44221.6 45398.3 42694.1 44026.3 44056.8 44064.8 45269.0 38720.4 39266.0 39779.6 39797.4 39869.4 37353.0 39208.6 39421.8 40050.8 40843.0 29748.8 29897.1 30068.2 30084.0 30207.4 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | [#2] jquery.html Cycles:180 Average:23560.27 Median:21411.18 stddev:53919.64 (251.8%) stddev-sans-first:54042.88 09:52:52 INFO - PROCESS | 10157 | Values: 410.1 414.1 415.1 420.6 442.6 18841.6 19791.4 19809.4 19991.9 20535.0 19640.9 19757.7 20468.7 20598.4 20666.0 5849.6 6138.0 6146.8 6147.6 6150.2 4281.4 4506.3 4562.8 6066.5 6071.7 6766.4 6836.3 6872.8 6883.4 6884.8 6561.5 6790.6 6809.3 6819.5 6861.3 5870.8 6055.1 6066.6 6078.6 6083.2 23915.2 24037.6 24289.3 24298.0 24596.6 2525.1 2547.3 2552.9 2619.7 3010.5 14971.8 15228.9 15367.8 15721.5 15778.8 1976.0 2013.3 2078.3 2091.5 2115.0 61079.0 65724.3 83782.0 84394.0 84852.0 11110.6 11170.9 11183.2 11273.5 11567.9 15966.9 15996.6 15998.3 16031.0 16055.5 8352.7 8403.2 8472.2 8542.5 8736.5 8204.1 8231.5 8254.5 8410.0 8478.2 6909.8 6917.8 6970.2 6982.8 7008.6 1918.3 1984.4 1988.7 1998.6 2037.0 918.3 963.3 975.9 1062.5 1091.7 21064.7 21401.9 21420.5 21946.3 21971.0 1021.5 1030.4 1061.2 1066.6 1083.0 11633.8 11922.3 12097.8 12146.2 12267.2 171674.0 171909.0 172222.0 395850.0 515142.0 82272.6 82809.6 82829.2 82874.0 83098.6 1034.2 1054.1 1081.2 1083.4 1090.4 17718.5 17939.2 17987.5 18305.2 18745.2 41577.0 41924.8 41944.2 41959.4 42562.6 54492.7 54666.7 55005.2 56342.7 56441.7 9218.5 9293.9 9364.1 9395.2 9491.0 12881.7 13785.1 14008.1 14257.3 14770.5 9309.4 11242.3 11557.8 11757.8 12063.8 11755.9 12334.8 12396.4 12682.6 12686.6 11634.4 11640.5 11702.8 12088.3 12446.1 10900.3 11648.2 12014.4 12825.4 13380.8 11632.4 12146.2 12362.6 12435.4 12868.7 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | [#3] mootools.html Cycles:180 Average:6048.62 Median:4602.34 stddev:3443.50 (74.8%) stddev-sans-first:3435.10 09:52:52 INFO - PROCESS | 10157 | Values: 1356.3 1516.1 1542.8 1688.7 1822.2 7864.1 8752.9 8973.9 9000.2 9262.5 9196.5 9259.6 9276.1 9295.4 9297.6 2485.3 2503.0 2506.5 2514.9 2515.8 2432.9 2517.6 2522.3 2523.4 2525.9 2435.3 2448.6 2514.1 2519.9 2522.6 2440.7 2450.2 2461.5 2483.3 2499.4 2405.6 2422.5 2437.5 2438.0 2442.7 9140.0 9316.3 9325.7 9339.8 9358.2 5429.9 5619.9 5632.7 5691.4 5696.8 3481.4 3485.8 3528.2 3545.8 3576.4 1203.9 1204.6 1217.6 1238.9 2143.2 12505.8 12768.4 12802.6 12839.9 12852.6 4699.9 4804.8 4833.0 4906.6 4919.5 10191.1 10200.0 10201.7 10213.2 10218.9 5569.7 5585.3 5589.6 5597.0 5608.6 4258.5 4395.9 4952.6 5102.8 5218.9 4042.8 4070.5 4132.8 4134.2 4164.1 1185.8 1187.9 1219.3 1225.3 1229.9 891.8 1020.7 1033.9 1035.5 1036.8 6000.7 6216.9 6333.7 6356.7 6417.3 499.9 504.6 511.9 520.3 524.1 4032.1 4392.7 4399.9 4444.9 4504.7 11207.7 11520.5 11579.5 11613.6 11640.5 11168.7 11343.0 11480.0 11497.9 11501.9 8656.5 8742.3 8747.3 8752.9 8754.3 6053.7 6160.9 6268.7 6366.5 6399.6 10495.6 10655.3 10694.4 10804.1 10868.8 10540.2 10612.3 10724.2 10755.2 10965.9 7268.8 7497.4 7903.3 7972.5 8134.3 8900.1 9197.1 9208.2 9217.1 9219.3 7764.2 7869.3 7869.7 8111.5 8240.8 7894.5 8022.3 8047.1 8108.2 8117.4 6940.3 7292.9 7379.0 7384.3 7389.2 5353.3 5388.8 5442.9 5459.6 5461.1 5318.9 5326.3 5336.9 5361.1 5421.8 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | [#4] prototype.html Cycles:180 Average:4592.80 Median:5096.00 stddev:1318.76 (25.9%) stddev-sans-first:1283.20 09:52:52 INFO - PROCESS | 10157 | Values: 337.3 345.9 355.0 355.9 359.2 5463.7 5569.4 5580.4 5599.3 5608.2 5089.1 5235.5 5247.5 5336.0 5408.3 4220.8 4285.1 4288.0 4301.4 4304.7 4206.6 4222.3 4225.2 4232.3 4268.5 4159.2 4282.4 4304.3 4307.4 4319.8 4254.6 4271.3 4274.1 4290.6 4301.7 3812.4 3819.2 3835.3 3841.6 3843.1 5066.6 5291.9 5307.4 5310.3 5317.8 4816.2 4825.8 4827.7 4837.8 4842.6 5646.0 5650.1 5655.2 5673.2 5691.5 3976.7 4053.5 4085.6 4096.5 4100.2 5820.9 5858.7 5873.4 5880.8 5889.3 4205.5 4217.4 4224.5 4225.6 4225.6 4123.8 4230.6 4253.3 4259.1 4265.6 4812.7 4823.7 4826.0 4834.9 4844.5 5705.0 5707.3 5726.5 5731.5 5736.9 1391.2 1454.5 1463.1 1480.3 1485.8 912.9 934.1 941.8 946.5 946.6 4866.4 4871.0 4872.4 4877.8 4882.9 5102.9 5104.6 5128.0 5129.7 5137.2 4452.5 4830.2 4874.6 4875.2 4898.8 2688.6 2689.4 2734.7 2759.2 2760.1 5193.7 5248.6 5298.7 5303.5 5319.0 4849.6 5142.9 5205.3 5215.5 5234.5 5175.8 5242.5 5280.0 5292.9 5303.2 4991.0 5023.7 5041.8 5105.9 5106.7 4825.6 4961.8 5027.5 5052.3 5059.7 5169.3 5178.2 5210.3 5213.7 5232.6 3765.6 3794.7 3796.3 3814.4 3824.3 5578.6 5722.5 5736.6 5778.0 5855.5 5671.1 5674.3 5697.3 5702.3 5713.7 5529.4 5646.2 5691.0 5713.8 5745.5 5653.5 5676.5 5677.5 5689.7 5695.0 5702.3 5704.6 5708.5 5717.9 5747.3 5371.0 5513.9 5542.3 5553.2 5562.8 09:52:52 INFO - PROCESS | 10157 | 09:52:52 INFO - PROCESS | 10157 | [#5] yui.html Cycles:180 Average:23871.80 Median:3727.66 stddev:34844.08 (934.7%) stddev-sans-first:34898.87 09:52:52 INFO - PROCESS | 10157 | Values: 828.6 830.7 840.8 847.3 918.5 16443.3 17637.5 17850.1 17920.6 17937.6 16056.0 16256.2 16350.9 16371.1 16877.9 882.4 894.9 900.4 902.0 902.1 725.4 752.1 861.8 899.5 905.9 941.4 946.3 947.4 951.3 953.2 943.5 943.5 943.8 943.9 944.3 697.8 698.0 698.2 698.6 698.6 15882.8 15903.7 15926.1 15971.7 16048.2 14688.3 14711.6 14780.3 15156.8 16305.3 14465.6 14542.3 14775.6 14795.3 15187.0 4755.2 4771.8 4771.9 4970.8 5009.7 91626.5 92370.5 93073.1 95305.5 95655.0 12181.6 12415.3 12529.2 12743.6 12782.0 23154.5 23193.4 23220.7 23226.1 23252.4 8639.2 8647.7 8696.0 8746.0 8985.9 5162.5 5271.8 5331.1 5334.0 5501.3 3619.2 3723.1 3726.3 3729.0 3730.7 623.8 623.8 630.8 645.1 649.5 599.3 613.6 697.4 713.1 795.6 9300.4 10252.9 10303.1 10376.3 10608.0 706.5 713.6 737.3 747.5 762.4 4865.4 4878.4 5006.3 5037.9 5073.4 128829.0 129277.0 129811.4 130496.3 132735.0 111786.0 112516.4 113596.4 114019.4 116535.4 99650.5 100243.5 101173.5 102960.0 105471.5 9332.0 9554.2 9641.8 9676.0 9677.4 61445.0 61599.0 61864.0 62639.4 63403.7 78747.0 80951.6 86299.0 88261.0 89255.0 12668.0 13846.5 13901.3 13931.4 15072.1 16670.4 16793.3 16865.6 18156.4 18214.6 14596.3 15338.3 15596.9 16107.7 16689.4 15391.2 15407.6 15480.0 15851.6 15859.3 14833.3 15172.3 15193.1 15293.5 15701.2 11878.3 12187.0 12420.9 12754.7 13578.5 12451.8 12621.8 12676.3 12765.0 13011.4 09:52:52 INFO - PROCESS | 10157 | -------- Summary: end -------- 09:52:52 INFO - PROCESS | 10157 | 09:52:53 INFO - TEST-INFO | 10157: exit 0 09:52:53 INFO - TEST-OK | dromaeo_css | took 1113449ms 09:52:53 INFO - TEST-START | kraken 09:52:53 INFO - operating with platform_type : linux_ 09:52:53 INFO - Initialising browser for kraken test... 09:52:53 INFO - TEST-INFO | started process 10465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYYKlvQ/profile http://localhost:36604/getInfo.html) 09:52:57 INFO - PROCESS | 10465 | __metrics Screen width/height:1600/1200 09:52:57 INFO - PROCESS | 10465 | colorDepth:24 09:52:57 INFO - PROCESS | 10465 | Browser inner width/height: 1024/697 09:52:57 INFO - PROCESS | 10465 | __metrics 09:52:57 INFO - PROCESS | 10465 | 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" 09:52:57 INFO - PROCESS | 10465 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:52:58 INFO - PROCESS | 10465 | console.error: PushService: 09:52:58 INFO - PROCESS | 10465 | stateChangeProcessEnqueue: Error transitioning state 09:52:58 INFO - PROCESS | 10465 | UnknownError 09:52:58 INFO - TEST-INFO | 10465: exit 0 09:52:58 INFO - Browser initialized. 09:52:58 INFO - Running cycle 1/1 for kraken test... 09:52:58 INFO - TEST-INFO | started process 10556 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYYKlvQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:10 INFO - PROCESS | 10556 | RSS: Main: 142270464 09:53:10 INFO - PROCESS | 10556 | 09:53:12 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36604/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:13 INFO - PROCESS | 10556 | RSS: Main: 185856000 09:53:13 INFO - PROCESS | 10556 | 09:53:16 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36604/tests/kraken/driver.html?testName=audio-dft) 09:53:16 INFO - PROCESS | 10556 | RSS: Main: 173969408 09:53:16 INFO - PROCESS | 10556 | 09:53:19 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36604/tests/kraken/driver.html?testName=audio-fft) 09:53:20 INFO - PROCESS | 10556 | RSS: Main: 265707520 09:53:20 INFO - PROCESS | 10556 | 09:53:22 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36604/tests/kraken/driver.html?testName=audio-oscillator) 09:53:23 INFO - PROCESS | 10556 | RSS: Main: 171397120 09:53:23 INFO - PROCESS | 10556 | 09:53:25 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36604/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:25 INFO - PROCESS | 10556 | RSS: Main: 172957696 09:53:25 INFO - PROCESS | 10556 | 09:53:28 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36604/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:29 INFO - PROCESS | 10556 | RSS: Main: 209203200 09:53:29 INFO - PROCESS | 10556 | 09:53:32 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36604/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:33 INFO - PROCESS | 10556 | RSS: Main: 208531456 09:53:33 INFO - PROCESS | 10556 | 09:53:36 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36604/tests/kraken/driver.html?testName=json-parse-financial) 09:53:36 INFO - PROCESS | 10556 | RSS: Main: 207912960 09:53:36 INFO - PROCESS | 10556 | 09:53:38 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36604/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:38 INFO - PROCESS | 10556 | RSS: Main: 170561536 09:53:38 INFO - PROCESS | 10556 | 09:53:40 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:41 INFO - PROCESS | 10556 | RSS: Main: 178159616 09:53:41 INFO - PROCESS | 10556 | 09:53:42 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:43 INFO - PROCESS | 10556 | RSS: Main: 187031552 09:53:43 INFO - PROCESS | 10556 | 09:53:46 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:46 INFO - PROCESS | 10556 | RSS: Main: 195330048 09:53:46 INFO - PROCESS | 10556 | 09:53:49 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:50 INFO - PROCESS | 10556 | RSS: Main: 191537152 09:53:50 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | Cycle 1(1): loaded http://localhost:36604/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36604/tests/kraken/driver.html?testName=ai-astar) 09:53:51 INFO - PROCESS | 10556 | RSS: Main: 243396608 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | __start_tp_report 09:53:51 INFO - PROCESS | 10556 | _x_x_mozilla_page_load 09:53:51 INFO - PROCESS | 10556 | _x_x_mozilla_page_load_details 09:53:51 INFO - PROCESS | 10556 | |i|pagename|runs| 09:53:51 INFO - PROCESS | 10556 | |0;ai-astar;113;111;111;111;112;113;110;113;112;112 09:53:51 INFO - PROCESS | 10556 | |1;audio-beat-detection;144;118;115;166;121;117;117;118;152;115 09:53:51 INFO - PROCESS | 10556 | |2;audio-dft;171;162;161;160;160;161;161;160;162;160 09:53:51 INFO - PROCESS | 10556 | |3;audio-fft;74;75;76;108;74;76;75;75;98;75 09:53:51 INFO - PROCESS | 10556 | |4;audio-oscillator;82;83;126;82;82;82;114;81;82;100 09:53:51 INFO - PROCESS | 10556 | |5;imaging-gaussian-blur;116;116;117;117;117;116;116;116;117;117 09:53:51 INFO - PROCESS | 10556 | |6;imaging-darkroom;146;145;146;145;148;147;147;147;147;146 09:53:51 INFO - PROCESS | 10556 | |7;imaging-desaturate;95;95;95;96;96;95;95;95;95;94 09:53:51 INFO - PROCESS | 10556 | |8;json-parse-financial;53;50;50;50;51;50;49;50;49;49 09:53:51 INFO - PROCESS | 10556 | |9;json-stringify-tinderbox;57;60;58;58;58;58;58;58;58;59 09:53:51 INFO - PROCESS | 10556 | |10;stanford-crypto-aes;74;74;74;74;81;73;76;73;73;73 09:53:51 INFO - PROCESS | 10556 | |11;stanford-crypto-ccm;136;126;121;121;120;120;120;120;156;119 09:53:51 INFO - PROCESS | 10556 | |12;stanford-crypto-pbkdf2;158;159;154;162;158;157;153;163;160;156 09:53:51 INFO - PROCESS | 10556 | |13;stanford-crypto-sha256-iterative;63;65;64;63;62;63;62;62;62;60 09:53:51 INFO - PROCESS | 10556 | __end_tp_report 09:53:51 INFO - PROCESS | 10556 | __start_cc_report 09:53:51 INFO - PROCESS | 10556 | _x_x_mozilla_cycle_collect,1574 09:53:51 INFO - PROCESS | 10556 | __end_cc_report 09:53:51 INFO - PROCESS | 10556 | __startTimestamp1481824431921__endTimestamp 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | ------- Summary: start ------- 09:53:51 INFO - PROCESS | 10556 | Number of tests: 14 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:1.03 (0.9%) stddev-sans-first:1.00 09:53:51 INFO - PROCESS | 10556 | Values: 113.0 111.0 111.0 111.0 112.0 113.0 110.0 113.0 112.0 112.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:119.50 stddev:18.57 (15.5%) stddev-sans-first:18.81 09:53:51 INFO - PROCESS | 10556 | Values: 144.0 118.0 115.0 166.0 121.0 117.0 117.0 118.0 152.0 115.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#2] audio-dft Cycles:10 Average:161.80 Median:161.00 stddev:3.33 (2.1%) stddev-sans-first:0.83 09:53:51 INFO - PROCESS | 10556 | Values: 171.0 162.0 161.0 160.0 160.0 161.0 161.0 160.0 162.0 160.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.00 stddev:12.06 (16.1%) stddev-sans-first:12.55 09:53:51 INFO - PROCESS | 10556 | Values: 74.0 75.0 76.0 108.0 74.0 76.0 75.0 75.0 98.0 75.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:82.00 stddev:16.34 (19.9%) stddev-sans-first:16.97 09:53:51 INFO - PROCESS | 10556 | Values: 82.0 83.0 126.0 82.0 82.0 82.0 114.0 81.0 82.0 100.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:53:51 INFO - PROCESS | 10556 | Values: 116.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 09:53:51 INFO - PROCESS | 10556 | Values: 146.0 145.0 146.0 145.0 148.0 147.0 147.0 147.0 147.0 146.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:53:51 INFO - PROCESS | 10556 | Values: 95.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 94.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:1.20 (2.4%) stddev-sans-first:0.67 09:53:51 INFO - PROCESS | 10556 | Values: 53.0 50.0 50.0 50.0 51.0 50.0 49.0 50.0 49.0 49.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.00 stddev:0.79 (1.4%) stddev-sans-first:0.71 09:53:51 INFO - PROCESS | 10556 | Values: 57.0 60.0 58.0 58.0 58.0 58.0 58.0 58.0 58.0 59.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.00 stddev:2.46 (3.3%) stddev-sans-first:2.60 09:53:51 INFO - PROCESS | 10556 | Values: 74.0 74.0 74.0 74.0 81.0 73.0 76.0 73.0 73.0 73.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:121.00 stddev:11.75 (9.7%) stddev-sans-first:11.88 09:53:51 INFO - PROCESS | 10556 | Values: 136.0 126.0 121.0 121.0 120.0 120.0 120.0 120.0 156.0 119.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.00 Median:158.50 stddev:3.20 (2.0%) stddev-sans-first:3.39 09:53:51 INFO - PROCESS | 10556 | Values: 158.0 159.0 154.0 162.0 158.0 157.0 153.0 163.0 160.0 156.0 09:53:51 INFO - PROCESS | 10556 | 09:53:51 INFO - PROCESS | 10556 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:1.35 (2.1%) stddev-sans-first:1.42 09:53:51 INFO - PROCESS | 10556 | Values: 63.0 65.0 64.0 63.0 62.0 63.0 62.0 62.0 62.0 60.0 09:53:51 INFO - PROCESS | 10556 | -------- Summary: end -------- 09:53:51 INFO - PROCESS | 10556 | 09:53:53 INFO - TEST-INFO | 10556: exit 0 09:53:53 INFO - TEST-OK | kraken | took 59843ms 09:53:53 INFO - SUITE-END | took 1173s 09:53:53 INFO - Completed test suite (00:19:33) 09:53:53 INFO - javascript benchmark 09:53:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3119.610048743907, 3160.036188962367, 3168.9049328520136, 3189.8245596492184, 3200.455922493176, 5538.446155384459, 5600.215969764236, 5649.689267090308, 5654.123610840321, 5664.745086471108, 5656.0, 5689.4879460848515, 5691.573132015094, 5694.060480020791, 5696.316442026952, 5132.8460146195675, 5172.791991544865, 5179.844604661847, 5184.662996905208, 5236.764345604438, 5068.771905264253, 5246.422893481733, 5261.158214685651, 5270.947290527103, 5299.046171689094, 5032.144535428967, 5182.4299327073995, 5187.533122019017, 5201.167813149897, 5233.73831308435, 5188.584913206934, 5287.7884884604955, 5292.153255479144, 5294.41761406245, 5297.814576489824, 5115.0025744979375, 5152.0, 5246.344206974129, 5249.1338929076655, 5278.9208161877605, 5480.588955828329, 5500.202470641774, 5510.1459273812625, 5543.334799824007, 5544.722763861791, 5909.379515150933, 5936.436038576371, 5942.316633587149, 6003.699815009232, 6029.125776762345, 6034.396560343975, 6042.184305118797, 6057.151998720183, 6058.636481811105, 6059.7273122709585, 5524.309461317335, 5585.469380408855, 5619.241402410663, 5654.773809047674, 5701.2018317435595, 6231.937680623136, 6260.0, 6278.277998030194, 6278.843028924313, 6301.369863013662, 6015.699215039231, 6025.969870150621, 6038.305594856648, 6039.214902062704, 6071.574989750675, 5801.6519008859605, 5873.559483038789, 5972.910406343908, 6025.548083893726, 6029.3970602940235, 6016.127661489059, 6040.214772079602, 6040.758369665252, 6041.939580604226, 6068.8786224276155, 6015.819525414245, 6059.424354686298, 6076.665783381868, 6078.635281883013, 6100.115483254903, 4549.044700612908, 4579.152856721517, 4605.102005109038, 4648.093621743795, 4676.111538807616, 4100.466939297932, 4108.4453598764385, 4132.152908653718, 4155.58444155588, 4171.8957026074595, 5768.077107662753, 5776.0, 5777.19119323295, 5779.624324418899, 5781.132830075522, 5862.443067908627, 5947.405259474104, 6042.758289668452, 6086.847828804315, 6098.786542470993, 5766.192733017381, 5846.473817356458, 5848.5321174305955, 5878.441548052928, 5880.970595146997, 5320.441353657888, 5323.733813309319, 5330.600204984642, 5331.30693009914, 5333.040052790535, 6087.391260873877, 6113.567524283718, 6309.116723658692, 6354.364563543609, 6369.267534233622, 5949.970250148722, 6041.305249896318, 6051.334353221054, 6091.177691011834, 6130.172426722335, 6244.219472565929, 6282.340354262919, 6282.465990390868, 6284.465820405134, 6286.874262514815, 5803.82588522345, 5969.970150149222, 5979.162917191509, 5983.132445795248, 6024.307204671519, 5896.115582662464, 6018.849528761641, 6054.243219597551, 6076.3012253591405, 6145.40219542931, 5933.139694744152, 5982.700864956648, 5983.850403739942, 5993.70031498432, 6018.067199584071, 5971.820845374646, 5981.7607295707685, 5987.311459182076, 5988.251468566429, 5991.400859913973, 5651.943480565142, 5768.365479797303, 5839.386864379179, 5868.94131058684, 5890.617109887844, 6012.428819262163, 6020.849478762891, 6024.3975602439405, 6033.12519684652, 6034.034554471439, 5686.402927692533, 5849.3858144894175, 5849.94150058494, 5854.414558544111, 5865.090910908816], "value": 5588.983136134752, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.4135176850801, 609.92071030766, 627.1188979483827, 640.9679516024198, 655.1679367203653, 34744.65255347449, 46019.76990115049, 46442.767786161065, 46475.535244647544, 46571.0, 47673.76163119188, 48001.51998480023, 48061.51938480614, 48176.27735583969, 48181.27728084082, 8331.416800823914, 9139.817203655923, 9429.151376376125, 9434.716958491255, 9440.81118377632, 8283.875741863876, 11043.779124417506, 11222.66332010037, 11274.887251127466, 11611.709707257301, 11345.943270283678, 11400.714982125472, 11403.315801051922, 11412.087033037378, 11423.828642570368, 11126.109911207084, 11175.161862860317, 11181.49683264247, 11197.384143872085, 11202.775944481105, 11076.667699969013, 11137.387443690593, 11139.498722557424, 11145.609903653332, 11149.052330551913, 44787.328190077176, 45376.0, 45390.77304613489, 45463.318050229274, 45500.77249613731, 3771.3400154973046, 3861.073342397832, 3871.477350557695, 3905.1994341159993, 3919.608039196086, 34108.0, 34298.82850585731, 34717.47923781145, 35739.106522336886, 35835.28329433423, 4506.459224893001, 4530.161920044769, 4615.284630882219, 4623.398958135421, 4761.59526440253, 350105.0, 352374.0, 353953.0, 354421.0, 356791.2160439207, 59754.40245597575, 59823.0, 60056.0, 60065.0, 60112.39887601155, 40490.1901961965, 41492.58507414947, 41501.1699766003, 43443.782781085894, 43587.782061090125, 17605.0, 17754.822451775315, 17765.289388424575, 17773.7333939991, 17774.91112544455, 12127.878721212852, 12244.0, 13039.087263891437, 13480.865191348157, 13544.45822167122, 19723.40829775109, 20847.687284690743, 20896.582068358854, 20922.267720629705, 21048.684269735968, 7237.493375463667, 7249.927500725031, 7511.399088072941, 7609.201033891472, 7648.42636802242, 907.228855472856, 1016.4714348538719, 1022.2537547590152, 1071.23406764164, 1108.0415440643947, 34047.31905361879, 34658.30683386318, 35079.0, 35235.11912202215, 35372.469412958824, 1070.4594179939081, 1086.2124959404368, 1119.9034669139835, 1123.2193625430411, 1159.3855256713955, 16534.32605294962, 17218.655626887394, 17306.82693173052, 17395.304187832346, 17657.55856103582, 540601.0, 542576.0, 543385.0, 544838.0, 545690.0, 59371.0, 59819.700901496086, 59957.40042599606, 60042.0, 60097.699511502164, 1257.6855786053486, 1275.591810620611, 1323.5632241360329, 1333.5865881576935, 1361.884239839605, 30468.0, 33931.3213735719, 35036.299274013865, 35550.46674299836, 35702.64297357097, 52615.21077183769, 53297.46702532925, 53366.46633533615, 53428.0, 53432.46567534275, 441741.0, 445872.0, 445993.0, 446685.0, 446708.0, 27378.31554211081, 28136.577951331157, 47294.29058564193, 48146.518534814204, 48408.0, 48458.0, 51605.48394516157, 51686.0, 51881.48118518917, 52357.47642523526, 43321.35017974796, 43331.566684332756, 43817.56182438135, 44221.55778442303, 45398.31902521531, 42694.146117078104, 44026.33960490659, 44056.77971610121, 44064.82348954507, 45269.0, 38720.419193711554, 39266.01834954149, 39779.60220397875, 39797.40303895386, 39869.40195897122, 37353.0, 39208.60791392164, 39421.80289098536, 40050.79974600225, 40843.0, 29748.810047597854, 29897.082164569027, 30068.24829379283, 30084.0, 30207.395852083275], "value": 21342.31378041526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.1325696152636, 414.1406581343713, 415.10544776007697, 420.5794205794207, 442.6171361772068, 18841.62316753664, 19791.41668666505, 19809.40571782849, 19991.900040499815, 20535.0, 19640.901795491, 19757.703634445494, 20468.69296960547, 20598.38204853853, 20666.0, 5849.590528662995, 6138.048602466625, 6146.84632884177, 6147.569670123094, 6150.169727086831, 4281.35779633056, 4506.324051392285, 4562.794674239651, 6066.454019138277, 6071.666058366788, 6766.391024807766, 6836.282190370014, 6872.828179295508, 6883.380495755381, 6884.7934561962975, 6561.540692151551, 6790.626515541643, 6809.250982391933, 6819.522633415663, 6861.313868613149, 5870.823875283749, 6055.091736239555, 6066.575339726221, 6078.635281883101, 6083.209182806207, 23915.16296929599, 24037.639435408484, 24289.27132186037, 24298.0189061242, 24596.631050534255, 2525.1162093267208, 2547.337692200041, 2552.8851201695784, 2619.6856377234853, 3010.4882170031146, 14971.775423368657, 15228.923855380652, 15367.846321536754, 15721.528354149394, 15778.76331855023, 1975.9703604445945, 2013.3054096336741, 2078.293380250722, 2091.466675997626, 2115.048228297257, 61079.0, 65724.2766149245, 83782.0, 84394.0, 84852.0, 11110.61112861046, 11170.888291117148, 11183.161262905314, 11273.492692828842, 11567.88432115685, 15966.920165399099, 15996.600084997968, 15998.280077396545, 16031.038137711774, 16055.518334449986, 8352.749417517483, 8403.15968403167, 8472.237498625153, 8542.530160841214, 8736.519491428033, 8204.138565450661, 8231.506109633441, 8254.54599996994, 8410.0, 8478.23695867375, 6909.7581584644295, 6917.817803810303, 6970.163580370287, 6982.825429364205, 7008.649567521604, 1918.2806447582157, 1984.3947595983363, 1988.6917527782928, 1998.5603167303461, 2037.032409605455, 918.2745630951607, 963.2679163835395, 975.8731364922514, 1062.4634559547533, 1091.6724982505216, 21064.684029739565, 21401.892990534947, 21420.464488387915, 21946.341609751733, 21971.0, 1021.5454122915231, 1030.438411065956, 1061.2253055269541, 1066.632011955874, 1082.9928166804948, 11633.825492617618, 11922.284662920252, 12097.758044839054, 12146.210496317712, 12267.202631828903, 171674.0, 171909.0, 172222.0, 395850.0207498944, 515142.0, 82272.58863705643, 82809.58595206986, 82829.17170828335, 82874.0, 83098.58450707707, 1034.234666346913, 1054.056619325699, 1081.216118314216, 1083.4420273559124, 1090.4384242115316, 17718.46844594664, 17939.192736326637, 17987.460376188734, 18305.17626706775, 18745.156467958703, 41577.0, 41924.790376047924, 41944.161116776886, 41959.370609441496, 42562.57437425586, 54492.727536362065, 54666.72666636641, 55005.174922375394, 56342.71828640831, 56441.71779141078, 9218.492982885737, 9293.907060929305, 9364.063593640582, 9395.15443610051, 9491.0, 12881.742365152457, 13785.103968242032, 14008.089474184146, 14257.287135643384, 14770.48303309379, 9309.394889332172, 11242.325460472397, 11557.826632600685, 11757.764844702888, 12063.81904271419, 11755.88244117548, 12334.75330493403, 12396.442160102637, 12682.556110536087, 12686.555970540987, 11634.36011019402, 11640.53437862476, 11702.765944681229, 12088.274703517816, 12446.128770986306, 10900.330628491576, 11648.184627076023, 12014.399280035788, 12825.422855971317, 13380.799288010883, 11632.418379080842, 12146.210496317712, 12362.567310144103, 12435.440405181607, 12868.742625147257], "value": 9729.72099882812, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.3150608942483, 1516.0524672079953, 1542.7917231173792, 1688.738245571937, 1822.2073398071839, 7864.095629002659, 8752.91247087529, 8973.910260897397, 9000.189982901538, 9262.536873156332, 9196.49419281941, 9259.583318750656, 9276.118768716979, 9295.39579927306, 9297.628094876214, 2485.2916918678134, 2503.0, 2506.4987002599555, 2514.9371265718414, 2515.7735803777655, 2432.8783560821976, 2517.609770485578, 2522.331582130737, 2523.3817714659936, 2525.8863351149153, 2435.2694191742494, 2448.5592593333195, 2514.0572285392977, 2519.937001574957, 2522.6216067589826, 2440.670509481224, 2450.2281781238944, 2461.4584791345874, 2483.2674361063528, 2499.350168956066, 2405.615101583756, 2422.5033868057008, 2437.524682686877, 2437.9878100609562, 2442.68245128134, 9140.0, 9316.347855650107, 9325.673601423985, 9339.813203735886, 9358.204552613037, 5429.945700542983, 5619.887602247973, 5632.690202038886, 5691.402402747734, 5696.829095127153, 3481.3733527964955, 3485.7734247274097, 3528.153243221634, 3545.7872527648424, 3576.445650924111, 1203.8856308650663, 1204.6205445284706, 1217.6347095871204, 1238.876112388763, 2143.164165975271, 12505.812412813724, 12768.361581920866, 12802.551910683174, 12839.935800321033, 12852.550160744422, 4699.943859021538, 4804.783784729696, 4832.95167048332, 4906.632002599819, 4919.458859525449, 10191.082802547806, 10200.030997055268, 10201.693949181536, 10213.182945364353, 10218.897811021943, 5569.721513924369, 5585.273914391184, 5589.5807814413265, 5597.0, 5608.635438696471, 4258.467691538558, 4395.934060989088, 4952.554270115635, 5102.795888164432, 5218.947810521922, 4042.7776472293717, 4070.5115386153852, 4132.793360331972, 4134.193832202752, 4164.06308580573, 1185.8102703567388, 1187.9406029698653, 1219.2867172704068, 1225.3383173086434, 1229.8954588859867, 891.8127193289482, 1020.7499162705036, 1033.8862725100232, 1035.5081336365167, 1036.7667274863247, 6000.729967151488, 6216.906746398808, 6333.68331583419, 6356.745730170742, 6417.326180751047, 499.92001279795056, 504.56607317706744, 511.86179731472293, 520.274217466632, 524.111630785822, 4032.0810805418414, 4392.714473559208, 4399.906130009959, 4444.933326000112, 4504.729716216972, 11207.719807004727, 11520.481578328996, 11579.536818527165, 11613.593524226611, 11640.476178571984, 11168.720781980353, 11343.0, 11480.024197943101, 11497.885021149681, 11501.942490287662, 8656.480611163224, 8742.25690816275, 8747.343949203836, 8752.8687069694, 8754.299656027375, 6053.667048312209, 6160.907586386297, 6268.655223962726, 6366.4588509977175, 6399.648019358794, 10495.632652857113, 10655.30740501865, 10694.358338499715, 10804.135669146292, 10868.78262434731, 10540.156787456832, 10612.310199837295, 10724.249302549058, 10755.247132700635, 10965.890341096487, 7268.818279543054, 7497.437692173, 7903.328217101611, 7972.521648701097, 8134.308583770209, 8900.06549312313, 9197.126273004054, 9208.171264586219, 9217.078292170729, 9219.262459003397, 7764.22357764219, 7869.3311068559815, 7869.724559640385, 8111.472754270953, 8240.83518329618, 7894.526328420313, 8022.318102961313, 8047.0745864226365, 8108.189181081844, 8117.431779775359, 6940.340667636567, 7292.927070729437, 7379.0, 7384.298491643285, 7389.150247721581, 5353.304070470814, 5388.838334849961, 5442.945570544244, 5459.563234941115, 5461.098918678374, 5318.867028324323, 5326.334208223972, 5336.9466305338, 5361.061814182424, 5421.783128674809], "value": 4780.149332426104, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.3489165909793, 345.9066052165915, 355.0431586873377, 355.89672016748085, 359.1900264902644, 5463.672179669223, 5569.359523654781, 5580.358258800239, 5599.300087489064, 5608.242887210231, 5089.109405853979, 5235.450277720842, 5247.501487358695, 5335.973320133404, 5408.323959505062, 4220.788960551976, 4285.100128972919, 4287.978560107211, 4301.440812694345, 4304.720193187449, 4206.621404073633, 4222.282212023948, 4225.197212529625, 4232.280512312899, 4268.530461649216, 4159.209750147463, 4282.400463935051, 4304.268274393345, 4307.375430562566, 4319.848805291816, 4254.595813397722, 4271.31658934569, 4274.1451709658195, 4290.648445739582, 4301.720388174775, 3812.370958791824, 3819.1788765415567, 3835.290471262825, 3841.5582208046153, 3843.13529455873, 5066.594672426197, 5291.862249616351, 5307.3896501902, 5310.336207974004, 5317.840464786024, 4816.157172494834, 4825.7587120644175, 4827.662063655545, 4837.758112094381, 4842.6368022398465, 5645.955498232799, 5650.0959846424785, 5655.236543066674, 5673.205751194836, 5691.51622112121, 3976.701747368959, 4053.5338436079933, 4085.55058943516, 4096.467459230311, 4100.220958017967, 5820.885826083026, 5858.707064646751, 5873.353931067579, 5880.8235752927485, 5889.322727886305, 4205.486025030987, 4217.40956266123, 4224.486430573804, 4225.556316586714, 4225.640820530228, 4123.793810309532, 4230.64039556635, 4253.340732186516, 4259.148170365939, 4265.573442655772, 4812.700570845922, 4823.734694591761, 4826.0, 4834.854954351375, 4844.491328410537, 5705.001624715658, 5707.258056452634, 5726.5418766499415, 5731.475142787156, 5736.881308144872, 1391.200059965524, 1454.4836583012877, 1463.1367493179075, 1480.3042569992301, 1485.7919891215238, 912.89083763228, 934.131257930118, 941.75985123793, 946.5267366316841, 946.5835032585641, 4866.416030076342, 4871.0, 4872.439669437989, 4877.804887804448, 4882.877928051827, 5102.897942041139, 5104.566111880457, 5128.0, 5129.692218466904, 5137.174553995288, 4452.532484089189, 4830.227163653869, 4874.612848436364, 4875.24433712775, 4898.828541001048, 2688.637034000424, 2689.367998520346, 2734.726527347289, 2759.199832048683, 2760.144355249879, 5193.714345710947, 5248.553872920845, 5298.70857102863, 5303.522682958553, 5319.0, 4849.563539281482, 5142.922856157161, 5205.297284866495, 5215.478452154754, 5234.476552344735, 5175.767090480937, 5242.475752424803, 5279.967212623091, 5292.914952434741, 5303.231031500488, 4991.0, 5023.723695196799, 5041.773120209526, 5105.923411148761, 5106.66806657566, 4825.648818330873, 4961.82633607822, 5027.522385373383, 5052.267421223975, 5059.671121377099, 5169.276301317894, 5178.197379406198, 5210.270562121382, 5213.739313034257, 5232.555232805102, 3765.585785563531, 3794.6774524165753, 3796.3166630006863, 3814.351560234822, 3824.3307421201735, 5578.633234857486, 5722.513586345207, 5736.5984381094595, 5777.988851950976, 5855.506845754306, 5671.149327600893, 5674.2623458950075, 5697.259356283656, 5702.315722113207, 5713.685747283939, 5529.364123125892, 5646.237757902631, 5691.0, 5713.8285851424525, 5745.529422348431, 5653.547716182778, 5676.545876329801, 5677.51741102011, 5689.687067211177, 5695.0, 5702.315722113373, 5704.629199102211, 5708.514776244066, 5717.8570535736935, 5747.252857128547, 5371.039570556797, 5513.917291240555, 5542.3349198094875, 5553.222548843246, 5562.805301814579], "value": 4179.399720363848, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.6064119543217, 830.7383174300095, 840.7729912923511, 847.2628812932747, 918.4626993208973, 16443.342266309348, 17637.47087587371, 17850.10749462525, 17920.641587168247, 17937.551561210978, 16056.0, 16256.18719064045, 16350.918245408786, 16371.099589522571, 16877.915610421962, 882.386741214856, 894.8702438146465, 900.3607438718518, 901.9729408117767, 902.1069141549851, 725.4450345485708, 752.1275320628073, 861.750092473182, 899.5322432335182, 905.9365844390895, 941.4069136444031, 946.2808265718035, 947.4220725357526, 951.2770294576103, 953.2326477185898, 943.4527973775193, 943.481085403029, 943.8065196634674, 943.8914524829664, 944.2587568758526, 697.8150790040643, 698.0367093411111, 698.2110215456553, 698.6087790837147, 698.6367089113633, 15882.76175857363, 15903.727701783946, 15926.124063176521, 15971.680566388608, 16048.197590120564, 14688.339024743913, 14711.63220919475, 14780.334884930204, 15156.848431515655, 16305.266263018075, 14465.566033019026, 14542.345594448276, 14775.630609234748, 14795.334209960578, 15187.0, 4755.239161734106, 4771.785269662873, 4771.928421073687, 4970.751462426864, 5009.7495125243595, 91626.54186729023, 92370.5381473095, 93073.06926930712, 95305.52347238289, 95655.0, 12181.573644922562, 12415.255084694947, 12529.248245105322, 12743.553975610808, 12782.0, 23154.536909261722, 23193.420164496023, 23220.65169022466, 23226.070957161523, 23252.442231354536, 8639.222469977733, 8647.654093836176, 8695.956520217485, 8745.956270218609, 8985.865212021825, 5162.483751624883, 5271.78912843482, 5331.1470164773455, 5334.039872822852, 5501.312335958005, 3619.1856832212543, 3723.1250656095795, 3726.2920045191327, 3729.030452082478, 3730.720195985312, 623.7847942459844, 623.8066199478176, 630.8233694565529, 645.122633218822, 649.5388274325283, 599.3437186281016, 613.6287546034674, 697.4281089506555, 713.1299814226635, 795.5942469340678, 9300.395474294284, 10252.948735256276, 10303.072723454925, 10376.325538839952, 10608.045275925204, 706.5336877660719, 713.5968177979529, 737.2885165815014, 747.5066456138923, 762.4205603741196, 4865.416150062015, 4878.438979517401, 5006.324146240284, 5037.924431133536, 5073.365829271341, 128829.03060437276, 129277.0, 129811.35094324467, 130496.3475182637, 132735.0, 111786.0, 112516.43741781403, 113596.43201783938, 114019.42990285161, 116535.41732291284, 99650.50174749225, 100243.49878250562, 101173.49413252887, 102960.0, 105471.47264263783, 9332.0, 9554.187894029086, 9641.807163856824, 9675.951620242135, 9677.419354838732, 61445.0, 61599.0, 61864.0, 62639.373606263354, 63403.682981584796, 78747.0, 80951.59524202341, 86299.0, 88261.0, 89255.0, 12668.0, 13846.515371961934, 13901.30493475302, 13931.442742290195, 15072.095674259579, 16670.416535421686, 16793.32826686919, 16865.578360541083, 18156.36452724201, 18214.635707285513, 14596.343164557407, 15338.309776059881, 15596.92201538985, 16107.67784644324, 16689.415870444474, 15391.1534865579, 15407.61480962985, 15480.0, 15851.603709907344, 15859.286332114852, 14833.332500037308, 15172.317245723749, 15193.088414695154, 15293.464728734441, 15701.214939252763, 11878.287302761863, 12187.0, 12420.875791241971, 12754.744905102032, 13578.524751633646, 12451.75096498083, 12621.81067283973, 12676.302803345905, 12765.0, 13011.414486347947], "value": 8367.410094247962, "unit": "score"}], "name": "dromaeo_css", "value": 7608.715262021803, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 111.0, 111.0, 112.0, 113.0, 110.0, 113.0, 112.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 115.0, 166.0, 121.0, 117.0, 117.0, 118.0, 152.0, 115.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 162.0, 161.0, 160.0, 160.0, 161.0, 161.0, 160.0, 162.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 108.0, 74.0, 76.0, 75.0, 75.0, 98.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 126.0, 82.0, 82.0, 82.0, 114.0, 81.0, 82.0, 100.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 148.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 50.0, 50.0, 51.0, 50.0, 49.0, 50.0, 49.0, 49.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 59.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 74.0, 81.0, 73.0, 76.0, 73.0, 73.0, 73.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 126.0, 121.0, 121.0, 120.0, 120.0, 120.0, 120.0, 156.0, 119.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 154.0, 162.0, 158.0, 157.0, 153.0, 163.0, 160.0, 156.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 63.0, 62.0, 63.0, 62.0, 62.0, 62.0, 60.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1461.2, "alertThreshold": 2.0}]} 09:53:53 INFO - Return code: 0 09:53:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:53 INFO - # TBPL SUCCESS # 09:53:53 INFO - Running post-action listener: _resource_record_post_action 09:53:53 INFO - [mozharness: 2016-12-15 17:53:53.726459Z] Finished run-tests step (success) 09:53:53 INFO - Running post-run listener: _resource_record_post_run 09:53:53 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 325656576; Read time: 36; Write time: 309544 09:53:53 INFO - TinderboxPrint: CPU usage
13.0% 09:53:53 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 36 09:53:53 INFO - TinderboxPrint: I/O write bytes / time
325,656,576 / 309,544 09:53:53 INFO - TinderboxPrint: CPU idle
8,241.6 (87.0%) 09:53:53 INFO - TinderboxPrint: CPU user
1,188.4 (12.5%) 09:53:53 INFO - TinderboxPrint: Swap in / out
0 / 0 09:53:53 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 106745856; Read time: 0; Write time: 155976 09:53:53 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218910720; Read time: 12; Write time: 153568 09:53:54 INFO - Running post-run listener: _upload_blobber_files 09:53:54 INFO - Blob upload gear active. 09:53:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:54 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 09:53:54 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:53:54 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:53:54 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:55 INFO - (blobuploader) - INFO - Done attempting. 09:53:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:53:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:53:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:57 INFO - (blobuploader) - INFO - Done attempting. 09:53:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:00 INFO - (blobuploader) - INFO - Done attempting. 09:54:00 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:00 INFO - Return code: 0 09:54:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9c1d706720b069315fd786ad20d90752dc8f2d518cde216be3f223afd20e7053bc945bfe0f7fb66454ad41b453cc5723bcccd80e9aa5b1f5ab1543ae451c833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a58c00e70ea0fceda352a38824feb7bb0826241183b9cc125d8576af4749c536e116ec1c87e8c76078917dd739efecbe45c569d51b00b8c64c226918f5f9c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10e7bf9258b838fc705a630e74ba551bb1f8b6ba6547a730df360bd694d3b9ef02687cd9815e7eeda83c937bfd80f9eafbd7c19d4e11f8c71b750f64562c5bf9"} 09:54:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:00 INFO - Contents: 09:54:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9c1d706720b069315fd786ad20d90752dc8f2d518cde216be3f223afd20e7053bc945bfe0f7fb66454ad41b453cc5723bcccd80e9aa5b1f5ab1543ae451c833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a58c00e70ea0fceda352a38824feb7bb0826241183b9cc125d8576af4749c536e116ec1c87e8c76078917dd739efecbe45c569d51b00b8c64c226918f5f9c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10e7bf9258b838fc705a630e74ba551bb1f8b6ba6547a730df360bd694d3b9ef02687cd9815e7eeda83c937bfd80f9eafbd7c19d4e11f8c71b750f64562c5bf9"} 09:54:00 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:00 INFO - Copying logs to upload dir... 09:54:00 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.763117 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-12-15 09:54:00.267950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 09:54:00.271481) ========= 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=17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9c1d706720b069315fd786ad20d90752dc8f2d518cde216be3f223afd20e7053bc945bfe0f7fb66454ad41b453cc5723bcccd80e9aa5b1f5ab1543ae451c833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a58c00e70ea0fceda352a38824feb7bb0826241183b9cc125d8576af4749c536e116ec1c87e8c76078917dd739efecbe45c569d51b00b8c64c226918f5f9c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10e7bf9258b838fc705a630e74ba551bb1f8b6ba6547a730df360bd694d3b9ef02687cd9815e7eeda83c937bfd80f9eafbd7c19d4e11f8c71b750f64562c5bf9"} build_url:https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011727 build_url: 'https://queue.taskcluster.net/v1/task/HxE0FPsAQEO8o-lAssWBCg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a9c1d706720b069315fd786ad20d90752dc8f2d518cde216be3f223afd20e7053bc945bfe0f7fb66454ad41b453cc5723bcccd80e9aa5b1f5ab1543ae451c833", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a58c00e70ea0fceda352a38824feb7bb0826241183b9cc125d8576af4749c536e116ec1c87e8c76078917dd739efecbe45c569d51b00b8c64c226918f5f9c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10e7bf9258b838fc705a630e74ba551bb1f8b6ba6547a730df360bd694d3b9ef02687cd9815e7eeda83c937bfd80f9eafbd7c19d4e11f8c71b750f64562c5bf9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 09:54:00.299032) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:54:00.299602) ========= 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=17051fcd5208b27b50bedfeb000001dd-1481823208.75340-1879226937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004367 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 09:54:00.383689) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 09:54:00.384004) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 09:54:00.384294) ========= ========= Total master_lag: 0.18 =========