builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1479415052.12 results: success (0) buildid: 20161117094728 builduid: f237af4c3faa4ce18e583f639f7b8b4b revision: 690ee038613a3b81fb78c52bbf9c4024db5c91d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.116956) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.117421) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.117745) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.132538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.132899) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-17 12:37:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.0K=0.1s 2016-11-17 12:37:32 (89.0 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.269051 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.416041) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.416362) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016112 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.466019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:32.466379) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 690ee038613a3b81fb78c52bbf9c4024db5c91d0 --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 690ee038613a3b81fb78c52bbf9c4024db5c91d0 --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=e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-17 12:37:32,958 truncating revision to first 12 chars 2016-11-17 12:37:32,959 Setting DEBUG logging. 2016-11-17 12:37:32,959 attempt 1/10 2016-11-17 12:37:32,959 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/690ee038613a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 12:37:33,266 unpacking tar archive at: mozilla-beta-690ee038613a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642676 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:33.123535) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:33.123998) ========= script_repo_revision: 690ee038613a3b81fb78c52bbf9c4024db5c91d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:33.124564) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:33.124941) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 12:37:33.136409) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-11-17 12:37:33.136755) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:37:33 INFO - MultiFileLogger online at 20161117 12:37:33 in /builds/slave/test 12:37:33 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 12:37:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:37:33 INFO - {'append_to_log': False, 12:37:33 INFO - 'base_work_dir': '/builds/slave/test', 12:37:33 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:37:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:37:33 INFO - 'branch': 'Mozilla-Beta', 12:37:33 INFO - 'buildbot_json_path': 'buildprops.json', 12:37:33 INFO - 'config_files': ('talos/linux_config.py',), 12:37:33 INFO - 'default_actions': ('clobber', 12:37:33 INFO - 'read-buildbot-config', 12:37:33 INFO - 'download-and-extract', 12:37:33 INFO - 'populate-webroot', 12:37:33 INFO - 'create-virtualenv', 12:37:33 INFO - 'install', 12:37:33 INFO - 'run-tests'), 12:37:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:37:33 INFO - 'download_minidump_stackwalk': True, 12:37:33 INFO - 'download_symbols': 'ondemand', 12:37:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:37:33 INFO - 'tooltool.py': '/tools/tooltool.py', 12:37:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:37:33 INFO - '/tools/misc-python/virtualenv.py')}, 12:37:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:37:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:37:33 INFO - 'installer_path': 'installer.exe', 12:37:33 INFO - 'log_level': 'info', 12:37:33 INFO - 'log_name': 'talos', 12:37:33 INFO - 'log_to_console': True, 12:37:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:37:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:37:33 INFO - 'opt_config_files': (), 12:37:33 INFO - 'pip_index': False, 12:37:33 INFO - 'sps_profile': False, 12:37:33 INFO - 'sps_profile_interval': 0, 12:37:33 INFO - 'suite': 'dromaeojs', 12:37:33 INFO - 'system_bits': '32', 12:37:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:37:33 INFO - 'title': 'talos-linux64-ix-006', 12:37:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:37:33 INFO - 'use_talos_json': True, 12:37:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:37:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:37:33 INFO - 'work_dir': 'build'} 12:37:33 INFO - [mozharness: 2016-11-17 20:37:33.727840Z] Running clobber step. 12:37:33 INFO - Running pre-action listener: _resource_record_pre_action 12:37:33 INFO - Running main action method: clobber 12:37:33 INFO - rmtree: /builds/slave/test/build 12:37:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:37:34 INFO - Running post-action listener: _resource_record_post_action 12:37:34 INFO - [mozharness: 2016-11-17 20:37:34.202839Z] Finished clobber step (success) 12:37:34 INFO - [mozharness: 2016-11-17 20:37:34.202939Z] Running read-buildbot-config step. 12:37:34 INFO - Running pre-action listener: _resource_record_pre_action 12:37:34 INFO - Running main action method: read_buildbot_config 12:37:34 INFO - Using buildbot properties: 12:37:34 INFO - { 12:37:34 INFO - "project": "", 12:37:34 INFO - "product": "firefox", 12:37:34 INFO - "script_repo_revision": "production", 12:37:34 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:37:34 INFO - "repository": "", 12:37:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:37:34 INFO - "buildid": "20161117094728", 12:37:34 INFO - "pgo_build": "True", 12:37:34 INFO - "basedir": "/builds/slave/test", 12:37:34 INFO - "buildnumber": 16, 12:37:34 INFO - "slavename": "talos-linux64-ix-006", 12:37:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:37:34 INFO - "platform": "ubuntu64_hw", 12:37:34 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:37:34 INFO - "branch": "mozilla-beta", 12:37:34 INFO - "repo_path": "releases/mozilla-beta", 12:37:34 INFO - "revision": "690ee038613a3b81fb78c52bbf9c4024db5c91d0", 12:37:34 INFO - "stage_platform": "linux64-pgo", 12:37:34 INFO - "builduid": "f237af4c3faa4ce18e583f639f7b8b4b", 12:37:34 INFO - "slavebuilddir": "test" 12:37:34 INFO - } 12:37:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 12:37:34 INFO - Running post-action listener: _resource_record_post_action 12:37:34 INFO - [mozharness: 2016-11-17 20:37:34.205673Z] Finished read-buildbot-config step (success) 12:37:34 INFO - [mozharness: 2016-11-17 20:37:34.205765Z] Running download-and-extract step. 12:37:34 INFO - Running pre-action listener: _resource_record_pre_action 12:37:34 INFO - Running main action method: download_and_extract 12:37:34 INFO - mkdir: /builds/slave/test/build/tests 12:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:34 INFO - trying https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 12:37:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/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 12:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/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 12:37:34 INFO - Downloaded 1327 bytes. 12:37:34 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 12:37:34 INFO - Using the following test package requirements: 12:37:34 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 12:37:34 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:37:34 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 12:37:34 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'jsshell-linux-x86_64.zip'], 12:37:34 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:37:34 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 12:37:34 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 12:37:34 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 12:37:34 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:37:34 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 12:37:34 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:37:34 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:37:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 12:37:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:37:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 12:37:35 INFO - Expected file size: 21962757 12:37:39 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 12:37:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 12:37:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:37:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 12:37:40 INFO - Expected file size: 21962757 12:37:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 12:37:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:37:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 12:37:45 INFO - Expected file size: 14414089 12:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:45 INFO - trying https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 12:37:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/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 12:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/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 12:37:47 INFO - Downloaded 56956958 bytes. 12:37:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 12:37:47 INFO - mkdir: /builds/slave/test/properties 12:37:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:37:47 INFO - Writing to file /builds/slave/test/properties/build_url 12:37:47 INFO - Contents: 12:37:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 12:37:47 INFO - Running post-action listener: _resource_record_post_action 12:37:47 INFO - Running post-action listener: set_extra_try_arguments 12:37:47 INFO - [mozharness: 2016-11-17 20:37:47.718484Z] Finished download-and-extract step (success) 12:37:47 INFO - [mozharness: 2016-11-17 20:37:47.718713Z] Running populate-webroot step. 12:37:47 INFO - Running pre-action listener: _resource_record_pre_action 12:37:47 INFO - Running main action method: populate_webroot 12:37:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:37:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:37:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:37:47 INFO - '--tppagecycles', 12:37:47 INFO - '7'], 12:37:47 INFO - 'tests': ['tsvgm']}}, 12:37:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:37:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:37:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:37:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:37:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:37:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:37:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['damp', 'tps']}, 12:37:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'tests': ['damp', 'tps']}, 12:37:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['dromaeo_dom']}, 12:37:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:37:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['basic_compositor_video']}, 12:37:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:37:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['a11yr', 12:37:47 INFO - 'ts_paint', 12:37:47 INFO - 'tpaint', 12:37:47 INFO - 'sessionrestore', 12:37:47 INFO - 'sessionrestore_no_auto_restore', 12:37:47 INFO - 'tabpaint']}, 12:37:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:37:47 INFO - 'ts_paint', 12:37:47 INFO - 'tpaint', 12:37:47 INFO - 'sessionrestore', 12:37:47 INFO - 'sessionrestore_no_auto_restore', 12:37:47 INFO - 'tabpaint']}, 12:37:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:37:47 INFO - 'ts_paint', 12:37:47 INFO - 'tpaint', 12:37:47 INFO - 'sessionrestore', 12:37:47 INFO - 'sessionrestore_no_auto_restore', 12:37:47 INFO - 'tabpaint']}, 12:37:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:37:47 INFO - 'ts_paint', 12:37:47 INFO - 'tpaint', 12:37:47 INFO - 'sessionrestore', 12:37:47 INFO - 'sessionrestore_no_auto_restore', 12:37:47 INFO - 'tabpaint']}, 12:37:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['a11yr', 12:37:47 INFO - 'ts_paint', 12:37:47 INFO - 'tpaint', 12:37:47 INFO - 'sessionrestore', 12:37:47 INFO - 'sessionrestore_no_auto_restore', 12:37:47 INFO - 'tabpaint']}, 12:37:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['a11yr', 12:37:47 INFO - 'ts_paint', 12:37:47 INFO - 'tpaint', 12:37:47 INFO - 'sessionrestore', 12:37:47 INFO - 'sessionrestore_no_auto_restore', 12:37:47 INFO - 'tabpaint']}, 12:37:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['tsvgx', 12:37:47 INFO - 'tsvgr_opacity', 12:37:47 INFO - 'tart', 12:37:47 INFO - 'tscrollx', 12:37:47 INFO - 'cart']}, 12:37:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:37:47 INFO - 'tsvgr_opacity', 12:37:47 INFO - 'tart', 12:37:47 INFO - 'tscrollx', 12:37:47 INFO - 'cart']}, 12:37:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'talos_options': ['--disable-e10s'], 12:37:47 INFO - 'tests': ['tp5o']}, 12:37:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'tests': ['tp5o']}, 12:37:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'talos_options': ['--disable-e10s', 12:37:47 INFO - '--xperf_path', 12:37:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:37:47 INFO - 'tests': ['tp5n']}, 12:37:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:37:47 INFO - 'talos_options': ['--xperf_path', 12:37:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:37:47 INFO - 'tests': ['tp5n']}}, 12:37:47 INFO - 'talos.zip': {'path': '', 12:37:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:37:47 INFO - Running post-action listener: _resource_record_post_action 12:37:47 INFO - [mozharness: 2016-11-17 20:37:47.738909Z] Finished populate-webroot step (success) 12:37:47 INFO - [mozharness: 2016-11-17 20:37:47.739003Z] Running create-virtualenv step. 12:37:47 INFO - Running pre-action listener: _resource_record_pre_action 12:37:47 INFO - Running main action method: create_virtualenv 12:37:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:37:47 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 12:37:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:37:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:37:47 INFO - Using real prefix '/usr' 12:37:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:37:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:37:50 INFO - Installing pip.................done. 12:37:50 INFO - Return code: 0 12:37:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:37:50 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')]} 12:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:50 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')]} 12:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:50 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:37:50 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 12:37:50 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 12:37:50 INFO - Using env: {'DISPLAY': ':0', 12:37:50 INFO - 'HOME': '/home/cltbld', 12:37:50 INFO - 'LANG': 'en_US.UTF-8', 12:37:50 INFO - 'LANGUAGE': 'en_US:en', 12:37:50 INFO - 'LOGNAME': 'cltbld', 12:37:50 INFO - 'MAIL': '/var/mail/cltbld', 12:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:50 INFO - 'MOZ_NO_REMOTE': '1', 12:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:37:50 INFO - 'NO_EM_RESTART': '1', 12:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:50 INFO - 'PWD': '/builds/slave/test', 12:37:50 INFO - 'SHELL': '/bin/bash', 12:37:50 INFO - 'SHLVL': '1', 12:37:50 INFO - 'TERM': 'linux', 12:37:50 INFO - 'TMOUT': '86400', 12:37:50 INFO - 'USER': 'cltbld', 12:37:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:50 INFO - '_': '/tools/buildbot/bin/python'} 12:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:50 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 12:37:50 INFO - Cleaning up... 12:37:50 INFO - Return code: 0 12:37:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:37:50 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')]} 12:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:50 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')]} 12:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:50 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:37:50 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 12:37:50 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 12:37:50 INFO - Using env: {'DISPLAY': ':0', 12:37:50 INFO - 'HOME': '/home/cltbld', 12:37:50 INFO - 'LANG': 'en_US.UTF-8', 12:37:50 INFO - 'LANGUAGE': 'en_US:en', 12:37:50 INFO - 'LOGNAME': 'cltbld', 12:37:50 INFO - 'MAIL': '/var/mail/cltbld', 12:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:50 INFO - 'MOZ_NO_REMOTE': '1', 12:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:37:50 INFO - 'NO_EM_RESTART': '1', 12:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:50 INFO - 'PWD': '/builds/slave/test', 12:37:50 INFO - 'SHELL': '/bin/bash', 12:37:50 INFO - 'SHLVL': '1', 12:37:50 INFO - 'TERM': 'linux', 12:37:50 INFO - 'TMOUT': '86400', 12:37:50 INFO - 'USER': 'cltbld', 12:37:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:50 INFO - '_': '/tools/buildbot/bin/python'} 12:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:50 INFO - Downloading/unpacking psutil>=3.1.1 12:37:50 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 12:37:50 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 12:37:50 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 12:37:50 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 12:37:50 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 12:37:50 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 12:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:37:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:37:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:37:52 INFO - Installing collected packages: psutil 12:37:52 INFO - Running setup.py install for psutil 12:37:52 INFO - building 'psutil._psutil_linux' extension 12:37:52 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 12:37:53 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 12:37:53 INFO - building 'psutil._psutil_posix' extension 12:37:53 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 12:37:53 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 12:37:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:37:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:37:53 INFO - Successfully installed psutil 12:37:53 INFO - Cleaning up... 12:37:53 INFO - Return code: 0 12:37:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:37: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')]} 12:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37: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')]} 12:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:53 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:37:53 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 12:37:53 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 12:37:53 INFO - Using env: {'DISPLAY': ':0', 12:37:53 INFO - 'HOME': '/home/cltbld', 12:37:53 INFO - 'LANG': 'en_US.UTF-8', 12:37:53 INFO - 'LANGUAGE': 'en_US:en', 12:37:53 INFO - 'LOGNAME': 'cltbld', 12:37:53 INFO - 'MAIL': '/var/mail/cltbld', 12:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:53 INFO - 'MOZ_NO_REMOTE': '1', 12:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:37:53 INFO - 'NO_EM_RESTART': '1', 12:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:53 INFO - 'PWD': '/builds/slave/test', 12:37:53 INFO - 'SHELL': '/bin/bash', 12:37:53 INFO - 'SHLVL': '1', 12:37:53 INFO - 'TERM': 'linux', 12:37:53 INFO - 'TMOUT': '86400', 12:37:53 INFO - 'USER': 'cltbld', 12:37:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:53 INFO - '_': '/tools/buildbot/bin/python'} 12:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:37:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:37:55 INFO - Installing collected packages: mozsystemmonitor 12:37:55 INFO - Running setup.py install for mozsystemmonitor 12:37:55 INFO - Successfully installed mozsystemmonitor 12:37:55 INFO - Cleaning up... 12:37:55 INFO - Return code: 0 12:37:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:55 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:37:55 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 12:37:55 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 12:37:55 INFO - Using env: {'DISPLAY': ':0', 12:37:55 INFO - 'HOME': '/home/cltbld', 12:37:55 INFO - 'LANG': 'en_US.UTF-8', 12:37:55 INFO - 'LANGUAGE': 'en_US:en', 12:37:55 INFO - 'LOGNAME': 'cltbld', 12:37:55 INFO - 'MAIL': '/var/mail/cltbld', 12:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:55 INFO - 'MOZ_NO_REMOTE': '1', 12:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:37:55 INFO - 'NO_EM_RESTART': '1', 12:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:55 INFO - 'PWD': '/builds/slave/test', 12:37:55 INFO - 'SHELL': '/bin/bash', 12:37:55 INFO - 'SHLVL': '1', 12:37:55 INFO - 'TERM': 'linux', 12:37:55 INFO - 'TMOUT': '86400', 12:37:55 INFO - 'USER': 'cltbld', 12:37:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:55 INFO - '_': '/tools/buildbot/bin/python'} 12:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:55 INFO - Downloading/unpacking jsonschema==2.5.1 12:37:55 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 12:37:55 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 12:37:55 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 12:37:55 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 12:37:55 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 12:37:55 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 12:37:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:37:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:58 INFO - Downloading functools32-3.2.3-2.tar.gz 12:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:37:58 INFO - warning: no files found matching '*.txt' 12:37:58 INFO - no previously-included directories found matching 'build' 12:37:58 INFO - no previously-included directories found matching 'dist' 12:37:58 INFO - no previously-included directories found matching '.git*' 12:37:58 INFO - Installing collected packages: jsonschema, functools32 12:37:58 INFO - Running setup.py install for functools32 12:37:58 INFO - warning: no files found matching '*.txt' 12:37:58 INFO - no previously-included directories found matching 'build' 12:37:58 INFO - no previously-included directories found matching 'dist' 12:37:58 INFO - no previously-included directories found matching '.git*' 12:37:58 INFO - Successfully installed jsonschema functools32 12:37:58 INFO - Cleaning up... 12:37:58 INFO - Return code: 0 12:37:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:58 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:37:58 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 12:37:58 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 12:37:58 INFO - Using env: {'DISPLAY': ':0', 12:37:58 INFO - 'HOME': '/home/cltbld', 12:37:58 INFO - 'LANG': 'en_US.UTF-8', 12:37:58 INFO - 'LANGUAGE': 'en_US:en', 12:37:58 INFO - 'LOGNAME': 'cltbld', 12:37:58 INFO - 'MAIL': '/var/mail/cltbld', 12:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:58 INFO - 'MOZ_NO_REMOTE': '1', 12:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:37:58 INFO - 'NO_EM_RESTART': '1', 12:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:58 INFO - 'PWD': '/builds/slave/test', 12:37:58 INFO - 'SHELL': '/bin/bash', 12:37:58 INFO - 'SHLVL': '1', 12:37:58 INFO - 'TERM': 'linux', 12:37:58 INFO - 'TMOUT': '86400', 12:37:58 INFO - 'USER': 'cltbld', 12:37:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:58 INFO - '_': '/tools/buildbot/bin/python'} 12:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:37:58 INFO - Cleaning up... 12:37:58 INFO - Return code: 0 12:37:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:58 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:37:58 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 12:37:58 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 12:37:58 INFO - Using env: {'DISPLAY': ':0', 12:37:58 INFO - 'HOME': '/home/cltbld', 12:37:58 INFO - 'LANG': 'en_US.UTF-8', 12:37:58 INFO - 'LANGUAGE': 'en_US:en', 12:37:58 INFO - 'LOGNAME': 'cltbld', 12:37:58 INFO - 'MAIL': '/var/mail/cltbld', 12:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:58 INFO - 'MOZ_NO_REMOTE': '1', 12:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:37:58 INFO - 'NO_EM_RESTART': '1', 12:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:58 INFO - 'PWD': '/builds/slave/test', 12:37:58 INFO - 'SHELL': '/bin/bash', 12:37:58 INFO - 'SHLVL': '1', 12:37:58 INFO - 'TERM': 'linux', 12:37:58 INFO - 'TMOUT': '86400', 12:37:58 INFO - 'USER': 'cltbld', 12:37:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:58 INFO - '_': '/tools/buildbot/bin/python'} 12:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:58 INFO - Downloading/unpacking blobuploader==1.2.4 12:37:58 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 12:37:58 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 12:37:58 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 12:37:58 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 12:37:58 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 12:37:58 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 12:38:00 INFO - Downloading blobuploader-1.2.4.tar.gz 12:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:38:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:38:00 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 12:38:00 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 12:38:00 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 12:38:00 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 12:38:00 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 12:38:00 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 12:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:38:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:38:01 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 12:38:01 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 12:38:01 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 12:38:01 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 12:38:01 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 12:38:01 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 12:38:01 INFO - Downloading docopt-0.6.1.tar.gz 12:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:38:01 INFO - Installing collected packages: blobuploader, requests, docopt 12:38:01 INFO - Running setup.py install for blobuploader 12:38:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:38:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:38:01 INFO - Running setup.py install for requests 12:38:01 INFO - Running setup.py install for docopt 12:38:02 INFO - Successfully installed blobuploader requests docopt 12:38:02 INFO - Cleaning up... 12:38:02 INFO - Return code: 0 12:38:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:02 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:38:02 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 12:38:02 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 12:38:02 INFO - Using env: {'DISPLAY': ':0', 12:38:02 INFO - 'HOME': '/home/cltbld', 12:38:02 INFO - 'LANG': 'en_US.UTF-8', 12:38:02 INFO - 'LANGUAGE': 'en_US:en', 12:38:02 INFO - 'LOGNAME': 'cltbld', 12:38:02 INFO - 'MAIL': '/var/mail/cltbld', 12:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:02 INFO - 'MOZ_NO_REMOTE': '1', 12:38:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:02 INFO - 'NO_EM_RESTART': '1', 12:38:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:02 INFO - 'PWD': '/builds/slave/test', 12:38:02 INFO - 'SHELL': '/bin/bash', 12:38:02 INFO - 'SHLVL': '1', 12:38:02 INFO - 'TERM': 'linux', 12:38:02 INFO - 'TMOUT': '86400', 12:38:02 INFO - 'USER': 'cltbld', 12:38:02 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:02 INFO - '_': '/tools/buildbot/bin/python'} 12:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:02 INFO - Running setup.py (path:/tmp/pip-dz6oto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:02 INFO - Running setup.py (path:/tmp/pip-9wmEm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:02 INFO - Running setup.py (path:/tmp/pip-zJkYoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:02 INFO - Running setup.py (path:/tmp/pip-N_hRu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:02 INFO - Running setup.py (path:/tmp/pip-WIhSTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:02 INFO - Running setup.py (path:/tmp/pip-HYWV6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:02 INFO - Running setup.py (path:/tmp/pip-HXvZ1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:02 INFO - Running setup.py (path:/tmp/pip-XMCZUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:02 INFO - Running setup.py (path:/tmp/pip-BZaqED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:02 INFO - Running setup.py (path:/tmp/pip-o1jbMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:03 INFO - Running setup.py (path:/tmp/pip-W3CiMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:03 INFO - Running setup.py (path:/tmp/pip-VH_ntL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:03 INFO - Running setup.py (path:/tmp/pip-2rhto3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:03 INFO - Running setup.py (path:/tmp/pip-eXR0_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:03 INFO - Running setup.py (path:/tmp/pip-xjws1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:03 INFO - Running setup.py (path:/tmp/pip-HQXQO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:03 INFO - Running setup.py (path:/tmp/pip-oDD4UZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:38:03 INFO - Running setup.py install for manifestparser 12:38:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:38:03 INFO - Running setup.py install for mozcrash 12:38:03 INFO - Running setup.py install for mozdebug 12:38:03 INFO - Running setup.py install for mozdevice 12:38:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:38:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:38:03 INFO - Running setup.py install for mozfile 12:38:04 INFO - Running setup.py install for mozhttpd 12:38:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Running setup.py install for mozinfo 12:38:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Running setup.py install for mozInstall 12:38:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Running setup.py install for mozleak 12:38:04 INFO - Running setup.py install for mozlog 12:38:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Running setup.py install for moznetwork 12:38:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Running setup.py install for mozprocess 12:38:04 INFO - Running setup.py install for mozprofile 12:38:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:38:04 INFO - Running setup.py install for mozrunner 12:38:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:38:05 INFO - Running setup.py install for mozscreenshot 12:38:05 INFO - Running setup.py install for moztest 12:38:05 INFO - Running setup.py install for mozversion 12:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:38:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:38:05 INFO - Cleaning up... 12:38:05 INFO - Return code: 0 12:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:05 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:38:05 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 12:38:05 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 12:38:05 INFO - Using env: {'DISPLAY': ':0', 12:38:05 INFO - 'HOME': '/home/cltbld', 12:38:05 INFO - 'LANG': 'en_US.UTF-8', 12:38:05 INFO - 'LANGUAGE': 'en_US:en', 12:38:05 INFO - 'LOGNAME': 'cltbld', 12:38:05 INFO - 'MAIL': '/var/mail/cltbld', 12:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:05 INFO - 'MOZ_NO_REMOTE': '1', 12:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:05 INFO - 'NO_EM_RESTART': '1', 12:38:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:05 INFO - 'PWD': '/builds/slave/test', 12:38:05 INFO - 'SHELL': '/bin/bash', 12:38:05 INFO - 'SHLVL': '1', 12:38:05 INFO - 'TERM': 'linux', 12:38:05 INFO - 'TMOUT': '86400', 12:38:05 INFO - 'USER': 'cltbld', 12:38:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:05 INFO - '_': '/tools/buildbot/bin/python'} 12:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:05 INFO - Running setup.py (path:/tmp/pip-WLtkBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:05 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)) 12:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:05 INFO - Running setup.py (path:/tmp/pip-byS1JC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:05 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)) 12:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:05 INFO - Running setup.py (path:/tmp/pip-UZQ5Kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:05 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)) 12:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:05 INFO - Running setup.py (path:/tmp/pip-lslA3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:05 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)) 12:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:05 INFO - Running setup.py (path:/tmp/pip-nXqOrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:05 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)) 12:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:05 INFO - Running setup.py (path:/tmp/pip-LwqtiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:06 INFO - Running setup.py (path:/tmp/pip-dqR9bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:06 INFO - Running setup.py (path:/tmp/pip-li5LXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:06 INFO - Running setup.py (path:/tmp/pip-IHzv4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:06 INFO - Running setup.py (path:/tmp/pip-dZzXV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:06 INFO - Running setup.py (path:/tmp/pip-0JFgoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:06 INFO - Running setup.py (path:/tmp/pip-8U6tHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:06 INFO - Running setup.py (path:/tmp/pip-hPxQOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:06 INFO - Running setup.py (path:/tmp/pip-hLCKvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:06 INFO - Running setup.py (path:/tmp/pip-Ji72a_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:06 INFO - Running setup.py (path:/tmp/pip-dB7an_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:06 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)) 12:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:06 INFO - Running setup.py (path:/tmp/pip-poGMrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:06 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)) 12:38:06 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)) 12:38:06 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)) 12:38:06 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)) 12:38:06 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)) 12:38:06 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)) 12:38:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:38:06 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 12:38:06 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 12:38:06 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 12:38:06 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 12:38:06 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 12:38:06 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 12:38:08 INFO - Downloading blessings-1.6.tar.gz 12:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:38:08 INFO - Installing collected packages: blessings 12:38:08 INFO - Running setup.py install for blessings 12:38:08 INFO - Successfully installed blessings 12:38:08 INFO - Cleaning up... 12:38:08 INFO - Return code: 0 12:38:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:09 INFO - Reading from file tmpfile_stdout 12:38:09 INFO - Current package versions: 12:38:09 INFO - argparse == 1.2.1 12:38:09 INFO - blessings == 1.6 12:38:09 INFO - blobuploader == 1.2.4 12:38:09 INFO - docopt == 0.6.1 12:38:09 INFO - functools32 == 3.2.3-2 12:38:09 INFO - jsonschema == 2.5.1 12:38:09 INFO - manifestparser == 1.1 12:38:09 INFO - mozInstall == 1.12 12:38:09 INFO - mozcrash == 0.17 12:38:09 INFO - mozdebug == 0.1 12:38:09 INFO - mozdevice == 0.48 12:38:09 INFO - mozfile == 1.2 12:38:09 INFO - mozhttpd == 0.7 12:38:09 INFO - mozinfo == 0.9 12:38:09 INFO - mozleak == 0.1 12:38:09 INFO - mozlog == 3.4 12:38:09 INFO - moznetwork == 0.27 12:38:09 INFO - mozprocess == 0.23 12:38:09 INFO - mozprofile == 0.28 12:38:09 INFO - mozrunner == 6.12 12:38:09 INFO - mozscreenshot == 0.1 12:38:09 INFO - mozsystemmonitor == 0.3 12:38:09 INFO - moztest == 0.7 12:38:09 INFO - mozversion == 1.4 12:38:09 INFO - psutil == 3.1.1 12:38:09 INFO - requests == 1.2.3 12:38:09 INFO - wsgiref == 0.1.2 12:38:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:09 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')]} 12:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:09 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')]} 12:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:09 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:38:09 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 12:38:09 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 12:38:09 INFO - Using env: {'DISPLAY': ':0', 12:38:09 INFO - 'HOME': '/home/cltbld', 12:38:09 INFO - 'LANG': 'en_US.UTF-8', 12:38:09 INFO - 'LANGUAGE': 'en_US:en', 12:38:09 INFO - 'LOGNAME': 'cltbld', 12:38:09 INFO - 'MAIL': '/var/mail/cltbld', 12:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:09 INFO - 'MOZ_NO_REMOTE': '1', 12:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:09 INFO - 'NO_EM_RESTART': '1', 12:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:09 INFO - 'PWD': '/builds/slave/test', 12:38:09 INFO - 'SHELL': '/bin/bash', 12:38:09 INFO - 'SHLVL': '1', 12:38:09 INFO - 'TERM': 'linux', 12:38:09 INFO - 'TMOUT': '86400', 12:38:09 INFO - 'USER': 'cltbld', 12:38:09 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:09 INFO - '_': '/tools/buildbot/bin/python'} 12:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 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)) 12:38:09 INFO - Cleaning up... 12:38:09 INFO - Return code: 0 12:38:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:38:09 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')]} 12:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:09 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')]} 12:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:09 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 0x7f09d60b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27aeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', '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 12:38:09 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 12:38:09 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 12:38:09 INFO - Using env: {'DISPLAY': ':0', 12:38:09 INFO - 'HOME': '/home/cltbld', 12:38:09 INFO - 'LANG': 'en_US.UTF-8', 12:38:09 INFO - 'LANGUAGE': 'en_US:en', 12:38:09 INFO - 'LOGNAME': 'cltbld', 12:38:09 INFO - 'MAIL': '/var/mail/cltbld', 12:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:09 INFO - 'MOZ_NO_REMOTE': '1', 12:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:09 INFO - 'NO_EM_RESTART': '1', 12:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:09 INFO - 'PWD': '/builds/slave/test', 12:38:09 INFO - 'SHELL': '/bin/bash', 12:38:09 INFO - 'SHLVL': '1', 12:38:09 INFO - 'TERM': 'linux', 12:38:09 INFO - 'TMOUT': '86400', 12:38:09 INFO - 'USER': 'cltbld', 12:38:09 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:09 INFO - '_': '/tools/buildbot/bin/python'} 12:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:38:09 INFO - Cleaning up... 12:38:09 INFO - Return code: 0 12:38:09 INFO - Running post-action listener: _resource_record_post_action 12:38:09 INFO - Running post-action listener: _start_resource_monitoring 12:38:09 INFO - Starting resource monitoring. 12:38:09 INFO - [mozharness: 2016-11-17 20:38:09.678964Z] Finished create-virtualenv step (success) 12:38:09 INFO - [mozharness: 2016-11-17 20:38:09.679462Z] Running install step. 12:38:09 INFO - Running pre-action listener: _resource_record_pre_action 12:38:09 INFO - Running main action method: install 12:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:09 INFO - Reading from file tmpfile_stdout 12:38:09 INFO - Detecting whether we're running mozinstall >=1.0... 12:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:38:09 INFO - Reading from file tmpfile_stdout 12:38:09 INFO - Output received: 12:38:09 INFO - Usage: mozinstall [options] installer 12:38:09 INFO - Options: 12:38:09 INFO - -h, --help show this help message and exit 12:38:09 INFO - -d DEST, --destination=DEST 12:38:09 INFO - Directory to install application into. [default: 12:38:09 INFO - "/builds/slave/test"] 12:38:09 INFO - --app=APP Application being installed. [default: firefox] 12:38:09 INFO - mkdir: /builds/slave/test/build/application 12:38:09 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'] 12:38:09 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 12:38:23 INFO - Reading from file tmpfile_stdout 12:38:23 INFO - Output received: 12:38:23 INFO - /builds/slave/test/build/application/firefox/firefox 12:38:23 INFO - Running post-action listener: _resource_record_post_action 12:38:23 INFO - [mozharness: 2016-11-17 20:38:23.484155Z] Finished install step (success) 12:38:23 INFO - [mozharness: 2016-11-17 20:38:23.484401Z] Running run-tests step. 12:38:23 INFO - Running pre-action listener: _resource_record_pre_action 12:38:23 INFO - Running main action method: run_tests 12:38:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:38:23 INFO - Python 2.7.3 12:38:23 INFO - Return code: 0 12:38:23 INFO - grabbing minidump binary from tooltool 12:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:38:23 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 0x27abf60>, '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 0x27acd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ad1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:38:23 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 12:38:23 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 12:38:23 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 12:38:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:38:23 INFO - Return code: 0 12:38:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:38:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:38:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:38:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:38:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:38:23 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/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 12:38:23 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/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 12:38:23 INFO - Using env: {'DISPLAY': ':0', 12:38:23 INFO - 'HOME': '/home/cltbld', 12:38:23 INFO - 'LANG': 'en_US.UTF-8', 12:38:23 INFO - 'LANGUAGE': 'en_US:en', 12:38:23 INFO - 'LOGNAME': 'cltbld', 12:38:23 INFO - 'MAIL': '/var/mail/cltbld', 12:38:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:38:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:23 INFO - 'MOZ_NO_REMOTE': '1', 12:38:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:38:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:38:23 INFO - 'NO_EM_RESTART': '1', 12:38:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:23 INFO - 'PWD': '/builds/slave/test', 12:38:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:38:23 INFO - 'SHELL': '/bin/bash', 12:38:23 INFO - 'SHLVL': '1', 12:38:23 INFO - 'TERM': 'linux', 12:38:23 INFO - 'TMOUT': '86400', 12:38:23 INFO - 'USER': 'cltbld', 12:38:23 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202', 12:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:23 INFO - '_': '/tools/buildbot/bin/python'} 12:38:23 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/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 12:38:23 INFO - mozversion application_buildid: 20161117094728 12:38:23 INFO - mozversion application_changeset: 690ee038613a3b81fb78c52bbf9c4024db5c91d0 12:38:23 INFO - mozversion application_display_name: Firefox 12:38:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:38:23 INFO - mozversion application_name: Firefox 12:38:23 INFO - mozversion application_remotingname: firefox 12:38:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:38:23 INFO - mozversion application_vendor: Mozilla 12:38:23 INFO - mozversion application_version: 51.0 12:38:23 INFO - mozversion platform_buildid: 20161117094728 12:38:23 INFO - mozversion platform_changeset: 690ee038613a3b81fb78c52bbf9c4024db5c91d0 12:38:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:38:23 INFO - mozversion platform_version: 51.0 12:38:23 INFO - using testdate: 1479415103 12:38:23 INFO - actual date: 1479415103 12:38:23 INFO - starting webserver on 'localhost:43550' 12:38:23 INFO - SUITE-START | Running 2 tests 12:38:23 INFO - TEST-START | dromaeo_css 12:38:23 INFO - operating with platform_type : linux_ 12:38:23 INFO - Initialising browser for dromaeo_css test... 12:38:23 INFO - TEST-INFO | started process 31758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEYtHWT/profile http://localhost:43550/getInfo.html) 12:38:27 INFO - PROCESS | 31758 | __metrics Screen width/height:1600/1200 12:38:27 INFO - PROCESS | 31758 | colorDepth:24 12:38:27 INFO - PROCESS | 31758 | Browser inner width/height: 1024/697 12:38:27 INFO - PROCESS | 31758 | __metrics 12:38:28 INFO - PROCESS | 31758 | 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" 12:38:28 INFO - PROCESS | 31758 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:38:28 INFO - PROCESS | 31758 | console.error: PushService: 12:38:28 INFO - PROCESS | 31758 | stateChangeProcessEnqueue: Error transitioning state 12:38:28 INFO - PROCESS | 31758 | UnknownError 12:38:29 INFO - TEST-INFO | 31758: exit 0 12:38:29 INFO - Browser initialized. 12:38:29 INFO - Running cycle 1/1 for dromaeo_css test... 12:38:29 INFO - TEST-INFO | started process 31854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEYtHWT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:38:40 INFO - PROCESS | 31854 | RSS: Main: 143003648 12:38:40 INFO - PROCESS | 31854 | 12:39:31 INFO - PROCESS | 31854 | 1479415171947 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] 12:41:41 INFO - PROCESS | 31854 | Cycle 1(1): loaded http://localhost:43550/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43550/tests/dromaeo/cssquery-ext.html) 12:41:42 INFO - PROCESS | 31854 | RSS: Main: 248123392 12:41:42 INFO - PROCESS | 31854 | 12:44:44 INFO - PROCESS | 31854 | Cycle 1(1): loaded http://localhost:43550/tests/dromaeo/cssquery-ext.html (next: http://localhost:43550/tests/dromaeo/cssquery-jquery.html) 12:44:45 INFO - PROCESS | 31854 | RSS: Main: 233005056 12:44:45 INFO - PROCESS | 31854 | 12:47:46 INFO - PROCESS | 31854 | Cycle 1(1): loaded http://localhost:43550/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43550/tests/dromaeo/cssquery-mootools.html) 12:47:47 INFO - PROCESS | 31854 | RSS: Main: 228560896 12:47:47 INFO - PROCESS | 31854 | 12:50:49 INFO - PROCESS | 31854 | Cycle 1(1): loaded http://localhost:43550/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43550/tests/dromaeo/cssquery-prototype.html) 12:50:49 INFO - PROCESS | 31854 | RSS: Main: 175632384 12:50:49 INFO - PROCESS | 31854 | 12:53:52 INFO - PROCESS | 31854 | Cycle 1(1): loaded http://localhost:43550/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43550/tests/dromaeo/cssquery-yui.html) 12:53:53 INFO - PROCESS | 31854 | RSS: Main: 178339840 12:53:53 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | Cycle 1(1): loaded http://localhost:43550/tests/dromaeo/cssquery-yui.html (next: http://localhost:43550/tests/dromaeo/cssquery-dojo.html) 12:56:55 INFO - PROCESS | 31854 | RSS: Main: 265269248 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | __start_tp_report 12:56:55 INFO - PROCESS | 31854 | _x_x_mozilla_page_load 12:56:55 INFO - PROCESS | 31854 | _x_x_mozilla_page_load_details 12:56:55 INFO - PROCESS | 31854 | |i|pagename|runs| 12:56:55 INFO - PROCESS | 31854 | |0;dojo.html;3144.7955882867614;3191.6808319168085;3196.1530194498446;3205.903822885314;3235.4014507316147;5591.273134492515;5641.041023026085;5679.233303504026;5679.488846003859;5690.487856092951;5616.859578510539;5709.457601527859;5717.514011309044;5717.799877004306;5733.140028995653;5182.974085129578;5237.45006774289;5243.318368612075;5243.921341179866;5299.046171689094;5217.5565076968505;5296.152615581508;5320.521153096221;5321.414644389114;5342.198670199462;5169.741512924357;5185.922211166836;5241.056609810233;5247.449017853128;5278.973605131969;5242.580593552526;5251.711155886424;5329.973350133263;5339.038972984861;5340.91988620171;5084.44071152173;5173.844784656466;5248.737563121828;5292;5298.549623282026;5601.467860553241;5643.774249030034;5714.457126572969;5721.141828725725;5761.222234998263;6158.199434073586;6196.1944947157035;6196.969015154896;6197.783077592263;6198.659073750942;6098.634081955052;6241.843953901143;6250.812475625738;6262.499000080028;6268.843278918018;5700.914486282709;5726.856828579277;5738.0532212184935;5760.74076666551;5855.502282306052;6309.463695585835;6312.7790527332245;6327.050942358683;6327.17746692927;6346.714397852108;6036.909446358308;6104.908426373608;6110.388961103854;6122.051082082285;6122.081687746873;5978.701064946736;6001.399860014051;6039.245094363205;6058.182145410401;6087.208662873887;6097.817065488042;6098.35967223444;6102.7558897644485;6115.388461153849;6133.601315914451;6079.422454866781;6088.299845517734;6098.054801505774;6099.085137229451;6124.78563250293;4490.461144662596;4587.747673877969;4625.4911959684405;4653.464851542049;4677.579017888338;4171.8957026074595;4173.457450531411;4177.770222637723;4182.56083111275;4203.810828512725;5856.882862342814;5869.090291004901;5887.264091988502;5893.292804863444;5911.497522710618;6035.8792824143275;6093.482054025458;6099.329073801879;6105.419985101497;6227.439530442282;5817.214676018768;5892.440218179266;5899.203607512931;5908.320543137508;5942.435468630473;5345.812896548602;5351.732413379315;5353.518183363438;5359.678419294854;5361.839144825662;6128.6016408934165;6147.754089836357;6333.334999825044;6368.872622547523;6384.170057892445;6025.8192254232445;6085.208922840003;6097.969510152421;6138.109974053827;6161.876762464636;6277.96861015692;6278.6546739929745;6305.117283580211;6315.5894866833505;6331.651759153291;5941.405859414197;5960.0761881908375;6041.184440100706;6042.758289668364;6050.848728781815;5991.490723288569;6043.546733995056;6049.51603871698;6099.756009759737;6124.655575319917;5965.850853728692;5969.552283578662;5972.372900845348;5978.671173085348;5994.520438365008;5974.522038237017;5978.521718262616;5983.521318294439;5989.760409583742;5991.490723288395;5950.047992321208;5975.103734439869;6028.186194863637;6053.878922421439;6113.719560719409;6120.782376608402;6147.354527774519;6158.3841615838055;6167.660778657217;6169.96915015422;5782.85542861415;5844.766209351578;5989.490893274121;6017.939820601913;6038.275406951194 12:56:55 INFO - PROCESS | 31854 | |1;ext.html;596.4244504053587;624.7251209467835;645.6061802300596;652.6475703120316;664.332345992278;34832.651673483255;35329.646703532955;38069.809650951705;38096.80951595238;38188.80905595471;46508;48152.75923620377;48306;50605.49394506054;51091.23363149547;9201.447913125201;9322.906770932272;9342.205912497446;9343.345965782399;9353.85969210459;8545.658173673077;9244.768880778001;9341.953290233574;9348.485833279166;9348.953255233715;8528.147185281485;10866;11177.66467005991;11193.944030279838;11521.135914806424;11275.605353812618;11347.659570212907;11350.375729334883;11355.318680879132;11356.943215283913;11033.724156896062;11096.223264371498;11114.888851111466;11116.555337786478;11117.388543630097;38364.61635383638;38411.42382864259;38638.420423693664;38640.420393693836;38643;3785.9432108518395;3812;4349.804258808361;4383.254846676073;4486.0355023669745;34148;34194.82902585471;34409.31181376384;34494.655053449394;34783.4782478263;4694.929576056362;4733.100710864959;4746.952530474686;4761.976190119062;4813.807447702087;386536;387453;388380;388533;389499;56936.145957810666;56967.71516142393;57016.14475782867;57071.42928570744;57116.714416428484;40657.59342406538;41273.58726412758;41588.168236635094;41821.79089104535;65106.34893651098;16691.49925502237;16818.57953551171;16823.242954067096;16827.495175144766;16864.32542698303;13498.865011349957;13605.183688978694;13614.455421783017;13639.727205456034;13643.317834108255;18560.81439185618;18877.150528226262;19975.900120499304;20739.8963005184;20837;7400.814979625552;7591;7630.236976302436;7736.149023607399;7794.610269486617;959.7456673981401;1060.8939106089483;1139.6011396011495;1175.44753965636;1178.8821117888315;34552.48171277433;36106.27787444289;36117.27765444729;36965;37166.070848229014;1087.6519513755522;1115.6876074699098;1119.1550379463454;1163.0114402757758;1169.7543515861764;17168.939727232813;18375.540611484554;18811.435656930313;19090;20200.59598808045;575125;580040;580706;580716;580929;63218.367816321246;63709.36290637127;63767.36232637707;63777;63841.36158638447;1250.156144602408;1293.7218498022971;1346.118292518402;1371.0745246958263;1429.0996672096512;31998.36003279968;35009.82495087508;35066.12334691653;35107.29785404226;35627.82186089053;52818.207726884895;53544.19683704669;53619;53629.7318513405;53645;473823;477497;477529;478739;478795;27360;28109.156725298275;29386.265343366587;30286.242843929078;30551;47990.28014579714;48306.5169348302;48406.27390589214;48553.27170092381;48786.26820597765;41101.79449102735;41149.588504115774;41357.37963930603;42997.785011074746;43302.350464743686;39998.80000600095;40528.797356013034;40630.79684601558;40669.796651017736;41732.74801755952;40521.873366032945;40563.797181013906;40787.592124079565;41254.587454125074;41546;33760.324793504486;35512.289754204256;36023.09942251465;36123.277534448636;36490.270194595425;28197.71802282033;28206.576901346907;28250.43499130047;28468;28496.57255141133 12:56:55 INFO - PROCESS | 31854 | |2;jquery.html;419.8467559340842;421.21443507857856;427.88661004833716;435.82784800004;441.384268944822;18958;19356.806431935693;20776.2728304509;20805.27181548646;20985.685214721754;19777.208911643516;19905.701414478757;20541.178352865867;20630.484237894023;20742.48143796402;5661.264035675356;5944.940550594503;5963.761449542013;5964.135200395962;5968.582199246054;5662.773489060432;5764.74058667361;5835.416458354173;5843.5325173985975;5852.239208902858;5792.160136780164;5806.477417032466;5813;5816.4474374934525;5819.53443724501;5617.8033768818095;5779.537636989031;5800.042992906166;5833.591648584601;5834.299884013945;5252.107141785906;5253.947460525423;5261.289725886994;5262.236975638511;5263.657862238981;23448;24146.758532414628;24173.153939612028;24199.274021779198;24382.878085609635;2442.401611605168;2480.6603139180997;2504.8246622736415;2520.5715028445206;2526.5831137862233;10350.63772767957;10666.78666426667;14989.100653960797;15204.847951520454;15552.533423997185;1951.3267922566693;1999.780024197337;2042.3158241988951;2087.1338394566237;2102.726645536086;77867;77991;88210.5589472055;89608;90456.54771726165;11115.277506962184;11324.37715925632;11347.659570212907;11349.716257093638;11550.88449115515;16177.19114044293;16186.919065404598;16191.352345906267;16199.109049002182;16207.594810129842;8104.432689711663;8225.465344752572;8364.665413383518;8391.286740626992;8487.660493580188;7860;7954.880676789853;7961.765926281436;8150.796230094176;8220.260176584135;4843.733594652257;4869.099216644932;4909.239067944474;4924.679895806761;4969.204927211629;1969.5765410436825;2038.1439795286014;2062.83497320217;2064.958700826005;2072.357569153567;721.6427868205272;869.9173578510031;953.5899563187896;994.9303548751662;1037.5382954585136;19234.615307693766;20315.492112697004;20471.795282047286;20483.283085092247;20532.38402847917;1025.0825511167463;1029.0738335497963;1029.6602121299952;1042.4162469017224;1076.5586109695143;10853.51159197838;11336.943315283535;11439.256448330832;11462.828057579143;11479.483423245974;469781;470352;473595;481567;485628;64464;89419.10580894108;95446;96158;99805.50097249467;963.3593660215878;988.8467287570294;1007.0734923870057;1021.1932573267185;1022.6983040003217;17486.91256543709;18094.18576164102;18163.27346906109;18239.270429183216;18526.166322515248;43283.35074973815;43508.347374790035;44192.55807441884;44420.77789611031;44991.32513012242;52977.47022529726;56753.432465676466;57146;57160.71419643042;58352.4164758364;8882.866756998521;9040.32753940523;9045.412048216846;9122.27021838238;9372.531373431164;6449.064885591469;9564.426134431957;12099.548054233197;14016.78974815399;14598.781018284946;11191.048760855092;11239.55041798319;11295.71760705956;11331.433428328386;11586.594469193538;11769.764604707687;11828.645140645795;12081.154319197572;12330.445129968994;12457.1902826316;11244.437778110898;11375.26060806045;11640.650780476599;11695.76608467843;11858.407079646156;9211.309151813775;9318.114779095975;9339.346245762732;9371.85942210881;9434.716958491255;11338.716532086764;11414.48634811419;11644.6506604802;11797.351145686725;11798 12:56:55 INFO - PROCESS | 31854 | |3;mootools.html;1550.2636247782302;1586.389240142545;1740.912954352283;1798.5953160538872;1842.2538871756942;9166.81666366673;9791.657291994781;9825.164860986815;9827;9836.950815245931;8471.534065626389;8715.477071375723;8719.520426376563;8985.056569060253;9659.613615455373;2430.428849220432;2517.811164162695;2522.445062086338;2532.392225865788;2534.2903986883703;2500.237427584595;2511.8744062796877;2528.203615861007;2533.1893793985932;2536.327873113617;2472.431340791619;2482.416632091457;2482.515909397668;2491.9875400622973;2511.623256511529;2511.359603301165;2526.873656317177;2534.4297533055005;2546.987265063663;2552.4639825636546;2464.778169964694;2465.95068098637;2471.369800700828;2474.270090323359;2475.0718480569785;9646.228301735844;9717.65988190417;9830.655927042519;9832;9850.064243896819;5585.497305242548;5686.232358631573;5742.770289188427;5743.971280143573;5744.080947048494;3523.5243242162237;3568.286342731464;3592.2815436912724;3602.3875941090078;3676.650718181795;1320.6038188543398;1324.913880597758;1330.5609148980814;1349.7030653256268;1363.3319673359981;13606.591802245948;13740.450381984709;13768.173909565458;13815.032947693666;13866.237356945365;4812.085703716282;4825.758712064453;4899.804007839646;4955.851324460272;5064.7214403207445;10560.894391055992;10577.259591828499;10581.894181058246;10601.522931468102;10625.203109766644;5533.391326954032;5561.082421400504;5566.276384070045;5566.693831839207;5577.52591029767;4703.482616912137;5089.033083714083;5141.691498510101;5153.149730294459;5244.29202057725;4153.61809489214;4168.562300958416;4225.091605304874;4231.87304380869;4260.829566817354;1243.5461056714414;1267.4486598329756;1269.2638269803492;1275.6045625856013;1283.4481173095471;944.4286206844897;975.7560609847538;989.7822479054596;1005.100435110572;1012.6911292055994;5885.382034886361;5900.704964751745;5974.910376344358;6012.609180403259;6152.815415537451;477.90680817240303;487.29342453441944;503.52920019781624;508.42547920849205;520.3859445853853;3939.2318497893207;3943.802809859553;3951.723379363417;3984.322665146932;4008.6793056555407;11967.64097077089;12225.083118766128;12245.510179592895;12257.203281786655;12298.20061695987;12076;12078.335691536875;12080.818787718192;12089.637310880687;12109.394530273452;8554.35842311829;8836.646534138563;8837.425567338103;9003.909960900439;9096.727098186933;6477.838054048687;6614.933850661432;6751.223609284799;6768.4585233182215;6793.218779840184;11204.49579768896;11218.495167717309;11351.716207094558;11625.941870290595;11634.767304653691;11267.098632109575;11507.42462876836;11625.59310424131;11775.352355620524;11799.882001179878;7819.178986206594;8042.758717238492;8389.11914248995;8573.399862009599;8669.869951950599;9384.108509691569;9748.756281093029;9760.511974401394;9761.41431514083;9781.902180978384;8281.089080201053;8310.54292013945;8404.327653787805;8581.528015959182;8635.82728345417;8400;8434.198751118634;8437.789055273668;8525.317974562144;8526.872096918427;7444.590547519939;7460.0674915635545;7523.1348394935285;7526.059242594676;7530.322270995636;5373.629724420248;5598.944010559842;5626.85932851682;5758.712064396847;5835.44563266489;5594.908992746376;5670.8015219467125;5790.420957904176;5814.709264536841;5892.145638882424 12:56:55 INFO - PROCESS | 31854 | |4;prototype.html;350.3203784657764;352.3252970561374;357.94809752585877;359.5272217034601;364.6353646353648;5647.265855438793;5671.829845104643;5694.316681998155;5715.657060576358;5737.225474560933;5603.3836278009385;5622.662640241589;5623.80316688916;5637.3088073577965;5644.742103158738;4164.396162556435;4287.1854347674;4346.609085092771;4372.497162826284;4426.313921342197;4392.912141757163;4402.889927751799;4425.491068527129;4444;4454.13144436835;4355.346697995295;4362.171187474386;4366.4105345778235;4381.3647021181905;4394.231009573312;4238.682098842583;4337.869863904072;4364.956350436519;4391.692581519295;4402.886614425094;3881.4177873318945;3883.873676633773;3902.180542086137;3909.882703518899;3918.6081391860585;5397.568194544427;5466.398696143421;5468.398476167618;5476.849861529044;5504.6421982571;4761.2143996240575;4963.086544321132;5025.547700706954;5028.667403138172;5093.083245015896;5858.619189752695;5858.882822343529;5861.531077513789;5880.529557635379;5883.61756485827;4236.724612900182;4271.103068355649;4273.829046838123;4276.465441819772;4284.807183676742;5952.553558483131;6031.8539477499135;6097.2683278006925;6108.358622344679;6112.663803490851;4326.134773045341;4330.198913201068;4331.415258940003;4338.0890013097605;4351.6518678505645;4376;4387.14450682114;4409.40473036136;4434.623057040188;4437.2234858899565;4894.8042078317185;4945.357103576512;4981.605150557777;5000.799968001312;5034.414159539733;5862.530997520188;5882.882342353215;5896.233489646319;5901.822945311647;5907.877503274364;1508.9320980555901;1515.886308526865;1525.3593490733917;1531.379791184572;1542.7531594944755;952.8856537215579;968.651285537206;974.133021610767;975.3124047546463;1013.3717095400751;5553.6390134641115;5601.439856014366;5624.634398764068;5642.492175704207;5650.519705824969;5906.202662640489;5955.13650520672;5962.837246736932;5983.072623743326;5993.790217342459;5465.234867118606;5675;5678.432156784371;5791.913121303184;5820.679862607512;3196.6164060312444;3213.4055199787645;3224.4840825467813;3235.53084802707;3239.5788547488673;6124.601900876429;6218.657973811393;6254.093156492283;6275.529335299871;6282.874342513124;6083.387902205919;6145.354737752561;6166.691665416801;6183;6211.596246243979;6031.487323577545;6157.5517710082495;6236.906446403307;6243.40687634683;6249.531285153632;6035.18524999137;6038.456538911519;6052.66710330936;6072.757089716539;6077.848053798691;5926.733297001559;5929.584929054925;5954.940450595612;5970.701464926649;6009.909851352146;6089.147519347383;6192.628442293477;6219.937800622117;6244.781432649836;6250;4356.346548017823;4383.232934236559;4397.252467080668;4397.978010109929;4419.491758447691;5949.226600542075;6096.75612975476;6126.417990291094;6136.907946380718;6155.2921414037955;5847.561432892635;5876.735546900485;5879.882822263756;5902.20320256777;5918.112283157388;5850.268716410449;5866.266716660418;5868.2664666916635;5875.001249787631;5913.379095194942;5630.268065151504;5829.737661805144;5853.385394533683;5861.824145275648;5870.412958704266;5820.798560791633;5854.385289544536;5855.707214639166;5881.911771323519;5883.499902508163;5523.447655234444;5748.281464816898;5767.336756273083;5793.768249269982;5798.33319168301 12:56:55 INFO - PROCESS | 31854 | |5;yui.html;804.3444592656984;814.2305521282389;820.9466384684995;905.2848249882593;912.8174365126974;16275.267612957416;17617.91191044043;17737.024463654492;17744.64510709785;17769.555761105996;15489.92255038726;16067.678646427064;16244.187790610482;16273.186340682949;16359.91820040901;741.3883546074483;754.9924500754977;855.2687452228348;878.1613559051091;882.8101958079012;860.9281444601448;882.3823323673422;888.3381880499037;890.9866352004693;891.3537685178259;934.850423932171;940.2571968145157;940.3323640215456;940.6895724410962;941.2234906202376;926.7636752628036;930.5347326336831;931.2224292715523;934.6728644974272;935.0649350649351;680.7617333933133;682.8770821252173;683.5795985468901;683.8734834055715;684.6063513479719;16341.18294085291;16383.508494745058;16386.75419868703;16387.59031024222;16428.425005124882;14540;14598.416063357454;14704.779428308584;14828.406863725439;15015.474458393905;14273.214973176471;14633.122012679274;14922.925385373112;14988.672857178442;15367;4860.076585448789;4922.950770492285;4923.90152196954;5035.018171456568;5064.290999260106;89065;91003;91188;93424.53287733586;93541.53229233879;12243.877561224273;12326;12329.938350308192;12359.50561977511;12482.50069997208;21666.133354665635;21853.781462185176;21878.23426180108;21945.341639750834;22144.557108857734;9252.907470925205;9391.107844754737;9435.49032154852;9442.622295108256;9557.426554406758;5279.498447647467;5375.301210842644;5413.7834486620095;5569.0254205513875;5684.403137670568;3629.0927268182954;3737.663610275088;3737.8131093445763;3738.32710112177;3739.457778622139;627.802242293676;637.3148864970176;637.512303088138;638.789199564152;647.207171215267;668.6422763821332;710.8969199466048;744.0810598910341;745.4659905924575;752.4319139049982;10438.739031524272;10498.685044439035;10583.523741431654;10642.521086551124;10982;562.9240052592929;620.7951376045894;662.2781168526329;670.6881300195432;742.9739959101405;4983.277424773388;4989.675671081368;4999.625028122832;5094.61790365724;5102.464241254689;104061.47969260105;131331;131449.34275328752;133766.33116834355;137396.31301843427;108554;110813;113660;115900;116145;57166;71863;96041;96322.51838740762;97583.51208244056;9908.801823963624;10004.949975250078;10066.49667516636;10072;10085.344452610558;56837.43162568322;57650.711746441;57902.7104864473;58677.70661146838;59431.70284148725;75991.62004190165;87228;88775.55612221897;89150;90156;11966.162368634112;12224.266544007389;12224.877751222373;12437.75124497523;12495.875041249472;13130.563428691177;13144.539941102015;13215.60353189406;13493.32533373347;13495.527656531975;12321.949032210065;12717.300548469922;12854.228746274879;13185.472581097032;13211.075224734177;12511.687207819878;12610.936945315214;12893.41979610901;13116.803247951479;13381.130226535244;11207.383593902414;11323.433828308716;11622.0702343814;11802.586909458129;12460.875391246334;10744.194185435967;10956.671299861016;10964.232503724663;11173.273737207055;11589.304641721523;10703.37458322311;11009.06422954058;11364.261323013978;11396.373199474108;11408.54365825391 12:56:55 INFO - PROCESS | 31854 | __end_tp_report 12:56:55 INFO - PROCESS | 31854 | __start_cc_report 12:56:55 INFO - PROCESS | 31854 | _x_x_mozilla_cycle_collect,905 12:56:55 INFO - PROCESS | 31854 | __end_cc_report 12:56:55 INFO - PROCESS | 31854 | __startTimestamp1479416215915__endTimestamp 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | ------- Summary: start ------- 12:56:55 INFO - PROCESS | 31854 | Number of tests: 6 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | [#0] dojo.html Cycles:165 Average:5703.24 Median:5972.37 stddev:660.33 (11.1%) stddev-sans-first:631.12 12:56:55 INFO - PROCESS | 31854 | Values: 3144.8 3191.7 3196.2 3205.9 3235.4 5591.3 5641.0 5679.2 5679.5 5690.5 5616.9 5709.5 5717.5 5717.8 5733.1 5183.0 5237.5 5243.3 5243.9 5299.0 5217.6 5296.2 5320.5 5321.4 5342.2 5169.7 5185.9 5241.1 5247.4 5279.0 5242.6 5251.7 5330.0 5339.0 5340.9 5084.4 5173.8 5248.7 5292.0 5298.5 5601.5 5643.8 5714.5 5721.1 5761.2 6158.2 6196.2 6197.0 6197.8 6198.7 6098.6 6241.8 6250.8 6262.5 6268.8 5700.9 5726.9 5738.1 5760.7 5855.5 6309.5 6312.8 6327.1 6327.2 6346.7 6036.9 6104.9 6110.4 6122.1 6122.1 5978.7 6001.4 6039.2 6058.2 6087.2 6097.8 6098.4 6102.8 6115.4 6133.6 6079.4 6088.3 6098.1 6099.1 6124.8 4490.5 4587.7 4625.5 4653.5 4677.6 4171.9 4173.5 4177.8 4182.6 4203.8 5856.9 5869.1 5887.3 5893.3 5911.5 6035.9 6093.5 6099.3 6105.4 6227.4 5817.2 5892.4 5899.2 5908.3 5942.4 5345.8 5351.7 5353.5 5359.7 5361.8 6128.6 6147.8 6333.3 6368.9 6384.2 6025.8 6085.2 6098.0 6138.1 6161.9 6278.0 6278.7 6305.1 6315.6 6331.7 5941.4 5960.1 6041.2 6042.8 6050.8 5991.5 6043.5 6049.5 6099.8 6124.7 5965.9 5969.6 5972.4 5978.7 5994.5 5974.5 5978.5 5983.5 5989.8 5991.5 5950.0 5975.1 6028.2 6053.9 6113.7 6120.8 6147.4 6158.4 6167.7 6170.0 5782.9 5844.8 5989.5 6017.9 6038.3 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | [#1] ext.html Cycles:180 Average:63915.18 Median:386994.50 stddev:129534.24 (33.5%) stddev-sans-first:129810.38 12:56:55 INFO - PROCESS | 31854 | Values: 596.4 624.7 645.6 652.6 664.3 34832.7 35329.6 38069.8 38096.8 38188.8 46508.0 48152.8 48306.0 50605.5 51091.2 9201.4 9322.9 9342.2 9343.3 9353.9 8545.7 9244.8 9342.0 9348.5 9349.0 8528.1 10866.0 11177.7 11193.9 11521.1 11275.6 11347.7 11350.4 11355.3 11356.9 11033.7 11096.2 11114.9 11116.6 11117.4 38364.6 38411.4 38638.4 38640.4 38643.0 3785.9 3812.0 4349.8 4383.3 4486.0 34148.0 34194.8 34409.3 34494.7 34783.5 4694.9 4733.1 4747.0 4762.0 4813.8 386536.0 387453.0 388380.0 388533.0 389499.0 56936.1 56967.7 57016.1 57071.4 57116.7 40657.6 41273.6 41588.2 41821.8 65106.3 16691.5 16818.6 16823.2 16827.5 16864.3 13498.9 13605.2 13614.5 13639.7 13643.3 18560.8 18877.2 19975.9 20739.9 20837.0 7400.8 7591.0 7630.2 7736.1 7794.6 959.7 1060.9 1139.6 1175.4 1178.9 34552.5 36106.3 36117.3 36965.0 37166.1 1087.7 1115.7 1119.2 1163.0 1169.8 17168.9 18375.5 18811.4 19090.0 20200.6 575125.0 580040.0 580706.0 580716.0 580929.0 63218.4 63709.4 63767.4 63777.0 63841.4 1250.2 1293.7 1346.1 1371.1 1429.1 31998.4 35009.8 35066.1 35107.3 35627.8 52818.2 53544.2 53619.0 53629.7 53645.0 473823.0 477497.0 477529.0 478739.0 478795.0 27360.0 28109.2 29386.3 30286.2 30551.0 47990.3 48306.5 48406.3 48553.3 48786.3 41101.8 41149.6 41357.4 42997.8 43302.4 39998.8 40528.8 40630.8 40669.8 41732.7 40521.9 40563.8 40787.6 41254.6 41546.0 33760.3 35512.3 36023.1 36123.3 36490.3 28197.7 28206.6 28250.4 28468.0 28496.6 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | [#2] jquery.html Cycles:180 Average:28938.81 Median:2523.58 stddev:78592.69 (3114.3%) stddev-sans-first:78783.99 12:56:55 INFO - PROCESS | 31854 | Values: 419.8 421.2 427.9 435.8 441.4 18958.0 19356.8 20776.3 20805.3 20985.7 19777.2 19905.7 20541.2 20630.5 20742.5 5661.3 5944.9 5963.8 5964.1 5968.6 5662.8 5764.7 5835.4 5843.5 5852.2 5792.2 5806.5 5813.0 5816.4 5819.5 5617.8 5779.5 5800.0 5833.6 5834.3 5252.1 5253.9 5261.3 5262.2 5263.7 23448.0 24146.8 24173.2 24199.3 24382.9 2442.4 2480.7 2504.8 2520.6 2526.6 10350.6 10666.8 14989.1 15204.8 15552.5 1951.3 1999.8 2042.3 2087.1 2102.7 77867.0 77991.0 88210.6 89608.0 90456.5 11115.3 11324.4 11347.7 11349.7 11550.9 16177.2 16186.9 16191.4 16199.1 16207.6 8104.4 8225.5 8364.7 8391.3 8487.7 7860.0 7954.9 7961.8 8150.8 8220.3 4843.7 4869.1 4909.2 4924.7 4969.2 1969.6 2038.1 2062.8 2065.0 2072.4 721.6 869.9 953.6 994.9 1037.5 19234.6 20315.5 20471.8 20483.3 20532.4 1025.1 1029.1 1029.7 1042.4 1076.6 10853.5 11336.9 11439.3 11462.8 11479.5 469781.0 470352.0 473595.0 481567.0 485628.0 64464.0 89419.1 95446.0 96158.0 99805.5 963.4 988.8 1007.1 1021.2 1022.7 17486.9 18094.2 18163.3 18239.3 18526.2 43283.4 43508.3 44192.6 44420.8 44991.3 52977.5 56753.4 57146.0 57160.7 58352.4 8882.9 9040.3 9045.4 9122.3 9372.5 6449.1 9564.4 12099.5 14016.8 14598.8 11191.0 11239.6 11295.7 11331.4 11586.6 11769.8 11828.6 12081.2 12330.4 12457.2 11244.4 11375.3 11640.7 11695.8 11858.4 9211.3 9318.1 9339.3 9371.9 9434.7 11338.7 11414.5 11644.7 11797.4 11798.0 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | [#3] mootools.html Cycles:180 Average:6295.78 Median:2656.53 stddev:3691.01 (138.9%) stddev-sans-first:3684.14 12:56:55 INFO - PROCESS | 31854 | Values: 1550.3 1586.4 1740.9 1798.6 1842.3 9166.8 9791.7 9825.2 9827.0 9837.0 8471.5 8715.5 8719.5 8985.1 9659.6 2430.4 2517.8 2522.4 2532.4 2534.3 2500.2 2511.9 2528.2 2533.2 2536.3 2472.4 2482.4 2482.5 2492.0 2511.6 2511.4 2526.9 2534.4 2547.0 2552.5 2464.8 2466.0 2471.4 2474.3 2475.1 9646.2 9717.7 9830.7 9832.0 9850.1 5585.5 5686.2 5742.8 5744.0 5744.1 3523.5 3568.3 3592.3 3602.4 3676.7 1320.6 1324.9 1330.6 1349.7 1363.3 13606.6 13740.5 13768.2 13815.0 13866.2 4812.1 4825.8 4899.8 4955.9 5064.7 10560.9 10577.3 10581.9 10601.5 10625.2 5533.4 5561.1 5566.3 5566.7 5577.5 4703.5 5089.0 5141.7 5153.1 5244.3 4153.6 4168.6 4225.1 4231.9 4260.8 1243.5 1267.4 1269.3 1275.6 1283.4 944.4 975.8 989.8 1005.1 1012.7 5885.4 5900.7 5974.9 6012.6 6152.8 477.9 487.3 503.5 508.4 520.4 3939.2 3943.8 3951.7 3984.3 4008.7 11967.6 12225.1 12245.5 12257.2 12298.2 12076.0 12078.3 12080.8 12089.6 12109.4 8554.4 8836.6 8837.4 9003.9 9096.7 6477.8 6614.9 6751.2 6768.5 6793.2 11204.5 11218.5 11351.7 11625.9 11634.8 11267.1 11507.4 11625.6 11775.4 11799.9 7819.2 8042.8 8389.1 8573.4 8669.9 9384.1 9748.8 9760.5 9761.4 9781.9 8281.1 8310.5 8404.3 8581.5 8635.8 8400.0 8434.2 8437.8 8525.3 8526.9 7444.6 7460.1 7523.1 7526.1 7530.3 5373.6 5598.9 5626.9 5758.7 5835.4 5594.9 5670.8 5790.4 5814.7 5892.1 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | [#4] prototype.html Cycles:180 Average:4963.55 Median:5673.41 stddev:1452.01 (25.6%) stddev-sans-first:1414.19 12:56:55 INFO - PROCESS | 31854 | Values: 350.3 352.3 357.9 359.5 364.6 5647.3 5671.8 5694.3 5715.7 5737.2 5603.4 5622.7 5623.8 5637.3 5644.7 4164.4 4287.2 4346.6 4372.5 4426.3 4392.9 4402.9 4425.5 4444.0 4454.1 4355.3 4362.2 4366.4 4381.4 4394.2 4238.7 4337.9 4365.0 4391.7 4402.9 3881.4 3883.9 3902.2 3909.9 3918.6 5397.6 5466.4 5468.4 5476.8 5504.6 4761.2 4963.1 5025.5 5028.7 5093.1 5858.6 5858.9 5861.5 5880.5 5883.6 4236.7 4271.1 4273.8 4276.5 4284.8 5952.6 6031.9 6097.3 6108.4 6112.7 4326.1 4330.2 4331.4 4338.1 4351.7 4376.0 4387.1 4409.4 4434.6 4437.2 4894.8 4945.4 4981.6 5000.8 5034.4 5862.5 5882.9 5896.2 5901.8 5907.9 1508.9 1515.9 1525.4 1531.4 1542.8 952.9 968.7 974.1 975.3 1013.4 5553.6 5601.4 5624.6 5642.5 5650.5 5906.2 5955.1 5962.8 5983.1 5993.8 5465.2 5675.0 5678.4 5791.9 5820.7 3196.6 3213.4 3224.5 3235.5 3239.6 6124.6 6218.7 6254.1 6275.5 6282.9 6083.4 6145.4 6166.7 6183.0 6211.6 6031.5 6157.6 6236.9 6243.4 6249.5 6035.2 6038.5 6052.7 6072.8 6077.8 5926.7 5929.6 5954.9 5970.7 6009.9 6089.1 6192.6 6219.9 6244.8 6250.0 4356.3 4383.2 4397.3 4398.0 4419.5 5949.2 6096.8 6126.4 6136.9 6155.3 5847.6 5876.7 5879.9 5902.2 5918.1 5850.3 5866.3 5868.3 5875.0 5913.4 5630.3 5829.7 5853.4 5861.8 5870.4 5820.8 5854.4 5855.7 5881.9 5883.5 5523.4 5748.3 5767.3 5793.8 5798.3 12:56:55 INFO - PROCESS | 31854 | 12:56:55 INFO - PROCESS | 31854 | [#5] yui.html Cycles:180 Average:22660.62 Median:3737.74 stddev:33677.80 (901.0%) stddev-sans-first:33732.29 12:56:55 INFO - PROCESS | 31854 | Values: 804.3 814.2 820.9 905.3 912.8 16275.3 17617.9 17737.0 17744.6 17769.6 15489.9 16067.7 16244.2 16273.2 16359.9 741.4 755.0 855.3 878.2 882.8 860.9 882.4 888.3 891.0 891.4 934.9 940.3 940.3 940.7 941.2 926.8 930.5 931.2 934.7 935.1 680.8 682.9 683.6 683.9 684.6 16341.2 16383.5 16386.8 16387.6 16428.4 14540.0 14598.4 14704.8 14828.4 15015.5 14273.2 14633.1 14922.9 14988.7 15367.0 4860.1 4923.0 4923.9 5035.0 5064.3 89065.0 91003.0 91188.0 93424.5 93541.5 12243.9 12326.0 12329.9 12359.5 12482.5 21666.1 21853.8 21878.2 21945.3 22144.6 9252.9 9391.1 9435.5 9442.6 9557.4 5279.5 5375.3 5413.8 5569.0 5684.4 3629.1 3737.7 3737.8 3738.3 3739.5 627.8 637.3 637.5 638.8 647.2 668.6 710.9 744.1 745.5 752.4 10438.7 10498.7 10583.5 10642.5 10982.0 562.9 620.8 662.3 670.7 743.0 4983.3 4989.7 4999.6 5094.6 5102.5 104061.5 131331.0 131449.3 133766.3 137396.3 108554.0 110813.0 113660.0 115900.0 116145.0 57166.0 71863.0 96041.0 96322.5 97583.5 9908.8 10004.9 10066.5 10072.0 10085.3 56837.4 57650.7 57902.7 58677.7 59431.7 75991.6 87228.0 88775.6 89150.0 90156.0 11966.2 12224.3 12224.9 12437.8 12495.9 13130.6 13144.5 13215.6 13493.3 13495.5 12321.9 12717.3 12854.2 13185.5 13211.1 12511.7 12610.9 12893.4 13116.8 13381.1 11207.4 11323.4 11622.1 11802.6 12460.9 10744.2 10956.7 10964.2 11173.3 11589.3 10703.4 11009.1 11364.3 11396.4 11408.5 12:56:55 INFO - PROCESS | 31854 | -------- Summary: end -------- 12:56:55 INFO - PROCESS | 31854 | 12:56:56 INFO - TEST-INFO | 31854: exit 0 12:56:56 INFO - TEST-OK | dromaeo_css | took 1112897ms 12:56:56 INFO - TEST-START | kraken 12:56:56 INFO - operating with platform_type : linux_ 12:56:56 INFO - Initialising browser for kraken test... 12:56:56 INFO - TEST-INFO | started process 32159 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeCo3gN/profile http://localhost:43550/getInfo.html) 12:57:00 INFO - PROCESS | 32159 | __metrics Screen width/height:1600/1200 12:57:00 INFO - PROCESS | 32159 | colorDepth:24 12:57:00 INFO - PROCESS | 32159 | Browser inner width/height: 1024/697 12:57:00 INFO - PROCESS | 32159 | __metrics 12:57:00 INFO - PROCESS | 32159 | 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" 12:57:00 INFO - PROCESS | 32159 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:57:01 INFO - PROCESS | 32159 | console.error: PushService: 12:57:01 INFO - PROCESS | 32159 | stateChangeProcessEnqueue: Error transitioning state 12:57:01 INFO - PROCESS | 32159 | UnknownError 12:57:01 INFO - TEST-INFO | 32159: exit 0 12:57:01 INFO - Browser initialized. 12:57:01 INFO - Running cycle 1/1 for kraken test... 12:57:01 INFO - TEST-INFO | started process 32252 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeCo3gN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:57:13 INFO - PROCESS | 32252 | RSS: Main: 143306752 12:57:13 INFO - PROCESS | 32252 | 12:57:16 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43550/tests/kraken/driver.html?testName=audio-beat-detection) 12:57:17 INFO - PROCESS | 32252 | RSS: Main: 185864192 12:57:17 INFO - PROCESS | 32252 | 12:57:19 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43550/tests/kraken/driver.html?testName=audio-dft) 12:57:20 INFO - PROCESS | 32252 | RSS: Main: 173776896 12:57:20 INFO - PROCESS | 32252 | 12:57:22 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43550/tests/kraken/driver.html?testName=audio-fft) 12:57:23 INFO - PROCESS | 32252 | RSS: Main: 265596928 12:57:23 INFO - PROCESS | 32252 | 12:57:25 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43550/tests/kraken/driver.html?testName=audio-oscillator) 12:57:26 INFO - PROCESS | 32252 | RSS: Main: 172077056 12:57:26 INFO - PROCESS | 32252 | 12:57:28 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43550/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:57:28 INFO - PROCESS | 32252 | RSS: Main: 174096384 12:57:28 INFO - PROCESS | 32252 | 12:57:31 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43550/tests/kraken/driver.html?testName=imaging-darkroom) 12:57:32 INFO - PROCESS | 32252 | RSS: Main: 210042880 12:57:32 INFO - PROCESS | 32252 | 12:57:35 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43550/tests/kraken/driver.html?testName=imaging-desaturate) 12:57:36 INFO - PROCESS | 32252 | RSS: Main: 208322560 12:57:36 INFO - PROCESS | 32252 | 12:57:39 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43550/tests/kraken/driver.html?testName=json-parse-financial) 12:57:39 INFO - PROCESS | 32252 | RSS: Main: 208465920 12:57:39 INFO - PROCESS | 32252 | 12:57:41 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43550/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:57:41 INFO - PROCESS | 32252 | RSS: Main: 169938944 12:57:41 INFO - PROCESS | 32252 | 12:57:43 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:57:44 INFO - PROCESS | 32252 | RSS: Main: 178094080 12:57:44 INFO - PROCESS | 32252 | 12:57:46 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:57:46 INFO - PROCESS | 32252 | RSS: Main: 186687488 12:57:46 INFO - PROCESS | 32252 | 12:57:49 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:57:49 INFO - PROCESS | 32252 | RSS: Main: 196034560 12:57:49 INFO - PROCESS | 32252 | 12:57:52 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:57:53 INFO - PROCESS | 32252 | RSS: Main: 192622592 12:57:53 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | Cycle 1(1): loaded http://localhost:43550/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43550/tests/kraken/driver.html?testName=ai-astar) 12:57:54 INFO - PROCESS | 32252 | RSS: Main: 241348608 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | __start_tp_report 12:57:54 INFO - PROCESS | 32252 | _x_x_mozilla_page_load 12:57:54 INFO - PROCESS | 32252 | _x_x_mozilla_page_load_details 12:57:54 INFO - PROCESS | 32252 | |i|pagename|runs| 12:57:54 INFO - PROCESS | 32252 | |0;ai-astar;112;112;112;113;115;113;113;113;113;112 12:57:54 INFO - PROCESS | 32252 | |1;audio-beat-detection;146;117;115;154;117;133;117;116;149;121 12:57:54 INFO - PROCESS | 32252 | |2;audio-dft;162;159;161;162;161;161;159;160;161;160 12:57:54 INFO - PROCESS | 32252 | |3;audio-fft;75;75;75;118;76;74;75;76;98;74 12:57:54 INFO - PROCESS | 32252 | |4;audio-oscillator;82;81;111;81;81;83;106;80;80;114 12:57:54 INFO - PROCESS | 32252 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;117;117;117 12:57:54 INFO - PROCESS | 32252 | |6;imaging-darkroom;146;146;146;146;145;146;146;146;146;147 12:57:54 INFO - PROCESS | 32252 | |7;imaging-desaturate;95;95;96;95;95;95;95;96;95;95 12:57:54 INFO - PROCESS | 32252 | |8;json-parse-financial;48;49;48;48;49;51;48;48;51;48 12:57:54 INFO - PROCESS | 32252 | |9;json-stringify-tinderbox;58;59;59;58;59;58;58;59;58;58 12:57:54 INFO - PROCESS | 32252 | |10;stanford-crypto-aes;81;75;73;75;73;73;73;78;74;80 12:57:54 INFO - PROCESS | 32252 | |11;stanford-crypto-ccm;122;120;120;128;122;118;118;122;163;123 12:57:54 INFO - PROCESS | 32252 | |12;stanford-crypto-pbkdf2;155;153;157;151;153;152;151;148;153;150 12:57:54 INFO - PROCESS | 32252 | |13;stanford-crypto-sha256-iterative;64;63;63;62;62;62;64;61;60;61 12:57:54 INFO - PROCESS | 32252 | __end_tp_report 12:57:54 INFO - PROCESS | 32252 | __start_cc_report 12:57:54 INFO - PROCESS | 32252 | _x_x_mozilla_cycle_collect,1574 12:57:54 INFO - PROCESS | 32252 | __end_cc_report 12:57:54 INFO - PROCESS | 32252 | __startTimestamp1479416274863__endTimestamp 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | ------- Summary: start ------- 12:57:54 INFO - PROCESS | 32252 | Number of tests: 14 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 12:57:54 INFO - PROCESS | 32252 | Values: 112.0 112.0 112.0 113.0 115.0 113.0 113.0 113.0 113.0 112.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:127.00 stddev:15.59 (12.3%) stddev-sans-first:15.20 12:57:54 INFO - PROCESS | 32252 | Values: 146.0 117.0 115.0 154.0 117.0 133.0 117.0 116.0 149.0 121.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.07 (0.7%) stddev-sans-first:1.01 12:57:54 INFO - PROCESS | 32252 | Values: 162.0 159.0 161.0 162.0 161.0 161.0 159.0 160.0 161.0 160.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:14.71 (19.6%) stddev-sans-first:15.40 12:57:54 INFO - PROCESS | 32252 | Values: 75.0 75.0 75.0 118.0 76.0 74.0 75.0 76.0 98.0 74.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:81.00 stddev:14.26 (17.6%) stddev-sans-first:14.83 12:57:54 INFO - PROCESS | 32252 | Values: 82.0 81.0 111.0 81.0 81.0 83.0 106.0 80.0 80.0 114.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:57:54 INFO - PROCESS | 32252 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 12:57:54 INFO - PROCESS | 32252 | Values: 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:57:54 INFO - PROCESS | 32252 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#8] json-parse-financial Cycles:10 Average:48.80 Median:48.50 stddev:1.23 (2.5%) stddev-sans-first:1.27 12:57:54 INFO - PROCESS | 32252 | Values: 48.0 49.0 48.0 48.0 49.0 51.0 48.0 48.0 51.0 48.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 12:57:54 INFO - PROCESS | 32252 | Values: 58.0 59.0 59.0 58.0 59.0 58.0 58.0 59.0 58.0 58.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.00 stddev:3.06 (4.1%) stddev-sans-first:2.52 12:57:54 INFO - PROCESS | 32252 | Values: 81.0 75.0 73.0 75.0 73.0 73.0 73.0 78.0 74.0 80.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:122.00 stddev:13.45 (11.0%) stddev-sans-first:14.20 12:57:54 INFO - PROCESS | 32252 | Values: 122.0 120.0 120.0 128.0 122.0 118.0 118.0 122.0 163.0 123.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:153.00 stddev:2.54 (1.7%) stddev-sans-first:2.50 12:57:54 INFO - PROCESS | 32252 | Values: 155.0 153.0 157.0 151.0 153.0 152.0 151.0 148.0 153.0 150.0 12:57:54 INFO - PROCESS | 32252 | 12:57:54 INFO - PROCESS | 32252 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.50 stddev:1.32 (2.1%) stddev-sans-first:1.22 12:57:54 INFO - PROCESS | 32252 | Values: 64.0 63.0 63.0 62.0 62.0 62.0 64.0 61.0 60.0 61.0 12:57:54 INFO - PROCESS | 32252 | -------- Summary: end -------- 12:57:54 INFO - PROCESS | 32252 | 12:57:55 INFO - TEST-INFO | 32252: exit 0 12:57:55 INFO - TEST-OK | kraken | took 59205ms 12:57:55 INFO - SUITE-END | took 1172s 12:57:56 INFO - Completed test suite (00:19:32) 12:57:56 INFO - javascript benchmark 12:57:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.7955882867614, 3191.6808319168085, 3196.1530194498446, 3205.903822885314, 3235.4014507316147, 5591.273134492515, 5641.041023026085, 5679.233303504026, 5679.488846003859, 5690.487856092951, 5616.859578510539, 5709.457601527859, 5717.514011309044, 5717.799877004306, 5733.140028995653, 5182.974085129578, 5237.45006774289, 5243.318368612075, 5243.921341179866, 5299.046171689094, 5217.5565076968505, 5296.152615581508, 5320.521153096221, 5321.414644389114, 5342.198670199462, 5169.741512924357, 5185.922211166836, 5241.056609810233, 5247.449017853128, 5278.973605131969, 5242.580593552526, 5251.711155886424, 5329.973350133263, 5339.038972984861, 5340.91988620171, 5084.44071152173, 5173.844784656466, 5248.737563121828, 5292.0, 5298.549623282026, 5601.467860553241, 5643.774249030034, 5714.457126572969, 5721.141828725725, 5761.222234998263, 6158.199434073586, 6196.1944947157035, 6196.969015154896, 6197.783077592263, 6198.659073750942, 6098.634081955052, 6241.843953901143, 6250.812475625738, 6262.499000080028, 6268.843278918018, 5700.914486282709, 5726.856828579277, 5738.0532212184935, 5760.74076666551, 5855.502282306052, 6309.463695585835, 6312.7790527332245, 6327.050942358683, 6327.17746692927, 6346.714397852108, 6036.909446358308, 6104.908426373608, 6110.388961103854, 6122.051082082285, 6122.081687746873, 5978.701064946736, 6001.399860014051, 6039.245094363205, 6058.182145410401, 6087.208662873887, 6097.817065488042, 6098.35967223444, 6102.7558897644485, 6115.388461153849, 6133.601315914451, 6079.422454866781, 6088.299845517734, 6098.054801505774, 6099.085137229451, 6124.78563250293, 4490.461144662596, 4587.747673877969, 4625.4911959684405, 4653.464851542049, 4677.579017888338, 4171.8957026074595, 4173.457450531411, 4177.770222637723, 4182.56083111275, 4203.810828512725, 5856.882862342814, 5869.090291004901, 5887.264091988502, 5893.292804863444, 5911.497522710618, 6035.8792824143275, 6093.482054025458, 6099.329073801879, 6105.419985101497, 6227.439530442282, 5817.214676018768, 5892.440218179266, 5899.203607512931, 5908.320543137508, 5942.435468630473, 5345.812896548602, 5351.732413379315, 5353.518183363438, 5359.678419294854, 5361.839144825662, 6128.6016408934165, 6147.754089836357, 6333.334999825044, 6368.872622547523, 6384.170057892445, 6025.8192254232445, 6085.208922840003, 6097.969510152421, 6138.109974053827, 6161.876762464636, 6277.96861015692, 6278.6546739929745, 6305.117283580211, 6315.5894866833505, 6331.651759153291, 5941.405859414197, 5960.0761881908375, 6041.184440100706, 6042.758289668364, 6050.848728781815, 5991.490723288569, 6043.546733995056, 6049.51603871698, 6099.756009759737, 6124.655575319917, 5965.850853728692, 5969.552283578662, 5972.372900845348, 5978.671173085348, 5994.520438365008, 5974.522038237017, 5978.521718262616, 5983.521318294439, 5989.760409583742, 5991.490723288395, 5950.047992321208, 5975.103734439869, 6028.186194863637, 6053.878922421439, 6113.719560719409, 6120.782376608402, 6147.354527774519, 6158.3841615838055, 6167.660778657217, 6169.96915015422, 5782.85542861415, 5844.766209351578, 5989.490893274121, 6017.939820601913, 6038.275406951194], "value": 5656.653742811296, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.4244504053587, 624.7251209467835, 645.6061802300596, 652.6475703120316, 664.332345992278, 34832.651673483255, 35329.646703532955, 38069.809650951705, 38096.80951595238, 38188.80905595471, 46508.0, 48152.75923620377, 48306.0, 50605.49394506054, 51091.23363149547, 9201.447913125201, 9322.906770932272, 9342.205912497446, 9343.345965782399, 9353.85969210459, 8545.658173673077, 9244.768880778001, 9341.953290233574, 9348.485833279166, 9348.953255233715, 8528.147185281485, 10866.0, 11177.66467005991, 11193.944030279838, 11521.135914806424, 11275.605353812618, 11347.659570212907, 11350.375729334883, 11355.318680879132, 11356.943215283913, 11033.724156896062, 11096.223264371498, 11114.888851111466, 11116.555337786478, 11117.388543630097, 38364.61635383638, 38411.42382864259, 38638.420423693664, 38640.420393693836, 38643.0, 3785.9432108518395, 3812.0, 4349.804258808361, 4383.254846676073, 4486.0355023669745, 34148.0, 34194.82902585471, 34409.31181376384, 34494.655053449394, 34783.4782478263, 4694.929576056362, 4733.100710864959, 4746.952530474686, 4761.976190119062, 4813.807447702087, 386536.0, 387453.0, 388380.0, 388533.0, 389499.0, 56936.145957810666, 56967.71516142393, 57016.14475782867, 57071.42928570744, 57116.714416428484, 40657.59342406538, 41273.58726412758, 41588.168236635094, 41821.79089104535, 65106.34893651098, 16691.49925502237, 16818.57953551171, 16823.242954067096, 16827.495175144766, 16864.32542698303, 13498.865011349957, 13605.183688978694, 13614.455421783017, 13639.727205456034, 13643.317834108255, 18560.81439185618, 18877.150528226262, 19975.900120499304, 20739.8963005184, 20837.0, 7400.814979625552, 7591.0, 7630.236976302436, 7736.149023607399, 7794.610269486617, 959.7456673981401, 1060.8939106089483, 1139.6011396011495, 1175.44753965636, 1178.8821117888315, 34552.48171277433, 36106.27787444289, 36117.27765444729, 36965.0, 37166.070848229014, 1087.6519513755522, 1115.6876074699098, 1119.1550379463454, 1163.0114402757758, 1169.7543515861764, 17168.939727232813, 18375.540611484554, 18811.435656930313, 19090.0, 20200.59598808045, 575125.0, 580040.0, 580706.0, 580716.0, 580929.0, 63218.367816321246, 63709.36290637127, 63767.36232637707, 63777.0, 63841.36158638447, 1250.156144602408, 1293.7218498022971, 1346.118292518402, 1371.0745246958263, 1429.0996672096512, 31998.36003279968, 35009.82495087508, 35066.12334691653, 35107.29785404226, 35627.82186089053, 52818.207726884895, 53544.19683704669, 53619.0, 53629.7318513405, 53645.0, 473823.0, 477497.0, 477529.0, 478739.0, 478795.0, 27360.0, 28109.156725298275, 29386.265343366587, 30286.242843929078, 30551.0, 47990.28014579714, 48306.5169348302, 48406.27390589214, 48553.27170092381, 48786.26820597765, 41101.79449102735, 41149.588504115774, 41357.37963930603, 42997.785011074746, 43302.350464743686, 39998.80000600095, 40528.797356013034, 40630.79684601558, 40669.796651017736, 41732.74801755952, 40521.873366032945, 40563.797181013906, 40787.592124079565, 41254.587454125074, 41546.0, 33760.324793504486, 35512.289754204256, 36023.09942251465, 36123.277534448636, 36490.270194595425, 28197.71802282033, 28206.576901346907, 28250.43499130047, 28468.0, 28496.57255141133], "value": 21067.19580252913, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.8467559340842, 421.21443507857856, 427.88661004833716, 435.82784800004, 441.384268944822, 18958.0, 19356.806431935693, 20776.2728304509, 20805.27181548646, 20985.685214721754, 19777.208911643516, 19905.701414478757, 20541.178352865867, 20630.484237894023, 20742.48143796402, 5661.264035675356, 5944.940550594503, 5963.761449542013, 5964.135200395962, 5968.582199246054, 5662.773489060432, 5764.74058667361, 5835.416458354173, 5843.5325173985975, 5852.239208902858, 5792.160136780164, 5806.477417032466, 5813.0, 5816.4474374934525, 5819.53443724501, 5617.8033768818095, 5779.537636989031, 5800.042992906166, 5833.591648584601, 5834.299884013945, 5252.107141785906, 5253.947460525423, 5261.289725886994, 5262.236975638511, 5263.657862238981, 23448.0, 24146.758532414628, 24173.153939612028, 24199.274021779198, 24382.878085609635, 2442.401611605168, 2480.6603139180997, 2504.8246622736415, 2520.5715028445206, 2526.5831137862233, 10350.63772767957, 10666.78666426667, 14989.100653960797, 15204.847951520454, 15552.533423997185, 1951.3267922566693, 1999.780024197337, 2042.3158241988951, 2087.1338394566237, 2102.726645536086, 77867.0, 77991.0, 88210.5589472055, 89608.0, 90456.54771726165, 11115.277506962184, 11324.37715925632, 11347.659570212907, 11349.716257093638, 11550.88449115515, 16177.19114044293, 16186.919065404598, 16191.352345906267, 16199.109049002182, 16207.594810129842, 8104.432689711663, 8225.465344752572, 8364.665413383518, 8391.286740626992, 8487.660493580188, 7860.0, 7954.880676789853, 7961.765926281436, 8150.796230094176, 8220.260176584135, 4843.733594652257, 4869.099216644932, 4909.239067944474, 4924.679895806761, 4969.204927211629, 1969.5765410436825, 2038.1439795286014, 2062.83497320217, 2064.958700826005, 2072.357569153567, 721.6427868205272, 869.9173578510031, 953.5899563187896, 994.9303548751662, 1037.5382954585136, 19234.615307693766, 20315.492112697004, 20471.795282047286, 20483.283085092247, 20532.38402847917, 1025.0825511167463, 1029.0738335497963, 1029.6602121299952, 1042.4162469017224, 1076.5586109695143, 10853.51159197838, 11336.943315283535, 11439.256448330832, 11462.828057579143, 11479.483423245974, 469781.0, 470352.0, 473595.0, 481567.0, 485628.0, 64464.0, 89419.10580894108, 95446.0, 96158.0, 99805.50097249467, 963.3593660215878, 988.8467287570294, 1007.0734923870057, 1021.1932573267185, 1022.6983040003217, 17486.91256543709, 18094.18576164102, 18163.27346906109, 18239.270429183216, 18526.166322515248, 43283.35074973815, 43508.347374790035, 44192.55807441884, 44420.77789611031, 44991.32513012242, 52977.47022529726, 56753.432465676466, 57146.0, 57160.71419643042, 58352.4164758364, 8882.866756998521, 9040.32753940523, 9045.412048216846, 9122.27021838238, 9372.531373431164, 6449.064885591469, 9564.426134431957, 12099.548054233197, 14016.78974815399, 14598.781018284946, 11191.048760855092, 11239.55041798319, 11295.71760705956, 11331.433428328386, 11586.594469193538, 11769.764604707687, 11828.645140645795, 12081.154319197572, 12330.445129968994, 12457.1902826316, 11244.437778110898, 11375.26060806045, 11640.650780476599, 11695.76608467843, 11858.407079646156, 9211.309151813775, 9318.114779095975, 9339.346245762732, 9371.85942210881, 9434.716958491255, 11338.716532086764, 11414.48634811419, 11644.6506604802, 11797.351145686725, 11798.0], "value": 9456.237496822001, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1550.2636247782302, 1586.389240142545, 1740.912954352283, 1798.5953160538872, 1842.2538871756942, 9166.81666366673, 9791.657291994781, 9825.164860986815, 9827.0, 9836.950815245931, 8471.534065626389, 8715.477071375723, 8719.520426376563, 8985.056569060253, 9659.613615455373, 2430.428849220432, 2517.811164162695, 2522.445062086338, 2532.392225865788, 2534.2903986883703, 2500.237427584595, 2511.8744062796877, 2528.203615861007, 2533.1893793985932, 2536.327873113617, 2472.431340791619, 2482.416632091457, 2482.515909397668, 2491.9875400622973, 2511.623256511529, 2511.359603301165, 2526.873656317177, 2534.4297533055005, 2546.987265063663, 2552.4639825636546, 2464.778169964694, 2465.95068098637, 2471.369800700828, 2474.270090323359, 2475.0718480569785, 9646.228301735844, 9717.65988190417, 9830.655927042519, 9832.0, 9850.064243896819, 5585.497305242548, 5686.232358631573, 5742.770289188427, 5743.971280143573, 5744.080947048494, 3523.5243242162237, 3568.286342731464, 3592.2815436912724, 3602.3875941090078, 3676.650718181795, 1320.6038188543398, 1324.913880597758, 1330.5609148980814, 1349.7030653256268, 1363.3319673359981, 13606.591802245948, 13740.450381984709, 13768.173909565458, 13815.032947693666, 13866.237356945365, 4812.085703716282, 4825.758712064453, 4899.804007839646, 4955.851324460272, 5064.7214403207445, 10560.894391055992, 10577.259591828499, 10581.894181058246, 10601.522931468102, 10625.203109766644, 5533.391326954032, 5561.082421400504, 5566.276384070045, 5566.693831839207, 5577.52591029767, 4703.482616912137, 5089.033083714083, 5141.691498510101, 5153.149730294459, 5244.29202057725, 4153.61809489214, 4168.562300958416, 4225.091605304874, 4231.87304380869, 4260.829566817354, 1243.5461056714414, 1267.4486598329756, 1269.2638269803492, 1275.6045625856013, 1283.4481173095471, 944.4286206844897, 975.7560609847538, 989.7822479054596, 1005.100435110572, 1012.6911292055994, 5885.382034886361, 5900.704964751745, 5974.910376344358, 6012.609180403259, 6152.815415537451, 477.90680817240303, 487.29342453441944, 503.52920019781624, 508.42547920849205, 520.3859445853853, 3939.2318497893207, 3943.802809859553, 3951.723379363417, 3984.322665146932, 4008.6793056555407, 11967.64097077089, 12225.083118766128, 12245.510179592895, 12257.203281786655, 12298.20061695987, 12076.0, 12078.335691536875, 12080.818787718192, 12089.637310880687, 12109.394530273452, 8554.35842311829, 8836.646534138563, 8837.425567338103, 9003.909960900439, 9096.727098186933, 6477.838054048687, 6614.933850661432, 6751.223609284799, 6768.4585233182215, 6793.218779840184, 11204.49579768896, 11218.495167717309, 11351.716207094558, 11625.941870290595, 11634.767304653691, 11267.098632109575, 11507.42462876836, 11625.59310424131, 11775.352355620524, 11799.882001179878, 7819.178986206594, 8042.758717238492, 8389.11914248995, 8573.399862009599, 8669.869951950599, 9384.108509691569, 9748.756281093029, 9760.511974401394, 9761.41431514083, 9781.902180978384, 8281.089080201053, 8310.54292013945, 8404.327653787805, 8581.528015959182, 8635.82728345417, 8400.0, 8434.198751118634, 8437.789055273668, 8525.317974562144, 8526.872096918427, 7444.590547519939, 7460.0674915635545, 7523.1348394935285, 7526.059242594676, 7530.322270995636, 5373.629724420248, 5598.944010559842, 5626.85932851682, 5758.712064396847, 5835.44563266489, 5594.908992746376, 5670.8015219467125, 5790.420957904176, 5814.709264536841, 5892.145638882424], "value": 4915.878273311835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.3203784657764, 352.3252970561374, 357.94809752585877, 359.5272217034601, 364.6353646353648, 5647.265855438793, 5671.829845104643, 5694.316681998155, 5715.657060576358, 5737.225474560933, 5603.3836278009385, 5622.662640241589, 5623.80316688916, 5637.3088073577965, 5644.742103158738, 4164.396162556435, 4287.1854347674, 4346.609085092771, 4372.497162826284, 4426.313921342197, 4392.912141757163, 4402.889927751799, 4425.491068527129, 4444.0, 4454.13144436835, 4355.346697995295, 4362.171187474386, 4366.4105345778235, 4381.3647021181905, 4394.231009573312, 4238.682098842583, 4337.869863904072, 4364.956350436519, 4391.692581519295, 4402.886614425094, 3881.4177873318945, 3883.873676633773, 3902.180542086137, 3909.882703518899, 3918.6081391860585, 5397.568194544427, 5466.398696143421, 5468.398476167618, 5476.849861529044, 5504.6421982571, 4761.2143996240575, 4963.086544321132, 5025.547700706954, 5028.667403138172, 5093.083245015896, 5858.619189752695, 5858.882822343529, 5861.531077513789, 5880.529557635379, 5883.61756485827, 4236.724612900182, 4271.103068355649, 4273.829046838123, 4276.465441819772, 4284.807183676742, 5952.553558483131, 6031.8539477499135, 6097.2683278006925, 6108.358622344679, 6112.663803490851, 4326.134773045341, 4330.198913201068, 4331.415258940003, 4338.0890013097605, 4351.6518678505645, 4376.0, 4387.14450682114, 4409.40473036136, 4434.623057040188, 4437.2234858899565, 4894.8042078317185, 4945.357103576512, 4981.605150557777, 5000.799968001312, 5034.414159539733, 5862.530997520188, 5882.882342353215, 5896.233489646319, 5901.822945311647, 5907.877503274364, 1508.9320980555901, 1515.886308526865, 1525.3593490733917, 1531.379791184572, 1542.7531594944755, 952.8856537215579, 968.651285537206, 974.133021610767, 975.3124047546463, 1013.3717095400751, 5553.6390134641115, 5601.439856014366, 5624.634398764068, 5642.492175704207, 5650.519705824969, 5906.202662640489, 5955.13650520672, 5962.837246736932, 5983.072623743326, 5993.790217342459, 5465.234867118606, 5675.0, 5678.432156784371, 5791.913121303184, 5820.679862607512, 3196.6164060312444, 3213.4055199787645, 3224.4840825467813, 3235.53084802707, 3239.5788547488673, 6124.601900876429, 6218.657973811393, 6254.093156492283, 6275.529335299871, 6282.874342513124, 6083.387902205919, 6145.354737752561, 6166.691665416801, 6183.0, 6211.596246243979, 6031.487323577545, 6157.5517710082495, 6236.906446403307, 6243.40687634683, 6249.531285153632, 6035.18524999137, 6038.456538911519, 6052.66710330936, 6072.757089716539, 6077.848053798691, 5926.733297001559, 5929.584929054925, 5954.940450595612, 5970.701464926649, 6009.909851352146, 6089.147519347383, 6192.628442293477, 6219.937800622117, 6244.781432649836, 6250.0, 4356.346548017823, 4383.232934236559, 4397.252467080668, 4397.978010109929, 4419.491758447691, 5949.226600542075, 6096.75612975476, 6126.417990291094, 6136.907946380718, 6155.2921414037955, 5847.561432892635, 5876.735546900485, 5879.882822263756, 5902.20320256777, 5918.112283157388, 5850.268716410449, 5866.266716660418, 5868.2664666916635, 5875.001249787631, 5913.379095194942, 5630.268065151504, 5829.737661805144, 5853.385394533683, 5861.824145275648, 5870.412958704266, 5820.798560791633, 5854.385289544536, 5855.707214639166, 5881.911771323519, 5883.499902508163, 5523.447655234444, 5748.281464816898, 5767.336756273083, 5793.768249269982, 5798.33319168301], "value": 4499.915086226957, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.3444592656984, 814.2305521282389, 820.9466384684995, 905.2848249882593, 912.8174365126974, 16275.267612957416, 17617.91191044043, 17737.024463654492, 17744.64510709785, 17769.555761105996, 15489.92255038726, 16067.678646427064, 16244.187790610482, 16273.186340682949, 16359.91820040901, 741.3883546074483, 754.9924500754977, 855.2687452228348, 878.1613559051091, 882.8101958079012, 860.9281444601448, 882.3823323673422, 888.3381880499037, 890.9866352004693, 891.3537685178259, 934.850423932171, 940.2571968145157, 940.3323640215456, 940.6895724410962, 941.2234906202376, 926.7636752628036, 930.5347326336831, 931.2224292715523, 934.6728644974272, 935.0649350649351, 680.7617333933133, 682.8770821252173, 683.5795985468901, 683.8734834055715, 684.6063513479719, 16341.18294085291, 16383.508494745058, 16386.75419868703, 16387.59031024222, 16428.425005124882, 14540.0, 14598.416063357454, 14704.779428308584, 14828.406863725439, 15015.474458393905, 14273.214973176471, 14633.122012679274, 14922.925385373112, 14988.672857178442, 15367.0, 4860.076585448789, 4922.950770492285, 4923.90152196954, 5035.018171456568, 5064.290999260106, 89065.0, 91003.0, 91188.0, 93424.53287733586, 93541.53229233879, 12243.877561224273, 12326.0, 12329.938350308192, 12359.50561977511, 12482.50069997208, 21666.133354665635, 21853.781462185176, 21878.23426180108, 21945.341639750834, 22144.557108857734, 9252.907470925205, 9391.107844754737, 9435.49032154852, 9442.622295108256, 9557.426554406758, 5279.498447647467, 5375.301210842644, 5413.7834486620095, 5569.0254205513875, 5684.403137670568, 3629.0927268182954, 3737.663610275088, 3737.8131093445763, 3738.32710112177, 3739.457778622139, 627.802242293676, 637.3148864970176, 637.512303088138, 638.789199564152, 647.207171215267, 668.6422763821332, 710.8969199466048, 744.0810598910341, 745.4659905924575, 752.4319139049982, 10438.739031524272, 10498.685044439035, 10583.523741431654, 10642.521086551124, 10982.0, 562.9240052592929, 620.7951376045894, 662.2781168526329, 670.6881300195432, 742.9739959101405, 4983.277424773388, 4989.675671081368, 4999.625028122832, 5094.61790365724, 5102.464241254689, 104061.47969260105, 131331.0, 131449.34275328752, 133766.33116834355, 137396.31301843427, 108554.0, 110813.0, 113660.0, 115900.0, 116145.0, 57166.0, 71863.0, 96041.0, 96322.51838740762, 97583.51208244056, 9908.801823963624, 10004.949975250078, 10066.49667516636, 10072.0, 10085.344452610558, 56837.43162568322, 57650.711746441, 57902.7104864473, 58677.70661146838, 59431.70284148725, 75991.62004190165, 87228.0, 88775.55612221897, 89150.0, 90156.0, 11966.162368634112, 12224.266544007389, 12224.877751222373, 12437.75124497523, 12495.875041249472, 13130.563428691177, 13144.539941102015, 13215.60353189406, 13493.32533373347, 13495.527656531975, 12321.949032210065, 12717.300548469922, 12854.228746274879, 13185.472581097032, 13211.075224734177, 12511.687207819878, 12610.936945315214, 12893.41979610901, 13116.803247951479, 13381.130226535244, 11207.383593902414, 11323.433828308716, 11622.0702343814, 11802.586909458129, 12460.875391246334, 10744.194185435967, 10956.671299861016, 10964.232503724663, 11173.273737207055, 11589.304641721523, 10703.37458322311, 11009.06422954058, 11364.261323013978, 11396.373199474108, 11408.54365825391], "value": 8008.939897252575, "unit": "score"}], "name": "dromaeo_css", "value": 7645.122586803126, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 112.0, 113.0, 115.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 115.0, 154.0, 117.0, 133.0, 117.0, 116.0, 149.0, 121.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 162.0, 161.0, 161.0, 159.0, 160.0, 161.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 118.0, 76.0, 74.0, 75.0, 76.0, 98.0, 74.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 111.0, 81.0, 81.0, 83.0, 106.0, 80.0, 80.0, 114.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 48.0, 48.0, 49.0, 51.0, 48.0, 48.0, 51.0, 48.0], "value": 48.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0, 59.0, 58.0, 58.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 73.0, 75.0, 73.0, 73.0, 73.0, 78.0, 74.0, 80.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 120.0, 128.0, 122.0, 118.0, 118.0, 122.0, 163.0, 123.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 153.0, 157.0, 151.0, 153.0, 152.0, 151.0, 148.0, 153.0, 150.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 62.0, 62.0, 62.0, 64.0, 61.0, 60.0, 61.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1454.1999999999998, "alertThreshold": 2.0}]} 12:57:56 INFO - Return code: 0 12:57:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:57:56 INFO - # TBPL SUCCESS # 12:57:56 INFO - Running post-action listener: _resource_record_post_action 12:57:56 INFO - [mozharness: 2016-11-17 20:57:56.172876Z] Finished run-tests step (success) 12:57:56 INFO - Running post-run listener: _resource_record_post_run 12:57:56 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 313413632; Read time: 12; Write time: 201228 12:57:56 INFO - TinderboxPrint: CPU usage
12.9% 12:57:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:57:56 INFO - TinderboxPrint: I/O write bytes / time
313,413,632 / 201,228 12:57:56 INFO - TinderboxPrint: CPU idle
8,243.6 (87.1%) 12:57:56 INFO - TinderboxPrint: CPU user
1,182.9 (12.5%) 12:57:56 INFO - TinderboxPrint: Swap in / out
0 / 0 12:57:56 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 100548608; Read time: 0; Write time: 66824 12:57:56 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 212865024; Read time: 12; Write time: 134404 12:57:56 INFO - Running post-run listener: _upload_blobber_files 12:57:56 INFO - Blob upload gear active. 12:57:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:57:56 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 12:57:56 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'] 12:57:56 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 12:57:57 INFO - (blobuploader) - INFO - Open directory for files ... 12:57:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:57:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:57:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:00 INFO - (blobuploader) - INFO - Done attempting. 12:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:01 INFO - (blobuploader) - INFO - Done attempting. 12:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:02 INFO - (blobuploader) - INFO - Done attempting. 12:58:02 INFO - (blobuploader) - INFO - Iteration through files over. 12:58:02 INFO - Return code: 0 12:58:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:58:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:58:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c46ea58a5433fee8bd039587d2f02ac7d8963150039f2e4618a07402e282c0712e85edf30cf741ad102a1d9b4a10bc3f4c68c8fd229fe67f334eff056ff1c60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fefc8d77df27a73e3ca8e093fd6ee21720563c78cdc2e8d40d23ec148117a4149222e514210d522ef7a97267b9ebd21d796b069c3e22d3f28896f7c33d8d67b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d6907e9a2e6b0119365ab116787a3ec195ce1b9dbf1ab6be0ba917a3bfeab083477a50678939e0a38d2f7ad9ac37894ad387bec3687e6d345c85f99d7491733"} 12:58:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:58:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:58:02 INFO - Contents: 12:58:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c46ea58a5433fee8bd039587d2f02ac7d8963150039f2e4618a07402e282c0712e85edf30cf741ad102a1d9b4a10bc3f4c68c8fd229fe67f334eff056ff1c60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fefc8d77df27a73e3ca8e093fd6ee21720563c78cdc2e8d40d23ec148117a4149222e514210d522ef7a97267b9ebd21d796b069c3e22d3f28896f7c33d8d67b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d6907e9a2e6b0119365ab116787a3ec195ce1b9dbf1ab6be0ba917a3bfeab083477a50678939e0a38d2f7ad9ac37894ad387bec3687e6d345c85f99d7491733"} 12:58:02 INFO - Running post-run listener: copy_logs_to_upload_dir 12:58:02 INFO - Copying logs to upload dir... 12:58:02 INFO - mkdir: /builds/slave/test/build/upload/logs 12:58:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.068911 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-11-17 12:58:02.222718) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 12:58:02.226986) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202 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/6c46ea58a5433fee8bd039587d2f02ac7d8963150039f2e4618a07402e282c0712e85edf30cf741ad102a1d9b4a10bc3f4c68c8fd229fe67f334eff056ff1c60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fefc8d77df27a73e3ca8e093fd6ee21720563c78cdc2e8d40d23ec148117a4149222e514210d522ef7a97267b9ebd21d796b069c3e22d3f28896f7c33d8d67b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d6907e9a2e6b0119365ab116787a3ec195ce1b9dbf1ab6be0ba917a3bfeab083477a50678939e0a38d2f7ad9ac37894ad387bec3687e6d345c85f99d7491733"} build_url:https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://queue.taskcluster.net/v1/task/Rnov2LApQyy5Xe6ind15UQ/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/6c46ea58a5433fee8bd039587d2f02ac7d8963150039f2e4618a07402e282c0712e85edf30cf741ad102a1d9b4a10bc3f4c68c8fd229fe67f334eff056ff1c60", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fefc8d77df27a73e3ca8e093fd6ee21720563c78cdc2e8d40d23ec148117a4149222e514210d522ef7a97267b9ebd21d796b069c3e22d3f28896f7c33d8d67b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d6907e9a2e6b0119365ab116787a3ec195ce1b9dbf1ab6be0ba917a3bfeab083477a50678939e0a38d2f7ad9ac37894ad387bec3687e6d345c85f99d7491733"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 12:58:02.256971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:58:02.257346) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1479415051.829037-1235346202 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 12:58:02.344649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 12:58:02.345618) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 12:58:02.346536) ========= ========= Total master_lag: 0.18 =========