builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1483031520.92 results: success (0) buildid: 20161229014137 builduid: 6691e6e4d9e64dbd97d7a90648586461 revision: d843dc104a2e124539e43aeff6fa8a89aa1cc430 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:00.919471) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:00.919949) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:00.920257) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:00.935136) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:00.935462) ========= 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=cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 09:12:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2016-12-29 09:12:01 (114 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137820 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.187234) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.187963) ========= 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=cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020345 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.224070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.224753) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d843dc104a2e124539e43aeff6fa8a89aa1cc430 --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 d843dc104a2e124539e43aeff6fa8a89aa1cc430 --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=cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 09:12:02,003 truncating revision to first 12 chars 2016-12-29 09:12:02,003 Setting DEBUG logging. 2016-12-29 09:12:02,003 attempt 1/10 2016-12-29 09:12:02,003 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d843dc104a2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 09:12:02,327 unpacking tar archive at: mozilla-beta-d843dc104a2e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.882395) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.883181) ========= script_repo_revision: d843dc104a2e124539e43aeff6fa8a89aa1cc430 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.884194) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.884871) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 09:12:01.896408) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-12-29 09:12:01.896727) ========= /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=cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:12:02 INFO - MultiFileLogger online at 20161229 09:12:02 in /builds/slave/test 09:12:02 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:12:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:12:02 INFO - {'append_to_log': False, 09:12:02 INFO - 'base_work_dir': '/builds/slave/test', 09:12:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:12:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:12:02 INFO - 'branch': 'Mozilla-Beta', 09:12:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:12:02 INFO - 'config_files': ('talos/linux_config.py',), 09:12:02 INFO - 'default_actions': ('clobber', 09:12:02 INFO - 'read-buildbot-config', 09:12:02 INFO - 'download-and-extract', 09:12:02 INFO - 'populate-webroot', 09:12:02 INFO - 'create-virtualenv', 09:12:02 INFO - 'install', 09:12:02 INFO - 'run-tests'), 09:12:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:12:02 INFO - 'download_minidump_stackwalk': True, 09:12:02 INFO - 'download_symbols': 'ondemand', 09:12:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:12:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:12:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:12:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:12:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:12:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:12:02 INFO - 'installer_path': 'installer.exe', 09:12:02 INFO - 'log_level': 'info', 09:12:02 INFO - 'log_name': 'talos', 09:12:02 INFO - 'log_to_console': True, 09:12:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:12:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:12:02 INFO - 'opt_config_files': (), 09:12:02 INFO - 'pip_index': False, 09:12:02 INFO - 'sps_profile': False, 09:12:02 INFO - 'sps_profile_interval': 0, 09:12:02 INFO - 'suite': 'dromaeojs', 09:12:02 INFO - 'system_bits': '32', 09:12:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:12:02 INFO - 'title': 'talos-linux64-ix-054', 09:12:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:12:02 INFO - 'use_talos_json': True, 09:12:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:12:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:12:02 INFO - 'work_dir': 'build'} 09:12:02 INFO - [mozharness: 2016-12-29 17:12:02.774145Z] Running clobber step. 09:12:02 INFO - Running pre-action listener: _resource_record_pre_action 09:12:02 INFO - Running main action method: clobber 09:12:02 INFO - rmtree: /builds/slave/test/build 09:12:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:12:03 INFO - Running post-action listener: _resource_record_post_action 09:12:03 INFO - [mozharness: 2016-12-29 17:12:03.761424Z] Finished clobber step (success) 09:12:03 INFO - [mozharness: 2016-12-29 17:12:03.761523Z] Running read-buildbot-config step. 09:12:03 INFO - Running pre-action listener: _resource_record_pre_action 09:12:03 INFO - Running main action method: read_buildbot_config 09:12:03 INFO - Using buildbot properties: 09:12:03 INFO - { 09:12:03 INFO - "project": "", 09:12:03 INFO - "product": "firefox", 09:12:03 INFO - "script_repo_revision": "production", 09:12:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:12:03 INFO - "repository": "", 09:12:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:12:03 INFO - "buildid": "20161229014137", 09:12:03 INFO - "pgo_build": "True", 09:12:03 INFO - "basedir": "/builds/slave/test", 09:12:03 INFO - "buildnumber": 66, 09:12:03 INFO - "slavename": "talos-linux64-ix-054", 09:12:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:12:03 INFO - "platform": "ubuntu64_hw", 09:12:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:12:03 INFO - "branch": "mozilla-beta", 09:12:03 INFO - "repo_path": "releases/mozilla-beta", 09:12:03 INFO - "revision": "d843dc104a2e124539e43aeff6fa8a89aa1cc430", 09:12:03 INFO - "stage_platform": "linux64-pgo", 09:12:03 INFO - "builduid": "6691e6e4d9e64dbd97d7a90648586461", 09:12:03 INFO - "slavebuilddir": "test" 09:12:03 INFO - } 09:12:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 09:12:03 INFO - Running post-action listener: _resource_record_post_action 09:12:03 INFO - [mozharness: 2016-12-29 17:12:03.763967Z] Finished read-buildbot-config step (success) 09:12:03 INFO - [mozharness: 2016-12-29 17:12:03.764057Z] Running download-and-extract step. 09:12:03 INFO - Running pre-action listener: _resource_record_pre_action 09:12:03 INFO - Running main action method: download_and_extract 09:12:03 INFO - mkdir: /builds/slave/test/build/tests 09:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:03 INFO - trying https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:12:03 INFO - Downloading https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/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:12:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/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:12:04 INFO - Downloaded 1327 bytes. 09:12:04 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 09:12:04 INFO - Using the following test package requirements: 09:12:04 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 09:12:04 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:12:04 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 09:12:04 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'jsshell-linux-x86_64.zip'], 09:12:04 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:12:04 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 09:12:04 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 09:12:04 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 09:12:04 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:12:04 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 09:12:04 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:12:04 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:12:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 09:12:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:12:04 INFO - Fetch https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 09:12:04 INFO - Expected file size: 21963871 09:12:09 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:12:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 09:12:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:12:09 INFO - Fetch https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 09:12:09 INFO - Expected file size: 21963871 09:12:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 09:12:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:12:14 INFO - Fetch https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 09:12:15 INFO - Expected file size: 14414089 09:12:15 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:12:15 INFO - trying https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:12:15 INFO - Downloading https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/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:12:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/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:12:16 INFO - Downloaded 56929971 bytes. 09:12:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:12:16 INFO - mkdir: /builds/slave/test/properties 09:12:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:16 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:16 INFO - Contents: 09:12:16 INFO - build_url:https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 09:12:17 INFO - Running post-action listener: _resource_record_post_action 09:12:17 INFO - Running post-action listener: set_extra_try_arguments 09:12:17 INFO - [mozharness: 2016-12-29 17:12:17.265710Z] Finished download-and-extract step (success) 09:12:17 INFO - [mozharness: 2016-12-29 17:12:17.266013Z] Running populate-webroot step. 09:12:17 INFO - Running pre-action listener: _resource_record_pre_action 09:12:17 INFO - Running main action method: populate_webroot 09:12:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:17 INFO - '--tppagecycles', 09:12:17 INFO - '7'], 09:12:17 INFO - 'tests': ['tsvgm']}}, 09:12:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['damp', 'tps']}, 09:12:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'tests': ['damp', 'tps']}, 09:12:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['dromaeo_dom']}, 09:12:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['basic_compositor_video']}, 09:12:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:12:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['a11yr', 09:12:17 INFO - 'ts_paint', 09:12:17 INFO - 'tpaint', 09:12:17 INFO - 'sessionrestore', 09:12:17 INFO - 'sessionrestore_no_auto_restore', 09:12:17 INFO - 'tabpaint']}, 09:12:17 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:17 INFO - 'ts_paint', 09:12:17 INFO - 'tpaint', 09:12:17 INFO - 'sessionrestore', 09:12:17 INFO - 'sessionrestore_no_auto_restore', 09:12:17 INFO - 'tabpaint']}, 09:12:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:17 INFO - 'ts_paint', 09:12:17 INFO - 'tpaint', 09:12:17 INFO - 'sessionrestore', 09:12:17 INFO - 'sessionrestore_no_auto_restore', 09:12:17 INFO - 'tabpaint']}, 09:12:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:17 INFO - 'ts_paint', 09:12:17 INFO - 'tpaint', 09:12:17 INFO - 'sessionrestore', 09:12:17 INFO - 'sessionrestore_no_auto_restore', 09:12:17 INFO - 'tabpaint']}, 09:12:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['a11yr', 09:12:17 INFO - 'ts_paint', 09:12:17 INFO - 'tpaint', 09:12:17 INFO - 'sessionrestore', 09:12:17 INFO - 'sessionrestore_no_auto_restore', 09:12:17 INFO - 'tabpaint']}, 09:12:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['a11yr', 09:12:17 INFO - 'ts_paint', 09:12:17 INFO - 'tpaint', 09:12:17 INFO - 'sessionrestore', 09:12:17 INFO - 'sessionrestore_no_auto_restore', 09:12:17 INFO - 'tabpaint']}, 09:12:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['tsvgx', 09:12:17 INFO - 'tsvgr_opacity', 09:12:17 INFO - 'tart', 09:12:17 INFO - 'tscrollx', 09:12:17 INFO - 'cart']}, 09:12:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:17 INFO - 'tsvgr_opacity', 09:12:17 INFO - 'tart', 09:12:17 INFO - 'tscrollx', 09:12:17 INFO - 'cart']}, 09:12:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'talos_options': ['--disable-e10s'], 09:12:17 INFO - 'tests': ['tp5o']}, 09:12:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'tests': ['tp5o']}, 09:12:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'talos_options': ['--disable-e10s', 09:12:17 INFO - '--xperf_path', 09:12:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:17 INFO - 'tests': ['tp5n']}, 09:12:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:17 INFO - 'talos_options': ['--xperf_path', 09:12:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:17 INFO - 'tests': ['tp5n']}}, 09:12:17 INFO - 'talos.zip': {'path': '', 09:12:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:17 INFO - Running post-action listener: _resource_record_post_action 09:12:17 INFO - [mozharness: 2016-12-29 17:12:17.283557Z] Finished populate-webroot step (success) 09:12:17 INFO - [mozharness: 2016-12-29 17:12:17.283656Z] Running create-virtualenv step. 09:12:17 INFO - Running pre-action listener: _resource_record_pre_action 09:12:17 INFO - Running main action method: create_virtualenv 09:12:17 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:17 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:12:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:17 INFO - Using real prefix '/usr' 09:12:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:19 INFO - Installing pip.................done. 09:12:19 INFO - Return code: 0 09:12:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:12:19 INFO - Using env: {'DISPLAY': ':0', 09:12:19 INFO - 'HOME': '/home/cltbld', 09:12:19 INFO - 'LANG': 'en_US.UTF-8', 09:12:19 INFO - 'LANGUAGE': 'en_US:en', 09:12:19 INFO - 'LOGNAME': 'cltbld', 09:12:19 INFO - 'MAIL': '/var/mail/cltbld', 09:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:19 INFO - 'MOZ_NO_REMOTE': '1', 09:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:19 INFO - 'NO_EM_RESTART': '1', 09:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:19 INFO - 'PWD': '/builds/slave/test', 09:12:19 INFO - 'SHELL': '/bin/bash', 09:12:19 INFO - 'SHLVL': '1', 09:12:19 INFO - 'TERM': 'linux', 09:12:19 INFO - 'TMOUT': '86400', 09:12:19 INFO - 'USER': 'cltbld', 09:12:19 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:19 INFO - '_': '/tools/buildbot/bin/python'} 09:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:20 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:12:20 INFO - Cleaning up... 09:12:20 INFO - Return code: 0 09:12:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:12:20 INFO - Using env: {'DISPLAY': ':0', 09:12:20 INFO - 'HOME': '/home/cltbld', 09:12:20 INFO - 'LANG': 'en_US.UTF-8', 09:12:20 INFO - 'LANGUAGE': 'en_US:en', 09:12:20 INFO - 'LOGNAME': 'cltbld', 09:12:20 INFO - 'MAIL': '/var/mail/cltbld', 09:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:20 INFO - 'MOZ_NO_REMOTE': '1', 09:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:20 INFO - 'NO_EM_RESTART': '1', 09:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:20 INFO - 'PWD': '/builds/slave/test', 09:12:20 INFO - 'SHELL': '/bin/bash', 09:12:20 INFO - 'SHLVL': '1', 09:12:20 INFO - 'TERM': 'linux', 09:12:20 INFO - 'TMOUT': '86400', 09:12:20 INFO - 'USER': 'cltbld', 09:12:20 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:20 INFO - '_': '/tools/buildbot/bin/python'} 09:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:20 INFO - Downloading/unpacking psutil>=3.1.1 09:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:22 INFO - Installing collected packages: psutil 09:12:22 INFO - Running setup.py install for psutil 09:12:22 INFO - building 'psutil._psutil_linux' extension 09:12:22 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:12:22 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:12:22 INFO - building 'psutil._psutil_posix' extension 09:12:22 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:12:22 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:12:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:22 INFO - Successfully installed psutil 09:12:22 INFO - Cleaning up... 09:12:22 INFO - Return code: 0 09:12:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:12:22 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:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 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:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:22 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:12:22 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:12:22 INFO - Using env: {'DISPLAY': ':0', 09:12:22 INFO - 'HOME': '/home/cltbld', 09:12:22 INFO - 'LANG': 'en_US.UTF-8', 09:12:22 INFO - 'LANGUAGE': 'en_US:en', 09:12:22 INFO - 'LOGNAME': 'cltbld', 09:12:22 INFO - 'MAIL': '/var/mail/cltbld', 09:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:22 INFO - 'MOZ_NO_REMOTE': '1', 09:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:22 INFO - 'NO_EM_RESTART': '1', 09:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:22 INFO - 'PWD': '/builds/slave/test', 09:12:22 INFO - 'SHELL': '/bin/bash', 09:12:22 INFO - 'SHLVL': '1', 09:12:22 INFO - 'TERM': 'linux', 09:12:22 INFO - 'TMOUT': '86400', 09:12:22 INFO - 'USER': 'cltbld', 09:12:22 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:22 INFO - '_': '/tools/buildbot/bin/python'} 09:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:12:25 INFO - Installing collected packages: mozsystemmonitor 09:12:25 INFO - Running setup.py install for mozsystemmonitor 09:12:25 INFO - Successfully installed mozsystemmonitor 09:12:25 INFO - Cleaning up... 09:12:25 INFO - Return code: 0 09:12:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:12:25 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:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:25 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:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:25 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:25 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:12:25 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:12:25 INFO - Using env: {'DISPLAY': ':0', 09:12:25 INFO - 'HOME': '/home/cltbld', 09:12:25 INFO - 'LANG': 'en_US.UTF-8', 09:12:25 INFO - 'LANGUAGE': 'en_US:en', 09:12:25 INFO - 'LOGNAME': 'cltbld', 09:12:25 INFO - 'MAIL': '/var/mail/cltbld', 09:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:25 INFO - 'MOZ_NO_REMOTE': '1', 09:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:25 INFO - 'NO_EM_RESTART': '1', 09:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:25 INFO - 'PWD': '/builds/slave/test', 09:12:25 INFO - 'SHELL': '/bin/bash', 09:12:25 INFO - 'SHLVL': '1', 09:12:25 INFO - 'TERM': 'linux', 09:12:25 INFO - 'TMOUT': '86400', 09:12:25 INFO - 'USER': 'cltbld', 09:12:25 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:25 INFO - '_': '/tools/buildbot/bin/python'} 09:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:12:25 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:12:25 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:12:25 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:12:25 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:12:25 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:12:25 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:12:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:12:27 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:12:27 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:12:27 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:12:27 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:12:27 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:12:27 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:12:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:27 INFO - warning: no files found matching '*.txt' 09:12:27 INFO - no previously-included directories found matching 'build' 09:12:27 INFO - no previously-included directories found matching 'dist' 09:12:27 INFO - no previously-included directories found matching '.git*' 09:12:27 INFO - Installing collected packages: jsonschema, functools32 09:12:27 INFO - Running setup.py install for functools32 09:12:27 INFO - warning: no files found matching '*.txt' 09:12:27 INFO - no previously-included directories found matching 'build' 09:12:27 INFO - no previously-included directories found matching 'dist' 09:12:27 INFO - no previously-included directories found matching '.git*' 09:12:28 INFO - Successfully installed jsonschema functools32 09:12:28 INFO - Cleaning up... 09:12:28 INFO - Return code: 0 09:12:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:12:28 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:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:28 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:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:28 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:28 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:12:28 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:12:28 INFO - Using env: {'DISPLAY': ':0', 09:12:28 INFO - 'HOME': '/home/cltbld', 09:12:28 INFO - 'LANG': 'en_US.UTF-8', 09:12:28 INFO - 'LANGUAGE': 'en_US:en', 09:12:28 INFO - 'LOGNAME': 'cltbld', 09:12:28 INFO - 'MAIL': '/var/mail/cltbld', 09:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:28 INFO - 'MOZ_NO_REMOTE': '1', 09:12:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:28 INFO - 'NO_EM_RESTART': '1', 09:12:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:28 INFO - 'PWD': '/builds/slave/test', 09:12:28 INFO - 'SHELL': '/bin/bash', 09:12:28 INFO - 'SHLVL': '1', 09:12:28 INFO - 'TERM': 'linux', 09:12:28 INFO - 'TMOUT': '86400', 09:12:28 INFO - 'USER': 'cltbld', 09:12:28 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:28 INFO - '_': '/tools/buildbot/bin/python'} 09:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:12:28 INFO - Cleaning up... 09:12:28 INFO - Return code: 0 09:12:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12:28 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:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:28 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:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:28 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:28 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:12:28 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:12:28 INFO - Using env: {'DISPLAY': ':0', 09:12:28 INFO - 'HOME': '/home/cltbld', 09:12:28 INFO - 'LANG': 'en_US.UTF-8', 09:12:28 INFO - 'LANGUAGE': 'en_US:en', 09:12:28 INFO - 'LOGNAME': 'cltbld', 09:12:28 INFO - 'MAIL': '/var/mail/cltbld', 09:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:28 INFO - 'MOZ_NO_REMOTE': '1', 09:12:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:28 INFO - 'NO_EM_RESTART': '1', 09:12:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:28 INFO - 'PWD': '/builds/slave/test', 09:12:28 INFO - 'SHELL': '/bin/bash', 09:12:28 INFO - 'SHLVL': '1', 09:12:28 INFO - 'TERM': 'linux', 09:12:28 INFO - 'TMOUT': '86400', 09:12:28 INFO - 'USER': 'cltbld', 09:12:28 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:28 INFO - '_': '/tools/buildbot/bin/python'} 09:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:28 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:12:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:30 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:12:30 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:12:30 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:12:30 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:12:30 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:12:30 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:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:12:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:30 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:12:30 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:12:30 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:12:30 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:12:30 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:12:30 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:12:31 INFO - Downloading docopt-0.6.1.tar.gz 09:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:31 INFO - Running setup.py install for blobuploader 09:12:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:12:31 INFO - Running setup.py install for requests 09:12:31 INFO - Running setup.py install for docopt 09:12:31 INFO - Successfully installed blobuploader requests docopt 09:12:31 INFO - Cleaning up... 09:12:31 INFO - Return code: 0 09:12:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:31 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:31 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:12:31 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:12:31 INFO - Using env: {'DISPLAY': ':0', 09:12:31 INFO - 'HOME': '/home/cltbld', 09:12:31 INFO - 'LANG': 'en_US.UTF-8', 09:12:31 INFO - 'LANGUAGE': 'en_US:en', 09:12:31 INFO - 'LOGNAME': 'cltbld', 09:12:31 INFO - 'MAIL': '/var/mail/cltbld', 09:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:31 INFO - 'MOZ_NO_REMOTE': '1', 09:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:31 INFO - 'NO_EM_RESTART': '1', 09:12:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:31 INFO - 'PWD': '/builds/slave/test', 09:12:31 INFO - 'SHELL': '/bin/bash', 09:12:31 INFO - 'SHLVL': '1', 09:12:31 INFO - 'TERM': 'linux', 09:12:31 INFO - 'TMOUT': '86400', 09:12:31 INFO - 'USER': 'cltbld', 09:12:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:31 INFO - '_': '/tools/buildbot/bin/python'} 09:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:32 INFO - Running setup.py (path:/tmp/pip-y3dFsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:32 INFO - Running setup.py (path:/tmp/pip-GVgoNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:32 INFO - Running setup.py (path:/tmp/pip-icTER3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:32 INFO - Running setup.py (path:/tmp/pip-xwrEuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:32 INFO - Running setup.py (path:/tmp/pip-TZypOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:32 INFO - Running setup.py (path:/tmp/pip-IKgkF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:32 INFO - Running setup.py (path:/tmp/pip-WnBtLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:32 INFO - Running setup.py (path:/tmp/pip-5q0p_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:32 INFO - Running setup.py (path:/tmp/pip-fAysRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:32 INFO - Running setup.py (path:/tmp/pip-3aTeWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:32 INFO - Running setup.py (path:/tmp/pip-RPcrTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:32 INFO - Running setup.py (path:/tmp/pip-Zp119V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:32 INFO - Running setup.py (path:/tmp/pip-PEeK45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:33 INFO - Running setup.py (path:/tmp/pip-Njq802-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:33 INFO - Running setup.py (path:/tmp/pip-M7mAQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:33 INFO - Running setup.py (path:/tmp/pip-2kuigr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:33 INFO - Running setup.py (path:/tmp/pip-GWdO53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:33 INFO - Running setup.py install for manifestparser 09:12:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:12:33 INFO - Running setup.py install for mozcrash 09:12:33 INFO - Running setup.py install for mozdebug 09:12:33 INFO - Running setup.py install for mozdevice 09:12:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:12:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:12:33 INFO - Running setup.py install for mozfile 09:12:33 INFO - Running setup.py install for mozhttpd 09:12:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:12:33 INFO - Running setup.py install for mozinfo 09:12:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Running setup.py install for mozInstall 09:12:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Running setup.py install for mozleak 09:12:34 INFO - Running setup.py install for mozlog 09:12:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Running setup.py install for moznetwork 09:12:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Running setup.py install for mozprocess 09:12:34 INFO - Running setup.py install for mozprofile 09:12:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Running setup.py install for mozrunner 09:12:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:12:34 INFO - Running setup.py install for mozscreenshot 09:12:34 INFO - Running setup.py install for moztest 09:12:35 INFO - Running setup.py install for mozversion 09:12:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:12:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:35 INFO - Cleaning up... 09:12:35 INFO - Return code: 0 09:12:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:35 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:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:35 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:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:35 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:35 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:12:35 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:12:35 INFO - Using env: {'DISPLAY': ':0', 09:12:35 INFO - 'HOME': '/home/cltbld', 09:12:35 INFO - 'LANG': 'en_US.UTF-8', 09:12:35 INFO - 'LANGUAGE': 'en_US:en', 09:12:35 INFO - 'LOGNAME': 'cltbld', 09:12:35 INFO - 'MAIL': '/var/mail/cltbld', 09:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:35 INFO - 'MOZ_NO_REMOTE': '1', 09:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:35 INFO - 'NO_EM_RESTART': '1', 09:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:35 INFO - 'PWD': '/builds/slave/test', 09:12:35 INFO - 'SHELL': '/bin/bash', 09:12:35 INFO - 'SHLVL': '1', 09:12:35 INFO - 'TERM': 'linux', 09:12:35 INFO - 'TMOUT': '86400', 09:12:35 INFO - 'USER': 'cltbld', 09:12:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:35 INFO - '_': '/tools/buildbot/bin/python'} 09:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:35 INFO - Running setup.py (path:/tmp/pip-S83BhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:35 INFO - Running setup.py (path:/tmp/pip-PVn8zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:35 INFO - Running setup.py (path:/tmp/pip-h3CYj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:35 INFO - Running setup.py (path:/tmp/pip-DdMRuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:35 INFO - Running setup.py (path:/tmp/pip-mIGB3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:35 INFO - Running setup.py (path:/tmp/pip-a6ke9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:35 INFO - Running setup.py (path:/tmp/pip-uO4qVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:35 INFO - Running setup.py (path:/tmp/pip-mpnVFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:35 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:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:35 INFO - Running setup.py (path:/tmp/pip-9tLLda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:36 INFO - Running setup.py (path:/tmp/pip-C5IBZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:36 INFO - Running setup.py (path:/tmp/pip-JtujKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:36 INFO - Running setup.py (path:/tmp/pip-Ul2zrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:36 INFO - Running setup.py (path:/tmp/pip-msXuPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:36 INFO - Running setup.py (path:/tmp/pip-KK5T0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:36 INFO - Running setup.py (path:/tmp/pip-nzo5wK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:36 INFO - Running setup.py (path:/tmp/pip-B3EQ0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:36 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:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:36 INFO - Running setup.py (path:/tmp/pip-wBetLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:36 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:12:36 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:12:36 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:12:36 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:12:36 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:12:36 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:12:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:38 INFO - Downloading blessings-1.6.tar.gz 09:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:38 INFO - Installing collected packages: blessings 09:12:38 INFO - Running setup.py install for blessings 09:12:38 INFO - Successfully installed blessings 09:12:38 INFO - Cleaning up... 09:12:38 INFO - Return code: 0 09:12:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:12:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:39 INFO - Reading from file tmpfile_stdout 09:12:39 INFO - Current package versions: 09:12:39 INFO - argparse == 1.2.1 09:12:39 INFO - blessings == 1.6 09:12:39 INFO - blobuploader == 1.2.4 09:12:39 INFO - docopt == 0.6.1 09:12:39 INFO - functools32 == 3.2.3-2 09:12:39 INFO - jsonschema == 2.5.1 09:12:39 INFO - manifestparser == 1.1 09:12:39 INFO - mozInstall == 1.12 09:12:39 INFO - mozcrash == 0.17 09:12:39 INFO - mozdebug == 0.1 09:12:39 INFO - mozdevice == 0.48 09:12:39 INFO - mozfile == 1.2 09:12:39 INFO - mozhttpd == 0.7 09:12:39 INFO - mozinfo == 0.9 09:12:39 INFO - mozleak == 0.1 09:12:39 INFO - mozlog == 3.4 09:12:39 INFO - moznetwork == 0.27 09:12:39 INFO - mozprocess == 0.23 09:12:39 INFO - mozprofile == 0.28 09:12:39 INFO - mozrunner == 6.12 09:12:39 INFO - mozscreenshot == 0.1 09:12:39 INFO - mozsystemmonitor == 0.3 09:12:39 INFO - moztest == 0.7 09:12:39 INFO - mozversion == 1.4 09:12:39 INFO - psutil == 3.1.1 09:12:39 INFO - requests == 1.2.3 09:12:39 INFO - wsgiref == 0.1.2 09:12:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:39 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:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:39 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:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:39 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:39 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:12:39 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:12:39 INFO - Using env: {'DISPLAY': ':0', 09:12:39 INFO - 'HOME': '/home/cltbld', 09:12:39 INFO - 'LANG': 'en_US.UTF-8', 09:12:39 INFO - 'LANGUAGE': 'en_US:en', 09:12:39 INFO - 'LOGNAME': 'cltbld', 09:12:39 INFO - 'MAIL': '/var/mail/cltbld', 09:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:39 INFO - 'MOZ_NO_REMOTE': '1', 09:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:39 INFO - 'NO_EM_RESTART': '1', 09:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:39 INFO - 'PWD': '/builds/slave/test', 09:12:39 INFO - 'SHELL': '/bin/bash', 09:12:39 INFO - 'SHLVL': '1', 09:12:39 INFO - 'TERM': 'linux', 09:12:39 INFO - 'TMOUT': '86400', 09:12:39 INFO - 'USER': 'cltbld', 09:12:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:39 INFO - '_': '/tools/buildbot/bin/python'} 09:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:39 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:12:39 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:12:39 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:12:39 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:12:39 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:12:39 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:12:39 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:12:39 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:12:39 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:12:39 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:12:39 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:12:39 INFO - Cleaning up... 09:12:39 INFO - Return code: 0 09:12:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:12:39 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:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:39 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:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:39 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 0x7f3344a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d0bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, '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': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', '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:12:39 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:12:39 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:12:39 INFO - Using env: {'DISPLAY': ':0', 09:12:39 INFO - 'HOME': '/home/cltbld', 09:12:39 INFO - 'LANG': 'en_US.UTF-8', 09:12:39 INFO - 'LANGUAGE': 'en_US:en', 09:12:39 INFO - 'LOGNAME': 'cltbld', 09:12:39 INFO - 'MAIL': '/var/mail/cltbld', 09:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:39 INFO - 'MOZ_NO_REMOTE': '1', 09:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:39 INFO - 'NO_EM_RESTART': '1', 09:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:39 INFO - 'PWD': '/builds/slave/test', 09:12:39 INFO - 'SHELL': '/bin/bash', 09:12:39 INFO - 'SHLVL': '1', 09:12:39 INFO - 'TERM': 'linux', 09:12:39 INFO - 'TMOUT': '86400', 09:12:39 INFO - 'USER': 'cltbld', 09:12:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:39 INFO - '_': '/tools/buildbot/bin/python'} 09:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:12:39 INFO - Cleaning up... 09:12:39 INFO - Return code: 0 09:12:39 INFO - Running post-action listener: _resource_record_post_action 09:12:39 INFO - Running post-action listener: _start_resource_monitoring 09:12:39 INFO - Starting resource monitoring. 09:12:39 INFO - [mozharness: 2016-12-29 17:12:39.533818Z] Finished create-virtualenv step (success) 09:12:39 INFO - [mozharness: 2016-12-29 17:12:39.534323Z] Running install step. 09:12:39 INFO - Running pre-action listener: _resource_record_pre_action 09:12:39 INFO - Running main action method: install 09:12:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:39 INFO - Reading from file tmpfile_stdout 09:12:39 INFO - Detecting whether we're running mozinstall >=1.0... 09:12:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:12:39 INFO - Reading from file tmpfile_stdout 09:12:39 INFO - Output received: 09:12:39 INFO - Usage: mozinstall [options] installer 09:12:39 INFO - Options: 09:12:39 INFO - -h, --help show this help message and exit 09:12:39 INFO - -d DEST, --destination=DEST 09:12:39 INFO - Directory to install application into. [default: 09:12:39 INFO - "/builds/slave/test"] 09:12:39 INFO - --app=APP Application being installed. [default: firefox] 09:12:39 INFO - mkdir: /builds/slave/test/build/application 09:12:39 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:12:39 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:12:53 INFO - Reading from file tmpfile_stdout 09:12:53 INFO - Output received: 09:12:53 INFO - /builds/slave/test/build/application/firefox/firefox 09:12:53 INFO - Running post-action listener: _resource_record_post_action 09:12:53 INFO - [mozharness: 2016-12-29 17:12:53.317064Z] Finished install step (success) 09:12:53 INFO - [mozharness: 2016-12-29 17:12:53.317309Z] Running run-tests step. 09:12:53 INFO - Running pre-action listener: _resource_record_pre_action 09:12:53 INFO - Running main action method: run_tests 09:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:12:53 INFO - Python 2.7.3 09:12:53 INFO - Return code: 0 09:12:53 INFO - grabbing minidump binary from tooltool 09:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:53 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 0x1d08f60>, '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 0x1d09d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d0a1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:12:53 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:12:53 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:12:53 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:12:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:53 INFO - Return code: 0 09:12:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:12:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:12:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:12:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:12:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:12:53 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/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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:12:53 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/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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:12:53 INFO - Using env: {'DISPLAY': ':0', 09:12:53 INFO - 'HOME': '/home/cltbld', 09:12:53 INFO - 'LANG': 'en_US.UTF-8', 09:12:53 INFO - 'LANGUAGE': 'en_US:en', 09:12:53 INFO - 'LOGNAME': 'cltbld', 09:12:53 INFO - 'MAIL': '/var/mail/cltbld', 09:12:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:12:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:53 INFO - 'MOZ_NO_REMOTE': '1', 09:12:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:53 INFO - 'NO_EM_RESTART': '1', 09:12:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:53 INFO - 'PWD': '/builds/slave/test', 09:12:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:12:53 INFO - 'SHELL': '/bin/bash', 09:12:53 INFO - 'SHLVL': '1', 09:12:53 INFO - 'TERM': 'linux', 09:12:53 INFO - 'TMOUT': '86400', 09:12:53 INFO - 'USER': 'cltbld', 09:12:53 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239', 09:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:53 INFO - '_': '/tools/buildbot/bin/python'} 09:12:53 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/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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:12:53 INFO - mozversion application_buildid: 20161229014137 09:12:53 INFO - mozversion application_changeset: d843dc104a2e124539e43aeff6fa8a89aa1cc430 09:12:53 INFO - mozversion application_display_name: Firefox 09:12:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:53 INFO - mozversion application_name: Firefox 09:12:53 INFO - mozversion application_remotingname: firefox 09:12:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:12:53 INFO - mozversion application_vendor: Mozilla 09:12:53 INFO - mozversion application_version: 51.0 09:12:53 INFO - mozversion platform_buildid: 20161229014137 09:12:53 INFO - mozversion platform_changeset: d843dc104a2e124539e43aeff6fa8a89aa1cc430 09:12:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:12:53 INFO - mozversion platform_version: 51.0 09:12:53 INFO - using testdate: 1483031573 09:12:53 INFO - actual date: 1483031573 09:12:53 INFO - starting webserver on 'localhost:38169' 09:12:53 INFO - SUITE-START | Running 2 tests 09:12:53 INFO - TEST-START | dromaeo_css 09:12:53 INFO - operating with platform_type : linux_ 09:12:53 INFO - Initialising browser for dromaeo_css test... 09:12:53 INFO - TEST-INFO | started process 16363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGnOI8t/profile http://localhost:38169/getInfo.html) 09:12:57 INFO - PROCESS | 16363 | __metrics Screen width/height:1600/1200 09:12:57 INFO - PROCESS | 16363 | colorDepth:24 09:12:57 INFO - PROCESS | 16363 | Browser inner width/height: 1024/697 09:12:57 INFO - PROCESS | 16363 | __metrics 09:12:58 INFO - PROCESS | 16363 | 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:12:58 INFO - PROCESS | 16363 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:58 INFO - PROCESS | 16363 | console.error: PushService: 09:12:58 INFO - PROCESS | 16363 | stateChangeProcessEnqueue: Error transitioning state 09:12:58 INFO - PROCESS | 16363 | UnknownError 09:12:59 INFO - TEST-INFO | 16363: exit 0 09:12:59 INFO - Browser initialized. 09:12:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:59 INFO - TEST-INFO | started process 16459 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGnOI8t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:11 INFO - PROCESS | 16459 | RSS: Main: 142983168 09:13:11 INFO - PROCESS | 16459 | 09:14:02 INFO - PROCESS | 16459 | 1483031642235 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:16:11 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:38169/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38169/tests/dromaeo/cssquery-ext.html) 09:16:12 INFO - PROCESS | 16459 | RSS: Main: 242470912 09:16:12 INFO - PROCESS | 16459 | 09:19:14 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:38169/tests/dromaeo/cssquery-ext.html (next: http://localhost:38169/tests/dromaeo/cssquery-jquery.html) 09:19:15 INFO - PROCESS | 16459 | RSS: Main: 236400640 09:19:15 INFO - PROCESS | 16459 | 09:22:17 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:38169/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38169/tests/dromaeo/cssquery-mootools.html) 09:22:18 INFO - PROCESS | 16459 | RSS: Main: 176734208 09:22:18 INFO - PROCESS | 16459 | 09:25:19 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:38169/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38169/tests/dromaeo/cssquery-prototype.html) 09:25:20 INFO - PROCESS | 16459 | RSS: Main: 228569088 09:25:20 INFO - PROCESS | 16459 | 09:28:23 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:38169/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38169/tests/dromaeo/cssquery-yui.html) 09:28:23 INFO - PROCESS | 16459 | RSS: Main: 178249728 09:28:23 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | Cycle 1(1): loaded http://localhost:38169/tests/dromaeo/cssquery-yui.html (next: http://localhost:38169/tests/dromaeo/cssquery-dojo.html) 09:31:26 INFO - PROCESS | 16459 | RSS: Main: 264343552 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | __start_tp_report 09:31:26 INFO - PROCESS | 16459 | _x_x_mozilla_page_load 09:31:26 INFO - PROCESS | 16459 | _x_x_mozilla_page_load_details 09:31:26 INFO - PROCESS | 16459 | |i|pagename|runs| 09:31:26 INFO - PROCESS | 16459 | |0;dojo.html;3126.0465558004807;3163.019463966171;3166.762492813038;3181.490961446169;3209.5988001499813;5579.497845193931;5683.5169010634045;5727.6563406195655;5730.255066841315;5757.366689664144;5581.832545023656;5651.2653355063885;5658.405867383927;5666.490015898568;5667.8583035424135;5064.316317297173;5107.872303192413;5107.948920510803;5151.381834179885;5155.123628983063;5007.148784706608;5128.282040514331;5179.792808287664;5185.585153187736;5216.113260745682;4961.603071754251;4980.377452818398;4993.950060499403;5015.247712843066;5100.642954993152;5131.948680513203;5162.174052151638;5166.638335316529;5242.501962313574;5252.8686782830355;5062.443131255559;5139.640225184238;5197.818076367309;5222.921656175123;5250.107481728077;5377.3009508764;5422.75597598109;5519.862003449905;5546.112621980464;5572.665640061609;6010.729517171738;6029.0353543433275;6032.034874420116;6136.263648362181;6140.263168419773;6170.105334726484;6181.629102253879;6183.9381606183815;6187.783427580013;6200.255969283715;5456.972715136439;5479.178123281539;5543.501084902378;5556.638818476786;5596.58025648078;6140.416660417253;6151.7539298428455;6174.691265436711;6176.4441200292185;6176.505879529627;5907.202527658797;5913.497352725067;5916.526677865761;5916.911246331309;5919.4080591940465;5785.566082543826;5823.737931793079;5995.910061349084;6021.608595441282;6070.908936365958;6082.574219804571;6114.755409783559;6115.113308570321;6116.510679145657;6140.293866205354;6121.081837724377;6131.013867888268;6144.8771024579255;6149.13912052313;6153.753849845956;4533.637309015271;4545.863624091283;4608.331791890158;4631.120087183424;4707.693999889996;4115.773632450184;4132.504099508078;4160.771157586301;4170.249355116048;4181.1010632714115;5746.42535746422;5788.0739081746715;5813.360530341659;5815.912761308584;5823.388544202882;5883.411658834082;5937.614055086406;6028.758849646053;6082.939170608326;6110.052941794029;5770.42295770428;5810.680412577349;5819.796307129315;5857.677827719431;5859.1797148399255;5306.6550674206055;5325.094733895247;5327.067763141435;5340.813071542478;5343.492368225013;6030.065339872327;6081.695915204311;6286.214223222098;6322.968385158045;6339.746410143634;5953.077273022688;6051.7579296827635;6063.909041364295;6128.625347140905;6142.498775697809;6146.631202127886;6149.046897730859;6202.379762023762;6203.503719702503;6209.161763162096;5681.687507187145;5921.851953701192;5921.940780592139;5926.662523306401;5951.315598706205;5737.885242295214;5874.5887787854435;5879;5910.645361278337;6003.909941350796;5803.970980145073;5858.7070646468355;5859.794907178229;5861.765529378948;5867.618604790675;5850.824475265749;5867.061270196748;5871.970640146772;5873.706314684334;5874.265716785402;5585.832425027255;5590.776368945197;5844.736986835687;5885.17607534954;5913.408659134052;6067.683744968479;6087.381936540344;6103.755849765959;6119.0821376792055;6130.908036379547;5622.100463925753;5945.851353716192;5946.702664866826;5950.077737950624;5967.373425790403 09:31:26 INFO - PROCESS | 16459 | |1;ext.html;584.523613255197;634.2959315160172;647.058529839084;650.6584043377229;653.5392548253482;37702.434463483034;38597.61402385975;38643;38646;38663.61336386639;38264.23471530575;38359.04102397434;46566.53433465673;47747;47991;9199.310051746112;9316.347855650107;9325.440473571574;9325.766855828591;9328.347015708903;9305.813883722321;9407.576659050359;9415.952920235424;9417.76455588609;9422;8615.65537378504;8814.074522175175;11078.778424431506;11343.432828358591;11344.48949797261;11221;11280.774384512304;11282.548698052067;11288.322700637988;11307.208495405324;11029.227954043221;11111.277766945122;11111.333320000826;11113.499892504857;11113.83329250062;45751.77124114391;46062.30906536405;46144.53855461469;46165.30752038722;46197.53802461966;3761.37937240355;3765.4540091686827;3772.1889793694486;3788.7347885648014;3866;34123.4881476778;34461.827690861384;34474.13814654654;34546.82726586376;34725.30549388998;4768.3085952536685;4820.180569303192;4825.1555977703765;4833.081714474239;4931.80272789088;338476;340258;340382;340654;341138;64321.356786431534;64529.354706453276;64654;64751.352486474534;64751.676241618494;40648.79675601603;41165.79417102955;41376.1724765509;44034;68610;16602;16625.6674866502;16630.916845415937;16786.4124755633;16794.832051679572;12179.451924663412;12229.14395992272;12250.264984100982;12355.6911077222;12398.194117382522;18368.724469132972;18830;20786.896065519577;20903.68644470334;21125.471863203544;7203.71185152588;7383.741569045058;7547.132079810783;7692.846143077107;7735.9226407735205;1066.9839952400564;1094.070040465598;1136.4431428599926;1150.5742875136252;1158.7914175448332;35192.47211291832;35270.8236458816;35329.64670353315;35385.82307088448;35469.29061418757;1146.9827952580717;1154.4054811771944;1179.6343133628432;1184.117832214988;1199.0707201918583;18214.180361883748;18258.269669213347;18310.26758929628;18478.722819157723;18524;534927;538136;538420;539182;539450;63486.682566586875;64378;64414;64415.355846440936;64421.67789161024;1397.797319388683;1441.3081720774087;1444.8627380398846;1457.7230326238193;1458.030409777486;31950.6804931957;33461.83269083639;34021.65978340185;34153;34458.65541344655;55966;61068.38931610805;63354.36645633485;63439;68198;482349;486945;488029;488570;489543;36584.45123323099;47213.29180062371;47696.28455573239;48121;48636.75681621569;51049;51088.23367649563;52293.47706522886;52632.47367526276;53913;43012;44246.336304954806;45266.32100518429;45281.32078018898;45789.31316030196;43950;44868.32697509605;45476.31785523286;45869;46311.30533042074;44322.556774433135;45175.54824451714;45211;45245.77377113225;47337;37536.624633753316;38830.805845970586;40104.39843402409;40244.195116096926;40472.39291410572;29838.10485685433;29946.550801738427;30154;30200.395992079597;30434.84782576073 09:31:26 INFO - PROCESS | 16459 | |2;jquery.html;402.1153462382755;415.5159239485999;415.6986185015862;431.2926800047921;442.84943119339437;15031.924840375796;16282.26729797159;20028.499287517818;20442.182312707475;20497.897510512463;19519.316823911166;20002;20059.398218053444;20246.088925998327;20386.388408347735;5843.444872737094;5854.209681692981;5889.8822023559505;5951.970240148793;5955.898158840607;4419.624331931774;5868.149118377833;5981.252343457068;5983.162357269964;5990.8801823963495;4676.766161691902;6527;6567.376099270586;6685;6695.46436285096;6413.0059840724525;6594.406503414716;6609.636469994149;6666.100076489684;6676.599404035749;5853.941460585383;5902.3507414184405;5903.645781253139;5905.4980326672285;5905.704714764244;22013.559728805496;22247.44381390462;23891.283261502183;24588.139415120564;24755.133570324953;2464.5194187133516;2474.01039584166;2513.843021379095;2719.3745438549245;2770.98858916494;14143.504977325743;14801.703965920622;14812.851871481364;15049.172295523742;15150.696986060328;2037.7350944377283;2041.6018876319124;2043.7240972468676;2096.5387614724737;2103.5897999890026;73783;75750;75825.62087189528;76901.61549192274;86536;11794.469248883821;11842.585509507298;11999.940000299943;12083.57707480234;12167.209131406431;16999.830001700073;17007.06461144649;17026.318947241973;17036.914815425844;17040.48878533646;8074.152214017561;8384.83230335402;8468.195521425454;8528.147185281423;8556.529390883437;7849.803754906173;7895.407844411578;7908.604569771488;8083.514989100673;8113.350931925432;5041.117804384218;5155.046316431472;5155.74221288934;5211.2443695663915;5225.1378522543355;2121.2787652198326;2138.882361470134;2138.957220855605;2170.2838063439026;2255.593993081261;900.2213085680839;1032.3341444768082;1032.83990981398;1036.828923227659;1052.1162223732101;19805.60388792216;20114.497137571678;20196.798032019786;20560.58878822445;20695.689564656543;1025.2874252394663;1041.1982773264695;1055.4933631856752;1061.0344586426315;1062.946852657364;10765.407902565277;11341.149413793999;11380.203385763083;11454.828177577343;11517.539298428137;424409;537421;540989;552673;553832;67705.66147169331;103212.48393757983;105320;105790;108179;1049.994750026245;1065.9413732244645;1085.8371244313416;1092.4100985467758;1094.195766111907;18369;18666.440006799818;18745.437636870916;18983.90508047451;19398.321058762875;31007.844960775954;42395.364069539595;43882.56117438785;44044.33933490936;44476.332855006556;61710.38289617226;62401.943873648066;62813.05780413206;63594.3640563607;63718.86694257924;8643.697470588499;9151.496667683341;9281.907180928105;9348.813023739624;9357.953210234178;10264.127549158406;12714.745705085661;13242.139260948008;13245.867541324464;13686.247256400993;11673.766524669289;11838.526458941546;11919.165658403828;11941.16411851162;12030.518779248732;11224.719382015515;11279.548818047186;11380.829287560859;11388.259763115371;12181;11708.356040417859;11934.104942129548;11942.223755455867;11951.521939122686;12181.208221465577;9016.639334426813;9051.185393314634;9082.318826087938;9085.863712044456;9141.588628511867;11814.704632384259;12036.398180091135;12050.457729402022;12140.75718485643;12621.74756504883 09:31:26 INFO - PROCESS | 16459 | |3;mootools.html;1374.7869080292558;1523.9695206095876;1565.4912153550097;1744.2674076887706;1921.625283069802;8458.788530286745;9351.6726914558;9651.903480965188;9665.613375464973;9962.302638815268;9625.470599117045;9737.07497787709;9744.220462363011;9744.561494732736;9746.561404736785;2413.0709676774436;2439.3657649011207;2441.4750828571855;2442.3283597010786;2444.4866578018637;2363.3500787283547;2377.988110059447;2408.2414039577475;2415.1184817541557;2417.2748175547354;2416.1301931304715;2432.8905199265987;2441.2676197140877;2466.5560199164142;2477.1206221791276;2415.7221919479307;2419.1895714935517;2422.2854257993936;2445.6698345723457;2499.737527559598;2414.8671823049726;2422.854628722282;2425.50277193175;2429.7934675552424;2431.6109422492495;8515.787105322355;8782.692605758768;9740.269479789044;9759;9795.90204097957;5069.44236134025;5231.084560201987;5615.326160860682;5789.507891829199;5797.36229014808;3673.4673472346626;3680.9079773005888;3724.683401910841;3743.1577894973684;3756.492873462075;1196.3719047500044;1200.4417945655225;1201.0571701214506;1214.4959841665698;1221.2306247064382;13267.86732132676;13289.667758306023;13299.734005319937;13304.600861974157;13315.933420332933;4880.536349046835;4940.851774446773;5046.772895219724;5067.239914012927;5102.795888164506;11520.193586448868;11544;11556.075513958864;11572.18994670365;11575.88424115765;5799.449052340021;5801.361850196475;5821.039528477754;5826;5831.183634291203;5262.842114736564;5309.57523398135;5344.43883392246;5364.517193452608;5367.5169234769055;4304.91390172201;4357.542458041923;4362.345648152803;4391.494978077562;4407.118576284756;1365.0853927868352;1381.5648070857649;1390.5133203378737;1393.2476462710024;1404.1785555449972;985.0887928665956;1035.471909326249;1041.8176819056634;1049.8635177427038;1055.567217440861;6191.279690885357;6285.748570057238;6617.669116544153;6674.966625166843;6887.690053947585;469.72051629280526;480.67554400779335;482.28621639972334;485.9101066302745;488.41634247074404;4354.695171337976;4448.7330760153845;4470.664700147502;4473.4408198975125;4485.887852803706;10184.898151018544;10517.158627309796;11501.827472587918;11852.051835853112;11968.401579921143;12295.508179672714;12314.692132696755;12318.507259709511;12323.876761232274;12330.938345308216;9028.548572571344;9140.862887056699;9145.405548639317;9150.130737579919;9175.357724959322;6395.936040639534;6400.71196796136;6540.574862633914;6600.60396376219;6680.298568650222;10916.672499825017;11290.43547822622;11303.943480282545;11307.378094204898;11315.660530184108;11139.108871290115;11189.229320404836;11315.943420282845;11324.94337528307;11459.770804583695;8508.829823403621;8509;8586.270167035622;8596.441231319945;8599.226069653761;9212.631494740135;9370.156685898302;9373.578188981648;9377.062293770568;9386.296027797805;8329.583520824055;8418.494890306602;8549.87175192385;8576.185262400062;8709.956450217709;8208.876866847122;8302.584870756316;8305.584720763816;8434.367422443218;8537.786555336166;7454.142773581108;7550.66022029021;7626.275503827128;7682.423818213544;7761.18507556721;5363.570914326767;5487.972560137334;5586.86032849182;5613.691246981455;5662.518685911744;5475.288212532345;5575.944240557705;5668.51817595509;5742;5827.038538641077 09:31:26 INFO - PROCESS | 16459 | |4;prototype.html;339.7333093521586;339.9422098243299;345.56679390257807;350.58630815637537;353.6463536463536;5607.149640618596;5610.523105536029;5702.230198923149;5703.31560212774;5716.914246286298;5315.309009828727;5335.866603334918;5386.380566234884;5465.344158700951;5622.971885140579;4338.980339620187;4342.97828510857;4364.498082720496;4368.672349573779;4390.846320378788;4346.109047645241;4346.6739994500385;4381.9342709859375;4388.517263101056;4393.582609652094;4089.44792453018;4302.698811083225;4326.264535029037;4326.372675961984;4330.155619654169;4276.978615106936;4289.549597292286;4297.03316753731;4297.613214810682;4300.172426718632;3791.3554695701796;3806.448065030555;3807.58116607173;3808.5620153682403;3810.542734871833;5249.370075590915;5352.330958630171;5363.6245462817615;5486.835394938158;5509.3664228613825;4936.7531623419045;4948.87627809304;4966.90066198674;4969.950300497021;4976.850694479135;5796.362400135981;5809.535237181015;5834.941650583482;5842.211301474289;5844.26946631671;4087.570805065485;4191.203671302443;4200.613797446836;4214.599613036757;4230.830766769325;6019.819405417845;6046.455818976313;6047.395260473917;6069.241344831896;6071.544634152412;4196.475440569929;4197.286461301525;4210.642095421862;4226;4232.25935461293;4213.325867861127;4240.767423958274;4293.527711951683;4295.591918767712;4310.396544483775;4886.804527818847;4917.5082491751255;4920.532549407801;4921.8523444296725;4927.77824997876;5832.88334233313;5854.2389489366105;5945.137954996501;5946.524278057745;5952.523798096142;1427.1192521576234;1458.3884807287793;1490.9403623855142;1495.0730547060891;1498.3332417074516;936.3773090894615;937.1277820981074;947.2185447006248;947.8909925358672;959.9200898988638;4965.503449655005;4979.975100124476;4991;5004.024215277986;5005.6996580205305;5172.586193104543;5196;5198.7140707260705;5208.874675194853;5234.2933703949475;4850.264977125447;4881.95118048822;4982.725950072709;5012.699238045729;5021.548060674484;2776.8889244429997;2778.3470884342164;2789.2887313735273;2790.725558669678;2795.8741856616502;5334.306540149756;5362.552110930027;5366.892662146735;5394.865128371822;5404.9192859390905;4990.3762029746285;5299.330710826078;5308.708021058879;5365.865853353619;5419.214213938957;5320.787168513293;5335.33308336458;5337.973310133424;5348.91976620351;5372.489613486712;5275.702685821361;5295.867603309794;5309.442508536548;5311.681299122065;5314.1497360422145;5255.527002569787;5568.916466252928;5963.075723262901;6023.186869772525;6026.578139530367;6170.413810687977;6235.469985051322;6242.188515492957;6262.373762623702;6271.717772700331;4134.524529679005;4341.9565804341555;4377.627901628397;4386.71486353386;4412.82348706061;5977.505623594101;5991.580589358703;6003.669798161143;6005.90991135142;6092.573519853568;5829.213056237354;5867.73595188209;5873.765049398147;5879.235699359056;5890.410958904075;5580.414056524007;5803.970980145241;5839.270091238595;5869.148973398748;5884.176215329771;5793.884122317446;5832.912506312324;5860.589758717019;5860.619059761102;5864.530837533072;5643.435656434323;5921.970390148194;5938.4061593842;5938.6436813791315;5939.000804593653;5774.48029677331;5781.005553084179;5796.782675638163;5798.623089499338;5803.970980145073 09:31:26 INFO - PROCESS | 16459 | |5;yui.html;790.936725061995;823.8228780812128;868.9782755431113;878.8154487557615;889.3996552327186;15876.206189690512;16880.1559922004;16896.408625698103;16909.323627054902;16914;15670.764938525932;15850.84149158508;15855.60360990973;15858.127802970832;15882.761758573602;704.7744721689074;728.406348825708;858.1632907914795;879.7712594725353;880.8590625499921;878.0122362342365;880.009988762642;880.3793325705394;880.6829541365104;881.3081730841291;915.3363811236867;915.7344370132654;916.8303863785188;917.3991035871514;917.554985831871;908.5956749246587;910.4628269321132;911.895132059815;915.0163574157774;915.3180880244228;671.8219671786981;675.5473832532215;676.3980057748607;677.1366507702669;677.4512644757713;16365.099919504542;16374.672506549921;16502.092384918826;16512.42206522766;16514.174291285388;16283.837161628351;17106.743398849027;17226.178702594727;17647.558811029827;17870.46388608344;13131.409086591126;13235.536756213487;13274.402651880591;13291;13340.33298335089;4061.756294622332;4085.816138273785;4120.670346372283;4156.833726650931;4250.022494826177;57778.422215777726;59858.700706496624;85794.5710271451;86441;87074;11683;11902.464389102512;12600.077575661118;12676.176048557;13149.523852059143;21828.345149645535;22111.33665990023;22337.664935025987;22356.217532386287;22517;8771.429857059271;8874.733757987271;9188.586513606904;9246.768830779285;9477.431354118775;5147.974260128675;5278.81524146653;5316.946830531645;5395.163749618815;5399.055165346049;3685.7235707322056;3810.504634397566;3820.9235815283537;3832.9808350958065;3846.8653597123966;650.9544331896816;659.9406053455116;668.6188872342718;670.5674839728348;673.727140508095;695.7147569496482;730.0582248898967;774.5972094510823;790.5651891459675;804.2480280937342;10142.137918277029;10231.181505479693;10316.535755891004;10503.369797812156;10689.358638481715;688.1466980943694;727.1310783613636;741.5587725303436;753.1113286322193;769.6882762481204;5056.64603477753;5174.8965020700125;5275.765045691124;5281.181416880389;5325.4940780625775;100032;128574;131848.3407582975;132165;132914;99557;106498.46750766352;107859.38210926842;110533;111395.44302278437;74187;81680.59159704164;97375.51312243535;102181;105312.47343763386;9884.901150988399;10017.39895606266;10198.490075496344;10216.489175541343;10230.693079207636;61147;61416.38583614286;61807;62347;62462.37537624747;87150.56424717836;88209.55895220482;89384.08387538488;90252;90847.5491470514;11677;12401.875981240071;12668.366581670694;12831.03767217481;13352.599422017354;14855.182964937032;14866.776998345249;14894.106353618818;15053.849461505682;16282.51152465428;13647.522336718168;13949.511767088103;14281.285935702965;14357.784633230302;14548.85451145475;12552.623421297376;12856.678583035498;12960.546380876624;13722.588322350346;14230.217338046506;13233;13565.525206617722;14081.577552673436;14126.646833828825;14304.499342523379;10592.629257975934;10850.131989440668;11035.172362072715;11238.606648767254;11273.774524509628;11098.2786118902;11204.327740335606;11387.715307117389;11429.714257143638;11451.141164412536 09:31:26 INFO - PROCESS | 16459 | __end_tp_report 09:31:26 INFO - PROCESS | 16459 | __start_cc_report 09:31:26 INFO - PROCESS | 16459 | _x_x_mozilla_cycle_collect,891 09:31:26 INFO - PROCESS | 16459 | __end_cc_report 09:31:26 INFO - PROCESS | 16459 | __startTimestamp1483032686411__endTimestamp 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | ------- Summary: start ------- 09:31:26 INFO - PROCESS | 16459 | Number of tests: 6 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | [#0] dojo.html Cycles:165 Average:5609.02 Median:5850.82 stddev:650.11 (11.1%) stddev-sans-first:622.24 09:31:26 INFO - PROCESS | 16459 | Values: 3126.0 3163.0 3166.8 3181.5 3209.6 5579.5 5683.5 5727.7 5730.3 5757.4 5581.8 5651.3 5658.4 5666.5 5667.9 5064.3 5107.9 5107.9 5151.4 5155.1 5007.1 5128.3 5179.8 5185.6 5216.1 4961.6 4980.4 4994.0 5015.2 5100.6 5131.9 5162.2 5166.6 5242.5 5252.9 5062.4 5139.6 5197.8 5222.9 5250.1 5377.3 5422.8 5519.9 5546.1 5572.7 6010.7 6029.0 6032.0 6136.3 6140.3 6170.1 6181.6 6183.9 6187.8 6200.3 5457.0 5479.2 5543.5 5556.6 5596.6 6140.4 6151.8 6174.7 6176.4 6176.5 5907.2 5913.5 5916.5 5916.9 5919.4 5785.6 5823.7 5995.9 6021.6 6070.9 6082.6 6114.8 6115.1 6116.5 6140.3 6121.1 6131.0 6144.9 6149.1 6153.8 4533.6 4545.9 4608.3 4631.1 4707.7 4115.8 4132.5 4160.8 4170.2 4181.1 5746.4 5788.1 5813.4 5815.9 5823.4 5883.4 5937.6 6028.8 6082.9 6110.1 5770.4 5810.7 5819.8 5857.7 5859.2 5306.7 5325.1 5327.1 5340.8 5343.5 6030.1 6081.7 6286.2 6323.0 6339.7 5953.1 6051.8 6063.9 6128.6 6142.5 6146.6 6149.0 6202.4 6203.5 6209.2 5681.7 5921.9 5921.9 5926.7 5951.3 5737.9 5874.6 5879.0 5910.6 6003.9 5804.0 5858.7 5859.8 5861.8 5867.6 5850.8 5867.1 5872.0 5873.7 5874.3 5585.8 5590.8 5844.7 5885.2 5913.4 6067.7 6087.4 6103.8 6119.1 6130.9 5622.1 5945.9 5946.7 5950.1 5967.4 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | [#1] ext.html Cycles:180 Average:63261.29 Median:40560.59 stddev:122503.10 (302.0%) stddev-sans-first:122756.37 09:31:26 INFO - PROCESS | 16459 | Values: 584.5 634.3 647.1 650.7 653.5 37702.4 38597.6 38643.0 38646.0 38663.6 38264.2 38359.0 46566.5 47747.0 47991.0 9199.3 9316.3 9325.4 9325.8 9328.3 9305.8 9407.6 9416.0 9417.8 9422.0 8615.7 8814.1 11078.8 11343.4 11344.5 11221.0 11280.8 11282.5 11288.3 11307.2 11029.2 11111.3 11111.3 11113.5 11113.8 45751.8 46062.3 46144.5 46165.3 46197.5 3761.4 3765.5 3772.2 3788.7 3866.0 34123.5 34461.8 34474.1 34546.8 34725.3 4768.3 4820.2 4825.2 4833.1 4931.8 338476.0 340258.0 340382.0 340654.0 341138.0 64321.4 64529.4 64654.0 64751.4 64751.7 40648.8 41165.8 41376.2 44034.0 68610.0 16602.0 16625.7 16630.9 16786.4 16794.8 12179.5 12229.1 12250.3 12355.7 12398.2 18368.7 18830.0 20786.9 20903.7 21125.5 7203.7 7383.7 7547.1 7692.8 7735.9 1067.0 1094.1 1136.4 1150.6 1158.8 35192.5 35270.8 35329.6 35385.8 35469.3 1147.0 1154.4 1179.6 1184.1 1199.1 18214.2 18258.3 18310.3 18478.7 18524.0 534927.0 538136.0 538420.0 539182.0 539450.0 63486.7 64378.0 64414.0 64415.4 64421.7 1397.8 1441.3 1444.9 1457.7 1458.0 31950.7 33461.8 34021.7 34153.0 34458.7 55966.0 61068.4 63354.4 63439.0 68198.0 482349.0 486945.0 488029.0 488570.0 489543.0 36584.5 47213.3 47696.3 48121.0 48636.8 51049.0 51088.2 52293.5 52632.5 53913.0 43012.0 44246.3 45266.3 45281.3 45789.3 43950.0 44868.3 45476.3 45869.0 46311.3 44322.6 45175.5 45211.0 45245.8 47337.0 37536.6 38830.8 40104.4 40244.2 40472.4 29838.1 29946.6 30154.0 30200.4 30434.8 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | [#2] jquery.html Cycles:180 Average:30509.60 Median:2154.62 stddev:86349.41 (4007.6%) stddev-sans-first:86562.05 09:31:26 INFO - PROCESS | 16459 | Values: 402.1 415.5 415.7 431.3 442.8 15031.9 16282.3 20028.5 20442.2 20497.9 19519.3 20002.0 20059.4 20246.1 20386.4 5843.4 5854.2 5889.9 5952.0 5955.9 4419.6 5868.1 5981.3 5983.2 5990.9 4676.8 6527.0 6567.4 6685.0 6695.5 6413.0 6594.4 6609.6 6666.1 6676.6 5853.9 5902.4 5903.6 5905.5 5905.7 22013.6 22247.4 23891.3 24588.1 24755.1 2464.5 2474.0 2513.8 2719.4 2771.0 14143.5 14801.7 14812.9 15049.2 15150.7 2037.7 2041.6 2043.7 2096.5 2103.6 73783.0 75750.0 75825.6 76901.6 86536.0 11794.5 11842.6 11999.9 12083.6 12167.2 16999.8 17007.1 17026.3 17036.9 17040.5 8074.2 8384.8 8468.2 8528.1 8556.5 7849.8 7895.4 7908.6 8083.5 8113.4 5041.1 5155.0 5155.7 5211.2 5225.1 2121.3 2138.9 2139.0 2170.3 2255.6 900.2 1032.3 1032.8 1036.8 1052.1 19805.6 20114.5 20196.8 20560.6 20695.7 1025.3 1041.2 1055.5 1061.0 1062.9 10765.4 11341.1 11380.2 11454.8 11517.5 424409.0 537421.0 540989.0 552673.0 553832.0 67705.7 103212.5 105320.0 105790.0 108179.0 1050.0 1065.9 1085.8 1092.4 1094.2 18369.0 18666.4 18745.4 18983.9 19398.3 31007.8 42395.4 43882.6 44044.3 44476.3 61710.4 62401.9 62813.1 63594.4 63718.9 8643.7 9151.5 9281.9 9348.8 9358.0 10264.1 12714.7 13242.1 13245.9 13686.2 11673.8 11838.5 11919.2 11941.2 12030.5 11224.7 11279.5 11380.8 11388.3 12181.0 11708.4 11934.1 11942.2 11951.5 12181.2 9016.6 9051.2 9082.3 9085.9 9141.6 11814.7 12036.4 12050.5 12140.8 12621.7 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | [#3] mootools.html Cycles:180 Average:6310.48 Median:481.48 stddev:3654.07 (758.9%) stddev-sans-first:3645.49 09:31:26 INFO - PROCESS | 16459 | Values: 1374.8 1524.0 1565.5 1744.3 1921.6 8458.8 9351.7 9651.9 9665.6 9962.3 9625.5 9737.1 9744.2 9744.6 9746.6 2413.1 2439.4 2441.5 2442.3 2444.5 2363.4 2378.0 2408.2 2415.1 2417.3 2416.1 2432.9 2441.3 2466.6 2477.1 2415.7 2419.2 2422.3 2445.7 2499.7 2414.9 2422.9 2425.5 2429.8 2431.6 8515.8 8782.7 9740.3 9759.0 9795.9 5069.4 5231.1 5615.3 5789.5 5797.4 3673.5 3680.9 3724.7 3743.2 3756.5 1196.4 1200.4 1201.1 1214.5 1221.2 13267.9 13289.7 13299.7 13304.6 13315.9 4880.5 4940.9 5046.8 5067.2 5102.8 11520.2 11544.0 11556.1 11572.2 11575.9 5799.4 5801.4 5821.0 5826.0 5831.2 5262.8 5309.6 5344.4 5364.5 5367.5 4304.9 4357.5 4362.3 4391.5 4407.1 1365.1 1381.6 1390.5 1393.2 1404.2 985.1 1035.5 1041.8 1049.9 1055.6 6191.3 6285.7 6617.7 6675.0 6887.7 469.7 480.7 482.3 485.9 488.4 4354.7 4448.7 4470.7 4473.4 4485.9 10184.9 10517.2 11501.8 11852.1 11968.4 12295.5 12314.7 12318.5 12323.9 12330.9 9028.5 9140.9 9145.4 9150.1 9175.4 6395.9 6400.7 6540.6 6600.6 6680.3 10916.7 11290.4 11303.9 11307.4 11315.7 11139.1 11189.2 11315.9 11324.9 11459.8 8508.8 8509.0 8586.3 8596.4 8599.2 9212.6 9370.2 9373.6 9377.1 9386.3 8329.6 8418.5 8549.9 8576.2 8710.0 8208.9 8302.6 8305.6 8434.4 8537.8 7454.1 7550.7 7626.3 7682.4 7761.2 5363.6 5488.0 5586.9 5613.7 5662.5 5475.3 5575.9 5668.5 5742.0 5827.0 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | [#4] prototype.html Cycles:180 Average:4761.80 Median:5304.02 stddev:1394.50 (26.3%) stddev-sans-first:1358.34 09:31:26 INFO - PROCESS | 16459 | Values: 339.7 339.9 345.6 350.6 353.6 5607.1 5610.5 5702.2 5703.3 5716.9 5315.3 5335.9 5386.4 5465.3 5623.0 4339.0 4343.0 4364.5 4368.7 4390.8 4346.1 4346.7 4381.9 4388.5 4393.6 4089.4 4302.7 4326.3 4326.4 4330.2 4277.0 4289.5 4297.0 4297.6 4300.2 3791.4 3806.4 3807.6 3808.6 3810.5 5249.4 5352.3 5363.6 5486.8 5509.4 4936.8 4948.9 4966.9 4970.0 4976.9 5796.4 5809.5 5834.9 5842.2 5844.3 4087.6 4191.2 4200.6 4214.6 4230.8 6019.8 6046.5 6047.4 6069.2 6071.5 4196.5 4197.3 4210.6 4226.0 4232.3 4213.3 4240.8 4293.5 4295.6 4310.4 4886.8 4917.5 4920.5 4921.9 4927.8 5832.9 5854.2 5945.1 5946.5 5952.5 1427.1 1458.4 1490.9 1495.1 1498.3 936.4 937.1 947.2 947.9 959.9 4965.5 4980.0 4991.0 5004.0 5005.7 5172.6 5196.0 5198.7 5208.9 5234.3 4850.3 4882.0 4982.7 5012.7 5021.5 2776.9 2778.3 2789.3 2790.7 2795.9 5334.3 5362.6 5366.9 5394.9 5404.9 4990.4 5299.3 5308.7 5365.9 5419.2 5320.8 5335.3 5338.0 5348.9 5372.5 5275.7 5295.9 5309.4 5311.7 5314.1 5255.5 5568.9 5963.1 6023.2 6026.6 6170.4 6235.5 6242.2 6262.4 6271.7 4134.5 4342.0 4377.6 4386.7 4412.8 5977.5 5991.6 6003.7 6005.9 6092.6 5829.2 5867.7 5873.8 5879.2 5890.4 5580.4 5804.0 5839.3 5869.1 5884.2 5793.9 5832.9 5860.6 5860.6 5864.5 5643.4 5922.0 5938.4 5938.6 5939.0 5774.5 5781.0 5796.8 5798.6 5804.0 09:31:26 INFO - PROCESS | 16459 | 09:31:26 INFO - PROCESS | 16459 | [#5] yui.html Cycles:180 Average:22542.88 Median:3748.11 stddev:32927.94 (878.5%) stddev-sans-first:32979.80 09:31:26 INFO - PROCESS | 16459 | Values: 790.9 823.8 869.0 878.8 889.4 15876.2 16880.2 16896.4 16909.3 16914.0 15670.8 15850.8 15855.6 15858.1 15882.8 704.8 728.4 858.2 879.8 880.9 878.0 880.0 880.4 880.7 881.3 915.3 915.7 916.8 917.4 917.6 908.6 910.5 911.9 915.0 915.3 671.8 675.5 676.4 677.1 677.5 16365.1 16374.7 16502.1 16512.4 16514.2 16283.8 17106.7 17226.2 17647.6 17870.5 13131.4 13235.5 13274.4 13291.0 13340.3 4061.8 4085.8 4120.7 4156.8 4250.0 57778.4 59858.7 85794.6 86441.0 87074.0 11683.0 11902.5 12600.1 12676.2 13149.5 21828.3 22111.3 22337.7 22356.2 22517.0 8771.4 8874.7 9188.6 9246.8 9477.4 5148.0 5278.8 5316.9 5395.2 5399.1 3685.7 3810.5 3820.9 3833.0 3846.9 651.0 659.9 668.6 670.6 673.7 695.7 730.1 774.6 790.6 804.2 10142.1 10231.2 10316.5 10503.4 10689.4 688.1 727.1 741.6 753.1 769.7 5056.6 5174.9 5275.8 5281.2 5325.5 100032.0 128574.0 131848.3 132165.0 132914.0 99557.0 106498.5 107859.4 110533.0 111395.4 74187.0 81680.6 97375.5 102181.0 105312.5 9884.9 10017.4 10198.5 10216.5 10230.7 61147.0 61416.4 61807.0 62347.0 62462.4 87150.6 88209.6 89384.1 90252.0 90847.5 11677.0 12401.9 12668.4 12831.0 13352.6 14855.2 14866.8 14894.1 15053.8 16282.5 13647.5 13949.5 14281.3 14357.8 14548.9 12552.6 12856.7 12960.5 13722.6 14230.2 13233.0 13565.5 14081.6 14126.6 14304.5 10592.6 10850.1 11035.2 11238.6 11273.8 11098.3 11204.3 11387.7 11429.7 11451.1 09:31:26 INFO - PROCESS | 16459 | -------- Summary: end -------- 09:31:26 INFO - PROCESS | 16459 | 09:31:27 INFO - TEST-INFO | 16459: exit 0 09:31:27 INFO - TEST-OK | dromaeo_css | took 1113378ms 09:31:27 INFO - TEST-START | kraken 09:31:27 INFO - operating with platform_type : linux_ 09:31:27 INFO - Initialising browser for kraken test... 09:31:27 INFO - TEST-INFO | started process 16766 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6uKA68/profile http://localhost:38169/getInfo.html) 09:31:31 INFO - PROCESS | 16766 | __metrics Screen width/height:1600/1200 09:31:31 INFO - PROCESS | 16766 | colorDepth:24 09:31:31 INFO - PROCESS | 16766 | Browser inner width/height: 1024/697 09:31:31 INFO - PROCESS | 16766 | __metrics 09:31:31 INFO - PROCESS | 16766 | 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:31:31 INFO - PROCESS | 16766 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:31:32 INFO - PROCESS | 16766 | console.error: PushService: 09:31:32 INFO - PROCESS | 16766 | stateChangeProcessEnqueue: Error transitioning state 09:31:32 INFO - PROCESS | 16766 | UnknownError 09:31:32 INFO - TEST-INFO | 16766: exit 0 09:31:32 INFO - Browser initialized. 09:31:32 INFO - Running cycle 1/1 for kraken test... 09:31:32 INFO - TEST-INFO | started process 16858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6uKA68/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:44 INFO - PROCESS | 16858 | RSS: Main: 143081472 09:31:44 INFO - PROCESS | 16858 | 09:31:47 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38169/tests/kraken/driver.html?testName=audio-beat-detection) 09:31:47 INFO - PROCESS | 16858 | RSS: Main: 186630144 09:31:47 INFO - PROCESS | 16858 | 09:31:50 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38169/tests/kraken/driver.html?testName=audio-dft) 09:31:51 INFO - PROCESS | 16858 | RSS: Main: 174096384 09:31:51 INFO - PROCESS | 16858 | 09:31:53 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38169/tests/kraken/driver.html?testName=audio-fft) 09:31:54 INFO - PROCESS | 16858 | RSS: Main: 265359360 09:31:54 INFO - PROCESS | 16858 | 09:31:56 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38169/tests/kraken/driver.html?testName=audio-oscillator) 09:31:57 INFO - PROCESS | 16858 | RSS: Main: 172269568 09:31:57 INFO - PROCESS | 16858 | 09:31:59 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38169/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:59 INFO - PROCESS | 16858 | RSS: Main: 173764608 09:31:59 INFO - PROCESS | 16858 | 09:32:02 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38169/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:03 INFO - PROCESS | 16858 | RSS: Main: 210137088 09:32:03 INFO - PROCESS | 16858 | 09:32:06 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38169/tests/kraken/driver.html?testName=imaging-desaturate) 09:32:07 INFO - PROCESS | 16858 | RSS: Main: 208695296 09:32:07 INFO - PROCESS | 16858 | 09:32:09 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38169/tests/kraken/driver.html?testName=json-parse-financial) 09:32:10 INFO - PROCESS | 16858 | RSS: Main: 208293888 09:32:10 INFO - PROCESS | 16858 | 09:32:12 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38169/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:32:12 INFO - PROCESS | 16858 | RSS: Main: 170467328 09:32:12 INFO - PROCESS | 16858 | 09:32:14 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:14 INFO - PROCESS | 16858 | RSS: Main: 178180096 09:32:14 INFO - PROCESS | 16858 | 09:32:16 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:32:17 INFO - PROCESS | 16858 | RSS: Main: 187117568 09:32:17 INFO - PROCESS | 16858 | 09:32:19 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:20 INFO - PROCESS | 16858 | RSS: Main: 195346432 09:32:20 INFO - PROCESS | 16858 | 09:32:23 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:23 INFO - PROCESS | 16858 | RSS: Main: 192688128 09:32:23 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | Cycle 1(1): loaded http://localhost:38169/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38169/tests/kraken/driver.html?testName=ai-astar) 09:32:25 INFO - PROCESS | 16858 | RSS: Main: 243986432 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | __start_tp_report 09:32:25 INFO - PROCESS | 16858 | _x_x_mozilla_page_load 09:32:25 INFO - PROCESS | 16858 | _x_x_mozilla_page_load_details 09:32:25 INFO - PROCESS | 16858 | |i|pagename|runs| 09:32:25 INFO - PROCESS | 16858 | |0;ai-astar;112;111;111;111;111;111;111;110;113;111 09:32:25 INFO - PROCESS | 16858 | |1;audio-beat-detection;149;124;117;155;119;120;122;124;143;119 09:32:25 INFO - PROCESS | 16858 | |2;audio-dft;160;161;163;165;162;160;161;162;161;161 09:32:25 INFO - PROCESS | 16858 | |3;audio-fft;75;76;75;109;76;75;77;76;105;76 09:32:25 INFO - PROCESS | 16858 | |4;audio-oscillator;82;81;110;82;82;81;103;82;82;125 09:32:25 INFO - PROCESS | 16858 | |5;imaging-gaussian-blur;116;116;116;116;117;117;117;117;116;117 09:32:25 INFO - PROCESS | 16858 | |6;imaging-darkroom;146;146;146;146;146;147;145;146;145;145 09:32:25 INFO - PROCESS | 16858 | |7;imaging-desaturate;95;95;97;96;96;95;95;95;96;94 09:32:25 INFO - PROCESS | 16858 | |8;json-parse-financial;51;51;48;49;50;50;49;49;50;49 09:32:25 INFO - PROCESS | 16858 | |9;json-stringify-tinderbox;58;59;58;61;59;58;59;59;58;59 09:32:25 INFO - PROCESS | 16858 | |10;stanford-crypto-aes;74;74;74;74;74;82;73;73;79;73 09:32:25 INFO - PROCESS | 16858 | |11;stanford-crypto-ccm;133;126;126;136;127;124;121;119;161;121 09:32:25 INFO - PROCESS | 16858 | |12;stanford-crypto-pbkdf2;153;151;154;153;152;154;156;155;152;153 09:32:25 INFO - PROCESS | 16858 | |13;stanford-crypto-sha256-iterative;62;61;64;65;61;62;61;64;61;60 09:32:25 INFO - PROCESS | 16858 | __end_tp_report 09:32:25 INFO - PROCESS | 16858 | __start_cc_report 09:32:25 INFO - PROCESS | 16858 | _x_x_mozilla_cycle_collect,1555 09:32:25 INFO - PROCESS | 16858 | __end_cc_report 09:32:25 INFO - PROCESS | 16858 | __startTimestamp1483032745815__endTimestamp 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | ------- Summary: start ------- 09:32:25 INFO - PROCESS | 16858 | Number of tests: 14 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 09:32:25 INFO - PROCESS | 16858 | Values: 112.0 111.0 111.0 111.0 111.0 111.0 111.0 110.0 113.0 111.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:124.00 stddev:14.12 (11.4%) stddev-sans-first:13.04 09:32:25 INFO - PROCESS | 16858 | Values: 149.0 124.0 117.0 155.0 119.0 120.0 122.0 124.0 143.0 119.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:1.51 (0.9%) stddev-sans-first:1.48 09:32:25 INFO - PROCESS | 16858 | Values: 160.0 161.0 163.0 165.0 162.0 160.0 161.0 162.0 161.0 161.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.00 stddev:13.22 (17.4%) stddev-sans-first:13.78 09:32:25 INFO - PROCESS | 16858 | Values: 75.0 76.0 75.0 109.0 76.0 75.0 77.0 76.0 105.0 76.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:82.00 stddev:15.87 (19.4%) stddev-sans-first:16.49 09:32:25 INFO - PROCESS | 16858 | Values: 82.0 81.0 110.0 82.0 82.0 81.0 103.0 82.0 82.0 125.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:32:25 INFO - PROCESS | 16858 | Values: 116.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:32:25 INFO - PROCESS | 16858 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 145.0 145.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.84 (0.9%) stddev-sans-first:0.88 09:32:25 INFO - PROCESS | 16858 | Values: 95.0 95.0 97.0 96.0 96.0 95.0 95.0 95.0 96.0 94.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:0.97 (1.9%) stddev-sans-first:0.88 09:32:25 INFO - PROCESS | 16858 | Values: 51.0 51.0 48.0 49.0 50.0 50.0 49.0 49.0 50.0 49.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.92 (1.6%) stddev-sans-first:0.93 09:32:25 INFO - PROCESS | 16858 | Values: 58.0 59.0 58.0 61.0 59.0 58.0 59.0 59.0 58.0 59.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:74.00 stddev:3.02 (4.1%) stddev-sans-first:3.18 09:32:25 INFO - PROCESS | 16858 | Values: 74.0 74.0 74.0 74.0 74.0 82.0 73.0 73.0 79.0 73.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:126.50 stddev:12.30 (9.7%) stddev-sans-first:12.98 09:32:25 INFO - PROCESS | 16858 | Values: 133.0 126.0 126.0 136.0 127.0 124.0 121.0 119.0 161.0 121.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:153.50 stddev:1.49 (1.0%) stddev-sans-first:1.58 09:32:25 INFO - PROCESS | 16858 | Values: 153.0 151.0 154.0 153.0 152.0 154.0 156.0 155.0 152.0 153.0 09:32:25 INFO - PROCESS | 16858 | 09:32:25 INFO - PROCESS | 16858 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.66 (2.7%) stddev-sans-first:1.76 09:32:25 INFO - PROCESS | 16858 | Values: 62.0 61.0 64.0 65.0 61.0 62.0 61.0 64.0 61.0 60.0 09:32:25 INFO - PROCESS | 16858 | -------- Summary: end -------- 09:32:25 INFO - PROCESS | 16858 | 09:32:26 INFO - TEST-INFO | 16858: exit 0 09:32:27 INFO - TEST-OK | kraken | took 59989ms 09:32:27 INFO - SUITE-END | took 1173s 09:32:27 INFO - Completed test suite (00:19:33) 09:32:27 INFO - javascript benchmark 09:32:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.0465558004807, 3163.019463966171, 3166.762492813038, 3181.490961446169, 3209.5988001499813, 5579.497845193931, 5683.5169010634045, 5727.6563406195655, 5730.255066841315, 5757.366689664144, 5581.832545023656, 5651.2653355063885, 5658.405867383927, 5666.490015898568, 5667.8583035424135, 5064.316317297173, 5107.872303192413, 5107.948920510803, 5151.381834179885, 5155.123628983063, 5007.148784706608, 5128.282040514331, 5179.792808287664, 5185.585153187736, 5216.113260745682, 4961.603071754251, 4980.377452818398, 4993.950060499403, 5015.247712843066, 5100.642954993152, 5131.948680513203, 5162.174052151638, 5166.638335316529, 5242.501962313574, 5252.8686782830355, 5062.443131255559, 5139.640225184238, 5197.818076367309, 5222.921656175123, 5250.107481728077, 5377.3009508764, 5422.75597598109, 5519.862003449905, 5546.112621980464, 5572.665640061609, 6010.729517171738, 6029.0353543433275, 6032.034874420116, 6136.263648362181, 6140.263168419773, 6170.105334726484, 6181.629102253879, 6183.9381606183815, 6187.783427580013, 6200.255969283715, 5456.972715136439, 5479.178123281539, 5543.501084902378, 5556.638818476786, 5596.58025648078, 6140.416660417253, 6151.7539298428455, 6174.691265436711, 6176.4441200292185, 6176.505879529627, 5907.202527658797, 5913.497352725067, 5916.526677865761, 5916.911246331309, 5919.4080591940465, 5785.566082543826, 5823.737931793079, 5995.910061349084, 6021.608595441282, 6070.908936365958, 6082.574219804571, 6114.755409783559, 6115.113308570321, 6116.510679145657, 6140.293866205354, 6121.081837724377, 6131.013867888268, 6144.8771024579255, 6149.13912052313, 6153.753849845956, 4533.637309015271, 4545.863624091283, 4608.331791890158, 4631.120087183424, 4707.693999889996, 4115.773632450184, 4132.504099508078, 4160.771157586301, 4170.249355116048, 4181.1010632714115, 5746.42535746422, 5788.0739081746715, 5813.360530341659, 5815.912761308584, 5823.388544202882, 5883.411658834082, 5937.614055086406, 6028.758849646053, 6082.939170608326, 6110.052941794029, 5770.42295770428, 5810.680412577349, 5819.796307129315, 5857.677827719431, 5859.1797148399255, 5306.6550674206055, 5325.094733895247, 5327.067763141435, 5340.813071542478, 5343.492368225013, 6030.065339872327, 6081.695915204311, 6286.214223222098, 6322.968385158045, 6339.746410143634, 5953.077273022688, 6051.7579296827635, 6063.909041364295, 6128.625347140905, 6142.498775697809, 6146.631202127886, 6149.046897730859, 6202.379762023762, 6203.503719702503, 6209.161763162096, 5681.687507187145, 5921.851953701192, 5921.940780592139, 5926.662523306401, 5951.315598706205, 5737.885242295214, 5874.5887787854435, 5879.0, 5910.645361278337, 6003.909941350796, 5803.970980145073, 5858.7070646468355, 5859.794907178229, 5861.765529378948, 5867.618604790675, 5850.824475265749, 5867.061270196748, 5871.970640146772, 5873.706314684334, 5874.265716785402, 5585.832425027255, 5590.776368945197, 5844.736986835687, 5885.17607534954, 5913.408659134052, 6067.683744968479, 6087.381936540344, 6103.755849765959, 6119.0821376792055, 6130.908036379547, 5622.100463925753, 5945.851353716192, 5946.702664866826, 5950.077737950624, 5967.373425790403], "value": 5563.783701045591, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.523613255197, 634.2959315160172, 647.058529839084, 650.6584043377229, 653.5392548253482, 37702.434463483034, 38597.61402385975, 38643.0, 38646.0, 38663.61336386639, 38264.23471530575, 38359.04102397434, 46566.53433465673, 47747.0, 47991.0, 9199.310051746112, 9316.347855650107, 9325.440473571574, 9325.766855828591, 9328.347015708903, 9305.813883722321, 9407.576659050359, 9415.952920235424, 9417.76455588609, 9422.0, 8615.65537378504, 8814.074522175175, 11078.778424431506, 11343.432828358591, 11344.48949797261, 11221.0, 11280.774384512304, 11282.548698052067, 11288.322700637988, 11307.208495405324, 11029.227954043221, 11111.277766945122, 11111.333320000826, 11113.499892504857, 11113.83329250062, 45751.77124114391, 46062.30906536405, 46144.53855461469, 46165.30752038722, 46197.53802461966, 3761.37937240355, 3765.4540091686827, 3772.1889793694486, 3788.7347885648014, 3866.0, 34123.4881476778, 34461.827690861384, 34474.13814654654, 34546.82726586376, 34725.30549388998, 4768.3085952536685, 4820.180569303192, 4825.1555977703765, 4833.081714474239, 4931.80272789088, 338476.0, 340258.0, 340382.0, 340654.0, 341138.0, 64321.356786431534, 64529.354706453276, 64654.0, 64751.352486474534, 64751.676241618494, 40648.79675601603, 41165.79417102955, 41376.1724765509, 44034.0, 68610.0, 16602.0, 16625.6674866502, 16630.916845415937, 16786.4124755633, 16794.832051679572, 12179.451924663412, 12229.14395992272, 12250.264984100982, 12355.6911077222, 12398.194117382522, 18368.724469132972, 18830.0, 20786.896065519577, 20903.68644470334, 21125.471863203544, 7203.71185152588, 7383.741569045058, 7547.132079810783, 7692.846143077107, 7735.9226407735205, 1066.9839952400564, 1094.070040465598, 1136.4431428599926, 1150.5742875136252, 1158.7914175448332, 35192.47211291832, 35270.8236458816, 35329.64670353315, 35385.82307088448, 35469.29061418757, 1146.9827952580717, 1154.4054811771944, 1179.6343133628432, 1184.117832214988, 1199.0707201918583, 18214.180361883748, 18258.269669213347, 18310.26758929628, 18478.722819157723, 18524.0, 534927.0, 538136.0, 538420.0, 539182.0, 539450.0, 63486.682566586875, 64378.0, 64414.0, 64415.355846440936, 64421.67789161024, 1397.797319388683, 1441.3081720774087, 1444.8627380398846, 1457.7230326238193, 1458.030409777486, 31950.6804931957, 33461.83269083639, 34021.65978340185, 34153.0, 34458.65541344655, 55966.0, 61068.38931610805, 63354.36645633485, 63439.0, 68198.0, 482349.0, 486945.0, 488029.0, 488570.0, 489543.0, 36584.45123323099, 47213.29180062371, 47696.28455573239, 48121.0, 48636.75681621569, 51049.0, 51088.23367649563, 52293.47706522886, 52632.47367526276, 53913.0, 43012.0, 44246.336304954806, 45266.32100518429, 45281.32078018898, 45789.31316030196, 43950.0, 44868.32697509605, 45476.31785523286, 45869.0, 46311.30533042074, 44322.556774433135, 45175.54824451714, 45211.0, 45245.77377113225, 47337.0, 37536.624633753316, 38830.805845970586, 40104.39843402409, 40244.195116096926, 40472.39291410572, 29838.10485685433, 29946.550801738427, 30154.0, 30200.395992079597, 30434.84782576073], "value": 21682.683131137295, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.1153462382755, 415.5159239485999, 415.6986185015862, 431.2926800047921, 442.84943119339437, 15031.924840375796, 16282.26729797159, 20028.499287517818, 20442.182312707475, 20497.897510512463, 19519.316823911166, 20002.0, 20059.398218053444, 20246.088925998327, 20386.388408347735, 5843.444872737094, 5854.209681692981, 5889.8822023559505, 5951.970240148793, 5955.898158840607, 4419.624331931774, 5868.149118377833, 5981.252343457068, 5983.162357269964, 5990.8801823963495, 4676.766161691902, 6527.0, 6567.376099270586, 6685.0, 6695.46436285096, 6413.0059840724525, 6594.406503414716, 6609.636469994149, 6666.100076489684, 6676.599404035749, 5853.941460585383, 5902.3507414184405, 5903.645781253139, 5905.4980326672285, 5905.704714764244, 22013.559728805496, 22247.44381390462, 23891.283261502183, 24588.139415120564, 24755.133570324953, 2464.5194187133516, 2474.01039584166, 2513.843021379095, 2719.3745438549245, 2770.98858916494, 14143.504977325743, 14801.703965920622, 14812.851871481364, 15049.172295523742, 15150.696986060328, 2037.7350944377283, 2041.6018876319124, 2043.7240972468676, 2096.5387614724737, 2103.5897999890026, 73783.0, 75750.0, 75825.62087189528, 76901.61549192274, 86536.0, 11794.469248883821, 11842.585509507298, 11999.940000299943, 12083.57707480234, 12167.209131406431, 16999.830001700073, 17007.06461144649, 17026.318947241973, 17036.914815425844, 17040.48878533646, 8074.152214017561, 8384.83230335402, 8468.195521425454, 8528.147185281423, 8556.529390883437, 7849.803754906173, 7895.407844411578, 7908.604569771488, 8083.514989100673, 8113.350931925432, 5041.117804384218, 5155.046316431472, 5155.74221288934, 5211.2443695663915, 5225.1378522543355, 2121.2787652198326, 2138.882361470134, 2138.957220855605, 2170.2838063439026, 2255.593993081261, 900.2213085680839, 1032.3341444768082, 1032.83990981398, 1036.828923227659, 1052.1162223732101, 19805.60388792216, 20114.497137571678, 20196.798032019786, 20560.58878822445, 20695.689564656543, 1025.2874252394663, 1041.1982773264695, 1055.4933631856752, 1061.0344586426315, 1062.946852657364, 10765.407902565277, 11341.149413793999, 11380.203385763083, 11454.828177577343, 11517.539298428137, 424409.0, 537421.0, 540989.0, 552673.0, 553832.0, 67705.66147169331, 103212.48393757983, 105320.0, 105790.0, 108179.0, 1049.994750026245, 1065.9413732244645, 1085.8371244313416, 1092.4100985467758, 1094.195766111907, 18369.0, 18666.440006799818, 18745.437636870916, 18983.90508047451, 19398.321058762875, 31007.844960775954, 42395.364069539595, 43882.56117438785, 44044.33933490936, 44476.332855006556, 61710.38289617226, 62401.943873648066, 62813.05780413206, 63594.3640563607, 63718.86694257924, 8643.697470588499, 9151.496667683341, 9281.907180928105, 9348.813023739624, 9357.953210234178, 10264.127549158406, 12714.745705085661, 13242.139260948008, 13245.867541324464, 13686.247256400993, 11673.766524669289, 11838.526458941546, 11919.165658403828, 11941.16411851162, 12030.518779248732, 11224.719382015515, 11279.548818047186, 11380.829287560859, 11388.259763115371, 12181.0, 11708.356040417859, 11934.104942129548, 11942.223755455867, 11951.521939122686, 12181.208221465577, 9016.639334426813, 9051.185393314634, 9082.318826087938, 9085.863712044456, 9141.588628511867, 11814.704632384259, 12036.398180091135, 12050.457729402022, 12140.75718485643, 12621.74756504883], "value": 9732.779642629266, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.7869080292558, 1523.9695206095876, 1565.4912153550097, 1744.2674076887706, 1921.625283069802, 8458.788530286745, 9351.6726914558, 9651.903480965188, 9665.613375464973, 9962.302638815268, 9625.470599117045, 9737.07497787709, 9744.220462363011, 9744.561494732736, 9746.561404736785, 2413.0709676774436, 2439.3657649011207, 2441.4750828571855, 2442.3283597010786, 2444.4866578018637, 2363.3500787283547, 2377.988110059447, 2408.2414039577475, 2415.1184817541557, 2417.2748175547354, 2416.1301931304715, 2432.8905199265987, 2441.2676197140877, 2466.5560199164142, 2477.1206221791276, 2415.7221919479307, 2419.1895714935517, 2422.2854257993936, 2445.6698345723457, 2499.737527559598, 2414.8671823049726, 2422.854628722282, 2425.50277193175, 2429.7934675552424, 2431.6109422492495, 8515.787105322355, 8782.692605758768, 9740.269479789044, 9759.0, 9795.90204097957, 5069.44236134025, 5231.084560201987, 5615.326160860682, 5789.507891829199, 5797.36229014808, 3673.4673472346626, 3680.9079773005888, 3724.683401910841, 3743.1577894973684, 3756.492873462075, 1196.3719047500044, 1200.4417945655225, 1201.0571701214506, 1214.4959841665698, 1221.2306247064382, 13267.86732132676, 13289.667758306023, 13299.734005319937, 13304.600861974157, 13315.933420332933, 4880.536349046835, 4940.851774446773, 5046.772895219724, 5067.239914012927, 5102.795888164506, 11520.193586448868, 11544.0, 11556.075513958864, 11572.18994670365, 11575.88424115765, 5799.449052340021, 5801.361850196475, 5821.039528477754, 5826.0, 5831.183634291203, 5262.842114736564, 5309.57523398135, 5344.43883392246, 5364.517193452608, 5367.5169234769055, 4304.91390172201, 4357.542458041923, 4362.345648152803, 4391.494978077562, 4407.118576284756, 1365.0853927868352, 1381.5648070857649, 1390.5133203378737, 1393.2476462710024, 1404.1785555449972, 985.0887928665956, 1035.471909326249, 1041.8176819056634, 1049.8635177427038, 1055.567217440861, 6191.279690885357, 6285.748570057238, 6617.669116544153, 6674.966625166843, 6887.690053947585, 469.72051629280526, 480.67554400779335, 482.28621639972334, 485.9101066302745, 488.41634247074404, 4354.695171337976, 4448.7330760153845, 4470.664700147502, 4473.4408198975125, 4485.887852803706, 10184.898151018544, 10517.158627309796, 11501.827472587918, 11852.051835853112, 11968.401579921143, 12295.508179672714, 12314.692132696755, 12318.507259709511, 12323.876761232274, 12330.938345308216, 9028.548572571344, 9140.862887056699, 9145.405548639317, 9150.130737579919, 9175.357724959322, 6395.936040639534, 6400.71196796136, 6540.574862633914, 6600.60396376219, 6680.298568650222, 10916.672499825017, 11290.43547822622, 11303.943480282545, 11307.378094204898, 11315.660530184108, 11139.108871290115, 11189.229320404836, 11315.943420282845, 11324.94337528307, 11459.770804583695, 8508.829823403621, 8509.0, 8586.270167035622, 8596.441231319945, 8599.226069653761, 9212.631494740135, 9370.156685898302, 9373.578188981648, 9377.062293770568, 9386.296027797805, 8329.583520824055, 8418.494890306602, 8549.87175192385, 8576.185262400062, 8709.956450217709, 8208.876866847122, 8302.584870756316, 8305.584720763816, 8434.367422443218, 8537.786555336166, 7454.142773581108, 7550.66022029021, 7626.275503827128, 7682.423818213544, 7761.18507556721, 5363.570914326767, 5487.972560137334, 5586.86032849182, 5613.691246981455, 5662.518685911744, 5475.288212532345, 5575.944240557705, 5668.51817595509, 5742.0, 5827.038538641077], "value": 4927.01323955442, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.7333093521586, 339.9422098243299, 345.56679390257807, 350.58630815637537, 353.6463536463536, 5607.149640618596, 5610.523105536029, 5702.230198923149, 5703.31560212774, 5716.914246286298, 5315.309009828727, 5335.866603334918, 5386.380566234884, 5465.344158700951, 5622.971885140579, 4338.980339620187, 4342.97828510857, 4364.498082720496, 4368.672349573779, 4390.846320378788, 4346.109047645241, 4346.6739994500385, 4381.9342709859375, 4388.517263101056, 4393.582609652094, 4089.44792453018, 4302.698811083225, 4326.264535029037, 4326.372675961984, 4330.155619654169, 4276.978615106936, 4289.549597292286, 4297.03316753731, 4297.613214810682, 4300.172426718632, 3791.3554695701796, 3806.448065030555, 3807.58116607173, 3808.5620153682403, 3810.542734871833, 5249.370075590915, 5352.330958630171, 5363.6245462817615, 5486.835394938158, 5509.3664228613825, 4936.7531623419045, 4948.87627809304, 4966.90066198674, 4969.950300497021, 4976.850694479135, 5796.362400135981, 5809.535237181015, 5834.941650583482, 5842.211301474289, 5844.26946631671, 4087.570805065485, 4191.203671302443, 4200.613797446836, 4214.599613036757, 4230.830766769325, 6019.819405417845, 6046.455818976313, 6047.395260473917, 6069.241344831896, 6071.544634152412, 4196.475440569929, 4197.286461301525, 4210.642095421862, 4226.0, 4232.25935461293, 4213.325867861127, 4240.767423958274, 4293.527711951683, 4295.591918767712, 4310.396544483775, 4886.804527818847, 4917.5082491751255, 4920.532549407801, 4921.8523444296725, 4927.77824997876, 5832.88334233313, 5854.2389489366105, 5945.137954996501, 5946.524278057745, 5952.523798096142, 1427.1192521576234, 1458.3884807287793, 1490.9403623855142, 1495.0730547060891, 1498.3332417074516, 936.3773090894615, 937.1277820981074, 947.2185447006248, 947.8909925358672, 959.9200898988638, 4965.503449655005, 4979.975100124476, 4991.0, 5004.024215277986, 5005.6996580205305, 5172.586193104543, 5196.0, 5198.7140707260705, 5208.874675194853, 5234.2933703949475, 4850.264977125447, 4881.95118048822, 4982.725950072709, 5012.699238045729, 5021.548060674484, 2776.8889244429997, 2778.3470884342164, 2789.2887313735273, 2790.725558669678, 2795.8741856616502, 5334.306540149756, 5362.552110930027, 5366.892662146735, 5394.865128371822, 5404.9192859390905, 4990.3762029746285, 5299.330710826078, 5308.708021058879, 5365.865853353619, 5419.214213938957, 5320.787168513293, 5335.33308336458, 5337.973310133424, 5348.91976620351, 5372.489613486712, 5275.702685821361, 5295.867603309794, 5309.442508536548, 5311.681299122065, 5314.1497360422145, 5255.527002569787, 5568.916466252928, 5963.075723262901, 6023.186869772525, 6026.578139530367, 6170.413810687977, 6235.469985051322, 6242.188515492957, 6262.373762623702, 6271.717772700331, 4134.524529679005, 4341.9565804341555, 4377.627901628397, 4386.71486353386, 4412.82348706061, 5977.505623594101, 5991.580589358703, 6003.669798161143, 6005.90991135142, 6092.573519853568, 5829.213056237354, 5867.73595188209, 5873.765049398147, 5879.235699359056, 5890.410958904075, 5580.414056524007, 5803.970980145241, 5839.270091238595, 5869.148973398748, 5884.176215329771, 5793.884122317446, 5832.912506312324, 5860.589758717019, 5860.619059761102, 5864.530837533072, 5643.435656434323, 5921.970390148194, 5938.4061593842, 5938.6436813791315, 5939.000804593653, 5774.48029677331, 5781.005553084179, 5796.782675638163, 5798.623089499338, 5803.970980145073], "value": 4318.146839666103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.936725061995, 823.8228780812128, 868.9782755431113, 878.8154487557615, 889.3996552327186, 15876.206189690512, 16880.1559922004, 16896.408625698103, 16909.323627054902, 16914.0, 15670.764938525932, 15850.84149158508, 15855.60360990973, 15858.127802970832, 15882.761758573602, 704.7744721689074, 728.406348825708, 858.1632907914795, 879.7712594725353, 880.8590625499921, 878.0122362342365, 880.009988762642, 880.3793325705394, 880.6829541365104, 881.3081730841291, 915.3363811236867, 915.7344370132654, 916.8303863785188, 917.3991035871514, 917.554985831871, 908.5956749246587, 910.4628269321132, 911.895132059815, 915.0163574157774, 915.3180880244228, 671.8219671786981, 675.5473832532215, 676.3980057748607, 677.1366507702669, 677.4512644757713, 16365.099919504542, 16374.672506549921, 16502.092384918826, 16512.42206522766, 16514.174291285388, 16283.837161628351, 17106.743398849027, 17226.178702594727, 17647.558811029827, 17870.46388608344, 13131.409086591126, 13235.536756213487, 13274.402651880591, 13291.0, 13340.33298335089, 4061.756294622332, 4085.816138273785, 4120.670346372283, 4156.833726650931, 4250.022494826177, 57778.422215777726, 59858.700706496624, 85794.5710271451, 86441.0, 87074.0, 11683.0, 11902.464389102512, 12600.077575661118, 12676.176048557, 13149.523852059143, 21828.345149645535, 22111.33665990023, 22337.664935025987, 22356.217532386287, 22517.0, 8771.429857059271, 8874.733757987271, 9188.586513606904, 9246.768830779285, 9477.431354118775, 5147.974260128675, 5278.81524146653, 5316.946830531645, 5395.163749618815, 5399.055165346049, 3685.7235707322056, 3810.504634397566, 3820.9235815283537, 3832.9808350958065, 3846.8653597123966, 650.9544331896816, 659.9406053455116, 668.6188872342718, 670.5674839728348, 673.727140508095, 695.7147569496482, 730.0582248898967, 774.5972094510823, 790.5651891459675, 804.2480280937342, 10142.137918277029, 10231.181505479693, 10316.535755891004, 10503.369797812156, 10689.358638481715, 688.1466980943694, 727.1310783613636, 741.5587725303436, 753.1113286322193, 769.6882762481204, 5056.64603477753, 5174.8965020700125, 5275.765045691124, 5281.181416880389, 5325.4940780625775, 100032.0, 128574.0, 131848.3407582975, 132165.0, 132914.0, 99557.0, 106498.46750766352, 107859.38210926842, 110533.0, 111395.44302278437, 74187.0, 81680.59159704164, 97375.51312243535, 102181.0, 105312.47343763386, 9884.901150988399, 10017.39895606266, 10198.490075496344, 10216.489175541343, 10230.693079207636, 61147.0, 61416.38583614286, 61807.0, 62347.0, 62462.37537624747, 87150.56424717836, 88209.55895220482, 89384.08387538488, 90252.0, 90847.5491470514, 11677.0, 12401.875981240071, 12668.366581670694, 12831.03767217481, 13352.599422017354, 14855.182964937032, 14866.776998345249, 14894.106353618818, 15053.849461505682, 16282.51152465428, 13647.522336718168, 13949.511767088103, 14281.285935702965, 14357.784633230302, 14548.85451145475, 12552.623421297376, 12856.678583035498, 12960.546380876624, 13722.588322350346, 14230.217338046506, 13233.0, 13565.525206617722, 14081.577552673436, 14126.646833828825, 14304.499342523379, 10592.629257975934, 10850.131989440668, 11035.172362072715, 11238.606648767254, 11273.774524509628, 11098.2786118902, 11204.327740335606, 11387.715307117389, 11429.714257143638, 11451.141164412536], "value": 8080.440350120225, "unit": "score"}], "name": "dromaeo_css", "value": 7659.143572463306, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 110.0, 113.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 117.0, 155.0, 119.0, 120.0, 122.0, 124.0, 143.0, 119.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 165.0, 162.0, 160.0, 161.0, 162.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 109.0, 76.0, 75.0, 77.0, 76.0, 105.0, 76.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 110.0, 82.0, 82.0, 81.0, 103.0, 82.0, 82.0, 125.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 48.0, 49.0, 50.0, 50.0, 49.0, 49.0, 50.0, 49.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 61.0, 59.0, 58.0, 59.0, 59.0, 58.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 74.0, 74.0, 82.0, 73.0, 73.0, 79.0, 73.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 126.0, 126.0, 136.0, 127.0, 124.0, 121.0, 119.0, 161.0, 121.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 154.0, 153.0, 152.0, 154.0, 156.0, 155.0, 152.0, 153.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 64.0, 65.0, 61.0, 62.0, 61.0, 64.0, 61.0, 60.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1460.8999999999999, "alertThreshold": 2.0}]} 09:32:27 INFO - Return code: 0 09:32:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:32:27 INFO - # TBPL SUCCESS # 09:32:27 INFO - Running post-action listener: _resource_record_post_action 09:32:27 INFO - [mozharness: 2016-12-29 17:32:27.620451Z] Finished run-tests step (success) 09:32:27 INFO - Running post-run listener: _resource_record_post_run 09:32:27 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 335478784; Read time: 56; Write time: 190492 09:32:27 INFO - TinderboxPrint: CPU usage
13.0% 09:32:27 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 56 09:32:27 INFO - TinderboxPrint: I/O write bytes / time
335,478,784 / 190,492 09:32:27 INFO - TinderboxPrint: CPU idle
8,252.0 (87.0%) 09:32:27 INFO - TinderboxPrint: CPU user
1,186.8 (12.5%) 09:32:27 INFO - TinderboxPrint: Swap in / out
0 / 0 09:32:27 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 117112832; Read time: 0; Write time: 63300 09:32:27 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218365952; Read time: 12; Write time: 127192 09:32:28 INFO - Running post-run listener: _upload_blobber_files 09:32:28 INFO - Blob upload gear active. 09:32:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:32:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:32:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:32:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:32:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:32:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:29 INFO - (blobuploader) - INFO - Done attempting. 09:32:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:32:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:32:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:32 INFO - (blobuploader) - INFO - Done attempting. 09:32:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:32:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:34 INFO - (blobuploader) - INFO - Done attempting. 09:32:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:32:34 INFO - Return code: 0 09:32:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:32:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:32:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9830c67aae12bee106f2d51463245634350e6437d291235818a39ff8b4bce69b6e634d3d8685027ab41912fce7d2e35e3fb8c743afed4c3813d21f0e70161774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f5480f7b1b1c8be08d54747954001217f07eb6d788fd2dfbaca9ccee82d1ffab15c6bc11257c6bf223ac37119f575a2d97882b44da177c39499144635fede2d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09471e84cfab1e3a2fa7fb893100a2a3757b56dcacd1f3f67ddda73641d8928c96d64a8a19148324bc9772daaff33b0848a0b653887e845613b366c6ca16b28f"} 09:32:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:32:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:32:34 INFO - Contents: 09:32:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9830c67aae12bee106f2d51463245634350e6437d291235818a39ff8b4bce69b6e634d3d8685027ab41912fce7d2e35e3fb8c743afed4c3813d21f0e70161774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f5480f7b1b1c8be08d54747954001217f07eb6d788fd2dfbaca9ccee82d1ffab15c6bc11257c6bf223ac37119f575a2d97882b44da177c39499144635fede2d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09471e84cfab1e3a2fa7fb893100a2a3757b56dcacd1f3f67ddda73641d8928c96d64a8a19148324bc9772daaff33b0848a0b653887e845613b366c6ca16b28f"} 09:32:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:32:34 INFO - Copying logs to upload dir... 09:32:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:32:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.487380 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-12-29 09:32:33.414010) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 09:32:33.419079) ========= 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=cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239 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/9830c67aae12bee106f2d51463245634350e6437d291235818a39ff8b4bce69b6e634d3d8685027ab41912fce7d2e35e3fb8c743afed4c3813d21f0e70161774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f5480f7b1b1c8be08d54747954001217f07eb6d788fd2dfbaca9ccee82d1ffab15c6bc11257c6bf223ac37119f575a2d97882b44da177c39499144635fede2d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09471e84cfab1e3a2fa7fb893100a2a3757b56dcacd1f3f67ddda73641d8928c96d64a8a19148324bc9772daaff33b0848a0b653887e845613b366c6ca16b28f"} build_url:https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011974 build_url: 'https://queue.taskcluster.net/v1/task/PC9tfmxkTvmy5S3key8n_Q/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/9830c67aae12bee106f2d51463245634350e6437d291235818a39ff8b4bce69b6e634d3d8685027ab41912fce7d2e35e3fb8c743afed4c3813d21f0e70161774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f5480f7b1b1c8be08d54747954001217f07eb6d788fd2dfbaca9ccee82d1ffab15c6bc11257c6bf223ac37119f575a2d97882b44da177c39499144635fede2d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09471e84cfab1e3a2fa7fb893100a2a3757b56dcacd1f3f67ddda73641d8928c96d64a8a19148324bc9772daaff33b0848a0b653887e845613b366c6ca16b28f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 09:32:33.444467) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:32:33.444777) ========= 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=cbe409c6de289db6ac707de3000001d4-1483031250.852403-880245239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:32:33.529265) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 09:32:33.529602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 09:32:33.530030) ========= ========= Total master_lag: 0.27 =========