builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1481557447.9 results: success (0) buildid: 20161212064450 builduid: ab4901a24ba04e399f3604d18065332f revision: 3c9a0a6cdfff95eaa1c851344e898b3b15d55609 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:07.896267) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:07.896773) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:07.897119) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:07.916619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:07.916914) ========= 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-1481550977.346156-1865240718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-12 07:44:08-- 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% 45.8M=0s 2016-12-12 07:44:08 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:08.085607) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:08.085963) ========= 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-1481550977.346156-1865240718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019660 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:08.139336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-12 07:44:08.140253) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3c9a0a6cdfff95eaa1c851344e898b3b15d55609 --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 3c9a0a6cdfff95eaa1c851344e898b3b15d55609 --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-1481550977.346156-1865240718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-12 07:44:08,758 truncating revision to first 12 chars 2016-12-12 07:44:08,759 Setting DEBUG logging. 2016-12-12 07:44:08,759 attempt 1/10 2016-12-12 07:44:08,759 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3c9a0a6cdfff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-12 07:44:09,544 unpacking tar archive at: mozilla-central-3c9a0a6cdfff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.128666 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-12 07:44:09.283785) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:09.284128) ========= script_repo_revision: 3c9a0a6cdfff95eaa1c851344e898b3b15d55609 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:09.284560) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:09.284861) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 07:44:09.298351) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-12 07:44:09.298706) ========= /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-1481550977.346156-1865240718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:44:10 INFO - MultiFileLogger online at 20161212 07:44:10 in /builds/slave/test 07:44:10 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 07:44:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:44:10 INFO - {'append_to_log': False, 07:44:10 INFO - 'base_work_dir': '/builds/slave/test', 07:44:10 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:44:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:44:10 INFO - 'branch': 'Firefox-Non-PGO', 07:44:10 INFO - 'buildbot_json_path': 'buildprops.json', 07:44:10 INFO - 'config_files': ('talos/linux_config.py',), 07:44:10 INFO - 'default_actions': ('clobber', 07:44:10 INFO - 'read-buildbot-config', 07:44:10 INFO - 'download-and-extract', 07:44:10 INFO - 'populate-webroot', 07:44:10 INFO - 'create-virtualenv', 07:44:10 INFO - 'install', 07:44:10 INFO - 'run-tests'), 07:44:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:44:10 INFO - 'download_minidump_stackwalk': True, 07:44:10 INFO - 'download_symbols': 'ondemand', 07:44:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:44:10 INFO - 'tooltool.py': '/tools/tooltool.py', 07:44:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:44:10 INFO - '/tools/misc-python/virtualenv.py')}, 07:44:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:44:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:44:10 INFO - 'installer_path': 'installer.exe', 07:44:10 INFO - 'log_level': 'info', 07:44:10 INFO - 'log_name': 'talos', 07:44:10 INFO - 'log_to_console': True, 07:44:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:44:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:44:10 INFO - 'opt_config_files': (), 07:44:10 INFO - 'pip_index': False, 07:44:10 INFO - 'sps_profile': False, 07:44:10 INFO - 'sps_profile_interval': 0, 07:44:10 INFO - 'suite': 'dromaeojs', 07:44:10 INFO - 'system_bits': '32', 07:44:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:44:10 INFO - 'title': 'talos-linux64-ix-030', 07:44:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:44:10 INFO - 'use_talos_json': True, 07:44:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:44:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:44:10 INFO - 'work_dir': 'build'} 07:44:10 INFO - [mozharness: 2016-12-12 15:44:10.020805Z] Running clobber step. 07:44:10 INFO - Running pre-action listener: _resource_record_pre_action 07:44:10 INFO - Running main action method: clobber 07:44:10 INFO - rmtree: /builds/slave/test/build 07:44:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:44:10 INFO - Running post-action listener: _resource_record_post_action 07:44:10 INFO - [mozharness: 2016-12-12 15:44:10.999760Z] Finished clobber step (success) 07:44:10 INFO - [mozharness: 2016-12-12 15:44:10.999859Z] Running read-buildbot-config step. 07:44:10 INFO - Running pre-action listener: _resource_record_pre_action 07:44:11 INFO - Running main action method: read_buildbot_config 07:44:11 INFO - Using buildbot properties: 07:44:11 INFO - { 07:44:11 INFO - "project": "", 07:44:11 INFO - "product": "firefox", 07:44:11 INFO - "script_repo_revision": "production", 07:44:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:44:11 INFO - "repository": "", 07:44:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:44:11 INFO - "buildid": "20161212064450", 07:44:11 INFO - "pgo_build": "False", 07:44:11 INFO - "basedir": "/builds/slave/test", 07:44:11 INFO - "buildnumber": 742, 07:44:11 INFO - "slavename": "talos-linux64-ix-030", 07:44:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:44:11 INFO - "platform": "ubuntu64_hw", 07:44:11 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:44:11 INFO - "branch": "mozilla-central", 07:44:11 INFO - "repo_path": "mozilla-central", 07:44:11 INFO - "revision": "3c9a0a6cdfff95eaa1c851344e898b3b15d55609", 07:44:11 INFO - "stage_platform": "linux64", 07:44:11 INFO - "builduid": "ab4901a24ba04e399f3604d18065332f", 07:44:11 INFO - "slavebuilddir": "test" 07:44:11 INFO - } 07:44:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:44:11 INFO - Running post-action listener: _resource_record_post_action 07:44:11 INFO - [mozharness: 2016-12-12 15:44:11.002349Z] Finished read-buildbot-config step (success) 07:44:11 INFO - [mozharness: 2016-12-12 15:44:11.002439Z] Running download-and-extract step. 07:44:11 INFO - Running pre-action listener: _resource_record_pre_action 07:44:11 INFO - Running main action method: download_and_extract 07:44:11 INFO - mkdir: /builds/slave/test/build/tests 07:44:11 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')]} 07:44:11 INFO - trying https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:44:11 INFO - Downloading https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/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 07:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/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 07:44:11 INFO - Downloaded 1361 bytes. 07:44:11 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:44:11 INFO - Using the following test package requirements: 07:44:11 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:44:11 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:44:11 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:44:11 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'jsshell-linux-x86_64.zip'], 07:44:11 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:44:11 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:44:11 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:44:11 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:44:11 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:44:11 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:44:11 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:44:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:44:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:44:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:44:11 INFO - Fetch https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:44:12 INFO - Expected file size: 20224488 07:44:12 INFO - Obtained file size: 20224488 07:44:16 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 07:44:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:44:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:44:16 INFO - Fetch https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:44:17 INFO - Expected file size: 20224488 07:44:17 INFO - Obtained file size: 20224488 07:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:44:22 INFO - Expected file size: 14409734 07:44:22 INFO - Obtained file size: 14409734 07:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:22 INFO - trying https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:44:22 INFO - Downloading https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/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 07:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/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 07:44:23 INFO - Downloaded 55772619 bytes. 07:44:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:44:23 INFO - mkdir: /builds/slave/test/properties 07:44:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:44:23 INFO - Writing to file /builds/slave/test/properties/build_url 07:44:23 INFO - Contents: 07:44:23 INFO - build_url:https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:44:24 INFO - Running post-action listener: _resource_record_post_action 07:44:24 INFO - Running post-action listener: set_extra_try_arguments 07:44:24 INFO - [mozharness: 2016-12-12 15:44:24.464318Z] Finished download-and-extract step (success) 07:44:24 INFO - [mozharness: 2016-12-12 15:44:24.464546Z] Running populate-webroot step. 07:44:24 INFO - Running pre-action listener: _resource_record_pre_action 07:44:24 INFO - Running main action method: populate_webroot 07:44:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:44:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:44:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:44:24 INFO - '--tppagecycles', 07:44:24 INFO - '7'], 07:44:24 INFO - 'tests': ['tsvgm']}}, 07:44:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:44:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:44:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:44:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:44:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:44:24 INFO - 'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:44:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:44:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:44:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:44:24 INFO - 'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['damp', 'tps']}, 07:44:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:44:24 INFO - 'tests': ['damp', 'tps']}, 07:44:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['dromaeo_dom']}, 07:44:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:44:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['basic_compositor_video']}, 07:44:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:44:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['a11yr', 07:44:24 INFO - 'ts_paint', 07:44:24 INFO - 'tpaint', 07:44:24 INFO - 'sessionrestore', 07:44:24 INFO - 'sessionrestore_no_auto_restore', 07:44:24 INFO - 'tabpaint']}, 07:44:24 INFO - 'other-e10s': {'tests': ['a11yr', 07:44:24 INFO - 'ts_paint', 07:44:24 INFO - 'tpaint', 07:44:24 INFO - 'sessionrestore', 07:44:24 INFO - 'sessionrestore_no_auto_restore', 07:44:24 INFO - 'tabpaint']}, 07:44:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:44:24 INFO - 'ts_paint', 07:44:24 INFO - 'tpaint', 07:44:24 INFO - 'sessionrestore', 07:44:24 INFO - 'sessionrestore_no_auto_restore', 07:44:24 INFO - 'tabpaint']}, 07:44:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:44:24 INFO - 'ts_paint', 07:44:24 INFO - 'tpaint', 07:44:24 INFO - 'sessionrestore', 07:44:24 INFO - 'sessionrestore_no_auto_restore', 07:44:24 INFO - 'tabpaint']}, 07:44:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['a11yr', 07:44:24 INFO - 'ts_paint', 07:44:24 INFO - 'tpaint', 07:44:24 INFO - 'sessionrestore', 07:44:24 INFO - 'sessionrestore_no_auto_restore', 07:44:24 INFO - 'tabpaint']}, 07:44:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['a11yr', 07:44:24 INFO - 'ts_paint', 07:44:24 INFO - 'tpaint', 07:44:24 INFO - 'sessionrestore', 07:44:24 INFO - 'sessionrestore_no_auto_restore', 07:44:24 INFO - 'tabpaint']}, 07:44:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['tsvgx', 07:44:24 INFO - 'tsvgr_opacity', 07:44:24 INFO - 'tart', 07:44:24 INFO - 'tscrollx', 07:44:24 INFO - 'cart', 07:44:24 INFO - 'tsvg_static']}, 07:44:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:44:24 INFO - 'tsvgr_opacity', 07:44:24 INFO - 'tart', 07:44:24 INFO - 'tscrollx', 07:44:24 INFO - 'cart', 07:44:24 INFO - 'tsvg_static']}, 07:44:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:44:24 INFO - 'talos_options': ['--disable-e10s'], 07:44:24 INFO - 'tests': ['tp5o']}, 07:44:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:44:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:44:24 INFO - 'talos_options': ['--disable-e10s', 07:44:24 INFO - '--xperf_path', 07:44:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:24 INFO - 'tests': ['tp5n']}, 07:44:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:44:24 INFO - 'talos_options': ['--xperf_path', 07:44:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:24 INFO - 'tests': ['tp5n']}}, 07:44:24 INFO - 'talos.zip': {'path': '', 07:44:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:44:24 INFO - Running post-action listener: _resource_record_post_action 07:44:24 INFO - [mozharness: 2016-12-12 15:44:24.485892Z] Finished populate-webroot step (success) 07:44:24 INFO - [mozharness: 2016-12-12 15:44:24.485981Z] Running create-virtualenv step. 07:44:24 INFO - Running pre-action listener: _resource_record_pre_action 07:44:24 INFO - Running main action method: create_virtualenv 07:44:24 INFO - Creating virtualenv /builds/slave/test/build/venv 07:44: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 07:44:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:44:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:44:24 INFO - Using real prefix '/usr' 07:44:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:44:25 INFO - Installing distribute.............................................................................................................................................................................................done. 07:44:27 INFO - Installing pip.................done. 07:44:27 INFO - Return code: 0 07:44:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:44:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:44:27 INFO - Reading from file tmpfile_stdout 07:44:27 INFO - Output received: 07:44: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) 07:44:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:27 INFO - Using env: {'DISPLAY': ':0', 07:44:27 INFO - 'HOME': '/home/cltbld', 07:44:27 INFO - 'LANG': 'en_US.UTF-8', 07:44:27 INFO - 'LANGUAGE': 'en_US:en', 07:44:27 INFO - 'LOGNAME': 'cltbld', 07:44:27 INFO - 'MAIL': '/var/mail/cltbld', 07:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:27 INFO - 'MOZ_NO_REMOTE': '1', 07:44:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:27 INFO - 'NO_EM_RESTART': '1', 07:44:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:27 INFO - 'PWD': '/builds/slave/test', 07:44:27 INFO - 'SHELL': '/bin/bash', 07:44:27 INFO - 'SHLVL': '1', 07:44:27 INFO - 'TERM': 'linux', 07:44:27 INFO - 'TMOUT': '86400', 07:44:27 INFO - 'USER': 'cltbld', 07:44:27 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:27 INFO - '_': '/tools/buildbot/bin/python'} 07:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44: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 07:44:27 INFO - Cleaning up... 07:44:27 INFO - Return code: 0 07:44:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:27 INFO - Using env: {'DISPLAY': ':0', 07:44:27 INFO - 'HOME': '/home/cltbld', 07:44:27 INFO - 'LANG': 'en_US.UTF-8', 07:44:27 INFO - 'LANGUAGE': 'en_US:en', 07:44:27 INFO - 'LOGNAME': 'cltbld', 07:44:27 INFO - 'MAIL': '/var/mail/cltbld', 07:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:27 INFO - 'MOZ_NO_REMOTE': '1', 07:44:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:27 INFO - 'NO_EM_RESTART': '1', 07:44:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:27 INFO - 'PWD': '/builds/slave/test', 07:44:27 INFO - 'SHELL': '/bin/bash', 07:44:27 INFO - 'SHLVL': '1', 07:44:27 INFO - 'TERM': 'linux', 07:44:27 INFO - 'TMOUT': '86400', 07:44:27 INFO - 'USER': 'cltbld', 07:44:27 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:27 INFO - '_': '/tools/buildbot/bin/python'} 07:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:27 INFO - Downloading/unpacking psutil>=3.1.1 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:44:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:44:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:44:29 INFO - Installing collected packages: psutil 07:44:29 INFO - Running setup.py install for psutil 07:44:29 INFO - building 'psutil._psutil_linux' extension 07:44:29 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 07:44: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 07:44:30 INFO - building 'psutil._psutil_posix' extension 07:44: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 07:44: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 07:44:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:44:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:44:30 INFO - Successfully installed psutil 07:44:30 INFO - Cleaning up... 07:44:30 INFO - Return code: 0 07:44:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:30 INFO - Using env: {'DISPLAY': ':0', 07:44:30 INFO - 'HOME': '/home/cltbld', 07:44:30 INFO - 'LANG': 'en_US.UTF-8', 07:44:30 INFO - 'LANGUAGE': 'en_US:en', 07:44:30 INFO - 'LOGNAME': 'cltbld', 07:44:30 INFO - 'MAIL': '/var/mail/cltbld', 07:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:30 INFO - 'MOZ_NO_REMOTE': '1', 07:44:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:30 INFO - 'NO_EM_RESTART': '1', 07:44:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:30 INFO - 'PWD': '/builds/slave/test', 07:44:30 INFO - 'SHELL': '/bin/bash', 07:44:30 INFO - 'SHLVL': '1', 07:44:30 INFO - 'TERM': 'linux', 07:44:30 INFO - 'TMOUT': '86400', 07:44:30 INFO - 'USER': 'cltbld', 07:44:30 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:30 INFO - '_': '/tools/buildbot/bin/python'} 07:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:44:32 INFO - Installing collected packages: mozsystemmonitor 07:44:32 INFO - Running setup.py install for mozsystemmonitor 07:44:32 INFO - Successfully installed mozsystemmonitor 07:44:32 INFO - Cleaning up... 07:44:32 INFO - Return code: 0 07:44:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:44:32 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')]} 07:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:32 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')]} 07:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:32 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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44:32 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 07:44:32 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 07:44:32 INFO - Using env: {'DISPLAY': ':0', 07:44:32 INFO - 'HOME': '/home/cltbld', 07:44:32 INFO - 'LANG': 'en_US.UTF-8', 07:44:32 INFO - 'LANGUAGE': 'en_US:en', 07:44:32 INFO - 'LOGNAME': 'cltbld', 07:44:32 INFO - 'MAIL': '/var/mail/cltbld', 07:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:32 INFO - 'MOZ_NO_REMOTE': '1', 07:44:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:32 INFO - 'NO_EM_RESTART': '1', 07:44:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:32 INFO - 'PWD': '/builds/slave/test', 07:44:32 INFO - 'SHELL': '/bin/bash', 07:44:32 INFO - 'SHLVL': '1', 07:44:32 INFO - 'TERM': 'linux', 07:44:32 INFO - 'TMOUT': '86400', 07:44:32 INFO - 'USER': 'cltbld', 07:44:32 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:32 INFO - '_': '/tools/buildbot/bin/python'} 07:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:32 INFO - Downloading/unpacking jsonschema==2.5.1 07:44:32 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 07:44:32 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 07:44:32 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 07:44:32 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 07:44:32 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 07:44:32 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 07:44:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:44:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:35 INFO - Downloading functools32-3.2.3-2.tar.gz 07:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:44:35 INFO - warning: no files found matching '*.txt' 07:44:35 INFO - no previously-included directories found matching 'build' 07:44:35 INFO - no previously-included directories found matching 'dist' 07:44:35 INFO - no previously-included directories found matching '.git*' 07:44:35 INFO - Installing collected packages: jsonschema, functools32 07:44:35 INFO - Running setup.py install for functools32 07:44:35 INFO - warning: no files found matching '*.txt' 07:44:35 INFO - no previously-included directories found matching 'build' 07:44:35 INFO - no previously-included directories found matching 'dist' 07:44:35 INFO - no previously-included directories found matching '.git*' 07:44:35 INFO - Successfully installed jsonschema functools32 07:44:35 INFO - Cleaning up... 07:44:35 INFO - Return code: 0 07:44:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:35 INFO - Using env: {'DISPLAY': ':0', 07:44:35 INFO - 'HOME': '/home/cltbld', 07:44:35 INFO - 'LANG': 'en_US.UTF-8', 07:44:35 INFO - 'LANGUAGE': 'en_US:en', 07:44:35 INFO - 'LOGNAME': 'cltbld', 07:44:35 INFO - 'MAIL': '/var/mail/cltbld', 07:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:35 INFO - 'MOZ_NO_REMOTE': '1', 07:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:35 INFO - 'NO_EM_RESTART': '1', 07:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:35 INFO - 'PWD': '/builds/slave/test', 07:44:35 INFO - 'SHELL': '/bin/bash', 07:44:35 INFO - 'SHLVL': '1', 07:44:35 INFO - 'TERM': 'linux', 07:44:35 INFO - 'TMOUT': '86400', 07:44:35 INFO - 'USER': 'cltbld', 07:44:35 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:35 INFO - '_': '/tools/buildbot/bin/python'} 07:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:44:35 INFO - Cleaning up... 07:44:35 INFO - Return code: 0 07:44:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:35 INFO - Using env: {'DISPLAY': ':0', 07:44:35 INFO - 'HOME': '/home/cltbld', 07:44:35 INFO - 'LANG': 'en_US.UTF-8', 07:44:35 INFO - 'LANGUAGE': 'en_US:en', 07:44:35 INFO - 'LOGNAME': 'cltbld', 07:44:35 INFO - 'MAIL': '/var/mail/cltbld', 07:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:35 INFO - 'MOZ_NO_REMOTE': '1', 07:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:35 INFO - 'NO_EM_RESTART': '1', 07:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:35 INFO - 'PWD': '/builds/slave/test', 07:44:35 INFO - 'SHELL': '/bin/bash', 07:44:35 INFO - 'SHLVL': '1', 07:44:35 INFO - 'TERM': 'linux', 07:44:35 INFO - 'TMOUT': '86400', 07:44:35 INFO - 'USER': 'cltbld', 07:44:35 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:35 INFO - '_': '/tools/buildbot/bin/python'} 07:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:35 INFO - Downloading/unpacking blobuploader==1.2.4 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44:37 INFO - Downloading blobuploader-1.2.4.tar.gz 07:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:44:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:44:37 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 07:44:37 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 07:44:37 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 07:44:37 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 07:44:37 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 07:44:37 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 07:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:44:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44: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 07:44:38 INFO - Downloading docopt-0.6.1.tar.gz 07:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:44:38 INFO - Installing collected packages: blobuploader, requests, docopt 07:44:38 INFO - Running setup.py install for blobuploader 07:44:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:44:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:44:38 INFO - Running setup.py install for requests 07:44:39 INFO - Running setup.py install for docopt 07:44:39 INFO - Successfully installed blobuploader requests docopt 07:44:39 INFO - Cleaning up... 07:44:39 INFO - Return code: 0 07:44:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:39 INFO - Using env: {'DISPLAY': ':0', 07:44:39 INFO - 'HOME': '/home/cltbld', 07:44:39 INFO - 'LANG': 'en_US.UTF-8', 07:44:39 INFO - 'LANGUAGE': 'en_US:en', 07:44:39 INFO - 'LOGNAME': 'cltbld', 07:44:39 INFO - 'MAIL': '/var/mail/cltbld', 07:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:39 INFO - 'MOZ_NO_REMOTE': '1', 07:44:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:39 INFO - 'NO_EM_RESTART': '1', 07:44:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:39 INFO - 'PWD': '/builds/slave/test', 07:44:39 INFO - 'SHELL': '/bin/bash', 07:44:39 INFO - 'SHLVL': '1', 07:44:39 INFO - 'TERM': 'linux', 07:44:39 INFO - 'TMOUT': '86400', 07:44:39 INFO - 'USER': 'cltbld', 07:44:39 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:39 INFO - '_': '/tools/buildbot/bin/python'} 07:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:44:39 INFO - Running setup.py (path:/tmp/pip-7CODTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:44:39 INFO - Running setup.py (path:/tmp/pip-IHPLdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:44:39 INFO - Running setup.py (path:/tmp/pip-n4mjHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:44:39 INFO - Running setup.py (path:/tmp/pip-xFCDN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:44:39 INFO - Running setup.py (path:/tmp/pip-vusPXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:44:39 INFO - Running setup.py (path:/tmp/pip-XMg7ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:44:39 INFO - Running setup.py (path:/tmp/pip-3N35UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:44:39 INFO - Running setup.py (path:/tmp/pip-8s2W0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:44:39 INFO - Running setup.py (path:/tmp/pip-_gSTJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:44:40 INFO - Running setup.py (path:/tmp/pip-jQq40c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:44:40 INFO - Running setup.py (path:/tmp/pip-VV9cvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:44:40 INFO - Running setup.py (path:/tmp/pip-qtKnhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:44:40 INFO - Running setup.py (path:/tmp/pip-TpX0HY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:44:40 INFO - Running setup.py (path:/tmp/pip-1CG6iV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:40 INFO - Running setup.py (path:/tmp/pip-SmiWso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:44:40 INFO - Running setup.py (path:/tmp/pip-C_6iFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:44:40 INFO - Running setup.py (path:/tmp/pip-X1U4RO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:44:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:44:40 INFO - Running setup.py install for manifestparser 07:44:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:44:40 INFO - Running setup.py install for mozcrash 07:44:40 INFO - Running setup.py install for mozdebug 07:44:40 INFO - Running setup.py install for mozdevice 07:44:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Running setup.py install for mozfile 07:44:41 INFO - Running setup.py install for mozhttpd 07:44:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Running setup.py install for mozinfo 07:44:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Running setup.py install for mozInstall 07:44:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Running setup.py install for mozleak 07:44:41 INFO - Running setup.py install for mozlog 07:44:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Running setup.py install for moznetwork 07:44:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Running setup.py install for mozprocess 07:44:41 INFO - Running setup.py install for mozprofile 07:44:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:44:41 INFO - Running setup.py install for mozrunner 07:44:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:44:42 INFO - Running setup.py install for mozscreenshot 07:44:42 INFO - Running setup.py install for moztest 07:44:42 INFO - Running setup.py install for mozversion 07:44:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:44:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:44:42 INFO - Cleaning up... 07:44:42 INFO - Return code: 0 07:44:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:42 INFO - Using env: {'DISPLAY': ':0', 07:44:42 INFO - 'HOME': '/home/cltbld', 07:44:42 INFO - 'LANG': 'en_US.UTF-8', 07:44:42 INFO - 'LANGUAGE': 'en_US:en', 07:44:42 INFO - 'LOGNAME': 'cltbld', 07:44:42 INFO - 'MAIL': '/var/mail/cltbld', 07:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:42 INFO - 'MOZ_NO_REMOTE': '1', 07:44:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:42 INFO - 'NO_EM_RESTART': '1', 07:44:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:42 INFO - 'PWD': '/builds/slave/test', 07:44:42 INFO - 'SHELL': '/bin/bash', 07:44:42 INFO - 'SHLVL': '1', 07:44:42 INFO - 'TERM': 'linux', 07:44:42 INFO - 'TMOUT': '86400', 07:44:42 INFO - 'USER': 'cltbld', 07:44:42 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:42 INFO - '_': '/tools/buildbot/bin/python'} 07:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:44:42 INFO - Running setup.py (path:/tmp/pip-8HpH16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:44:42 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)) 07:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:44:42 INFO - Running setup.py (path:/tmp/pip-dRUrvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:44:42 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)) 07:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:44:42 INFO - Running setup.py (path:/tmp/pip-iZHK_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:44:42 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)) 07:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:44:42 INFO - Running setup.py (path:/tmp/pip-KCYX7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:44:42 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)) 07:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:44:42 INFO - Running setup.py (path:/tmp/pip-rY4N9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:44:42 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)) 07:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:44:42 INFO - Running setup.py (path:/tmp/pip-WETP8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:44:43 INFO - Running setup.py (path:/tmp/pip-sO21G8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:44:43 INFO - Running setup.py (path:/tmp/pip-jBMycs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:44:43 INFO - Running setup.py (path:/tmp/pip-yO0SE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:44:43 INFO - Running setup.py (path:/tmp/pip-i8AxqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:44:43 INFO - Running setup.py (path:/tmp/pip-3NPCrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:44:43 INFO - Running setup.py (path:/tmp/pip-5JCXZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:44:43 INFO - Running setup.py (path:/tmp/pip-oxxOws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:44:43 INFO - Running setup.py (path:/tmp/pip-gLhm3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:44: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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:43 INFO - Running setup.py (path:/tmp/pip-D8_3DK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:43 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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:44:43 INFO - Running setup.py (path:/tmp/pip-ZoOdJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:44:43 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)) 07:44:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:44:43 INFO - Running setup.py (path:/tmp/pip-YEBQ1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:44:43 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)) 07:44:43 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)) 07:44:43 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)) 07:44:43 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)) 07:44:43 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)) 07:44:43 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)) 07:44:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:44:43 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 07:44:43 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 07:44:43 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 07:44:43 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 07:44:43 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 07:44:43 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 07:44:45 INFO - Downloading blessings-1.6.tar.gz 07:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:44:45 INFO - Installing collected packages: blessings 07:44:45 INFO - Running setup.py install for blessings 07:44:46 INFO - Successfully installed blessings 07:44:46 INFO - Cleaning up... 07:44:46 INFO - Return code: 0 07:44:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:44:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:44:46 INFO - Reading from file tmpfile_stdout 07:44:46 INFO - Current package versions: 07:44:46 INFO - argparse == 1.2.1 07:44:46 INFO - blessings == 1.6 07:44:46 INFO - blobuploader == 1.2.4 07:44:46 INFO - docopt == 0.6.1 07:44:46 INFO - functools32 == 3.2.3-2 07:44:46 INFO - jsonschema == 2.5.1 07:44:46 INFO - manifestparser == 1.1 07:44:46 INFO - mozInstall == 1.12 07:44:46 INFO - mozcrash == 1.0 07:44:46 INFO - mozdebug == 0.1 07:44:46 INFO - mozdevice == 0.49 07:44:46 INFO - mozfile == 1.2 07:44:46 INFO - mozhttpd == 0.7 07:44:46 INFO - mozinfo == 0.9 07:44:46 INFO - mozleak == 0.1 07:44:46 INFO - mozlog == 3.4 07:44:46 INFO - moznetwork == 0.27 07:44:46 INFO - mozprocess == 0.23 07:44:46 INFO - mozprofile == 0.28 07:44:46 INFO - mozrunner == 6.13 07:44:46 INFO - mozscreenshot == 0.1 07:44:46 INFO - mozsystemmonitor == 0.3 07:44:46 INFO - moztest == 0.7 07:44:46 INFO - mozversion == 1.4 07:44:46 INFO - psutil == 3.1.1 07:44:46 INFO - requests == 1.2.3 07:44:46 INFO - wsgiref == 0.1.2 07:44:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:46 INFO - Using env: {'DISPLAY': ':0', 07:44:46 INFO - 'HOME': '/home/cltbld', 07:44:46 INFO - 'LANG': 'en_US.UTF-8', 07:44:46 INFO - 'LANGUAGE': 'en_US:en', 07:44:46 INFO - 'LOGNAME': 'cltbld', 07:44:46 INFO - 'MAIL': '/var/mail/cltbld', 07:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:46 INFO - 'MOZ_NO_REMOTE': '1', 07:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:46 INFO - 'NO_EM_RESTART': '1', 07:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:46 INFO - 'PWD': '/builds/slave/test', 07:44:46 INFO - 'SHELL': '/bin/bash', 07:44:46 INFO - 'SHLVL': '1', 07:44:46 INFO - 'TERM': 'linux', 07:44:46 INFO - 'TMOUT': '86400', 07:44:46 INFO - 'USER': 'cltbld', 07:44:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:46 INFO - '_': '/tools/buildbot/bin/python'} 07:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44: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)) 07:44:46 INFO - Cleaning up... 07:44:46 INFO - Return code: 0 07:44:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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 0x7f58c0e52e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d70120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, '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-1481550977.346156-1865240718', '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 07:44: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 07:44: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 07:44:46 INFO - Using env: {'DISPLAY': ':0', 07:44:46 INFO - 'HOME': '/home/cltbld', 07:44:46 INFO - 'LANG': 'en_US.UTF-8', 07:44:46 INFO - 'LANGUAGE': 'en_US:en', 07:44:46 INFO - 'LOGNAME': 'cltbld', 07:44:46 INFO - 'MAIL': '/var/mail/cltbld', 07:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:46 INFO - 'MOZ_NO_REMOTE': '1', 07:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:46 INFO - 'NO_EM_RESTART': '1', 07:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:46 INFO - 'PWD': '/builds/slave/test', 07:44:46 INFO - 'SHELL': '/bin/bash', 07:44:46 INFO - 'SHLVL': '1', 07:44:46 INFO - 'TERM': 'linux', 07:44:46 INFO - 'TMOUT': '86400', 07:44:46 INFO - 'USER': 'cltbld', 07:44:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:46 INFO - '_': '/tools/buildbot/bin/python'} 07:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:44:46 INFO - Cleaning up... 07:44:46 INFO - Return code: 0 07:44:46 INFO - Running post-action listener: _resource_record_post_action 07:44:46 INFO - Running post-action listener: _start_resource_monitoring 07:44:46 INFO - Starting resource monitoring. 07:44:46 INFO - [mozharness: 2016-12-12 15:44:46.812656Z] Finished create-virtualenv step (success) 07:44:46 INFO - [mozharness: 2016-12-12 15:44:46.813178Z] Running install step. 07:44:46 INFO - Running pre-action listener: _resource_record_pre_action 07:44:46 INFO - Running main action method: install 07:44:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:44:47 INFO - Reading from file tmpfile_stdout 07:44:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:44:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:44:47 INFO - Reading from file tmpfile_stdout 07:44:47 INFO - Output received: 07:44:47 INFO - Usage: mozinstall [options] installer 07:44:47 INFO - Options: 07:44:47 INFO - -h, --help show this help message and exit 07:44:47 INFO - -d DEST, --destination=DEST 07:44:47 INFO - Directory to install application into. [default: 07:44:47 INFO - "/builds/slave/test"] 07:44:47 INFO - --app=APP Application being installed. [default: firefox] 07:44:47 INFO - mkdir: /builds/slave/test/build/application 07:44: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'] 07:44: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 07:45:03 INFO - Reading from file tmpfile_stdout 07:45:03 INFO - Output received: 07:45:03 INFO - /builds/slave/test/build/application/firefox/firefox 07:45:03 INFO - Running post-action listener: _resource_record_post_action 07:45:03 INFO - [mozharness: 2016-12-12 15:45:03.157211Z] Finished install step (success) 07:45:03 INFO - [mozharness: 2016-12-12 15:45:03.157531Z] Running run-tests step. 07:45:03 INFO - Running pre-action listener: _resource_record_pre_action 07:45:03 INFO - Running main action method: run_tests 07:45:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:45:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:45:03 INFO - Python 2.7.3 07:45:03 INFO - Return code: 0 07:45:03 INFO - grabbing minidump binary from tooltool 07:45: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')]} 07:45: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 0x1e00f20>, '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 0x1d6dc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d6df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:45: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 07:45: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 07:45: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 07:45:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:45:03 INFO - Return code: 0 07:45:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:45:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:45:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:45:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:45:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:45: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/F0ZibLr6Semz6tknVgLuJQ/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 07:45: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/F0ZibLr6Semz6tknVgLuJQ/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 07:45:03 INFO - Using env: {'DISPLAY': ':0', 07:45:03 INFO - 'HOME': '/home/cltbld', 07:45:03 INFO - 'LANG': 'en_US.UTF-8', 07:45:03 INFO - 'LANGUAGE': 'en_US:en', 07:45:03 INFO - 'LOGNAME': 'cltbld', 07:45:03 INFO - 'MAIL': '/var/mail/cltbld', 07:45:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:45:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:03 INFO - 'MOZ_NO_REMOTE': '1', 07:45:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:45:03 INFO - 'NO_EM_RESTART': '1', 07:45:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:03 INFO - 'PWD': '/builds/slave/test', 07:45:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:45:03 INFO - 'SHELL': '/bin/bash', 07:45:03 INFO - 'SHLVL': '1', 07:45:03 INFO - 'TERM': 'linux', 07:45:03 INFO - 'TMOUT': '86400', 07:45:03 INFO - 'USER': 'cltbld', 07:45:03 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1481550977.346156-1865240718', 07:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:03 INFO - '_': '/tools/buildbot/bin/python'} 07:45: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/F0ZibLr6Semz6tknVgLuJQ/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 07:45:03 INFO - mozversion application_buildid: 20161212064450 07:45:03 INFO - mozversion application_changeset: 3c9a0a6cdfff95eaa1c851344e898b3b15d55609 07:45:03 INFO - mozversion application_display_name: Nightly 07:45:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:45:03 INFO - mozversion application_name: Firefox 07:45:03 INFO - mozversion application_remotingname: firefox 07:45:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:45:03 INFO - mozversion application_vendor: Mozilla 07:45:03 INFO - mozversion application_version: 53.0a1 07:45:03 INFO - mozversion platform_buildid: 20161212064450 07:45:03 INFO - mozversion platform_changeset: 3c9a0a6cdfff95eaa1c851344e898b3b15d55609 07:45:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:45:03 INFO - mozversion platform_version: 53.0a1 07:45:03 INFO - using testdate: 1481557503 07:45:03 INFO - actual date: 1481557503 07:45:03 INFO - starting webserver on 'localhost:49656' 07:45:03 INFO - SUITE-START | Running 2 tests 07:45:03 INFO - TEST-START | dromaeo_css 07:45:03 INFO - operating with platform_type : linux_ 07:45:03 INFO - Initialising browser for dromaeo_css test... 07:45:03 INFO - TEST-INFO | started process 7375 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMUUeav/profile http://localhost:49656/getInfo.html) 07:45:07 INFO - PROCESS | 7375 | __metrics Screen width/height:1600/1200 07:45:07 INFO - PROCESS | 7375 | colorDepth:24 07:45:07 INFO - PROCESS | 7375 | Browser inner width/height: 1024/697 07:45:07 INFO - PROCESS | 7375 | __metrics 07:45:08 INFO - PROCESS | 7375 | 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" 07:45:08 INFO - PROCESS | 7375 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:45:09 INFO - PROCESS | 7375 | console.error: PushService: 07:45:09 INFO - PROCESS | 7375 | stateChangeProcessEnqueue: Error transitioning state 07:45:09 INFO - PROCESS | 7375 | UnknownError 07:45:09 INFO - TEST-INFO | 7375: exit 0 07:45:09 INFO - Browser initialized. 07:45:09 INFO - Running cycle 1/1 for dromaeo_css test... 07:45:09 INFO - TEST-INFO | started process 7458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMUUeav/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:45:22 INFO - PROCESS | 7458 | RSS: Main: 174321664 07:45:22 INFO - PROCESS | 7458 | 07:46:13 INFO - PROCESS | 7458 | 1481557573731 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] 07:48:28 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49656/tests/dromaeo/cssquery-ext.html) 07:48:30 INFO - PROCESS | 7458 | RSS: Main: 330334208 07:48:30 INFO - PROCESS | 7458 | 07:51:33 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-ext.html (next: http://localhost:49656/tests/dromaeo/cssquery-jquery.html) 07:51:33 INFO - PROCESS | 7458 | RSS: Main: 285016064 07:51:33 INFO - PROCESS | 7458 | 07:54:36 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49656/tests/dromaeo/cssquery-mootools.html) 07:54:36 INFO - PROCESS | 7458 | RSS: Main: 219607040 07:54:36 INFO - PROCESS | 7458 | 07:57:39 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49656/tests/dromaeo/cssquery-prototype.html) 07:57:39 INFO - PROCESS | 7458 | RSS: Main: 220893184 07:57:39 INFO - PROCESS | 7458 | 08:00:42 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49656/tests/dromaeo/cssquery-yui.html) 08:00:43 INFO - PROCESS | 7458 | RSS: Main: 223342592 08:00:43 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:49656/tests/dromaeo/cssquery-yui.html (next: http://localhost:49656/tests/dromaeo/cssquery-dojo.html) 08:03:46 INFO - PROCESS | 7458 | RSS: Main: 313630720 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | __start_tp_report 08:03:46 INFO - PROCESS | 7458 | _x_x_mozilla_page_load 08:03:46 INFO - PROCESS | 7458 | _x_x_mozilla_page_load_details 08:03:46 INFO - PROCESS | 7458 | |i|pagename|runs| 08:03:46 INFO - PROCESS | 7458 | |0;dojo.html;2596.2730435478065;2628.855412952289;2657.3888005758677;2659.1623638553856;2671.452352267784;4670.065986802636;4687.56238770214;4699.835505757299;4793.664443488953;4831.5168483151665;4717.080169366966;4797.496262892399;4821.30091136785;4835.879103022417;4948.48040955699;4386.649068074562;4418.80115394808;4441.622462090726;4489.236829738936;4508.571685689871;4479.641628669699;4488.3043128315;4497.10057988402;4554.2713165893465;4559.566841150102;4518.774061296938;4526.660500462462;4527.524609915944;4536.818527258905;4583.908321833561;4358.455193100863;4385.517593064792;4393.275109606911;4398.780060996937;4452.510223875371;4423.712458690175;4427.933580996288;4432.202203603382;4432.312991486325;4433.312836510346;4764.285357196413;4809.687370320918;4824.324594556765;4825.131476334259;4828.251620998751;5161.148410512261;5165.767540460688;5173.508516690908;5173.534381905646;5175.404828444739;5050.267711181895;5152.098382782998;5165.354330708661;5188.11801993662;5191.169412893956;4816.95183048172;4821.95178048215;4822.083804077214;4861.80552777892;4865.075635629254;5226.686398816083;5271.209318602164;5271.472852714698;5279;5281.630285880028;4912.164931961575;4957.628177886673;4960.479149689303;5027.4721154279005;5060.240963855377;5172.922406163911;5225.399079105951;5235;5267.499587539178;5283.365996080495;5123.103456895028;5131.589472842164;5138.58891288696;5174.611904107207;5175;4953.405591329064;4986.875328116754;5128.692278463304;5130.948690513122;5131.384233891957;4380.299152135707;4394.12117576486;4415.4259946206785;4418.80115394808;4492.550744925481;3801.201747632973;3825.062859599416;3826.3877779555696;3846.1922996170556;3858.15120673457;4414.161309351213;4421.46942366918;4427.734335939854;4461.687681862239;4497.505274419811;5042.294078828893;5052.242163675404;5074.416442109131;5078.974605127025;5081.28861959326;4470.307102399133;4477.8208871644765;4498.190325741332;4514.81940722374;4517.435320584927;4671.43942726875;4675.4155730533685;4677.672562920631;4679.3916790816975;4723.173444647173;4642;4873.707577545359;4875.0493653737185;4876.146674332049;4918.43438004634;5015.322931404359;5027.095122877917;5035.84892453227;5049.621278404061;5056.7471626419265;4761.500042495488;4771.475137734916;4781.354517140281;4815.15734746425;4841.491643377394;5015;5049.368828896388;5055.16589762685;5057.14028615129;5057.924131138109;4993.126202914403;5022.547970682656;5026.346574945233;5072.289879416959;5123.180291153398;4885.5358740919555;4952.5542701157065;4966.031623833318;4966.031623833318;4969.130402179532;4933.605311574994;4933.629977751755;4937.703737775745;4975.950240497694;5050.595952323879;4927.531884470969;4937.802487900443;4938.851834444972;4939.185034469416;4994.650374473752;5022.74886255693;5058.570021548209;5059.5952323814745;5062;5103.693778373309;4806.158922188533;4840.661153719347;4882.072406242944;4890.779914903909;4931.50684931504 08:03:46 INFO - PROCESS | 7458 | |1;ext.html;527.2565682387833;544.7793643574352;553.5986409852857;554.6921458590481;556.9693666848324;27643.447131057394;36875.815620921894;38132.61867381329;38277.42583861244;42120.36819447711;38447.80776096122;38554;38593.61406385935;38880.61119388812;38894.61105388945;7182.066331376914;7502.624868756568;7683.884741728851;7684.884726729104;7686.730964416247;7194.928050719504;9414;9653.565589548452;9673.452247640378;9953.552090155961;9923.057309555617;9986.400815951067;9992.300538962276;9999.500024998757;10006.249531285146;9871;9962.750931226705;9965.651202207888;9973.102420782128;9977.451240181786;9813.754656133653;9874.851877221776;9876.753081172956;9901.702948911545;9908.554115064768;36741;37123.62876371256;37201;37211.627883721085;37298.254034919424;2811.226912599031;2896.189067061226;2918.9270268243254;3224.4357237483346;3504.351694936445;28100.156995290174;28306.575401368995;28386.432271354664;28993.85503072471;29759.404811903856;3459.2043829919257;3487.5814902211896;3493.074335301147;3530.6469353064745;3544.769589976643;238452;240187;240628;240899;241069.99999999825;50453.49546504561;50631.74684126629;50754;50803.74598127059;50808.49191508112;52165;52311.47688523066;52350.21474677883;52358.73820630873;52386.21420678693;12030.338331391682;12374.195677280948;12388;12403;12405.937970310091;9363.204127649089;9518.857217141609;9610.95194524037;9803.411795292306;9913.900860991442;14797.778033329509;15003.17482538449;15090.24548772557;15247.695046099016;15398.15310157952;5837;5888.322842873039;5892.587518873724;5973.492253158443;6122.785702500391;765.3379826450192;842.8609279468819;845.7970087179156;859.2867919626694;970.8786401699788;25606.48787024249;27686;27706.722932770415;28500.429991400055;28507.857460712978;893.883795106632;928.2805825485303;957.1624828275259;963.9228861691048;1018.974525636865;13892.052834562268;14393.208373539346;14692.41230350774;14698;14746.705065898623;355524;356032;359432;360306;360603;48037.519624804;48302.516974830505;48312.27531587029;48317;48447.75776121167;1121.1087185687397;1140.1278022312938;1158.6002829023973;1164.4876254448186;1175.5474142455046;25496.49007019812;26607;26964.191074267805;27719.584206237327;27808.582871257353;41677.583224168586;42058.7897060525;42069.57930420656;42070.36894446647;42130;318361;320863;321016;321060;321529;29908.700912991462;30652.314122723463;31496.685033149377;34093.31813363769;35058.82470587631;39958.60041399549;41055;41134;41289.79355103205;41295.58704412918;33340.83329583336;33820.32359352848;33863.32273354568;34169;34550.65449345474;33861;34718;34725;34763.30473390569;35175.361847861;34188.31623367569;34609.826950865085;34826.303473930886;35001.124971875906;35003.299934000665;29459;31551;31628.683713162573;31955.6804431962;32027.35945281128;24416.63375049411;24674.753252467246;24699.506009879344;24706.933346568498;24917.53109971776 08:03:46 INFO - PROCESS | 7458 | |2;jquery.html;338.8567311753162;364.1715098151705;366.2437067455702;366.94128939369705;391.0790089339605;12478.376081195938;15842.92078539607;16198.595035124114;16529.090900000494;17412.738808917846;16113.91943040286;16261.918690406532;16418.425355112544;16518.17409129545;16605;3946.48695669564;4879.072976134541;5159.019786240621;5163.173892177252;5201.427842937273;5033.949660503385;5080.644354895159;5120.283160357534;5124.795008199668;5127.871803204931;4896.902061958759;5027.748612569375;5064.746762661871;5092.541671249587;5100.668456550312;5028.8239911603105;5053.797848086072;5061.595072394218;5065.544101030925;5065.924011139836;4356.4336636237085;4507.864764057083;4518.3222516622445;4521.118381915528;4575.908481830378;19165.80834191668;19306.71039934402;19971.700424493643;19987;20261.8986905066;1821.317006122704;1921.702136168896;2108.39910625471;2191.561687662458;2352.094443639194;8767.649294028231;9315.487648179347;12466.065045121652;13073.738525229537;13743.175409475463;1676.1619190404797;1720.232690408015;1723.8534724548429;1749.8862573932654;1771.8051014388407;62878;64654.67672661653;65636.6718166406;66339;67467;9670.661526846527;9869.457179855177;9870.802583948425;10060.647877324258;10081.596736130472;13249.138805977773;13262.93368533151;13283;13287.06990510655;13297.468101276034;6605.141331626858;6699.794037073373;6700.296468870796;6836.863262734718;6853.554518956252;6242.313345531988;6255.874882502325;6382.393672601095;6398.776042838572;6409.711562979677;4125.23683118624;4183.163367326547;4188.560201178893;4230.576942305806;4303.63419109373;1620.3599578166559;1744.1366523570914;1771.7430972509171;1786.5890845105698;1854.3880519428826;372.7390826421516;373.16783572632636;387.065237451557;688.9517733758689;794.4875555266821;10114.848277275847;10379.792404152025;10384.37693738378;10401.635942741968;10420.791584168273;411.4815332680844;417.99164016719493;421.1787015320138;429.23167530120736;432.38169417732945;5544.63959842609;5651.8304450866535;5666.631668941588;5678.5741069419955;5719;357510;368171;369734;371379;371561;72053;76509.23490765132;77694.22305776984;78123.5782600652;78948;371.8122348214189;406.95726948669966;415.02883253187684;415.8627652874544;416.0223474834116;7710.691572337044;9757.853632195382;9827.508624568885;10050.145737612385;10537.262391632512;36601.08497287589;37319.813400933905;37615.81192094022;38283.42574861435;38355.42466863055;50429.74785126051;51675;51736.74131629318;51807.48192518027;52426.737866310425;6216.689165541795;6685.6322902240845;6788.524803263724;6985.720571177096;7425.962870185831;9084.364094513321;10512.42181680015;10772.192085593768;10792.136629069499;11628.476718547516;9262;9607.279454040836;9675.097470758885;9983.60065597368;10100.949495252476;9478.85781713261;9612.279079068958;9644.614215431304;9722.1736152425;10425.165986720893;9195.310351723514;9263.629454821732;9366.859497107685;10316.43259620728;10648;6646.634435106115;6741.52809303344;6792.694328755119;6808.557443766139;6953.721851126101;9036.231920286586;9979.322125326122;10033.44816035125;10213.540390682288;10332.62632069298 08:03:46 INFO - PROCESS | 7458 | |3;mootools.html;1351.418889877353;1358.4769863602512;1470.1913947328974;1677.1278934953832;1695.0338307164914;7133.179684336303;7543.924560754391;7554.282343177396;7555.622218889061;7559.886601700965;6382.297947225813;6525.510586706004;7136.1436627604635;7326.084239470067;7424.146223184335;2092.8325733941247;2131.030381176569;2133.615949129156;2134.3703607435846;2137.6152292587326;2058.320754151134;2097.265956915083;2103.2533450625037;2105.157936825267;2109.440998135495;2074.2740040985686;2111.060578042772;2111.356036408895;2125.4580082079046;2130.8401869859745;1995.5909038647042;2024.210557882427;2024.7671517775498;2028.29009846554;2037.6128535578193;2066.4214020074405;2066.927657531994;2077.792220777925;2081.1259271106173;2087.1859974609915;7213.963930180368;7290.380317673004;7346.118465784087;7350.4119670426235;7433.553986760811;4374.540673229329;4501.099780043972;4501.3022981437925;4508.120916421299;4516.412866327357;2822.6189464422246;2924.4735947529434;2940.206144341016;2953.5569664550276;2967.4361871244396;837.9622916968751;889.5685592487638;890.6081324217324;899.2985471332371;964.7202311329706;10090.545925433373;10151.94924025375;10161.847572286422;10162.237832162617;10168.13570846479;3986.9068738911724;4167.312393455046;4245.808938597709;4248.1078973415315;4250.51119121305;8605.569721513899;8618.39671223021;8625.525596092275;8635.395522313504;8637.870431943526;4478.507364189936;4560.201964656172;4573.702709323883;4575.084983003413;4575.3823233863;3734.066483379155;3817.8091095445116;3974.3839704845795;4142.896427589334;4147.232761939051;3418.1967237699123;3536.328097661436;3613.674769270726;3624.6919011884283;3689.151495156129;1174.7356844709873;1199.3688583526398;1209.9274043557414;1217.6468824040928;1218.713602303458;671.4024518178825;828.6850996621246;831.1314676163423;849.0448245723561;870.2515836380708;4813.229883218668;4857.562819346276;5014.09746245672;5031.572316353077;5330.706811125426;395.33188910740756;405.2806268872716;408.29161404962366;410.1980627872502;414.20265987973033;3266.44470440021;3291.637919828817;3304.5538852255117;3366.5286859839644;3414.8634054637537;8732.083131271253;8980.820383592292;9023.593938272794;9028.09719028092;9079.137481939206;8736.082711315348;8884.289256859436;8950;8990.640374384951;8992.775180620536;6938.5836849789175;6940.722371105099;7040.155181378267;7044.859102817915;7058.576485410892;4874.098291816025;4901.191303434894;4923.08922849274;4958.900821983612;4997.700137991732;7982.9600851995365;8190.549519776469;8263.462874913112;8287.668493260202;8364.498130112213;8311.875321870057;8364.247217750431;8429.915700842914;8432.283255923316;8519.787005324917;5902.350741418355;6068.180795592539;6350.650714210763;6515.348465153446;6534.934650653433;6767.086443330088;7019.403350715246;7022.12223472066;7172.959920811349;7173.892391614026;6398.232212134388;6451.645159516084;6477.967610161919;6657.700403481758;6689.76585819494;6374.394432528902;6436.656346749226;6450.41946224842;6530.771423000172;6533.575317604341;6083.574149809471;6195.256569211723;6196.1015652731;6197.318294987638;6202.891017054223;4315.331123675835;4430.468343798765;4468.4637843458995;4527.479339875957;4548.56788605082;4350.695451318377;4370.715903466392;4398.032432864765;4493.59557639814;4539.863804085882 08:03:46 INFO - PROCESS | 7458 | |4;prototype.html;328.56465183631684;335.93113411750596;341.23563218390797;343.3647751659429;350.6686181558427;4809.543093406125;4818.759062046893;4822.421309442868;4828.999999999995;4860.465348811628;4763.737994410306;4769.451513075997;4777.6177905767545;4778.474367819537;4787.13831510328;3643.544556930377;3715.089802998269;3761.661450469457;3762.7554208976467;3796.9240615187678;3805.5052843130356;3806.9048273793237;3814.713896457763;3825.674817640504;3831.386978083507;3768.5854555998817;3771.396576547753;3791.848326066954;3792.601776813436;3807.847686092553;3615.9096022599383;3713.610070942539;3725.6646901778972;3779.8677046303383;3812.1422679897078;3420.504026916108;3421.9734079776217;3422.110251334671;3427.691507764313;3430.691237788611;4632.768361581908;4638.976805115986;4646.465656449484;4648.953510464886;4659.974805542775;3993.880183594497;4365.738055716667;4387.736735795863;4389.648828093745;4404.647628189737;4927.95072049282;4942.703437793708;4951.5543601075715;4974.825881094181;5014.971930754223;3494.8951531454095;3502.1594817243677;3510.596281427643;3535.4520049392386;3539.132912436444;5224.11931704586;5232.764525596357;5261.657992230493;5276.12943864262;5279.1817268323475;3475.011490118496;3589.2592092834952;3608.45873119029;3615.5661320641693;3617.408340330246;3656.939487548634;3658.70730341572;3667.211549516867;3670.9816450917574;3684.299983003221;4200.4959404871615;4216.536181020086;4217.553818785097;4217.806740494987;4222.902045468139;4754.976225118852;4944.802207911644;5020.322256495399;5052.823151189691;5062.670926389773;1190.9166358354832;1199.4362649554696;1202.7053371923935;1203.7291609387992;1204.4459548607738;800.6357107516067;826.5330088499978;827.4414770030205;835.9592307577105;839.1859895900966;4239.1521695660995;4314;4370.978145109254;4419.850838781876;4447.777611119431;5086.18621020635;5092.337996060489;5097.439281679012;5103.8468845934685;5106.4382917879;4373.584509471595;4383.736975781399;4389.6927215095275;4424.203643344164;4445.844395446208;2516.5218608464334;2640.419107796282;2644.1010056580853;2649.841009539434;2659.973400266011;4654.37165982588;4680.555347242007;4681.67228294023;4703.7883295251795;4705.717656940595;5125.308083408692;5177.559907407913;5189.506996835295;5190.883959948554;5195.402528709247;4467.687261891701;4699.201135806921;4715.669903106749;4733.644976626767;4757.571818536418;5006.899862002812;5009.77456014473;5023.2214006829;5035.29505869186;5040.14317566022;4913.410390753133;4925.38432695913;4927.901441971068;4928.753562321941;4937.617731853692;4438.334249862546;4673.4625518064495;4684.400157912255;4689.507601701908;4692.41344831896;3452.1197094740683;3455.6371580983637;3465.2722928185362;3474.7046501046684;3476.1309672581856;5005.299258103795;5011.772115831644;5028.245763135559;5038.48845346402;5054.3176671150395;4694.248920172756;4825.3485779419325;4860.92708609364;4879.56083952446;4903;4822.228443449031;4841.176999910094;4852.660313778002;4863.221884498464;4885.90228195427;4811.903761924672;4825.179719447632;4826.806927722992;4834.347363106076;4848.927266090941;4721.111555377876;4877.5366340197625;4910.386201724785;4910.779014944407;4952.826651067196;4761.523847615211;4762.238041913277;4774.928376074292;4780;4786.090642777861 08:03:46 INFO - PROCESS | 7458 | |5;yui.html;586.721307378995;596.627108057464;687.2989550658332;687.3161204601422;692.3734020711255;12771.489140434383;14094.295285235736;14219.928900355484;14266.785998210034;14334.13995160291;12204.267743935348;12340.753184936297;13782.448702051932;14348.928255358735;14470.059446135994;773.3465221887496;795.8647030004913;797.3979645367739;797.6410615223144;798.1738900238255;631.1164369882156;647.2782847125445;762.7177944160641;772.6600295869828;793.1037927142349;803.5901690138013;808.462372522272;808.7533302342783;808.9887640449438;809.9311558517504;798.8442253760519;802.0175285275549;802.8795680647892;803.4054799448367;803.7307501986842;486.2560282767336;491.1061867401331;586.182275725365;587.0167469488632;598.0729868703493;13781.862181378157;13888.652783680387;13888.93055534726;13889.861101388959;13891.305434728325;10997.285176463503;10997.84522625121;11370.31778093317;11596.246243994114;11815.409229538574;11114.722131946684;11149.386783726892;11267.380294083821;11542.07663386927;12108.394580270951;3819.656230939201;3843.423486477023;3877.836649005287;3955.0310174007263;3970.5235371755284;60410.69794651042;60951;62850.37149628491;63978.360216397705;64294.67852660754;9307.860382094274;9569.712908612753;9695.466749328714;9702.805943881083;10242.6415075472;18918.621627567372;19029.61940761205;19069.427917162506;19256.614867702567;19389.80610193908;6704.597724136567;6747.831304217434;6749.493787965922;6796.286389929085;7023.122109736283;3651.6165802590344;3682.8342724576924;3728.4034554471154;3734.775913445202;3736.308782875177;2938.250746059784;2980.7019298070018;3021.531662592302;3023.425549145655;3024.848757562157;492.68960554850213;508.506748453999;519.3689667054547;520.8307300127474;528.918017707256;469.3042291950887;531.9521243088141;549.0885130683047;580.0834681203688;590.2916500199777;7325.743598974011;7509.36170425509;7663.578503182267;7804.804879878048;7877.291043806085;482.09366391184346;506.1817320690296;533.8745394832212;562.0809975689695;563.5036787033399;3745;3824.9235015300096;3847.8076096195077;3866.3813789794062;3908.6091390861257;66373.34066648372;66709;67145.66427167832;69361.65319173371;69371.3062869375;46827.53172468232;50590;54933.450665493634;58760.11859822106;60383.69808151019;54171.18743218776;54966.725166373915;55118;55135.41619794701;55263;7210.4231661468;7282.089738782653;7393.630318483947;7609.6575654094595;7691.807704807425;35434.29131417409;35748.64251357557;37273.44089838709;37507.43738843974;37588.81205593955;45497;49627.75186124046;49942.25086623631;50679.493205067476;52773;9093.4998575079;9516.205815844816;9541.570629321835;9567.234621230424;9814.70555883296;10429.26995110335;10518.526666300188;10531.842022369823;10750.408727520062;11043.723906902393;9198.724038278862;9497.24022078246;9707;10034.69895903124;10269.794604107727;9527.857082143635;9595.856062158933;10176;10687.572497100029;10735.892641073802;10697.572097116028;10814.728906929633;11039.116870650489;11149.442527873736;11697.122715796178;8912.108789121296;8933.46399216049;9142.63429462814;9152.771180720536;9360.672376466791;8876.778080548038;9151.134178778873;9195.816083678155;9321.207697345802;9350.579223934803 08:03:46 INFO - PROCESS | 7458 | __end_tp_report 08:03:46 INFO - PROCESS | 7458 | __start_cc_report 08:03:46 INFO - PROCESS | 7458 | _x_x_mozilla_cycle_collect,1554 08:03:46 INFO - PROCESS | 7458 | __end_cc_report 08:03:46 INFO - PROCESS | 7458 | __startTimestamp1481558626970__endTimestamp 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | ------- Summary: start ------- 08:03:46 INFO - PROCESS | 7458 | Number of tests: 6 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | [#0] dojo.html Cycles:165 Average:4748.54 Median:4873.71 stddev:487.65 (10.0%) stddev-sans-first:458.99 08:03:46 INFO - PROCESS | 7458 | Values: 2596.3 2628.9 2657.4 2659.2 2671.5 4670.1 4687.6 4699.8 4793.7 4831.5 4717.1 4797.5 4821.3 4835.9 4948.5 4386.6 4418.8 4441.6 4489.2 4508.6 4479.6 4488.3 4497.1 4554.3 4559.6 4518.8 4526.7 4527.5 4536.8 4583.9 4358.5 4385.5 4393.3 4398.8 4452.5 4423.7 4427.9 4432.2 4432.3 4433.3 4764.3 4809.7 4824.3 4825.1 4828.3 5161.1 5165.8 5173.5 5173.5 5175.4 5050.3 5152.1 5165.4 5188.1 5191.2 4817.0 4822.0 4822.1 4861.8 4865.1 5226.7 5271.2 5271.5 5279.0 5281.6 4912.2 4957.6 4960.5 5027.5 5060.2 5172.9 5225.4 5235.0 5267.5 5283.4 5123.1 5131.6 5138.6 5174.6 5175.0 4953.4 4986.9 5128.7 5130.9 5131.4 4380.3 4394.1 4415.4 4418.8 4492.6 3801.2 3825.1 3826.4 3846.2 3858.2 4414.2 4421.5 4427.7 4461.7 4497.5 5042.3 5052.2 5074.4 5079.0 5081.3 4470.3 4477.8 4498.2 4514.8 4517.4 4671.4 4675.4 4677.7 4679.4 4723.2 4642.0 4873.7 4875.0 4876.1 4918.4 5015.3 5027.1 5035.8 5049.6 5056.7 4761.5 4771.5 4781.4 4815.2 4841.5 5015.0 5049.4 5055.2 5057.1 5057.9 4993.1 5022.5 5026.3 5072.3 5123.2 4885.5 4952.6 4966.0 4966.0 4969.1 4933.6 4933.6 4937.7 4976.0 5050.6 4927.5 4937.8 4938.9 4939.2 4994.7 5022.7 5058.6 5059.6 5062.0 5103.7 4806.2 4840.7 4882.1 4890.8 4931.5 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | [#1] ext.html Cycles:180 Average:45965.07 Median:359869.00 stddev:81512.90 (22.7%) stddev-sans-first:81670.17 08:03:46 INFO - PROCESS | 7458 | Values: 527.3 544.8 553.6 554.7 557.0 27643.4 36875.8 38132.6 38277.4 42120.4 38447.8 38554.0 38593.6 38880.6 38894.6 7182.1 7502.6 7683.9 7684.9 7686.7 7194.9 9414.0 9653.6 9673.5 9953.6 9923.1 9986.4 9992.3 9999.5 10006.2 9871.0 9962.8 9965.7 9973.1 9977.5 9813.8 9874.9 9876.8 9901.7 9908.6 36741.0 37123.6 37201.0 37211.6 37298.3 2811.2 2896.2 2918.9 3224.4 3504.4 28100.2 28306.6 28386.4 28993.9 29759.4 3459.2 3487.6 3493.1 3530.6 3544.8 238452.0 240187.0 240628.0 240899.0 241070.0 50453.5 50631.7 50754.0 50803.7 50808.5 52165.0 52311.5 52350.2 52358.7 52386.2 12030.3 12374.2 12388.0 12403.0 12405.9 9363.2 9518.9 9611.0 9803.4 9913.9 14797.8 15003.2 15090.2 15247.7 15398.2 5837.0 5888.3 5892.6 5973.5 6122.8 765.3 842.9 845.8 859.3 970.9 25606.5 27686.0 27706.7 28500.4 28507.9 893.9 928.3 957.2 963.9 1019.0 13892.1 14393.2 14692.4 14698.0 14746.7 355524.0 356032.0 359432.0 360306.0 360603.0 48037.5 48302.5 48312.3 48317.0 48447.8 1121.1 1140.1 1158.6 1164.5 1175.5 25496.5 26607.0 26964.2 27719.6 27808.6 41677.6 42058.8 42069.6 42070.4 42130.0 318361.0 320863.0 321016.0 321060.0 321529.0 29908.7 30652.3 31496.7 34093.3 35058.8 39958.6 41055.0 41134.0 41289.8 41295.6 33340.8 33820.3 33863.3 34169.0 34550.7 33861.0 34718.0 34725.0 34763.3 35175.4 34188.3 34609.8 34826.3 35001.1 35003.3 29459.0 31551.0 31628.7 31955.7 32027.4 24416.6 24674.8 24699.5 24706.9 24917.5 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | [#2] jquery.html Cycles:180 Average:22874.70 Median:2368.01 stddev:60949.27 (2573.9%) stddev-sans-first:61096.76 08:03:46 INFO - PROCESS | 7458 | Values: 338.9 364.2 366.2 366.9 391.1 12478.4 15842.9 16198.6 16529.1 17412.7 16113.9 16261.9 16418.4 16518.2 16605.0 3946.5 4879.1 5159.0 5163.2 5201.4 5033.9 5080.6 5120.3 5124.8 5127.9 4896.9 5027.7 5064.7 5092.5 5100.7 5028.8 5053.8 5061.6 5065.5 5065.9 4356.4 4507.9 4518.3 4521.1 4575.9 19165.8 19306.7 19971.7 19987.0 20261.9 1821.3 1921.7 2108.4 2191.6 2352.1 8767.6 9315.5 12466.1 13073.7 13743.2 1676.2 1720.2 1723.9 1749.9 1771.8 62878.0 64654.7 65636.7 66339.0 67467.0 9670.7 9869.5 9870.8 10060.6 10081.6 13249.1 13262.9 13283.0 13287.1 13297.5 6605.1 6699.8 6700.3 6836.9 6853.6 6242.3 6255.9 6382.4 6398.8 6409.7 4125.2 4183.2 4188.6 4230.6 4303.6 1620.4 1744.1 1771.7 1786.6 1854.4 372.7 373.2 387.1 689.0 794.5 10114.8 10379.8 10384.4 10401.6 10420.8 411.5 418.0 421.2 429.2 432.4 5544.6 5651.8 5666.6 5678.6 5719.0 357510.0 368171.0 369734.0 371379.0 371561.0 72053.0 76509.2 77694.2 78123.6 78948.0 371.8 407.0 415.0 415.9 416.0 7710.7 9757.9 9827.5 10050.1 10537.3 36601.1 37319.8 37615.8 38283.4 38355.4 50429.7 51675.0 51736.7 51807.5 52426.7 6216.7 6685.6 6788.5 6985.7 7426.0 9084.4 10512.4 10772.2 10792.1 11628.5 9262.0 9607.3 9675.1 9983.6 10100.9 9478.9 9612.3 9644.6 9722.2 10425.2 9195.3 9263.6 9366.9 10316.4 10648.0 6646.6 6741.5 6792.7 6808.6 6953.7 9036.2 9979.3 10033.4 10213.5 10332.6 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | [#3] mootools.html Cycles:180 Average:4878.04 Median:4835.40 stddev:2695.75 (55.8%) stddev-sans-first:2690.29 08:03:46 INFO - PROCESS | 7458 | Values: 1351.4 1358.5 1470.2 1677.1 1695.0 7133.2 7543.9 7554.3 7555.6 7559.9 6382.3 6525.5 7136.1 7326.1 7424.1 2092.8 2131.0 2133.6 2134.4 2137.6 2058.3 2097.3 2103.3 2105.2 2109.4 2074.3 2111.1 2111.4 2125.5 2130.8 1995.6 2024.2 2024.8 2028.3 2037.6 2066.4 2066.9 2077.8 2081.1 2087.2 7214.0 7290.4 7346.1 7350.4 7433.6 4374.5 4501.1 4501.3 4508.1 4516.4 2822.6 2924.5 2940.2 2953.6 2967.4 838.0 889.6 890.6 899.3 964.7 10090.5 10151.9 10161.8 10162.2 10168.1 3986.9 4167.3 4245.8 4248.1 4250.5 8605.6 8618.4 8625.5 8635.4 8637.9 4478.5 4560.2 4573.7 4575.1 4575.4 3734.1 3817.8 3974.4 4142.9 4147.2 3418.2 3536.3 3613.7 3624.7 3689.2 1174.7 1199.4 1209.9 1217.6 1218.7 671.4 828.7 831.1 849.0 870.3 4813.2 4857.6 5014.1 5031.6 5330.7 395.3 405.3 408.3 410.2 414.2 3266.4 3291.6 3304.6 3366.5 3414.9 8732.1 8980.8 9023.6 9028.1 9079.1 8736.1 8884.3 8950.0 8990.6 8992.8 6938.6 6940.7 7040.2 7044.9 7058.6 4874.1 4901.2 4923.1 4958.9 4997.7 7983.0 8190.5 8263.5 8287.7 8364.5 8311.9 8364.2 8429.9 8432.3 8519.8 5902.4 6068.2 6350.7 6515.3 6534.9 6767.1 7019.4 7022.1 7173.0 7173.9 6398.2 6451.6 6478.0 6657.7 6689.8 6374.4 6436.7 6450.4 6530.8 6533.6 6083.6 6195.3 6196.1 6197.3 6202.9 4315.3 4430.5 4468.5 4527.5 4548.6 4350.7 4370.7 4398.0 4493.6 4539.9 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | [#4] prototype.html Cycles:180 Average:4106.62 Median:4683.04 stddev:1183.83 (25.3%) stddev-sans-first:1152.69 08:03:46 INFO - PROCESS | 7458 | Values: 328.6 335.9 341.2 343.4 350.7 4809.5 4818.8 4822.4 4829.0 4860.5 4763.7 4769.5 4777.6 4778.5 4787.1 3643.5 3715.1 3761.7 3762.8 3796.9 3805.5 3806.9 3814.7 3825.7 3831.4 3768.6 3771.4 3791.8 3792.6 3807.8 3615.9 3713.6 3725.7 3779.9 3812.1 3420.5 3422.0 3422.1 3427.7 3430.7 4632.8 4639.0 4646.5 4649.0 4660.0 3993.9 4365.7 4387.7 4389.6 4404.6 4928.0 4942.7 4951.6 4974.8 5015.0 3494.9 3502.2 3510.6 3535.5 3539.1 5224.1 5232.8 5261.7 5276.1 5279.2 3475.0 3589.3 3608.5 3615.6 3617.4 3656.9 3658.7 3667.2 3671.0 3684.3 4200.5 4216.5 4217.6 4217.8 4222.9 4755.0 4944.8 5020.3 5052.8 5062.7 1190.9 1199.4 1202.7 1203.7 1204.4 800.6 826.5 827.4 836.0 839.2 4239.2 4314.0 4371.0 4419.9 4447.8 5086.2 5092.3 5097.4 5103.8 5106.4 4373.6 4383.7 4389.7 4424.2 4445.8 2516.5 2640.4 2644.1 2649.8 2660.0 4654.4 4680.6 4681.7 4703.8 4705.7 5125.3 5177.6 5189.5 5190.9 5195.4 4467.7 4699.2 4715.7 4733.6 4757.6 5006.9 5009.8 5023.2 5035.3 5040.1 4913.4 4925.4 4927.9 4928.8 4937.6 4438.3 4673.5 4684.4 4689.5 4692.4 3452.1 3455.6 3465.3 3474.7 3476.1 5005.3 5011.8 5028.2 5038.5 5054.3 4694.2 4825.3 4860.9 4879.6 4903.0 4822.2 4841.2 4852.7 4863.2 4885.9 4811.9 4825.2 4826.8 4834.3 4848.9 4721.1 4877.5 4910.4 4910.8 4952.8 4761.5 4762.2 4774.9 4780.0 4786.1 08:03:46 INFO - PROCESS | 7458 | 08:03:46 INFO - PROCESS | 7458 | [#5] yui.html Cycles:180 Average:14734.14 Median:27360.14 stddev:18837.58 (68.9%) stddev-sans-first:18860.46 08:03:46 INFO - PROCESS | 7458 | Values: 586.7 596.6 687.3 687.3 692.4 12771.5 14094.3 14219.9 14266.8 14334.1 12204.3 12340.8 13782.4 14348.9 14470.1 773.3 795.9 797.4 797.6 798.2 631.1 647.3 762.7 772.7 793.1 803.6 808.5 808.8 809.0 809.9 798.8 802.0 802.9 803.4 803.7 486.3 491.1 586.2 587.0 598.1 13781.9 13888.7 13888.9 13889.9 13891.3 10997.3 10997.8 11370.3 11596.2 11815.4 11114.7 11149.4 11267.4 11542.1 12108.4 3819.7 3843.4 3877.8 3955.0 3970.5 60410.7 60951.0 62850.4 63978.4 64294.7 9307.9 9569.7 9695.5 9702.8 10242.6 18918.6 19029.6 19069.4 19256.6 19389.8 6704.6 6747.8 6749.5 6796.3 7023.1 3651.6 3682.8 3728.4 3734.8 3736.3 2938.3 2980.7 3021.5 3023.4 3024.8 492.7 508.5 519.4 520.8 528.9 469.3 532.0 549.1 580.1 590.3 7325.7 7509.4 7663.6 7804.8 7877.3 482.1 506.2 533.9 562.1 563.5 3745.0 3824.9 3847.8 3866.4 3908.6 66373.3 66709.0 67145.7 69361.7 69371.3 46827.5 50590.0 54933.5 58760.1 60383.7 54171.2 54966.7 55118.0 55135.4 55263.0 7210.4 7282.1 7393.6 7609.7 7691.8 35434.3 35748.6 37273.4 37507.4 37588.8 45497.0 49627.8 49942.3 50679.5 52773.0 9093.5 9516.2 9541.6 9567.2 9814.7 10429.3 10518.5 10531.8 10750.4 11043.7 9198.7 9497.2 9707.0 10034.7 10269.8 9527.9 9595.9 10176.0 10687.6 10735.9 10697.6 10814.7 11039.1 11149.4 11697.1 8912.1 8933.5 9142.6 9152.8 9360.7 8876.8 9151.1 9195.8 9321.2 9350.6 08:03:46 INFO - PROCESS | 7458 | -------- Summary: end -------- 08:03:46 INFO - PROCESS | 7458 | 08:03:47 INFO - TEST-INFO | 7458: exit 0 08:03:47 INFO - TEST-OK | dromaeo_css | took 1124523ms 08:03:47 INFO - TEST-START | kraken 08:03:47 INFO - operating with platform_type : linux_ 08:03:47 INFO - Initialising browser for kraken test... 08:03:47 INFO - TEST-INFO | started process 7760 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphN095j/profile http://localhost:49656/getInfo.html) 08:03:52 INFO - PROCESS | 7760 | __metrics Screen width/height:1600/1200 08:03:52 INFO - PROCESS | 7760 | colorDepth:24 08:03:52 INFO - PROCESS | 7760 | Browser inner width/height: 1024/697 08:03:52 INFO - PROCESS | 7760 | __metrics 08:03:52 INFO - PROCESS | 7760 | 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" 08:03:52 INFO - PROCESS | 7760 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:03:54 INFO - TEST-INFO | 7760: exit 0 08:03:54 INFO - Browser initialized. 08:03:54 INFO - Running cycle 1/1 for kraken test... 08:03:54 INFO - TEST-INFO | started process 7843 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphN095j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:04:06 INFO - PROCESS | 7843 | RSS: Main: 173395968 08:04:06 INFO - PROCESS | 7843 | 08:04:09 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-beat-detection) 08:04:10 INFO - PROCESS | 7843 | RSS: Main: 240021504 08:04:10 INFO - PROCESS | 7843 | 08:04:13 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-dft) 08:04:13 INFO - PROCESS | 7843 | RSS: Main: 217739264 08:04:13 INFO - PROCESS | 7843 | 08:04:16 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-fft) 08:04:17 INFO - PROCESS | 7843 | RSS: Main: 301481984 08:04:17 INFO - PROCESS | 7843 | 08:04:19 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49656/tests/kraken/driver.html?testName=audio-oscillator) 08:04:20 INFO - PROCESS | 7843 | RSS: Main: 210124800 08:04:20 INFO - PROCESS | 7843 | 08:04:22 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49656/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:04:23 INFO - PROCESS | 7843 | RSS: Main: 214872064 08:04:23 INFO - PROCESS | 7843 | 08:04:26 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49656/tests/kraken/driver.html?testName=imaging-darkroom) 08:04:27 INFO - PROCESS | 7843 | RSS: Main: 255078400 08:04:27 INFO - PROCESS | 7843 | 08:04:30 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49656/tests/kraken/driver.html?testName=imaging-desaturate) 08:04:31 INFO - PROCESS | 7843 | RSS: Main: 247922688 08:04:31 INFO - PROCESS | 7843 | 08:04:34 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49656/tests/kraken/driver.html?testName=json-parse-financial) 08:04:34 INFO - PROCESS | 7843 | RSS: Main: 249962496 08:04:34 INFO - PROCESS | 7843 | 08:04:36 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49656/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:04:37 INFO - PROCESS | 7843 | RSS: Main: 212918272 08:04:37 INFO - PROCESS | 7843 | 08:04:39 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:04:39 INFO - PROCESS | 7843 | RSS: Main: 220344320 08:04:39 INFO - PROCESS | 7843 | 08:04:41 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:04:42 INFO - PROCESS | 7843 | RSS: Main: 229605376 08:04:42 INFO - PROCESS | 7843 | 08:04:45 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:04:46 INFO - PROCESS | 7843 | RSS: Main: 238395392 08:04:46 INFO - PROCESS | 7843 | 08:04:49 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:04:49 INFO - PROCESS | 7843 | RSS: Main: 234729472 08:04:49 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | Cycle 1(1): loaded http://localhost:49656/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49656/tests/kraken/driver.html?testName=ai-astar) 08:04:51 INFO - PROCESS | 7843 | RSS: Main: 287391744 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | __start_tp_report 08:04:51 INFO - PROCESS | 7843 | _x_x_mozilla_page_load 08:04:51 INFO - PROCESS | 7843 | _x_x_mozilla_page_load_details 08:04:51 INFO - PROCESS | 7843 | |i|pagename|runs| 08:04:51 INFO - PROCESS | 7843 | |0;ai-astar;109;116;115;117;115;117;115;115;115;114 08:04:51 INFO - PROCESS | 7843 | |1;audio-beat-detection;151;129;130;174;131;136;134;128;155;127 08:04:51 INFO - PROCESS | 7843 | |2;audio-dft;161;160;162;160;161;161;161;160;161;160 08:04:51 INFO - PROCESS | 7843 | |3;audio-fft;76;76;77;110;78;87;77;75;104;78 08:04:51 INFO - PROCESS | 7843 | |4;audio-oscillator;84;85;124;84;85;84;106;85;85;108 08:04:51 INFO - PROCESS | 7843 | |5;imaging-gaussian-blur;127;126;128;128;128;128;127;128;127;127 08:04:51 INFO - PROCESS | 7843 | |6;imaging-darkroom;147;146;148;147;147;147;148;146;146;146 08:04:51 INFO - PROCESS | 7843 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 08:04:51 INFO - PROCESS | 7843 | |8;json-parse-financial;66;63;71;64;65;65;65;65;65;65 08:04:51 INFO - PROCESS | 7843 | |9;json-stringify-tinderbox;66;65;65;66;66;66;66;67;66;66 08:04:51 INFO - PROCESS | 7843 | |10;stanford-crypto-aes;86;89;86;85;88;91;84;101;85;85 08:04:51 INFO - PROCESS | 7843 | |11;stanford-crypto-ccm;150;149;140;146;140;157;141;137;181;153 08:04:51 INFO - PROCESS | 7843 | |12;stanford-crypto-pbkdf2;181;181;182;190;186;181;180;182;182;179 08:04:51 INFO - PROCESS | 7843 | |13;stanford-crypto-sha256-iterative;69;70;70;68;69;68;68;68;68;68 08:04:51 INFO - PROCESS | 7843 | __end_tp_report 08:04:51 INFO - PROCESS | 7843 | __start_cc_report 08:04:51 INFO - PROCESS | 7843 | _x_x_mozilla_cycle_collect,1939 08:04:51 INFO - PROCESS | 7843 | __end_cc_report 08:04:51 INFO - PROCESS | 7843 | __startTimestamp1481558691860__endTimestamp 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | ------- Summary: start ------- 08:04:51 INFO - PROCESS | 7843 | Number of tests: 14 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:2.25 (2.0%) stddev-sans-first:1.01 08:04:51 INFO - PROCESS | 7843 | Values: 109.0 116.0 115.0 117.0 115.0 117.0 115.0 115.0 115.0 114.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:135.00 stddev:15.52 (11.5%) stddev-sans-first:15.89 08:04:51 INFO - PROCESS | 7843 | Values: 151.0 129.0 130.0 174.0 131.0 136.0 134.0 128.0 155.0 127.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 08:04:51 INFO - PROCESS | 7843 | Values: 161.0 160.0 162.0 160.0 161.0 161.0 161.0 160.0 161.0 160.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#3] audio-fft Cycles:10 Average:83.80 Median:77.00 stddev:12.75 (16.6%) stddev-sans-first:13.21 08:04:51 INFO - PROCESS | 7843 | Values: 76.0 76.0 77.0 110.0 78.0 87.0 77.0 75.0 104.0 78.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:84.50 stddev:14.35 (17.0%) stddev-sans-first:14.85 08:04:51 INFO - PROCESS | 7843 | Values: 84.0 85.0 124.0 84.0 85.0 84.0 106.0 85.0 85.0 108.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#5] imaging-gaussian-blur Cycles:10 Average:127.40 Median:128.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 08:04:51 INFO - PROCESS | 7843 | Values: 127.0 126.0 128.0 128.0 128.0 128.0 127.0 128.0 127.0 127.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 08:04:51 INFO - PROCESS | 7843 | Values: 147.0 146.0 148.0 147.0 147.0 147.0 148.0 146.0 146.0 146.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:04:51 INFO - PROCESS | 7843 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#8] json-parse-financial Cycles:10 Average:65.40 Median:65.00 stddev:2.12 (3.3%) stddev-sans-first:2.24 08:04:51 INFO - PROCESS | 7843 | Values: 66.0 63.0 71.0 64.0 65.0 65.0 65.0 65.0 65.0 65.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 08:04:51 INFO - PROCESS | 7843 | Values: 66.0 65.0 65.0 66.0 66.0 66.0 66.0 67.0 66.0 66.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:86.00 stddev:5.06 (5.9%) stddev-sans-first:5.31 08:04:51 INFO - PROCESS | 7843 | Values: 86.0 89.0 86.0 85.0 88.0 91.0 84.0 101.0 85.0 85.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:149.50 stddev:12.83 (8.6%) stddev-sans-first:13.61 08:04:51 INFO - PROCESS | 7843 | Values: 150.0 149.0 140.0 146.0 140.0 157.0 141.0 137.0 181.0 153.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.40 Median:182.00 stddev:3.24 (1.8%) stddev-sans-first:3.40 08:04:51 INFO - PROCESS | 7843 | Values: 181.0 181.0 182.0 190.0 186.0 181.0 180.0 182.0 182.0 179.0 08:04:51 INFO - PROCESS | 7843 | 08:04:51 INFO - PROCESS | 7843 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 08:04:51 INFO - PROCESS | 7843 | Values: 69.0 70.0 70.0 68.0 69.0 68.0 68.0 68.0 68.0 68.0 08:04:51 INFO - PROCESS | 7843 | -------- Summary: end -------- 08:04:51 INFO - PROCESS | 7843 | 08:04:53 INFO - TEST-INFO | 7843: exit 0 08:04:53 INFO - TEST-OK | kraken | took 65206ms 08:04:53 INFO - SUITE-END | took 1189s 08:04:53 INFO - Completed test suite (00:19:50) 08:04:53 INFO - javascript benchmark 08:04:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2596.2730435478065, 2628.855412952289, 2657.3888005758677, 2659.1623638553856, 2671.452352267784, 4670.065986802636, 4687.56238770214, 4699.835505757299, 4793.664443488953, 4831.5168483151665, 4717.080169366966, 4797.496262892399, 4821.30091136785, 4835.879103022417, 4948.48040955699, 4386.649068074562, 4418.80115394808, 4441.622462090726, 4489.236829738936, 4508.571685689871, 4479.641628669699, 4488.3043128315, 4497.10057988402, 4554.2713165893465, 4559.566841150102, 4518.774061296938, 4526.660500462462, 4527.524609915944, 4536.818527258905, 4583.908321833561, 4358.455193100863, 4385.517593064792, 4393.275109606911, 4398.780060996937, 4452.510223875371, 4423.712458690175, 4427.933580996288, 4432.202203603382, 4432.312991486325, 4433.312836510346, 4764.285357196413, 4809.687370320918, 4824.324594556765, 4825.131476334259, 4828.251620998751, 5161.148410512261, 5165.767540460688, 5173.508516690908, 5173.534381905646, 5175.404828444739, 5050.267711181895, 5152.098382782998, 5165.354330708661, 5188.11801993662, 5191.169412893956, 4816.95183048172, 4821.95178048215, 4822.083804077214, 4861.80552777892, 4865.075635629254, 5226.686398816083, 5271.209318602164, 5271.472852714698, 5279.0, 5281.630285880028, 4912.164931961575, 4957.628177886673, 4960.479149689303, 5027.4721154279005, 5060.240963855377, 5172.922406163911, 5225.399079105951, 5235.0, 5267.499587539178, 5283.365996080495, 5123.103456895028, 5131.589472842164, 5138.58891288696, 5174.611904107207, 5175.0, 4953.405591329064, 4986.875328116754, 5128.692278463304, 5130.948690513122, 5131.384233891957, 4380.299152135707, 4394.12117576486, 4415.4259946206785, 4418.80115394808, 4492.550744925481, 3801.201747632973, 3825.062859599416, 3826.3877779555696, 3846.1922996170556, 3858.15120673457, 4414.161309351213, 4421.46942366918, 4427.734335939854, 4461.687681862239, 4497.505274419811, 5042.294078828893, 5052.242163675404, 5074.416442109131, 5078.974605127025, 5081.28861959326, 4470.307102399133, 4477.8208871644765, 4498.190325741332, 4514.81940722374, 4517.435320584927, 4671.43942726875, 4675.4155730533685, 4677.672562920631, 4679.3916790816975, 4723.173444647173, 4642.0, 4873.707577545359, 4875.0493653737185, 4876.146674332049, 4918.43438004634, 5015.322931404359, 5027.095122877917, 5035.84892453227, 5049.621278404061, 5056.7471626419265, 4761.500042495488, 4771.475137734916, 4781.354517140281, 4815.15734746425, 4841.491643377394, 5015.0, 5049.368828896388, 5055.16589762685, 5057.14028615129, 5057.924131138109, 4993.126202914403, 5022.547970682656, 5026.346574945233, 5072.289879416959, 5123.180291153398, 4885.5358740919555, 4952.5542701157065, 4966.031623833318, 4966.031623833318, 4969.130402179532, 4933.605311574994, 4933.629977751755, 4937.703737775745, 4975.950240497694, 5050.595952323879, 4927.531884470969, 4937.802487900443, 4938.851834444972, 4939.185034469416, 4994.650374473752, 5022.74886255693, 5058.570021548209, 5059.5952323814745, 5062.0, 5103.693778373309, 4806.158922188533, 4840.661153719347, 4882.072406242944, 4890.779914903909, 4931.50684931504], "value": 4717.350751953234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.2565682387833, 544.7793643574352, 553.5986409852857, 554.6921458590481, 556.9693666848324, 27643.447131057394, 36875.815620921894, 38132.61867381329, 38277.42583861244, 42120.36819447711, 38447.80776096122, 38554.0, 38593.61406385935, 38880.61119388812, 38894.61105388945, 7182.066331376914, 7502.624868756568, 7683.884741728851, 7684.884726729104, 7686.730964416247, 7194.928050719504, 9414.0, 9653.565589548452, 9673.452247640378, 9953.552090155961, 9923.057309555617, 9986.400815951067, 9992.300538962276, 9999.500024998757, 10006.249531285146, 9871.0, 9962.750931226705, 9965.651202207888, 9973.102420782128, 9977.451240181786, 9813.754656133653, 9874.851877221776, 9876.753081172956, 9901.702948911545, 9908.554115064768, 36741.0, 37123.62876371256, 37201.0, 37211.627883721085, 37298.254034919424, 2811.226912599031, 2896.189067061226, 2918.9270268243254, 3224.4357237483346, 3504.351694936445, 28100.156995290174, 28306.575401368995, 28386.432271354664, 28993.85503072471, 29759.404811903856, 3459.2043829919257, 3487.5814902211896, 3493.074335301147, 3530.6469353064745, 3544.769589976643, 238452.0, 240187.0, 240628.0, 240899.0, 241069.99999999825, 50453.49546504561, 50631.74684126629, 50754.0, 50803.74598127059, 50808.49191508112, 52165.0, 52311.47688523066, 52350.21474677883, 52358.73820630873, 52386.21420678693, 12030.338331391682, 12374.195677280948, 12388.0, 12403.0, 12405.937970310091, 9363.204127649089, 9518.857217141609, 9610.95194524037, 9803.411795292306, 9913.900860991442, 14797.778033329509, 15003.17482538449, 15090.24548772557, 15247.695046099016, 15398.15310157952, 5837.0, 5888.322842873039, 5892.587518873724, 5973.492253158443, 6122.785702500391, 765.3379826450192, 842.8609279468819, 845.7970087179156, 859.2867919626694, 970.8786401699788, 25606.48787024249, 27686.0, 27706.722932770415, 28500.429991400055, 28507.857460712978, 893.883795106632, 928.2805825485303, 957.1624828275259, 963.9228861691048, 1018.974525636865, 13892.052834562268, 14393.208373539346, 14692.41230350774, 14698.0, 14746.705065898623, 355524.0, 356032.0, 359432.0, 360306.0, 360603.0, 48037.519624804, 48302.516974830505, 48312.27531587029, 48317.0, 48447.75776121167, 1121.1087185687397, 1140.1278022312938, 1158.6002829023973, 1164.4876254448186, 1175.5474142455046, 25496.49007019812, 26607.0, 26964.191074267805, 27719.584206237327, 27808.582871257353, 41677.583224168586, 42058.7897060525, 42069.57930420656, 42070.36894446647, 42130.0, 318361.0, 320863.0, 321016.0, 321060.0, 321529.0, 29908.700912991462, 30652.314122723463, 31496.685033149377, 34093.31813363769, 35058.82470587631, 39958.60041399549, 41055.0, 41134.0, 41289.79355103205, 41295.58704412918, 33340.83329583336, 33820.32359352848, 33863.32273354568, 34169.0, 34550.65449345474, 33861.0, 34718.0, 34725.0, 34763.30473390569, 35175.361847861, 34188.31623367569, 34609.826950865085, 34826.303473930886, 35001.124971875906, 35003.299934000665, 29459.0, 31551.0, 31628.683713162573, 31955.6804431962, 32027.35945281128, 24416.63375049411, 24674.753252467246, 24699.506009879344, 24706.933346568498, 24917.53109971776], "value": 17342.06567461427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.8567311753162, 364.1715098151705, 366.2437067455702, 366.94128939369705, 391.0790089339605, 12478.376081195938, 15842.92078539607, 16198.595035124114, 16529.090900000494, 17412.738808917846, 16113.91943040286, 16261.918690406532, 16418.425355112544, 16518.17409129545, 16605.0, 3946.48695669564, 4879.072976134541, 5159.019786240621, 5163.173892177252, 5201.427842937273, 5033.949660503385, 5080.644354895159, 5120.283160357534, 5124.795008199668, 5127.871803204931, 4896.902061958759, 5027.748612569375, 5064.746762661871, 5092.541671249587, 5100.668456550312, 5028.8239911603105, 5053.797848086072, 5061.595072394218, 5065.544101030925, 5065.924011139836, 4356.4336636237085, 4507.864764057083, 4518.3222516622445, 4521.118381915528, 4575.908481830378, 19165.80834191668, 19306.71039934402, 19971.700424493643, 19987.0, 20261.8986905066, 1821.317006122704, 1921.702136168896, 2108.39910625471, 2191.561687662458, 2352.094443639194, 8767.649294028231, 9315.487648179347, 12466.065045121652, 13073.738525229537, 13743.175409475463, 1676.1619190404797, 1720.232690408015, 1723.8534724548429, 1749.8862573932654, 1771.8051014388407, 62878.0, 64654.67672661653, 65636.6718166406, 66339.0, 67467.0, 9670.661526846527, 9869.457179855177, 9870.802583948425, 10060.647877324258, 10081.596736130472, 13249.138805977773, 13262.93368533151, 13283.0, 13287.06990510655, 13297.468101276034, 6605.141331626858, 6699.794037073373, 6700.296468870796, 6836.863262734718, 6853.554518956252, 6242.313345531988, 6255.874882502325, 6382.393672601095, 6398.776042838572, 6409.711562979677, 4125.23683118624, 4183.163367326547, 4188.560201178893, 4230.576942305806, 4303.63419109373, 1620.3599578166559, 1744.1366523570914, 1771.7430972509171, 1786.5890845105698, 1854.3880519428826, 372.7390826421516, 373.16783572632636, 387.065237451557, 688.9517733758689, 794.4875555266821, 10114.848277275847, 10379.792404152025, 10384.37693738378, 10401.635942741968, 10420.791584168273, 411.4815332680844, 417.99164016719493, 421.1787015320138, 429.23167530120736, 432.38169417732945, 5544.63959842609, 5651.8304450866535, 5666.631668941588, 5678.5741069419955, 5719.0, 357510.0, 368171.0, 369734.0, 371379.0, 371561.0, 72053.0, 76509.23490765132, 77694.22305776984, 78123.5782600652, 78948.0, 371.8122348214189, 406.95726948669966, 415.02883253187684, 415.8627652874544, 416.0223474834116, 7710.691572337044, 9757.853632195382, 9827.508624568885, 10050.145737612385, 10537.262391632512, 36601.08497287589, 37319.813400933905, 37615.81192094022, 38283.42574861435, 38355.42466863055, 50429.74785126051, 51675.0, 51736.74131629318, 51807.48192518027, 52426.737866310425, 6216.689165541795, 6685.6322902240845, 6788.524803263724, 6985.720571177096, 7425.962870185831, 9084.364094513321, 10512.42181680015, 10772.192085593768, 10792.136629069499, 11628.476718547516, 9262.0, 9607.279454040836, 9675.097470758885, 9983.60065597368, 10100.949495252476, 9478.85781713261, 9612.279079068958, 9644.614215431304, 9722.1736152425, 10425.165986720893, 9195.310351723514, 9263.629454821732, 9366.859497107685, 10316.43259620728, 10648.0, 6646.634435106115, 6741.52809303344, 6792.694328755119, 6808.557443766139, 6953.721851126101, 9036.231920286586, 9979.322125326122, 10033.44816035125, 10213.540390682288, 10332.62632069298], "value": 7201.034596425935, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.418889877353, 1358.4769863602512, 1470.1913947328974, 1677.1278934953832, 1695.0338307164914, 7133.179684336303, 7543.924560754391, 7554.282343177396, 7555.622218889061, 7559.886601700965, 6382.297947225813, 6525.510586706004, 7136.1436627604635, 7326.084239470067, 7424.146223184335, 2092.8325733941247, 2131.030381176569, 2133.615949129156, 2134.3703607435846, 2137.6152292587326, 2058.320754151134, 2097.265956915083, 2103.2533450625037, 2105.157936825267, 2109.440998135495, 2074.2740040985686, 2111.060578042772, 2111.356036408895, 2125.4580082079046, 2130.8401869859745, 1995.5909038647042, 2024.210557882427, 2024.7671517775498, 2028.29009846554, 2037.6128535578193, 2066.4214020074405, 2066.927657531994, 2077.792220777925, 2081.1259271106173, 2087.1859974609915, 7213.963930180368, 7290.380317673004, 7346.118465784087, 7350.4119670426235, 7433.553986760811, 4374.540673229329, 4501.099780043972, 4501.3022981437925, 4508.120916421299, 4516.412866327357, 2822.6189464422246, 2924.4735947529434, 2940.206144341016, 2953.5569664550276, 2967.4361871244396, 837.9622916968751, 889.5685592487638, 890.6081324217324, 899.2985471332371, 964.7202311329706, 10090.545925433373, 10151.94924025375, 10161.847572286422, 10162.237832162617, 10168.13570846479, 3986.9068738911724, 4167.312393455046, 4245.808938597709, 4248.1078973415315, 4250.51119121305, 8605.569721513899, 8618.39671223021, 8625.525596092275, 8635.395522313504, 8637.870431943526, 4478.507364189936, 4560.201964656172, 4573.702709323883, 4575.084983003413, 4575.3823233863, 3734.066483379155, 3817.8091095445116, 3974.3839704845795, 4142.896427589334, 4147.232761939051, 3418.1967237699123, 3536.328097661436, 3613.674769270726, 3624.6919011884283, 3689.151495156129, 1174.7356844709873, 1199.3688583526398, 1209.9274043557414, 1217.6468824040928, 1218.713602303458, 671.4024518178825, 828.6850996621246, 831.1314676163423, 849.0448245723561, 870.2515836380708, 4813.229883218668, 4857.562819346276, 5014.09746245672, 5031.572316353077, 5330.706811125426, 395.33188910740756, 405.2806268872716, 408.29161404962366, 410.1980627872502, 414.20265987973033, 3266.44470440021, 3291.637919828817, 3304.5538852255117, 3366.5286859839644, 3414.8634054637537, 8732.083131271253, 8980.820383592292, 9023.593938272794, 9028.09719028092, 9079.137481939206, 8736.082711315348, 8884.289256859436, 8950.0, 8990.640374384951, 8992.775180620536, 6938.5836849789175, 6940.722371105099, 7040.155181378267, 7044.859102817915, 7058.576485410892, 4874.098291816025, 4901.191303434894, 4923.08922849274, 4958.900821983612, 4997.700137991732, 7982.9600851995365, 8190.549519776469, 8263.462874913112, 8287.668493260202, 8364.498130112213, 8311.875321870057, 8364.247217750431, 8429.915700842914, 8432.283255923316, 8519.787005324917, 5902.350741418355, 6068.180795592539, 6350.650714210763, 6515.348465153446, 6534.934650653433, 6767.086443330088, 7019.403350715246, 7022.12223472066, 7172.959920811349, 7173.892391614026, 6398.232212134388, 6451.645159516084, 6477.967610161919, 6657.700403481758, 6689.76585819494, 6374.394432528902, 6436.656346749226, 6450.41946224842, 6530.771423000172, 6533.575317604341, 6083.574149809471, 6195.256569211723, 6196.1015652731, 6197.318294987638, 6202.891017054223, 4315.331123675835, 4430.468343798765, 4468.4637843458995, 4527.479339875957, 4548.56788605082, 4350.695451318377, 4370.715903466392, 4398.032432864765, 4493.59557639814, 4539.863804085882], "value": 3894.9151383667777, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.56465183631684, 335.93113411750596, 341.23563218390797, 343.3647751659429, 350.6686181558427, 4809.543093406125, 4818.759062046893, 4822.421309442868, 4828.999999999995, 4860.465348811628, 4763.737994410306, 4769.451513075997, 4777.6177905767545, 4778.474367819537, 4787.13831510328, 3643.544556930377, 3715.089802998269, 3761.661450469457, 3762.7554208976467, 3796.9240615187678, 3805.5052843130356, 3806.9048273793237, 3814.713896457763, 3825.674817640504, 3831.386978083507, 3768.5854555998817, 3771.396576547753, 3791.848326066954, 3792.601776813436, 3807.847686092553, 3615.9096022599383, 3713.610070942539, 3725.6646901778972, 3779.8677046303383, 3812.1422679897078, 3420.504026916108, 3421.9734079776217, 3422.110251334671, 3427.691507764313, 3430.691237788611, 4632.768361581908, 4638.976805115986, 4646.465656449484, 4648.953510464886, 4659.974805542775, 3993.880183594497, 4365.738055716667, 4387.736735795863, 4389.648828093745, 4404.647628189737, 4927.95072049282, 4942.703437793708, 4951.5543601075715, 4974.825881094181, 5014.971930754223, 3494.8951531454095, 3502.1594817243677, 3510.596281427643, 3535.4520049392386, 3539.132912436444, 5224.11931704586, 5232.764525596357, 5261.657992230493, 5276.12943864262, 5279.1817268323475, 3475.011490118496, 3589.2592092834952, 3608.45873119029, 3615.5661320641693, 3617.408340330246, 3656.939487548634, 3658.70730341572, 3667.211549516867, 3670.9816450917574, 3684.299983003221, 4200.4959404871615, 4216.536181020086, 4217.553818785097, 4217.806740494987, 4222.902045468139, 4754.976225118852, 4944.802207911644, 5020.322256495399, 5052.823151189691, 5062.670926389773, 1190.9166358354832, 1199.4362649554696, 1202.7053371923935, 1203.7291609387992, 1204.4459548607738, 800.6357107516067, 826.5330088499978, 827.4414770030205, 835.9592307577105, 839.1859895900966, 4239.1521695660995, 4314.0, 4370.978145109254, 4419.850838781876, 4447.777611119431, 5086.18621020635, 5092.337996060489, 5097.439281679012, 5103.8468845934685, 5106.4382917879, 4373.584509471595, 4383.736975781399, 4389.6927215095275, 4424.203643344164, 4445.844395446208, 2516.5218608464334, 2640.419107796282, 2644.1010056580853, 2649.841009539434, 2659.973400266011, 4654.37165982588, 4680.555347242007, 4681.67228294023, 4703.7883295251795, 4705.717656940595, 5125.308083408692, 5177.559907407913, 5189.506996835295, 5190.883959948554, 5195.402528709247, 4467.687261891701, 4699.201135806921, 4715.669903106749, 4733.644976626767, 4757.571818536418, 5006.899862002812, 5009.77456014473, 5023.2214006829, 5035.29505869186, 5040.14317566022, 4913.410390753133, 4925.38432695913, 4927.901441971068, 4928.753562321941, 4937.617731853692, 4438.334249862546, 4673.4625518064495, 4684.400157912255, 4689.507601701908, 4692.41344831896, 3452.1197094740683, 3455.6371580983637, 3465.2722928185362, 3474.7046501046684, 3476.1309672581856, 5005.299258103795, 5011.772115831644, 5028.245763135559, 5038.48845346402, 5054.3176671150395, 4694.248920172756, 4825.3485779419325, 4860.92708609364, 4879.56083952446, 4903.0, 4822.228443449031, 4841.176999910094, 4852.660313778002, 4863.221884498464, 4885.90228195427, 4811.903761924672, 4825.179719447632, 4826.806927722992, 4834.347363106076, 4848.927266090941, 4721.111555377876, 4877.5366340197625, 4910.386201724785, 4910.779014944407, 4952.826651067196, 4761.523847615211, 4762.238041913277, 4774.928376074292, 4780.0, 4786.090642777861], "value": 3738.802146398236, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.721307378995, 596.627108057464, 687.2989550658332, 687.3161204601422, 692.3734020711255, 12771.489140434383, 14094.295285235736, 14219.928900355484, 14266.785998210034, 14334.13995160291, 12204.267743935348, 12340.753184936297, 13782.448702051932, 14348.928255358735, 14470.059446135994, 773.3465221887496, 795.8647030004913, 797.3979645367739, 797.6410615223144, 798.1738900238255, 631.1164369882156, 647.2782847125445, 762.7177944160641, 772.6600295869828, 793.1037927142349, 803.5901690138013, 808.462372522272, 808.7533302342783, 808.9887640449438, 809.9311558517504, 798.8442253760519, 802.0175285275549, 802.8795680647892, 803.4054799448367, 803.7307501986842, 486.2560282767336, 491.1061867401331, 586.182275725365, 587.0167469488632, 598.0729868703493, 13781.862181378157, 13888.652783680387, 13888.93055534726, 13889.861101388959, 13891.305434728325, 10997.285176463503, 10997.84522625121, 11370.31778093317, 11596.246243994114, 11815.409229538574, 11114.722131946684, 11149.386783726892, 11267.380294083821, 11542.07663386927, 12108.394580270951, 3819.656230939201, 3843.423486477023, 3877.836649005287, 3955.0310174007263, 3970.5235371755284, 60410.69794651042, 60951.0, 62850.37149628491, 63978.360216397705, 64294.67852660754, 9307.860382094274, 9569.712908612753, 9695.466749328714, 9702.805943881083, 10242.6415075472, 18918.621627567372, 19029.61940761205, 19069.427917162506, 19256.614867702567, 19389.80610193908, 6704.597724136567, 6747.831304217434, 6749.493787965922, 6796.286389929085, 7023.122109736283, 3651.6165802590344, 3682.8342724576924, 3728.4034554471154, 3734.775913445202, 3736.308782875177, 2938.250746059784, 2980.7019298070018, 3021.531662592302, 3023.425549145655, 3024.848757562157, 492.68960554850213, 508.506748453999, 519.3689667054547, 520.8307300127474, 528.918017707256, 469.3042291950887, 531.9521243088141, 549.0885130683047, 580.0834681203688, 590.2916500199777, 7325.743598974011, 7509.36170425509, 7663.578503182267, 7804.804879878048, 7877.291043806085, 482.09366391184346, 506.1817320690296, 533.8745394832212, 562.0809975689695, 563.5036787033399, 3745.0, 3824.9235015300096, 3847.8076096195077, 3866.3813789794062, 3908.6091390861257, 66373.34066648372, 66709.0, 67145.66427167832, 69361.65319173371, 69371.3062869375, 46827.53172468232, 50590.0, 54933.450665493634, 58760.11859822106, 60383.69808151019, 54171.18743218776, 54966.725166373915, 55118.0, 55135.41619794701, 55263.0, 7210.4231661468, 7282.089738782653, 7393.630318483947, 7609.6575654094595, 7691.807704807425, 35434.29131417409, 35748.64251357557, 37273.44089838709, 37507.43738843974, 37588.81205593955, 45497.0, 49627.75186124046, 49942.25086623631, 50679.493205067476, 52773.0, 9093.4998575079, 9516.205815844816, 9541.570629321835, 9567.234621230424, 9814.70555883296, 10429.26995110335, 10518.526666300188, 10531.842022369823, 10750.408727520062, 11043.723906902393, 9198.724038278862, 9497.24022078246, 9707.0, 10034.69895903124, 10269.794604107727, 9527.857082143635, 9595.856062158933, 10176.0, 10687.572497100029, 10735.892641073802, 10697.572097116028, 10814.728906929633, 11039.116870650489, 11149.442527873736, 11697.122715796178, 8912.108789121296, 8933.46399216049, 9142.63429462814, 9152.771180720536, 9360.672376466791, 8876.778080548038, 9151.134178778873, 9195.816083678155, 9321.207697345802, 9350.579223934803], "value": 6084.0123435366995, "unit": "score"}], "name": "dromaeo_css", "value": 6113.324427673969, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 115.0, 117.0, 115.0, 117.0, 115.0, 115.0, 115.0, 114.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 129.0, 130.0, 174.0, 131.0, 136.0, 134.0, 128.0, 155.0, 127.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 160.0, 161.0, 161.0, 161.0, 160.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 110.0, 78.0, 87.0, 77.0, 75.0, 104.0, 78.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 124.0, 84.0, 85.0, 84.0, 106.0, 85.0, 85.0, 108.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 126.0, 128.0, 128.0, 128.0, 128.0, 127.0, 128.0, 127.0, 127.0], "value": 127.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 148.0, 147.0, 147.0, 147.0, 148.0, 146.0, 146.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 71.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 86.0, 85.0, 88.0, 91.0, 84.0, 101.0, 85.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 140.0, 146.0, 140.0, 157.0, 141.0, 137.0, 181.0, 153.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 181.0, 182.0, 190.0, 186.0, 181.0, 180.0, 182.0, 182.0, 179.0], "value": 182.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1580.8000000000002, "alertThreshold": 2.0}]} 08:04:53 INFO - Return code: 0 08:04:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:04:53 INFO - # TBPL SUCCESS # 08:04:53 INFO - Running post-action listener: _resource_record_post_action 08:04:53 INFO - [mozharness: 2016-12-12 16:04:53.639979Z] Finished run-tests step (success) 08:04:53 INFO - Running post-run listener: _resource_record_post_run 08:04:53 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 0; Write bytes: 407859200; Read time: 0; Write time: 287868 08:04:53 INFO - TinderboxPrint: CPU usage
13.2% 08:04:53 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:04:53 INFO - TinderboxPrint: I/O write bytes / time
407,859,200 / 287,868 08:04:53 INFO - TinderboxPrint: CPU idle
8,353.0 (86.8%) 08:04:53 INFO - TinderboxPrint: CPU user
1,220.3 (12.7%) 08:04:53 INFO - TinderboxPrint: Swap in / out
0 / 0 08:04:53 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115896320; Read time: 0; Write time: 77284 08:04:53 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 290832384; Read time: 0; Write time: 210560 08:04:54 INFO - Running post-run listener: _upload_blobber_files 08:04:54 INFO - Blob upload gear active. 08:04:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:04:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:04:54 INFO - (blobuploader) - INFO - Open directory for files ... 08:04:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:04:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:58 INFO - (blobuploader) - INFO - Done attempting. 08:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:59 INFO - (blobuploader) - INFO - Done attempting. 08:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:00 INFO - (blobuploader) - INFO - Done attempting. 08:05:00 INFO - (blobuploader) - INFO - Iteration through files over. 08:05:00 INFO - Return code: 0 08:05:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:05:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:05:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/783c4c05ec334462c59c487c836232a6de13037e09f82065c593ca46a5189b0bf0a158a67e4a2734d7ad956ed3755e7d2c3f891f60c7c34439f07e5a3f93d08c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d729ac56947017d00be73c05772cf4ec3fda11200f18055385cde655bef6ad2553ec96ca7d504b702435610975268ad2ec84573a21e8291e1548c3f56c3dbb1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43c6e73b30c053d7154d74e1d5768cbd3a492752ea1cb13bf8f4df0310f4f9069aca1191240cc2c87a2b80c527aa4f2b0a8babf6a10f9009967f7aef56b3b8f0"} 08:05:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:05:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:05:00 INFO - Contents: 08:05:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/783c4c05ec334462c59c487c836232a6de13037e09f82065c593ca46a5189b0bf0a158a67e4a2734d7ad956ed3755e7d2c3f891f60c7c34439f07e5a3f93d08c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d729ac56947017d00be73c05772cf4ec3fda11200f18055385cde655bef6ad2553ec96ca7d504b702435610975268ad2ec84573a21e8291e1548c3f56c3dbb1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43c6e73b30c053d7154d74e1d5768cbd3a492752ea1cb13bf8f4df0310f4f9069aca1191240cc2c87a2b80c527aa4f2b0a8babf6a10f9009967f7aef56b3b8f0"} 08:05:00 INFO - Running post-run listener: copy_logs_to_upload_dir 08:05:00 INFO - Copying logs to upload dir... 08:05:00 INFO - mkdir: /builds/slave/test/build/upload/logs 08:05:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.529951 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-12 08:04:59.874564) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 08:04:59.879470) ========= 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-1481550977.346156-1865240718 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/783c4c05ec334462c59c487c836232a6de13037e09f82065c593ca46a5189b0bf0a158a67e4a2734d7ad956ed3755e7d2c3f891f60c7c34439f07e5a3f93d08c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d729ac56947017d00be73c05772cf4ec3fda11200f18055385cde655bef6ad2553ec96ca7d504b702435610975268ad2ec84573a21e8291e1548c3f56c3dbb1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43c6e73b30c053d7154d74e1d5768cbd3a492752ea1cb13bf8f4df0310f4f9069aca1191240cc2c87a2b80c527aa4f2b0a8babf6a10f9009967f7aef56b3b8f0"} build_url:https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011549 build_url: 'https://queue.taskcluster.net/v1/task/F0ZibLr6Semz6tknVgLuJQ/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/783c4c05ec334462c59c487c836232a6de13037e09f82065c593ca46a5189b0bf0a158a67e4a2734d7ad956ed3755e7d2c3f891f60c7c34439f07e5a3f93d08c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d729ac56947017d00be73c05772cf4ec3fda11200f18055385cde655bef6ad2553ec96ca7d504b702435610975268ad2ec84573a21e8291e1548c3f56c3dbb1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/43c6e73b30c053d7154d74e1d5768cbd3a492752ea1cb13bf8f4df0310f4f9069aca1191240cc2c87a2b80c527aa4f2b0a8babf6a10f9009967f7aef56b3b8f0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 08:04:59.907647) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 08:04:59.908015) ========= 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-1481550977.346156-1865240718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004601 ========= master_lag: 0.34 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 08:05:00.252545) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 08:05:00.252899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 08:05:00.256272) ========= ========= Total master_lag: 0.47 =========