builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1478451670.83 results: success (0) buildid: 20161106062227 builduid: 495414f4320141a589210c7eb5cffb9d revision: 8027a7b723c9db7254bdb0bea3bc8937220daa7e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:10.831597) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:10.832024) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:10.832352) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:10.854281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:10.854575) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-06 09:01:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.3M=0s 2016-11-06 09:01:11 (35.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115740 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:10.986287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:10.986562) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021347 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:11.038743) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:11.039050) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8027a7b723c9db7254bdb0bea3bc8937220daa7e --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-aurora --rev 8027a7b723c9db7254bdb0bea3bc8937220daa7e --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=9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-06 09:01:11,550 truncating revision to first 12 chars 2016-11-06 09:01:11,550 Setting DEBUG logging. 2016-11-06 09:01:11,550 attempt 1/10 2016-11-06 09:01:11,550 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8027a7b723c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-06 09:01:11,922 unpacking tar archive at: mozilla-aurora-8027a7b723c9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.767346 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:11.819811) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:11.820079) ========= script_repo_revision: 8027a7b723c9db7254bdb0bea3bc8937220daa7e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:11.820452) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:11.820713) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-06 09:01:11.829803) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-11-06 09:01:11.830069) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:01:12 INFO - MultiFileLogger online at 20161106 09:01:12 in /builds/slave/test 09:01:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:01:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:12 INFO - {'append_to_log': False, 09:01:12 INFO - 'base_work_dir': '/builds/slave/test', 09:01:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:01:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:12 INFO - 'branch': 'Mozilla-Aurora', 09:01:12 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:12 INFO - 'config_files': ('talos/linux_config.py',), 09:01:12 INFO - 'default_actions': ('clobber', 09:01:12 INFO - 'read-buildbot-config', 09:01:12 INFO - 'download-and-extract', 09:01:12 INFO - 'populate-webroot', 09:01:12 INFO - 'create-virtualenv', 09:01:12 INFO - 'install', 09:01:12 INFO - 'run-tests'), 09:01:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:12 INFO - 'download_minidump_stackwalk': True, 09:01:12 INFO - 'download_symbols': 'ondemand', 09:01:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:12 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:12 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:12 INFO - 'installer_path': 'installer.exe', 09:01:12 INFO - 'log_level': 'info', 09:01:12 INFO - 'log_name': 'talos', 09:01:12 INFO - 'log_to_console': True, 09:01:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:01:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:01:12 INFO - 'opt_config_files': (), 09:01:12 INFO - 'pip_index': False, 09:01:12 INFO - 'sps_profile': False, 09:01:12 INFO - 'sps_profile_interval': 0, 09:01:12 INFO - 'suite': 'dromaeojs', 09:01:12 INFO - 'system_bits': '32', 09:01:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:12 INFO - 'title': 'talos-linux64-ix-013', 09:01:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:12 INFO - 'use_talos_json': True, 09:01:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:12 INFO - 'work_dir': 'build'} 09:01:12 INFO - [mozharness: 2016-11-06 17:01:12.439651Z] Running clobber step. 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: clobber 09:01:12 INFO - rmtree: /builds/slave/test/build 09:01:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - [mozharness: 2016-11-06 17:01:12.896777Z] Finished clobber step (success) 09:01:12 INFO - [mozharness: 2016-11-06 17:01:12.896878Z] Running read-buildbot-config step. 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: read_buildbot_config 09:01:12 INFO - Using buildbot properties: 09:01:12 INFO - { 09:01:12 INFO - "project": "", 09:01:12 INFO - "product": "firefox", 09:01:12 INFO - "script_repo_revision": "production", 09:01:12 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:01:12 INFO - "repository": "", 09:01:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:01:12 INFO - "buildid": "20161106062227", 09:01:12 INFO - "pgo_build": "True", 09:01:12 INFO - "basedir": "/builds/slave/test", 09:01:12 INFO - "buildnumber": 862, 09:01:12 INFO - "slavename": "talos-linux64-ix-013", 09:01:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:01:12 INFO - "platform": "ubuntu64_hw", 09:01:12 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:01:12 INFO - "branch": "mozilla-aurora", 09:01:12 INFO - "repo_path": "releases/mozilla-aurora", 09:01:12 INFO - "revision": "8027a7b723c9db7254bdb0bea3bc8937220daa7e", 09:01:12 INFO - "stage_platform": "linux64-pgo", 09:01:12 INFO - "builduid": "495414f4320141a589210c7eb5cffb9d", 09:01:12 INFO - "slavebuilddir": "test" 09:01:12 INFO - } 09:01:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - [mozharness: 2016-11-06 17:01:12.899633Z] Finished read-buildbot-config step (success) 09:01:12 INFO - [mozharness: 2016-11-06 17:01:12.899723Z] Running download-and-extract step. 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: download_and_extract 09:01:12 INFO - mkdir: /builds/slave/test/build/tests 09:01:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:12 INFO - trying https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:01:12 INFO - Downloading https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:01:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:01:13 INFO - Downloaded 1361 bytes. 09:01:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:01:13 INFO - Using the following test package requirements: 09:01:13 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:01:13 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:01:13 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:01:13 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'jsshell-linux-x86_64.zip'], 09:01:13 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:01:13 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:01:13 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:01:13 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:01:13 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:01:13 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:01:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:01:13 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:01:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:01:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:01:13 INFO - Fetch https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:01:14 INFO - Expected file size: 21895022 09:01:18 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:01:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:01:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:01:18 INFO - Fetch https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:01:18 INFO - Expected file size: 21895022 09:01:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:01:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:01:23 INFO - Fetch https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:01:24 INFO - Expected file size: 14414089 09:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:25 INFO - trying https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:01:25 INFO - Downloading https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:01:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:01:27 INFO - Downloaded 63977852 bytes. 09:01:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:01:27 INFO - mkdir: /builds/slave/test/properties 09:01:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:27 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:27 INFO - Contents: 09:01:27 INFO - build_url:https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:01:28 INFO - Running post-action listener: _resource_record_post_action 09:01:28 INFO - Running post-action listener: set_extra_try_arguments 09:01:28 INFO - [mozharness: 2016-11-06 17:01:28.411529Z] Finished download-and-extract step (success) 09:01:28 INFO - [mozharness: 2016-11-06 17:01:28.411758Z] Running populate-webroot step. 09:01:28 INFO - Running pre-action listener: _resource_record_pre_action 09:01:28 INFO - Running main action method: populate_webroot 09:01:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:28 INFO - '--tppagecycles', 09:01:28 INFO - '7'], 09:01:28 INFO - 'tests': ['tsvgm']}}, 09:01:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['damp', 'tps']}, 09:01:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'tests': ['damp', 'tps']}, 09:01:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['dromaeo_dom']}, 09:01:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['basic_compositor_video']}, 09:01:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:01:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['a11yr', 09:01:28 INFO - 'ts_paint', 09:01:28 INFO - 'tpaint', 09:01:28 INFO - 'sessionrestore', 09:01:28 INFO - 'sessionrestore_no_auto_restore', 09:01:28 INFO - 'tabpaint']}, 09:01:28 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:28 INFO - 'ts_paint', 09:01:28 INFO - 'tpaint', 09:01:28 INFO - 'sessionrestore', 09:01:28 INFO - 'sessionrestore_no_auto_restore', 09:01:28 INFO - 'tabpaint']}, 09:01:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:28 INFO - 'ts_paint', 09:01:28 INFO - 'tpaint', 09:01:28 INFO - 'sessionrestore', 09:01:28 INFO - 'sessionrestore_no_auto_restore', 09:01:28 INFO - 'tabpaint']}, 09:01:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:28 INFO - 'ts_paint', 09:01:28 INFO - 'tpaint', 09:01:28 INFO - 'sessionrestore', 09:01:28 INFO - 'sessionrestore_no_auto_restore', 09:01:28 INFO - 'tabpaint']}, 09:01:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['a11yr', 09:01:28 INFO - 'ts_paint', 09:01:28 INFO - 'tpaint', 09:01:28 INFO - 'sessionrestore', 09:01:28 INFO - 'sessionrestore_no_auto_restore', 09:01:28 INFO - 'tabpaint']}, 09:01:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['a11yr', 09:01:28 INFO - 'ts_paint', 09:01:28 INFO - 'tpaint', 09:01:28 INFO - 'sessionrestore', 09:01:28 INFO - 'sessionrestore_no_auto_restore', 09:01:28 INFO - 'tabpaint']}, 09:01:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['tsvgx', 09:01:28 INFO - 'tsvgr_opacity', 09:01:28 INFO - 'tart', 09:01:28 INFO - 'tscrollx', 09:01:28 INFO - 'cart']}, 09:01:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:28 INFO - 'tsvgr_opacity', 09:01:28 INFO - 'tart', 09:01:28 INFO - 'tscrollx', 09:01:28 INFO - 'cart']}, 09:01:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'talos_options': ['--disable-e10s'], 09:01:28 INFO - 'tests': ['tp5o']}, 09:01:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'tests': ['tp5o']}, 09:01:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'talos_options': ['--disable-e10s', 09:01:28 INFO - '--xperf_path', 09:01:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:28 INFO - 'tests': ['tp5n']}, 09:01:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:28 INFO - 'talos_options': ['--xperf_path', 09:01:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:28 INFO - 'tests': ['tp5n']}}, 09:01:28 INFO - 'talos.zip': {'path': '', 09:01:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:28 INFO - Running post-action listener: _resource_record_post_action 09:01:28 INFO - [mozharness: 2016-11-06 17:01:28.431186Z] Finished populate-webroot step (success) 09:01:28 INFO - [mozharness: 2016-11-06 17:01:28.431275Z] Running create-virtualenv step. 09:01:28 INFO - Running pre-action listener: _resource_record_pre_action 09:01:28 INFO - Running main action method: create_virtualenv 09:01:28 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:01:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:28 INFO - Using real prefix '/usr' 09:01:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:29 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:30 INFO - Installing pip.................done. 09:01:31 INFO - Return code: 0 09:01:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:01:31 INFO - Using env: {'DISPLAY': ':0', 09:01:31 INFO - 'HOME': '/home/cltbld', 09:01:31 INFO - 'LANG': 'en_US.UTF-8', 09:01:31 INFO - 'LANGUAGE': 'en_US:en', 09:01:31 INFO - 'LOGNAME': 'cltbld', 09:01:31 INFO - 'MAIL': '/var/mail/cltbld', 09:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:31 INFO - 'MOZ_NO_REMOTE': '1', 09:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:31 INFO - 'NO_EM_RESTART': '1', 09:01:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:31 INFO - 'PWD': '/builds/slave/test', 09:01:31 INFO - 'SHELL': '/bin/bash', 09:01:31 INFO - 'SHLVL': '1', 09:01:31 INFO - 'TERM': 'linux', 09:01:31 INFO - 'TMOUT': '86400', 09:01:31 INFO - 'USER': 'cltbld', 09:01:31 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:31 INFO - '_': '/tools/buildbot/bin/python'} 09:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:01:31 INFO - Cleaning up... 09:01:31 INFO - Return code: 0 09:01:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:01:31 INFO - Using env: {'DISPLAY': ':0', 09:01:31 INFO - 'HOME': '/home/cltbld', 09:01:31 INFO - 'LANG': 'en_US.UTF-8', 09:01:31 INFO - 'LANGUAGE': 'en_US:en', 09:01:31 INFO - 'LOGNAME': 'cltbld', 09:01:31 INFO - 'MAIL': '/var/mail/cltbld', 09:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:31 INFO - 'MOZ_NO_REMOTE': '1', 09:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:31 INFO - 'NO_EM_RESTART': '1', 09:01:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:31 INFO - 'PWD': '/builds/slave/test', 09:01:31 INFO - 'SHELL': '/bin/bash', 09:01:31 INFO - 'SHLVL': '1', 09:01:31 INFO - 'TERM': 'linux', 09:01:31 INFO - 'TMOUT': '86400', 09:01:31 INFO - 'USER': 'cltbld', 09:01:31 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:31 INFO - '_': '/tools/buildbot/bin/python'} 09:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:31 INFO - Downloading/unpacking psutil>=3.1.1 09:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:33 INFO - Installing collected packages: psutil 09:01:33 INFO - Running setup.py install for psutil 09:01:33 INFO - building 'psutil._psutil_linux' extension 09:01:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:01:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:01:33 INFO - building 'psutil._psutil_posix' extension 09:01:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:01:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:01:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:33 INFO - Successfully installed psutil 09:01:33 INFO - Cleaning up... 09:01:34 INFO - Return code: 0 09:01:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:01:34 INFO - Using env: {'DISPLAY': ':0', 09:01:34 INFO - 'HOME': '/home/cltbld', 09:01:34 INFO - 'LANG': 'en_US.UTF-8', 09:01:34 INFO - 'LANGUAGE': 'en_US:en', 09:01:34 INFO - 'LOGNAME': 'cltbld', 09:01:34 INFO - 'MAIL': '/var/mail/cltbld', 09:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:34 INFO - 'MOZ_NO_REMOTE': '1', 09:01:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:34 INFO - 'NO_EM_RESTART': '1', 09:01:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:34 INFO - 'PWD': '/builds/slave/test', 09:01:34 INFO - 'SHELL': '/bin/bash', 09:01:34 INFO - 'SHLVL': '1', 09:01:34 INFO - 'TERM': 'linux', 09:01:34 INFO - 'TMOUT': '86400', 09:01:34 INFO - 'USER': 'cltbld', 09:01:34 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:34 INFO - '_': '/tools/buildbot/bin/python'} 09:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:01:36 INFO - Installing collected packages: mozsystemmonitor 09:01:36 INFO - Running setup.py install for mozsystemmonitor 09:01:36 INFO - Successfully installed mozsystemmonitor 09:01:36 INFO - Cleaning up... 09:01:36 INFO - Return code: 0 09:01:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:36 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 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:01:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:01:36 INFO - Using env: {'DISPLAY': ':0', 09:01:36 INFO - 'HOME': '/home/cltbld', 09:01:36 INFO - 'LANG': 'en_US.UTF-8', 09:01:36 INFO - 'LANGUAGE': 'en_US:en', 09:01:36 INFO - 'LOGNAME': 'cltbld', 09:01:36 INFO - 'MAIL': '/var/mail/cltbld', 09:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:36 INFO - 'MOZ_NO_REMOTE': '1', 09:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:36 INFO - 'NO_EM_RESTART': '1', 09:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:36 INFO - 'PWD': '/builds/slave/test', 09:01:36 INFO - 'SHELL': '/bin/bash', 09:01:36 INFO - 'SHLVL': '1', 09:01:36 INFO - 'TERM': 'linux', 09:01:36 INFO - 'TMOUT': '86400', 09:01:36 INFO - 'USER': 'cltbld', 09:01:36 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:36 INFO - '_': '/tools/buildbot/bin/python'} 09:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:36 INFO - Downloading/unpacking jsonschema==2.5.1 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:38 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:38 INFO - warning: no files found matching '*.txt' 09:01:38 INFO - no previously-included directories found matching 'build' 09:01:38 INFO - no previously-included directories found matching 'dist' 09:01:38 INFO - no previously-included directories found matching '.git*' 09:01:38 INFO - Installing collected packages: jsonschema, functools32 09:01:38 INFO - Running setup.py install for functools32 09:01:38 INFO - warning: no files found matching '*.txt' 09:01:38 INFO - no previously-included directories found matching 'build' 09:01:38 INFO - no previously-included directories found matching 'dist' 09:01:38 INFO - no previously-included directories found matching '.git*' 09:01:38 INFO - Successfully installed jsonschema functools32 09:01:38 INFO - Cleaning up... 09:01:38 INFO - Return code: 0 09:01:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:38 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 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:01:39 INFO - Using env: {'DISPLAY': ':0', 09:01:39 INFO - 'HOME': '/home/cltbld', 09:01:39 INFO - 'LANG': 'en_US.UTF-8', 09:01:39 INFO - 'LANGUAGE': 'en_US:en', 09:01:39 INFO - 'LOGNAME': 'cltbld', 09:01:39 INFO - 'MAIL': '/var/mail/cltbld', 09:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:39 INFO - 'MOZ_NO_REMOTE': '1', 09:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:39 INFO - 'NO_EM_RESTART': '1', 09:01:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:39 INFO - 'PWD': '/builds/slave/test', 09:01:39 INFO - 'SHELL': '/bin/bash', 09:01:39 INFO - 'SHLVL': '1', 09:01:39 INFO - 'TERM': 'linux', 09:01:39 INFO - 'TMOUT': '86400', 09:01:39 INFO - 'USER': 'cltbld', 09:01:39 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:39 INFO - '_': '/tools/buildbot/bin/python'} 09:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:01:39 INFO - Cleaning up... 09:01:39 INFO - Return code: 0 09:01:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:01:39 INFO - Using env: {'DISPLAY': ':0', 09:01:39 INFO - 'HOME': '/home/cltbld', 09:01:39 INFO - 'LANG': 'en_US.UTF-8', 09:01:39 INFO - 'LANGUAGE': 'en_US:en', 09:01:39 INFO - 'LOGNAME': 'cltbld', 09:01:39 INFO - 'MAIL': '/var/mail/cltbld', 09:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:39 INFO - 'MOZ_NO_REMOTE': '1', 09:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:39 INFO - 'NO_EM_RESTART': '1', 09:01:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:39 INFO - 'PWD': '/builds/slave/test', 09:01:39 INFO - 'SHELL': '/bin/bash', 09:01:39 INFO - 'SHLVL': '1', 09:01:39 INFO - 'TERM': 'linux', 09:01:39 INFO - 'TMOUT': '86400', 09:01:39 INFO - 'USER': 'cltbld', 09:01:39 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:39 INFO - '_': '/tools/buildbot/bin/python'} 09:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:39 INFO - Downloading/unpacking blobuploader==1.2.4 09:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:01:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:42 INFO - Downloading docopt-0.6.1.tar.gz 09:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:42 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:42 INFO - Running setup.py install for blobuploader 09:01:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:42 INFO - Running setup.py install for requests 09:01:42 INFO - Running setup.py install for docopt 09:01:42 INFO - Successfully installed blobuploader requests docopt 09:01:42 INFO - Cleaning up... 09:01:42 INFO - Return code: 0 09:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:42 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 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:42 INFO - Using env: {'DISPLAY': ':0', 09:01:42 INFO - 'HOME': '/home/cltbld', 09:01:42 INFO - 'LANG': 'en_US.UTF-8', 09:01:42 INFO - 'LANGUAGE': 'en_US:en', 09:01:42 INFO - 'LOGNAME': 'cltbld', 09:01:42 INFO - 'MAIL': '/var/mail/cltbld', 09:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:42 INFO - 'MOZ_NO_REMOTE': '1', 09:01:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:42 INFO - 'NO_EM_RESTART': '1', 09:01:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:42 INFO - 'PWD': '/builds/slave/test', 09:01:42 INFO - 'SHELL': '/bin/bash', 09:01:42 INFO - 'SHLVL': '1', 09:01:42 INFO - 'TERM': 'linux', 09:01:42 INFO - 'TMOUT': '86400', 09:01:42 INFO - 'USER': 'cltbld', 09:01:42 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:42 INFO - '_': '/tools/buildbot/bin/python'} 09:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:43 INFO - Running setup.py (path:/tmp/pip-Bo0EpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:43 INFO - Running setup.py (path:/tmp/pip-yvPd3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:43 INFO - Running setup.py (path:/tmp/pip-Z08szv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:43 INFO - Running setup.py (path:/tmp/pip-Cfn3S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:43 INFO - Running setup.py (path:/tmp/pip-3OqT5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:43 INFO - Running setup.py (path:/tmp/pip-BY7Hey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:43 INFO - Running setup.py (path:/tmp/pip-b9duHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:43 INFO - Running setup.py (path:/tmp/pip-WMCDf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:43 INFO - Running setup.py (path:/tmp/pip-iATlXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:43 INFO - Running setup.py (path:/tmp/pip-GVga2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:43 INFO - Running setup.py (path:/tmp/pip-8auSHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:43 INFO - Running setup.py (path:/tmp/pip-53Wadv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:44 INFO - Running setup.py (path:/tmp/pip-O7WWaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:44 INFO - Running setup.py (path:/tmp/pip-AxMz3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:44 INFO - Running setup.py (path:/tmp/pip-Swx5gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:44 INFO - Running setup.py (path:/tmp/pip-oKdIbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:44 INFO - Running setup.py (path:/tmp/pip-I5zSAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:44 INFO - Running setup.py install for manifestparser 09:01:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Running setup.py install for mozcrash 09:01:44 INFO - Running setup.py install for mozdebug 09:01:44 INFO - Running setup.py install for mozdevice 09:01:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Running setup.py install for mozfile 09:01:44 INFO - Running setup.py install for mozhttpd 09:01:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Running setup.py install for mozinfo 09:01:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Running setup.py install for mozInstall 09:01:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Running setup.py install for mozleak 09:01:45 INFO - Running setup.py install for mozlog 09:01:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Running setup.py install for moznetwork 09:01:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Running setup.py install for mozprocess 09:01:45 INFO - Running setup.py install for mozprofile 09:01:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Running setup.py install for mozrunner 09:01:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:45 INFO - Running setup.py install for mozscreenshot 09:01:45 INFO - Running setup.py install for moztest 09:01:46 INFO - Running setup.py install for mozversion 09:01:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:46 INFO - Cleaning up... 09:01:46 INFO - Return code: 0 09:01:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:46 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 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:46 INFO - Using env: {'DISPLAY': ':0', 09:01:46 INFO - 'HOME': '/home/cltbld', 09:01:46 INFO - 'LANG': 'en_US.UTF-8', 09:01:46 INFO - 'LANGUAGE': 'en_US:en', 09:01:46 INFO - 'LOGNAME': 'cltbld', 09:01:46 INFO - 'MAIL': '/var/mail/cltbld', 09:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:46 INFO - 'MOZ_NO_REMOTE': '1', 09:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:46 INFO - 'NO_EM_RESTART': '1', 09:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:46 INFO - 'PWD': '/builds/slave/test', 09:01:46 INFO - 'SHELL': '/bin/bash', 09:01:46 INFO - 'SHLVL': '1', 09:01:46 INFO - 'TERM': 'linux', 09:01:46 INFO - 'TMOUT': '86400', 09:01:46 INFO - 'USER': 'cltbld', 09:01:46 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:46 INFO - '_': '/tools/buildbot/bin/python'} 09:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:46 INFO - Running setup.py (path:/tmp/pip-5T9VlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:46 INFO - Running setup.py (path:/tmp/pip-Mw1vE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:46 INFO - Running setup.py (path:/tmp/pip-JxK4NT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:46 INFO - Running setup.py (path:/tmp/pip-VgI1jP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:46 INFO - Running setup.py (path:/tmp/pip-S_NSnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:46 INFO - Running setup.py (path:/tmp/pip-4UYGwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:46 INFO - Running setup.py (path:/tmp/pip-DbzzsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:46 INFO - Running setup.py (path:/tmp/pip-BZwFTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:46 INFO - Running setup.py (path:/tmp/pip-tJtoGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:47 INFO - Running setup.py (path:/tmp/pip-OI2qBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:47 INFO - Running setup.py (path:/tmp/pip-xKck1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:47 INFO - Running setup.py (path:/tmp/pip-L4Om3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:47 INFO - Running setup.py (path:/tmp/pip-wXiRua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:47 INFO - Running setup.py (path:/tmp/pip-AI4TyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:47 INFO - Running setup.py (path:/tmp/pip-yNj3sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:47 INFO - Running setup.py (path:/tmp/pip-w_R71b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:47 INFO - Running setup.py (path:/tmp/pip-HhAw2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:01:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:49 INFO - Downloading blessings-1.6.tar.gz 09:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:49 INFO - Installing collected packages: blessings 09:01:49 INFO - Running setup.py install for blessings 09:01:49 INFO - Successfully installed blessings 09:01:49 INFO - Cleaning up... 09:01:49 INFO - Return code: 0 09:01:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:50 INFO - Reading from file tmpfile_stdout 09:01:50 INFO - Current package versions: 09:01:50 INFO - argparse == 1.2.1 09:01:50 INFO - blessings == 1.6 09:01:50 INFO - blobuploader == 1.2.4 09:01:50 INFO - docopt == 0.6.1 09:01:50 INFO - functools32 == 3.2.3-2 09:01:50 INFO - jsonschema == 2.5.1 09:01:50 INFO - manifestparser == 1.1 09:01:50 INFO - mozInstall == 1.12 09:01:50 INFO - mozcrash == 0.17 09:01:50 INFO - mozdebug == 0.1 09:01:50 INFO - mozdevice == 0.48 09:01:50 INFO - mozfile == 1.2 09:01:50 INFO - mozhttpd == 0.7 09:01:50 INFO - mozinfo == 0.9 09:01:50 INFO - mozleak == 0.1 09:01:50 INFO - mozlog == 3.4 09:01:50 INFO - moznetwork == 0.27 09:01:50 INFO - mozprocess == 0.23 09:01:50 INFO - mozprofile == 0.28 09:01:50 INFO - mozrunner == 6.12 09:01:50 INFO - mozscreenshot == 0.1 09:01:50 INFO - mozsystemmonitor == 0.3 09:01:50 INFO - moztest == 0.7 09:01:50 INFO - mozversion == 1.4 09:01:50 INFO - psutil == 3.1.1 09:01:50 INFO - requests == 1.2.3 09:01:50 INFO - wsgiref == 0.1.2 09:01:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:50 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 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:01:50 INFO - Using env: {'DISPLAY': ':0', 09:01:50 INFO - 'HOME': '/home/cltbld', 09:01:50 INFO - 'LANG': 'en_US.UTF-8', 09:01:50 INFO - 'LANGUAGE': 'en_US:en', 09:01:50 INFO - 'LOGNAME': 'cltbld', 09:01:50 INFO - 'MAIL': '/var/mail/cltbld', 09:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:50 INFO - 'MOZ_NO_REMOTE': '1', 09:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:50 INFO - 'NO_EM_RESTART': '1', 09:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:50 INFO - 'PWD': '/builds/slave/test', 09:01:50 INFO - 'SHELL': '/bin/bash', 09:01:50 INFO - 'SHLVL': '1', 09:01:50 INFO - 'TERM': 'linux', 09:01:50 INFO - 'TMOUT': '86400', 09:01:50 INFO - 'USER': 'cltbld', 09:01:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:50 INFO - '_': '/tools/buildbot/bin/python'} 09:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:50 INFO - Cleaning up... 09:01:50 INFO - Return code: 0 09:01:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9458725f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:01: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', 'jsonschema'] in /builds/slave/test/build 09:01: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 jsonschema 09:01:50 INFO - Using env: {'DISPLAY': ':0', 09:01:50 INFO - 'HOME': '/home/cltbld', 09:01:50 INFO - 'LANG': 'en_US.UTF-8', 09:01:50 INFO - 'LANGUAGE': 'en_US:en', 09:01:50 INFO - 'LOGNAME': 'cltbld', 09:01:50 INFO - 'MAIL': '/var/mail/cltbld', 09:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:50 INFO - 'MOZ_NO_REMOTE': '1', 09:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:50 INFO - 'NO_EM_RESTART': '1', 09:01:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:50 INFO - 'PWD': '/builds/slave/test', 09:01:50 INFO - 'SHELL': '/bin/bash', 09:01:50 INFO - 'SHLVL': '1', 09:01:50 INFO - 'TERM': 'linux', 09:01:50 INFO - 'TMOUT': '86400', 09:01:50 INFO - 'USER': 'cltbld', 09:01:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:50 INFO - '_': '/tools/buildbot/bin/python'} 09:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:01:50 INFO - Cleaning up... 09:01:50 INFO - Return code: 0 09:01:50 INFO - Running post-action listener: _resource_record_post_action 09:01:50 INFO - Running post-action listener: _start_resource_monitoring 09:01:50 INFO - Starting resource monitoring. 09:01:50 INFO - [mozharness: 2016-11-06 17:01:50.514331Z] Finished create-virtualenv step (success) 09:01:50 INFO - [mozharness: 2016-11-06 17:01:50.514824Z] Running install step. 09:01:50 INFO - Running pre-action listener: _resource_record_pre_action 09:01:50 INFO - Running main action method: install 09:01:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:50 INFO - Reading from file tmpfile_stdout 09:01:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:50 INFO - Reading from file tmpfile_stdout 09:01:50 INFO - Output received: 09:01:50 INFO - Usage: mozinstall [options] installer 09:01:50 INFO - Options: 09:01:50 INFO - -h, --help show this help message and exit 09:01:50 INFO - -d DEST, --destination=DEST 09:01:50 INFO - Directory to install application into. [default: 09:01:50 INFO - "/builds/slave/test"] 09:01:50 INFO - --app=APP Application being installed. [default: firefox] 09:01:50 INFO - mkdir: /builds/slave/test/build/application 09:01:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:02:06 INFO - Reading from file tmpfile_stdout 09:02:06 INFO - Output received: 09:02:06 INFO - /builds/slave/test/build/application/firefox/firefox 09:02:06 INFO - Running post-action listener: _resource_record_post_action 09:02:06 INFO - [mozharness: 2016-11-06 17:02:06.777016Z] Finished install step (success) 09:02:06 INFO - [mozharness: 2016-11-06 17:02:06.777302Z] Running run-tests step. 09:02:06 INFO - Running pre-action listener: _resource_record_pre_action 09:02:06 INFO - Running main action method: run_tests 09:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:06 INFO - Python 2.7.3 09:02:06 INFO - Return code: 0 09:02:06 INFO - grabbing minidump binary from tooltool 09:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:06 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 0xd35f60>, '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 0xd36d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd371d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:02:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:02:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:02:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:06 INFO - Return code: 0 09:02:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:02:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:02:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:02:06 INFO - Using env: {'DISPLAY': ':0', 09:02:06 INFO - 'HOME': '/home/cltbld', 09:02:06 INFO - 'LANG': 'en_US.UTF-8', 09:02:06 INFO - 'LANGUAGE': 'en_US:en', 09:02:06 INFO - 'LOGNAME': 'cltbld', 09:02:06 INFO - 'MAIL': '/var/mail/cltbld', 09:02:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:06 INFO - 'MOZ_NO_REMOTE': '1', 09:02:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:06 INFO - 'NO_EM_RESTART': '1', 09:02:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:06 INFO - 'PWD': '/builds/slave/test', 09:02:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:06 INFO - 'SHELL': '/bin/bash', 09:02:06 INFO - 'SHLVL': '1', 09:02:06 INFO - 'TERM': 'linux', 09:02:06 INFO - 'TMOUT': '86400', 09:02:06 INFO - 'USER': 'cltbld', 09:02:06 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987', 09:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:06 INFO - '_': '/tools/buildbot/bin/python'} 09:02:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:02:07 INFO - mozversion application_buildid: 20161106062227 09:02:07 INFO - mozversion application_changeset: 8027a7b723c9db7254bdb0bea3bc8937220daa7e 09:02:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:02:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:07 INFO - mozversion application_name: Firefox 09:02:07 INFO - mozversion application_remotingname: firefox-dev 09:02:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:02:07 INFO - mozversion application_vendor: Mozilla 09:02:07 INFO - mozversion application_version: 51.0a2 09:02:07 INFO - mozversion platform_buildid: 20161106062227 09:02:07 INFO - mozversion platform_changeset: 8027a7b723c9db7254bdb0bea3bc8937220daa7e 09:02:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:02:07 INFO - mozversion platform_version: 51.0a2 09:02:07 INFO - using testdate: 1478451727 09:02:07 INFO - actual date: 1478451727 09:02:07 INFO - starting webserver on 'localhost:49931' 09:02:07 INFO - SUITE-START | Running 2 tests 09:02:07 INFO - TEST-START | dromaeo_css 09:02:07 INFO - operating with platform_type : linux_ 09:02:07 INFO - Initialising browser for dromaeo_css test... 09:02:07 INFO - TEST-INFO | started process 29209 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMnODQJ/profile http://localhost:49931/getInfo.html) 09:02:11 INFO - PROCESS | 29209 | __metrics Screen width/height:1600/1200 09:02:11 INFO - PROCESS | 29209 | colorDepth:24 09:02:11 INFO - PROCESS | 29209 | Browser inner width/height: 1024/697 09:02:11 INFO - PROCESS | 29209 | __metrics 09:02:11 INFO - PROCESS | 29209 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:02:11 INFO - PROCESS | 29209 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:02:12 INFO - PROCESS | 29209 | console.error: PushService: 09:02:12 INFO - PROCESS | 29209 | stateChangeProcessEnqueue: Error transitioning state 09:02:12 INFO - PROCESS | 29209 | UnknownError 09:02:12 INFO - TEST-INFO | 29209: exit 0 09:02:12 INFO - Browser initialized. 09:02:12 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:12 INFO - TEST-INFO | started process 29297 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMnODQJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:24 INFO - PROCESS | 29297 | RSS: Main: 145076224 09:02:24 INFO - PROCESS | 29297 | 09:03:15 INFO - PROCESS | 29297 | 1478451795868 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:05:25 INFO - PROCESS | 29297 | Cycle 1(1): loaded http://localhost:49931/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49931/tests/dromaeo/cssquery-ext.html) 09:05:26 INFO - PROCESS | 29297 | RSS: Main: 263180288 09:05:26 INFO - PROCESS | 29297 | 09:08:28 INFO - PROCESS | 29297 | Cycle 1(1): loaded http://localhost:49931/tests/dromaeo/cssquery-ext.html (next: http://localhost:49931/tests/dromaeo/cssquery-jquery.html) 09:08:29 INFO - PROCESS | 29297 | RSS: Main: 229724160 09:08:29 INFO - PROCESS | 29297 | 09:11:31 INFO - PROCESS | 29297 | Cycle 1(1): loaded http://localhost:49931/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49931/tests/dromaeo/cssquery-mootools.html) 09:11:31 INFO - PROCESS | 29297 | RSS: Main: 233361408 09:11:31 INFO - PROCESS | 29297 | 09:14:33 INFO - PROCESS | 29297 | Cycle 1(1): loaded http://localhost:49931/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49931/tests/dromaeo/cssquery-prototype.html) 09:14:34 INFO - PROCESS | 29297 | RSS: Main: 225615872 09:14:34 INFO - PROCESS | 29297 | 09:17:37 INFO - PROCESS | 29297 | Cycle 1(1): loaded http://localhost:49931/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49931/tests/dromaeo/cssquery-yui.html) 09:17:37 INFO - PROCESS | 29297 | RSS: Main: 173371392 09:17:37 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | Cycle 1(1): loaded http://localhost:49931/tests/dromaeo/cssquery-yui.html (next: http://localhost:49931/tests/dromaeo/cssquery-dojo.html) 09:20:40 INFO - PROCESS | 29297 | RSS: Main: 266571776 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | __start_tp_report 09:20:40 INFO - PROCESS | 29297 | _x_x_mozilla_page_load 09:20:40 INFO - PROCESS | 29297 | _x_x_mozilla_page_load_details 09:20:40 INFO - PROCESS | 29297 | |i|pagename|runs| 09:20:40 INFO - PROCESS | 29297 | |0;dojo.html;3143.5913331266934;3173.7143657070865;3196.5365022071787;3210.6949839765216;3229.354129174166;5563.805266815662;5647.9717601411985;5667.348254950672;5683.460071293231;5700.515456186224;5654.491095801377;5724.026915424378;5724.141378793183;5738.612005622969;5762.3373312069125;5132.28148059272;5158.716270605115;5183.144781111112;5191.351081114861;5240.711760853152;4972;5239.187925871477;5248.8687782805355;5260.92108618371;5285.762140703678;4953.108440480712;5055.6713813602;5062.215356619711;5097.05704444677;5100.362454693163;5108.744562771865;5113.488651134894;5191.636585439021;5191.870203244949;5220.92168617471;4887.804487820483;5083.669561478492;5107.361579802525;5141.897162056738;5174.146265866128;5468.179773034037;5491.313585801775;5491.450854914517;5503.807366742145;5574.9721251393885;5901.0558310670085;5913.497352725023;5968.612040217416;6009.939900600982;6011;5805.854853628651;6023.247094113236;6038;6039.335673076002;6044.818655440344;5454.945450545483;5551.861203469905;5601.887962240732;5617.522510586646;5637.26715526987;6193.969030154821;6196.47329976957;6197;6202.255729312511;6203.689815509207;5670.716464176775;5950.434708702666;5957.731902064417;5962.61243019211;5965.403459654087;5782.566307526952;5813.680247586339;5868.295804503401;5869.236999190078;5874.383189765013;5850.765969361263;5855.326637436664;5857.0335894577765;5858.970705146447;5859;5854.882902341929;5939.10913362999;5955.910661340084;5969.940300597025;6036.5170786336985;4624.352590637313;4634.46703629087;4635.791389387486;4652.930206046912;4656.976715116402;4118.19695159447;4130.483689538808;4135.0902801383645;4139.379093136054;4159.542450330461;5489.2589500417735;5529.668219906818;5653.802116925888;5716.599838011382;5727.140928860705;6117.15760190703;6118.345080237303;6124.716609781064;6128.203333566607;6129.542179248731;5761.798337058183;5762.567807414461;5765.3946335634155;5766.625169363978;5796.043652797325;5114.693118412907;5153.020926024044;5165.096108181053;5293.920591191135;5300.761465734051;6124.411449640054;6251.031090181029;6252;6262.718177682015;6263.937360626335;6045.153678484928;6052.243469566304;6056.3035250946705;6059.757609695739;6091.969540152271;5937.762489500372;6221.704824731607;6237.812865614039;6240.438360547572;6249.218847644044;5772.307323121252;5781.971090144691;5793;5836.941630583639;5949.8215053548465;5988.760449582142;6001.879962400641;6002.129691194839;6006.129111278753;6011.098335249572;5848.210491583582;5863.149843272617;5866.208061911758;5868.501177399798;5871.94128058714;5603.159526071122;5711.143328500758;5792.594518383841;5841.591088623755;5847.035239185487;5806.65160090396;5847.619904706181;5851.034579294539;5856.648601083949;5873.73568189424;5931.406859314034;5933.317668468181;5937.435943585352;5942.31663358702;5946.167536544801;5611.438856114519;5611.74747136388;5649.802256920988;5665.376808550974;5769.509591684586 09:20:40 INFO - PROCESS | 29297 | |1;ext.html;587.8089620873217;628.4375483941872;649.2468736265932;656.6257233376975;672.9132451091489;33310.50034249485;45823.31265031023;46652.76673616631;46780;46790.532094679045;48534.27198592024;49015.75492122543;49121;49141.262881056726;49157;8431.451955622864;9282.582283797245;9446.385984910994;9450.763730906714;9454.81090378192;8390.790230244262;10828.783424331508;11076.39079850608;11089.778204435906;11516.251443656178;11371.715707107347;11435.714107147347;11441.885581144206;11446.370449625267;11466.369349685763;11015.063719583844;11074.501647425805;11088.445577721082;11093.55625774976;11115.72210694731;11108.500117494732;11186.384748838811;11193.10455163585;11196.608118715887;11200.663980080612;45767.313490297674;45984.47085410301;46022.76988615035;46051.5394846054;46098;3744.887653370376;3822.406937478761;3849.576546579874;3862.1503269280715;3864.652181303668;34117.31765364679;34298.14254643629;34781.652183478094;35014.649853501396;35850.282994340225;4721.929171062437;4764.785584648699;4766.475687674353;4801.831935882227;4846.418429788448;262409;263608;264151;264510.9999999981;266184;38944.805275974;40672.38991415131;63538.68230658817;64109.358906411275;64203.35796641974;42243.7887810559;42465;42599;42607.147857042684;42609.147817044104;17908.373206937955;18134.274629014955;18136.274549017893;18136.274549017893;18146.90926545359;13525.66185845342;13848.030637855254;14069.788953165711;14155;14266.572002839932;9474.05259474061;9595.952020239854;9619.422834629944;9674.177694895872;9682.419054856731;3614.8734794282072;3652.7808331500196;3664.4320130379833;3669.3395188865725;3732.197577520846;1014.7919676466307;1059.075299855662;1066.0618655583037;1074.382230217628;1119.9216054876244;32752.508712369334;32993.34013319768;33753.32493350119;33986.32027359439;34374;1169.0180248591225;1169.3334799164395;1172.1443346357207;1196.877969187599;1278.8337516122845;17262.482125536255;17392.65214695699;17406;17474.65050698979;17569.472915812545;618043;620655;623830;625929;626488;59403.40596594065;59714.40285597088;59749.70125149346;59768.70115649394;59794.40205597975;1338.7991801229894;1399.4122468563228;1409.3023953143054;1441.207335965215;1456.3446449097949;31090;33628.32743345168;33735.15662108369;33796;34447;53190.20214696705;54318.728406359296;54332.728336358065;54333.18500222421;54337;356365;357795;358243;359025;359441;29535.85232073826;30850.228744280674;31779.52330714995;31950.520742188422;32892.34215315632;49022.03911843486;49506.75246623888;49620.25569616531;50003;51357;35280.64719352774;35329.470057949664;35469.64530354663;35968;36324.45513317355;42115;42686.57313426826;42847;42915;44496.555034449244;41998;42163.578364215966;42560.57439425566;43177.56822431736;43493;36076.81961590175;38212.235755284186;38396.720564790776;38841.6115838838;39001.6099838998;28407.43185136327;28469.572956405256;28907.56638650464;28974.855125724236;29168.56247156252 09:20:40 INFO - PROCESS | 29297 | |2;jquery.html;392.97642141471493;412.6162668718093;413.22107826746594;420.82115101082036;421.8755467137194;14312.570622881318;16299.266533006014;20046.498837529052;20148;21028.158873645036;20127.19491220353;20417.795822041775;20477.385678429633;20479.18083276671;21043.36869893902;5898.380670029649;6043.365446628107;6105.938940610604;6286.057091436298;6337.936620633781;4174.66602671787;6311.810645680637;6361;6367.936320636803;6368.936310636881;4424.40270563473;4445.666575006871;6828.214755303129;6879.3120687931305;6910.654467276641;6585.176852893388;6798.150231221098;6889.311068893096;6893.586384816927;6900;6090.33006369299;6094.664793436404;6096.237970253718;6096.786612468542;6117.66352850593;16088.678226435522;23880.522389552287;24311.878440607685;24918.12786552462;25104.246872593852;2338.228384633084;2394.916177933764;2450.4486490539666;2494.6756921600077;2540.9872950635313;11150.66548003553;11463.828042579367;11878.821817672742;12741.808872866914;15685.607859803482;1957.2660252405349;1992.9238211365846;1995.6607376745987;2059.083707750052;2061.4846288427893;69185;79838.60080699559;80108.19891801066;83539;87613.56193219057;11631.709207269885;11685.415729213506;12066.517339306329;12071.034317254598;12124.818127728091;17227.655446890993;17227.913860430617;17229.91385043092;17241.137943102796;17244.913775431294;8898;9023.187913087853;9027.593758280893;9096.954515227382;9260.166585007382;8890.777730556658;8903.599338029675;8950.104989501127;9138.497382644018;9151.77120571991;5102.260172275;5119;5187.922181167285;5223.268742376071;5247.947520524822;2065.5635093715587;2070.0438086008576;2112.29238205203;2133.567732784627;2155.5904378168098;958.8465845464692;974.1038244814788;998.9400635961865;1039.428314427062;1051.6792378324685;19625.411237662607;19647.31234406789;19785.901070494845;19912;19999.200031998556;1001.4942454060798;1024.5850430575629;1041.8176819056634;1042.7966546523508;1043.2644985285356;10388.480575971322;11009.339439633648;11152.275102118336;11167.105204477506;11220.607278745205;397895;401480;529865;535993;537177;60989;61477;78328;88505.55747221223;101383;1041.4584416103735;1074.4090750087578;1092.448313601626;1095.9342439453499;1097.4567589043475;17894.642107157524;18192.636147276717;18238.544036399195;18393.004094825512;18491.907540462213;42288.365674515524;43708.56291437045;45117.87205319893;45296.547034529234;45753;59337.703311483165;59483;60671.69664151651;60774.392256078645;61203.38796612155;8765.07966663491;9105.863412048691;9132.634694612407;9252.537373131181;9323.254139668947;13063.608091757262;13495.39270732839;13934.163950163023;14039;14376.28118594087;11451.656450306504;11780.410979451164;11787.823182652439;11916.622382033242;12118;11634.70913227176;12115.757684846078;12173.452194651085;12205.816912746493;12356.320402377956;11266.774664506827;11381.943090284496;11466.31202127875;11484.82772758426;11918.642440726791;8991.955040224757;9165.220956218796;9262.861057084285;9359.532023398939;9451.905480945103;8725.52009639476;11136.164787640797;11159.609413670807;11209.103271738079;11413.372264525531 09:20:40 INFO - PROCESS | 29297 | |3;mootools.html;1423.1958943197094;1498.5878883307093;1592.7531232658935;1739.8434140927313;1758.8831092256414;9200.26397888169;9880.357776744515;9888.505574721272;9889;9895.059969302923;8351;8680.131986801316;9178.770530736734;9530;9532.332736708433;2524.1922584772833;2546.719860815309;2547.46503234321;2554.067765265674;2557.8337408068414;2167.338961616714;2531.721510633829;2574.832635878671;2575.8325708828866;2576.3043978125893;2216.7783221677782;2466.962995555068;2567.5763499022637;2573.3823882268216;2580.65161203238;2484.776370126688;2494.052260141135;2494.6756921600077;2513.1455305196228;2515.610080437535;2467.0625162438337;2476.3809047738064;2481.8262721609494;2481.8883150258102;2506.724260331362;9772.12050915421;9812.803743925153;9820.950895245549;9831.705048848546;9835.901640983642;5566.443355664442;5639.661620302754;5642.4357564243655;5691.373948865621;5861.70691465425;3558.804265765382;3680.8159592020293;3726.1988672435286;3729.645683660048;3741.9251614967548;1256.2525297448003;1291.4769518345086;1301.3948513940968;1315.6447759104988;1328.774108401574;12933.094683372168;13426.664333391645;13602.979776516702;13664.726705465935;13667.863321366758;4784.425868895755;4829.637777166727;4910.950890491121;4937.23472861707;4949.331840201598;10594.417307048187;10610.09814165789;10612.681619551426;10615.946920265349;10624.521896514676;5738.454846789548;5740.167675687002;5740.741666624928;5742.569307301969;5744.052231381859;5156.252343410185;5282.788688452419;5359.544438722674;5369.677819330853;5374.543163831118;4196.992721746758;4205.663546916239;4218.451601291874;4231.344141658048;4247.702660813713;1180.8582970043649;1203.1397550751255;1289.2908900104906;1300.843898732158;1304.1718508747015;933.071593764209;1017.1760873692333;1046.5395226100643;1046.785408991155;1058.7141471802724;6378.234611846516;6579.572327798678;6604.174478190226;6605.70274337656;6618.338166183343;562.061357532921;715.7995761186876;1041.859348987877;1051.6844946516014;1084.2139448899643;4227.260229459894;4236.343366778093;4289.721168124062;4364.083542456056;4429.734215946989;11906.404679765978;12387.75224495505;12393.256404615751;12395.876041239653;12401.689957751129;11911.940440297743;12234.265944043385;12239.571614993432;12270.202436841577;12310;8864.689735859214;9182.86225706615;9190.49452280131;9196.448213107235;9204.401713888576;5990.970045149921;6096.177016102769;6116.369960916849;6161.137440758387;6192.133101365723;11011.339319640847;11106.944465277622;11225.831612525655;11235.775284494428;11497.597584084517;11457.59898403552;11467.541298347973;11504.942475287904;11513.251638643462;11584.594539191088;8045.396595255263;8374.120717324593;8454.746357609281;8633.438826476258;8655.740327790176;9307.813843722952;9327.160555550034;9399.295052871199;9414.388064775767;9488.667896623585;7150.785476435507;8259.545724985184;8487.872681909652;8548.273396761097;8574.31405487572;8199;8317.085120636604;8353.289970352587;8445.95777021111;8475.74572762818;7605.429592780674;7788.182240864854;7795.181505941794;7798.493097948615;7810.6485208166905;5424.159255315432;5427.8100266492065;5472.91790623149;5517.751701173376;5540.972295138498;5300.31095957523;5368.758405871667;5393.7033463159905;5422.810201642924;5439.048166570913 09:20:40 INFO - PROCESS | 29297 | |4;prototype.html;349.5124301599269;352.29013537721494;354.48245561480246;360.25427365353727;368.27266149355046;5595.356533998592;5616.326040875096;5627.971860140694;5638.674911395824;5650.350209725882;5226.503482169188;5340.55804932668;5398.4357430220625;5476.644018138818;5492.615516913807;4337.54455782143;4340.417451616283;4362.607365337119;4366.8995013540425;4376.008915943254;4352.43312407533;4352.695311328208;4355.956440435602;4369.825206991716;4399.768064941822;4165.187788381267;4278.8502402415925;4306.870793876189;4323.805428755713;4380.912381752363;4289.348600788688;4291.206126866539;4303.224420248953;4316.374125751749;4318.913621727564;3837.5609146570037;3839.712021598363;3843.1545060086737;3848.1918797052654;3849.576546579874;5236.842894713165;5374.865628359284;5414.485623865727;5427.240186373911;5434.2935418395755;4934.481879402647;4974.801007959677;4981.800727970877;4986.379426686305;4996.925046124311;5768.336641286264;5774.740136693859;5781.335146458169;5804.390538993429;5819.7090145492975;4133.627973482371;4195.853145139936;4229.302165142779;4237.32202847546;4260.062786187033;5946.881062378771;6009.338972712998;6010.519158467268;6033.159886234696;6035.849103772441;4317.481902171759;4317.697761156752;4318.201132790444;4346.543612920661;4360.869173924787;4329.76186309756;4403.647708183337;4445.377647129404;4457.554244575581;4462.174497717932;4957.546014722735;4974.204127339608;4978.153713868651;4984.127777638899;5000.399872040912;5847.8363005687315;5856.619319744203;5863.091220860773;5888.67504811414;5893.998020336553;1416.4405060001243;1455.9490417835325;1468.4346526587128;1495.8130233720785;1497.7229212595705;1007.8034783217349;1010.4846528270635;1036.43514284715;1036.8133735927606;1047.8061558611682;5535.418781028015;5536.889262214733;5545.611807173459;5564.721763911788;5597.93639208549;5887.35239123696;5898.174255604219;5908.645481271137;5915.254999775077;5917.349091600006;5388.649737767033;5562.499375056264;5587.469190426903;5594.468525490072;5597.412271711493;3121.2352973521633;3122.656507784142;3129.702678245563;3132.4674805283157;3150.6219253689246;5887.293524777054;6043.576949613484;6055.455009049118;6064.514838812973;6071.362506936708;5979.385565897183;5988.760449582055;5990.940090599125;6026.6986650667295;6043.425874541911;6057.242844644419;6077.179580756541;6094.8171554853425;6098.908516372258;6113.174721412553;5757.366689664217;5863.237779088862;5901.940980590311;5917.142014407973;5919;5796.188533605215;5826.533877289891;5839.737211825393;5841.766329346779;5851.385604511465;5958.463738263577;6026.819195424145;6027.879442411039;6040.912635725612;6101.223943105788;4253.21315556623;4319.503257125471;4331.263685173465;4346.847860324873;4357.803898824497;5910.202122713551;5936.910946335895;5946.940530594812;5952.553558483044;5983.192269043798;5566.165075238747;5748.252727145445;5750.942490575041;5796.855078623068;5869.911951320648;5697;5783.681897495678;5817;5828.242328497268;5841.824745257649;5592.462072929977;5758.395368486416;5776.624519406225;5790;5800.8259752207505;5855.448306198776;5867.413258674269;5868.38619379674;5886.882262354815;5899.793507227227;5710.5146062585145;5722.599418040863;5758.610176776759;5764.538836892954;5791.13133030032 09:20:40 INFO - PROCESS | 29297 | |5;yui.html;789.4197764642986;795.852767238061;838.4508147163608;861.7888617288766;871.9084496127906;16920.83079169208;17763.20065597048;17931.91034044831;17950.371736989207;18047.639047219047;16217.351305947777;16316.184190790442;16330.918345408256;16337.26482308296;16351.182440877938;701.3057073497253;721.6824597177225;825.5830805443243;829.7966998085448;856.8843206167181;852.1308265569115;852.8550146475083;854.6965827131372;854.7606670132342;855.0978717453085;905.4702998745742;906.3247880329164;907.1518130547959;907.2787134228271;907.4509921497498;736.8452624948767;756.3646536908996;857.6269322844552;874.3049275825733;885.340421386065;654.3979538824293;654.450261780107;654.6563054396432;654.8362909272682;654.9705263263164;15298.388064477518;15301.311440985182;15339.769903451457;15344.693106137815;15354;15289.311980960883;15301.311440985182;15384.846151538566;15428;15752.448664296695;13986.230757308344;14024.92987535066;14054.367553460119;14080.15519068859;14313.141211527342;4479.238529450001;4480.395146655193;4497.662675299365;4523.36672865799;4595.862124136282;83460.58269708735;83528.58235708783;83827;83827;84938;11713.355765432983;11895.762084758257;11962.222455540363;11994.400279985966;12569.057320700984;23073.769262307498;23208.41978950506;23490.7650923492;23500.882495587754;23566;8537.53043582609;8715.869261961076;8734.737957861274;9002.954985225031;9242.399244049115;5190.688558686491;5259.421463638997;5294.205869119586;5298.072837253465;5338.8665283367445;3643.216708407705;3800.847966081326;3805.3911374179997;3805.82873770681;3807.923841523154;584.360125662399;669.1735706402588;671.6943790575276;671.7783131566571;672.7342699633617;744.2966396755014;765.3915137465729;789.4394979564573;792.5205250823169;825.1871906172415;9966.601335946625;10011.349262297927;10071.899281007243;10404.635837745642;10480.947595261974;788.7949133225403;798.3413683710962;838.781916701662;842.5829214538843;872.1235158665501;4540.364348991144;4975.676581022222;5000.824971125993;5016.849494515171;5018.949810501921;102906;109360;114148;118816;125489.37255313665;100549.49725251473;102542;103249.48375258075;104823.47588262163;105833;56740;81266.59366703247;89137;91065;93302.53348733348;9921.751956200864;9950.701478955643;10059.346142500714;10214.795704085727;10356.378617282988;54053.189202161215;54302.45697542974;55458.44541554533;55555.72222139025;56554;73952;77516.44967100513;77605;78138.6093069531;79899.60050199712;13019;13696.246706431242;14168.291585420893;14252.363312786752;14521.636959075686;14850.702985940437;14998.475053373517;15116.622084447978;15370.923145384202;16241.187940602686;12505.374731263219;14417.990740648473;14797.482088126864;15165.620859478602;16447.91776041112;14157.646058848612;14984.850151498345;15009.549713508612;15304.387824486896;15683.529494115195;14562.635934101732;14643.414263429344;14708.485203017843;14712.264386780404;15112.035435393329;11354.318740875575;12509.624711258677;12564.748705026032;13068.085234033906;13222.801657974946;10971.23201375896;11022.944885275843;11057;11092.11263098934;11358.54565817358 09:20:40 INFO - PROCESS | 29297 | __end_tp_report 09:20:40 INFO - PROCESS | 29297 | __start_cc_report 09:20:40 INFO - PROCESS | 29297 | _x_x_mozilla_cycle_collect,953 09:20:40 INFO - PROCESS | 29297 | __end_cc_report 09:20:40 INFO - PROCESS | 29297 | __startTimestamp1478452840606__endTimestamp 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | ------- Summary: start ------- 09:20:40 INFO - PROCESS | 29297 | Number of tests: 6 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | [#0] dojo.html Cycles:165 Average:5572.47 Median:5772.31 stddev:628.33 (10.9%) stddev-sans-first:600.67 09:20:40 INFO - PROCESS | 29297 | Values: 3143.6 3173.7 3196.5 3210.7 3229.4 5563.8 5648.0 5667.3 5683.5 5700.5 5654.5 5724.0 5724.1 5738.6 5762.3 5132.3 5158.7 5183.1 5191.4 5240.7 4972.0 5239.2 5248.9 5260.9 5285.8 4953.1 5055.7 5062.2 5097.1 5100.4 5108.7 5113.5 5191.6 5191.9 5220.9 4887.8 5083.7 5107.4 5141.9 5174.1 5468.2 5491.3 5491.5 5503.8 5575.0 5901.1 5913.5 5968.6 6009.9 6011.0 5805.9 6023.2 6038.0 6039.3 6044.8 5454.9 5551.9 5601.9 5617.5 5637.3 6194.0 6196.5 6197.0 6202.3 6203.7 5670.7 5950.4 5957.7 5962.6 5965.4 5782.6 5813.7 5868.3 5869.2 5874.4 5850.8 5855.3 5857.0 5859.0 5859.0 5854.9 5939.1 5955.9 5969.9 6036.5 4624.4 4634.5 4635.8 4652.9 4657.0 4118.2 4130.5 4135.1 4139.4 4159.5 5489.3 5529.7 5653.8 5716.6 5727.1 6117.2 6118.3 6124.7 6128.2 6129.5 5761.8 5762.6 5765.4 5766.6 5796.0 5114.7 5153.0 5165.1 5293.9 5300.8 6124.4 6251.0 6252.0 6262.7 6263.9 6045.2 6052.2 6056.3 6059.8 6092.0 5937.8 6221.7 6237.8 6240.4 6249.2 5772.3 5782.0 5793.0 5836.9 5949.8 5988.8 6001.9 6002.1 6006.1 6011.1 5848.2 5863.1 5866.2 5868.5 5871.9 5603.2 5711.1 5792.6 5841.6 5847.0 5806.7 5847.6 5851.0 5856.6 5873.7 5931.4 5933.3 5937.4 5942.3 5946.2 5611.4 5611.7 5649.8 5665.4 5769.5 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | [#1] ext.html Cycles:180 Average:58200.56 Median:19969.66 stddev:117922.19 (590.5%) stddev-sans-first:118173.66 09:20:40 INFO - PROCESS | 29297 | Values: 587.8 628.4 649.2 656.6 672.9 33310.5 45823.3 46652.8 46780.0 46790.5 48534.3 49015.8 49121.0 49141.3 49157.0 8431.5 9282.6 9446.4 9450.8 9454.8 8390.8 10828.8 11076.4 11089.8 11516.3 11371.7 11435.7 11441.9 11446.4 11466.4 11015.1 11074.5 11088.4 11093.6 11115.7 11108.5 11186.4 11193.1 11196.6 11200.7 45767.3 45984.5 46022.8 46051.5 46098.0 3744.9 3822.4 3849.6 3862.2 3864.7 34117.3 34298.1 34781.7 35014.6 35850.3 4721.9 4764.8 4766.5 4801.8 4846.4 262409.0 263608.0 264151.0 264511.0 266184.0 38944.8 40672.4 63538.7 64109.4 64203.4 42243.8 42465.0 42599.0 42607.1 42609.1 17908.4 18134.3 18136.3 18136.3 18146.9 13525.7 13848.0 14069.8 14155.0 14266.6 9474.1 9596.0 9619.4 9674.2 9682.4 3614.9 3652.8 3664.4 3669.3 3732.2 1014.8 1059.1 1066.1 1074.4 1119.9 32752.5 32993.3 33753.3 33986.3 34374.0 1169.0 1169.3 1172.1 1196.9 1278.8 17262.5 17392.7 17406.0 17474.7 17569.5 618043.0 620655.0 623830.0 625929.0 626488.0 59403.4 59714.4 59749.7 59768.7 59794.4 1338.8 1399.4 1409.3 1441.2 1456.3 31090.0 33628.3 33735.2 33796.0 34447.0 53190.2 54318.7 54332.7 54333.2 54337.0 356365.0 357795.0 358243.0 359025.0 359441.0 29535.9 30850.2 31779.5 31950.5 32892.3 49022.0 49506.8 49620.3 50003.0 51357.0 35280.6 35329.5 35469.6 35968.0 36324.5 42115.0 42686.6 42847.0 42915.0 44496.6 41998.0 42163.6 42560.6 43177.6 43493.0 36076.8 38212.2 38396.7 38841.6 39001.6 28407.4 28469.6 28907.6 28974.9 29168.6 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | [#2] jquery.html Cycles:180 Average:28843.87 Median:13353.40 stddev:79754.88 (597.3%) stddev-sans-first:79950.00 09:20:40 INFO - PROCESS | 29297 | Values: 393.0 412.6 413.2 420.8 421.9 14312.6 16299.3 20046.5 20148.0 21028.2 20127.2 20417.8 20477.4 20479.2 21043.4 5898.4 6043.4 6105.9 6286.1 6337.9 4174.7 6311.8 6361.0 6367.9 6368.9 4424.4 4445.7 6828.2 6879.3 6910.7 6585.2 6798.2 6889.3 6893.6 6900.0 6090.3 6094.7 6096.2 6096.8 6117.7 16088.7 23880.5 24311.9 24918.1 25104.2 2338.2 2394.9 2450.4 2494.7 2541.0 11150.7 11463.8 11878.8 12741.8 15685.6 1957.3 1992.9 1995.7 2059.1 2061.5 69185.0 79838.6 80108.2 83539.0 87613.6 11631.7 11685.4 12066.5 12071.0 12124.8 17227.7 17227.9 17229.9 17241.1 17244.9 8898.0 9023.2 9027.6 9097.0 9260.2 8890.8 8903.6 8950.1 9138.5 9151.8 5102.3 5119.0 5187.9 5223.3 5247.9 2065.6 2070.0 2112.3 2133.6 2155.6 958.8 974.1 998.9 1039.4 1051.7 19625.4 19647.3 19785.9 19912.0 19999.2 1001.5 1024.6 1041.8 1042.8 1043.3 10388.5 11009.3 11152.3 11167.1 11220.6 397895.0 401480.0 529865.0 535993.0 537177.0 60989.0 61477.0 78328.0 88505.6 101383.0 1041.5 1074.4 1092.4 1095.9 1097.5 17894.6 18192.6 18238.5 18393.0 18491.9 42288.4 43708.6 45117.9 45296.5 45753.0 59337.7 59483.0 60671.7 60774.4 61203.4 8765.1 9105.9 9132.6 9252.5 9323.3 13063.6 13495.4 13934.2 14039.0 14376.3 11451.7 11780.4 11787.8 11916.6 12118.0 11634.7 12115.8 12173.5 12205.8 12356.3 11266.8 11381.9 11466.3 11484.8 11918.6 8992.0 9165.2 9262.9 9359.5 9451.9 8725.5 11136.2 11159.6 11209.1 11413.4 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | [#3] mootools.html Cycles:180 Average:6308.20 Median:4396.91 stddev:3657.92 (83.2%) stddev-sans-first:3649.76 09:20:40 INFO - PROCESS | 29297 | Values: 1423.2 1498.6 1592.8 1739.8 1758.9 9200.3 9880.4 9888.5 9889.0 9895.1 8351.0 8680.1 9178.8 9530.0 9532.3 2524.2 2546.7 2547.5 2554.1 2557.8 2167.3 2531.7 2574.8 2575.8 2576.3 2216.8 2467.0 2567.6 2573.4 2580.7 2484.8 2494.1 2494.7 2513.1 2515.6 2467.1 2476.4 2481.8 2481.9 2506.7 9772.1 9812.8 9821.0 9831.7 9835.9 5566.4 5639.7 5642.4 5691.4 5861.7 3558.8 3680.8 3726.2 3729.6 3741.9 1256.3 1291.5 1301.4 1315.6 1328.8 12933.1 13426.7 13603.0 13664.7 13667.9 4784.4 4829.6 4911.0 4937.2 4949.3 10594.4 10610.1 10612.7 10615.9 10624.5 5738.5 5740.2 5740.7 5742.6 5744.1 5156.3 5282.8 5359.5 5369.7 5374.5 4197.0 4205.7 4218.5 4231.3 4247.7 1180.9 1203.1 1289.3 1300.8 1304.2 933.1 1017.2 1046.5 1046.8 1058.7 6378.2 6579.6 6604.2 6605.7 6618.3 562.1 715.8 1041.9 1051.7 1084.2 4227.3 4236.3 4289.7 4364.1 4429.7 11906.4 12387.8 12393.3 12395.9 12401.7 11911.9 12234.3 12239.6 12270.2 12310.0 8864.7 9182.9 9190.5 9196.4 9204.4 5991.0 6096.2 6116.4 6161.1 6192.1 11011.3 11106.9 11225.8 11235.8 11497.6 11457.6 11467.5 11504.9 11513.3 11584.6 8045.4 8374.1 8454.7 8633.4 8655.7 9307.8 9327.2 9399.3 9414.4 9488.7 7150.8 8259.5 8487.9 8548.3 8574.3 8199.0 8317.1 8353.3 8446.0 8475.7 7605.4 7788.2 7795.2 7798.5 7810.6 5424.2 5427.8 5472.9 5517.8 5541.0 5300.3 5368.8 5393.7 5422.8 5439.0 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | [#4] prototype.html Cycles:180 Average:4893.19 Median:5565.44 stddev:1420.17 (25.5%) stddev-sans-first:1382.60 09:20:40 INFO - PROCESS | 29297 | Values: 349.5 352.3 354.5 360.3 368.3 5595.4 5616.3 5628.0 5638.7 5650.4 5226.5 5340.6 5398.4 5476.6 5492.6 4337.5 4340.4 4362.6 4366.9 4376.0 4352.4 4352.7 4356.0 4369.8 4399.8 4165.2 4278.9 4306.9 4323.8 4380.9 4289.3 4291.2 4303.2 4316.4 4318.9 3837.6 3839.7 3843.2 3848.2 3849.6 5236.8 5374.9 5414.5 5427.2 5434.3 4934.5 4974.8 4981.8 4986.4 4996.9 5768.3 5774.7 5781.3 5804.4 5819.7 4133.6 4195.9 4229.3 4237.3 4260.1 5946.9 6009.3 6010.5 6033.2 6035.8 4317.5 4317.7 4318.2 4346.5 4360.9 4329.8 4403.6 4445.4 4457.6 4462.2 4957.5 4974.2 4978.2 4984.1 5000.4 5847.8 5856.6 5863.1 5888.7 5894.0 1416.4 1455.9 1468.4 1495.8 1497.7 1007.8 1010.5 1036.4 1036.8 1047.8 5535.4 5536.9 5545.6 5564.7 5597.9 5887.4 5898.2 5908.6 5915.3 5917.3 5388.6 5562.5 5587.5 5594.5 5597.4 3121.2 3122.7 3129.7 3132.5 3150.6 5887.3 6043.6 6055.5 6064.5 6071.4 5979.4 5988.8 5990.9 6026.7 6043.4 6057.2 6077.2 6094.8 6098.9 6113.2 5757.4 5863.2 5901.9 5917.1 5919.0 5796.2 5826.5 5839.7 5841.8 5851.4 5958.5 6026.8 6027.9 6040.9 6101.2 4253.2 4319.5 4331.3 4346.8 4357.8 5910.2 5936.9 5946.9 5952.6 5983.2 5566.2 5748.3 5750.9 5796.9 5869.9 5697.0 5783.7 5817.0 5828.2 5841.8 5592.5 5758.4 5776.6 5790.0 5800.8 5855.4 5867.4 5868.4 5886.9 5899.8 5710.5 5722.6 5758.6 5764.5 5791.1 09:20:40 INFO - PROCESS | 29297 | 09:20:40 INFO - PROCESS | 29297 | [#5] yui.html Cycles:180 Average:21742.01 Median:3806.88 stddev:30588.08 (803.5%) stddev-sans-first:30633.43 09:20:40 INFO - PROCESS | 29297 | Values: 789.4 795.9 838.5 861.8 871.9 16920.8 17763.2 17931.9 17950.4 18047.6 16217.4 16316.2 16330.9 16337.3 16351.2 701.3 721.7 825.6 829.8 856.9 852.1 852.9 854.7 854.8 855.1 905.5 906.3 907.2 907.3 907.5 736.8 756.4 857.6 874.3 885.3 654.4 654.5 654.7 654.8 655.0 15298.4 15301.3 15339.8 15344.7 15354.0 15289.3 15301.3 15384.8 15428.0 15752.4 13986.2 14024.9 14054.4 14080.2 14313.1 4479.2 4480.4 4497.7 4523.4 4595.9 83460.6 83528.6 83827.0 83827.0 84938.0 11713.4 11895.8 11962.2 11994.4 12569.1 23073.8 23208.4 23490.8 23500.9 23566.0 8537.5 8715.9 8734.7 9003.0 9242.4 5190.7 5259.4 5294.2 5298.1 5338.9 3643.2 3800.8 3805.4 3805.8 3807.9 584.4 669.2 671.7 671.8 672.7 744.3 765.4 789.4 792.5 825.2 9966.6 10011.3 10071.9 10404.6 10480.9 788.8 798.3 838.8 842.6 872.1 4540.4 4975.7 5000.8 5016.8 5018.9 102906.0 109360.0 114148.0 118816.0 125489.4 100549.5 102542.0 103249.5 104823.5 105833.0 56740.0 81266.6 89137.0 91065.0 93302.5 9921.8 9950.7 10059.3 10214.8 10356.4 54053.2 54302.5 55458.4 55555.7 56554.0 73952.0 77516.4 77605.0 78138.6 79899.6 13019.0 13696.2 14168.3 14252.4 14521.6 14850.7 14998.5 15116.6 15370.9 16241.2 12505.4 14418.0 14797.5 15165.6 16447.9 14157.6 14984.9 15009.5 15304.4 15683.5 14562.6 14643.4 14708.5 14712.3 15112.0 11354.3 12509.6 12564.7 13068.1 13222.8 10971.2 11022.9 11057.0 11092.1 11358.5 09:20:40 INFO - PROCESS | 29297 | -------- Summary: end -------- 09:20:40 INFO - PROCESS | 29297 | 09:20:41 INFO - TEST-INFO | 29297: exit 0 09:20:41 INFO - TEST-OK | dromaeo_css | took 1114315ms 09:20:41 INFO - TEST-START | kraken 09:20:41 INFO - operating with platform_type : linux_ 09:20:41 INFO - Initialising browser for kraken test... 09:20:41 INFO - TEST-INFO | started process 29618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpims9ri/profile http://localhost:49931/getInfo.html) 09:20:45 INFO - PROCESS | 29618 | __metrics Screen width/height:1600/1200 09:20:45 INFO - PROCESS | 29618 | colorDepth:24 09:20:45 INFO - PROCESS | 29618 | Browser inner width/height: 1024/697 09:20:45 INFO - PROCESS | 29618 | __metrics 09:20:46 INFO - PROCESS | 29618 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:20:46 INFO - PROCESS | 29618 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:20:46 INFO - PROCESS | 29618 | console.error: PushService: 09:20:46 INFO - PROCESS | 29618 | stateChangeProcessEnqueue: Error transitioning state 09:20:46 INFO - PROCESS | 29618 | UnknownError 09:20:47 INFO - TEST-INFO | 29618: exit 0 09:20:47 INFO - Browser initialized. 09:20:47 INFO - Running cycle 1/1 for kraken test... 09:20:47 INFO - TEST-INFO | started process 29708 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpims9ri/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:58 INFO - PROCESS | 29708 | RSS: Main: 145047552 09:20:58 INFO - PROCESS | 29708 | 09:21:01 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49931/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:02 INFO - PROCESS | 29708 | RSS: Main: 186580992 09:21:02 INFO - PROCESS | 29708 | 09:21:04 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49931/tests/kraken/driver.html?testName=audio-dft) 09:21:05 INFO - PROCESS | 29708 | RSS: Main: 175468544 09:21:05 INFO - PROCESS | 29708 | 09:21:08 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49931/tests/kraken/driver.html?testName=audio-fft) 09:21:08 INFO - PROCESS | 29708 | RSS: Main: 267288576 09:21:08 INFO - PROCESS | 29708 | 09:21:10 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49931/tests/kraken/driver.html?testName=audio-oscillator) 09:21:11 INFO - PROCESS | 29708 | RSS: Main: 173727744 09:21:11 INFO - PROCESS | 29708 | 09:21:13 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49931/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:14 INFO - PROCESS | 29708 | RSS: Main: 175902720 09:21:14 INFO - PROCESS | 29708 | 09:21:17 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49931/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:17 INFO - PROCESS | 29708 | RSS: Main: 211095552 09:21:17 INFO - PROCESS | 29708 | 09:21:20 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49931/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:21 INFO - PROCESS | 29708 | RSS: Main: 210329600 09:21:21 INFO - PROCESS | 29708 | 09:21:24 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49931/tests/kraken/driver.html?testName=json-parse-financial) 09:21:24 INFO - PROCESS | 29708 | RSS: Main: 209833984 09:21:24 INFO - PROCESS | 29708 | 09:21:26 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49931/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:27 INFO - PROCESS | 29708 | RSS: Main: 171925504 09:21:27 INFO - PROCESS | 29708 | 09:21:28 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:29 INFO - PROCESS | 29708 | RSS: Main: 179068928 09:21:29 INFO - PROCESS | 29708 | 09:21:31 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:31 INFO - PROCESS | 29708 | RSS: Main: 188633088 09:21:31 INFO - PROCESS | 29708 | 09:21:34 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:35 INFO - PROCESS | 29708 | RSS: Main: 196980736 09:21:35 INFO - PROCESS | 29708 | 09:21:37 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:38 INFO - PROCESS | 29708 | RSS: Main: 193605632 09:21:38 INFO - PROCESS | 29708 | 09:21:39 INFO - PROCESS | 29708 | Cycle 1(1): loaded http://localhost:49931/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49931/tests/kraken/driver.html?testName=ai-astar) 09:21:40 INFO - PROCESS | 29708 | RSS: Main: 244400128 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | __start_tp_report 09:21:40 INFO - PROCESS | 29708 | _x_x_mozilla_page_load 09:21:40 INFO - PROCESS | 29708 | _x_x_mozilla_page_load_details 09:21:40 INFO - PROCESS | 29708 | |i|pagename|runs| 09:21:40 INFO - PROCESS | 29708 | |0;ai-astar;107;113;112;110;110;111;112;110;110;111 09:21:40 INFO - PROCESS | 29708 | |1;audio-beat-detection;147;116;115;162;119;117;116;114;155;116 09:21:40 INFO - PROCESS | 29708 | |2;audio-dft;160;159;159;163;161;160;161;161;160;159 09:21:40 INFO - PROCESS | 29708 | |3;audio-fft;74;76;75;121;76;75;75;75;110;75 09:21:40 INFO - PROCESS | 29708 | |4;audio-oscillator;81;82;124;81;81;82;115;82;81;107 09:21:40 INFO - PROCESS | 29708 | |5;imaging-gaussian-blur;116;117;116;117;117;117;116;117;117;117 09:21:40 INFO - PROCESS | 29708 | |6;imaging-darkroom;146;146;146;145;145;146;145;147;146;145 09:21:40 INFO - PROCESS | 29708 | |7;imaging-desaturate;95;95;94;95;95;94;95;94;94;96 09:21:40 INFO - PROCESS | 29708 | |8;json-parse-financial;51;48;49;50;48;48;49;49;48;49 09:21:40 INFO - PROCESS | 29708 | |9;json-stringify-tinderbox;58;58;60;58;59;58;59;62;59;59 09:21:40 INFO - PROCESS | 29708 | |10;stanford-crypto-aes;73;73;76;73;72;72;78;74;75;73 09:21:40 INFO - PROCESS | 29708 | |11;stanford-crypto-ccm;129;122;119;125;120;120;119;126;170;124 09:21:40 INFO - PROCESS | 29708 | |12;stanford-crypto-pbkdf2;158;164;153;156;157;152;154;152;156;155 09:21:40 INFO - PROCESS | 29708 | |13;stanford-crypto-sha256-iterative;62;62;62;62;61;62;63;64;64;60 09:21:40 INFO - PROCESS | 29708 | __end_tp_report 09:21:40 INFO - PROCESS | 29708 | __start_cc_report 09:21:40 INFO - PROCESS | 29708 | _x_x_mozilla_cycle_collect,1649 09:21:40 INFO - PROCESS | 29708 | __end_cc_report 09:21:40 INFO - PROCESS | 29708 | __startTimestamp1478452900210__endTimestamp 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | ------- Summary: start ------- 09:21:40 INFO - PROCESS | 29708 | Number of tests: 14 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.65 (1.5%) stddev-sans-first:1.12 09:21:40 INFO - PROCESS | 29708 | Values: 107.0 113.0 112.0 110.0 110.0 111.0 112.0 110.0 110.0 111.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:118.00 stddev:18.99 (16.1%) stddev-sans-first:18.81 09:21:40 INFO - PROCESS | 29708 | Values: 147.0 116.0 115.0 162.0 119.0 117.0 116.0 114.0 155.0 116.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:1.25 (0.8%) stddev-sans-first:1.32 09:21:40 INFO - PROCESS | 29708 | Values: 160.0 159.0 159.0 163.0 161.0 160.0 161.0 161.0 160.0 159.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:17.23 (23.0%) stddev-sans-first:17.95 09:21:40 INFO - PROCESS | 29708 | Values: 74.0 76.0 75.0 121.0 76.0 75.0 75.0 75.0 110.0 75.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:81.00 stddev:16.87 (20.8%) stddev-sans-first:17.45 09:21:40 INFO - PROCESS | 29708 | Values: 81.0 82.0 124.0 81.0 81.0 82.0 115.0 82.0 81.0 107.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 09:21:40 INFO - PROCESS | 29708 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:21:40 INFO - PROCESS | 29708 | Values: 146.0 146.0 146.0 145.0 145.0 146.0 145.0 147.0 146.0 145.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:21:40 INFO - PROCESS | 29708 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 94.0 96.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:49.00 stddev:0.99 (2.0%) stddev-sans-first:0.71 09:21:40 INFO - PROCESS | 29708 | Values: 51.0 48.0 49.0 50.0 48.0 48.0 49.0 49.0 48.0 49.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:1.25 (2.1%) stddev-sans-first:1.27 09:21:40 INFO - PROCESS | 29708 | Values: 58.0 58.0 60.0 58.0 59.0 58.0 59.0 62.0 59.0 59.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.50 stddev:1.91 (2.6%) stddev-sans-first:2.00 09:21:40 INFO - PROCESS | 29708 | Values: 73.0 73.0 76.0 73.0 72.0 72.0 78.0 74.0 75.0 73.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:124.50 stddev:15.33 (12.3%) stddev-sans-first:16.25 09:21:40 INFO - PROCESS | 29708 | Values: 129.0 122.0 119.0 125.0 120.0 120.0 119.0 126.0 170.0 124.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:156.00 stddev:3.56 (2.3%) stddev-sans-first:3.68 09:21:40 INFO - PROCESS | 29708 | Values: 158.0 164.0 153.0 156.0 157.0 152.0 154.0 152.0 156.0 155.0 09:21:40 INFO - PROCESS | 29708 | 09:21:40 INFO - PROCESS | 29708 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 09:21:40 INFO - PROCESS | 29708 | Values: 62.0 62.0 62.0 62.0 61.0 62.0 63.0 64.0 64.0 60.0 09:21:40 INFO - PROCESS | 29708 | -------- Summary: end -------- 09:21:40 INFO - PROCESS | 29708 | 09:21:41 INFO - TEST-INFO | 29708: exit 0 09:21:41 INFO - TEST-OK | kraken | took 59877ms 09:21:41 INFO - SUITE-END | took 1174s 09:21:41 INFO - Completed test suite (00:19:34) 09:21:41 INFO - javascript benchmark 09:21:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3143.5913331266934, 3173.7143657070865, 3196.5365022071787, 3210.6949839765216, 3229.354129174166, 5563.805266815662, 5647.9717601411985, 5667.348254950672, 5683.460071293231, 5700.515456186224, 5654.491095801377, 5724.026915424378, 5724.141378793183, 5738.612005622969, 5762.3373312069125, 5132.28148059272, 5158.716270605115, 5183.144781111112, 5191.351081114861, 5240.711760853152, 4972.0, 5239.187925871477, 5248.8687782805355, 5260.92108618371, 5285.762140703678, 4953.108440480712, 5055.6713813602, 5062.215356619711, 5097.05704444677, 5100.362454693163, 5108.744562771865, 5113.488651134894, 5191.636585439021, 5191.870203244949, 5220.92168617471, 4887.804487820483, 5083.669561478492, 5107.361579802525, 5141.897162056738, 5174.146265866128, 5468.179773034037, 5491.313585801775, 5491.450854914517, 5503.807366742145, 5574.9721251393885, 5901.0558310670085, 5913.497352725023, 5968.612040217416, 6009.939900600982, 6011.0, 5805.854853628651, 6023.247094113236, 6038.0, 6039.335673076002, 6044.818655440344, 5454.945450545483, 5551.861203469905, 5601.887962240732, 5617.522510586646, 5637.26715526987, 6193.969030154821, 6196.47329976957, 6197.0, 6202.255729312511, 6203.689815509207, 5670.716464176775, 5950.434708702666, 5957.731902064417, 5962.61243019211, 5965.403459654087, 5782.566307526952, 5813.680247586339, 5868.295804503401, 5869.236999190078, 5874.383189765013, 5850.765969361263, 5855.326637436664, 5857.0335894577765, 5858.970705146447, 5859.0, 5854.882902341929, 5939.10913362999, 5955.910661340084, 5969.940300597025, 6036.5170786336985, 4624.352590637313, 4634.46703629087, 4635.791389387486, 4652.930206046912, 4656.976715116402, 4118.19695159447, 4130.483689538808, 4135.0902801383645, 4139.379093136054, 4159.542450330461, 5489.2589500417735, 5529.668219906818, 5653.802116925888, 5716.599838011382, 5727.140928860705, 6117.15760190703, 6118.345080237303, 6124.716609781064, 6128.203333566607, 6129.542179248731, 5761.798337058183, 5762.567807414461, 5765.3946335634155, 5766.625169363978, 5796.043652797325, 5114.693118412907, 5153.020926024044, 5165.096108181053, 5293.920591191135, 5300.761465734051, 6124.411449640054, 6251.031090181029, 6252.0, 6262.718177682015, 6263.937360626335, 6045.153678484928, 6052.243469566304, 6056.3035250946705, 6059.757609695739, 6091.969540152271, 5937.762489500372, 6221.704824731607, 6237.812865614039, 6240.438360547572, 6249.218847644044, 5772.307323121252, 5781.971090144691, 5793.0, 5836.941630583639, 5949.8215053548465, 5988.760449582142, 6001.879962400641, 6002.129691194839, 6006.129111278753, 6011.098335249572, 5848.210491583582, 5863.149843272617, 5866.208061911758, 5868.501177399798, 5871.94128058714, 5603.159526071122, 5711.143328500758, 5792.594518383841, 5841.591088623755, 5847.035239185487, 5806.65160090396, 5847.619904706181, 5851.034579294539, 5856.648601083949, 5873.73568189424, 5931.406859314034, 5933.317668468181, 5937.435943585352, 5942.31663358702, 5946.167536544801, 5611.438856114519, 5611.74747136388, 5649.802256920988, 5665.376808550974, 5769.509591684586], "value": 5529.990578271986, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.8089620873217, 628.4375483941872, 649.2468736265932, 656.6257233376975, 672.9132451091489, 33310.50034249485, 45823.31265031023, 46652.76673616631, 46780.0, 46790.532094679045, 48534.27198592024, 49015.75492122543, 49121.0, 49141.262881056726, 49157.0, 8431.451955622864, 9282.582283797245, 9446.385984910994, 9450.763730906714, 9454.81090378192, 8390.790230244262, 10828.783424331508, 11076.39079850608, 11089.778204435906, 11516.251443656178, 11371.715707107347, 11435.714107147347, 11441.885581144206, 11446.370449625267, 11466.369349685763, 11015.063719583844, 11074.501647425805, 11088.445577721082, 11093.55625774976, 11115.72210694731, 11108.500117494732, 11186.384748838811, 11193.10455163585, 11196.608118715887, 11200.663980080612, 45767.313490297674, 45984.47085410301, 46022.76988615035, 46051.5394846054, 46098.0, 3744.887653370376, 3822.406937478761, 3849.576546579874, 3862.1503269280715, 3864.652181303668, 34117.31765364679, 34298.14254643629, 34781.652183478094, 35014.649853501396, 35850.282994340225, 4721.929171062437, 4764.785584648699, 4766.475687674353, 4801.831935882227, 4846.418429788448, 262409.0, 263608.0, 264151.0, 264510.9999999981, 266184.0, 38944.805275974, 40672.38991415131, 63538.68230658817, 64109.358906411275, 64203.35796641974, 42243.7887810559, 42465.0, 42599.0, 42607.147857042684, 42609.147817044104, 17908.373206937955, 18134.274629014955, 18136.274549017893, 18136.274549017893, 18146.90926545359, 13525.66185845342, 13848.030637855254, 14069.788953165711, 14155.0, 14266.572002839932, 9474.05259474061, 9595.952020239854, 9619.422834629944, 9674.177694895872, 9682.419054856731, 3614.8734794282072, 3652.7808331500196, 3664.4320130379833, 3669.3395188865725, 3732.197577520846, 1014.7919676466307, 1059.075299855662, 1066.0618655583037, 1074.382230217628, 1119.9216054876244, 32752.508712369334, 32993.34013319768, 33753.32493350119, 33986.32027359439, 34374.0, 1169.0180248591225, 1169.3334799164395, 1172.1443346357207, 1196.877969187599, 1278.8337516122845, 17262.482125536255, 17392.65214695699, 17406.0, 17474.65050698979, 17569.472915812545, 618043.0, 620655.0, 623830.0, 625929.0, 626488.0, 59403.40596594065, 59714.40285597088, 59749.70125149346, 59768.70115649394, 59794.40205597975, 1338.7991801229894, 1399.4122468563228, 1409.3023953143054, 1441.207335965215, 1456.3446449097949, 31090.0, 33628.32743345168, 33735.15662108369, 33796.0, 34447.0, 53190.20214696705, 54318.728406359296, 54332.728336358065, 54333.18500222421, 54337.0, 356365.0, 357795.0, 358243.0, 359025.0, 359441.0, 29535.85232073826, 30850.228744280674, 31779.52330714995, 31950.520742188422, 32892.34215315632, 49022.03911843486, 49506.75246623888, 49620.25569616531, 50003.0, 51357.0, 35280.64719352774, 35329.470057949664, 35469.64530354663, 35968.0, 36324.45513317355, 42115.0, 42686.57313426826, 42847.0, 42915.0, 44496.555034449244, 41998.0, 42163.578364215966, 42560.57439425566, 43177.56822431736, 43493.0, 36076.81961590175, 38212.235755284186, 38396.720564790776, 38841.6115838838, 39001.6099838998, 28407.43185136327, 28469.572956405256, 28907.56638650464, 28974.855125724236, 29168.56247156252], "value": 20126.183108705853, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.97642141471493, 412.6162668718093, 413.22107826746594, 420.82115101082036, 421.8755467137194, 14312.570622881318, 16299.266533006014, 20046.498837529052, 20148.0, 21028.158873645036, 20127.19491220353, 20417.795822041775, 20477.385678429633, 20479.18083276671, 21043.36869893902, 5898.380670029649, 6043.365446628107, 6105.938940610604, 6286.057091436298, 6337.936620633781, 4174.66602671787, 6311.810645680637, 6361.0, 6367.936320636803, 6368.936310636881, 4424.40270563473, 4445.666575006871, 6828.214755303129, 6879.3120687931305, 6910.654467276641, 6585.176852893388, 6798.150231221098, 6889.311068893096, 6893.586384816927, 6900.0, 6090.33006369299, 6094.664793436404, 6096.237970253718, 6096.786612468542, 6117.66352850593, 16088.678226435522, 23880.522389552287, 24311.878440607685, 24918.12786552462, 25104.246872593852, 2338.228384633084, 2394.916177933764, 2450.4486490539666, 2494.6756921600077, 2540.9872950635313, 11150.66548003553, 11463.828042579367, 11878.821817672742, 12741.808872866914, 15685.607859803482, 1957.2660252405349, 1992.9238211365846, 1995.6607376745987, 2059.083707750052, 2061.4846288427893, 69185.0, 79838.60080699559, 80108.19891801066, 83539.0, 87613.56193219057, 11631.709207269885, 11685.415729213506, 12066.517339306329, 12071.034317254598, 12124.818127728091, 17227.655446890993, 17227.913860430617, 17229.91385043092, 17241.137943102796, 17244.913775431294, 8898.0, 9023.187913087853, 9027.593758280893, 9096.954515227382, 9260.166585007382, 8890.777730556658, 8903.599338029675, 8950.104989501127, 9138.497382644018, 9151.77120571991, 5102.260172275, 5119.0, 5187.922181167285, 5223.268742376071, 5247.947520524822, 2065.5635093715587, 2070.0438086008576, 2112.29238205203, 2133.567732784627, 2155.5904378168098, 958.8465845464692, 974.1038244814788, 998.9400635961865, 1039.428314427062, 1051.6792378324685, 19625.411237662607, 19647.31234406789, 19785.901070494845, 19912.0, 19999.200031998556, 1001.4942454060798, 1024.5850430575629, 1041.8176819056634, 1042.7966546523508, 1043.2644985285356, 10388.480575971322, 11009.339439633648, 11152.275102118336, 11167.105204477506, 11220.607278745205, 397895.0, 401480.0, 529865.0, 535993.0, 537177.0, 60989.0, 61477.0, 78328.0, 88505.55747221223, 101383.0, 1041.4584416103735, 1074.4090750087578, 1092.448313601626, 1095.9342439453499, 1097.4567589043475, 17894.642107157524, 18192.636147276717, 18238.544036399195, 18393.004094825512, 18491.907540462213, 42288.365674515524, 43708.56291437045, 45117.87205319893, 45296.547034529234, 45753.0, 59337.703311483165, 59483.0, 60671.69664151651, 60774.392256078645, 61203.38796612155, 8765.07966663491, 9105.863412048691, 9132.634694612407, 9252.537373131181, 9323.254139668947, 13063.608091757262, 13495.39270732839, 13934.163950163023, 14039.0, 14376.28118594087, 11451.656450306504, 11780.410979451164, 11787.823182652439, 11916.622382033242, 12118.0, 11634.70913227176, 12115.757684846078, 12173.452194651085, 12205.816912746493, 12356.320402377956, 11266.774664506827, 11381.943090284496, 11466.31202127875, 11484.82772758426, 11918.642440726791, 8991.955040224757, 9165.220956218796, 9262.861057084285, 9359.532023398939, 9451.905480945103, 8725.52009639476, 11136.164787640797, 11159.609413670807, 11209.103271738079, 11413.372264525531], "value": 9635.95894461248, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.1958943197094, 1498.5878883307093, 1592.7531232658935, 1739.8434140927313, 1758.8831092256414, 9200.26397888169, 9880.357776744515, 9888.505574721272, 9889.0, 9895.059969302923, 8351.0, 8680.131986801316, 9178.770530736734, 9530.0, 9532.332736708433, 2524.1922584772833, 2546.719860815309, 2547.46503234321, 2554.067765265674, 2557.8337408068414, 2167.338961616714, 2531.721510633829, 2574.832635878671, 2575.8325708828866, 2576.3043978125893, 2216.7783221677782, 2466.962995555068, 2567.5763499022637, 2573.3823882268216, 2580.65161203238, 2484.776370126688, 2494.052260141135, 2494.6756921600077, 2513.1455305196228, 2515.610080437535, 2467.0625162438337, 2476.3809047738064, 2481.8262721609494, 2481.8883150258102, 2506.724260331362, 9772.12050915421, 9812.803743925153, 9820.950895245549, 9831.705048848546, 9835.901640983642, 5566.443355664442, 5639.661620302754, 5642.4357564243655, 5691.373948865621, 5861.70691465425, 3558.804265765382, 3680.8159592020293, 3726.1988672435286, 3729.645683660048, 3741.9251614967548, 1256.2525297448003, 1291.4769518345086, 1301.3948513940968, 1315.6447759104988, 1328.774108401574, 12933.094683372168, 13426.664333391645, 13602.979776516702, 13664.726705465935, 13667.863321366758, 4784.425868895755, 4829.637777166727, 4910.950890491121, 4937.23472861707, 4949.331840201598, 10594.417307048187, 10610.09814165789, 10612.681619551426, 10615.946920265349, 10624.521896514676, 5738.454846789548, 5740.167675687002, 5740.741666624928, 5742.569307301969, 5744.052231381859, 5156.252343410185, 5282.788688452419, 5359.544438722674, 5369.677819330853, 5374.543163831118, 4196.992721746758, 4205.663546916239, 4218.451601291874, 4231.344141658048, 4247.702660813713, 1180.8582970043649, 1203.1397550751255, 1289.2908900104906, 1300.843898732158, 1304.1718508747015, 933.071593764209, 1017.1760873692333, 1046.5395226100643, 1046.785408991155, 1058.7141471802724, 6378.234611846516, 6579.572327798678, 6604.174478190226, 6605.70274337656, 6618.338166183343, 562.061357532921, 715.7995761186876, 1041.859348987877, 1051.6844946516014, 1084.2139448899643, 4227.260229459894, 4236.343366778093, 4289.721168124062, 4364.083542456056, 4429.734215946989, 11906.404679765978, 12387.75224495505, 12393.256404615751, 12395.876041239653, 12401.689957751129, 11911.940440297743, 12234.265944043385, 12239.571614993432, 12270.202436841577, 12310.0, 8864.689735859214, 9182.86225706615, 9190.49452280131, 9196.448213107235, 9204.401713888576, 5990.970045149921, 6096.177016102769, 6116.369960916849, 6161.137440758387, 6192.133101365723, 11011.339319640847, 11106.944465277622, 11225.831612525655, 11235.775284494428, 11497.597584084517, 11457.59898403552, 11467.541298347973, 11504.942475287904, 11513.251638643462, 11584.594539191088, 8045.396595255263, 8374.120717324593, 8454.746357609281, 8633.438826476258, 8655.740327790176, 9307.813843722952, 9327.160555550034, 9399.295052871199, 9414.388064775767, 9488.667896623585, 7150.785476435507, 8259.545724985184, 8487.872681909652, 8548.273396761097, 8574.31405487572, 8199.0, 8317.085120636604, 8353.289970352587, 8445.95777021111, 8475.74572762818, 7605.429592780674, 7788.182240864854, 7795.181505941794, 7798.493097948615, 7810.6485208166905, 5424.159255315432, 5427.8100266492065, 5472.91790623149, 5517.751701173376, 5540.972295138498, 5300.31095957523, 5368.758405871667, 5393.7033463159905, 5422.810201642924, 5439.048166570913], "value": 4997.80835871403, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.5124301599269, 352.29013537721494, 354.48245561480246, 360.25427365353727, 368.27266149355046, 5595.356533998592, 5616.326040875096, 5627.971860140694, 5638.674911395824, 5650.350209725882, 5226.503482169188, 5340.55804932668, 5398.4357430220625, 5476.644018138818, 5492.615516913807, 4337.54455782143, 4340.417451616283, 4362.607365337119, 4366.8995013540425, 4376.008915943254, 4352.43312407533, 4352.695311328208, 4355.956440435602, 4369.825206991716, 4399.768064941822, 4165.187788381267, 4278.8502402415925, 4306.870793876189, 4323.805428755713, 4380.912381752363, 4289.348600788688, 4291.206126866539, 4303.224420248953, 4316.374125751749, 4318.913621727564, 3837.5609146570037, 3839.712021598363, 3843.1545060086737, 3848.1918797052654, 3849.576546579874, 5236.842894713165, 5374.865628359284, 5414.485623865727, 5427.240186373911, 5434.2935418395755, 4934.481879402647, 4974.801007959677, 4981.800727970877, 4986.379426686305, 4996.925046124311, 5768.336641286264, 5774.740136693859, 5781.335146458169, 5804.390538993429, 5819.7090145492975, 4133.627973482371, 4195.853145139936, 4229.302165142779, 4237.32202847546, 4260.062786187033, 5946.881062378771, 6009.338972712998, 6010.519158467268, 6033.159886234696, 6035.849103772441, 4317.481902171759, 4317.697761156752, 4318.201132790444, 4346.543612920661, 4360.869173924787, 4329.76186309756, 4403.647708183337, 4445.377647129404, 4457.554244575581, 4462.174497717932, 4957.546014722735, 4974.204127339608, 4978.153713868651, 4984.127777638899, 5000.399872040912, 5847.8363005687315, 5856.619319744203, 5863.091220860773, 5888.67504811414, 5893.998020336553, 1416.4405060001243, 1455.9490417835325, 1468.4346526587128, 1495.8130233720785, 1497.7229212595705, 1007.8034783217349, 1010.4846528270635, 1036.43514284715, 1036.8133735927606, 1047.8061558611682, 5535.418781028015, 5536.889262214733, 5545.611807173459, 5564.721763911788, 5597.93639208549, 5887.35239123696, 5898.174255604219, 5908.645481271137, 5915.254999775077, 5917.349091600006, 5388.649737767033, 5562.499375056264, 5587.469190426903, 5594.468525490072, 5597.412271711493, 3121.2352973521633, 3122.656507784142, 3129.702678245563, 3132.4674805283157, 3150.6219253689246, 5887.293524777054, 6043.576949613484, 6055.455009049118, 6064.514838812973, 6071.362506936708, 5979.385565897183, 5988.760449582055, 5990.940090599125, 6026.6986650667295, 6043.425874541911, 6057.242844644419, 6077.179580756541, 6094.8171554853425, 6098.908516372258, 6113.174721412553, 5757.366689664217, 5863.237779088862, 5901.940980590311, 5917.142014407973, 5919.0, 5796.188533605215, 5826.533877289891, 5839.737211825393, 5841.766329346779, 5851.385604511465, 5958.463738263577, 6026.819195424145, 6027.879442411039, 6040.912635725612, 6101.223943105788, 4253.21315556623, 4319.503257125471, 4331.263685173465, 4346.847860324873, 4357.803898824497, 5910.202122713551, 5936.910946335895, 5946.940530594812, 5952.553558483044, 5983.192269043798, 5566.165075238747, 5748.252727145445, 5750.942490575041, 5796.855078623068, 5869.911951320648, 5697.0, 5783.681897495678, 5817.0, 5828.242328497268, 5841.824745257649, 5592.462072929977, 5758.395368486416, 5776.624519406225, 5790.0, 5800.8259752207505, 5855.448306198776, 5867.413258674269, 5868.38619379674, 5886.882262354815, 5899.793507227227, 5710.5146062585145, 5722.599418040863, 5758.610176776759, 5764.538836892954, 5791.13133030032], "value": 4443.699531258146, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.4197764642986, 795.852767238061, 838.4508147163608, 861.7888617288766, 871.9084496127906, 16920.83079169208, 17763.20065597048, 17931.91034044831, 17950.371736989207, 18047.639047219047, 16217.351305947777, 16316.184190790442, 16330.918345408256, 16337.26482308296, 16351.182440877938, 701.3057073497253, 721.6824597177225, 825.5830805443243, 829.7966998085448, 856.8843206167181, 852.1308265569115, 852.8550146475083, 854.6965827131372, 854.7606670132342, 855.0978717453085, 905.4702998745742, 906.3247880329164, 907.1518130547959, 907.2787134228271, 907.4509921497498, 736.8452624948767, 756.3646536908996, 857.6269322844552, 874.3049275825733, 885.340421386065, 654.3979538824293, 654.450261780107, 654.6563054396432, 654.8362909272682, 654.9705263263164, 15298.388064477518, 15301.311440985182, 15339.769903451457, 15344.693106137815, 15354.0, 15289.311980960883, 15301.311440985182, 15384.846151538566, 15428.0, 15752.448664296695, 13986.230757308344, 14024.92987535066, 14054.367553460119, 14080.15519068859, 14313.141211527342, 4479.238529450001, 4480.395146655193, 4497.662675299365, 4523.36672865799, 4595.862124136282, 83460.58269708735, 83528.58235708783, 83827.0, 83827.0, 84938.0, 11713.355765432983, 11895.762084758257, 11962.222455540363, 11994.400279985966, 12569.057320700984, 23073.769262307498, 23208.41978950506, 23490.7650923492, 23500.882495587754, 23566.0, 8537.53043582609, 8715.869261961076, 8734.737957861274, 9002.954985225031, 9242.399244049115, 5190.688558686491, 5259.421463638997, 5294.205869119586, 5298.072837253465, 5338.8665283367445, 3643.216708407705, 3800.847966081326, 3805.3911374179997, 3805.82873770681, 3807.923841523154, 584.360125662399, 669.1735706402588, 671.6943790575276, 671.7783131566571, 672.7342699633617, 744.2966396755014, 765.3915137465729, 789.4394979564573, 792.5205250823169, 825.1871906172415, 9966.601335946625, 10011.349262297927, 10071.899281007243, 10404.635837745642, 10480.947595261974, 788.7949133225403, 798.3413683710962, 838.781916701662, 842.5829214538843, 872.1235158665501, 4540.364348991144, 4975.676581022222, 5000.824971125993, 5016.849494515171, 5018.949810501921, 102906.0, 109360.0, 114148.0, 118816.0, 125489.37255313665, 100549.49725251473, 102542.0, 103249.48375258075, 104823.47588262163, 105833.0, 56740.0, 81266.59366703247, 89137.0, 91065.0, 93302.53348733348, 9921.751956200864, 9950.701478955643, 10059.346142500714, 10214.795704085727, 10356.378617282988, 54053.189202161215, 54302.45697542974, 55458.44541554533, 55555.72222139025, 56554.0, 73952.0, 77516.44967100513, 77605.0, 78138.6093069531, 79899.60050199712, 13019.0, 13696.246706431242, 14168.291585420893, 14252.363312786752, 14521.636959075686, 14850.702985940437, 14998.475053373517, 15116.622084447978, 15370.923145384202, 16241.187940602686, 12505.374731263219, 14417.990740648473, 14797.482088126864, 15165.620859478602, 16447.91776041112, 14157.646058848612, 14984.850151498345, 15009.549713508612, 15304.387824486896, 15683.529494115195, 14562.635934101732, 14643.414263429344, 14708.485203017843, 14712.264386780404, 15112.035435393329, 11354.318740875575, 12509.624711258677, 12564.748705026032, 13068.085234033906, 13222.801657974946, 10971.23201375896, 11022.944885275843, 11057.0, 11092.11263098934, 11358.54565817358], "value": 8061.346627517611, "unit": "score"}], "name": "dromaeo_css", "value": 7595.54382451648, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 113.0, 112.0, 110.0, 110.0, 111.0, 112.0, 110.0, 110.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 115.0, 162.0, 119.0, 117.0, 116.0, 114.0, 155.0, 116.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 159.0, 163.0, 161.0, 160.0, 161.0, 161.0, 160.0, 159.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 121.0, 76.0, 75.0, 75.0, 75.0, 110.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 124.0, 81.0, 81.0, 82.0, 115.0, 82.0, 81.0, 107.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 147.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 96.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 49.0, 50.0, 48.0, 48.0, 49.0, 49.0, 48.0, 49.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 60.0, 58.0, 59.0, 58.0, 59.0, 62.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 76.0, 73.0, 72.0, 72.0, 78.0, 74.0, 75.0, 73.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 122.0, 119.0, 125.0, 120.0, 120.0, 119.0, 126.0, 170.0, 124.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 164.0, 153.0, 156.0, 157.0, 152.0, 154.0, 152.0, 156.0, 155.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 61.0, 62.0, 63.0, 64.0, 64.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1457.6000000000004, "alertThreshold": 2.0}]} 09:21:41 INFO - Return code: 0 09:21:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:21:41 INFO - # TBPL SUCCESS # 09:21:41 INFO - Running post-action listener: _resource_record_post_action 09:21:41 INFO - [mozharness: 2016-11-06 17:21:41.508047Z] Finished run-tests step (success) 09:21:41 INFO - Running post-run listener: _resource_record_post_run 09:21:41 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2134016; Write bytes: 368418816; Read time: 456; Write time: 252436 09:21:41 INFO - TinderboxPrint: CPU usage
12.9% 09:21:41 INFO - TinderboxPrint: I/O read bytes / time
2,134,016 / 456 09:21:41 INFO - TinderboxPrint: I/O write bytes / time
368,418,816 / 252,436 09:21:41 INFO - TinderboxPrint: CPU idle
8,268.6 (87.1%) 09:21:41 INFO - TinderboxPrint: CPU user
1,185.2 (12.5%) 09:21:41 INFO - TinderboxPrint: Swap in / out
0 / 0 09:21:41 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 108171264; Read time: 0; Write time: 89212 09:21:41 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248782848; Read time: 12; Write time: 160280 09:21:42 INFO - Running post-run listener: _upload_blobber_files 09:21:42 INFO - Blob upload gear active. 09:21:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:21:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:21:42 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:21:42 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:21:42 INFO - (blobuploader) - INFO - Open directory for files ... 09:21:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:21:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:21:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:43 INFO - (blobuploader) - INFO - Done attempting. 09:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:21:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:47 INFO - (blobuploader) - INFO - Done attempting. 09:21:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:21:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:21:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:48 INFO - (blobuploader) - INFO - Done attempting. 09:21:48 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:48 INFO - Return code: 0 09:21:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/688750c1aa25646271b3100470a42721822d645fb4273217a1e2efa5619d56e0677eaba25eb449c3e1d162599bc5d6653ae7e0c510155b69a9484c0914d5e156", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c2c43725a4c035a0e5295f954a80a6bd27b455c8f26a4976b9d352e8ef3c1f797ba1acafba105dc812c434270676ecc46c02230f4d8465153b46858b9e3940", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/487f33a6eaa654acdefeb95446d819591deb54a99415116d976d0abe4fdd78b465a6c74c4ff91243574dd6c4e8542d60c401eb5d17b5e1b47112505845efb95b"} 09:21:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:48 INFO - Contents: 09:21:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/688750c1aa25646271b3100470a42721822d645fb4273217a1e2efa5619d56e0677eaba25eb449c3e1d162599bc5d6653ae7e0c510155b69a9484c0914d5e156", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c2c43725a4c035a0e5295f954a80a6bd27b455c8f26a4976b9d352e8ef3c1f797ba1acafba105dc812c434270676ecc46c02230f4d8465153b46858b9e3940", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/487f33a6eaa654acdefeb95446d819591deb54a99415116d976d0abe4fdd78b465a6c74c4ff91243574dd6c4e8542d60c401eb5d17b5e1b47112505845efb95b"} 09:21:48 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:48 INFO - Copying logs to upload dir... 09:21:48 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.469771 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-11-06 09:21:48.321526) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 09:21:48.324683) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987 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-Aurora/sha512/688750c1aa25646271b3100470a42721822d645fb4273217a1e2efa5619d56e0677eaba25eb449c3e1d162599bc5d6653ae7e0c510155b69a9484c0914d5e156", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c2c43725a4c035a0e5295f954a80a6bd27b455c8f26a4976b9d352e8ef3c1f797ba1acafba105dc812c434270676ecc46c02230f4d8465153b46858b9e3940", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/487f33a6eaa654acdefeb95446d819591deb54a99415116d976d0abe4fdd78b465a6c74c4ff91243574dd6c4e8542d60c401eb5d17b5e1b47112505845efb95b"} build_url:https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011649 build_url: 'https://queue.taskcluster.net/v1/task/YiZq9k4XSy-2Ct83nLfprg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/688750c1aa25646271b3100470a42721822d645fb4273217a1e2efa5619d56e0677eaba25eb449c3e1d162599bc5d6653ae7e0c510155b69a9484c0914d5e156", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57c2c43725a4c035a0e5295f954a80a6bd27b455c8f26a4976b9d352e8ef3c1f797ba1acafba105dc812c434270676ecc46c02230f4d8465153b46858b9e3940", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/487f33a6eaa654acdefeb95446d819591deb54a99415116d976d0abe4fdd78b465a6c74c4ff91243574dd6c4e8542d60c401eb5d17b5e1b47112505845efb95b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-06 09:21:48.351279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:21:48.351636) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1478451149.858656-238175987 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-06 09:21:48.438266) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 09:21:48.438949) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-06 09:21:48.439673) ========= ========= Total master_lag: 0.18 =========