builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-009 starttime: 1482772256.7 results: success (0) buildid: 20161226073116 builduid: 226d61d76f284082bcf67dbe96ba1303 revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.698191) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.700392) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.700694) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.715232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.715555) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-26 09:10:56-- 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-26 09:10:57 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101230 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.830845) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.831132) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017779 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:56.880202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 09:10:56.880543) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --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 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --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=17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-26 09:10:57,120 truncating revision to first 12 chars 2016-12-26 09:10:57,120 Setting DEBUG logging. 2016-12-26 09:10:57,120 attempt 1/10 2016-12-26 09:10:57,120 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1747e26ee37b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 09:10:57,868 unpacking tar archive at: mozilla-central-1747e26ee37b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.060892 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 09:10:57.973497) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.973947) ========= script_repo_revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.974508) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.974896) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:58.290210) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-26 09:10:58.290701) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:10:58 INFO - MultiFileLogger online at 20161226 09:10:58 in /builds/slave/test 09:10:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:10:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:10:58 INFO - {'append_to_log': False, 09:10:58 INFO - 'base_work_dir': '/builds/slave/test', 09:10:58 INFO - 'blob_upload_branch': 'Firefox', 09:10:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:10:58 INFO - 'branch': 'Firefox', 09:10:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:10:58 INFO - 'config_files': ('talos/linux_config.py',), 09:10:58 INFO - 'default_actions': ('clobber', 09:10:58 INFO - 'read-buildbot-config', 09:10:58 INFO - 'download-and-extract', 09:10:58 INFO - 'populate-webroot', 09:10:58 INFO - 'create-virtualenv', 09:10:58 INFO - 'install', 09:10:58 INFO - 'run-tests'), 09:10:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:10:58 INFO - 'download_minidump_stackwalk': True, 09:10:58 INFO - 'download_symbols': 'ondemand', 09:10:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:10:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:10:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:10:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:10:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:10:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:10:58 INFO - 'installer_path': 'installer.exe', 09:10:58 INFO - 'log_level': 'info', 09:10:58 INFO - 'log_name': 'talos', 09:10:58 INFO - 'log_to_console': True, 09:10:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:10:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:10:58 INFO - 'opt_config_files': (), 09:10:58 INFO - 'pip_index': False, 09:10:58 INFO - 'sps_profile': False, 09:10:58 INFO - 'sps_profile_interval': 0, 09:10:58 INFO - 'suite': 'dromaeojs-e10s', 09:10:58 INFO - 'system_bits': '32', 09:10:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:10:58 INFO - 'title': 'talos-linux64-ix-009', 09:10:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:10:58 INFO - 'use_talos_json': True, 09:10:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:10:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:10:58 INFO - 'work_dir': 'build'} 09:10:58 INFO - [mozharness: 2016-12-26 17:10:58.636496Z] Running clobber step. 09:10:58 INFO - Running pre-action listener: _resource_record_pre_action 09:10:58 INFO - Running main action method: clobber 09:10:58 INFO - rmtree: /builds/slave/test/build 09:10:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:10:59 INFO - Running post-action listener: _resource_record_post_action 09:10:59 INFO - [mozharness: 2016-12-26 17:10:59.628343Z] Finished clobber step (success) 09:10:59 INFO - [mozharness: 2016-12-26 17:10:59.628442Z] Running read-buildbot-config step. 09:10:59 INFO - Running pre-action listener: _resource_record_pre_action 09:10:59 INFO - Running main action method: read_buildbot_config 09:10:59 INFO - Using buildbot properties: 09:10:59 INFO - { 09:10:59 INFO - "project": "", 09:10:59 INFO - "product": "firefox", 09:10:59 INFO - "script_repo_revision": "production", 09:10:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:10:59 INFO - "repository": "", 09:10:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 09:10:59 INFO - "buildid": "20161226073116", 09:10:59 INFO - "pgo_build": "True", 09:10:59 INFO - "basedir": "/builds/slave/test", 09:10:59 INFO - "buildnumber": 500, 09:10:59 INFO - "slavename": "talos-linux64-ix-009", 09:10:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:10:59 INFO - "platform": "ubuntu64_hw", 09:10:59 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:10:59 INFO - "branch": "mozilla-central", 09:10:59 INFO - "repo_path": "mozilla-central", 09:10:59 INFO - "revision": "1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9", 09:10:59 INFO - "stage_platform": "linux64-pgo", 09:10:59 INFO - "builduid": "226d61d76f284082bcf67dbe96ba1303", 09:10:59 INFO - "slavebuilddir": "test" 09:10:59 INFO - } 09:10:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:10:59 INFO - Running post-action listener: _resource_record_post_action 09:10:59 INFO - [mozharness: 2016-12-26 17:10:59.630889Z] Finished read-buildbot-config step (success) 09:10:59 INFO - [mozharness: 2016-12-26 17:10:59.630980Z] Running download-and-extract step. 09:10:59 INFO - Running pre-action listener: _resource_record_pre_action 09:10:59 INFO - Running main action method: download_and_extract 09:10:59 INFO - mkdir: /builds/slave/test/build/tests 09:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:59 INFO - trying https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:10:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:10:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:11:00 INFO - Downloaded 1361 bytes. 09:11:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:00 INFO - Using the following test package requirements: 09:11:00 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:00 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:11:00 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:11:00 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'jsshell-linux-x86_64.zip'], 09:11:00 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:11:00 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:00 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:11:00 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:11:00 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:11:00 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:00 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:11:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:11:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:11:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:11:00 INFO - Expected file size: 21829987 09:11:00 INFO - Obtained file size: 21829987 09:11:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:11:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:11:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:11:06 INFO - Expected file size: 21829987 09:11:06 INFO - Obtained file size: 21829987 09:11:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:11:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:11:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:11:12 INFO - Expected file size: 14409776 09:11:12 INFO - Obtained file size: 14409776 09:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:12 INFO - trying https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:11:13 INFO - Downloaded 61372319 bytes. 09:11:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:13 INFO - mkdir: /builds/slave/test/properties 09:11:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:13 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:13 INFO - Contents: 09:11:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:14 INFO - Running post-action listener: _resource_record_post_action 09:11:14 INFO - Running post-action listener: set_extra_try_arguments 09:11:14 INFO - [mozharness: 2016-12-26 17:11:14.230968Z] Finished download-and-extract step (success) 09:11:14 INFO - [mozharness: 2016-12-26 17:11:14.231196Z] Running populate-webroot step. 09:11:14 INFO - Running pre-action listener: _resource_record_pre_action 09:11:14 INFO - Running main action method: populate_webroot 09:11:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:14 INFO - '--tppagecycles', 09:11:14 INFO - '7'], 09:11:14 INFO - 'tests': ['tsvgm']}}, 09:11:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:11:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:11:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:11:14 INFO - 'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:11:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:11:14 INFO - 'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['damp', 'tps']}, 09:11:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:11:14 INFO - 'tests': ['damp', 'tps']}, 09:11:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['dromaeo_dom']}, 09:11:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['basic_compositor_video']}, 09:11:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:11:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['a11yr', 09:11:14 INFO - 'ts_paint', 09:11:14 INFO - 'tpaint', 09:11:14 INFO - 'sessionrestore', 09:11:14 INFO - 'sessionrestore_no_auto_restore', 09:11:14 INFO - 'tabpaint']}, 09:11:14 INFO - 'other-e10s': {'tests': ['a11yr', 09:11:14 INFO - 'ts_paint', 09:11:14 INFO - 'tpaint', 09:11:14 INFO - 'sessionrestore', 09:11:14 INFO - 'sessionrestore_no_auto_restore', 09:11:14 INFO - 'tabpaint']}, 09:11:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:11:14 INFO - 'ts_paint', 09:11:14 INFO - 'tpaint', 09:11:14 INFO - 'sessionrestore', 09:11:14 INFO - 'sessionrestore_no_auto_restore', 09:11:14 INFO - 'tabpaint']}, 09:11:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:11:14 INFO - 'ts_paint', 09:11:14 INFO - 'tpaint', 09:11:14 INFO - 'sessionrestore', 09:11:14 INFO - 'sessionrestore_no_auto_restore', 09:11:14 INFO - 'tabpaint']}, 09:11:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['a11yr', 09:11:14 INFO - 'ts_paint', 09:11:14 INFO - 'tpaint', 09:11:14 INFO - 'sessionrestore', 09:11:14 INFO - 'sessionrestore_no_auto_restore', 09:11:14 INFO - 'tabpaint']}, 09:11:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['a11yr', 09:11:14 INFO - 'ts_paint', 09:11:14 INFO - 'tpaint', 09:11:14 INFO - 'sessionrestore', 09:11:14 INFO - 'sessionrestore_no_auto_restore', 09:11:14 INFO - 'tabpaint']}, 09:11:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['tsvgx', 09:11:14 INFO - 'tsvgr_opacity', 09:11:14 INFO - 'tart', 09:11:14 INFO - 'tscrollx', 09:11:14 INFO - 'cart', 09:11:14 INFO - 'tsvg_static']}, 09:11:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:11:14 INFO - 'tsvgr_opacity', 09:11:14 INFO - 'tart', 09:11:14 INFO - 'tscrollx', 09:11:14 INFO - 'cart', 09:11:14 INFO - 'tsvg_static']}, 09:11:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:11:14 INFO - 'talos_options': ['--disable-e10s'], 09:11:14 INFO - 'tests': ['tp5o']}, 09:11:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:11:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:11:14 INFO - 'talos_options': ['--disable-e10s', 09:11:14 INFO - '--xperf_path', 09:11:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:14 INFO - 'tests': ['tp5n']}, 09:11:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:11:14 INFO - 'talos_options': ['--xperf_path', 09:11:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:14 INFO - 'tests': ['tp5n']}}, 09:11:14 INFO - 'talos.zip': {'path': '', 09:11:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:14 INFO - Running post-action listener: _resource_record_post_action 09:11:14 INFO - [mozharness: 2016-12-26 17:11:14.252359Z] Finished populate-webroot step (success) 09:11:14 INFO - [mozharness: 2016-12-26 17:11:14.252448Z] Running create-virtualenv step. 09:11:14 INFO - Running pre-action listener: _resource_record_pre_action 09:11:14 INFO - Running main action method: create_virtualenv 09:11:14 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:11:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:14 INFO - Using real prefix '/usr' 09:11:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:16 INFO - Installing pip.................done. 09:11:16 INFO - Return code: 0 09:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:11:17 INFO - Reading from file tmpfile_stdout 09:11:17 INFO - Output received: 09:11:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:11:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:17 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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:11:17 INFO - Using env: {'DISPLAY': ':0', 09:11:17 INFO - 'HOME': '/home/cltbld', 09:11:17 INFO - 'LANG': 'en_US.UTF-8', 09:11:17 INFO - 'LANGUAGE': 'en_US:en', 09:11:17 INFO - 'LOGNAME': 'cltbld', 09:11:17 INFO - 'MAIL': '/var/mail/cltbld', 09:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:17 INFO - 'MOZ_NO_REMOTE': '1', 09:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:17 INFO - 'NO_EM_RESTART': '1', 09:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:17 INFO - 'PWD': '/builds/slave/test', 09:11:17 INFO - 'SHELL': '/bin/bash', 09:11:17 INFO - 'SHLVL': '1', 09:11:17 INFO - 'TERM': 'linux', 09:11:17 INFO - 'TMOUT': '86400', 09:11:17 INFO - 'USER': 'cltbld', 09:11:17 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:17 INFO - '_': '/tools/buildbot/bin/python'} 09:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:11:17 INFO - Cleaning up... 09:11:17 INFO - Return code: 0 09:11:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:17 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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:11:17 INFO - Using env: {'DISPLAY': ':0', 09:11:17 INFO - 'HOME': '/home/cltbld', 09:11:17 INFO - 'LANG': 'en_US.UTF-8', 09:11:17 INFO - 'LANGUAGE': 'en_US:en', 09:11:17 INFO - 'LOGNAME': 'cltbld', 09:11:17 INFO - 'MAIL': '/var/mail/cltbld', 09:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:17 INFO - 'MOZ_NO_REMOTE': '1', 09:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:17 INFO - 'NO_EM_RESTART': '1', 09:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:17 INFO - 'PWD': '/builds/slave/test', 09:11:17 INFO - 'SHELL': '/bin/bash', 09:11:17 INFO - 'SHLVL': '1', 09:11:17 INFO - 'TERM': 'linux', 09:11:17 INFO - 'TMOUT': '86400', 09:11:17 INFO - 'USER': 'cltbld', 09:11:17 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:17 INFO - '_': '/tools/buildbot/bin/python'} 09:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:17 INFO - Downloading/unpacking psutil>=3.1.1 09:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:19 INFO - Installing collected packages: psutil 09:11:19 INFO - Running setup.py install for psutil 09:11:19 INFO - building 'psutil._psutil_linux' extension 09:11:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:11:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:11:19 INFO - building 'psutil._psutil_posix' extension 09:11:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:11:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:11:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:20 INFO - Successfully installed psutil 09:11:20 INFO - Cleaning up... 09:11:20 INFO - Return code: 0 09:11:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:11:20 INFO - Using env: {'DISPLAY': ':0', 09:11:20 INFO - 'HOME': '/home/cltbld', 09:11:20 INFO - 'LANG': 'en_US.UTF-8', 09:11:20 INFO - 'LANGUAGE': 'en_US:en', 09:11:20 INFO - 'LOGNAME': 'cltbld', 09:11:20 INFO - 'MAIL': '/var/mail/cltbld', 09:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:20 INFO - 'MOZ_NO_REMOTE': '1', 09:11:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:20 INFO - 'NO_EM_RESTART': '1', 09:11:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:20 INFO - 'PWD': '/builds/slave/test', 09:11:20 INFO - 'SHELL': '/bin/bash', 09:11:20 INFO - 'SHLVL': '1', 09:11:20 INFO - 'TERM': 'linux', 09:11:20 INFO - 'TMOUT': '86400', 09:11:20 INFO - 'USER': 'cltbld', 09:11:20 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:20 INFO - '_': '/tools/buildbot/bin/python'} 09:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:11:22 INFO - Installing collected packages: mozsystemmonitor 09:11:22 INFO - Running setup.py install for mozsystemmonitor 09:11:22 INFO - Successfully installed mozsystemmonitor 09:11:22 INFO - Cleaning up... 09:11:22 INFO - Return code: 0 09:11:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:11:22 INFO - Using env: {'DISPLAY': ':0', 09:11:22 INFO - 'HOME': '/home/cltbld', 09:11:22 INFO - 'LANG': 'en_US.UTF-8', 09:11:22 INFO - 'LANGUAGE': 'en_US:en', 09:11:22 INFO - 'LOGNAME': 'cltbld', 09:11:22 INFO - 'MAIL': '/var/mail/cltbld', 09:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:22 INFO - 'MOZ_NO_REMOTE': '1', 09:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:22 INFO - 'NO_EM_RESTART': '1', 09:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:22 INFO - 'PWD': '/builds/slave/test', 09:11:22 INFO - 'SHELL': '/bin/bash', 09:11:22 INFO - 'SHLVL': '1', 09:11:22 INFO - 'TERM': 'linux', 09:11:22 INFO - 'TMOUT': '86400', 09:11:22 INFO - 'USER': 'cltbld', 09:11:22 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:22 INFO - '_': '/tools/buildbot/bin/python'} 09:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:22 INFO - Downloading/unpacking jsonschema==2.5.1 09:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:24 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:25 INFO - warning: no files found matching '*.txt' 09:11:25 INFO - no previously-included directories found matching 'build' 09:11:25 INFO - no previously-included directories found matching 'dist' 09:11:25 INFO - no previously-included directories found matching '.git*' 09:11:25 INFO - Installing collected packages: jsonschema, functools32 09:11:25 INFO - Running setup.py install for functools32 09:11:25 INFO - warning: no files found matching '*.txt' 09:11:25 INFO - no previously-included directories found matching 'build' 09:11:25 INFO - no previously-included directories found matching 'dist' 09:11:25 INFO - no previously-included directories found matching '.git*' 09:11:25 INFO - Successfully installed jsonschema functools32 09:11:25 INFO - Cleaning up... 09:11:25 INFO - Return code: 0 09:11:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:11:25 INFO - Using env: {'DISPLAY': ':0', 09:11:25 INFO - 'HOME': '/home/cltbld', 09:11:25 INFO - 'LANG': 'en_US.UTF-8', 09:11:25 INFO - 'LANGUAGE': 'en_US:en', 09:11:25 INFO - 'LOGNAME': 'cltbld', 09:11:25 INFO - 'MAIL': '/var/mail/cltbld', 09:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:25 INFO - 'MOZ_NO_REMOTE': '1', 09:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:25 INFO - 'NO_EM_RESTART': '1', 09:11:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:25 INFO - 'PWD': '/builds/slave/test', 09:11:25 INFO - 'SHELL': '/bin/bash', 09:11:25 INFO - 'SHLVL': '1', 09:11:25 INFO - 'TERM': 'linux', 09:11:25 INFO - 'TMOUT': '86400', 09:11:25 INFO - 'USER': 'cltbld', 09:11:25 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:25 INFO - '_': '/tools/buildbot/bin/python'} 09:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:11:25 INFO - Cleaning up... 09:11:25 INFO - Return code: 0 09:11:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:11:25 INFO - Using env: {'DISPLAY': ':0', 09:11:25 INFO - 'HOME': '/home/cltbld', 09:11:25 INFO - 'LANG': 'en_US.UTF-8', 09:11:25 INFO - 'LANGUAGE': 'en_US:en', 09:11:25 INFO - 'LOGNAME': 'cltbld', 09:11:25 INFO - 'MAIL': '/var/mail/cltbld', 09:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:25 INFO - 'MOZ_NO_REMOTE': '1', 09:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:25 INFO - 'NO_EM_RESTART': '1', 09:11:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:25 INFO - 'PWD': '/builds/slave/test', 09:11:25 INFO - 'SHELL': '/bin/bash', 09:11:25 INFO - 'SHLVL': '1', 09:11:25 INFO - 'TERM': 'linux', 09:11:25 INFO - 'TMOUT': '86400', 09:11:25 INFO - 'USER': 'cltbld', 09:11:25 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:25 INFO - '_': '/tools/buildbot/bin/python'} 09:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:25 INFO - Downloading/unpacking blobuploader==1.2.4 09:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:27 INFO - Downloading blobuploader-1.2.4.tar.gz 09:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:11:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:28 INFO - Downloading docopt-0.6.1.tar.gz 09:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:11:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:11:28 INFO - Running setup.py install for blobuploader 09:11:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:11:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:11:28 INFO - Running setup.py install for requests 09:11:28 INFO - Running setup.py install for docopt 09:11:29 INFO - Successfully installed blobuploader requests docopt 09:11:29 INFO - Cleaning up... 09:11:29 INFO - Return code: 0 09:11:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:29 INFO - Using env: {'DISPLAY': ':0', 09:11:29 INFO - 'HOME': '/home/cltbld', 09:11:29 INFO - 'LANG': 'en_US.UTF-8', 09:11:29 INFO - 'LANGUAGE': 'en_US:en', 09:11:29 INFO - 'LOGNAME': 'cltbld', 09:11:29 INFO - 'MAIL': '/var/mail/cltbld', 09:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:29 INFO - 'MOZ_NO_REMOTE': '1', 09:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:29 INFO - 'NO_EM_RESTART': '1', 09:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:29 INFO - 'PWD': '/builds/slave/test', 09:11:29 INFO - 'SHELL': '/bin/bash', 09:11:29 INFO - 'SHLVL': '1', 09:11:29 INFO - 'TERM': 'linux', 09:11:29 INFO - 'TMOUT': '86400', 09:11:29 INFO - 'USER': 'cltbld', 09:11:29 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:29 INFO - '_': '/tools/buildbot/bin/python'} 09:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:29 INFO - Running setup.py (path:/tmp/pip-KNrgmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:29 INFO - Running setup.py (path:/tmp/pip-LwPQAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:29 INFO - Running setup.py (path:/tmp/pip-9Ktibu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:29 INFO - Running setup.py (path:/tmp/pip-DcwQt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:29 INFO - Running setup.py (path:/tmp/pip-4mpWtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:29 INFO - Running setup.py (path:/tmp/pip-q7bXJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:29 INFO - Running setup.py (path:/tmp/pip-JorZAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:29 INFO - Running setup.py (path:/tmp/pip-oumTGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:29 INFO - Running setup.py (path:/tmp/pip-BfaLx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:30 INFO - Running setup.py (path:/tmp/pip-1usT7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:30 INFO - Running setup.py (path:/tmp/pip-Pj7gMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:30 INFO - Running setup.py (path:/tmp/pip-dN4fyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:30 INFO - Running setup.py (path:/tmp/pip-NwaPy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:30 INFO - Running setup.py (path:/tmp/pip-1vYpqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:30 INFO - Running setup.py (path:/tmp/pip-uD420L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:30 INFO - Running setup.py (path:/tmp/pip-R5pxeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:30 INFO - Running setup.py (path:/tmp/pip-mBV01s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:30 INFO - Running setup.py install for manifestparser 09:11:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Running setup.py install for mozcrash 09:11:30 INFO - Running setup.py install for mozdebug 09:11:30 INFO - Running setup.py install for mozdevice 09:11:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for mozfile 09:11:31 INFO - Running setup.py install for mozhttpd 09:11:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for mozinfo 09:11:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for mozInstall 09:11:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for mozleak 09:11:31 INFO - Running setup.py install for mozlog 09:11:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for moznetwork 09:11:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for mozprocess 09:11:31 INFO - Running setup.py install for mozprofile 09:11:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for mozrunner 09:11:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:32 INFO - Running setup.py install for mozscreenshot 09:11:32 INFO - Running setup.py install for moztest 09:11:32 INFO - Running setup.py install for mozversion 09:11:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:32 INFO - Cleaning up... 09:11:32 INFO - Return code: 0 09:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:32 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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:32 INFO - Using env: {'DISPLAY': ':0', 09:11:32 INFO - 'HOME': '/home/cltbld', 09:11:32 INFO - 'LANG': 'en_US.UTF-8', 09:11:32 INFO - 'LANGUAGE': 'en_US:en', 09:11:32 INFO - 'LOGNAME': 'cltbld', 09:11:32 INFO - 'MAIL': '/var/mail/cltbld', 09:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:32 INFO - 'MOZ_NO_REMOTE': '1', 09:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:32 INFO - 'NO_EM_RESTART': '1', 09:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:32 INFO - 'PWD': '/builds/slave/test', 09:11:32 INFO - 'SHELL': '/bin/bash', 09:11:32 INFO - 'SHLVL': '1', 09:11:32 INFO - 'TERM': 'linux', 09:11:32 INFO - 'TMOUT': '86400', 09:11:32 INFO - 'USER': 'cltbld', 09:11:32 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:32 INFO - '_': '/tools/buildbot/bin/python'} 09:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:32 INFO - Running setup.py (path:/tmp/pip-y1zmgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:32 INFO - Running setup.py (path:/tmp/pip-A9sk4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:32 INFO - Running setup.py (path:/tmp/pip-MepgnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:32 INFO - Running setup.py (path:/tmp/pip-Yi6tWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:32 INFO - Running setup.py (path:/tmp/pip-piyskg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:32 INFO - Running setup.py (path:/tmp/pip-9NtUTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:33 INFO - Running setup.py (path:/tmp/pip-rcNRv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:33 INFO - Running setup.py (path:/tmp/pip-_E2oYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:33 INFO - Running setup.py (path:/tmp/pip-9yaKJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:33 INFO - Running setup.py (path:/tmp/pip-eJXi0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:33 INFO - Running setup.py (path:/tmp/pip-W5IWuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:33 INFO - Running setup.py (path:/tmp/pip-yqfTgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:33 INFO - Running setup.py (path:/tmp/pip-GLsvQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:33 INFO - Running setup.py (path:/tmp/pip-zxLBxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:33 INFO - Running setup.py (path:/tmp/pip-XhVxvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:33 INFO - Running setup.py (path:/tmp/pip-AsLNKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:33 INFO - Running setup.py (path:/tmp/pip-wU7eHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:11:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:35 INFO - Downloading blessings-1.6.tar.gz 09:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:35 INFO - Installing collected packages: blessings 09:11:35 INFO - Running setup.py install for blessings 09:11:36 INFO - Successfully installed blessings 09:11:36 INFO - Cleaning up... 09:11:36 INFO - Return code: 0 09:11:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:36 INFO - Reading from file tmpfile_stdout 09:11:36 INFO - Current package versions: 09:11:36 INFO - argparse == 1.2.1 09:11:36 INFO - blessings == 1.6 09:11:36 INFO - blobuploader == 1.2.4 09:11:36 INFO - docopt == 0.6.1 09:11:36 INFO - functools32 == 3.2.3-2 09:11:36 INFO - jsonschema == 2.5.1 09:11:36 INFO - manifestparser == 1.1 09:11:36 INFO - mozInstall == 1.12 09:11:36 INFO - mozcrash == 1.0 09:11:36 INFO - mozdebug == 0.1 09:11:36 INFO - mozdevice == 0.49 09:11:36 INFO - mozfile == 1.2 09:11:36 INFO - mozhttpd == 0.7 09:11:36 INFO - mozinfo == 0.9 09:11:36 INFO - mozleak == 0.1 09:11:36 INFO - mozlog == 3.4 09:11:36 INFO - moznetwork == 0.27 09:11:36 INFO - mozprocess == 0.23 09:11:36 INFO - mozprofile == 0.28 09:11:36 INFO - mozrunner == 6.13 09:11:36 INFO - mozscreenshot == 0.1 09:11:36 INFO - mozsystemmonitor == 0.3 09:11:36 INFO - moztest == 0.7 09:11:36 INFO - mozversion == 1.4 09:11:36 INFO - psutil == 3.1.1 09:11:36 INFO - requests == 1.2.3 09:11:36 INFO - wsgiref == 0.1.2 09:11:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:36 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 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:11:36 INFO - Using env: {'DISPLAY': ':0', 09:11:36 INFO - 'HOME': '/home/cltbld', 09:11:36 INFO - 'LANG': 'en_US.UTF-8', 09:11:36 INFO - 'LANGUAGE': 'en_US:en', 09:11:36 INFO - 'LOGNAME': 'cltbld', 09:11:36 INFO - 'MAIL': '/var/mail/cltbld', 09:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:36 INFO - 'MOZ_NO_REMOTE': '1', 09:11:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:36 INFO - 'NO_EM_RESTART': '1', 09:11:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:36 INFO - 'PWD': '/builds/slave/test', 09:11:36 INFO - 'SHELL': '/bin/bash', 09:11:36 INFO - 'SHLVL': '1', 09:11:36 INFO - 'TERM': 'linux', 09:11:36 INFO - 'TMOUT': '86400', 09:11:36 INFO - 'USER': 'cltbld', 09:11:36 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:36 INFO - '_': '/tools/buildbot/bin/python'} 09:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:11:36 INFO - Cleaning up... 09:11:36 INFO - Return code: 0 09:11:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x297c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b48a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cedc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:11:36 INFO - Using env: {'DISPLAY': ':0', 09:11:36 INFO - 'HOME': '/home/cltbld', 09:11:36 INFO - 'LANG': 'en_US.UTF-8', 09:11:36 INFO - 'LANGUAGE': 'en_US:en', 09:11:36 INFO - 'LOGNAME': 'cltbld', 09:11:36 INFO - 'MAIL': '/var/mail/cltbld', 09:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:36 INFO - 'MOZ_NO_REMOTE': '1', 09:11:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:36 INFO - 'NO_EM_RESTART': '1', 09:11:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:36 INFO - 'PWD': '/builds/slave/test', 09:11:36 INFO - 'SHELL': '/bin/bash', 09:11:36 INFO - 'SHLVL': '1', 09:11:36 INFO - 'TERM': 'linux', 09:11:36 INFO - 'TMOUT': '86400', 09:11:36 INFO - 'USER': 'cltbld', 09:11:36 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:36 INFO - '_': '/tools/buildbot/bin/python'} 09:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:36 INFO - Cleaning up... 09:11:36 INFO - Return code: 0 09:11:36 INFO - Running post-action listener: _resource_record_post_action 09:11:36 INFO - Running post-action listener: _start_resource_monitoring 09:11:36 INFO - Starting resource monitoring. 09:11:36 INFO - [mozharness: 2016-12-26 17:11:36.831128Z] Finished create-virtualenv step (success) 09:11:36 INFO - [mozharness: 2016-12-26 17:11:36.831642Z] Running install step. 09:11:36 INFO - Running pre-action listener: _resource_record_pre_action 09:11:36 INFO - Running main action method: install 09:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:37 INFO - Reading from file tmpfile_stdout 09:11:37 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:37 INFO - Reading from file tmpfile_stdout 09:11:37 INFO - Output received: 09:11:37 INFO - Usage: mozinstall [options] installer 09:11:37 INFO - Options: 09:11:37 INFO - -h, --help show this help message and exit 09:11:37 INFO - -d DEST, --destination=DEST 09:11:37 INFO - Directory to install application into. [default: 09:11:37 INFO - "/builds/slave/test"] 09:11:37 INFO - --app=APP Application being installed. [default: firefox] 09:11:37 INFO - mkdir: /builds/slave/test/build/application 09:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:11:54 INFO - Reading from file tmpfile_stdout 09:11:54 INFO - Output received: 09:11:54 INFO - /builds/slave/test/build/application/firefox/firefox 09:11:54 INFO - Running post-action listener: _resource_record_post_action 09:11:54 INFO - [mozharness: 2016-12-26 17:11:54.489534Z] Finished install step (success) 09:11:54 INFO - [mozharness: 2016-12-26 17:11:54.489776Z] Running run-tests step. 09:11:54 INFO - Running pre-action listener: _resource_record_pre_action 09:11:54 INFO - Running main action method: run_tests 09:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:54 INFO - Python 2.7.3 09:11:54 INFO - Return code: 0 09:11:54 INFO - grabbing minidump binary from tooltool 09:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:54 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 0x2ceb3f0>, '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 0x2cebcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cec1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:11:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:11:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:11:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:54 INFO - Return code: 0 09:11:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:11:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:11:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:11:54 INFO - Using env: {'DISPLAY': ':0', 09:11:54 INFO - 'HOME': '/home/cltbld', 09:11:54 INFO - 'LANG': 'en_US.UTF-8', 09:11:54 INFO - 'LANGUAGE': 'en_US:en', 09:11:54 INFO - 'LOGNAME': 'cltbld', 09:11:54 INFO - 'MAIL': '/var/mail/cltbld', 09:11:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:54 INFO - 'MOZ_NO_REMOTE': '1', 09:11:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:54 INFO - 'NO_EM_RESTART': '1', 09:11:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:54 INFO - 'PWD': '/builds/slave/test', 09:11:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:54 INFO - 'SHELL': '/bin/bash', 09:11:54 INFO - 'SHLVL': '1', 09:11:54 INFO - 'TERM': 'linux', 09:11:54 INFO - 'TMOUT': '86400', 09:11:54 INFO - 'USER': 'cltbld', 09:11:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720', 09:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:54 INFO - '_': '/tools/buildbot/bin/python'} 09:11:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:11:54 INFO - mozversion application_buildid: 20161226073116 09:11:54 INFO - mozversion application_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 09:11:54 INFO - mozversion application_display_name: Nightly 09:11:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:11:54 INFO - mozversion application_name: Firefox 09:11:54 INFO - mozversion application_remotingname: firefox 09:11:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:11:54 INFO - mozversion application_vendor: Mozilla 09:11:54 INFO - mozversion application_version: 53.0a1 09:11:54 INFO - mozversion platform_buildid: 20161226073116 09:11:54 INFO - mozversion platform_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 09:11:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:11:54 INFO - mozversion platform_version: 53.0a1 09:11:54 INFO - using testdate: 1482772314 09:11:54 INFO - actual date: 1482772314 09:11:54 INFO - starting webserver on 'localhost:56129' 09:11:54 INFO - SUITE-START | Running 2 tests 09:11:54 INFO - TEST-START | dromaeo_css 09:11:54 INFO - operating with platform_type : linux_ 09:11:54 INFO - Initialising browser for dromaeo_css test... 09:11:54 INFO - TEST-INFO | started process 25092 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Cq6bE/profile http://localhost:56129/getInfo.html) 09:11:55 INFO - PROCESS | 25092 | 1482772315226 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:11:58 INFO - PROCESS | 25092 | __metrics Screen width/height:1600/1200 09:11:58 INFO - PROCESS | 25092 | colorDepth:24 09:11:58 INFO - PROCESS | 25092 | Browser inner width/height: 1024/697 09:11:58 INFO - PROCESS | 25092 | __metrics 09:11:59 ERROR - PROCESS | 25092 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:12:00 INFO - TEST-INFO | 25092: exit 0 09:12:00 INFO - Browser initialized. 09:12:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:00 INFO - TEST-INFO | started process 25216 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Cq6bE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:12 INFO - PROCESS | 25216 | RSS: Main: 153702400 09:12:12 INFO - PROCESS | 25216 | 09:15:12 INFO - PROCESS | 25216 | Cycle 1(1): loaded http://localhost:56129/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56129/tests/dromaeo/cssquery-ext.html) 09:15:13 INFO - PROCESS | 25216 | RSS: Main: 163450880 09:15:13 INFO - PROCESS | 25216 | 09:17:01 INFO - PROCESS | 25216 | ************************* 09:17:01 INFO - PROCESS | 25216 | A coding exception was thrown and uncaught in a Task. 09:17:01 INFO - PROCESS | 25216 | 09:17:01 INFO - PROCESS | 25216 | Full message: ReferenceError: fetch is not defined 09:17:01 INFO - PROCESS | 25216 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:17:01 INFO - PROCESS | 25216 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:17:01 INFO - PROCESS | 25216 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:17:01 INFO - PROCESS | 25216 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:17:01 INFO - PROCESS | 25216 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:17:01 INFO - PROCESS | 25216 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:17:01 INFO - PROCESS | 25216 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:17:01 INFO - PROCESS | 25216 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:17:01 INFO - PROCESS | 25216 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:17:01 INFO - PROCESS | 25216 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:17:01 INFO - PROCESS | 25216 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:17:01 INFO - PROCESS | 25216 | 09:17:01 INFO - PROCESS | 25216 | ************************* 09:18:15 INFO - PROCESS | 25216 | Cycle 1(1): loaded http://localhost:56129/tests/dromaeo/cssquery-ext.html (next: http://localhost:56129/tests/dromaeo/cssquery-jquery.html) 09:18:16 INFO - PROCESS | 25216 | RSS: Main: 159977472 09:18:16 INFO - PROCESS | 25216 | 09:21:18 INFO - PROCESS | 25216 | Cycle 1(1): loaded http://localhost:56129/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56129/tests/dromaeo/cssquery-mootools.html) 09:21:18 INFO - PROCESS | 25216 | RSS: Main: 165158912 09:21:18 INFO - PROCESS | 25216 | 09:24:20 INFO - PROCESS | 25216 | Cycle 1(1): loaded http://localhost:56129/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56129/tests/dromaeo/cssquery-prototype.html) 09:24:21 INFO - PROCESS | 25216 | RSS: Main: 160137216 09:24:21 INFO - PROCESS | 25216 | 09:27:24 INFO - PROCESS | 25216 | Cycle 1(1): loaded http://localhost:56129/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56129/tests/dromaeo/cssquery-yui.html) 09:27:24 INFO - PROCESS | 25216 | RSS: Main: 164306944 09:27:24 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | Cycle 1(1): loaded http://localhost:56129/tests/dromaeo/cssquery-yui.html (next: http://localhost:56129/tests/dromaeo/cssquery-dojo.html) 09:30:27 INFO - PROCESS | 25216 | RSS: Main: 163442688 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | __start_tp_report 09:30:27 INFO - PROCESS | 25216 | _x_x_mozilla_page_load 09:30:27 INFO - PROCESS | 25216 | _x_x_mozilla_page_load_details 09:30:27 INFO - PROCESS | 25216 | |i|pagename|runs| 09:30:27 INFO - PROCESS | 25216 | |0;dojo.html;3141.8900338488156;3187.968120318796;3208.390405822894;3211.100891750311;3215.2765627733747;5169.250458683488;5330.653507522008;5332.493413125751;5367.946320536794;5395.325584301962;5178.844634660966;5210;5224.764885580148;5277.947220527793;5311.73441327934;4682.953170468303;4722.126406614779;4760.404949381327;4818.831340903069;4871.975640121795;4716.929246061295;4771.28430735389;4814.518548145193;4834.1540230459705;4840.661153719241;4730.0776348612035;4784.425868895755;4849.9757501212625;4863.489333619955;4955.033768415161;4756.6908150970075;4759.286107083931;4789.976050119727;4814.470408255089;4841.709497430165;4759.405074365704;4760.33355330254;4765.356676848615;4772.9761351193365;4818.614510839125;5081.237814327844;5107.157319042391;5182.32629758129;5241.31862857826;5285.550728188108;5553.361363443175;5642.069058605366;5642.097264437671;5678.375378708339;5687.829365119053;5638.069718496443;5651.5761317900915;5652.74562644682;5655.3779084300695;5662.37713851476;5224.164133738584;5251.632385733;5251.868703282448;5258.710770907599;5292.1003429416705;5651.095824668033;5655.886882262332;5667.829965101054;5716.599838011382;5765.4811067004175;5426.403095659474;5456.427075157131;5460.426655201147;5475.233467314579;5478.726063696799;5527.309086364205;5562.443755624405;5571.860703482445;5577.107662774018;5633.887322253532;5597.412271711493;5600.047991841397;5639.718014099279;5654.293213348332;5709.05800542914;5631.493165615114;5647.124695672177;5650.067738823048;5680.744366503517;5699.686517241508;4274.978625106854;4283.464566929134;4284.271673815459;4289.9571004289555;4319.611234988866;3772.07584141887;3791.184895247535;3798.3922572388287;3805.029717422095;3855.4602355670227;5457.2359869618285;5467.152591348347;5476.698781566973;5479.04116779562;5539.307586551681;5735.569832262597;5743.368229494752;5750.827475175752;5752.424757524298;5754.56840736938;5233.685978841282;5266.210068489757;5461.371942226615;5481.616286859881;5482.972585137129;4787.114383838932;4805.495422980537;4808.735519546459;4809.879753006133;4835.516448355136;6000.759969601254;6001.759929602855;6013.849653758691;6052.515798736091;6061.212042434456;5598.524125449219;5609.298837645294;5628.071368224361;5799.884002320015;5849.239598852122;5830.737616807169;5845.620034697731;5865.4134586541;5899.233099697012;5909.6158749681135;5542.2795036644975;5553.16702494629;5554.389017208023;5560.4161563035295;5565.443455654402;5470.64440811346;5494.148406996922;5648.15277708347;5708;5722.713864306851;5627.803026894203;5635.802746903839;5638.85902852432;5651.576131790214;5666.320041595035;5604.607677462538;5622.128570071645;5623.971880140573;5624.690642014565;5633.0987042073075;5359.27649767292;5433.185022246695;5491.341039075336;5571.498565129158;5630.718464076862;5512.586556008396;5607.07483265273;5645.181448689999;5650.434956504317;5658.5473162147755;5464.398916119303;5473.28847249855;5475.890482190254;5478.287822583039;5493.807716729895 09:30:27 INFO - PROCESS | 25216 | |1;ext.html;544.711303009405;563.264939254273;566.6741623566451;568.8833789073237;572.4533070917274;41822.58177418225;42864.78567607165;42900.356494652566;46271.53728462714;46548.76725616376;43857.56142438575;44417.55582444175;44420.3336949946;44519;44590.55409445905;8811.955940220307;8919.554022298891;8929.41958772681;8964.731058068268;10113.140383067448;9886.110250077492;10146.036126567964;10171.643992460266;10184.541695623677;10206.948965255164;10167.644132455365;10276.691699249035;10277.280590358678;10279.383237005766;10321.174306055498;10155.847662285034;10176.185905127571;10187.08316251541;10194.082532572103;10243.846342304834;10172.084512393845;10180.185585153244;10239.334443261165;10240.74398140045;10249.282550221413;37562.62437375646;37712.81143594265;37896.810515947516;38078.61921380806;38090.80954595209;3971.4042893565907;4042.6765858731233;4052.6555242804397;4062.2281766464275;4095.4266402703647;29260.41479170426;29468;29476.70523294761;29513;29698;4149.211649786531;4343.370211319372;4386;4418.513963464016;4544.704594201399;303807.48096259596;306480;307761;309144;309418;50815;51111.23333150006;51160.74419627878;51379.486205138215;51405.48594514082;53620.19569706458;53769.73115134478;53950.460495394545;54192.72903635457;54352.45647543474;14643.414263429344;14711.779323310158;14774.556763297118;14781.33483993223;14876.776848347283;10913.181511386678;10999.725006874893;11354.047947533702;11366.772664546663;11419.885801142049;16452.34190632385;16479.505614831574;16530.173491325626;16586.58533536647;16619.6676066478;6592.340765923369;6602.933970660232;6710.16122984627;6775.898361524581;6895.931040689529;923.1829830599834;923.3351986569659;929.8884133903974;933.2160984772688;937.9671711490065;29489.557656635166;29983.400331993238;30353.392932141232;31049.223769405493;31231;1079.9956040882055;1087.3910610058392;1127.2898074213358;1127.5433449452985;1147.6901236666054;15819.129947852749;16271.26779294911;16371.918140409221;16446.259918303465;16494.42269520561;497630;506411;506444;506502;507088;57494;57614.8146666802;57953;58038;58111;1353.4247944623496;1399.636094615387;1403.7192561487743;1407.0361802165548;1410.4781230944616;28892.42215155727;29668.406631867674;30360.848195759765;30485.695143048284;30561.847190764795;48965;50312;50460;50711.2393314108;50783.492165079355;405810;407723;411368;411945;412105;25668.229953101436;26161.345966350993;26239.34401639898;27302.453950921266;27775.861120694266;41261.38107928444;41284.1743165129;41872.3719144207;41952.58047419609;42459.787701061294;35438;35573.466398004566;35728.82135589305;36009.459858101625;36702;34332.48501272533;34717.13207169841;34877.302453951284;34907.47638785471;35060.298794024486;34264.65735342615;34583.65416345905;35040;35167.648323516434;35466.8226658865;30943.535846962764;32256.193595160308;32652;32719.34561308808;32817.50773738443;24687;24690;25256.368590785376;25780.484390311714;26256.606150908134 09:30:27 INFO - PROCESS | 25216 | |2;jquery.html;371.9014461167791;378.6856908765725;411.22894572676233;413.1324219139806;417.19481400896257;14058.367373468192;18586.535336616573;18921.337753178643;19195.21669357944;19235.90382048088;18769.436916892482;19254.807451925477;20966.26618068368;21599.35201943944;21806.127754889785;6192.78325258616;6455.580387274812;6501.902471462932;6502.544821862448;6510.08858759774;6259.530535209855;6396.1045453636525;6454.160959075313;6513.804585862432;6535.411812936834;6505.251896031969;6509;6519.869602607945;6524.836879078013;6539.574927629713;6292.622442653455;6492.513061520382;6516.022596610499;6541.247756507967;6547.083408322838;6157.3534778848025;6319.842003949892;6345.365463453618;6368.044793281045;6386.2336519617475;25292;25502.74497255041;25602.487950241077;25913.352166195808;25935.35161620956;2967.9703202968126;3231.4991176367703;3286.556314897482;3328.267781088181;3397.4394224952853;14904.850951490454;15938.920305398398;16267.18664066792;16461.58846028847;16615.750763738553;2053.9281125160696;2100.4433825036376;2102.98948505258;2116.153538584563;2135.6476181430044;88255;92029.99999999933;92688;93020.53489732576;94016.52991735066;17505.562360940825;17545.385911493035;17561.77067605281;18183.454406375342;19104;12304.876951230552;31012.379752404824;31082.378352432825;31178;31308.686913130576;7534.849303013909;7555.09338879327;7606.23937606246;7807.102183248886;7861.253180947915;7458.4033277337685;7458.925410745823;7620.961895190489;7700.730474433368;7755.650995705207;4124.072083781185;4137.441445404832;4202.919554820383;4226.070264541796;4276.786160691953;1959.6472634925851;1963.558199405151;1976.1107501624326;2010.286348346316;2013.677811550145;859.3941271403645;871.8822958900594;880.0869123202093;888.5157589113949;916.3356566489242;16913.32346706118;17066.914665426593;17500.299988000592;18386.448406547825;18442.26230950747;882.280941033056;885.0308911741632;885.3935054487567;893.2452077994085;919.5172534419349;10161.54273057714;10186.643467478603;10196.898031019744;10271.66250678773;10325.122364599094;425330;432631;438141;438549;443115;81438;83287.16712832915;83745.58127209324;83820.58089709512;84974.57512712397;972.8443449047977;996.9850452243222;1022.2077889635443;1023.9436830974219;1028.0593257169605;15671.921640391725;16115.516534503984;16252.593685157966;16348.673026539165;16351.182440878147;36259.818700906326;38559.42160867645;39314.606853931095;39459;39782.80108599438;46849;49366.75316623394;51592;51606.74196629143;52242.738786305825;8172.346212302882;8208.58957052157;8521.531315777444;8975.28197744192;9063.592138353659;10531.89468105309;12206;12590.811137833123;12959.740805183656;13003.934980325037;6121.265448146073;8522.78693032679;9584.808303833745;11745.706357341134;11757.647270581896;11650.30098194111;11702.122340824302;11758.58844940423;11827.940860295988;12020;11712.88287117152;11881.22772019816;12059.336736479227;12161.69595760113;12287.44706488228;7377.483576149618;7585.469017168745;8320.37597180226;8434.409591328547;8777.648894044421;10507.527161277609;10931.562737490729;10989.670309890716;11120.555177792798;11287.604933827277 09:30:27 INFO - PROCESS | 25216 | |3;mootools.html;1365.5015919189486;1367.3983447283194;1372.2932689664824;1374.1411617738906;1378.0973462382137;7546.358559522433;7768.88346674799;7861.567613781247;8344.874826877594;8363.414560980726;7984.201579842027;8240.464369815973;8255.42212045157;8382.538960357178;8394.24451799338;2332.1487657005155;2371.3241726108063;2374.204641445118;2375.714914210297;2381.392744850061;2285.2458688632796;2312.8727919964394;2343.039353864916;2349.9882500587473;2387.6657267982496;2402.8798560072014;2429.647701083342;2431.258466167818;2434.549608322466;2434.817388695846;2279.247848210095;2319.884005799703;2327.813774898004;2330.697009388786;2343.5664402085586;2331.778481044298;2333.9066437342485;2341.0518739910194;2365.8935347909387;2367.384480035203;8357.080721120672;8505.277051450634;8520.446170998926;8533.658653653847;8546.2308392245;5187.559057480081;5315.495027972336;5324.361076670824;5328.46715328468;5338.866528336823;3414.197663549064;3511.490833829106;3591.5510561179854;3628.9274214515563;3642.8542858285637;1360.815691282404;1404;1436.7772995185762;1474.8598883106088;1493.2085994422973;11226.382548959804;11230.775384492346;11275.32348059119;11334.319940803496;11362.318260904372;4211.663066954698;4452.688311818207;4526.569975852289;4572.88567785808;4608.631309495232;9020.8646870297;9097.90902090984;9120.680776172801;9127.361084724138;9127.90465144187;5155.819546315936;5175.120229560984;5202.479752024768;5203.9219411708855;5253.133233016586;4744.027474367746;4763.618910487153;4785.042991401664;4820.831270905572;4836.443808961944;3525.876594319238;3547.7516573839584;3597.6042635310096;3652.8538858445895;3725.776453412804;1049.7375656085978;1073.8550295710136;1105.5467258424007;1121.9158563107799;1165.0330225912471;966.2752935298527;977.1205914676706;978.5596481583174;991.6033586565432;995.2635050062902;5485.670859748428;5729.255196824386;5736.254286942671;5843.941560584425;5866.823995280063;937.5593471068587;944.3153713557616;954.4750387286964;960.7790208252142;981.3915372469115;3941.7043721720984;3966.7619942803526;3977.264206121819;4006.59934006597;4072.470578824734;9696.6121355145;10160.441175735405;10163.390196588229;10169.084782369622;10182;9917.355371900803;10076.59693612262;10097.646582369582;10132.594696212216;10135.239857010754;7618.390528757686;7755.1469338372735;7762.495437796638;7815.921840781633;7823.178566250575;5550.666959982414;5634.211210430461;5682.573806964577;5723.255976723;5835.095560188178;9021.819563608822;9124.133207345292;9193.908060919306;9217.078292170729;9241.584128714358;8964.641414343354;9252.398594091363;9323.627054917999;9409.34134610598;9429.669961551313;7485.139208991036;7550.622468876424;7592.506487078323;7634.656440460304;7666.041744781903;8046.074701409413;8056.637451314587;8071.959640201761;8114.837703245784;8143.837123257384;7193.712251509881;7238.0952380952385;7426.2945020223;7434.7769566913075;7536.0956685198125;6971.965140174267;7156.677949492181;7188.173360063451;7228.385587224934;7367.963160184165;6371.5221358397375;6472.579282346633;6501.4798816095545;6502;6505.41451269388;5054.19132938728;5088.875574196155;5141;5225.216217567396;5266.999270138662;5029.64792464524;5083.6187285952965;5094.592432605308;5147.742612869267;5168 09:30:27 INFO - PROCESS | 25216 | |4;prototype.html;382.8124219132626;401.9718619696621;403.43317638717605;408.14294957941377;411.8537919038743;5270.736463176845;5303.628745987782;5358.196270559418;5426.321709786272;5431.538319242864;5226.7647955841985;5299.867503312419;5382;5382.59630527711;5383.246345511631;4288.185244803493;4307.483102027761;4309.396684464162;4313.762743049131;4318.265894797892;3976.840926362942;4058.8985275368204;4297.828086876537;4306.935395969063;4307.82768689252;4154.356074808409;4161.271777438951;4167.6457501112445;4169.3954376615375;4179.958200418003;4138.52406973199;4149.792510374469;4162.188373267214;4175.582441755831;4184.644305234028;3733.757305775116;3743.494628225182;3746.1571146492092;3753.7560058596105;3756.211195648917;5071.54356107952;5180.766865491062;5198.766055527511;5208.270842082112;5287.86780330491;4805.765319126588;4824.614030877521;4848.587870031051;4848.781804818756;4857.368542089505;5482.506574408282;5650.039493286151;5662.2639056922335;5669.659820410789;5670.376258611549;4040.979795101035;4063.6342729154517;4079.673626109904;4080.2451546463703;4081.4081958115908;5789.768409263625;5815.651060936358;5821;5898.439648233411;5907.970460147715;4047.433359329696;4082.5509193988637;4095.9795201023803;4132.958670413317;4137.855175068858;4054.432379466877;4193.7693426861815;4213.1784302060805;4214.957850421518;4244.936325955113;4754.904901901942;4808.903821923612;4816.614670826326;4823.5658790709;4833.806647734052;5706.828795136153;5716.857078573069;5751.511121554631;5758.165066065397;5796.739146738407;1338.491373278148;1421;1422.2035660030417;1434.0535246737104;1439.7402273011114;956.273232343424;987.3779518903189;992.950352482373;999.0908273471107;999.8601594182637;5265.631405801634;5272.182811664122;5297.311349524537;5316.867078323073;5334.946650533445;5558.193587084133;5578.330600327987;5584.994700953786;5603.739158689344;5627.296587926509;5160.845174644766;5185.637005409661;5292.841214763564;5330.9733451332495;5348.491893270211;2940.8382538960564;2979.2551862034493;2982.3587928595457;3006.9175096965073;3016.8039077459894;5901.822945311647;5902.2622172228475;5939.881202376015;5944.851378715567;5966.806638672283;5481.780728770885;5709.51469125129;5744.798932037442;5766.8374359615145;5787.090260214203;5810.360860305447;5912.674802885883;5914.225732280299;5934.6142500737315;5943.257092863392;5422.728863556886;5555.972220138873;5598.972005139948;5602.803901863415;5784.987627165145;5761.654300741969;5762.567807414377;5768.913466298093;5826.30084389859;5848.795292164754;6082.36135205797;6105.328413874381;6162.845928851814;6215;6238.625682459067;3970.3647416413237;4140.441040459499;4173.560121757989;4188.183304255763;4195.811188496464;5681.602287839977;5820.185174075717;5822;5823.7379317931645;5913.231279933581;5735.627184233012;5737.426257374229;5754.597178197486;5789.623674461147;5800.69360464548;5668.433156684464;5713.229083894391;5735.713214339183;5758.2514273144225;5760.884782304414;5610.551155907599;5683.488486036276;5688.175214593944;5694.743736531783;5741.885162296647;5753.884922301614;5776.797812076726;5821.27234095738;5834.212381328466;5844.795432159854;5392.6225164239695;5570.69088764132;5599.60802743804;5644.85887852807;5714.857128571819 09:30:27 INFO - PROCESS | 25216 | |5;yui.html;724.7390939261868;734;764.4075841223051;808.2887059387738;838.8532006898791;15803;16466.50600481986;16477.423290184845;16547.917260413695;16565.66868662626;14995.700085998273;15278.923605381986;15285.847141528582;15480.225988700577;16092.114933678673;781.1563511407694;790.6955822008539;793.1909452358591;796.3271035974592;816.3713940265992;830.8961379827522;831.1107115386538;832.1719888710742;832.2592892325835;833.2500749325595;876.3252295732283;879.0769691823591;881.0220655072864;881.18930583863;881.4094556647062;868.0885070675828;868.587420975035;871.0331531999476;874.9300055995506;876.3996662286322;622.4180391334118;624.9687515624246;628.6448156791398;633.4552285034866;637.0857819029707;15283.465078722302;15653.217339132998;15777.60555986098;15875.444359447363;16266.593335166597;13935.163890166521;14149;14536.70926581473;14566.781498277534;14674.853251467455;11230.606928757454;11475.368854712988;11561.132915031407;11594.6521604352;11745.236559623598;3741.3639681254253;3777.58446570877;3808.9428658570146;3837.7889216093104;3894.279558281727;81306;81748;81831.590842046;81992.59003704943;82074.58962705148;9959.651412200537;10089.243306752023;10917.890821091847;11029.007389334998;11088.778224435466;21569.89215053946;21667.34997950064;21667.56664866694;21743;21769.346919592437;8035.919640803517;8125.024997000398;8134.105248422668;8265.917340826634;8416.116307787717;4439;4478.305862591304;4545.227311357077;4574.656900732459;4597.126545956325;3471.3057388522398;3536.9646303536633;3540.6105328413855;3593.586737525166;3606.224661697695;649.4414803269185;663.5288944849115;667.829703425633;668.7559040950116;682.853186564891;735.709394789066;745.4185735126609;756.3911051603402;765.0398749569255;792.6155814429997;9042.276617870555;9869.161121304625;10026.097651211427;10623.681289561171;10671.893281067245;732.2713899669862;737.7528527943092;747.8579069976687;757.1595528962847;762.5462849604477;4571.794269257892;4876.317315575822;4932.778024988884;4971.875703107451;4979.45226025137;69423.65288173527;81386.59306703428;83930.58034709787;85255.57372213223;86483;71526.64236678783;71852.64073679702;74024;74818.25181748113;75565;64633.99999999906;64742.35257647363;65303.34696652972;65788;67822;8975;9356.157945784911;9365.157135857806;9452;9452.385594936582;39116.60883391243;39161.41257881077;41318;42357.15285694331;45966.57685174464;45208.32187517256;52498.73750631222;54598.454015459334;56402.05185636966;56624;12973.805392918925;13160.802587960996;13284.335783210994;13675;13842;14487.420503179756;14549.854501455273;14768;15095.169765662993;15818.762718559;13572.93213533926;13906.860931390556;13941.651458713208;14990.175540345386;15148.772768408704;14282.785758213842;14558.4904528341;14615.34230959588;14922.626934326729;15059.623509411915;13562.186268823902;13955.372008259856;13987.790183147465;14124;14447.711045779235;11396.209889322385;11467.541298347973;11549.307041577533;11615.88384116148;11725.061995040205;10321.277510574188;10400.531976060944;10411.94794026025;10534.578616855546;10546.683599492027 09:30:27 INFO - PROCESS | 25216 | __end_tp_report 09:30:27 INFO - PROCESS | 25216 | __start_cc_report 09:30:27 INFO - PROCESS | 25216 | _x_x_mozilla_cycle_collect,318 09:30:27 INFO - PROCESS | 25216 | __end_cc_report 09:30:27 INFO - PROCESS | 25216 | __startTimestamp1482773427534__endTimestamp 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | ------- Summary: start ------- 09:30:27 INFO - PROCESS | 25216 | Number of tests: 6 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | [#0] dojo.html Cycles:165 Average:5260.96 Median:5478.73 stddev:600.31 (11.0%) stddev-sans-first:578.68 09:30:27 INFO - PROCESS | 25216 | Values: 3141.9 3188.0 3208.4 3211.1 3215.3 5169.3 5330.7 5332.5 5367.9 5395.3 5178.8 5210.0 5224.8 5277.9 5311.7 4683.0 4722.1 4760.4 4818.8 4872.0 4716.9 4771.3 4814.5 4834.2 4840.7 4730.1 4784.4 4850.0 4863.5 4955.0 4756.7 4759.3 4790.0 4814.5 4841.7 4759.4 4760.3 4765.4 4773.0 4818.6 5081.2 5107.2 5182.3 5241.3 5285.6 5553.4 5642.1 5642.1 5678.4 5687.8 5638.1 5651.6 5652.7 5655.4 5662.4 5224.2 5251.6 5251.9 5258.7 5292.1 5651.1 5655.9 5667.8 5716.6 5765.5 5426.4 5456.4 5460.4 5475.2 5478.7 5527.3 5562.4 5571.9 5577.1 5633.9 5597.4 5600.0 5639.7 5654.3 5709.1 5631.5 5647.1 5650.1 5680.7 5699.7 4275.0 4283.5 4284.3 4290.0 4319.6 3772.1 3791.2 3798.4 3805.0 3855.5 5457.2 5467.2 5476.7 5479.0 5539.3 5735.6 5743.4 5750.8 5752.4 5754.6 5233.7 5266.2 5461.4 5481.6 5483.0 4787.1 4805.5 4808.7 4809.9 4835.5 6000.8 6001.8 6013.8 6052.5 6061.2 5598.5 5609.3 5628.1 5799.9 5849.2 5830.7 5845.6 5865.4 5899.2 5909.6 5542.3 5553.2 5554.4 5560.4 5565.4 5470.6 5494.1 5648.2 5708.0 5722.7 5627.8 5635.8 5638.9 5651.6 5666.3 5604.6 5622.1 5624.0 5624.7 5633.1 5359.3 5433.2 5491.3 5571.5 5630.7 5512.6 5607.1 5645.2 5650.4 5658.5 5464.4 5473.3 5475.9 5478.3 5493.8 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | [#1] ext.html Cycles:180 Average:55855.82 Median:34973.74 stddev:110064.87 (314.7%) stddev-sans-first:110295.29 09:30:27 INFO - PROCESS | 25216 | Values: 544.7 563.3 566.7 568.9 572.5 41822.6 42864.8 42900.4 46271.5 46548.8 43857.6 44417.6 44420.3 44519.0 44590.6 8812.0 8919.6 8929.4 8964.7 10113.1 9886.1 10146.0 10171.6 10184.5 10206.9 10167.6 10276.7 10277.3 10279.4 10321.2 10155.8 10176.2 10187.1 10194.1 10243.8 10172.1 10180.2 10239.3 10240.7 10249.3 37562.6 37712.8 37896.8 38078.6 38090.8 3971.4 4042.7 4052.7 4062.2 4095.4 29260.4 29468.0 29476.7 29513.0 29698.0 4149.2 4343.4 4386.0 4418.5 4544.7 303807.5 306480.0 307761.0 309144.0 309418.0 50815.0 51111.2 51160.7 51379.5 51405.5 53620.2 53769.7 53950.5 54192.7 54352.5 14643.4 14711.8 14774.6 14781.3 14876.8 10913.2 10999.7 11354.0 11366.8 11419.9 16452.3 16479.5 16530.2 16586.6 16619.7 6592.3 6602.9 6710.2 6775.9 6895.9 923.2 923.3 929.9 933.2 938.0 29489.6 29983.4 30353.4 31049.2 31231.0 1080.0 1087.4 1127.3 1127.5 1147.7 15819.1 16271.3 16371.9 16446.3 16494.4 497630.0 506411.0 506444.0 506502.0 507088.0 57494.0 57614.8 57953.0 58038.0 58111.0 1353.4 1399.6 1403.7 1407.0 1410.5 28892.4 29668.4 30360.8 30485.7 30561.8 48965.0 50312.0 50460.0 50711.2 50783.5 405810.0 407723.0 411368.0 411945.0 412105.0 25668.2 26161.3 26239.3 27302.5 27775.9 41261.4 41284.2 41872.4 41952.6 42459.8 35438.0 35573.5 35728.8 36009.5 36702.0 34332.5 34717.1 34877.3 34907.5 35060.3 34264.7 34583.7 35040.0 35167.6 35466.8 30943.5 32256.2 32652.0 32719.3 32817.5 24687.0 24690.0 25256.4 25780.5 26256.6 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | [#2] jquery.html Cycles:180 Average:27916.75 Median:39620.90 stddev:72037.71 (181.8%) stddev-sans-first:72210.11 09:30:27 INFO - PROCESS | 25216 | Values: 371.9 378.7 411.2 413.1 417.2 14058.4 18586.5 18921.3 19195.2 19235.9 18769.4 19254.8 20966.3 21599.4 21806.1 6192.8 6455.6 6501.9 6502.5 6510.1 6259.5 6396.1 6454.2 6513.8 6535.4 6505.3 6509.0 6519.9 6524.8 6539.6 6292.6 6492.5 6516.0 6541.2 6547.1 6157.4 6319.8 6345.4 6368.0 6386.2 25292.0 25502.7 25602.5 25913.4 25935.4 2968.0 3231.5 3286.6 3328.3 3397.4 14904.9 15938.9 16267.2 16461.6 16615.8 2053.9 2100.4 2103.0 2116.2 2135.6 88255.0 92030.0 92688.0 93020.5 94016.5 17505.6 17545.4 17561.8 18183.5 19104.0 12304.9 31012.4 31082.4 31178.0 31308.7 7534.8 7555.1 7606.2 7807.1 7861.3 7458.4 7458.9 7621.0 7700.7 7755.7 4124.1 4137.4 4202.9 4226.1 4276.8 1959.6 1963.6 1976.1 2010.3 2013.7 859.4 871.9 880.1 888.5 916.3 16913.3 17066.9 17500.3 18386.4 18442.3 882.3 885.0 885.4 893.2 919.5 10161.5 10186.6 10196.9 10271.7 10325.1 425330.0 432631.0 438141.0 438549.0 443115.0 81438.0 83287.2 83745.6 83820.6 84974.6 972.8 997.0 1022.2 1023.9 1028.1 15671.9 16115.5 16252.6 16348.7 16351.2 36259.8 38559.4 39314.6 39459.0 39782.8 46849.0 49366.8 51592.0 51606.7 52242.7 8172.3 8208.6 8521.5 8975.3 9063.6 10531.9 12206.0 12590.8 12959.7 13003.9 6121.3 8522.8 9584.8 11745.7 11757.6 11650.3 11702.1 11758.6 11827.9 12020.0 11712.9 11881.2 12059.3 12161.7 12287.4 7377.5 7585.5 8320.4 8434.4 8777.6 10507.5 10931.6 10989.7 11120.6 11287.6 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | [#3] mootools.html Cycles:180 Average:5510.49 Median:5214.57 stddev:2976.85 (57.1%) stddev-sans-first:2968.90 09:30:27 INFO - PROCESS | 25216 | Values: 1365.5 1367.4 1372.3 1374.1 1378.1 7546.4 7768.9 7861.6 8344.9 8363.4 7984.2 8240.5 8255.4 8382.5 8394.2 2332.1 2371.3 2374.2 2375.7 2381.4 2285.2 2312.9 2343.0 2350.0 2387.7 2402.9 2429.6 2431.3 2434.5 2434.8 2279.2 2319.9 2327.8 2330.7 2343.6 2331.8 2333.9 2341.1 2365.9 2367.4 8357.1 8505.3 8520.4 8533.7 8546.2 5187.6 5315.5 5324.4 5328.5 5338.9 3414.2 3511.5 3591.6 3628.9 3642.9 1360.8 1404.0 1436.8 1474.9 1493.2 11226.4 11230.8 11275.3 11334.3 11362.3 4211.7 4452.7 4526.6 4572.9 4608.6 9020.9 9097.9 9120.7 9127.4 9127.9 5155.8 5175.1 5202.5 5203.9 5253.1 4744.0 4763.6 4785.0 4820.8 4836.4 3525.9 3547.8 3597.6 3652.9 3725.8 1049.7 1073.9 1105.5 1121.9 1165.0 966.3 977.1 978.6 991.6 995.3 5485.7 5729.3 5736.3 5843.9 5866.8 937.6 944.3 954.5 960.8 981.4 3941.7 3966.8 3977.3 4006.6 4072.5 9696.6 10160.4 10163.4 10169.1 10182.0 9917.4 10076.6 10097.6 10132.6 10135.2 7618.4 7755.1 7762.5 7815.9 7823.2 5550.7 5634.2 5682.6 5723.3 5835.1 9021.8 9124.1 9193.9 9217.1 9241.6 8964.6 9252.4 9323.6 9409.3 9429.7 7485.1 7550.6 7592.5 7634.7 7666.0 8046.1 8056.6 8072.0 8114.8 8143.8 7193.7 7238.1 7426.3 7434.8 7536.1 6972.0 7156.7 7188.2 7228.4 7368.0 6371.5 6472.6 6501.5 6502.0 6505.4 5054.2 5088.9 5141.0 5225.2 5267.0 5029.6 5083.6 5094.6 5147.7 5168.0 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | [#4] prototype.html Cycles:180 Average:4755.54 Median:5382.30 stddev:1394.30 (25.9%) stddev-sans-first:1359.03 09:30:27 INFO - PROCESS | 25216 | Values: 382.8 402.0 403.4 408.1 411.9 5270.7 5303.6 5358.2 5426.3 5431.5 5226.8 5299.9 5382.0 5382.6 5383.2 4288.2 4307.5 4309.4 4313.8 4318.3 3976.8 4058.9 4297.8 4306.9 4307.8 4154.4 4161.3 4167.6 4169.4 4180.0 4138.5 4149.8 4162.2 4175.6 4184.6 3733.8 3743.5 3746.2 3753.8 3756.2 5071.5 5180.8 5198.8 5208.3 5287.9 4805.8 4824.6 4848.6 4848.8 4857.4 5482.5 5650.0 5662.3 5669.7 5670.4 4041.0 4063.6 4079.7 4080.2 4081.4 5789.8 5815.7 5821.0 5898.4 5908.0 4047.4 4082.6 4096.0 4133.0 4137.9 4054.4 4193.8 4213.2 4215.0 4244.9 4754.9 4808.9 4816.6 4823.6 4833.8 5706.8 5716.9 5751.5 5758.2 5796.7 1338.5 1421.0 1422.2 1434.1 1439.7 956.3 987.4 993.0 999.1 999.9 5265.6 5272.2 5297.3 5316.9 5334.9 5558.2 5578.3 5585.0 5603.7 5627.3 5160.8 5185.6 5292.8 5331.0 5348.5 2940.8 2979.3 2982.4 3006.9 3016.8 5901.8 5902.3 5939.9 5944.9 5966.8 5481.8 5709.5 5744.8 5766.8 5787.1 5810.4 5912.7 5914.2 5934.6 5943.3 5422.7 5556.0 5599.0 5602.8 5785.0 5761.7 5762.6 5768.9 5826.3 5848.8 6082.4 6105.3 6162.8 6215.0 6238.6 3970.4 4140.4 4173.6 4188.2 4195.8 5681.6 5820.2 5822.0 5823.7 5913.2 5735.6 5737.4 5754.6 5789.6 5800.7 5668.4 5713.2 5735.7 5758.3 5760.9 5610.6 5683.5 5688.2 5694.7 5741.9 5753.9 5776.8 5821.3 5834.2 5844.8 5392.6 5570.7 5599.6 5644.9 5714.9 09:30:27 INFO - PROCESS | 25216 | 09:30:27 INFO - PROCESS | 25216 | [#5] yui.html Cycles:180 Average:17981.36 Median:4573.23 stddev:23197.33 (507.2%) stddev-sans-first:23226.21 09:30:27 INFO - PROCESS | 25216 | Values: 724.7 734.0 764.4 808.3 838.9 15803.0 16466.5 16477.4 16547.9 16565.7 14995.7 15278.9 15285.8 15480.2 16092.1 781.2 790.7 793.2 796.3 816.4 830.9 831.1 832.2 832.3 833.3 876.3 879.1 881.0 881.2 881.4 868.1 868.6 871.0 874.9 876.4 622.4 625.0 628.6 633.5 637.1 15283.5 15653.2 15777.6 15875.4 16266.6 13935.2 14149.0 14536.7 14566.8 14674.9 11230.6 11475.4 11561.1 11594.7 11745.2 3741.4 3777.6 3808.9 3837.8 3894.3 81306.0 81748.0 81831.6 81992.6 82074.6 9959.7 10089.2 10917.9 11029.0 11088.8 21569.9 21667.3 21667.6 21743.0 21769.3 8035.9 8125.0 8134.1 8265.9 8416.1 4439.0 4478.3 4545.2 4574.7 4597.1 3471.3 3537.0 3540.6 3593.6 3606.2 649.4 663.5 667.8 668.8 682.9 735.7 745.4 756.4 765.0 792.6 9042.3 9869.2 10026.1 10623.7 10671.9 732.3 737.8 747.9 757.2 762.5 4571.8 4876.3 4932.8 4971.9 4979.5 69423.7 81386.6 83930.6 85255.6 86483.0 71526.6 71852.6 74024.0 74818.3 75565.0 64634.0 64742.4 65303.3 65788.0 67822.0 8975.0 9356.2 9365.2 9452.0 9452.4 39116.6 39161.4 41318.0 42357.2 45966.6 45208.3 52498.7 54598.5 56402.1 56624.0 12973.8 13160.8 13284.3 13675.0 13842.0 14487.4 14549.9 14768.0 15095.2 15818.8 13572.9 13906.9 13941.7 14990.2 15148.8 14282.8 14558.5 14615.3 14922.6 15059.6 13562.2 13955.4 13987.8 14124.0 14447.7 11396.2 11467.5 11549.3 11615.9 11725.1 10321.3 10400.5 10411.9 10534.6 10546.7 09:30:27 INFO - PROCESS | 25216 | -------- Summary: end -------- 09:30:27 INFO - PROCESS | 25216 | 09:30:28 INFO - TEST-INFO | 25216: exit 0 09:30:28 INFO - TEST-OK | dromaeo_css | took 1113587ms 09:30:28 INFO - TEST-START | kraken 09:30:28 INFO - operating with platform_type : linux_ 09:30:28 INFO - Initialising browser for kraken test... 09:30:28 INFO - TEST-INFO | started process 25569 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvie9JJ/profile http://localhost:56129/getInfo.html) 09:30:28 INFO - PROCESS | 25569 | 1482773428792 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:30:32 INFO - PROCESS | 25569 | __metrics Screen width/height:1600/1200 09:30:32 INFO - PROCESS | 25569 | colorDepth:24 09:30:32 INFO - PROCESS | 25569 | Browser inner width/height: 1024/697 09:30:32 INFO - PROCESS | 25569 | __metrics 09:30:33 ERROR - PROCESS | 25569 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:30:34 INFO - TEST-INFO | 25569: exit 0 09:30:34 INFO - Browser initialized. 09:30:34 INFO - Running cycle 1/1 for kraken test... 09:30:34 INFO - TEST-INFO | started process 25692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvie9JJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:45 INFO - PROCESS | 25692 | RSS: Main: 153812992 09:30:45 INFO - PROCESS | 25692 | 09:30:48 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56129/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:49 INFO - PROCESS | 25692 | RSS: Main: 160653312 09:30:49 INFO - PROCESS | 25692 | 09:30:52 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56129/tests/kraken/driver.html?testName=audio-dft) 09:30:52 INFO - PROCESS | 25692 | RSS: Main: 159256576 09:30:52 INFO - PROCESS | 25692 | 09:30:55 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56129/tests/kraken/driver.html?testName=audio-fft) 09:30:56 INFO - PROCESS | 25692 | RSS: Main: 156237824 09:30:56 INFO - PROCESS | 25692 | 09:30:58 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56129/tests/kraken/driver.html?testName=audio-oscillator) 09:30:58 INFO - PROCESS | 25692 | RSS: Main: 156516352 09:30:58 INFO - PROCESS | 25692 | 09:31:01 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56129/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:01 INFO - PROCESS | 25692 | RSS: Main: 157474816 09:31:01 INFO - PROCESS | 25692 | 09:31:04 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56129/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:05 INFO - PROCESS | 25692 | RSS: Main: 161120256 09:31:05 INFO - PROCESS | 25692 | 09:31:08 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56129/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:09 INFO - PROCESS | 25692 | RSS: Main: 158302208 09:31:09 INFO - PROCESS | 25692 | 09:31:12 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56129/tests/kraken/driver.html?testName=json-parse-financial) 09:31:12 INFO - PROCESS | 25692 | RSS: Main: 159068160 09:31:12 INFO - PROCESS | 25692 | 09:31:14 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56129/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:15 INFO - PROCESS | 25692 | RSS: Main: 159150080 09:31:15 INFO - PROCESS | 25692 | 09:31:17 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:31:17 INFO - PROCESS | 25692 | RSS: Main: 158429184 09:31:17 INFO - PROCESS | 25692 | 09:31:19 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:31:20 INFO - PROCESS | 25692 | RSS: Main: 159830016 09:31:20 INFO - PROCESS | 25692 | 09:31:23 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:31:23 INFO - PROCESS | 25692 | RSS: Main: 159272960 09:31:23 INFO - PROCESS | 25692 | 09:31:26 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:31:27 INFO - PROCESS | 25692 | RSS: Main: 158957568 09:31:27 INFO - PROCESS | 25692 | 09:31:28 INFO - PROCESS | 25692 | Cycle 1(1): loaded http://localhost:56129/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56129/tests/kraken/driver.html?testName=ai-astar) 09:31:29 INFO - PROCESS | 25692 | RSS: Main: 161296384 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | __start_tp_report 09:31:29 INFO - PROCESS | 25692 | _x_x_mozilla_page_load 09:31:29 INFO - PROCESS | 25692 | _x_x_mozilla_page_load_details 09:31:29 INFO - PROCESS | 25692 | |i|pagename|runs| 09:31:29 INFO - PROCESS | 25692 | |0;ai-astar;104;112;112;112;113;112;113;110;112;113 09:31:29 INFO - PROCESS | 25692 | |1;audio-beat-detection;151;121;123;170;131;125;127;124;146;124 09:31:29 INFO - PROCESS | 25692 | |2;audio-dft;163;163;162;163;165;172;163;164;162;161 09:31:29 INFO - PROCESS | 25692 | |3;audio-fft;76;76;75;118;76;78;77;76;97;76 09:31:29 INFO - PROCESS | 25692 | |4;audio-oscillator;84;84;110;85;83;83;95;89;87;105 09:31:29 INFO - PROCESS | 25692 | |5;imaging-gaussian-blur;119;117;117;118;117;118;119;118;119;118 09:31:29 INFO - PROCESS | 25692 | |6;imaging-darkroom;150;148;148;147;148;147;148;147;148;150 09:31:29 INFO - PROCESS | 25692 | |7;imaging-desaturate;97;98;96;96;97;96;99;97;96;97 09:31:29 INFO - PROCESS | 25692 | |8;json-parse-financial;57;57;56;60;59;57;59;58;65;60 09:31:29 INFO - PROCESS | 25692 | |9;json-stringify-tinderbox;68;69;71;68;67;67;68;68;68;69 09:31:29 INFO - PROCESS | 25692 | |10;stanford-crypto-aes;91;83;86;82;83;81;84;88;82;82 09:31:29 INFO - PROCESS | 25692 | |11;stanford-crypto-ccm;126;132;127;130;131;129;130;128;171;128 09:31:29 INFO - PROCESS | 25692 | |12;stanford-crypto-pbkdf2;166;165;168;167;165;167;170;170;172;165 09:31:29 INFO - PROCESS | 25692 | |13;stanford-crypto-sha256-iterative;67;64;64;68;63;63;66;63;63;63 09:31:29 INFO - PROCESS | 25692 | __end_tp_report 09:31:29 INFO - PROCESS | 25692 | __start_cc_report 09:31:29 INFO - PROCESS | 25692 | _x_x_mozilla_cycle_collect,715 09:31:29 INFO - PROCESS | 25692 | __end_cc_report 09:31:29 INFO - PROCESS | 25692 | __startTimestamp1482773489185__endTimestamp 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | ------- Summary: start ------- 09:31:29 INFO - PROCESS | 25692 | Number of tests: 14 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.71 (2.4%) stddev-sans-first:0.93 09:31:29 INFO - PROCESS | 25692 | Values: 104.0 112.0 112.0 112.0 113.0 112.0 113.0 110.0 112.0 113.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:129.00 stddev:16.19 (12.5%) stddev-sans-first:15.98 09:31:29 INFO - PROCESS | 25692 | Values: 151.0 121.0 123.0 170.0 131.0 125.0 127.0 124.0 146.0 124.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:3.08 (1.9%) stddev-sans-first:3.26 09:31:29 INFO - PROCESS | 25692 | Values: 163.0 163.0 162.0 163.0 165.0 172.0 163.0 164.0 162.0 161.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#3] audio-fft Cycles:10 Average:82.50 Median:76.00 stddev:14.10 (18.5%) stddev-sans-first:14.75 09:31:29 INFO - PROCESS | 25692 | Values: 76.0 76.0 75.0 118.0 76.0 78.0 77.0 76.0 97.0 76.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:84.50 stddev:9.73 (11.5%) stddev-sans-first:10.03 09:31:29 INFO - PROCESS | 25692 | Values: 84.0 84.0 110.0 85.0 83.0 83.0 95.0 89.0 87.0 105.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 09:31:29 INFO - PROCESS | 25692 | Values: 119.0 117.0 117.0 118.0 117.0 118.0 119.0 118.0 119.0 118.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:1.10 (0.7%) stddev-sans-first:0.93 09:31:29 INFO - PROCESS | 25692 | Values: 150.0 148.0 148.0 147.0 148.0 147.0 148.0 147.0 148.0 150.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:97.00 stddev:0.99 (1.0%) stddev-sans-first:1.05 09:31:29 INFO - PROCESS | 25692 | Values: 97.0 98.0 96.0 96.0 97.0 96.0 99.0 97.0 96.0 97.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.00 stddev:2.57 (4.4%) stddev-sans-first:2.65 09:31:29 INFO - PROCESS | 25692 | Values: 57.0 57.0 56.0 60.0 59.0 57.0 59.0 58.0 65.0 60.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 09:31:29 INFO - PROCESS | 25692 | Values: 68.0 69.0 71.0 68.0 67.0 67.0 68.0 68.0 68.0 69.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:83.50 stddev:3.19 (3.8%) stddev-sans-first:2.24 09:31:29 INFO - PROCESS | 25692 | Values: 91.0 83.0 86.0 82.0 83.0 81.0 84.0 88.0 82.0 82.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#11] stanford-crypto-ccm Cycles:10 Average:133.20 Median:130.00 stddev:13.41 (10.3%) stddev-sans-first:13.96 09:31:29 INFO - PROCESS | 25692 | Values: 126.0 132.0 127.0 130.0 131.0 129.0 130.0 128.0 171.0 128.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:167.50 stddev:2.46 (1.5%) stddev-sans-first:2.55 09:31:29 INFO - PROCESS | 25692 | Values: 166.0 165.0 168.0 167.0 165.0 167.0 170.0 170.0 172.0 165.0 09:31:29 INFO - PROCESS | 25692 | 09:31:29 INFO - PROCESS | 25692 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.00 stddev:1.90 (3.0%) stddev-sans-first:1.76 09:31:29 INFO - PROCESS | 25692 | Values: 67.0 64.0 64.0 68.0 63.0 63.0 66.0 63.0 63.0 63.0 09:31:29 INFO - PROCESS | 25692 | -------- Summary: end -------- 09:31:29 INFO - PROCESS | 25692 | 09:31:30 INFO - TEST-INFO | 25692: exit 0 09:31:30 INFO - TEST-OK | kraken | took 61891ms 09:31:30 INFO - SUITE-END | took 1175s 09:31:30 INFO - Completed test suite (00:19:35) 09:31:30 INFO - javascript benchmark 09:31:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7187.273568326982, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.8900338488156, 3187.968120318796, 3208.390405822894, 3211.100891750311, 3215.2765627733747, 5169.250458683488, 5330.653507522008, 5332.493413125751, 5367.946320536794, 5395.325584301962, 5178.844634660966, 5210.0, 5224.764885580148, 5277.947220527793, 5311.73441327934, 4682.953170468303, 4722.126406614779, 4760.404949381327, 4818.831340903069, 4871.975640121795, 4716.929246061295, 4771.28430735389, 4814.518548145193, 4834.1540230459705, 4840.661153719241, 4730.0776348612035, 4784.425868895755, 4849.9757501212625, 4863.489333619955, 4955.033768415161, 4756.6908150970075, 4759.286107083931, 4789.976050119727, 4814.470408255089, 4841.709497430165, 4759.405074365704, 4760.33355330254, 4765.356676848615, 4772.9761351193365, 4818.614510839125, 5081.237814327844, 5107.157319042391, 5182.32629758129, 5241.31862857826, 5285.550728188108, 5553.361363443175, 5642.069058605366, 5642.097264437671, 5678.375378708339, 5687.829365119053, 5638.069718496443, 5651.5761317900915, 5652.74562644682, 5655.3779084300695, 5662.37713851476, 5224.164133738584, 5251.632385733, 5251.868703282448, 5258.710770907599, 5292.1003429416705, 5651.095824668033, 5655.886882262332, 5667.829965101054, 5716.599838011382, 5765.4811067004175, 5426.403095659474, 5456.427075157131, 5460.426655201147, 5475.233467314579, 5478.726063696799, 5527.309086364205, 5562.443755624405, 5571.860703482445, 5577.107662774018, 5633.887322253532, 5597.412271711493, 5600.047991841397, 5639.718014099279, 5654.293213348332, 5709.05800542914, 5631.493165615114, 5647.124695672177, 5650.067738823048, 5680.744366503517, 5699.686517241508, 4274.978625106854, 4283.464566929134, 4284.271673815459, 4289.9571004289555, 4319.611234988866, 3772.07584141887, 3791.184895247535, 3798.3922572388287, 3805.029717422095, 3855.4602355670227, 5457.2359869618285, 5467.152591348347, 5476.698781566973, 5479.04116779562, 5539.307586551681, 5735.569832262597, 5743.368229494752, 5750.827475175752, 5752.424757524298, 5754.56840736938, 5233.685978841282, 5266.210068489757, 5461.371942226615, 5481.616286859881, 5482.972585137129, 4787.114383838932, 4805.495422980537, 4808.735519546459, 4809.879753006133, 4835.516448355136, 6000.759969601254, 6001.759929602855, 6013.849653758691, 6052.515798736091, 6061.212042434456, 5598.524125449219, 5609.298837645294, 5628.071368224361, 5799.884002320015, 5849.239598852122, 5830.737616807169, 5845.620034697731, 5865.4134586541, 5899.233099697012, 5909.6158749681135, 5542.2795036644975, 5553.16702494629, 5554.389017208023, 5560.4161563035295, 5565.443455654402, 5470.64440811346, 5494.148406996922, 5648.15277708347, 5708.0, 5722.713864306851, 5627.803026894203, 5635.802746903839, 5638.85902852432, 5651.576131790214, 5666.320041595035, 5604.607677462538, 5622.128570071645, 5623.971880140573, 5624.690642014565, 5633.0987042073075, 5359.27649767292, 5433.185022246695, 5491.341039075336, 5571.498565129158, 5630.718464076862, 5512.586556008396, 5607.07483265273, 5645.181448689999, 5650.434956504317, 5658.5473162147755, 5464.398916119303, 5473.28847249855, 5475.890482190254, 5478.287822583039, 5493.807716729895], "value": 5221.152891639496, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.711303009405, 563.264939254273, 566.6741623566451, 568.8833789073237, 572.4533070917274, 41822.58177418225, 42864.78567607165, 42900.356494652566, 46271.53728462714, 46548.76725616376, 43857.56142438575, 44417.55582444175, 44420.3336949946, 44519.0, 44590.55409445905, 8811.955940220307, 8919.554022298891, 8929.41958772681, 8964.731058068268, 10113.140383067448, 9886.110250077492, 10146.036126567964, 10171.643992460266, 10184.541695623677, 10206.948965255164, 10167.644132455365, 10276.691699249035, 10277.280590358678, 10279.383237005766, 10321.174306055498, 10155.847662285034, 10176.185905127571, 10187.08316251541, 10194.082532572103, 10243.846342304834, 10172.084512393845, 10180.185585153244, 10239.334443261165, 10240.74398140045, 10249.282550221413, 37562.62437375646, 37712.81143594265, 37896.810515947516, 38078.61921380806, 38090.80954595209, 3971.4042893565907, 4042.6765858731233, 4052.6555242804397, 4062.2281766464275, 4095.4266402703647, 29260.41479170426, 29468.0, 29476.70523294761, 29513.0, 29698.0, 4149.211649786531, 4343.370211319372, 4386.0, 4418.513963464016, 4544.704594201399, 303807.48096259596, 306480.0, 307761.0, 309144.0, 309418.0, 50815.0, 51111.23333150006, 51160.74419627878, 51379.486205138215, 51405.48594514082, 53620.19569706458, 53769.73115134478, 53950.460495394545, 54192.72903635457, 54352.45647543474, 14643.414263429344, 14711.779323310158, 14774.556763297118, 14781.33483993223, 14876.776848347283, 10913.181511386678, 10999.725006874893, 11354.047947533702, 11366.772664546663, 11419.885801142049, 16452.34190632385, 16479.505614831574, 16530.173491325626, 16586.58533536647, 16619.6676066478, 6592.340765923369, 6602.933970660232, 6710.16122984627, 6775.898361524581, 6895.931040689529, 923.1829830599834, 923.3351986569659, 929.8884133903974, 933.2160984772688, 937.9671711490065, 29489.557656635166, 29983.400331993238, 30353.392932141232, 31049.223769405493, 31231.0, 1079.9956040882055, 1087.3910610058392, 1127.2898074213358, 1127.5433449452985, 1147.6901236666054, 15819.129947852749, 16271.26779294911, 16371.918140409221, 16446.259918303465, 16494.42269520561, 497630.0, 506411.0, 506444.0, 506502.0, 507088.0, 57494.0, 57614.8146666802, 57953.0, 58038.0, 58111.0, 1353.4247944623496, 1399.636094615387, 1403.7192561487743, 1407.0361802165548, 1410.4781230944616, 28892.42215155727, 29668.406631867674, 30360.848195759765, 30485.695143048284, 30561.847190764795, 48965.0, 50312.0, 50460.0, 50711.2393314108, 50783.492165079355, 405810.0, 407723.0, 411368.0, 411945.0, 412105.0, 25668.229953101436, 26161.345966350993, 26239.34401639898, 27302.453950921266, 27775.861120694266, 41261.38107928444, 41284.1743165129, 41872.3719144207, 41952.58047419609, 42459.787701061294, 35438.0, 35573.466398004566, 35728.82135589305, 36009.459858101625, 36702.0, 34332.48501272533, 34717.13207169841, 34877.302453951284, 34907.47638785471, 35060.298794024486, 34264.65735342615, 34583.65416345905, 35040.0, 35167.648323516434, 35466.8226658865, 30943.535846962764, 32256.193595160308, 32652.0, 32719.34561308808, 32817.50773738443, 24687.0, 24690.0, 25256.368590785376, 25780.484390311714, 26256.606150908134], "value": 19245.39135665715, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.9014461167791, 378.6856908765725, 411.22894572676233, 413.1324219139806, 417.19481400896257, 14058.367373468192, 18586.535336616573, 18921.337753178643, 19195.21669357944, 19235.90382048088, 18769.436916892482, 19254.807451925477, 20966.26618068368, 21599.35201943944, 21806.127754889785, 6192.78325258616, 6455.580387274812, 6501.902471462932, 6502.544821862448, 6510.08858759774, 6259.530535209855, 6396.1045453636525, 6454.160959075313, 6513.804585862432, 6535.411812936834, 6505.251896031969, 6509.0, 6519.869602607945, 6524.836879078013, 6539.574927629713, 6292.622442653455, 6492.513061520382, 6516.022596610499, 6541.247756507967, 6547.083408322838, 6157.3534778848025, 6319.842003949892, 6345.365463453618, 6368.044793281045, 6386.2336519617475, 25292.0, 25502.74497255041, 25602.487950241077, 25913.352166195808, 25935.35161620956, 2967.9703202968126, 3231.4991176367703, 3286.556314897482, 3328.267781088181, 3397.4394224952853, 14904.850951490454, 15938.920305398398, 16267.18664066792, 16461.58846028847, 16615.750763738553, 2053.9281125160696, 2100.4433825036376, 2102.98948505258, 2116.153538584563, 2135.6476181430044, 88255.0, 92029.99999999933, 92688.0, 93020.53489732576, 94016.52991735066, 17505.562360940825, 17545.385911493035, 17561.77067605281, 18183.454406375342, 19104.0, 12304.876951230552, 31012.379752404824, 31082.378352432825, 31178.0, 31308.686913130576, 7534.849303013909, 7555.09338879327, 7606.23937606246, 7807.102183248886, 7861.253180947915, 7458.4033277337685, 7458.925410745823, 7620.961895190489, 7700.730474433368, 7755.650995705207, 4124.072083781185, 4137.441445404832, 4202.919554820383, 4226.070264541796, 4276.786160691953, 1959.6472634925851, 1963.558199405151, 1976.1107501624326, 2010.286348346316, 2013.677811550145, 859.3941271403645, 871.8822958900594, 880.0869123202093, 888.5157589113949, 916.3356566489242, 16913.32346706118, 17066.914665426593, 17500.299988000592, 18386.448406547825, 18442.26230950747, 882.280941033056, 885.0308911741632, 885.3935054487567, 893.2452077994085, 919.5172534419349, 10161.54273057714, 10186.643467478603, 10196.898031019744, 10271.66250678773, 10325.122364599094, 425330.0, 432631.0, 438141.0, 438549.0, 443115.0, 81438.0, 83287.16712832915, 83745.58127209324, 83820.58089709512, 84974.57512712397, 972.8443449047977, 996.9850452243222, 1022.2077889635443, 1023.9436830974219, 1028.0593257169605, 15671.921640391725, 16115.516534503984, 16252.593685157966, 16348.673026539165, 16351.182440878147, 36259.818700906326, 38559.42160867645, 39314.606853931095, 39459.0, 39782.80108599438, 46849.0, 49366.75316623394, 51592.0, 51606.74196629143, 52242.738786305825, 8172.346212302882, 8208.58957052157, 8521.531315777444, 8975.28197744192, 9063.592138353659, 10531.89468105309, 12206.0, 12590.811137833123, 12959.740805183656, 13003.934980325037, 6121.265448146073, 8522.78693032679, 9584.808303833745, 11745.706357341134, 11757.647270581896, 11650.30098194111, 11702.122340824302, 11758.58844940423, 11827.940860295988, 12020.0, 11712.88287117152, 11881.22772019816, 12059.336736479227, 12161.69595760113, 12287.44706488228, 7377.483576149618, 7585.469017168745, 8320.37597180226, 8434.409591328547, 8777.648894044421, 10507.527161277609, 10931.562737490729, 10989.670309890716, 11120.555177792798, 11287.604933827277], "value": 9574.131826768556, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.5015919189486, 1367.3983447283194, 1372.2932689664824, 1374.1411617738906, 1378.0973462382137, 7546.358559522433, 7768.88346674799, 7861.567613781247, 8344.874826877594, 8363.414560980726, 7984.201579842027, 8240.464369815973, 8255.42212045157, 8382.538960357178, 8394.24451799338, 2332.1487657005155, 2371.3241726108063, 2374.204641445118, 2375.714914210297, 2381.392744850061, 2285.2458688632796, 2312.8727919964394, 2343.039353864916, 2349.9882500587473, 2387.6657267982496, 2402.8798560072014, 2429.647701083342, 2431.258466167818, 2434.549608322466, 2434.817388695846, 2279.247848210095, 2319.884005799703, 2327.813774898004, 2330.697009388786, 2343.5664402085586, 2331.778481044298, 2333.9066437342485, 2341.0518739910194, 2365.8935347909387, 2367.384480035203, 8357.080721120672, 8505.277051450634, 8520.446170998926, 8533.658653653847, 8546.2308392245, 5187.559057480081, 5315.495027972336, 5324.361076670824, 5328.46715328468, 5338.866528336823, 3414.197663549064, 3511.490833829106, 3591.5510561179854, 3628.9274214515563, 3642.8542858285637, 1360.815691282404, 1404.0, 1436.7772995185762, 1474.8598883106088, 1493.2085994422973, 11226.382548959804, 11230.775384492346, 11275.32348059119, 11334.319940803496, 11362.318260904372, 4211.663066954698, 4452.688311818207, 4526.569975852289, 4572.88567785808, 4608.631309495232, 9020.8646870297, 9097.90902090984, 9120.680776172801, 9127.361084724138, 9127.90465144187, 5155.819546315936, 5175.120229560984, 5202.479752024768, 5203.9219411708855, 5253.133233016586, 4744.027474367746, 4763.618910487153, 4785.042991401664, 4820.831270905572, 4836.443808961944, 3525.876594319238, 3547.7516573839584, 3597.6042635310096, 3652.8538858445895, 3725.776453412804, 1049.7375656085978, 1073.8550295710136, 1105.5467258424007, 1121.9158563107799, 1165.0330225912471, 966.2752935298527, 977.1205914676706, 978.5596481583174, 991.6033586565432, 995.2635050062902, 5485.670859748428, 5729.255196824386, 5736.254286942671, 5843.941560584425, 5866.823995280063, 937.5593471068587, 944.3153713557616, 954.4750387286964, 960.7790208252142, 981.3915372469115, 3941.7043721720984, 3966.7619942803526, 3977.264206121819, 4006.59934006597, 4072.470578824734, 9696.6121355145, 10160.441175735405, 10163.390196588229, 10169.084782369622, 10182.0, 9917.355371900803, 10076.59693612262, 10097.646582369582, 10132.594696212216, 10135.239857010754, 7618.390528757686, 7755.1469338372735, 7762.495437796638, 7815.921840781633, 7823.178566250575, 5550.666959982414, 5634.211210430461, 5682.573806964577, 5723.255976723, 5835.095560188178, 9021.819563608822, 9124.133207345292, 9193.908060919306, 9217.078292170729, 9241.584128714358, 8964.641414343354, 9252.398594091363, 9323.627054917999, 9409.34134610598, 9429.669961551313, 7485.139208991036, 7550.622468876424, 7592.506487078323, 7634.656440460304, 7666.041744781903, 8046.074701409413, 8056.637451314587, 8071.959640201761, 8114.837703245784, 8143.837123257384, 7193.712251509881, 7238.0952380952385, 7426.2945020223, 7434.7769566913075, 7536.0956685198125, 6971.965140174267, 7156.677949492181, 7188.173360063451, 7228.385587224934, 7367.963160184165, 6371.5221358397375, 6472.579282346633, 6501.4798816095545, 6502.0, 6505.41451269388, 5054.19132938728, 5088.875574196155, 5141.0, 5225.216217567396, 5266.999270138662, 5029.64792464524, 5083.6187285952965, 5094.592432605308, 5147.742612869267, 5168.0], "value": 4493.890921727276, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.8124219132626, 401.9718619696621, 403.43317638717605, 408.14294957941377, 411.8537919038743, 5270.736463176845, 5303.628745987782, 5358.196270559418, 5426.321709786272, 5431.538319242864, 5226.7647955841985, 5299.867503312419, 5382.0, 5382.59630527711, 5383.246345511631, 4288.185244803493, 4307.483102027761, 4309.396684464162, 4313.762743049131, 4318.265894797892, 3976.840926362942, 4058.8985275368204, 4297.828086876537, 4306.935395969063, 4307.82768689252, 4154.356074808409, 4161.271777438951, 4167.6457501112445, 4169.3954376615375, 4179.958200418003, 4138.52406973199, 4149.792510374469, 4162.188373267214, 4175.582441755831, 4184.644305234028, 3733.757305775116, 3743.494628225182, 3746.1571146492092, 3753.7560058596105, 3756.211195648917, 5071.54356107952, 5180.766865491062, 5198.766055527511, 5208.270842082112, 5287.86780330491, 4805.765319126588, 4824.614030877521, 4848.587870031051, 4848.781804818756, 4857.368542089505, 5482.506574408282, 5650.039493286151, 5662.2639056922335, 5669.659820410789, 5670.376258611549, 4040.979795101035, 4063.6342729154517, 4079.673626109904, 4080.2451546463703, 4081.4081958115908, 5789.768409263625, 5815.651060936358, 5821.0, 5898.439648233411, 5907.970460147715, 4047.433359329696, 4082.5509193988637, 4095.9795201023803, 4132.958670413317, 4137.855175068858, 4054.432379466877, 4193.7693426861815, 4213.1784302060805, 4214.957850421518, 4244.936325955113, 4754.904901901942, 4808.903821923612, 4816.614670826326, 4823.5658790709, 4833.806647734052, 5706.828795136153, 5716.857078573069, 5751.511121554631, 5758.165066065397, 5796.739146738407, 1338.491373278148, 1421.0, 1422.2035660030417, 1434.0535246737104, 1439.7402273011114, 956.273232343424, 987.3779518903189, 992.950352482373, 999.0908273471107, 999.8601594182637, 5265.631405801634, 5272.182811664122, 5297.311349524537, 5316.867078323073, 5334.946650533445, 5558.193587084133, 5578.330600327987, 5584.994700953786, 5603.739158689344, 5627.296587926509, 5160.845174644766, 5185.637005409661, 5292.841214763564, 5330.9733451332495, 5348.491893270211, 2940.8382538960564, 2979.2551862034493, 2982.3587928595457, 3006.9175096965073, 3016.8039077459894, 5901.822945311647, 5902.2622172228475, 5939.881202376015, 5944.851378715567, 5966.806638672283, 5481.780728770885, 5709.51469125129, 5744.798932037442, 5766.8374359615145, 5787.090260214203, 5810.360860305447, 5912.674802885883, 5914.225732280299, 5934.6142500737315, 5943.257092863392, 5422.728863556886, 5555.972220138873, 5598.972005139948, 5602.803901863415, 5784.987627165145, 5761.654300741969, 5762.567807414377, 5768.913466298093, 5826.30084389859, 5848.795292164754, 6082.36135205797, 6105.328413874381, 6162.845928851814, 6215.0, 6238.625682459067, 3970.3647416413237, 4140.441040459499, 4173.560121757989, 4188.183304255763, 4195.811188496464, 5681.602287839977, 5820.185174075717, 5822.0, 5823.7379317931645, 5913.231279933581, 5735.627184233012, 5737.426257374229, 5754.597178197486, 5789.623674461147, 5800.69360464548, 5668.433156684464, 5713.229083894391, 5735.713214339183, 5758.2514273144225, 5760.884782304414, 5610.551155907599, 5683.488486036276, 5688.175214593944, 5694.743736531783, 5741.885162296647, 5753.884922301614, 5776.797812076726, 5821.27234095738, 5834.212381328466, 5844.795432159854, 5392.6225164239695, 5570.69088764132, 5599.60802743804, 5644.85887852807, 5714.857128571819], "value": 4328.7206847597945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.7390939261868, 734.0, 764.4075841223051, 808.2887059387738, 838.8532006898791, 15803.0, 16466.50600481986, 16477.423290184845, 16547.917260413695, 16565.66868662626, 14995.700085998273, 15278.923605381986, 15285.847141528582, 15480.225988700577, 16092.114933678673, 781.1563511407694, 790.6955822008539, 793.1909452358591, 796.3271035974592, 816.3713940265992, 830.8961379827522, 831.1107115386538, 832.1719888710742, 832.2592892325835, 833.2500749325595, 876.3252295732283, 879.0769691823591, 881.0220655072864, 881.18930583863, 881.4094556647062, 868.0885070675828, 868.587420975035, 871.0331531999476, 874.9300055995506, 876.3996662286322, 622.4180391334118, 624.9687515624246, 628.6448156791398, 633.4552285034866, 637.0857819029707, 15283.465078722302, 15653.217339132998, 15777.60555986098, 15875.444359447363, 16266.593335166597, 13935.163890166521, 14149.0, 14536.70926581473, 14566.781498277534, 14674.853251467455, 11230.606928757454, 11475.368854712988, 11561.132915031407, 11594.6521604352, 11745.236559623598, 3741.3639681254253, 3777.58446570877, 3808.9428658570146, 3837.7889216093104, 3894.279558281727, 81306.0, 81748.0, 81831.590842046, 81992.59003704943, 82074.58962705148, 9959.651412200537, 10089.243306752023, 10917.890821091847, 11029.007389334998, 11088.778224435466, 21569.89215053946, 21667.34997950064, 21667.56664866694, 21743.0, 21769.346919592437, 8035.919640803517, 8125.024997000398, 8134.105248422668, 8265.917340826634, 8416.116307787717, 4439.0, 4478.305862591304, 4545.227311357077, 4574.656900732459, 4597.126545956325, 3471.3057388522398, 3536.9646303536633, 3540.6105328413855, 3593.586737525166, 3606.224661697695, 649.4414803269185, 663.5288944849115, 667.829703425633, 668.7559040950116, 682.853186564891, 735.709394789066, 745.4185735126609, 756.3911051603402, 765.0398749569255, 792.6155814429997, 9042.276617870555, 9869.161121304625, 10026.097651211427, 10623.681289561171, 10671.893281067245, 732.2713899669862, 737.7528527943092, 747.8579069976687, 757.1595528962847, 762.5462849604477, 4571.794269257892, 4876.317315575822, 4932.778024988884, 4971.875703107451, 4979.45226025137, 69423.65288173527, 81386.59306703428, 83930.58034709787, 85255.57372213223, 86483.0, 71526.64236678783, 71852.64073679702, 74024.0, 74818.25181748113, 75565.0, 64633.99999999906, 64742.35257647363, 65303.34696652972, 65788.0, 67822.0, 8975.0, 9356.157945784911, 9365.157135857806, 9452.0, 9452.385594936582, 39116.60883391243, 39161.41257881077, 41318.0, 42357.15285694331, 45966.57685174464, 45208.32187517256, 52498.73750631222, 54598.454015459334, 56402.05185636966, 56624.0, 12973.805392918925, 13160.802587960996, 13284.335783210994, 13675.0, 13842.0, 14487.420503179756, 14549.854501455273, 14768.0, 15095.169765662993, 15818.762718559, 13572.93213533926, 13906.860931390556, 13941.651458713208, 14990.175540345386, 15148.772768408704, 14282.785758213842, 14558.4904528341, 14615.34230959588, 14922.626934326729, 15059.623509411915, 13562.186268823902, 13955.372008259856, 13987.790183147465, 14124.0, 14447.711045779235, 11396.209889322385, 11467.541298347973, 11549.307041577533, 11615.88384116148, 11725.061995040205, 10321.277510574188, 10400.531976060944, 10411.94794026025, 10534.578616855546, 10546.683599492027], "value": 7364.884201421628, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 112.0, 113.0, 112.0, 113.0, 110.0, 112.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 123.0, 170.0, 131.0, 125.0, 127.0, 124.0, 146.0, 124.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 163.0, 165.0, 172.0, 163.0, 164.0, 162.0, 161.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 118.0, 76.0, 78.0, 77.0, 76.0, 97.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 110.0, 85.0, 83.0, 83.0, 95.0, 89.0, 87.0, 105.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 117.0, 118.0, 117.0, 118.0, 119.0, 118.0, 119.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 150.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 96.0, 97.0, 96.0, 99.0, 97.0, 96.0, 97.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 60.0, 59.0, 57.0, 59.0, 58.0, 65.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 71.0, 68.0, 67.0, 67.0, 68.0, 68.0, 68.0, 69.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 83.0, 86.0, 82.0, 83.0, 81.0, 84.0, 88.0, 82.0, 82.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 132.0, 127.0, 130.0, 131.0, 129.0, 130.0, 128.0, 171.0, 128.0], "value": 133.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 168.0, 167.0, 165.0, 167.0, 170.0, 170.0, 172.0, 165.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 68.0, 63.0, 63.0, 66.0, 63.0, 63.0, 63.0], "value": 64.4, "unit": "score"}]}]} 09:31:30 INFO - Return code: 0 09:31:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:31:30 ERROR - # TBPL SUCCESS # 09:31:30 INFO - Running post-action listener: _resource_record_post_action 09:31:30 INFO - [mozharness: 2016-12-26 17:31:30.484451Z] Finished run-tests step (success) 09:31:30 INFO - Running post-run listener: _resource_record_post_run 09:31:30 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 16384; Write bytes: 416256000; Read time: 60; Write time: 441160 09:31:30 INFO - TinderboxPrint: CPU usage
13.4% 09:31:30 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 60 09:31:30 INFO - TinderboxPrint: I/O write bytes / time
416,256,000 / 441,160 09:31:30 INFO - TinderboxPrint: CPU idle
8,240.5 (86.6%) 09:31:30 INFO - TinderboxPrint: CPU user
1,216.3 (12.8%) 09:31:30 INFO - TinderboxPrint: Swap in / out
0 / 0 09:31:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105635840; Read time: 0; Write time: 149796 09:31:30 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 12288; Write bytes: 293896192; Read time: 44; Write time: 286152 09:31:31 INFO - Running post-run listener: _upload_blobber_files 09:31:31 INFO - Blob upload gear active. 09:31:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:31:31 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:31:31 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:31:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:31:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:31:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:32 INFO - (blobuploader) - INFO - Done attempting. 09:31:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:31:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:31:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:35 INFO - (blobuploader) - INFO - Done attempting. 09:31:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:31:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:31:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:36 INFO - (blobuploader) - INFO - Done attempting. 09:31:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:31:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:37 INFO - (blobuploader) - INFO - Done attempting. 09:31:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:37 INFO - Return code: 0 09:31:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:37 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f6beb89c87b522db2ccc16a345c62cd8e998bb21003da1f6490df6ec4357a6b08e4a9ff27420cebfe8d5244b21505c59a48fdc0f0508d0b2e47ad782abd9e9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c946a1f981b77cec4acb548552cd2bbae737cee429736946fd820ea0978a1322a1003710fe6233a4af62749a4ddab28f561b0a1d59261f15b0e7b48dd0b75bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66d7b4b85b12e86184ea7c57a0cf137779d3f7134951225eb6b3be20703bc4bd8e243fd6689ffd8d814d38ccdd53e9c1a85952aa1f0047c8c30e2089f298fa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1fd84c0afb825ff5f1b19527ea42bfc09156331d0a9d43b9b8413b1c836964e2352fcede82a910ceae2b1d29797c8eaff83c1ab011abc962bf8ea73d608453c"} 09:31:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:37 INFO - Contents: 09:31:37 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f6beb89c87b522db2ccc16a345c62cd8e998bb21003da1f6490df6ec4357a6b08e4a9ff27420cebfe8d5244b21505c59a48fdc0f0508d0b2e47ad782abd9e9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c946a1f981b77cec4acb548552cd2bbae737cee429736946fd820ea0978a1322a1003710fe6233a4af62749a4ddab28f561b0a1d59261f15b0e7b48dd0b75bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66d7b4b85b12e86184ea7c57a0cf137779d3f7134951225eb6b3be20703bc4bd8e243fd6689ffd8d814d38ccdd53e9c1a85952aa1f0047c8c30e2089f298fa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1fd84c0afb825ff5f1b19527ea42bfc09156331d0a9d43b9b8413b1c836964e2352fcede82a910ceae2b1d29797c8eaff83c1ab011abc962bf8ea73d608453c"} 09:31:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:37 INFO - Copying logs to upload dir... 09:31:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.895293 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-26 09:31:37.214442) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:37.217980) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f6beb89c87b522db2ccc16a345c62cd8e998bb21003da1f6490df6ec4357a6b08e4a9ff27420cebfe8d5244b21505c59a48fdc0f0508d0b2e47ad782abd9e9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c946a1f981b77cec4acb548552cd2bbae737cee429736946fd820ea0978a1322a1003710fe6233a4af62749a4ddab28f561b0a1d59261f15b0e7b48dd0b75bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66d7b4b85b12e86184ea7c57a0cf137779d3f7134951225eb6b3be20703bc4bd8e243fd6689ffd8d814d38ccdd53e9c1a85952aa1f0047c8c30e2089f298fa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1fd84c0afb825ff5f1b19527ea42bfc09156331d0a9d43b9b8413b1c836964e2352fcede82a910ceae2b1d29797c8eaff83c1ab011abc962bf8ea73d608453c"} build_url:https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011965 build_url: 'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f6beb89c87b522db2ccc16a345c62cd8e998bb21003da1f6490df6ec4357a6b08e4a9ff27420cebfe8d5244b21505c59a48fdc0f0508d0b2e47ad782abd9e9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c946a1f981b77cec4acb548552cd2bbae737cee429736946fd820ea0978a1322a1003710fe6233a4af62749a4ddab28f561b0a1d59261f15b0e7b48dd0b75bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b66d7b4b85b12e86184ea7c57a0cf137779d3f7134951225eb6b3be20703bc4bd8e243fd6689ffd8d814d38ccdd53e9c1a85952aa1f0047c8c30e2089f298fa7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1fd84c0afb825ff5f1b19527ea42bfc09156331d0a9d43b9b8413b1c836964e2352fcede82a910ceae2b1d29797c8eaff83c1ab011abc962bf8ea73d608453c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:37.248729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:37.249415) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482771369.636360-661712720 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:37.334701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 09:31:37.335503) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 09:31:37.336221) ========= ========= Total master_lag: 0.21 =========