builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1481562424.28 results: success (0) buildid: 20161212081051 builduid: 4b7e6ef7e41c41e398654d52b38f239e revision: f46f85dcfbc2b3098ea758825d18be6fab33cbc6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.275842) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.276325) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.276605) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.352831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.353106) ========= 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=3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-12 09:07:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-12-12 09:07:05 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102780 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.480599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.480883) ========= 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=3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014936 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.530481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:04.530789) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f46f85dcfbc2b3098ea758825d18be6fab33cbc6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f46f85dcfbc2b3098ea758825d18be6fab33cbc6 --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=3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-12 09:07:05,167 truncating revision to first 12 chars 2016-12-12 09:07:05,167 Setting DEBUG logging. 2016-12-12 09:07:05,167 attempt 1/10 2016-12-12 09:07:05,167 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f46f85dcfbc2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-12 09:07:05,495 unpacking tar archive at: mozilla-central-f46f85dcfbc2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.704155 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:05.250550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:05.250912) ========= script_repo_revision: f46f85dcfbc2b3098ea758825d18be6fab33cbc6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:05.251432) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:05.251748) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 09:07:05.260945) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-12-12 09:07:05.261336) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:07:05 INFO - MultiFileLogger online at 20161212 09:07:05 in /builds/slave/test 09:07:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:07:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:05 INFO - {'append_to_log': False, 09:07:05 INFO - 'base_work_dir': '/builds/slave/test', 09:07:05 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:07:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:05 INFO - 'branch': 'Firefox-Non-PGO', 09:07:05 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:05 INFO - 'config_files': ('talos/linux_config.py',), 09:07:05 INFO - 'default_actions': ('clobber', 09:07:05 INFO - 'read-buildbot-config', 09:07:05 INFO - 'download-and-extract', 09:07:05 INFO - 'populate-webroot', 09:07:05 INFO - 'create-virtualenv', 09:07:05 INFO - 'install', 09:07:05 INFO - 'run-tests'), 09:07:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:05 INFO - 'download_minidump_stackwalk': True, 09:07:05 INFO - 'download_symbols': 'ondemand', 09:07:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:05 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:05 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:05 INFO - 'installer_path': 'installer.exe', 09:07:05 INFO - 'log_level': 'info', 09:07:05 INFO - 'log_name': 'talos', 09:07:05 INFO - 'log_to_console': True, 09:07:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:07:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:07:05 INFO - 'opt_config_files': (), 09:07:05 INFO - 'pip_index': False, 09:07:05 INFO - 'sps_profile': False, 09:07:05 INFO - 'sps_profile_interval': 0, 09:07:05 INFO - 'suite': 'dromaeojs', 09:07:05 INFO - 'system_bits': '32', 09:07:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:05 INFO - 'title': 'talos-linux64-ix-030', 09:07:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:05 INFO - 'use_talos_json': True, 09:07:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:05 INFO - 'work_dir': 'build'} 09:07:05 INFO - [mozharness: 2016-12-12 17:07:05.998896Z] Running clobber step. 09:07:05 INFO - Running pre-action listener: _resource_record_pre_action 09:07:05 INFO - Running main action method: clobber 09:07:05 INFO - rmtree: /builds/slave/test/build 09:07:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:06 INFO - Running post-action listener: _resource_record_post_action 09:07:06 INFO - [mozharness: 2016-12-12 17:07:06.996759Z] Finished clobber step (success) 09:07:06 INFO - [mozharness: 2016-12-12 17:07:06.996858Z] Running read-buildbot-config step. 09:07:06 INFO - Running pre-action listener: _resource_record_pre_action 09:07:06 INFO - Running main action method: read_buildbot_config 09:07:06 INFO - Using buildbot properties: 09:07:06 INFO - { 09:07:06 INFO - "project": "", 09:07:06 INFO - "product": "firefox", 09:07:06 INFO - "script_repo_revision": "production", 09:07:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:07:06 INFO - "repository": "", 09:07:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:07:06 INFO - "buildid": "20161212081051", 09:07:06 INFO - "pgo_build": "False", 09:07:06 INFO - "basedir": "/builds/slave/test", 09:07:06 INFO - "buildnumber": 743, 09:07:06 INFO - "slavename": "talos-linux64-ix-030", 09:07:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:07:06 INFO - "platform": "ubuntu64_hw", 09:07:06 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:07:06 INFO - "branch": "mozilla-central", 09:07:06 INFO - "repo_path": "mozilla-central", 09:07:06 INFO - "revision": "f46f85dcfbc2b3098ea758825d18be6fab33cbc6", 09:07:06 INFO - "stage_platform": "linux64", 09:07:06 INFO - "builduid": "4b7e6ef7e41c41e398654d52b38f239e", 09:07:06 INFO - "slavebuilddir": "test" 09:07:06 INFO - } 09:07:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:07:06 INFO - Running post-action listener: _resource_record_post_action 09:07:06 INFO - [mozharness: 2016-12-12 17:07:06.999304Z] Finished read-buildbot-config step (success) 09:07:06 INFO - [mozharness: 2016-12-12 17:07:06.999390Z] Running download-and-extract step. 09:07:06 INFO - Running pre-action listener: _resource_record_pre_action 09:07:06 INFO - Running main action method: download_and_extract 09:07:06 INFO - mkdir: /builds/slave/test/build/tests 09:07: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:07:07 INFO - trying https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:07:07 INFO - Downloading https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:07:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:07:07 INFO - Downloaded 1361 bytes. 09:07:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:07:07 INFO - Using the following test package requirements: 09:07:07 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:07:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:07:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:07:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'jsshell-linux-x86_64.zip'], 09:07:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:07:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:07:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:07:07 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:07:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:07:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:07:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:07:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:07:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:07:07 INFO - Fetch https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:07:09 INFO - Expected file size: 20224488 09:07:09 INFO - Obtained file size: 20224488 09:07:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:07:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:07:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:07:13 INFO - Fetch https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:07:14 INFO - Expected file size: 20224488 09:07:14 INFO - Obtained file size: 20224488 09:07:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:07:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:07:18 INFO - Fetch https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:07:20 INFO - Expected file size: 14409734 09:07:20 INFO - Obtained file size: 14409734 09:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:20 INFO - trying https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:07:20 INFO - Downloading https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:07:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:07:24 INFO - Downloaded 55772868 bytes. 09:07:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:07:24 INFO - mkdir: /builds/slave/test/properties 09:07:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:24 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:24 INFO - Contents: 09:07:24 INFO - build_url:https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:07:24 INFO - Running post-action listener: _resource_record_post_action 09:07:24 INFO - Running post-action listener: set_extra_try_arguments 09:07:24 INFO - [mozharness: 2016-12-12 17:07:24.748381Z] Finished download-and-extract step (success) 09:07:24 INFO - [mozharness: 2016-12-12 17:07:24.748607Z] Running populate-webroot step. 09:07:24 INFO - Running pre-action listener: _resource_record_pre_action 09:07:24 INFO - Running main action method: populate_webroot 09:07:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:24 INFO - '--tppagecycles', 09:07:24 INFO - '7'], 09:07:24 INFO - 'tests': ['tsvgm']}}, 09:07:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:07:24 INFO - 'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:07:24 INFO - 'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['damp', 'tps']}, 09:07:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:07:24 INFO - 'tests': ['damp', 'tps']}, 09:07:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['dromaeo_dom']}, 09:07:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['basic_compositor_video']}, 09:07:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:07:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['a11yr', 09:07:24 INFO - 'ts_paint', 09:07:24 INFO - 'tpaint', 09:07:24 INFO - 'sessionrestore', 09:07:24 INFO - 'sessionrestore_no_auto_restore', 09:07:24 INFO - 'tabpaint']}, 09:07:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:24 INFO - 'ts_paint', 09:07:24 INFO - 'tpaint', 09:07:24 INFO - 'sessionrestore', 09:07:24 INFO - 'sessionrestore_no_auto_restore', 09:07:24 INFO - 'tabpaint']}, 09:07:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:24 INFO - 'ts_paint', 09:07:24 INFO - 'tpaint', 09:07:24 INFO - 'sessionrestore', 09:07:24 INFO - 'sessionrestore_no_auto_restore', 09:07:24 INFO - 'tabpaint']}, 09:07:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:24 INFO - 'ts_paint', 09:07:24 INFO - 'tpaint', 09:07:24 INFO - 'sessionrestore', 09:07:24 INFO - 'sessionrestore_no_auto_restore', 09:07:24 INFO - 'tabpaint']}, 09:07:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['a11yr', 09:07:24 INFO - 'ts_paint', 09:07:24 INFO - 'tpaint', 09:07:24 INFO - 'sessionrestore', 09:07:24 INFO - 'sessionrestore_no_auto_restore', 09:07:24 INFO - 'tabpaint']}, 09:07:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['a11yr', 09:07:24 INFO - 'ts_paint', 09:07:24 INFO - 'tpaint', 09:07:24 INFO - 'sessionrestore', 09:07:24 INFO - 'sessionrestore_no_auto_restore', 09:07:24 INFO - 'tabpaint']}, 09:07:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['tsvgx', 09:07:24 INFO - 'tsvgr_opacity', 09:07:24 INFO - 'tart', 09:07:24 INFO - 'tscrollx', 09:07:24 INFO - 'cart', 09:07:24 INFO - 'tsvg_static']}, 09:07:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:24 INFO - 'tsvgr_opacity', 09:07:24 INFO - 'tart', 09:07:24 INFO - 'tscrollx', 09:07:24 INFO - 'cart', 09:07:24 INFO - 'tsvg_static']}, 09:07:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:07:24 INFO - 'talos_options': ['--disable-e10s'], 09:07:24 INFO - 'tests': ['tp5o']}, 09:07:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:07:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:07:24 INFO - 'talos_options': ['--disable-e10s', 09:07:24 INFO - '--xperf_path', 09:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:24 INFO - 'tests': ['tp5n']}, 09:07:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:07:24 INFO - 'talos_options': ['--xperf_path', 09:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:24 INFO - 'tests': ['tp5n']}}, 09:07:24 INFO - 'talos.zip': {'path': '', 09:07:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:24 INFO - Running post-action listener: _resource_record_post_action 09:07:24 INFO - [mozharness: 2016-12-12 17:07:24.764685Z] Finished populate-webroot step (success) 09:07:24 INFO - [mozharness: 2016-12-12 17:07:24.764773Z] Running create-virtualenv step. 09:07:24 INFO - Running pre-action listener: _resource_record_pre_action 09:07:24 INFO - Running main action method: create_virtualenv 09:07:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:24 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:07:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:24 INFO - Using real prefix '/usr' 09:07:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:27 INFO - Installing pip.................done. 09:07:27 INFO - Return code: 0 09:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:07:27 INFO - Reading from file tmpfile_stdout 09:07:27 INFO - Output received: 09:07:27 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:07:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07:27 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:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 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:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:27 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:07:27 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:07:27 INFO - Using env: {'DISPLAY': ':0', 09:07:27 INFO - 'HOME': '/home/cltbld', 09:07:27 INFO - 'LANG': 'en_US.UTF-8', 09:07:27 INFO - 'LANGUAGE': 'en_US:en', 09:07:27 INFO - 'LOGNAME': 'cltbld', 09:07:27 INFO - 'MAIL': '/var/mail/cltbld', 09:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:27 INFO - 'MOZ_NO_REMOTE': '1', 09:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:27 INFO - 'NO_EM_RESTART': '1', 09:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:27 INFO - 'PWD': '/builds/slave/test', 09:07:27 INFO - 'SHELL': '/bin/bash', 09:07:27 INFO - 'SHLVL': '1', 09:07:27 INFO - 'TERM': 'linux', 09:07:27 INFO - 'TMOUT': '86400', 09:07:27 INFO - 'USER': 'cltbld', 09:07:27 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:27 INFO - '_': '/tools/buildbot/bin/python'} 09:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:27 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:07:27 INFO - Cleaning up... 09:07:27 INFO - Return code: 0 09:07:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:07:27 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:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 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:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:27 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:07:27 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:07:27 INFO - Using env: {'DISPLAY': ':0', 09:07:27 INFO - 'HOME': '/home/cltbld', 09:07:27 INFO - 'LANG': 'en_US.UTF-8', 09:07:27 INFO - 'LANGUAGE': 'en_US:en', 09:07:27 INFO - 'LOGNAME': 'cltbld', 09:07:27 INFO - 'MAIL': '/var/mail/cltbld', 09:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:27 INFO - 'MOZ_NO_REMOTE': '1', 09:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:27 INFO - 'NO_EM_RESTART': '1', 09:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:27 INFO - 'PWD': '/builds/slave/test', 09:07:27 INFO - 'SHELL': '/bin/bash', 09:07:27 INFO - 'SHLVL': '1', 09:07:27 INFO - 'TERM': 'linux', 09:07:27 INFO - 'TMOUT': '86400', 09:07:27 INFO - 'USER': 'cltbld', 09:07:27 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:27 INFO - '_': '/tools/buildbot/bin/python'} 09:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:27 INFO - Downloading/unpacking psutil>=3.1.1 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:07:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:30 INFO - Installing collected packages: psutil 09:07:30 INFO - Running setup.py install for psutil 09:07:30 INFO - building 'psutil._psutil_linux' extension 09:07:30 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:07:30 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:07:30 INFO - building 'psutil._psutil_posix' extension 09:07:30 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:07:30 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:07:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:30 INFO - Successfully installed psutil 09:07:30 INFO - Cleaning up... 09:07:30 INFO - Return code: 0 09:07:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:30 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:30 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:07:30 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:07:30 INFO - Using env: {'DISPLAY': ':0', 09:07:30 INFO - 'HOME': '/home/cltbld', 09:07:30 INFO - 'LANG': 'en_US.UTF-8', 09:07:30 INFO - 'LANGUAGE': 'en_US:en', 09:07:30 INFO - 'LOGNAME': 'cltbld', 09:07:30 INFO - 'MAIL': '/var/mail/cltbld', 09:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:30 INFO - 'MOZ_NO_REMOTE': '1', 09:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:30 INFO - 'NO_EM_RESTART': '1', 09:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:30 INFO - 'PWD': '/builds/slave/test', 09:07:30 INFO - 'SHELL': '/bin/bash', 09:07:30 INFO - 'SHLVL': '1', 09:07:30 INFO - 'TERM': 'linux', 09:07:30 INFO - 'TMOUT': '86400', 09:07:30 INFO - 'USER': 'cltbld', 09:07:30 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:30 INFO - '_': '/tools/buildbot/bin/python'} 09:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:32 INFO - Installing collected packages: mozsystemmonitor 09:07:32 INFO - Running setup.py install for mozsystemmonitor 09:07:32 INFO - Successfully installed mozsystemmonitor 09:07:32 INFO - Cleaning up... 09:07:33 INFO - Return code: 0 09:07:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:07:33 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:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:33 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:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:33 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:33 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:07:33 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:07:33 INFO - Using env: {'DISPLAY': ':0', 09:07:33 INFO - 'HOME': '/home/cltbld', 09:07:33 INFO - 'LANG': 'en_US.UTF-8', 09:07:33 INFO - 'LANGUAGE': 'en_US:en', 09:07:33 INFO - 'LOGNAME': 'cltbld', 09:07:33 INFO - 'MAIL': '/var/mail/cltbld', 09:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:33 INFO - 'MOZ_NO_REMOTE': '1', 09:07:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:33 INFO - 'NO_EM_RESTART': '1', 09:07:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:33 INFO - 'PWD': '/builds/slave/test', 09:07:33 INFO - 'SHELL': '/bin/bash', 09:07:33 INFO - 'SHLVL': '1', 09:07:33 INFO - 'TERM': 'linux', 09:07:33 INFO - 'TMOUT': '86400', 09:07:33 INFO - 'USER': 'cltbld', 09:07:33 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:33 INFO - '_': '/tools/buildbot/bin/python'} 09:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:33 INFO - Downloading/unpacking jsonschema==2.5.1 09:07:33 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:07:33 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:07:33 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:07:33 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:07:33 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:07:33 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:07:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:07:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:07:35 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:07:35 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:07:35 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:07:35 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:07:35 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:07:35 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:07:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:35 INFO - warning: no files found matching '*.txt' 09:07:35 INFO - no previously-included directories found matching 'build' 09:07:35 INFO - no previously-included directories found matching 'dist' 09:07:35 INFO - no previously-included directories found matching '.git*' 09:07:35 INFO - Installing collected packages: jsonschema, functools32 09:07:35 INFO - Running setup.py install for functools32 09:07:35 INFO - warning: no files found matching '*.txt' 09:07:35 INFO - no previously-included directories found matching 'build' 09:07:35 INFO - no previously-included directories found matching 'dist' 09:07:35 INFO - no previously-included directories found matching '.git*' 09:07:35 INFO - Successfully installed jsonschema functools32 09:07:35 INFO - Cleaning up... 09:07:35 INFO - Return code: 0 09:07:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:35 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:07:35 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:07:35 INFO - Using env: {'DISPLAY': ':0', 09:07:35 INFO - 'HOME': '/home/cltbld', 09:07:35 INFO - 'LANG': 'en_US.UTF-8', 09:07:35 INFO - 'LANGUAGE': 'en_US:en', 09:07:35 INFO - 'LOGNAME': 'cltbld', 09:07:35 INFO - 'MAIL': '/var/mail/cltbld', 09:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:35 INFO - 'MOZ_NO_REMOTE': '1', 09:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:35 INFO - 'NO_EM_RESTART': '1', 09:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:35 INFO - 'PWD': '/builds/slave/test', 09:07:35 INFO - 'SHELL': '/bin/bash', 09:07:35 INFO - 'SHLVL': '1', 09:07:35 INFO - 'TERM': 'linux', 09:07:35 INFO - 'TMOUT': '86400', 09:07:35 INFO - 'USER': 'cltbld', 09:07:35 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:35 INFO - '_': '/tools/buildbot/bin/python'} 09:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:07:35 INFO - Cleaning up... 09:07:35 INFO - Return code: 0 09:07:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:35 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:35 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:07:35 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:07:35 INFO - Using env: {'DISPLAY': ':0', 09:07:35 INFO - 'HOME': '/home/cltbld', 09:07:35 INFO - 'LANG': 'en_US.UTF-8', 09:07:35 INFO - 'LANGUAGE': 'en_US:en', 09:07:35 INFO - 'LOGNAME': 'cltbld', 09:07:35 INFO - 'MAIL': '/var/mail/cltbld', 09:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:35 INFO - 'MOZ_NO_REMOTE': '1', 09:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:35 INFO - 'NO_EM_RESTART': '1', 09:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:35 INFO - 'PWD': '/builds/slave/test', 09:07:35 INFO - 'SHELL': '/bin/bash', 09:07:35 INFO - 'SHLVL': '1', 09:07:35 INFO - 'TERM': 'linux', 09:07:35 INFO - 'TMOUT': '86400', 09:07:35 INFO - 'USER': 'cltbld', 09:07:35 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:35 INFO - '_': '/tools/buildbot/bin/python'} 09:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:07: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:07: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:07: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:07: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:07: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:07: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:07:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07: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:07: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:07: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:07: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:07: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:07: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:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07: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:07: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:07: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:07: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:07: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:07: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:07:38 INFO - Downloading docopt-0.6.1.tar.gz 09:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:38 INFO - Running setup.py install for blobuploader 09:07:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:38 INFO - Running setup.py install for requests 09:07:39 INFO - Running setup.py install for docopt 09:07:39 INFO - Successfully installed blobuploader requests docopt 09:07:39 INFO - Cleaning up... 09:07:39 INFO - Return code: 0 09:07:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07: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:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:39 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:39 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:07:39 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:07:39 INFO - Using env: {'DISPLAY': ':0', 09:07:39 INFO - 'HOME': '/home/cltbld', 09:07:39 INFO - 'LANG': 'en_US.UTF-8', 09:07:39 INFO - 'LANGUAGE': 'en_US:en', 09:07:39 INFO - 'LOGNAME': 'cltbld', 09:07:39 INFO - 'MAIL': '/var/mail/cltbld', 09:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:39 INFO - 'MOZ_NO_REMOTE': '1', 09:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:39 INFO - 'NO_EM_RESTART': '1', 09:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:39 INFO - 'PWD': '/builds/slave/test', 09:07:39 INFO - 'SHELL': '/bin/bash', 09:07:39 INFO - 'SHLVL': '1', 09:07:39 INFO - 'TERM': 'linux', 09:07:39 INFO - 'TMOUT': '86400', 09:07:39 INFO - 'USER': 'cltbld', 09:07:39 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:39 INFO - '_': '/tools/buildbot/bin/python'} 09:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:39 INFO - Running setup.py (path:/tmp/pip-ECjjEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:39 INFO - Running setup.py (path:/tmp/pip-dDi02b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:39 INFO - Running setup.py (path:/tmp/pip-9SPU4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:39 INFO - Running setup.py (path:/tmp/pip-MNaHzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:39 INFO - Running setup.py (path:/tmp/pip-yVdipB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:40 INFO - Running setup.py (path:/tmp/pip-IgIJJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:40 INFO - Running setup.py (path:/tmp/pip-BH4UQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:40 INFO - Running setup.py (path:/tmp/pip-h1QIlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:40 INFO - Running setup.py (path:/tmp/pip-UtAFdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:40 INFO - Running setup.py (path:/tmp/pip-AJpTgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:40 INFO - Running setup.py (path:/tmp/pip-cOBE6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:40 INFO - Running setup.py (path:/tmp/pip-nAjU1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:40 INFO - Running setup.py (path:/tmp/pip-HnJfp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:40 INFO - Running setup.py (path:/tmp/pip-zm_AeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:40 INFO - Running setup.py (path:/tmp/pip-rZIwvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:40 INFO - Running setup.py (path:/tmp/pip-032M1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:40 INFO - Running setup.py (path:/tmp/pip-cAyA4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:40 INFO - Running setup.py install for manifestparser 09:07:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Running setup.py install for mozcrash 09:07:41 INFO - Running setup.py install for mozdebug 09:07:41 INFO - Running setup.py install for mozdevice 09:07:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Running setup.py install for mozfile 09:07:41 INFO - Running setup.py install for mozhttpd 09:07:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Running setup.py install for mozinfo 09:07:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Running setup.py install for mozInstall 09:07:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Running setup.py install for mozleak 09:07:41 INFO - Running setup.py install for mozlog 09:07:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Running setup.py install for moznetwork 09:07:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:42 INFO - Running setup.py install for mozprocess 09:07:42 INFO - Running setup.py install for mozprofile 09:07:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:42 INFO - Running setup.py install for mozrunner 09:07:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:42 INFO - Running setup.py install for mozscreenshot 09:07:42 INFO - Running setup.py install for moztest 09:07:42 INFO - Running setup.py install for mozversion 09:07:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:42 INFO - Cleaning up... 09:07:42 INFO - Return code: 0 09:07:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07: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:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:42 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:42 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:07:42 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:07:42 INFO - Using env: {'DISPLAY': ':0', 09:07:42 INFO - 'HOME': '/home/cltbld', 09:07:42 INFO - 'LANG': 'en_US.UTF-8', 09:07:42 INFO - 'LANGUAGE': 'en_US:en', 09:07:42 INFO - 'LOGNAME': 'cltbld', 09:07:42 INFO - 'MAIL': '/var/mail/cltbld', 09:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:42 INFO - 'MOZ_NO_REMOTE': '1', 09:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:42 INFO - 'NO_EM_RESTART': '1', 09:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:42 INFO - 'PWD': '/builds/slave/test', 09:07:42 INFO - 'SHELL': '/bin/bash', 09:07:42 INFO - 'SHLVL': '1', 09:07:42 INFO - 'TERM': 'linux', 09:07:42 INFO - 'TMOUT': '86400', 09:07:42 INFO - 'USER': 'cltbld', 09:07:42 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:42 INFO - '_': '/tools/buildbot/bin/python'} 09:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:42 INFO - Running setup.py (path:/tmp/pip-DoU1lM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:43 INFO - Running setup.py (path:/tmp/pip-gTodZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:43 INFO - Running setup.py (path:/tmp/pip-knzcle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:43 INFO - Running setup.py (path:/tmp/pip-Bv8Uqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:43 INFO - Running setup.py (path:/tmp/pip-chpRWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:43 INFO - Running setup.py (path:/tmp/pip-sUFpCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:43 INFO - Running setup.py (path:/tmp/pip-vJqF0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:43 INFO - Running setup.py (path:/tmp/pip-lf4hJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:43 INFO - Running setup.py (path:/tmp/pip-QsEfgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:43 INFO - Running setup.py (path:/tmp/pip-byb44_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:43 INFO - Running setup.py (path:/tmp/pip-81DD6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:43 INFO - Running setup.py (path:/tmp/pip-eR3OhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:43 INFO - Running setup.py (path:/tmp/pip-bjMtoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:43 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:43 INFO - Running setup.py (path:/tmp/pip-SKzrEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:43 INFO - Running setup.py (path:/tmp/pip-LNaLpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:44 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:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:44 INFO - Running setup.py (path:/tmp/pip-pAoBa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:44 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:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:44 INFO - Running setup.py (path:/tmp/pip-9wZa7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:44 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:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:44 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:07:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:46 INFO - Downloading blessings-1.6.tar.gz 09:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:07:46 INFO - Installing collected packages: blessings 09:07:46 INFO - Running setup.py install for blessings 09:07:46 INFO - Successfully installed blessings 09:07:46 INFO - Cleaning up... 09:07:46 INFO - Return code: 0 09:07:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:07:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:46 INFO - Reading from file tmpfile_stdout 09:07:46 INFO - Current package versions: 09:07:46 INFO - argparse == 1.2.1 09:07:46 INFO - blessings == 1.6 09:07:46 INFO - blobuploader == 1.2.4 09:07:46 INFO - docopt == 0.6.1 09:07:46 INFO - functools32 == 3.2.3-2 09:07:46 INFO - jsonschema == 2.5.1 09:07:46 INFO - manifestparser == 1.1 09:07:46 INFO - mozInstall == 1.12 09:07:46 INFO - mozcrash == 1.0 09:07:46 INFO - mozdebug == 0.1 09:07:46 INFO - mozdevice == 0.49 09:07:46 INFO - mozfile == 1.2 09:07:46 INFO - mozhttpd == 0.7 09:07:46 INFO - mozinfo == 0.9 09:07:46 INFO - mozleak == 0.1 09:07:46 INFO - mozlog == 3.4 09:07:46 INFO - moznetwork == 0.27 09:07:46 INFO - mozprocess == 0.23 09:07:46 INFO - mozprofile == 0.28 09:07:46 INFO - mozrunner == 6.13 09:07:46 INFO - mozscreenshot == 0.1 09:07:46 INFO - mozsystemmonitor == 0.3 09:07:46 INFO - moztest == 0.7 09:07:46 INFO - mozversion == 1.4 09:07:46 INFO - psutil == 3.1.1 09:07:46 INFO - requests == 1.2.3 09:07:46 INFO - wsgiref == 0.1.2 09:07:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07: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:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:46 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 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:46 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:07:46 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:07:46 INFO - Using env: {'DISPLAY': ':0', 09:07:46 INFO - 'HOME': '/home/cltbld', 09:07:46 INFO - 'LANG': 'en_US.UTF-8', 09:07:46 INFO - 'LANGUAGE': 'en_US:en', 09:07:46 INFO - 'LOGNAME': 'cltbld', 09:07:46 INFO - 'MAIL': '/var/mail/cltbld', 09:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:46 INFO - 'MOZ_NO_REMOTE': '1', 09:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:46 INFO - 'NO_EM_RESTART': '1', 09:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:46 INFO - 'PWD': '/builds/slave/test', 09:07:46 INFO - 'SHELL': '/bin/bash', 09:07:46 INFO - 'SHLVL': '1', 09:07:46 INFO - 'TERM': 'linux', 09:07:46 INFO - 'TMOUT': '86400', 09:07:46 INFO - 'USER': 'cltbld', 09:07:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:46 INFO - '_': '/tools/buildbot/bin/python'} 09:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:46 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:07:46 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:07:46 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:07:46 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:07:46 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:07:46 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:07:46 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:07:46 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:07:46 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:07:46 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:07:46 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:07:46 INFO - Cleaning up... 09:07:46 INFO - Return code: 0 09:07:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:07: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:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb697b06e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d9120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, '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': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', '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:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:07:46 INFO - Using env: {'DISPLAY': ':0', 09:07:46 INFO - 'HOME': '/home/cltbld', 09:07:46 INFO - 'LANG': 'en_US.UTF-8', 09:07:46 INFO - 'LANGUAGE': 'en_US:en', 09:07:46 INFO - 'LOGNAME': 'cltbld', 09:07:46 INFO - 'MAIL': '/var/mail/cltbld', 09:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:46 INFO - 'MOZ_NO_REMOTE': '1', 09:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:46 INFO - 'NO_EM_RESTART': '1', 09:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:46 INFO - 'PWD': '/builds/slave/test', 09:07:46 INFO - 'SHELL': '/bin/bash', 09:07:46 INFO - 'SHLVL': '1', 09:07:46 INFO - 'TERM': 'linux', 09:07:46 INFO - 'TMOUT': '86400', 09:07:46 INFO - 'USER': 'cltbld', 09:07:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:46 INFO - '_': '/tools/buildbot/bin/python'} 09:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:07:47 INFO - Cleaning up... 09:07:47 INFO - Return code: 0 09:07:47 INFO - Running post-action listener: _resource_record_post_action 09:07:47 INFO - Running post-action listener: _start_resource_monitoring 09:07:47 INFO - Starting resource monitoring. 09:07:47 INFO - [mozharness: 2016-12-12 17:07:47.105763Z] Finished create-virtualenv step (success) 09:07:47 INFO - [mozharness: 2016-12-12 17:07:47.106282Z] Running install step. 09:07:47 INFO - Running pre-action listener: _resource_record_pre_action 09:07:47 INFO - Running main action method: install 09:07:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:47 INFO - Reading from file tmpfile_stdout 09:07:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:07:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:07:47 INFO - Reading from file tmpfile_stdout 09:07:47 INFO - Output received: 09:07:47 INFO - Usage: mozinstall [options] installer 09:07:47 INFO - Options: 09:07:47 INFO - -h, --help show this help message and exit 09:07:47 INFO - -d DEST, --destination=DEST 09:07:47 INFO - Directory to install application into. [default: 09:07:47 INFO - "/builds/slave/test"] 09:07:47 INFO - --app=APP Application being installed. [default: firefox] 09:07:47 INFO - mkdir: /builds/slave/test/build/application 09:07:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:08:03 INFO - Reading from file tmpfile_stdout 09:08:03 INFO - Output received: 09:08:03 INFO - /builds/slave/test/build/application/firefox/firefox 09:08:03 INFO - Running post-action listener: _resource_record_post_action 09:08:03 INFO - [mozharness: 2016-12-12 17:08:03.461948Z] Finished install step (success) 09:08:03 INFO - [mozharness: 2016-12-12 17:08:03.462190Z] Running run-tests step. 09:08:03 INFO - Running pre-action listener: _resource_record_pre_action 09:08:03 INFO - Running main action method: run_tests 09:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:03 INFO - Python 2.7.3 09:08:03 INFO - Return code: 0 09:08:03 INFO - grabbing minidump binary from tooltool 09:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:03 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2369f20>, '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 0x22d6c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:08:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:08:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:08:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:03 INFO - Return code: 0 09:08:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:08:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:08:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --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:08:03 INFO - Using env: {'DISPLAY': ':0', 09:08:03 INFO - 'HOME': '/home/cltbld', 09:08:03 INFO - 'LANG': 'en_US.UTF-8', 09:08:03 INFO - 'LANGUAGE': 'en_US:en', 09:08:03 INFO - 'LOGNAME': 'cltbld', 09:08:03 INFO - 'MAIL': '/var/mail/cltbld', 09:08:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:03 INFO - 'MOZ_NO_REMOTE': '1', 09:08:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:03 INFO - 'NO_EM_RESTART': '1', 09:08:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:03 INFO - 'PWD': '/builds/slave/test', 09:08:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:03 INFO - 'SHELL': '/bin/bash', 09:08:03 INFO - 'SHLVL': '1', 09:08:03 INFO - 'TERM': 'linux', 09:08:03 INFO - 'TMOUT': '86400', 09:08:03 INFO - 'USER': 'cltbld', 09:08:03 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787', 09:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:03 INFO - '_': '/tools/buildbot/bin/python'} 09:08:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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:08:03 INFO - mozversion application_buildid: 20161212081051 09:08:03 INFO - mozversion application_changeset: f46f85dcfbc2b3098ea758825d18be6fab33cbc6 09:08:03 INFO - mozversion application_display_name: Nightly 09:08:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:03 INFO - mozversion application_name: Firefox 09:08:03 INFO - mozversion application_remotingname: firefox 09:08:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:08:03 INFO - mozversion application_vendor: Mozilla 09:08:03 INFO - mozversion application_version: 53.0a1 09:08:03 INFO - mozversion platform_buildid: 20161212081051 09:08:03 INFO - mozversion platform_changeset: f46f85dcfbc2b3098ea758825d18be6fab33cbc6 09:08:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:08:03 INFO - mozversion platform_version: 53.0a1 09:08:03 INFO - using testdate: 1481562483 09:08:03 INFO - actual date: 1481562483 09:08:03 INFO - starting webserver on 'localhost:35441' 09:08:03 INFO - SUITE-START | Running 2 tests 09:08:03 INFO - TEST-START | dromaeo_css 09:08:03 INFO - operating with platform_type : linux_ 09:08:03 INFO - Initialising browser for dromaeo_css test... 09:08:03 INFO - TEST-INFO | started process 30993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIr6_ic/profile http://localhost:35441/getInfo.html) 09:08:08 INFO - PROCESS | 30993 | __metrics Screen width/height:1600/1200 09:08:08 INFO - PROCESS | 30993 | colorDepth:24 09:08:08 INFO - PROCESS | 30993 | Browser inner width/height: 1024/697 09:08:08 INFO - PROCESS | 30993 | __metrics 09:08:09 INFO - PROCESS | 30993 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:08:09 INFO - PROCESS | 30993 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:09 INFO - PROCESS | 30993 | console.error: PushService: 09:08:09 INFO - PROCESS | 30993 | stateChangeProcessEnqueue: Error transitioning state 09:08:09 INFO - PROCESS | 30993 | UnknownError 09:08:10 INFO - TEST-INFO | 30993: exit 0 09:08:10 INFO - Browser initialized. 09:08:10 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:10 INFO - TEST-INFO | started process 31075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIr6_ic/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:22 INFO - PROCESS | 31075 | RSS: Main: 173613056 09:08:22 INFO - PROCESS | 31075 | 09:09:14 INFO - PROCESS | 31075 | 1481562554271 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:11:28 INFO - PROCESS | 31075 | Cycle 1(1): loaded http://localhost:35441/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35441/tests/dromaeo/cssquery-ext.html) 09:11:29 INFO - PROCESS | 31075 | RSS: Main: 288686080 09:11:29 INFO - PROCESS | 31075 | 09:14:32 INFO - PROCESS | 31075 | Cycle 1(1): loaded http://localhost:35441/tests/dromaeo/cssquery-ext.html (next: http://localhost:35441/tests/dromaeo/cssquery-jquery.html) 09:14:32 INFO - PROCESS | 31075 | RSS: Main: 282742784 09:14:32 INFO - PROCESS | 31075 | 09:17:35 INFO - PROCESS | 31075 | Cycle 1(1): loaded http://localhost:35441/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35441/tests/dromaeo/cssquery-mootools.html) 09:17:35 INFO - PROCESS | 31075 | RSS: Main: 221601792 09:17:35 INFO - PROCESS | 31075 | 09:20:38 INFO - PROCESS | 31075 | Cycle 1(1): loaded http://localhost:35441/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35441/tests/dromaeo/cssquery-prototype.html) 09:20:38 INFO - PROCESS | 31075 | RSS: Main: 244002816 09:20:38 INFO - PROCESS | 31075 | 09:23:42 INFO - PROCESS | 31075 | Cycle 1(1): loaded http://localhost:35441/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35441/tests/dromaeo/cssquery-yui.html) 09:23:42 INFO - PROCESS | 31075 | RSS: Main: 228552704 09:23:42 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | Cycle 1(1): loaded http://localhost:35441/tests/dromaeo/cssquery-yui.html (next: http://localhost:35441/tests/dromaeo/cssquery-dojo.html) 09:26:45 INFO - PROCESS | 31075 | RSS: Main: 319688704 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | __start_tp_report 09:26:45 INFO - PROCESS | 31075 | _x_x_mozilla_page_load 09:26:45 INFO - PROCESS | 31075 | _x_x_mozilla_page_load_details 09:26:45 INFO - PROCESS | 31075 | |i|pagename|runs| 09:26:45 INFO - PROCESS | 31075 | |0;dojo.html;2575.781058610018;2604.257786530839;2629.1718108795726;2637.472505498901;2650.562657161567;4468.687191896569;4480.865574032776;4613.815447382105;4634.65240106992;4640.280756482742;4528.932066019004;4543.795529201186;4547.977260113703;4556.931646025304;4564.497905230422;4144.8549300774475;4167.187398457302;4174.666026717855;4205.306124489471;4219.746815191083;4273.465816772903;4285.014446677266;4302.892427689317;4315;4315.546867578906;4229.429027081335;4262.424572682695;4279.679024073192;4281.764502952336;4308.80610372534;4327.956720432787;4332.588404101637;4340.674449416307;4349.456317960255;4350.825966961318;4163.7293575917465;4190.120074784299;4196.58034196581;4247.851325203634;4248.575142485757;4545.909081818379;4586.472555656109;4600.6549508786975;4600.746958917258;4661.976690116561;4949.901001979976;4953.876153096201;4971.403431588233;4977.527134922177;5007.2489126630935;4812.326274321597;4847.75761211938;4849.58778503824;4980.77586508608;5046.167382381903;4587.747673877902;4591.79336929839;4604.746738929391;4634.397528321297;4682.41469816273;5061.569766569809;5066.620003499752;5068.797248110108;5070.61970352225;5117.130087885069;4729.810807567659;4858.854234372974;4864.513548645178;4867.172580661296;4914;4849.321095046696;4893.755312234374;4908.631852611068;4912.38595175603;4916.631252656065;4880.048390563806;4884.194107972145;4886.975565122151;4924.852254432372;4939.728314942641;4854.708717476963;4866.148424025781;4868.172410690191;4869.63477739171;4920.040592084509;3972.801359931992;3979.7015223858325;4011.097503061846;4053.8783836484954;4078.938815917764;3587.4798154268005;3592.191756854739;3596.604373518911;3607.6753092221825;3609.9278014440088;4321.848735294312;4347.847825326162;4375.584319489643;4387.056782791715;4387.232234358974;4964.131277026579;4964.528369804862;5007.674501157413;5008.874778130503;5040.7479626019285;4157.563455837154;4193.7903104845245;4304.289792184254;4321.0709697415205;4349.934750978738;4293.50624678166;4299.849505267363;4335;4337.392765012901;4338.783060846945;4575.0392417592675;4575.359449677115;4577.061702351077;4578.633709303181;4593.058423023273;4807.663463557517;4844.176489996638;4849.733264670477;4854.538818812349;4855.635827313036;4583.358329833759;4586.3808385868815;4586.403767510203;4607.907841843077;4611.930821037754;4875.317455556154;4885.438174609965;4886.462489126122;4887.462379138221;4891.755412229445;4871.610271178368;4891.804327826847;4922.532359425849;4978.32792572998;4986.877952460813;4779.952200477951;4787.593054590398;4789.9042019160115;4790.7365094920115;4826.927596086131;4786.425628924551;4793.592544633745;4793.712377257376;4807.807687692453;4840.975795121143;4673.813047478063;4712.340272361941;4733.786979585858;4781.282807578891;4785.210440277316;4882.487338829514;4888.2178851383605;4891.559759621651;4926.088673595396;4930.6795058321095;4794.232922732346;4798.304245884257;4799.400074990626;4799.784009719501;4846.781894814671 09:26:45 INFO - PROCESS | 31075 | |1;ext.html;509.1446370098233;510.2499325990792;521.9138842091055;547.5756288876121;550.64208264628;26939.730602693966;27528;29831.105066847977;29851.999999999975;29891.850540747266;29852.850735746346;37067.44398834013;38871.22257554854;38892;42350;7288.416926645868;7421.888671669903;7428.220036896102;7429.145648250439;7439.8140046499;7580.128285247212;7673.501222420553;7676.040494938133;7676.9616151919445;7677;7446.441516886228;7603.847923041536;9660.275479339043;9941.055599718014;9947.502624868765;9806.607735690564;9825.361351512129;9829.803403931954;9867.950660246654;9890.851637225447;9617.27870409722;9679.516024198832;9688.612455501772;9688.903110968871;9696.70909872705;36246.275074498364;36485.45271820898;36486.45270320947;36551;36600.63399365999;2713.7828973682253;2800.8599570021415;2924.0496838527565;2926.912192634203;3207.5028370602595;27466.725332746617;27753.722462775317;27851.86074069637;27946.580801287997;28033.71966280352;3270.787398819069;3391.2369716813764;3422.469517224834;3512.1921957949817;3524.682778549917;244294;245365;246994.9999999982;247123;247381.9999999982;44918;45260.773696131306;45289.54710452919;45308.54691453043;45369.09261814811;47213.527864721604;47314.290285645744;47354.289685654745;47359;47371.289430658566;11992.340421276913;12132.636020919386;12158.635240942785;12162.878371216351;12167.452464639113;8428.199321064612;8528.360372972053;8529.658813647384;8533.871991920127;8569.571521423904;13123.934380328037;13844.653883652989;14808.777868331983;14861.182634954965;14903.329350179269;5106.570160355107;6227.688615569204;6240.7503699852405;6438.742450301936;6538.934610653832;735.4116706634671;782.3311069036006;895.9785844137688;910.9772255693529;960.9615615375307;25006.249812505655;26193.214203573923;26637.866810665822;26815.597766033134;26832.19503414901;969.3747532841278;970.1898914406408;1018.4907546226887;1029.0892560084378;1031.3347890610664;13002;14068.155910645393;14128.152310861382;14249.073810202512;14354;385796;389637;389968;390351;391931;41933.370999435036;42730;47157.528424716;47651.52348476471;48438;1089.5696200000955;1098.2312381332938;1137.4085475552831;1139.515705825021;1147.8507793986728;27308.863455682593;27945.58081628818;28344;29056.27359316034;29169.41661166807;40966.79516602517;41961.58038419577;42040.369394458496;42104.78947605242;42144.578554215295;339801;554184;570853;576322;578282;32268.677313227505;32303.676963230067;32324.67675323217;32804.179895502806;32818.34363312673;35204;35473.1131721709;35625;35735.658377093445;35879.64120358763;30736.202430762565;31073.8446307767;31722;32308.35383292368;32565.511517326784;30803.383932321678;30810;30832.691673082983;30839.2290192747;32115.51826722648;30005;30649.846750766104;30697.846510767304;30842.691573083983;30946.84526577353;26874.865625671748;28287;28544.714552854206;28851.422971540032;29097;22194.223202188492;22408.775912241323;22433.663495047913;22472.378776969097;22693.319200423353 09:26:45 INFO - PROCESS | 31075 | |2;jquery.html;327.6854219948849;342.951986721859;348.9110276879396;349.17420300988164;360.65377237851663;12180.81728774069;13117.409716562766;15227;15382.615434614141;16383.344666213367;16472.670546589092;16590.50228493144;16631.833681663178;16791.32834686611;16804;3851.9807400962954;4788.162071637458;5008.7996480140755;5013.949860501403;5015.172496538067;3674.1182116292166;3873.767573945572;4816.783244753994;4875.561199492045;5524.088525393306;5718.142278658193;5802.883942321172;5823.301203855522;5833.679147646878;5842.532597392198;5618.91571626426;5711.371749107595;5717.771289148429;5790.797322093748;5823.767049318022;5029.1450453423;5030.698158110525;5034.647574669774;5035.496450354972;5037.622178336639;18677;19441.611167776566;19533.121009554605;19542.902285488482;19639.509012274666;1932.439592518182;2005.6991451282279;2005.7593088829433;2134.7224860768156;2367.135995361698;8543.401961862672;12038.81941770874;13161.934190328988;13365.264910429922;13619.319034048258;1697.787776527934;1749.5735788455102;1769.7345398190246;1792.050213386907;1810.9637807243912;67260.66369668169;67937;68126;68462.31537684609;69257.30742692559;9388.906110938804;9454.810903782023;9557.23542116629;9734.464604446823;9736.610535578639;12862;12864.614061578168;12872.22766634005;12874.16317939331;12876.420561074774;6262.68686565679;6493.577917435446;6494.707738151795;6598.505112116611;6627.801165965029;6029.668368239789;6077.513798896078;6212.782552610637;6221.129041934133;6222.253329600477;4070.430139780433;4089.3048181809163;4093.16090201508;4135.214309281287;4212.389203565466;1550.6356006338501;1625.9674806503804;1689.2313997131275;1709.837565431282;1722.5779683977505;789.5065584009993;813.0690359538414;861.763015170833;871.6556960000764;872.6989188729877;15753.527394178192;16201.351945922266;16274.349026039063;16645.50063498097;16817.41139060127;823.6993497373535;827.7103013945072;858.7252079334553;884.5577211394303;912.5163663258484;8482.363822713321;9369.250459963187;9427.245820334358;9497.357404761633;9561.187299079656;330624;333101;339820;342096;342857;70186;71613.64193179001;75529;75867.62066189744;75890;790.2887401338841;803.3452736020125;810.4043528006911;810.5987536169633;812.2405550810012;12887.80668289995;14490.492832751224;14500.419983200554;15255.923720381326;15937.362505500114;34736;34867.65132348644;35118.29763404769;35148.71533350324;35543.64456355507;45354.31968520541;48991;49306.01387972292;49776.25335619896;52412;6287.685615719104;6593.571417857824;7002.8949565757575;7154.427645788221;7613.619319034137;8030.87953680707;11298.717532061764;11384.203105782515;11412.600558980395;11459.19785614999;10075;10148.695539133838;10161.288709790244;10461.790764184521;10674.306170098918;9862.309638325249;10102.797944041225;10112.342697724624;10130.949345253226;10383.663675672913;10100.494975251355;10107.241956853119;10334.948325258325;10744.570217191225;10769.62306319275;6696.866062678817;6709.698063587052;6825.078614386995;6863.622500762506;6999.580025198505;9686;9756.658516951873;9990.200783937122;10327.173826094046;10647.094996925038 09:26:45 INFO - PROCESS | 31075 | |3;mootools.html;1303.328177236665;1340.8814073763456;1421.7725163973766;1627.6826018926315;1637.94267200648;5737.971310143444;5890.852728681774;6605.1083103780975;6986.61573613451;6992.5105242633035;6564.2451118121435;6759.45924326054;6779.2542820289855;6941.930580694191;6943.479239057066;2025.3113941260006;2064.1330641130685;2066.235492867641;2067.2557879163487;2068.6586713192305;1743.7035703930362;1804.6480936217442;2018.7678416982014;2079.979200208001;2085.7497100347905;1766.6819972404962;1792.7669402977594;1959.0694420150458;1998.9200431982777;2023.6863286190664;1940.8932508712014;1945.7567804024497;1948.074664534349;1953.6385768632706;1954.0620502158972;1973.2994786850747;1974.6050789842088;2001.6597178479549;2014.1540552967788;2023.4637820977453;6668.166479190101;6718.193816741973;6738.056672065914;6782.253952065269;6864.347886950732;4131.586841315874;4227.957720422787;4237.7669228192435;4242.957570424287;4247.405363249148;2670.092168662664;2799.650043744532;2843.5876797864403;2855.514562524376;2866.1688110447944;819.9139090395482;838.4675730910855;870.5995242188537;873.9650413983433;874.4709450782312;8584.098669639723;8617.913820861837;8633.568321583896;8637.093105223987;8643.827123457495;3525.753197276217;3686.377993682771;3741.1582393961685;3756.2675278320467;3881.146147847469;7687.692492300357;7690.2694244046725;7693.49992250502;7706.036745406825;7734.1492435832015;4207.284761590537;4260.0840819223395;4273.316269396882;4278.72188307759;4278.957210427856;3765.45400916871;3849.6920246380223;3871.128995975883;3874.5931677174053;3880.7865567394065;3223.3714425686767;3233.4664780311455;3299.043277449513;3299.967000330014;3329.167708072982;1056.9524371403306;1058.4707646176912;1059.6132411669685;1062.3360399750156;1067.151614466501;624.2072567838819;704.7075463682514;728.1553398058227;733.4645708632728;746.9365103966223;4516.277395616686;4701.153792317347;4760.523947605212;4912.901741965141;4933.062718083553;398.34472293077727;402.9979850100731;403.96364327210694;407.33197556008173;826.2109685250572;3304.438245498223;3384.4584866421255;3388.8136152511356;3410.437277849127;3480.9477857832153;7883.763487095396;8102.635381407851;8116.228958248957;8149.918500815034;8157.714479993172;8035.558044307502;8060.959695201486;8062.475939063942;8072.273495385443;8075.676972921135;6137.478314343331;6259.906101408483;6354.046892965999;6385.169927909342;6397.4562162216735;4825.276208568743;4828.855134345975;4843.539863712942;4937.876553086202;5009.173486374707;7339.816504587215;7378;7534.321911028033;7548.773536793905;7668.309852113337;7651.426143039405;7704.38364930793;7756.263155000266;7770.145284018867;7823.608819559113;5942.881142377042;5953.642781433128;6001.639901605918;6062.757489700539;6068.21113255274;6367.840803979938;6493.22081350241;6494.772682956074;6495.123158373559;6500.772472963424;5946.316173639913;5960.642361458326;6020.277566692025;6027.668478233739;6044.667543285162;5750.741216645178;5815.912761308499;5819.791286798377;5840.123981402823;5844.649321040752;5505.697186654773;5548.69482178484;5593.105103183471;5597.132444471114;5598.440155984532;4145.507617257725;4150.315197992418;4283.892902677458;4285.97857010713;4286.978565107279;4301.118270754425;4334;4363;4376.256036473871;4411.250087485071 09:26:45 INFO - PROCESS | 31075 | |4;prototype.html;285.7928002198405;322.3536808698559;332.70887973023605;338.4229888040891;341.04507379337855;4459.487158976719;4589.541045895408;4697.154512187805;4724.220503616908;4738.763061846911;4547.477040140385;4652.3021546768005;4660;4674.532546745324;4675.158471475133;3730.608286129958;3734.346489364364;3737.3459644562095;3757.9436308455397;3766.8304926278247;3538.4515400113023;3613.5844377896483;3649.233660931208;3739.5886452490204;3766.9811650941706;3719.2189640175598;3731.365667836474;3732.122951106488;3739.0839244385165;3740.195857890553;3721.572019217784;3727.1800203955086;3732.832022558964;3734.60786617404;3737.5328083989502;3175.7773257295876;3233.417984762742;3243.5783348164587;3343.7993720376853;3371.4774209997245;4496.5278645742055;4499.212637788374;4507.414036175276;4511.278195488706;4521;4252.383404406375;4254;4257.893552661178;4260.211860805761;4262.893427664302;4672.878509157777;4826.541478559531;4850.132698910909;4870.318155458239;4885.853424397274;3621.64188429339;3640.8725694600826;3648.8175591220333;3649.3066317399607;3655.94516082259;4987.875303117415;5064.9746751266375;5082.339295891547;5100.5409513143995;5100.7449627518845;3748.8500459981296;3751.2309976454762;3751.6435938586337;3761.605031471711;3778.301014312361;3648.616895225963;3803.8478460861256;3831.59768224338;3856.305864944281;3882.3011857865854;4021.738586991895;4111.157212771374;4119.155573107566;4260.659147268211;4269.466316710411;4906.435759887659;4912.287718280901;4925.975370123198;4927.876803079952;4929.309896614477;1302.3162839509334;1305.1842598376015;1308.7840506316352;1313.6256166992553;1315.8552559218479;860.0582362313257;908.68650315641;924.4453328003144;935.2798345274101;938.2962777916563;4277.2087163874785;4277.796934578694;4280.850170244026;4298.46269216348;4315.244832154361;4893.4617192108835;4921.867970366836;4930.5069493050405;4935.407751069823;4939.592216218361;4215.852445164404;4221.282381995007;4263.360495925636;4271.572842715766;4302.913941721211;2492.9127480538095;2637.4988752137033;2665.7867370610306;2669.2526092694075;2669.9733002670114;4725.763711814464;4728.692634978715;4728.929066064012;4735.644826638016;4735.763211839394;4889.266610008456;5120.334356533701;5121.231815227671;5126.87182820425;5132.743362831843;4683;4708.9058218835435;4718.820294926337;4719.315699223662;4720.433547974197;4956.925646115384;4963.776630051729;4976.253561965734;4984.601231901512;4988.276699878425;4684;4836.806527738851;4853.708777473363;4854.441739200037;4879.87800304981;4601.309803529497;4645.651576131736;4682.929756053593;4691.741954192552;4699.929501057419;3397.0148656889223;3408.9829550852087;3421.7262618990917;3426.1434641339665;3429.1427143214196;4844.771405153528;4899.755012249445;4907.803687852446;4923.753812309442;4973.726445045412;4808.2787581863;4857.2714092884935;4921.579502245905;4927.778249978831;4932.4327702314695;4905.5094490550655;4909.803607855646;4911.901761964812;4918.532739389752;4930.359053323045;4729.243321068612;4804.279358096173;4853.563179313737;4872.464028956882;4876.366072410564;4892.68197567157;4911.607071434205;4916.303875163063;4928.162212423825;4938.506149385176;4803.279508073677;4823.155947709067;4827.710337379768;4829.975850120727;4847.394075740533 09:26:45 INFO - PROCESS | 31075 | |5;yui.html;610.7618028968701;651.2543138106871;663.2008429842041;682.6689055807934;688.5765254368562;13010.804837927426;13362.265075420848;13717.862821371784;13849.723005539883;13936;12878.098533102686;13268.270245136513;13435.99230057747;13456.058075934689;13461.66345841352;759.5860256160406;771.9228077192292;772.64072206424;773.9883901741479;774.1175060431113;625.659015836368;643.7006791841793;751.113685850696;765.2615226306613;774.4385320642546;806.7579726082181;807.1766797866202;808.2604416958505;808.7775861638038;808.8139727862596;802.3982013489882;802.5505716798612;806.1656185847678;806.4556424413497;806.4596720197482;482.25732372146854;490.0688691486205;582.086124784085;588.7792077970761;597.7907732293706;13680.247586382746;13700.725985480234;13711.588652340446;13712.45150193991;13717.588472345846;12102.394880255952;12210.633680989584;12340.691482712915;12352.197107188003;12512.687182820411;10262.589496420134;10347.275690701654;10390.532426040847;10591.629292974785;10610.734731631694;3997.5003124609425;4002.799860006988;4021.638052575253;4034.144293624936;4055.1484188320487;58322.708386457794;60533;60845;61330.38669613291;62176.68911655413;9200.171984521425;9398.2951278653;9640.566174522164;9728.367656102331;9748.842038013006;18935;18957.052147392576;18964.336248231244;18972.14625341863;19130.426087217405;6353.618782873043;6366.36336366369;6371.617702937839;6375.2987171411105;6530.183727034121;3408.4205685033603;3573.2853429313723;3649.361361761681;3650.105724097613;3719.1074142205675;2726.182145356425;2777.1668499450084;2794.608754774333;2802.061309461353;2804.509210888127;462.3203890281281;465.68100850917233;469.9365585645894;473.4578907151292;476.43066535489754;492.7856382473635;495.8363739965803;521.5059349750362;521.5175962234918;521.9530242278137;7548.886766698504;7728.6522106505345;7752.147263800977;7890.566018868903;8001.279884810278;487.0925426329742;487.6830060460729;495.86363749969047;510.7868811572515;521.2962500624203;3675.9889640935708;3677.5770786359376;3697.3159965406485;3773.8679146230297;3880.2821478026653;64658;66796;66937.3306266931;68942;69297;48474.75762621164;54027.72986135044;59774;59791;61439.385606144264;53576.73211633917;53637;53706.462935370146;54046.7297663517;54393.728031360384;6923.480738944499;7305.196428392979;7346.853062938604;7553.924460755322;7561.886571701539;36478.27043459169;37508.624913751606;38413.423798642485;39188.41217381799;39491.40762888501;45079.77460112678;46280.768596156806;49904.75047624738;50689.493105068475;51334.74332628313;8565.48607083577;8721.91278087211;8786.428882122642;8881.511516866376;9043;9785.706428807147;9873.851892221479;9896.950515247378;9993.500324983575;10358.896411035794;9179.816403672023;9348.158665720119;9491.620335186515;9532.857007145038;9613.567389467631;9025.864612030957;9224.03147669512;9295.535223238676;9355.953220233856;9748.902510975084;9117.635294588143;9174.633014679339;9519.190868776232;9524.14282714559;9584.424934503952;7887.171846955987;7971.720989765562;8041.437099402986;8125.187481251827;8212;7887.369010479033;7907.248811363142;7947.205279472238;8207.507549547046;8435.451695639764 09:26:45 INFO - PROCESS | 31075 | __end_tp_report 09:26:45 INFO - PROCESS | 31075 | __start_cc_report 09:26:45 INFO - PROCESS | 31075 | _x_x_mozilla_cycle_collect,1216 09:26:45 INFO - PROCESS | 31075 | __end_cc_report 09:26:45 INFO - PROCESS | 31075 | __startTimestamp1481563605806__endTimestamp 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | ------- Summary: start ------- 09:26:45 INFO - PROCESS | 31075 | Number of tests: 6 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | [#0] dojo.html Cycles:165 Average:4550.99 Median:4640.28 stddev:477.73 (10.3%) stddev-sans-first:453.37 09:26:45 INFO - PROCESS | 31075 | Values: 2575.8 2604.3 2629.2 2637.5 2650.6 4468.7 4480.9 4613.8 4634.7 4640.3 4528.9 4543.8 4548.0 4556.9 4564.5 4144.9 4167.2 4174.7 4205.3 4219.7 4273.5 4285.0 4302.9 4315.0 4315.5 4229.4 4262.4 4279.7 4281.8 4308.8 4328.0 4332.6 4340.7 4349.5 4350.8 4163.7 4190.1 4196.6 4247.9 4248.6 4545.9 4586.5 4600.7 4600.7 4662.0 4949.9 4953.9 4971.4 4977.5 5007.2 4812.3 4847.8 4849.6 4980.8 5046.2 4587.7 4591.8 4604.7 4634.4 4682.4 5061.6 5066.6 5068.8 5070.6 5117.1 4729.8 4858.9 4864.5 4867.2 4914.0 4849.3 4893.8 4908.6 4912.4 4916.6 4880.0 4884.2 4887.0 4924.9 4939.7 4854.7 4866.1 4868.2 4869.6 4920.0 3972.8 3979.7 4011.1 4053.9 4078.9 3587.5 3592.2 3596.6 3607.7 3609.9 4321.8 4347.8 4375.6 4387.1 4387.2 4964.1 4964.5 5007.7 5008.9 5040.7 4157.6 4193.8 4304.3 4321.1 4349.9 4293.5 4299.8 4335.0 4337.4 4338.8 4575.0 4575.4 4577.1 4578.6 4593.1 4807.7 4844.2 4849.7 4854.5 4855.6 4583.4 4586.4 4586.4 4607.9 4611.9 4875.3 4885.4 4886.5 4887.5 4891.8 4871.6 4891.8 4922.5 4978.3 4986.9 4780.0 4787.6 4789.9 4790.7 4826.9 4786.4 4793.6 4793.7 4807.8 4841.0 4673.8 4712.3 4733.8 4781.3 4785.2 4882.5 4888.2 4891.6 4926.1 4930.7 4794.2 4798.3 4799.4 4799.8 4846.8 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | [#1] ext.html Cycles:180 Average:51261.41 Median:19498.90 stddev:108616.66 (557.0%) stddev-sans-first:108854.52 09:26:45 INFO - PROCESS | 31075 | Values: 509.1 510.2 521.9 547.6 550.6 26939.7 27528.0 29831.1 29852.0 29891.9 29852.9 37067.4 38871.2 38892.0 42350.0 7288.4 7421.9 7428.2 7429.1 7439.8 7580.1 7673.5 7676.0 7677.0 7677.0 7446.4 7603.8 9660.3 9941.1 9947.5 9806.6 9825.4 9829.8 9868.0 9890.9 9617.3 9679.5 9688.6 9688.9 9696.7 36246.3 36485.5 36486.5 36551.0 36600.6 2713.8 2800.9 2924.0 2926.9 3207.5 27466.7 27753.7 27851.9 27946.6 28033.7 3270.8 3391.2 3422.5 3512.2 3524.7 244294.0 245365.0 246995.0 247123.0 247382.0 44918.0 45260.8 45289.5 45308.5 45369.1 47213.5 47314.3 47354.3 47359.0 47371.3 11992.3 12132.6 12158.6 12162.9 12167.5 8428.2 8528.4 8529.7 8533.9 8569.6 13123.9 13844.7 14808.8 14861.2 14903.3 5106.6 6227.7 6240.8 6438.7 6538.9 735.4 782.3 896.0 911.0 961.0 25006.2 26193.2 26637.9 26815.6 26832.2 969.4 970.2 1018.5 1029.1 1031.3 13002.0 14068.2 14128.2 14249.1 14354.0 385796.0 389637.0 389968.0 390351.0 391931.0 41933.4 42730.0 47157.5 47651.5 48438.0 1089.6 1098.2 1137.4 1139.5 1147.9 27308.9 27945.6 28344.0 29056.3 29169.4 40966.8 41961.6 42040.4 42104.8 42144.6 339801.0 554184.0 570853.0 576322.0 578282.0 32268.7 32303.7 32324.7 32804.2 32818.3 35204.0 35473.1 35625.0 35735.7 35879.6 30736.2 31073.8 31722.0 32308.4 32565.5 30803.4 30810.0 30832.7 30839.2 32115.5 30005.0 30649.8 30697.8 30842.7 30946.8 26874.9 28287.0 28544.7 28851.4 29097.0 22194.2 22408.8 22433.7 22472.4 22693.3 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | [#2] jquery.html Cycles:180 Average:22379.05 Median:4173.80 stddev:56060.82 (1343.2%) stddev-sans-first:56193.64 09:26:45 INFO - PROCESS | 31075 | Values: 327.7 343.0 348.9 349.2 360.7 12180.8 13117.4 15227.0 15382.6 16383.3 16472.7 16590.5 16631.8 16791.3 16804.0 3852.0 4788.2 5008.8 5013.9 5015.2 3674.1 3873.8 4816.8 4875.6 5524.1 5718.1 5802.9 5823.3 5833.7 5842.5 5618.9 5711.4 5717.8 5790.8 5823.8 5029.1 5030.7 5034.6 5035.5 5037.6 18677.0 19441.6 19533.1 19542.9 19639.5 1932.4 2005.7 2005.8 2134.7 2367.1 8543.4 12038.8 13161.9 13365.3 13619.3 1697.8 1749.6 1769.7 1792.1 1811.0 67260.7 67937.0 68126.0 68462.3 69257.3 9388.9 9454.8 9557.2 9734.5 9736.6 12862.0 12864.6 12872.2 12874.2 12876.4 6262.7 6493.6 6494.7 6598.5 6627.8 6029.7 6077.5 6212.8 6221.1 6222.3 4070.4 4089.3 4093.2 4135.2 4212.4 1550.6 1626.0 1689.2 1709.8 1722.6 789.5 813.1 861.8 871.7 872.7 15753.5 16201.4 16274.3 16645.5 16817.4 823.7 827.7 858.7 884.6 912.5 8482.4 9369.3 9427.2 9497.4 9561.2 330624.0 333101.0 339820.0 342096.0 342857.0 70186.0 71613.6 75529.0 75867.6 75890.0 790.3 803.3 810.4 810.6 812.2 12887.8 14490.5 14500.4 15255.9 15937.4 34736.0 34867.7 35118.3 35148.7 35543.6 45354.3 48991.0 49306.0 49776.3 52412.0 6287.7 6593.6 7002.9 7154.4 7613.6 8030.9 11298.7 11384.2 11412.6 11459.2 10075.0 10148.7 10161.3 10461.8 10674.3 9862.3 10102.8 10112.3 10130.9 10383.7 10100.5 10107.2 10334.9 10744.6 10769.6 6696.9 6709.7 6825.1 6863.6 6999.6 9686.0 9756.7 9990.2 10327.2 10647.1 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | [#3] mootools.html Cycles:180 Average:4501.78 Median:4922.98 stddev:2394.42 (48.6%) stddev-sans-first:2389.07 09:26:45 INFO - PROCESS | 31075 | Values: 1303.3 1340.9 1421.8 1627.7 1637.9 5738.0 5890.9 6605.1 6986.6 6992.5 6564.2 6759.5 6779.3 6941.9 6943.5 2025.3 2064.1 2066.2 2067.3 2068.7 1743.7 1804.6 2018.8 2080.0 2085.7 1766.7 1792.8 1959.1 1998.9 2023.7 1940.9 1945.8 1948.1 1953.6 1954.1 1973.3 1974.6 2001.7 2014.2 2023.5 6668.2 6718.2 6738.1 6782.3 6864.3 4131.6 4228.0 4237.8 4243.0 4247.4 2670.1 2799.7 2843.6 2855.5 2866.2 819.9 838.5 870.6 874.0 874.5 8584.1 8617.9 8633.6 8637.1 8643.8 3525.8 3686.4 3741.2 3756.3 3881.1 7687.7 7690.3 7693.5 7706.0 7734.1 4207.3 4260.1 4273.3 4278.7 4279.0 3765.5 3849.7 3871.1 3874.6 3880.8 3223.4 3233.5 3299.0 3300.0 3329.2 1057.0 1058.5 1059.6 1062.3 1067.2 624.2 704.7 728.2 733.5 746.9 4516.3 4701.2 4760.5 4912.9 4933.1 398.3 403.0 404.0 407.3 826.2 3304.4 3384.5 3388.8 3410.4 3480.9 7883.8 8102.6 8116.2 8149.9 8157.7 8035.6 8061.0 8062.5 8072.3 8075.7 6137.5 6259.9 6354.0 6385.2 6397.5 4825.3 4828.9 4843.5 4937.9 5009.2 7339.8 7378.0 7534.3 7548.8 7668.3 7651.4 7704.4 7756.3 7770.1 7823.6 5942.9 5953.6 6001.6 6062.8 6068.2 6367.8 6493.2 6494.8 6495.1 6500.8 5946.3 5960.6 6020.3 6027.7 6044.7 5750.7 5815.9 5819.8 5840.1 5844.6 5505.7 5548.7 5593.1 5597.1 5598.4 4145.5 4150.3 4283.9 4286.0 4287.0 4301.1 4334.0 4363.0 4376.3 4411.3 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | [#4] prototype.html Cycles:180 Average:4074.65 Median:4656.15 stddev:1148.57 (24.7%) stddev-sans-first:1116.03 09:26:45 INFO - PROCESS | 31075 | Values: 285.8 322.4 332.7 338.4 341.0 4459.5 4589.5 4697.2 4724.2 4738.8 4547.5 4652.3 4660.0 4674.5 4675.2 3730.6 3734.3 3737.3 3757.9 3766.8 3538.5 3613.6 3649.2 3739.6 3767.0 3719.2 3731.4 3732.1 3739.1 3740.2 3721.6 3727.2 3732.8 3734.6 3737.5 3175.8 3233.4 3243.6 3343.8 3371.5 4496.5 4499.2 4507.4 4511.3 4521.0 4252.4 4254.0 4257.9 4260.2 4262.9 4672.9 4826.5 4850.1 4870.3 4885.9 3621.6 3640.9 3648.8 3649.3 3655.9 4987.9 5065.0 5082.3 5100.5 5100.7 3748.9 3751.2 3751.6 3761.6 3778.3 3648.6 3803.8 3831.6 3856.3 3882.3 4021.7 4111.2 4119.2 4260.7 4269.5 4906.4 4912.3 4926.0 4927.9 4929.3 1302.3 1305.2 1308.8 1313.6 1315.9 860.1 908.7 924.4 935.3 938.3 4277.2 4277.8 4280.9 4298.5 4315.2 4893.5 4921.9 4930.5 4935.4 4939.6 4215.9 4221.3 4263.4 4271.6 4302.9 2492.9 2637.5 2665.8 2669.3 2670.0 4725.8 4728.7 4728.9 4735.6 4735.8 4889.3 5120.3 5121.2 5126.9 5132.7 4683.0 4708.9 4718.8 4719.3 4720.4 4956.9 4963.8 4976.3 4984.6 4988.3 4684.0 4836.8 4853.7 4854.4 4879.9 4601.3 4645.7 4682.9 4691.7 4699.9 3397.0 3409.0 3421.7 3426.1 3429.1 4844.8 4899.8 4907.8 4923.8 4973.7 4808.3 4857.3 4921.6 4927.8 4932.4 4905.5 4909.8 4911.9 4918.5 4930.4 4729.2 4804.3 4853.6 4872.5 4876.4 4892.7 4911.6 4916.3 4928.2 4938.5 4803.3 4823.2 4827.7 4830.0 4847.4 09:26:45 INFO - PROCESS | 31075 | 09:26:45 INFO - PROCESS | 31075 | [#5] yui.html Cycles:180 Average:14493.81 Median:60162.00 stddev:18813.48 (31.3%) stddev-sans-first:18837.38 09:26:45 INFO - PROCESS | 31075 | Values: 610.8 651.3 663.2 682.7 688.6 13010.8 13362.3 13717.9 13849.7 13936.0 12878.1 13268.3 13436.0 13456.1 13461.7 759.6 771.9 772.6 774.0 774.1 625.7 643.7 751.1 765.3 774.4 806.8 807.2 808.3 808.8 808.8 802.4 802.6 806.2 806.5 806.5 482.3 490.1 582.1 588.8 597.8 13680.2 13700.7 13711.6 13712.5 13717.6 12102.4 12210.6 12340.7 12352.2 12512.7 10262.6 10347.3 10390.5 10591.6 10610.7 3997.5 4002.8 4021.6 4034.1 4055.1 58322.7 60533.0 60845.0 61330.4 62176.7 9200.2 9398.3 9640.6 9728.4 9748.8 18935.0 18957.1 18964.3 18972.1 19130.4 6353.6 6366.4 6371.6 6375.3 6530.2 3408.4 3573.3 3649.4 3650.1 3719.1 2726.2 2777.2 2794.6 2802.1 2804.5 462.3 465.7 469.9 473.5 476.4 492.8 495.8 521.5 521.5 522.0 7548.9 7728.7 7752.1 7890.6 8001.3 487.1 487.7 495.9 510.8 521.3 3676.0 3677.6 3697.3 3773.9 3880.3 64658.0 66796.0 66937.3 68942.0 69297.0 48474.8 54027.7 59774.0 59791.0 61439.4 53576.7 53637.0 53706.5 54046.7 54393.7 6923.5 7305.2 7346.9 7553.9 7561.9 36478.3 37508.6 38413.4 39188.4 39491.4 45079.8 46280.8 49904.8 50689.5 51334.7 8565.5 8721.9 8786.4 8881.5 9043.0 9785.7 9873.9 9897.0 9993.5 10358.9 9179.8 9348.2 9491.6 9532.9 9613.6 9025.9 9224.0 9295.5 9356.0 9748.9 9117.6 9174.6 9519.2 9524.1 9584.4 7887.2 7971.7 8041.4 8125.2 8212.0 7887.4 7907.2 7947.2 8207.5 8435.5 09:26:45 INFO - PROCESS | 31075 | -------- Summary: end -------- 09:26:45 INFO - PROCESS | 31075 | 09:26:46 INFO - TEST-INFO | 31075: exit 0 09:26:46 INFO - TEST-OK | dromaeo_css | took 1123085ms 09:26:46 INFO - TEST-START | kraken 09:26:46 INFO - operating with platform_type : linux_ 09:26:46 INFO - Initialising browser for kraken test... 09:26:46 INFO - TEST-INFO | started process 31383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKxKYAh/profile http://localhost:35441/getInfo.html) 09:26:51 INFO - PROCESS | 31383 | __metrics Screen width/height:1600/1200 09:26:51 INFO - PROCESS | 31383 | colorDepth:24 09:26:51 INFO - PROCESS | 31383 | Browser inner width/height: 1024/697 09:26:51 INFO - PROCESS | 31383 | __metrics 09:26:51 INFO - PROCESS | 31383 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:26:51 INFO - PROCESS | 31383 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:52 INFO - PROCESS | 31383 | console.error: PushService: 09:26:52 INFO - PROCESS | 31383 | stateChangeProcessEnqueue: Error transitioning state 09:26:52 INFO - PROCESS | 31383 | UnknownError 09:26:53 INFO - TEST-INFO | 31383: exit 0 09:26:53 INFO - Browser initialized. 09:26:53 INFO - Running cycle 1/1 for kraken test... 09:26:53 INFO - TEST-INFO | started process 31464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKxKYAh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:05 INFO - PROCESS | 31464 | RSS: Main: 173359104 09:27:05 INFO - PROCESS | 31464 | 09:27:08 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35441/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:09 INFO - PROCESS | 31464 | RSS: Main: 239783936 09:27:09 INFO - PROCESS | 31464 | 09:27:12 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35441/tests/kraken/driver.html?testName=audio-dft) 09:27:13 INFO - PROCESS | 31464 | RSS: Main: 217772032 09:27:13 INFO - PROCESS | 31464 | 09:27:16 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35441/tests/kraken/driver.html?testName=audio-fft) 09:27:16 INFO - PROCESS | 31464 | RSS: Main: 300150784 09:27:16 INFO - PROCESS | 31464 | 09:27:18 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35441/tests/kraken/driver.html?testName=audio-oscillator) 09:27:19 INFO - PROCESS | 31464 | RSS: Main: 211611648 09:27:19 INFO - PROCESS | 31464 | 09:27:21 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35441/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:22 INFO - PROCESS | 31464 | RSS: Main: 214142976 09:27:22 INFO - PROCESS | 31464 | 09:27:25 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35441/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:26 INFO - PROCESS | 31464 | RSS: Main: 253652992 09:27:26 INFO - PROCESS | 31464 | 09:27:29 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35441/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:30 INFO - PROCESS | 31464 | RSS: Main: 253603840 09:27:30 INFO - PROCESS | 31464 | 09:27:33 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35441/tests/kraken/driver.html?testName=json-parse-financial) 09:27:33 INFO - PROCESS | 31464 | RSS: Main: 252563456 09:27:33 INFO - PROCESS | 31464 | 09:27:35 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35441/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:36 INFO - PROCESS | 31464 | RSS: Main: 214581248 09:27:36 INFO - PROCESS | 31464 | 09:27:38 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:38 INFO - PROCESS | 31464 | RSS: Main: 222212096 09:27:38 INFO - PROCESS | 31464 | 09:27:40 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:41 INFO - PROCESS | 31464 | RSS: Main: 230133760 09:27:41 INFO - PROCESS | 31464 | 09:27:44 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:45 INFO - PROCESS | 31464 | RSS: Main: 239607808 09:27:45 INFO - PROCESS | 31464 | 09:27:48 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:48 INFO - PROCESS | 31464 | RSS: Main: 236249088 09:27:48 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | Cycle 1(1): loaded http://localhost:35441/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35441/tests/kraken/driver.html?testName=ai-astar) 09:27:50 INFO - PROCESS | 31464 | RSS: Main: 288661504 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | __start_tp_report 09:27:50 INFO - PROCESS | 31464 | _x_x_mozilla_page_load 09:27:50 INFO - PROCESS | 31464 | _x_x_mozilla_page_load_details 09:27:50 INFO - PROCESS | 31464 | |i|pagename|runs| 09:27:50 INFO - PROCESS | 31464 | |0;ai-astar;109;116;116;116;119;117;116;119;117;117 09:27:50 INFO - PROCESS | 31464 | |1;audio-beat-detection;164;131;129;177;131;128;127;132;150;130 09:27:50 INFO - PROCESS | 31464 | |2;audio-dft;161;161;161;161;162;163;161;162;162;171 09:27:50 INFO - PROCESS | 31464 | |3;audio-fft;77;77;76;121;77;76;77;77;109;76 09:27:50 INFO - PROCESS | 31464 | |4;audio-oscillator;84;85;105;84;84;84;99;85;85;106 09:27:50 INFO - PROCESS | 31464 | |5;imaging-gaussian-blur;128;128;128;128;127;127;127;127;126;127 09:27:50 INFO - PROCESS | 31464 | |6;imaging-darkroom;148;147;146;147;147;147;147;148;148;146 09:27:50 INFO - PROCESS | 31464 | |7;imaging-desaturate;96;96;95;95;95;95;96;95;96;96 09:27:50 INFO - PROCESS | 31464 | |8;json-parse-financial;64;64;64;63;65;66;65;65;64;64 09:27:50 INFO - PROCESS | 31464 | |9;json-stringify-tinderbox;66;67;66;67;69;66;66;67;66;66 09:27:50 INFO - PROCESS | 31464 | |10;stanford-crypto-aes;85;85;87;84;85;86;85;88;84;84 09:27:50 INFO - PROCESS | 31464 | |11;stanford-crypto-ccm;141;144;152;144;138;141;166;153;193;141 09:27:50 INFO - PROCESS | 31464 | |12;stanford-crypto-pbkdf2;181;188;179;181;186;177;185;180;179;182 09:27:50 INFO - PROCESS | 31464 | |13;stanford-crypto-sha256-iterative;68;69;72;69;69;73;71;68;72;67 09:27:50 INFO - PROCESS | 31464 | __end_tp_report 09:27:50 INFO - PROCESS | 31464 | __start_cc_report 09:27:50 INFO - PROCESS | 31464 | _x_x_mozilla_cycle_collect,1944 09:27:50 INFO - PROCESS | 31464 | __end_cc_report 09:27:50 INFO - PROCESS | 31464 | __startTimestamp1481563670907__endTimestamp 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | ------- Summary: start ------- 09:27:50 INFO - PROCESS | 31464 | Number of tests: 14 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#0] ai-astar Cycles:10 Average:116.20 Median:117.00 stddev:2.78 (2.4%) stddev-sans-first:1.22 09:27:50 INFO - PROCESS | 31464 | Values: 109.0 116.0 116.0 116.0 119.0 117.0 116.0 119.0 117.0 117.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:131.50 stddev:17.65 (13.4%) stddev-sans-first:16.43 09:27:50 INFO - PROCESS | 31464 | Values: 164.0 131.0 129.0 177.0 131.0 128.0 127.0 132.0 150.0 130.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.00 stddev:3.06 (1.9%) stddev-sans-first:3.20 09:27:50 INFO - PROCESS | 31464 | Values: 161.0 161.0 161.0 161.0 162.0 163.0 161.0 162.0 162.0 171.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#3] audio-fft Cycles:10 Average:84.30 Median:77.00 stddev:16.43 (21.3%) stddev-sans-first:17.22 09:27:50 INFO - PROCESS | 31464 | Values: 77.0 77.0 76.0 121.0 77.0 76.0 77.0 77.0 109.0 76.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:84.50 stddev:9.31 (11.0%) stddev-sans-first:9.61 09:27:50 INFO - PROCESS | 31464 | Values: 84.0 85.0 105.0 84.0 84.0 84.0 99.0 85.0 85.0 106.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#5] imaging-gaussian-blur Cycles:10 Average:127.30 Median:127.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:27:50 INFO - PROCESS | 31464 | Values: 128.0 128.0 128.0 128.0 127.0 127.0 127.0 127.0 126.0 127.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 09:27:50 INFO - PROCESS | 31464 | Values: 148.0 147.0 146.0 147.0 147.0 147.0 147.0 148.0 148.0 146.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:27:50 INFO - PROCESS | 31464 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 09:27:50 INFO - PROCESS | 31464 | Values: 64.0 64.0 64.0 63.0 65.0 66.0 65.0 65.0 64.0 64.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:66.50 stddev:0.97 (1.5%) stddev-sans-first:1.00 09:27:50 INFO - PROCESS | 31464 | Values: 66.0 67.0 66.0 67.0 69.0 66.0 66.0 67.0 66.0 66.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.41 09:27:50 INFO - PROCESS | 31464 | Values: 85.0 85.0 87.0 84.0 85.0 86.0 85.0 88.0 84.0 84.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:148.00 stddev:16.87 (11.4%) stddev-sans-first:17.47 09:27:50 INFO - PROCESS | 31464 | Values: 141.0 144.0 152.0 144.0 138.0 141.0 166.0 153.0 193.0 141.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.80 Median:181.50 stddev:3.49 (1.9%) stddev-sans-first:3.69 09:27:50 INFO - PROCESS | 31464 | Values: 181.0 188.0 179.0 181.0 186.0 177.0 185.0 180.0 179.0 182.0 09:27:50 INFO - PROCESS | 31464 | 09:27:50 INFO - PROCESS | 31464 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:2.04 (2.9%) stddev-sans-first:2.06 09:27:50 INFO - PROCESS | 31464 | Values: 68.0 69.0 72.0 69.0 69.0 73.0 71.0 68.0 72.0 67.0 09:27:50 INFO - PROCESS | 31464 | -------- Summary: end -------- 09:27:50 INFO - PROCESS | 31464 | 09:27:52 INFO - TEST-INFO | 31464: exit 0 09:27:52 INFO - TEST-OK | kraken | took 65396ms 09:27:52 INFO - SUITE-END | took 1188s 09:27:52 INFO - Completed test suite (00:19:48) 09:27:52 INFO - javascript benchmark 09:27:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2575.781058610018, 2604.257786530839, 2629.1718108795726, 2637.472505498901, 2650.562657161567, 4468.687191896569, 4480.865574032776, 4613.815447382105, 4634.65240106992, 4640.280756482742, 4528.932066019004, 4543.795529201186, 4547.977260113703, 4556.931646025304, 4564.497905230422, 4144.8549300774475, 4167.187398457302, 4174.666026717855, 4205.306124489471, 4219.746815191083, 4273.465816772903, 4285.014446677266, 4302.892427689317, 4315.0, 4315.546867578906, 4229.429027081335, 4262.424572682695, 4279.679024073192, 4281.764502952336, 4308.80610372534, 4327.956720432787, 4332.588404101637, 4340.674449416307, 4349.456317960255, 4350.825966961318, 4163.7293575917465, 4190.120074784299, 4196.58034196581, 4247.851325203634, 4248.575142485757, 4545.909081818379, 4586.472555656109, 4600.6549508786975, 4600.746958917258, 4661.976690116561, 4949.901001979976, 4953.876153096201, 4971.403431588233, 4977.527134922177, 5007.2489126630935, 4812.326274321597, 4847.75761211938, 4849.58778503824, 4980.77586508608, 5046.167382381903, 4587.747673877902, 4591.79336929839, 4604.746738929391, 4634.397528321297, 4682.41469816273, 5061.569766569809, 5066.620003499752, 5068.797248110108, 5070.61970352225, 5117.130087885069, 4729.810807567659, 4858.854234372974, 4864.513548645178, 4867.172580661296, 4914.0, 4849.321095046696, 4893.755312234374, 4908.631852611068, 4912.38595175603, 4916.631252656065, 4880.048390563806, 4884.194107972145, 4886.975565122151, 4924.852254432372, 4939.728314942641, 4854.708717476963, 4866.148424025781, 4868.172410690191, 4869.63477739171, 4920.040592084509, 3972.801359931992, 3979.7015223858325, 4011.097503061846, 4053.8783836484954, 4078.938815917764, 3587.4798154268005, 3592.191756854739, 3596.604373518911, 3607.6753092221825, 3609.9278014440088, 4321.848735294312, 4347.847825326162, 4375.584319489643, 4387.056782791715, 4387.232234358974, 4964.131277026579, 4964.528369804862, 5007.674501157413, 5008.874778130503, 5040.7479626019285, 4157.563455837154, 4193.7903104845245, 4304.289792184254, 4321.0709697415205, 4349.934750978738, 4293.50624678166, 4299.849505267363, 4335.0, 4337.392765012901, 4338.783060846945, 4575.0392417592675, 4575.359449677115, 4577.061702351077, 4578.633709303181, 4593.058423023273, 4807.663463557517, 4844.176489996638, 4849.733264670477, 4854.538818812349, 4855.635827313036, 4583.358329833759, 4586.3808385868815, 4586.403767510203, 4607.907841843077, 4611.930821037754, 4875.317455556154, 4885.438174609965, 4886.462489126122, 4887.462379138221, 4891.755412229445, 4871.610271178368, 4891.804327826847, 4922.532359425849, 4978.32792572998, 4986.877952460813, 4779.952200477951, 4787.593054590398, 4789.9042019160115, 4790.7365094920115, 4826.927596086131, 4786.425628924551, 4793.592544633745, 4793.712377257376, 4807.807687692453, 4840.975795121143, 4673.813047478063, 4712.340272361941, 4733.786979585858, 4781.282807578891, 4785.210440277316, 4882.487338829514, 4888.2178851383605, 4891.559759621651, 4926.088673595396, 4930.6795058321095, 4794.232922732346, 4798.304245884257, 4799.400074990626, 4799.784009719501, 4846.781894814671], "value": 4520.741262584482, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.1446370098233, 510.2499325990792, 521.9138842091055, 547.5756288876121, 550.64208264628, 26939.730602693966, 27528.0, 29831.105066847977, 29851.999999999975, 29891.850540747266, 29852.850735746346, 37067.44398834013, 38871.22257554854, 38892.0, 42350.0, 7288.416926645868, 7421.888671669903, 7428.220036896102, 7429.145648250439, 7439.8140046499, 7580.128285247212, 7673.501222420553, 7676.040494938133, 7676.9616151919445, 7677.0, 7446.441516886228, 7603.847923041536, 9660.275479339043, 9941.055599718014, 9947.502624868765, 9806.607735690564, 9825.361351512129, 9829.803403931954, 9867.950660246654, 9890.851637225447, 9617.27870409722, 9679.516024198832, 9688.612455501772, 9688.903110968871, 9696.70909872705, 36246.275074498364, 36485.45271820898, 36486.45270320947, 36551.0, 36600.63399365999, 2713.7828973682253, 2800.8599570021415, 2924.0496838527565, 2926.912192634203, 3207.5028370602595, 27466.725332746617, 27753.722462775317, 27851.86074069637, 27946.580801287997, 28033.71966280352, 3270.787398819069, 3391.2369716813764, 3422.469517224834, 3512.1921957949817, 3524.682778549917, 244294.0, 245365.0, 246994.9999999982, 247123.0, 247381.9999999982, 44918.0, 45260.773696131306, 45289.54710452919, 45308.54691453043, 45369.09261814811, 47213.527864721604, 47314.290285645744, 47354.289685654745, 47359.0, 47371.289430658566, 11992.340421276913, 12132.636020919386, 12158.635240942785, 12162.878371216351, 12167.452464639113, 8428.199321064612, 8528.360372972053, 8529.658813647384, 8533.871991920127, 8569.571521423904, 13123.934380328037, 13844.653883652989, 14808.777868331983, 14861.182634954965, 14903.329350179269, 5106.570160355107, 6227.688615569204, 6240.7503699852405, 6438.742450301936, 6538.934610653832, 735.4116706634671, 782.3311069036006, 895.9785844137688, 910.9772255693529, 960.9615615375307, 25006.249812505655, 26193.214203573923, 26637.866810665822, 26815.597766033134, 26832.19503414901, 969.3747532841278, 970.1898914406408, 1018.4907546226887, 1029.0892560084378, 1031.3347890610664, 13002.0, 14068.155910645393, 14128.152310861382, 14249.073810202512, 14354.0, 385796.0, 389637.0, 389968.0, 390351.0, 391931.0, 41933.370999435036, 42730.0, 47157.528424716, 47651.52348476471, 48438.0, 1089.5696200000955, 1098.2312381332938, 1137.4085475552831, 1139.515705825021, 1147.8507793986728, 27308.863455682593, 27945.58081628818, 28344.0, 29056.27359316034, 29169.41661166807, 40966.79516602517, 41961.58038419577, 42040.369394458496, 42104.78947605242, 42144.578554215295, 339801.0, 554184.0, 570853.0, 576322.0, 578282.0, 32268.677313227505, 32303.676963230067, 32324.67675323217, 32804.179895502806, 32818.34363312673, 35204.0, 35473.1131721709, 35625.0, 35735.658377093445, 35879.64120358763, 30736.202430762565, 31073.8446307767, 31722.0, 32308.35383292368, 32565.511517326784, 30803.383932321678, 30810.0, 30832.691673082983, 30839.2290192747, 32115.51826722648, 30005.0, 30649.846750766104, 30697.846510767304, 30842.691573083983, 30946.84526577353, 26874.865625671748, 28287.0, 28544.714552854206, 28851.422971540032, 29097.0, 22194.223202188492, 22408.775912241323, 22433.663495047913, 22472.378776969097, 22693.319200423353], "value": 16746.248153938417, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.6854219948849, 342.951986721859, 348.9110276879396, 349.17420300988164, 360.65377237851663, 12180.81728774069, 13117.409716562766, 15227.0, 15382.615434614141, 16383.344666213367, 16472.670546589092, 16590.50228493144, 16631.833681663178, 16791.32834686611, 16804.0, 3851.9807400962954, 4788.162071637458, 5008.7996480140755, 5013.949860501403, 5015.172496538067, 3674.1182116292166, 3873.767573945572, 4816.783244753994, 4875.561199492045, 5524.088525393306, 5718.142278658193, 5802.883942321172, 5823.301203855522, 5833.679147646878, 5842.532597392198, 5618.91571626426, 5711.371749107595, 5717.771289148429, 5790.797322093748, 5823.767049318022, 5029.1450453423, 5030.698158110525, 5034.647574669774, 5035.496450354972, 5037.622178336639, 18677.0, 19441.611167776566, 19533.121009554605, 19542.902285488482, 19639.509012274666, 1932.439592518182, 2005.6991451282279, 2005.7593088829433, 2134.7224860768156, 2367.135995361698, 8543.401961862672, 12038.81941770874, 13161.934190328988, 13365.264910429922, 13619.319034048258, 1697.787776527934, 1749.5735788455102, 1769.7345398190246, 1792.050213386907, 1810.9637807243912, 67260.66369668169, 67937.0, 68126.0, 68462.31537684609, 69257.30742692559, 9388.906110938804, 9454.810903782023, 9557.23542116629, 9734.464604446823, 9736.610535578639, 12862.0, 12864.614061578168, 12872.22766634005, 12874.16317939331, 12876.420561074774, 6262.68686565679, 6493.577917435446, 6494.707738151795, 6598.505112116611, 6627.801165965029, 6029.668368239789, 6077.513798896078, 6212.782552610637, 6221.129041934133, 6222.253329600477, 4070.430139780433, 4089.3048181809163, 4093.16090201508, 4135.214309281287, 4212.389203565466, 1550.6356006338501, 1625.9674806503804, 1689.2313997131275, 1709.837565431282, 1722.5779683977505, 789.5065584009993, 813.0690359538414, 861.763015170833, 871.6556960000764, 872.6989188729877, 15753.527394178192, 16201.351945922266, 16274.349026039063, 16645.50063498097, 16817.41139060127, 823.6993497373535, 827.7103013945072, 858.7252079334553, 884.5577211394303, 912.5163663258484, 8482.363822713321, 9369.250459963187, 9427.245820334358, 9497.357404761633, 9561.187299079656, 330624.0, 333101.0, 339820.0, 342096.0, 342857.0, 70186.0, 71613.64193179001, 75529.0, 75867.62066189744, 75890.0, 790.2887401338841, 803.3452736020125, 810.4043528006911, 810.5987536169633, 812.2405550810012, 12887.80668289995, 14490.492832751224, 14500.419983200554, 15255.923720381326, 15937.362505500114, 34736.0, 34867.65132348644, 35118.29763404769, 35148.71533350324, 35543.64456355507, 45354.31968520541, 48991.0, 49306.01387972292, 49776.25335619896, 52412.0, 6287.685615719104, 6593.571417857824, 7002.8949565757575, 7154.427645788221, 7613.619319034137, 8030.87953680707, 11298.717532061764, 11384.203105782515, 11412.600558980395, 11459.19785614999, 10075.0, 10148.695539133838, 10161.288709790244, 10461.790764184521, 10674.306170098918, 9862.309638325249, 10102.797944041225, 10112.342697724624, 10130.949345253226, 10383.663675672913, 10100.494975251355, 10107.241956853119, 10334.948325258325, 10744.570217191225, 10769.62306319275, 6696.866062678817, 6709.698063587052, 6825.078614386995, 6863.622500762506, 6999.580025198505, 9686.0, 9756.658516951873, 9990.200783937122, 10327.173826094046, 10647.094996925038], "value": 7876.887188993884, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.328177236665, 1340.8814073763456, 1421.7725163973766, 1627.6826018926315, 1637.94267200648, 5737.971310143444, 5890.852728681774, 6605.1083103780975, 6986.61573613451, 6992.5105242633035, 6564.2451118121435, 6759.45924326054, 6779.2542820289855, 6941.930580694191, 6943.479239057066, 2025.3113941260006, 2064.1330641130685, 2066.235492867641, 2067.2557879163487, 2068.6586713192305, 1743.7035703930362, 1804.6480936217442, 2018.7678416982014, 2079.979200208001, 2085.7497100347905, 1766.6819972404962, 1792.7669402977594, 1959.0694420150458, 1998.9200431982777, 2023.6863286190664, 1940.8932508712014, 1945.7567804024497, 1948.074664534349, 1953.6385768632706, 1954.0620502158972, 1973.2994786850747, 1974.6050789842088, 2001.6597178479549, 2014.1540552967788, 2023.4637820977453, 6668.166479190101, 6718.193816741973, 6738.056672065914, 6782.253952065269, 6864.347886950732, 4131.586841315874, 4227.957720422787, 4237.7669228192435, 4242.957570424287, 4247.405363249148, 2670.092168662664, 2799.650043744532, 2843.5876797864403, 2855.514562524376, 2866.1688110447944, 819.9139090395482, 838.4675730910855, 870.5995242188537, 873.9650413983433, 874.4709450782312, 8584.098669639723, 8617.913820861837, 8633.568321583896, 8637.093105223987, 8643.827123457495, 3525.753197276217, 3686.377993682771, 3741.1582393961685, 3756.2675278320467, 3881.146147847469, 7687.692492300357, 7690.2694244046725, 7693.49992250502, 7706.036745406825, 7734.1492435832015, 4207.284761590537, 4260.0840819223395, 4273.316269396882, 4278.72188307759, 4278.957210427856, 3765.45400916871, 3849.6920246380223, 3871.128995975883, 3874.5931677174053, 3880.7865567394065, 3223.3714425686767, 3233.4664780311455, 3299.043277449513, 3299.967000330014, 3329.167708072982, 1056.9524371403306, 1058.4707646176912, 1059.6132411669685, 1062.3360399750156, 1067.151614466501, 624.2072567838819, 704.7075463682514, 728.1553398058227, 733.4645708632728, 746.9365103966223, 4516.277395616686, 4701.153792317347, 4760.523947605212, 4912.901741965141, 4933.062718083553, 398.34472293077727, 402.9979850100731, 403.96364327210694, 407.33197556008173, 826.2109685250572, 3304.438245498223, 3384.4584866421255, 3388.8136152511356, 3410.437277849127, 3480.9477857832153, 7883.763487095396, 8102.635381407851, 8116.228958248957, 8149.918500815034, 8157.714479993172, 8035.558044307502, 8060.959695201486, 8062.475939063942, 8072.273495385443, 8075.676972921135, 6137.478314343331, 6259.906101408483, 6354.046892965999, 6385.169927909342, 6397.4562162216735, 4825.276208568743, 4828.855134345975, 4843.539863712942, 4937.876553086202, 5009.173486374707, 7339.816504587215, 7378.0, 7534.321911028033, 7548.773536793905, 7668.309852113337, 7651.426143039405, 7704.38364930793, 7756.263155000266, 7770.145284018867, 7823.608819559113, 5942.881142377042, 5953.642781433128, 6001.639901605918, 6062.757489700539, 6068.21113255274, 6367.840803979938, 6493.22081350241, 6494.772682956074, 6495.123158373559, 6500.772472963424, 5946.316173639913, 5960.642361458326, 6020.277566692025, 6027.668478233739, 6044.667543285162, 5750.741216645178, 5815.912761308499, 5819.791286798377, 5840.123981402823, 5844.649321040752, 5505.697186654773, 5548.69482178484, 5593.105103183471, 5597.132444471114, 5598.440155984532, 4145.507617257725, 4150.315197992418, 4283.892902677458, 4285.97857010713, 4286.978565107279, 4301.118270754425, 4334.0, 4363.0, 4376.256036473871, 4411.250087485071], "value": 3645.4592067857607, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.7928002198405, 322.3536808698559, 332.70887973023605, 338.4229888040891, 341.04507379337855, 4459.487158976719, 4589.541045895408, 4697.154512187805, 4724.220503616908, 4738.763061846911, 4547.477040140385, 4652.3021546768005, 4660.0, 4674.532546745324, 4675.158471475133, 3730.608286129958, 3734.346489364364, 3737.3459644562095, 3757.9436308455397, 3766.8304926278247, 3538.4515400113023, 3613.5844377896483, 3649.233660931208, 3739.5886452490204, 3766.9811650941706, 3719.2189640175598, 3731.365667836474, 3732.122951106488, 3739.0839244385165, 3740.195857890553, 3721.572019217784, 3727.1800203955086, 3732.832022558964, 3734.60786617404, 3737.5328083989502, 3175.7773257295876, 3233.417984762742, 3243.5783348164587, 3343.7993720376853, 3371.4774209997245, 4496.5278645742055, 4499.212637788374, 4507.414036175276, 4511.278195488706, 4521.0, 4252.383404406375, 4254.0, 4257.893552661178, 4260.211860805761, 4262.893427664302, 4672.878509157777, 4826.541478559531, 4850.132698910909, 4870.318155458239, 4885.853424397274, 3621.64188429339, 3640.8725694600826, 3648.8175591220333, 3649.3066317399607, 3655.94516082259, 4987.875303117415, 5064.9746751266375, 5082.339295891547, 5100.5409513143995, 5100.7449627518845, 3748.8500459981296, 3751.2309976454762, 3751.6435938586337, 3761.605031471711, 3778.301014312361, 3648.616895225963, 3803.8478460861256, 3831.59768224338, 3856.305864944281, 3882.3011857865854, 4021.738586991895, 4111.157212771374, 4119.155573107566, 4260.659147268211, 4269.466316710411, 4906.435759887659, 4912.287718280901, 4925.975370123198, 4927.876803079952, 4929.309896614477, 1302.3162839509334, 1305.1842598376015, 1308.7840506316352, 1313.6256166992553, 1315.8552559218479, 860.0582362313257, 908.68650315641, 924.4453328003144, 935.2798345274101, 938.2962777916563, 4277.2087163874785, 4277.796934578694, 4280.850170244026, 4298.46269216348, 4315.244832154361, 4893.4617192108835, 4921.867970366836, 4930.5069493050405, 4935.407751069823, 4939.592216218361, 4215.852445164404, 4221.282381995007, 4263.360495925636, 4271.572842715766, 4302.913941721211, 2492.9127480538095, 2637.4988752137033, 2665.7867370610306, 2669.2526092694075, 2669.9733002670114, 4725.763711814464, 4728.692634978715, 4728.929066064012, 4735.644826638016, 4735.763211839394, 4889.266610008456, 5120.334356533701, 5121.231815227671, 5126.87182820425, 5132.743362831843, 4683.0, 4708.9058218835435, 4718.820294926337, 4719.315699223662, 4720.433547974197, 4956.925646115384, 4963.776630051729, 4976.253561965734, 4984.601231901512, 4988.276699878425, 4684.0, 4836.806527738851, 4853.708777473363, 4854.441739200037, 4879.87800304981, 4601.309803529497, 4645.651576131736, 4682.929756053593, 4691.741954192552, 4699.929501057419, 3397.0148656889223, 3408.9829550852087, 3421.7262618990917, 3426.1434641339665, 3429.1427143214196, 4844.771405153528, 4899.755012249445, 4907.803687852446, 4923.753812309442, 4973.726445045412, 4808.2787581863, 4857.2714092884935, 4921.579502245905, 4927.778249978831, 4932.4327702314695, 4905.5094490550655, 4909.803607855646, 4911.901761964812, 4918.532739389752, 4930.359053323045, 4729.243321068612, 4804.279358096173, 4853.563179313737, 4872.464028956882, 4876.366072410564, 4892.68197567157, 4911.607071434205, 4916.303875163063, 4928.162212423825, 4938.506149385176, 4803.279508073677, 4823.155947709067, 4827.710337379768, 4829.975850120727, 4847.394075740533], "value": 3724.26815740299, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.7618028968701, 651.2543138106871, 663.2008429842041, 682.6689055807934, 688.5765254368562, 13010.804837927426, 13362.265075420848, 13717.862821371784, 13849.723005539883, 13936.0, 12878.098533102686, 13268.270245136513, 13435.99230057747, 13456.058075934689, 13461.66345841352, 759.5860256160406, 771.9228077192292, 772.64072206424, 773.9883901741479, 774.1175060431113, 625.659015836368, 643.7006791841793, 751.113685850696, 765.2615226306613, 774.4385320642546, 806.7579726082181, 807.1766797866202, 808.2604416958505, 808.7775861638038, 808.8139727862596, 802.3982013489882, 802.5505716798612, 806.1656185847678, 806.4556424413497, 806.4596720197482, 482.25732372146854, 490.0688691486205, 582.086124784085, 588.7792077970761, 597.7907732293706, 13680.247586382746, 13700.725985480234, 13711.588652340446, 13712.45150193991, 13717.588472345846, 12102.394880255952, 12210.633680989584, 12340.691482712915, 12352.197107188003, 12512.687182820411, 10262.589496420134, 10347.275690701654, 10390.532426040847, 10591.629292974785, 10610.734731631694, 3997.5003124609425, 4002.799860006988, 4021.638052575253, 4034.144293624936, 4055.1484188320487, 58322.708386457794, 60533.0, 60845.0, 61330.38669613291, 62176.68911655413, 9200.171984521425, 9398.2951278653, 9640.566174522164, 9728.367656102331, 9748.842038013006, 18935.0, 18957.052147392576, 18964.336248231244, 18972.14625341863, 19130.426087217405, 6353.618782873043, 6366.36336366369, 6371.617702937839, 6375.2987171411105, 6530.183727034121, 3408.4205685033603, 3573.2853429313723, 3649.361361761681, 3650.105724097613, 3719.1074142205675, 2726.182145356425, 2777.1668499450084, 2794.608754774333, 2802.061309461353, 2804.509210888127, 462.3203890281281, 465.68100850917233, 469.9365585645894, 473.4578907151292, 476.43066535489754, 492.7856382473635, 495.8363739965803, 521.5059349750362, 521.5175962234918, 521.9530242278137, 7548.886766698504, 7728.6522106505345, 7752.147263800977, 7890.566018868903, 8001.279884810278, 487.0925426329742, 487.6830060460729, 495.86363749969047, 510.7868811572515, 521.2962500624203, 3675.9889640935708, 3677.5770786359376, 3697.3159965406485, 3773.8679146230297, 3880.2821478026653, 64658.0, 66796.0, 66937.3306266931, 68942.0, 69297.0, 48474.75762621164, 54027.72986135044, 59774.0, 59791.0, 61439.385606144264, 53576.73211633917, 53637.0, 53706.462935370146, 54046.7297663517, 54393.728031360384, 6923.480738944499, 7305.196428392979, 7346.853062938604, 7553.924460755322, 7561.886571701539, 36478.27043459169, 37508.624913751606, 38413.423798642485, 39188.41217381799, 39491.40762888501, 45079.77460112678, 46280.768596156806, 49904.75047624738, 50689.493105068475, 51334.74332628313, 8565.48607083577, 8721.91278087211, 8786.428882122642, 8881.511516866376, 9043.0, 9785.706428807147, 9873.851892221479, 9896.950515247378, 9993.500324983575, 10358.896411035794, 9179.816403672023, 9348.158665720119, 9491.620335186515, 9532.857007145038, 9613.567389467631, 9025.864612030957, 9224.03147669512, 9295.535223238676, 9355.953220233856, 9748.902510975084, 9117.635294588143, 9174.633014679339, 9519.190868776232, 9524.14282714559, 9584.424934503952, 7887.171846955987, 7971.720989765562, 8041.437099402986, 8125.187481251827, 8212.0, 7887.369010479033, 7907.248811363142, 7947.205279472238, 8207.507549547046, 8435.451695639764], "value": 5894.0445842299905, "unit": "score"}], "name": "dromaeo_css", "value": 6022.688229085189, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 116.0, 116.0, 119.0, 117.0, 116.0, 119.0, 117.0, 117.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 131.0, 129.0, 177.0, 131.0, 128.0, 127.0, 132.0, 150.0, 130.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 162.0, 163.0, 161.0, 162.0, 162.0, 171.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 121.0, 77.0, 76.0, 77.0, 77.0, 109.0, 76.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 105.0, 84.0, 84.0, 84.0, 99.0, 85.0, 85.0, 106.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 128.0, 128.0, 127.0, 127.0, 127.0, 127.0, 126.0, 127.0], "value": 127.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 148.0, 148.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 65.0, 66.0, 65.0, 65.0, 64.0, 64.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 69.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 84.0, 85.0, 86.0, 85.0, 88.0, 84.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 144.0, 152.0, 144.0, 138.0, 141.0, 166.0, 153.0, 193.0, 141.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 188.0, 179.0, 181.0, 186.0, 177.0, 185.0, 180.0, 179.0, 182.0], "value": 181.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 72.0, 69.0, 69.0, 73.0, 71.0, 68.0, 72.0, 67.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1582.0999999999997, "alertThreshold": 2.0}]} 09:27:52 INFO - Return code: 0 09:27:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:27:52 INFO - # TBPL SUCCESS # 09:27:52 INFO - Running post-action listener: _resource_record_post_action 09:27:52 INFO - [mozharness: 2016-12-12 17:27:52.708235Z] Finished run-tests step (success) 09:27:52 INFO - Running post-run listener: _resource_record_post_run 09:27:52 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 0; Write bytes: 390459392; Read time: 0; Write time: 334992 09:27:52 INFO - TinderboxPrint: CPU usage
13.2% 09:27:52 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:27:52 INFO - TinderboxPrint: I/O write bytes / time
390,459,392 / 334,992 09:27:52 INFO - TinderboxPrint: CPU idle
8,346.9 (86.8%) 09:27:52 INFO - TinderboxPrint: CPU user
1,215.8 (12.6%) 09:27:52 INFO - TinderboxPrint: Swap in / out
0 / 0 09:27:52 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99196928; Read time: 0; Write time: 88964 09:27:52 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 291262464; Read time: 0; Write time: 246028 09:27:53 INFO - Running post-run listener: _upload_blobber_files 09:27:53 INFO - Blob upload gear active. 09:27:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:53 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:27:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:27:53 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:27:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:27:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:57 INFO - (blobuploader) - INFO - Done attempting. 09:27:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:27:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:27:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:58 INFO - (blobuploader) - INFO - Done attempting. 09:27:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:27:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:27:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:59 INFO - (blobuploader) - INFO - Done attempting. 09:27:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:59 INFO - Return code: 0 09:27:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc24c3c949721e5c9d7f42d720e8a33cd9cf080928c4f00d98a8d3d64be85673bab8693f6916eb394ff0de70f952ca1461c7206f2396d06e7de8f13ce40c80ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e71eb2517c20fda05191e1361a14d1a3cf32fb5d8cdc29e7fb3c73493febfc726c1837e5f1c89fefc55bccb8fe6143a49c55fb56c3dc0abba22798ce58bb015d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb07cb4315e25de0ab9f960e90610f6c980fffc1c513057c461cfcf5625ab9cb757526fc8d4f7a2234988217e4da5c87f9cf80345e68ba525e8527cf6a650f44"} 09:27:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:59 INFO - Contents: 09:27:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc24c3c949721e5c9d7f42d720e8a33cd9cf080928c4f00d98a8d3d64be85673bab8693f6916eb394ff0de70f952ca1461c7206f2396d06e7de8f13ce40c80ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e71eb2517c20fda05191e1361a14d1a3cf32fb5d8cdc29e7fb3c73493febfc726c1837e5f1c89fefc55bccb8fe6143a49c55fb56c3dc0abba22798ce58bb015d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb07cb4315e25de0ab9f960e90610f6c980fffc1c513057c461cfcf5625ab9cb757526fc8d4f7a2234988217e4da5c87f9cf80345e68ba525e8527cf6a650f44"} 09:27:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:59 INFO - Copying logs to upload dir... 09:27:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.270014 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-12-12 09:27:58.553410) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 09:27:58.556368) ========= 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=3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc24c3c949721e5c9d7f42d720e8a33cd9cf080928c4f00d98a8d3d64be85673bab8693f6916eb394ff0de70f952ca1461c7206f2396d06e7de8f13ce40c80ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e71eb2517c20fda05191e1361a14d1a3cf32fb5d8cdc29e7fb3c73493febfc726c1837e5f1c89fefc55bccb8fe6143a49c55fb56c3dc0abba22798ce58bb015d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb07cb4315e25de0ab9f960e90610f6c980fffc1c513057c461cfcf5625ab9cb757526fc8d4f7a2234988217e4da5c87f9cf80345e68ba525e8527cf6a650f44"} build_url:https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011457 build_url: 'https://queue.taskcluster.net/v1/task/DdUIqKlHRyqFQKsor3L0uQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc24c3c949721e5c9d7f42d720e8a33cd9cf080928c4f00d98a8d3d64be85673bab8693f6916eb394ff0de70f952ca1461c7206f2396d06e7de8f13ce40c80ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e71eb2517c20fda05191e1361a14d1a3cf32fb5d8cdc29e7fb3c73493febfc726c1837e5f1c89fefc55bccb8fe6143a49c55fb56c3dc0abba22798ce58bb015d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb07cb4315e25de0ab9f960e90610f6c980fffc1c513057c461cfcf5625ab9cb757526fc8d4f7a2234988217e4da5c87f9cf80345e68ba525e8527cf6a650f44"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 09:27:58.581428) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:27:58.581734) ========= 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=3836458cccd887f2d2de3cf1000001de-1481562053.553780-141644787 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 09:27:58.664630) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 09:27:58.664930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 09:27:58.665214) ========= ========= Total master_lag: 0.19 =========