builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1482772257.53 results: success (0) buildid: 20161226073116 builduid: 226d61d76f284082bcf67dbe96ba1303 revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.526659) ========= 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:57.527015) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.527294) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.544931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.545200) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-26 09:10:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.6K=0.2s 2016-12-26 09:10:58 (66.6 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.320928 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.897961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.898463) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014912 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.978240) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:57.978723) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-26 09:10:58,271 truncating revision to first 12 chars 2016-12-26 09:10:58,271 Setting DEBUG logging. 2016-12-26 09:10:58,271 attempt 1/10 2016-12-26 09:10:58,271 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:58,620 unpacking tar archive at: mozilla-central-1747e26ee37b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.713372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:58.708584) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:58.708888) ========= script_repo_revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:58.709268) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:58.709545) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 09:10:58.718495) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-26 09:10:58.718846) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:10:59 INFO - MultiFileLogger online at 20161226 09:10:59 in /builds/slave/test 09:10:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:10:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:10:59 INFO - {'append_to_log': False, 09:10:59 INFO - 'base_work_dir': '/builds/slave/test', 09:10:59 INFO - 'blob_upload_branch': 'Firefox', 09:10:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:10:59 INFO - 'branch': 'Firefox', 09:10:59 INFO - 'buildbot_json_path': 'buildprops.json', 09:10:59 INFO - 'config_files': ('talos/linux_config.py',), 09:10:59 INFO - 'default_actions': ('clobber', 09:10:59 INFO - 'read-buildbot-config', 09:10:59 INFO - 'download-and-extract', 09:10:59 INFO - 'populate-webroot', 09:10:59 INFO - 'create-virtualenv', 09:10:59 INFO - 'install', 09:10:59 INFO - 'run-tests'), 09:10:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:10:59 INFO - 'download_minidump_stackwalk': True, 09:10:59 INFO - 'download_symbols': 'ondemand', 09:10:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:10:59 INFO - 'tooltool.py': '/tools/tooltool.py', 09:10:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:10:59 INFO - '/tools/misc-python/virtualenv.py')}, 09:10:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:10:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:10:59 INFO - 'installer_path': 'installer.exe', 09:10:59 INFO - 'log_level': 'info', 09:10:59 INFO - 'log_name': 'talos', 09:10:59 INFO - 'log_to_console': True, 09:10:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:10:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:10:59 INFO - 'opt_config_files': (), 09:10:59 INFO - 'pip_index': False, 09:10:59 INFO - 'sps_profile': False, 09:10:59 INFO - 'sps_profile_interval': 0, 09:10:59 INFO - 'suite': 'dromaeojs', 09:10:59 INFO - 'system_bits': '32', 09:10:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:10:59 INFO - 'title': 'talos-linux64-ix-061', 09:10:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:10:59 INFO - 'use_talos_json': True, 09:10:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:10:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:10:59 INFO - 'work_dir': 'build'} 09:10:59 INFO - [mozharness: 2016-12-26 17:10:59.110966Z] Running clobber step. 09:10:59 INFO - Running pre-action listener: _resource_record_pre_action 09:10:59 INFO - Running main action method: clobber 09:10:59 INFO - rmtree: /builds/slave/test/build 09:10:59 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.565159Z] Finished clobber step (success) 09:10:59 INFO - [mozharness: 2016-12-26 17:10:59.565261Z] 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", 09:10:59 INFO - "repository": "", 09:10:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 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": 135, 09:10:59 INFO - "slavename": "talos-linux64-ix-061", 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.567827Z] Finished read-buildbot-config step (success) 09:10:59 INFO - [mozharness: 2016-12-26 17:10:59.567917Z] 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:05 INFO - Expected file size: 21829987 09:11:05 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:11 INFO - Expected file size: 14409776 09:11:11 INFO - Obtained file size: 14409776 09:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:11 INFO - trying https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:11:11 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:11 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:12 INFO - Downloaded 61372319 bytes. 09:11:12 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:12 INFO - mkdir: /builds/slave/test/properties 09:11:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:12 INFO - Contents: 09:11:12 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:13 INFO - Running post-action listener: _resource_record_post_action 09:11:13 INFO - Running post-action listener: set_extra_try_arguments 09:11:13 INFO - [mozharness: 2016-12-26 17:11:13.382039Z] Finished download-and-extract step (success) 09:11:13 INFO - [mozharness: 2016-12-26 17:11:13.382266Z] Running populate-webroot step. 09:11:13 INFO - Running pre-action listener: _resource_record_pre_action 09:11:13 INFO - Running main action method: populate_webroot 09:11:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:13 INFO - '--tppagecycles', 09:11:13 INFO - '7'], 09:11:13 INFO - 'tests': ['tsvgm']}}, 09:11:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:11:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:11:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:11:13 INFO - 'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:11:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:11:13 INFO - 'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['damp', 'tps']}, 09:11:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:11:13 INFO - 'tests': ['damp', 'tps']}, 09:11:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['dromaeo_dom']}, 09:11:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['basic_compositor_video']}, 09:11:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:11:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['a11yr', 09:11:13 INFO - 'ts_paint', 09:11:13 INFO - 'tpaint', 09:11:13 INFO - 'sessionrestore', 09:11:13 INFO - 'sessionrestore_no_auto_restore', 09:11:13 INFO - 'tabpaint']}, 09:11:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:11:13 INFO - 'ts_paint', 09:11:13 INFO - 'tpaint', 09:11:13 INFO - 'sessionrestore', 09:11:13 INFO - 'sessionrestore_no_auto_restore', 09:11:13 INFO - 'tabpaint']}, 09:11:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:11:13 INFO - 'ts_paint', 09:11:13 INFO - 'tpaint', 09:11:13 INFO - 'sessionrestore', 09:11:13 INFO - 'sessionrestore_no_auto_restore', 09:11:13 INFO - 'tabpaint']}, 09:11:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:11:13 INFO - 'ts_paint', 09:11:13 INFO - 'tpaint', 09:11:13 INFO - 'sessionrestore', 09:11:13 INFO - 'sessionrestore_no_auto_restore', 09:11:13 INFO - 'tabpaint']}, 09:11:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['a11yr', 09:11:13 INFO - 'ts_paint', 09:11:13 INFO - 'tpaint', 09:11:13 INFO - 'sessionrestore', 09:11:13 INFO - 'sessionrestore_no_auto_restore', 09:11:13 INFO - 'tabpaint']}, 09:11:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['a11yr', 09:11:13 INFO - 'ts_paint', 09:11:13 INFO - 'tpaint', 09:11:13 INFO - 'sessionrestore', 09:11:13 INFO - 'sessionrestore_no_auto_restore', 09:11:13 INFO - 'tabpaint']}, 09:11:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['tsvgx', 09:11:13 INFO - 'tsvgr_opacity', 09:11:13 INFO - 'tart', 09:11:13 INFO - 'tscrollx', 09:11:13 INFO - 'cart', 09:11:13 INFO - 'tsvg_static']}, 09:11:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:11:13 INFO - 'tsvgr_opacity', 09:11:13 INFO - 'tart', 09:11:13 INFO - 'tscrollx', 09:11:13 INFO - 'cart', 09:11:13 INFO - 'tsvg_static']}, 09:11:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:11:13 INFO - 'talos_options': ['--disable-e10s'], 09:11:13 INFO - 'tests': ['tp5o']}, 09:11:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:11:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:11:13 INFO - 'talos_options': ['--disable-e10s', 09:11:13 INFO - '--xperf_path', 09:11:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:13 INFO - 'tests': ['tp5n']}, 09:11:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:11:13 INFO - 'talos_options': ['--xperf_path', 09:11:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:13 INFO - 'tests': ['tp5n']}}, 09:11:13 INFO - 'talos.zip': {'path': '', 09:11:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:13 INFO - Running post-action listener: _resource_record_post_action 09:11:13 INFO - [mozharness: 2016-12-26 17:11:13.400486Z] Finished populate-webroot step (success) 09:11:13 INFO - [mozharness: 2016-12-26 17:11:13.400575Z] Running create-virtualenv step. 09:11:13 INFO - Running pre-action listener: _resource_record_pre_action 09:11:13 INFO - Running main action method: create_virtualenv 09:11:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:13 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:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:13 INFO - Using real prefix '/usr' 09:11:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:15 INFO - Installing pip.................done. 09:11:15 INFO - Return code: 0 09:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:11:16 INFO - Reading from file tmpfile_stdout 09:11:16 INFO - Output received: 09:11:16 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:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:16 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:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:16 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:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:16 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:16 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:16 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:16 INFO - Using env: {'DISPLAY': ':0', 09:11:16 INFO - 'HOME': '/home/cltbld', 09:11:16 INFO - 'LANG': 'en_US.UTF-8', 09:11:16 INFO - 'LANGUAGE': 'en_US:en', 09:11:16 INFO - 'LOGNAME': 'cltbld', 09:11:16 INFO - 'MAIL': '/var/mail/cltbld', 09:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:16 INFO - 'MOZ_NO_REMOTE': '1', 09:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:16 INFO - 'NO_EM_RESTART': '1', 09:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:16 INFO - 'PWD': '/builds/slave/test', 09:11:16 INFO - 'SHELL': '/bin/bash', 09:11:16 INFO - 'SHLVL': '1', 09:11:16 INFO - 'TERM': 'linux', 09:11:16 INFO - 'TMOUT': '86400', 09:11:16 INFO - 'USER': 'cltbld', 09:11:16 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:16 INFO - '_': '/tools/buildbot/bin/python'} 09:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:16 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:16 INFO - Cleaning up... 09:11:16 INFO - Return code: 0 09:11:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:11:16 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:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:16 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:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:16 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:16 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:16 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:16 INFO - Using env: {'DISPLAY': ':0', 09:11:16 INFO - 'HOME': '/home/cltbld', 09:11:16 INFO - 'LANG': 'en_US.UTF-8', 09:11:16 INFO - 'LANGUAGE': 'en_US:en', 09:11:16 INFO - 'LOGNAME': 'cltbld', 09:11:16 INFO - 'MAIL': '/var/mail/cltbld', 09:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:16 INFO - 'MOZ_NO_REMOTE': '1', 09:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:16 INFO - 'NO_EM_RESTART': '1', 09:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:16 INFO - 'PWD': '/builds/slave/test', 09:11:16 INFO - 'SHELL': '/bin/bash', 09:11:16 INFO - 'SHLVL': '1', 09:11:16 INFO - 'TERM': 'linux', 09:11:16 INFO - 'TMOUT': '86400', 09:11:16 INFO - 'USER': 'cltbld', 09:11:16 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:16 INFO - '_': '/tools/buildbot/bin/python'} 09:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:16 INFO - Downloading/unpacking psutil>=3.1.1 09:11:16 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:16 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:16 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:16 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:16 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:16 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:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:18 INFO - Installing collected packages: psutil 09:11:18 INFO - Running setup.py install for psutil 09:11:18 INFO - building 'psutil._psutil_linux' extension 09:11:18 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:18 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:18 INFO - building 'psutil._psutil_posix' extension 09:11:18 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: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 - Successfully installed psutil 09:11:19 INFO - Cleaning up... 09:11:19 INFO - Return code: 0 09:11:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:11:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:19 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:19 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:19 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:19 INFO - Using env: {'DISPLAY': ':0', 09:11:19 INFO - 'HOME': '/home/cltbld', 09:11:19 INFO - 'LANG': 'en_US.UTF-8', 09:11:19 INFO - 'LANGUAGE': 'en_US:en', 09:11:19 INFO - 'LOGNAME': 'cltbld', 09:11:19 INFO - 'MAIL': '/var/mail/cltbld', 09:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:19 INFO - 'MOZ_NO_REMOTE': '1', 09:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:19 INFO - 'NO_EM_RESTART': '1', 09:11:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:19 INFO - 'PWD': '/builds/slave/test', 09:11:19 INFO - 'SHELL': '/bin/bash', 09:11:19 INFO - 'SHLVL': '1', 09:11:19 INFO - 'TERM': 'linux', 09:11:19 INFO - 'TMOUT': '86400', 09:11:19 INFO - 'USER': 'cltbld', 09:11:19 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:19 INFO - '_': '/tools/buildbot/bin/python'} 09:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:11:19 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:19 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 - 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:19 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:19 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:19 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:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:21 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:21 INFO - Installing collected packages: mozsystemmonitor 09:11:21 INFO - Running setup.py install for mozsystemmonitor 09:11:21 INFO - Successfully installed mozsystemmonitor 09:11:21 INFO - Cleaning up... 09:11:21 INFO - Return code: 0 09:11:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:11:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:21 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:21 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:21 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:21 INFO - Using env: {'DISPLAY': ':0', 09:11:21 INFO - 'HOME': '/home/cltbld', 09:11:21 INFO - 'LANG': 'en_US.UTF-8', 09:11:21 INFO - 'LANGUAGE': 'en_US:en', 09:11:21 INFO - 'LOGNAME': 'cltbld', 09:11:21 INFO - 'MAIL': '/var/mail/cltbld', 09:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:21 INFO - 'MOZ_NO_REMOTE': '1', 09:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:21 INFO - 'NO_EM_RESTART': '1', 09:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:21 INFO - 'PWD': '/builds/slave/test', 09:11:21 INFO - 'SHELL': '/bin/bash', 09:11:21 INFO - 'SHLVL': '1', 09:11:21 INFO - 'TERM': 'linux', 09:11:21 INFO - 'TMOUT': '86400', 09:11:21 INFO - 'USER': 'cltbld', 09:11:21 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:21 INFO - '_': '/tools/buildbot/bin/python'} 09:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:21 INFO - Downloading/unpacking jsonschema==2.5.1 09:11:21 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:21 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:21 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:21 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:21 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:21 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:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:11:23 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:23 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:23 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:23 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:23 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:23 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:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:24 INFO - warning: no files found matching '*.txt' 09:11:24 INFO - no previously-included directories found matching 'build' 09:11:24 INFO - no previously-included directories found matching 'dist' 09:11:24 INFO - no previously-included directories found matching '.git*' 09:11:24 INFO - Installing collected packages: jsonschema, functools32 09:11:24 INFO - Running setup.py install for functools32 09:11:24 INFO - warning: no files found matching '*.txt' 09:11:24 INFO - no previously-included directories found matching 'build' 09:11:24 INFO - no previously-included directories found matching 'dist' 09:11:24 INFO - no previously-included directories found matching '.git*' 09:11:24 INFO - Successfully installed jsonschema functools32 09:11:24 INFO - Cleaning up... 09:11:24 INFO - Return code: 0 09:11:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:11:24 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:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:24 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:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:24 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 09:11:24 INFO - 'HOME': '/home/cltbld', 09:11:24 INFO - 'LANG': 'en_US.UTF-8', 09:11:24 INFO - 'LANGUAGE': 'en_US:en', 09:11:24 INFO - 'LOGNAME': 'cltbld', 09:11:24 INFO - 'MAIL': '/var/mail/cltbld', 09:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:24 INFO - 'MOZ_NO_REMOTE': '1', 09:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:24 INFO - 'NO_EM_RESTART': '1', 09:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:24 INFO - 'PWD': '/builds/slave/test', 09:11:24 INFO - 'SHELL': '/bin/bash', 09:11:24 INFO - 'SHLVL': '1', 09:11:24 INFO - 'TERM': 'linux', 09:11:24 INFO - 'TMOUT': '86400', 09:11:24 INFO - 'USER': 'cltbld', 09:11:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:24 INFO - '_': '/tools/buildbot/bin/python'} 09:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:11:24 INFO - Cleaning up... 09:11:24 INFO - Return code: 0 09:11:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11:24 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:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:24 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:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:24 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 09:11:24 INFO - 'HOME': '/home/cltbld', 09:11:24 INFO - 'LANG': 'en_US.UTF-8', 09:11:24 INFO - 'LANGUAGE': 'en_US:en', 09:11:24 INFO - 'LOGNAME': 'cltbld', 09:11:24 INFO - 'MAIL': '/var/mail/cltbld', 09:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:24 INFO - 'MOZ_NO_REMOTE': '1', 09:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:24 INFO - 'NO_EM_RESTART': '1', 09:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:24 INFO - 'PWD': '/builds/slave/test', 09:11:24 INFO - 'SHELL': '/bin/bash', 09:11:24 INFO - 'SHLVL': '1', 09:11:24 INFO - 'TERM': 'linux', 09:11:24 INFO - 'TMOUT': '86400', 09:11:24 INFO - 'USER': 'cltbld', 09:11:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:24 INFO - '_': '/tools/buildbot/bin/python'} 09:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:24 INFO - Downloading/unpacking blobuploader==1.2.4 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:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:11:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:11:26 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:26 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:26 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:26 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:26 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:26 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 - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:27 INFO - Downloading/unpacking docopt==0.6.1 (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:27 INFO - Downloading docopt-0.6.1.tar.gz 09:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:11:27 INFO - Installing collected packages: blobuploader, requests, docopt 09:11:27 INFO - Running setup.py install for blobuploader 09:11:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:11:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:11:27 INFO - Running setup.py install for requests 09:11:28 INFO - Running setup.py install for docopt 09:11:28 INFO - Successfully installed blobuploader requests docopt 09:11:28 INFO - Cleaning up... 09:11:28 INFO - Return code: 0 09:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:28 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:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:28 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:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:28 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:28 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:28 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:28 INFO - Using env: {'DISPLAY': ':0', 09:11:28 INFO - 'HOME': '/home/cltbld', 09:11:28 INFO - 'LANG': 'en_US.UTF-8', 09:11:28 INFO - 'LANGUAGE': 'en_US:en', 09:11:28 INFO - 'LOGNAME': 'cltbld', 09:11:28 INFO - 'MAIL': '/var/mail/cltbld', 09:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:28 INFO - 'MOZ_NO_REMOTE': '1', 09:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:28 INFO - 'NO_EM_RESTART': '1', 09:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:28 INFO - 'PWD': '/builds/slave/test', 09:11:28 INFO - 'SHELL': '/bin/bash', 09:11:28 INFO - 'SHLVL': '1', 09:11:28 INFO - 'TERM': 'linux', 09:11:28 INFO - 'TMOUT': '86400', 09:11:28 INFO - 'USER': 'cltbld', 09:11:28 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:28 INFO - '_': '/tools/buildbot/bin/python'} 09:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:28 INFO - Running setup.py (path:/tmp/pip-nuYWIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:28 INFO - Running setup.py (path:/tmp/pip-0afi5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:28 INFO - Running setup.py (path:/tmp/pip-XDpVmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:28 INFO - Running setup.py (path:/tmp/pip-BbOORV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:28 INFO - Running setup.py (path:/tmp/pip-VlJoQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:28 INFO - Running setup.py (path:/tmp/pip-Z2j41z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:28 INFO - Running setup.py (path:/tmp/pip-RVkI00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:28 INFO - Running setup.py (path:/tmp/pip-Zm1Kob-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-tqZyzo-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:29 INFO - Running setup.py (path:/tmp/pip-5Thmex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:29 INFO - Running setup.py (path:/tmp/pip-3BbpXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:29 INFO - Running setup.py (path:/tmp/pip-h0SYhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:29 INFO - Running setup.py (path:/tmp/pip-mBngHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:29 INFO - Running setup.py (path:/tmp/pip-IGKF7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:29 INFO - Running setup.py (path:/tmp/pip-YQsRC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:29 INFO - Running setup.py (path:/tmp/pip-SNx7tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:29 INFO - Running setup.py (path:/tmp/pip-NbggW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:29 INFO - Running setup.py install for manifestparser 09:11:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:29 INFO - Running setup.py install for mozcrash 09:11:29 INFO - Running setup.py install for mozdebug 09:11:29 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:30 INFO - Running setup.py install for mozfile 09:11:30 INFO - Running setup.py install for mozhttpd 09:11:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Running setup.py install for mozinfo 09:11:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Running setup.py install for mozInstall 09:11:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Running setup.py install for mozleak 09:11:30 INFO - Running setup.py install for mozlog 09:11:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Running setup.py install for moznetwork 09:11:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:30 INFO - Running setup.py install for mozprocess 09:11:30 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:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Running setup.py install for mozscreenshot 09:11:31 INFO - Running setup.py install for moztest 09:11:31 INFO - Running setup.py install for mozversion 09:11:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:31 INFO - Cleaning up... 09:11:31 INFO - Return code: 0 09:11:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:31 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:31 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:31 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:31 INFO - Using env: {'DISPLAY': ':0', 09:11:31 INFO - 'HOME': '/home/cltbld', 09:11:31 INFO - 'LANG': 'en_US.UTF-8', 09:11:31 INFO - 'LANGUAGE': 'en_US:en', 09:11:31 INFO - 'LOGNAME': 'cltbld', 09:11:31 INFO - 'MAIL': '/var/mail/cltbld', 09:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:31 INFO - 'MOZ_NO_REMOTE': '1', 09:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:31 INFO - 'NO_EM_RESTART': '1', 09:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:31 INFO - 'PWD': '/builds/slave/test', 09:11:31 INFO - 'SHELL': '/bin/bash', 09:11:31 INFO - 'SHLVL': '1', 09:11:31 INFO - 'TERM': 'linux', 09:11:31 INFO - 'TMOUT': '86400', 09:11:31 INFO - 'USER': 'cltbld', 09:11:31 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:31 INFO - '_': '/tools/buildbot/bin/python'} 09:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:31 INFO - Running setup.py (path:/tmp/pip-f6Wk7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:31 INFO - Running setup.py (path:/tmp/pip-gqKqB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:31 INFO - Running setup.py (path:/tmp/pip-Y3q0w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:31 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:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:31 INFO - Running setup.py (path:/tmp/pip-nqdzaG-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-5aVYPA-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-ngaQmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:32 INFO - Running setup.py (path:/tmp/pip-99_ZD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:32 INFO - Running setup.py (path:/tmp/pip-LwkyIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:32 INFO - Running setup.py (path:/tmp/pip-xCXPg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:32 INFO - Running setup.py (path:/tmp/pip-TkfRDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:32 INFO - Running setup.py (path:/tmp/pip-IxppHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:32 INFO - Running setup.py (path:/tmp/pip-Gvjzfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:32 INFO - Running setup.py (path:/tmp/pip-2TJkZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:32 INFO - Running setup.py (path:/tmp/pip-C6SVRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:32 INFO - Running setup.py (path:/tmp/pip-7uXub0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:32 INFO - Running setup.py (path:/tmp/pip-CZIsk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:32 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:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:32 INFO - Running setup.py (path:/tmp/pip-L9dItH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:32 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:32 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:32 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:32 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:32 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:32 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:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:34 INFO - Downloading blessings-1.6.tar.gz 09:11:34 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:35 INFO - Successfully installed blessings 09:11:35 INFO - Cleaning up... 09:11:35 INFO - Return code: 0 09:11:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:35 INFO - Reading from file tmpfile_stdout 09:11:35 INFO - Current package versions: 09:11:35 INFO - argparse == 1.2.1 09:11:35 INFO - blessings == 1.6 09:11:35 INFO - blobuploader == 1.2.4 09:11:35 INFO - docopt == 0.6.1 09:11:35 INFO - functools32 == 3.2.3-2 09:11:35 INFO - jsonschema == 2.5.1 09:11:35 INFO - manifestparser == 1.1 09:11:35 INFO - mozInstall == 1.12 09:11:35 INFO - mozcrash == 1.0 09:11:35 INFO - mozdebug == 0.1 09:11:35 INFO - mozdevice == 0.49 09:11:35 INFO - mozfile == 1.2 09:11:35 INFO - mozhttpd == 0.7 09:11:35 INFO - mozinfo == 0.9 09:11:35 INFO - mozleak == 0.1 09:11:35 INFO - mozlog == 3.4 09:11:35 INFO - moznetwork == 0.27 09:11:35 INFO - mozprocess == 0.23 09:11:35 INFO - mozprofile == 0.28 09:11:35 INFO - mozrunner == 6.13 09:11:35 INFO - mozscreenshot == 0.1 09:11:35 INFO - mozsystemmonitor == 0.3 09:11:35 INFO - moztest == 0.7 09:11:35 INFO - mozversion == 1.4 09:11:35 INFO - psutil == 3.1.1 09:11:35 INFO - requests == 1.2.3 09:11:35 INFO - wsgiref == 0.1.2 09:11:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:35 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 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:35 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:35 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:35 INFO - Using env: {'DISPLAY': ':0', 09:11:35 INFO - 'HOME': '/home/cltbld', 09:11:35 INFO - 'LANG': 'en_US.UTF-8', 09:11:35 INFO - 'LANGUAGE': 'en_US:en', 09:11:35 INFO - 'LOGNAME': 'cltbld', 09:11:35 INFO - 'MAIL': '/var/mail/cltbld', 09:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:35 INFO - 'MOZ_NO_REMOTE': '1', 09:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:35 INFO - 'NO_EM_RESTART': '1', 09:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:35 INFO - 'PWD': '/builds/slave/test', 09:11:35 INFO - 'SHELL': '/bin/bash', 09:11:35 INFO - 'SHLVL': '1', 09:11:35 INFO - 'TERM': 'linux', 09:11:35 INFO - 'TMOUT': '86400', 09:11:35 INFO - 'USER': 'cltbld', 09:11:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:35 INFO - '_': '/tools/buildbot/bin/python'} 09:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 INFO - Cleaning up... 09:11:35 INFO - Return code: 0 09:11:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2965c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', '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:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:11:35 INFO - Using env: {'DISPLAY': ':0', 09:11:35 INFO - 'HOME': '/home/cltbld', 09:11:35 INFO - 'LANG': 'en_US.UTF-8', 09:11:35 INFO - 'LANGUAGE': 'en_US:en', 09:11:35 INFO - 'LOGNAME': 'cltbld', 09:11:35 INFO - 'MAIL': '/var/mail/cltbld', 09:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:35 INFO - 'MOZ_NO_REMOTE': '1', 09:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:35 INFO - 'NO_EM_RESTART': '1', 09:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:35 INFO - 'PWD': '/builds/slave/test', 09:11:35 INFO - 'SHELL': '/bin/bash', 09:11:35 INFO - 'SHLVL': '1', 09:11:35 INFO - 'TERM': 'linux', 09:11:35 INFO - 'TMOUT': '86400', 09:11:35 INFO - 'USER': 'cltbld', 09:11:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:35 INFO - '_': '/tools/buildbot/bin/python'} 09:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:35 INFO - Cleaning up... 09:11:35 INFO - Return code: 0 09:11:35 INFO - Running post-action listener: _resource_record_post_action 09:11:35 INFO - Running post-action listener: _start_resource_monitoring 09:11:35 INFO - Starting resource monitoring. 09:11:35 INFO - [mozharness: 2016-12-26 17:11:35.967785Z] Finished create-virtualenv step (success) 09:11:35 INFO - [mozharness: 2016-12-26 17:11:35.968260Z] Running install step. 09:11:35 INFO - Running pre-action listener: _resource_record_pre_action 09:11:35 INFO - Running main action method: install 09:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:36 INFO - Reading from file tmpfile_stdout 09:11:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:36 INFO - Reading from file tmpfile_stdout 09:11:36 INFO - Output received: 09:11:36 INFO - Usage: mozinstall [options] installer 09:11:36 INFO - Options: 09:11:36 INFO - -h, --help show this help message and exit 09:11:36 INFO - -d DEST, --destination=DEST 09:11:36 INFO - Directory to install application into. [default: 09:11:36 INFO - "/builds/slave/test"] 09:11:36 INFO - --app=APP Application being installed. [default: firefox] 09:11:36 INFO - mkdir: /builds/slave/test/build/application 09:11:36 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:36 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:53 INFO - Reading from file tmpfile_stdout 09:11:53 INFO - Output received: 09:11:53 INFO - /builds/slave/test/build/application/firefox/firefox 09:11:53 INFO - Running post-action listener: _resource_record_post_action 09:11:53 INFO - [mozharness: 2016-12-26 17:11:53.891905Z] Finished install step (success) 09:11:53 INFO - [mozharness: 2016-12-26 17:11:53.892149Z] Running run-tests step. 09:11:53 INFO - Running pre-action listener: _resource_record_pre_action 09:11:53 INFO - Running main action method: run_tests 09:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:53 INFO - Python 2.7.3 09:11:53 INFO - Return code: 0 09:11:53 INFO - grabbing minidump binary from tooltool 09:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:53 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 0x29633f0>, '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 0x2963cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29641a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:53 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:53 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:53 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:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:53 INFO - Return code: 0 09:11:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:11:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:11:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --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-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:11:53 INFO - Using env: {'DISPLAY': ':0', 09:11:53 INFO - 'HOME': '/home/cltbld', 09:11:53 INFO - 'LANG': 'en_US.UTF-8', 09:11:53 INFO - 'LANGUAGE': 'en_US:en', 09:11:53 INFO - 'LOGNAME': 'cltbld', 09:11:53 INFO - 'MAIL': '/var/mail/cltbld', 09:11:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:53 INFO - 'MOZ_NO_REMOTE': '1', 09:11:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:53 INFO - 'NO_EM_RESTART': '1', 09:11:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:53 INFO - 'PWD': '/builds/slave/test', 09:11:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:53 INFO - 'SHELL': '/bin/bash', 09:11:53 INFO - 'SHLVL': '1', 09:11:53 INFO - 'TERM': 'linux', 09:11:53 INFO - 'TMOUT': '86400', 09:11:53 INFO - 'USER': 'cltbld', 09:11:53 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488', 09:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:53 INFO - '_': '/tools/buildbot/bin/python'} 09:11:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09: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:32851' 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 20971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpulZ3D6/profile http://localhost:32851/getInfo.html) 09:11:54 INFO - PROCESS | 20971 | 1482772314610 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 | 20971 | __metrics Screen width/height:1600/1200 09:11:58 INFO - PROCESS | 20971 | colorDepth:24 09:11:58 INFO - PROCESS | 20971 | Browser inner width/height: 1024/697 09:11:58 INFO - PROCESS | 20971 | __metrics 09:11:59 INFO - PROCESS | 20971 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:11:59 INFO - PROCESS | 20971 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:11:59 INFO - PROCESS | 20971 | console.error: PushService: 09:11:59 INFO - PROCESS | 20971 | stateChangeProcessEnqueue: Error transitioning state 09:11:59 INFO - PROCESS | 20971 | UnknownError 09:12:00 INFO - TEST-INFO | 20971: 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 21055 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpulZ3D6/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 | 21055 | RSS: Main: 146923520 09:12:12 INFO - PROCESS | 21055 | 09:15:13 INFO - PROCESS | 21055 | Cycle 1(1): loaded http://localhost:32851/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32851/tests/dromaeo/cssquery-ext.html) 09:15:14 INFO - PROCESS | 21055 | RSS: Main: 258002944 09:15:14 INFO - PROCESS | 21055 | 09:17:01 INFO - PROCESS | 21055 | ************************* 09:17:01 INFO - PROCESS | 21055 | A coding exception was thrown and uncaught in a Task. 09:17:01 INFO - PROCESS | 21055 | 09:17:01 INFO - PROCESS | 21055 | Full message: ReferenceError: fetch is not defined 09:17:01 INFO - PROCESS | 21055 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:17:01 INFO - PROCESS | 21055 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:17:01 INFO - PROCESS | 21055 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:17:01 INFO - PROCESS | 21055 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:17:01 INFO - PROCESS | 21055 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:17:01 INFO - PROCESS | 21055 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:17:01 INFO - PROCESS | 21055 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:17:01 INFO - PROCESS | 21055 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:17:01 INFO - PROCESS | 21055 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:17:01 INFO - PROCESS | 21055 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:17:01 INFO - PROCESS | 21055 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:17:01 INFO - PROCESS | 21055 | 09:17:01 INFO - PROCESS | 21055 | ************************* 09:18:17 INFO - PROCESS | 21055 | Cycle 1(1): loaded http://localhost:32851/tests/dromaeo/cssquery-ext.html (next: http://localhost:32851/tests/dromaeo/cssquery-jquery.html) 09:18:17 INFO - PROCESS | 21055 | RSS: Main: 236912640 09:18:17 INFO - PROCESS | 21055 | 09:21:19 INFO - PROCESS | 21055 | Cycle 1(1): loaded http://localhost:32851/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32851/tests/dromaeo/cssquery-mootools.html) 09:21:20 INFO - PROCESS | 21055 | RSS: Main: 220164096 09:21:20 INFO - PROCESS | 21055 | 09:24:22 INFO - PROCESS | 21055 | Cycle 1(1): loaded http://localhost:32851/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32851/tests/dromaeo/cssquery-prototype.html) 09:24:22 INFO - PROCESS | 21055 | RSS: Main: 181014528 09:24:22 INFO - PROCESS | 21055 | 09:27:25 INFO - PROCESS | 21055 | Cycle 1(1): loaded http://localhost:32851/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32851/tests/dromaeo/cssquery-yui.html) 09:27:26 INFO - PROCESS | 21055 | RSS: Main: 184033280 09:27:26 INFO - PROCESS | 21055 | 09:30:28 INFO - PROCESS | 21055 | Cycle 1(1): loaded http://localhost:32851/tests/dromaeo/cssquery-yui.html (next: http://localhost:32851/tests/dromaeo/cssquery-dojo.html) 09:30:29 INFO - PROCESS | 21055 | RSS: Main: 277286912 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | __start_tp_report 09:30:29 INFO - PROCESS | 21055 | _x_x_mozilla_page_load 09:30:29 INFO - PROCESS | 21055 | _x_x_mozilla_page_load_details 09:30:29 INFO - PROCESS | 21055 | |i|pagename|runs| 09:30:29 INFO - PROCESS | 21055 | |0;dojo.html;3097.0399176255355;3125.468670326046;3132.3578666373387;3156.636986746525;3178.6344570374413;5101.260317253996;5132.307138536296;5134.768935397907;5223.895522089556;5226.712530810799;5196.740162991845;5225.163973764198;5238.4499627539135;5250.05499010178;5257.263983042377;4740.146773580754;4766.499517550659;4802.447718512363;4822.951770482286;4837.129316722989;4772.546608072227;4814.590759785422;4818.036392721452;4850.854474365757;4856.538628830273;4294.475585839161;4866.099771542276;4872.829450969217;4892.902141957159;4929.137400954836;4608.1244563533155;4623.8150473981;4854.854354369375;4895.9510404895855;4898.706077635354;4694.953050469486;4737.478877323491;4745.908441058576;4766.046790641885;4800.943792365674;5203.505666961632;5208.635395522315;5219.138842091088;5235.502627250406;5286.15421532553;5411.377691565442;5536.723163841792;5606.439356064402;5625.606207565472;5626.071698169797;5504.146857237135;5655.915161272585;5664.745086471119;5714.828555143353;5786.855328616776;5307.57539396855;5313.415524292324;5318.893622127536;5324.54741346986;5329.120695085307;5818.359980402152;5830.271216097988;5831.183634291203;5831.504322132666;5837.241158649348;5399.919001214985;5426.945730542723;5435.972820135874;5439.564834813205;5465.535429488538;5562.610617256753;5563.693996830132;5570.442955704397;5572.442755724395;5612.1581762735095;5504.871501342215;5507.586930980113;5556.222128901957;5557.277553917965;5579.860503487445;5434.809781657702;5470.288862447856;5471.753771080311;5610.214569960209;5647.463490968352;4260.25445547028;4278.101598664253;4299.333603291495;4302.978485107617;4303.096349766584;3801.4867992820614;3808.4858544096746;3815.1797363566966;3844.0774214188395;3852.075501879529;5248.816291429782;5254.238135470412;5304.098303288451;5441.863953401196;5445.047116754552;5635.436456354414;5675;5675.2054712340305;5756.021476348947;5760.164776107525;5386.353637563439;5421.241026256327;5430.348358197041;5437.537809286255;5455.18172274162;4731.33761273422;4753.786079626425;4755.643326750507;4758.04839032188;4792.9281060784115;5747.137929310553;5789.3921138279875;5884.08796636522;5888.146218798164;5914;5731.512821410227;5731.512821410227;5735.627184233012;5767.990601644779;5778.335491418541;5972.163897054329;5980.641161530322;5983.222181116427;5988.341282459013;5993.190919225849;5357.785688572413;5451.018816613047;5452.754626041917;5624.971875140598;5629.493345598916;5576.498115169654;5604.579656525695;5633.6619802811965;5671.149327600893;5678.432156784454;5509.586780991522;5517.310336207974;5521.917171242515;5524.254225679642;5527.391986881359;5279.52484276417;5496.780128794963;5518.365387980433;5522.834314970557;5545.639533430475;5234.869128271671;5497.230387745799;5498.422665620213;5545.5009049184155;5617.4101719320515;5597.216389705363;5610.158476228598;5611.663300201838;5614.129809879475;5615.943840561542;5173.379194496684;5193.584513239008;5205.375354957429;5307.9734601326745;5411.918821217606 09:30:29 INFO - PROCESS | 21055 | |1;ext.html;570.113473548632;594.5778497266941;597.3339726205282;602.587227748992;606.484488185043;32737.67262327376;44663.33005004923;46915.06169876605;46988.29517557231;47013.29480057802;44599.77700111495;45001.324980125246;45017.09965800682;45078.01290799923;45086.323705144365;8323.25090741833;8865.601047952841;9031.909680903173;9043.773905652379;9052.728418147433;8236.546989915552;10583.788324233548;10808.459577021156;10830.566777328897;11161;10759.246852720313;10827.458627068654;10830.72923176923;10831.187660925423;10835.945820270888;10794.838077428805;10799.352038877692;10850.186236032328;10865;10872.293300935493;10552.15582753378;10639.202059845542;10640.521176547074;10649.520771565298;10654;41636.58363416357;41908.74273771792;41962;41967.370489442685;41991.370129448085;4120.464339635828;4165.45849039626;4170.627435561829;4226.788660566959;4247.68142389322;31826.36347273065;31922.20194495133;32264.6773532264;32267.6773232267;33399.16502087443;4281.207976524353;4342.782860856944;4353.455818022747;4506.909861802778;4588.541145885418;318992;319618.9999999977;320248;321017;321967;54224.49306083217;54824.17763733547;54912.725436372566;54919.45080549143;54924.45075549193;59210.40789592135;59246.703766481754;59334.406655932886;59352.70323648354;59364.40635593675;14662;15451.304691288919;15643.921780391252;15661.843381566267;15687;11186.832197517044;11218.66344009681;11391.145664075228;11657.125715571367;11682.065434765198;15754.369825207092;15999.60000999961;16110.436134735462;16217.432389866299;16297.103659298615;5950.642961422329;6021.397860214031;6063.9090413643835;6083.178770865965;6139.662318572432;918.1186061381148;919.1589695428742;922.9400088994194;926.5274709898;928.6842473558887;28911.42177156445;31787.523187152212;31796.36407271888;31880.20299492485;32019.839900800813;1057.8095942730383;1063.3832377221347;1074.462768615692;1083.1497274639528;1185.25921299188;15050.548483545512;16361.263743131587;16431.342746290014;16447.671046579002;16474.314777621166;472493;478458;480166;481859;481861;57997.42002580005;58403.4159658398;58406.831863362495;58415.415845840995;58502.70748646314;1384.259421209648;1416.0300194366891;1418.0853349589456;1419.936102875373;1423.0252277190157;28692.708828102368;30177.849110754305;30548.389032218787;30999.84500077485;31102.22244443907;49714;50109.24836127388;50407.747961259956;50487.74756126196;50612.493875060776;381988;382729;383301;383578;385679;35115.29769404649;39007.804960975016;39553.440190383;39831.80084099561;42721.35917961171;45231.321530176414;46934.530654693015;47033.29450058183;47480.20158387251;47520.2871956914;39212.2157556853;39409.315183538936;39642.216100276135;40639.79680101581;41559.58440415557;38659.42010869783;38790.80604596959;39429.2114157721;39654.80172599119;40699.59300407077;35833.82083089568;36671.44992825163;36932;38829.96271155838;39291;29952.25119372033;32327.676723232467;33172.66827331696;33223.66776332206;33595.32809343848;25864.8706756465;25905.611415829153;26202.344941376617;26350.4729905397;26672.59991100174 09:30:29 INFO - PROCESS | 21055 | |2;jquery.html;397.64212209011885;401.6525705264947;402.46271227910756;410.9506859176899;421.95147558030817;13034.957203423726;17747.73378399325;17793.288268469245;19243.903780481112;19256.807431925678;15885.761713574306;16772.748408773878;17882.642347153083;18040.54898627535;18589.7211541827;5309.840704778853;5618.747156377953;5670.773169073233;5672.5178359839465;5677.318721753375;5491.670499770006;5637.830865074034;5705.400932902048;5719.142128680711;5729.541636669056;5605.215269862222;5633.63381380211;5644.74598643062;5652.660840349592;5655.066913959192;5420.159875219348;5512.972435137799;5638.238837756891;5645.774169033254;5646.040173170551;5045.747712614355;5048.091343558158;5051.469595692436;5054.090263752523;5058.873528161788;21525.56948861014;21971.121155153774;22016.559668806534;22072.117115315366;22199.778002219933;2971.1680729395803;3079.0608864296382;3116.7974081684843;3129.29590842061;3180.236743181643;13449.125806822525;13941.542504400486;14209.573712788633;14218.715625687531;14516.854831451656;1920.3470819921295;1946.3771593090216;1958.1188465190721;1969.5962327722782;2006.518435575466;77230;77251.61374193092;77380.61309693416;77637;77904.99999999943;10689.83965240522;10714;10725.83911241332;10809;11596.710082247842;14937.551873443814;15054.322555485029;15081.170535620646;15086;15089.170095644626;7111.431085513146;7363.668634911442;7428.925710742823;7979.760607181794;8108.675652973815;7424.740134095281;7454.329110380092;7522.962385188039;7620.5808680522;7624.885626715603;6571.4085732283365;6578.144841170713;6617.834554136089;6665.1335326408225;6667.633280169637;1851.9722204166949;1937.9127939242767;1984.3550845974828;1984.474114359696;2027.3107143571256;1051.7055224537141;1067.8665166854144;1072.3137192197;1075.2741899217997;1116.413882711583;17163.141842908055;17848.732269015974;17857.553561160814;17934;18357.265709371743;1085.2891356161726;1090.7000574841982;1095.2552264460244;1099.7910397024539;1122.4612186150694;9722.708318750449;10153.441560714084;10215.489225538693;10296.948515257525;10339.276250662382;419122;422818;431930;432859;434049;69632.3036769636;70339.37285017186;70866.291337087;71117.64441177761;71626;1014.7767491151935;1058.6241884131023;1066.6373433032807;1068.9577661779704;1075.763332066944;15971.28129234164;16253.67492650164;16274.593135171242;16284.43004494837;16510.17449127515;31068;32591.18522036968;35311;38224.23551529009;39527.407088894266;51157.48842511527;51385;53391.199132013826;54576.454235458725;54834;8037.156098609562;8128.309093727099;8235.464694794819;8481.491110533389;8505.702300419456;11790.94104529472;12246.877531224574;12315;12355.814662779885;12424.192427492184;10051.949740251252;10293.794124117625;10383.221258405802;10833.945830270799;10884.183686223407;10006.8498972514;10044.748881278027;10317.432541210006;10605.787884242427;10666.253362264877;10749.81752007263;10799.946000269949;11079.224454288122;11357.772844542898;11409.60066397672;8182.386321026066;8210.876836847332;8225.95887020561;8483.618237179218;8699.217070463688;8287.751367458986;9928.255380846322;10170.8474372883;10536.15710743154;10572.629957951434 09:30:29 INFO - PROCESS | 21055 | |3;mootools.html;1340.631326385244;1359.170905747494;1362.8773410393067;1368.0492058019681;1375.1542801177272;7914.802129946755;8393;8394.62224199911;8396.62215200316;8405.747827565176;7131.108611423572;7340.192578816326;8037.517748935068;8188.877166842502;8247.42268041236;2324.2213858357472;2357.5049239659693;2358.657994590792;2361.8110551155864;2368.5736567417857;2297.597920363938;2375.061850569024;2386.451116243265;2388.319328991238;2388.7014123234594;2087.7390326209224;2161.8702877827304;2274.9886250568725;2343.2150229672993;2346.4485845826216;2201.9449513762206;2206.470447092702;2222.2444368914485;2234.139856155392;2241.753407125223;2331.918382856592;2340.590396680574;2357.834951553392;2379.7382287948312;2379.9524009519714;8353.624086916103;8409.411341206118;8410.579471026424;8421.368397370221;8423.15768423159;5060.2409638554145;5242;5246.895062098737;5247.10799164143;5252.553532949704;3283.622383425889;3312.5528053712683;3557.7331700122595;3559.733020023483;3562.2697347043795;1329.7141114660299;1383.5849245226493;1384.6469150366593;1448.478547722819;1449.2753623188405;11260.54957801687;11313;11315.207935444521;11324.603638872682;11328.09375249978;4570.954290457119;4663.813447462131;4757.405324334458;4764;4766.189747742892;9085.13691199335;9092.909070909338;9149.771255718528;9157.404768690014;9161.175494205554;5208.374995000624;5221.399539052982;5223.164293712987;5227.424983251839;5227.817026404057;4744.786484608201;4880.072786170616;4899.3385892904;4904.6321525885705;4905.386826646669;3431.69114779671;3495.405781017233;3506.368853606324;3537.2218112015316;3556.2354093869944;1093.8851420600881;1095.1129585035987;1100.4002818463957;1106.3804269609045;1108.501174471475;685.3831551603497;806.3790881021581;813.7151996801073;821.7616891101633;832.8584140695975;5316.149416093406;5497.175423686479;5543.556515478752;5567.916481252784;5640.520555752725;942.1473566422399;945.272140451862;949.3070058857073;964.5948701545368;966.6085235479642;3630.3102410541915;3928.489296391451;4033.778142202207;4090.9795451022555;4096.897577560525;9836.606535738634;10362.948185259176;10371.94814025925;10374.533146008447;10385.480725963671;10241.900410415996;10243.38539687621;10249.30886403735;10267.486625668687;10298.07317341428;7663.961680191564;7866.213378662088;7923.405744569181;7971;7985.361171106298;5285.471452854684;5409.188621706776;5488;5490.011797876421;5634.85912852182;8964.910350896407;9252.583633736363;9279;9383.671571494964;9405.482698451651;9339.20616747584;9486.667966621135;9495.572699228689;9534.475603841855;9617.182539483942;7222.566646001257;7328.487005909536;7367.373773229336;7374.852502950018;7415.555066696015;7783.182765809508;7867.213278672087;7875.606219688878;7894.21057894206;7896.131425543071;7028.613426261605;7054.435645148478;7159.785206443815;7194.064771579661;7316.634168291458;6878.896816547655;7100.573965562083;7123.643817809192;7264.963675181591;7315.158756743042;6327.90508142369;6329.145565348619;6383.904241436475;6394.200724909387;6396.42432181106;4816.373871396696;4904.558589726942;4908.779104940358;4911.926321105258;4962.950370496394;5061.83577777106;5109.9744501277255;5120.003198752564;5163.690178589297;5227.55565776898 09:30:29 INFO - PROCESS | 21055 | |4;prototype.html;392.13142888501966;395.3259692224757;398.30098177698136;401.61043787526097;405.1936646074313;5139.3318868547085;5185.3777546694355;5193.948060519394;5223.555997740196;5233.874716935858;5065.366829146356;5070;5118.744062796864;5143.434222235561;5144.691318520882;4080.9183816323657;4088.7751173685438;4108.77401742904;4115.403266526352;4124.793760311987;4101.343784994401;4106.67146628269;4110.958890411102;4123.9793801030955;4146.792660366985;3972.960270397288;4117.300058989964;4128.793560321987;4132.566080561543;4145.854895078673;4091.5908409158847;4106.8973275668195;4112.362583799486;4118.91762164755;4132.40080188374;3739.813009349549;3740.4763333133383;3750.5185451746406;3752.6434988676306;3771.2652179997235;5012.398512178562;5015.22264049073;5075.720835354054;5090.847274581768;5104.132297509433;4650.7209567425725;4737.407824021997;4748.857534273978;4766.666333356667;4783.617310615143;5382.838514844561;5391.541718953894;5406.864828379322;5408.945910540883;5413.2692086568595;4103.548609652936;4116.608922152391;4133.6693064554765;4133.751974881487;4138.751674899516;5345.438728933484;5490.478404551562;5503.724813759336;5583.441655834384;5601.383847776741;4155.854545090907;4208.894777630584;4259.74441533509;4295.463067116611;4300.741955482681;4358.951292892336;4374.321980093091;4375.27807911691;4376.059147283349;4384.846530371422;4509.391232183613;4569.634429245652;4767.69010014348;4770.330384365514;4792.496787837296;5452;5460.31746031746;5472.659198496394;5509.283793106951;5544.833654990357;1462.6782107936237;1469.7648376259747;1474.373391308711;1480.5854360778999;1486;1007.773251018515;1060.7242117049623;1065.9307145035702;1079.7192729890285;1098.5441041967654;5061.342025536656;5068.062408454448;5091.7963281469065;5122.617982638091;5129.79480820763;5390.838274851761;5405.567554595623;5409.43200963901;5410.91883621746;5414.91877621836;4912.263160525878;5010.092128140057;5062.620303477254;5140.511651393112;5164.767585458663;2847.430513897229;2862.822267283314;2863.6849946505868;2869.512182928907;2870.5790633636484;5570.387257401682;5582.91625625616;5595.328560572676;5622.240997465372;5635.549156067505;5287.259783630295;5495.642783219078;5524.977879092381;5533.474319939599;5559.49893528745;5572.3870374258795;5583.720813959367;5617.775288988395;5625.296837895263;5640.379558248589;5170.362034884022;5405.216243644728;5407;5416.9729151353995;5447.645903016291;5353.678779273256;5358.91002509014;5362.222477740628;5370.952664230437;5377.408485066561;5599.916001259903;5652.519535839499;5665.49010589049;5667.744951477111;5750.6773442107715;4231.301835197158;4261.446012018418;4278.914421711611;4296.033392486665;4308.24605693996;5546.89062187556;5548.55611551083;5555.916661250165;5611.410801865745;5620.875824834968;5189.792408303777;5473.397926228032;5488.725563721718;5492.5331346835965;5536.7231638418725;5517.862053448535;5524.696141712084;5525.834224973257;5536.861578460571;5545.833624991256;5263.868403289948;5548.585110796721;5553;5554.389017208185;5555.30558680165;5434.972825135849;5454.3454785426;5463.562914966714;5464.3442786865835;5496.587755918402;5347.27811745425;5350.384705758887;5393.164059570773;5409.107497262908;5431.158170483582 09:30:29 INFO - PROCESS | 21055 | |5;yui.html;739.0983000739103;753.1489416958835;792.6393490961613;829.4525613095358;834.9081601023886;14712.852871471296;15834.68330633388;15844.049357038586;15932.442364517245;16290.755638665429;15211.543653690407;15325.846741532581;15338.46315378962;15376.308066137022;15429.922850385761;706.4736771105534;728.6466063958976;787.5943888897223;800.7237503061434;802.7471346525855;811.3914564076942;814.2305521282391;814.9837003259931;816.6243527977138;826.5164878546058;884.4339622641514;885.8626912828522;886.0873300500494;886.1360173830496;898.9595468203914;749.5203070035154;774.8256642255532;840.474703310431;881.0880738435688;881.7442941546943;631.516889579472;634.4607083978627;641.3394203969918;649.9675016249168;651.5308977536166;14505;15109.47116850905;15137.545873623809;15155.696886062327;15220.771688424682;13464.663383415493;13780.460009315131;14171.149731016172;14309.427622895071;14347.42610295587;13024.29787951182;13169;13229.470821167142;13241.470341186341;13601.387937542835;4375.671824613167;4392.428984232061;4429.446319210098;4457.1977044132045;4500.729956202638;75525.24474755237;75572.62213688952;75751.62124189344;77736.61131694305;80420.59789701014;11204.943975280072;11237.719057023476;11243.494042768096;11279.210455268052;11492.54029838797;21247.575048498944;21337.89331053366;21406.892965535073;21543.2459863904;21628.243011494524;7789.493682910593;7960.960195199103;8088.919110808816;8213.096559378464;8266.421350505527;4468.463784345834;4515;4527.275635898306;4641.721496710208;4701.388819453449;3563.9287214256087;3633.9818300908855;3645.434957581526;3667.8716244931297;3699.186179040607;588.1677426441623;592.1059200607115;596.2249076200922;599.2060519811267;608.7808388979963;637.719403462475;649.5226008883467;654.4044919123575;658.2512590795375;671.6238906212536;8340.791480213044;8530.573471326408;8653.69712060075;8657.134286571418;8674.78313042179;614.4132353602299;625.893598088326;638.141699414287;640.8365866703961;660.5706290910946;3969.543502497192;4173.800517618989;4195.706300558932;4204.2432362175105;4238.406623072772;81320.59339703263;82417;83088;83341.58329208315;85161.57419212864;70637.64681176664;71603;71869.6406517964;72481.27518724745;73783;64321.67839160774;66172.05193454714;66560.33439665638;67760.32239677639;69497;7742.341900938258;7863.921360786548;7964.681412743425;8071.152528984607;8099.271065603888;42826.78586607047;43828.56171438372;44620.77689611531;44900.77549612231;45480.31779523376;50003.749981249865;52443.73778131085;52587.73706131445;52720.472795271555;53103;11099.944500277446;11206.831897521706;11207.887921121011;11266.887331126913;11507.884921150682;12161.209521381083;12273.570425035081;12314.445849936954;12569.371531423576;12919.870801292243;11070.501827417947;11143.108551315712;11352.772944541228;11572.710682233012;11743.412829358327;11557.133215009077;11579.478923448296;11705.531778729097;12019;12062.638120856389;11106.61126860556;11262.831057534308;11468.82796758066;11632.360220187971;11935.880641193477;9058.41120327203;9103.089691030844;9214.401063930824;9386.342955993015;9414.858777118487;8441.15588441151;8529.189726975927;8904.19862212404;9101.36290459688;9189.816203675755 09:30:29 INFO - PROCESS | 21055 | __end_tp_report 09:30:29 INFO - PROCESS | 21055 | __start_cc_report 09:30:29 INFO - PROCESS | 21055 | _x_x_mozilla_cycle_collect,982 09:30:29 INFO - PROCESS | 21055 | __end_cc_report 09:30:29 INFO - PROCESS | 21055 | __startTimestamp1482773429012__endTimestamp 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | ------- Summary: start ------- 09:30:29 INFO - PROCESS | 21055 | Number of tests: 6 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | [#0] dojo.html Cycles:165 Average:5227.91 Median:5434.81 stddev:597.92 (11.0%) stddev-sans-first:575.92 09:30:29 INFO - PROCESS | 21055 | Values: 3097.0 3125.5 3132.4 3156.6 3178.6 5101.3 5132.3 5134.8 5223.9 5226.7 5196.7 5225.2 5238.4 5250.1 5257.3 4740.1 4766.5 4802.4 4823.0 4837.1 4772.5 4814.6 4818.0 4850.9 4856.5 4294.5 4866.1 4872.8 4892.9 4929.1 4608.1 4623.8 4854.9 4896.0 4898.7 4695.0 4737.5 4745.9 4766.0 4800.9 5203.5 5208.6 5219.1 5235.5 5286.2 5411.4 5536.7 5606.4 5625.6 5626.1 5504.1 5655.9 5664.7 5714.8 5786.9 5307.6 5313.4 5318.9 5324.5 5329.1 5818.4 5830.3 5831.2 5831.5 5837.2 5399.9 5426.9 5436.0 5439.6 5465.5 5562.6 5563.7 5570.4 5572.4 5612.2 5504.9 5507.6 5556.2 5557.3 5579.9 5434.8 5470.3 5471.8 5610.2 5647.5 4260.3 4278.1 4299.3 4303.0 4303.1 3801.5 3808.5 3815.2 3844.1 3852.1 5248.8 5254.2 5304.1 5441.9 5445.0 5635.4 5675.0 5675.2 5756.0 5760.2 5386.4 5421.2 5430.3 5437.5 5455.2 4731.3 4753.8 4755.6 4758.0 4792.9 5747.1 5789.4 5884.1 5888.1 5914.0 5731.5 5731.5 5735.6 5768.0 5778.3 5972.2 5980.6 5983.2 5988.3 5993.2 5357.8 5451.0 5452.8 5625.0 5629.5 5576.5 5604.6 5633.7 5671.1 5678.4 5509.6 5517.3 5521.9 5524.3 5527.4 5279.5 5496.8 5518.4 5522.8 5545.6 5234.9 5497.2 5498.4 5545.5 5617.4 5597.2 5610.2 5611.7 5614.1 5615.9 5173.4 5193.6 5205.4 5308.0 5411.9 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | [#1] ext.html Cycles:180 Average:56136.34 Median:38810.38 stddev:105480.10 (271.8%) stddev-sans-first:105693.49 09:30:29 INFO - PROCESS | 21055 | Values: 570.1 594.6 597.3 602.6 606.5 32737.7 44663.3 46915.1 46988.3 47013.3 44599.8 45001.3 45017.1 45078.0 45086.3 8323.3 8865.6 9031.9 9043.8 9052.7 8236.5 10583.8 10808.5 10830.6 11161.0 10759.2 10827.5 10830.7 10831.2 10835.9 10794.8 10799.4 10850.2 10865.0 10872.3 10552.2 10639.2 10640.5 10649.5 10654.0 41636.6 41908.7 41962.0 41967.4 41991.4 4120.5 4165.5 4170.6 4226.8 4247.7 31826.4 31922.2 32264.7 32267.7 33399.2 4281.2 4342.8 4353.5 4506.9 4588.5 318992.0 319619.0 320248.0 321017.0 321967.0 54224.5 54824.2 54912.7 54919.5 54924.5 59210.4 59246.7 59334.4 59352.7 59364.4 14662.0 15451.3 15643.9 15661.8 15687.0 11186.8 11218.7 11391.1 11657.1 11682.1 15754.4 15999.6 16110.4 16217.4 16297.1 5950.6 6021.4 6063.9 6083.2 6139.7 918.1 919.2 922.9 926.5 928.7 28911.4 31787.5 31796.4 31880.2 32019.8 1057.8 1063.4 1074.5 1083.1 1185.3 15050.5 16361.3 16431.3 16447.7 16474.3 472493.0 478458.0 480166.0 481859.0 481861.0 57997.4 58403.4 58406.8 58415.4 58502.7 1384.3 1416.0 1418.1 1419.9 1423.0 28692.7 30177.8 30548.4 30999.8 31102.2 49714.0 50109.2 50407.7 50487.7 50612.5 381988.0 382729.0 383301.0 383578.0 385679.0 35115.3 39007.8 39553.4 39831.8 42721.4 45231.3 46934.5 47033.3 47480.2 47520.3 39212.2 39409.3 39642.2 40639.8 41559.6 38659.4 38790.8 39429.2 39654.8 40699.6 35833.8 36671.4 36932.0 38830.0 39291.0 29952.3 32327.7 33172.7 33223.7 33595.3 25864.9 25905.6 26202.3 26350.5 26672.6 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | [#2] jquery.html Cycles:180 Average:26011.19 Median:22135.95 stddev:70406.13 (318.1%) stddev-sans-first:70577.38 09:30:29 INFO - PROCESS | 21055 | Values: 397.6 401.7 402.5 411.0 422.0 13035.0 17747.7 17793.3 19243.9 19256.8 15885.8 16772.7 17882.6 18040.5 18589.7 5309.8 5618.7 5670.8 5672.5 5677.3 5491.7 5637.8 5705.4 5719.1 5729.5 5605.2 5633.6 5644.7 5652.7 5655.1 5420.2 5513.0 5638.2 5645.8 5646.0 5045.7 5048.1 5051.5 5054.1 5058.9 21525.6 21971.1 22016.6 22072.1 22199.8 2971.2 3079.1 3116.8 3129.3 3180.2 13449.1 13941.5 14209.6 14218.7 14516.9 1920.3 1946.4 1958.1 1969.6 2006.5 77230.0 77251.6 77380.6 77637.0 77905.0 10689.8 10714.0 10725.8 10809.0 11596.7 14937.6 15054.3 15081.2 15086.0 15089.2 7111.4 7363.7 7428.9 7979.8 8108.7 7424.7 7454.3 7523.0 7620.6 7624.9 6571.4 6578.1 6617.8 6665.1 6667.6 1852.0 1937.9 1984.4 1984.5 2027.3 1051.7 1067.9 1072.3 1075.3 1116.4 17163.1 17848.7 17857.6 17934.0 18357.3 1085.3 1090.7 1095.3 1099.8 1122.5 9722.7 10153.4 10215.5 10296.9 10339.3 419122.0 422818.0 431930.0 432859.0 434049.0 69632.3 70339.4 70866.3 71117.6 71626.0 1014.8 1058.6 1066.6 1069.0 1075.8 15971.3 16253.7 16274.6 16284.4 16510.2 31068.0 32591.2 35311.0 38224.2 39527.4 51157.5 51385.0 53391.2 54576.5 54834.0 8037.2 8128.3 8235.5 8481.5 8505.7 11790.9 12246.9 12315.0 12355.8 12424.2 10051.9 10293.8 10383.2 10833.9 10884.2 10006.8 10044.7 10317.4 10605.8 10666.3 10749.8 10799.9 11079.2 11357.8 11409.6 8182.4 8210.9 8226.0 8483.6 8699.2 8287.8 9928.3 10170.8 10536.2 10572.6 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | [#3] mootools.html Cycles:180 Average:5473.10 Median:5222.28 stddev:3010.49 (57.6%) stddev-sans-first:3002.92 09:30:29 INFO - PROCESS | 21055 | Values: 1340.6 1359.2 1362.9 1368.0 1375.2 7914.8 8393.0 8394.6 8396.6 8405.7 7131.1 7340.2 8037.5 8188.9 8247.4 2324.2 2357.5 2358.7 2361.8 2368.6 2297.6 2375.1 2386.5 2388.3 2388.7 2087.7 2161.9 2275.0 2343.2 2346.4 2201.9 2206.5 2222.2 2234.1 2241.8 2331.9 2340.6 2357.8 2379.7 2380.0 8353.6 8409.4 8410.6 8421.4 8423.2 5060.2 5242.0 5246.9 5247.1 5252.6 3283.6 3312.6 3557.7 3559.7 3562.3 1329.7 1383.6 1384.6 1448.5 1449.3 11260.5 11313.0 11315.2 11324.6 11328.1 4571.0 4663.8 4757.4 4764.0 4766.2 9085.1 9092.9 9149.8 9157.4 9161.2 5208.4 5221.4 5223.2 5227.4 5227.8 4744.8 4880.1 4899.3 4904.6 4905.4 3431.7 3495.4 3506.4 3537.2 3556.2 1093.9 1095.1 1100.4 1106.4 1108.5 685.4 806.4 813.7 821.8 832.9 5316.1 5497.2 5543.6 5567.9 5640.5 942.1 945.3 949.3 964.6 966.6 3630.3 3928.5 4033.8 4091.0 4096.9 9836.6 10362.9 10371.9 10374.5 10385.5 10241.9 10243.4 10249.3 10267.5 10298.1 7664.0 7866.2 7923.4 7971.0 7985.4 5285.5 5409.2 5488.0 5490.0 5634.9 8964.9 9252.6 9279.0 9383.7 9405.5 9339.2 9486.7 9495.6 9534.5 9617.2 7222.6 7328.5 7367.4 7374.9 7415.6 7783.2 7867.2 7875.6 7894.2 7896.1 7028.6 7054.4 7159.8 7194.1 7316.6 6878.9 7100.6 7123.6 7265.0 7315.2 6327.9 6329.1 6383.9 6394.2 6396.4 4816.4 4904.6 4908.8 4911.9 4963.0 5061.8 5110.0 5120.0 5163.7 5227.6 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | [#4] prototype.html Cycles:180 Average:4603.19 Median:5134.56 stddev:1286.43 (25.1%) stddev-sans-first:1250.60 09:30:29 INFO - PROCESS | 21055 | Values: 392.1 395.3 398.3 401.6 405.2 5139.3 5185.4 5193.9 5223.6 5233.9 5065.4 5070.0 5118.7 5143.4 5144.7 4080.9 4088.8 4108.8 4115.4 4124.8 4101.3 4106.7 4111.0 4124.0 4146.8 3973.0 4117.3 4128.8 4132.6 4145.9 4091.6 4106.9 4112.4 4118.9 4132.4 3739.8 3740.5 3750.5 3752.6 3771.3 5012.4 5015.2 5075.7 5090.8 5104.1 4650.7 4737.4 4748.9 4766.7 4783.6 5382.8 5391.5 5406.9 5408.9 5413.3 4103.5 4116.6 4133.7 4133.8 4138.8 5345.4 5490.5 5503.7 5583.4 5601.4 4155.9 4208.9 4259.7 4295.5 4300.7 4359.0 4374.3 4375.3 4376.1 4384.8 4509.4 4569.6 4767.7 4770.3 4792.5 5452.0 5460.3 5472.7 5509.3 5544.8 1462.7 1469.8 1474.4 1480.6 1486.0 1007.8 1060.7 1065.9 1079.7 1098.5 5061.3 5068.1 5091.8 5122.6 5129.8 5390.8 5405.6 5409.4 5410.9 5414.9 4912.3 5010.1 5062.6 5140.5 5164.8 2847.4 2862.8 2863.7 2869.5 2870.6 5570.4 5582.9 5595.3 5622.2 5635.5 5287.3 5495.6 5525.0 5533.5 5559.5 5572.4 5583.7 5617.8 5625.3 5640.4 5170.4 5405.2 5407.0 5417.0 5447.6 5353.7 5358.9 5362.2 5371.0 5377.4 5599.9 5652.5 5665.5 5667.7 5750.7 4231.3 4261.4 4278.9 4296.0 4308.2 5546.9 5548.6 5555.9 5611.4 5620.9 5189.8 5473.4 5488.7 5492.5 5536.7 5517.9 5524.7 5525.8 5536.9 5545.8 5263.9 5548.6 5553.0 5554.4 5555.3 5435.0 5454.3 5463.6 5464.3 5496.6 5347.3 5350.4 5393.2 5409.1 5431.2 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - PROCESS | 21055 | [#5] yui.html Cycles:180 Average:17388.88 Median:590.14 stddev:23097.11 (3913.9%) stddev-sans-first:23128.07 09:30:29 INFO - PROCESS | 21055 | Values: 739.1 753.1 792.6 829.5 834.9 14712.9 15834.7 15844.0 15932.4 16290.8 15211.5 15325.8 15338.5 15376.3 15429.9 706.5 728.6 787.6 800.7 802.7 811.4 814.2 815.0 816.6 826.5 884.4 885.9 886.1 886.1 899.0 749.5 774.8 840.5 881.1 881.7 631.5 634.5 641.3 650.0 651.5 14505.0 15109.5 15137.5 15155.7 15220.8 13464.7 13780.5 14171.1 14309.4 14347.4 13024.3 13169.0 13229.5 13241.5 13601.4 4375.7 4392.4 4429.4 4457.2 4500.7 75525.2 75572.6 75751.6 77736.6 80420.6 11204.9 11237.7 11243.5 11279.2 11492.5 21247.6 21337.9 21406.9 21543.2 21628.2 7789.5 7961.0 8088.9 8213.1 8266.4 4468.5 4515.0 4527.3 4641.7 4701.4 3563.9 3634.0 3645.4 3667.9 3699.2 588.2 592.1 596.2 599.2 608.8 637.7 649.5 654.4 658.3 671.6 8340.8 8530.6 8653.7 8657.1 8674.8 614.4 625.9 638.1 640.8 660.6 3969.5 4173.8 4195.7 4204.2 4238.4 81320.6 82417.0 83088.0 83341.6 85161.6 70637.6 71603.0 71869.6 72481.3 73783.0 64321.7 66172.1 66560.3 67760.3 69497.0 7742.3 7863.9 7964.7 8071.2 8099.3 42826.8 43828.6 44620.8 44900.8 45480.3 50003.7 52443.7 52587.7 52720.5 53103.0 11099.9 11206.8 11207.9 11266.9 11507.9 12161.2 12273.6 12314.4 12569.4 12919.9 11070.5 11143.1 11352.8 11572.7 11743.4 11557.1 11579.5 11705.5 12019.0 12062.6 11106.6 11262.8 11468.8 11632.4 11935.9 9058.4 9103.1 9214.4 9386.3 9414.9 8441.2 8529.2 8904.2 9101.4 9189.8 09:30:29 INFO - PROCESS | 21055 | -------- Summary: end -------- 09:30:29 INFO - PROCESS | 21055 | 09:30:29 INFO - TEST-INFO | 21055: exit 0 09:30:29 INFO - TEST-OK | dromaeo_css | took 1115669ms 09:30:29 INFO - TEST-START | kraken 09:30:29 INFO - operating with platform_type : linux_ 09:30:29 INFO - Initialising browser for kraken test... 09:30:29 INFO - TEST-INFO | started process 21361 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDDkZfu/profile http://localhost:32851/getInfo.html) 09:30:30 INFO - PROCESS | 21361 | 1482773430293 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:33 INFO - PROCESS | 21361 | __metrics Screen width/height:1600/1200 09:30:33 INFO - PROCESS | 21361 | colorDepth:24 09:30:33 INFO - PROCESS | 21361 | Browser inner width/height: 1024/697 09:30:33 INFO - PROCESS | 21361 | __metrics 09:30:34 INFO - PROCESS | 21361 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:30:34 INFO - PROCESS | 21361 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:35 INFO - PROCESS | 21361 | console.error: PushService: 09:30:35 INFO - PROCESS | 21361 | stateChangeProcessEnqueue: Error transitioning state 09:30:35 INFO - PROCESS | 21361 | UnknownError 09:30:35 INFO - TEST-INFO | 21361: exit 0 09:30:35 INFO - Browser initialized. 09:30:35 INFO - Running cycle 1/1 for kraken test... 09:30:35 INFO - TEST-INFO | started process 21447 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDDkZfu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:47 INFO - PROCESS | 21447 | RSS: Main: 146538496 09:30:47 INFO - PROCESS | 21447 | 09:30:50 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32851/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:50 INFO - PROCESS | 21447 | RSS: Main: 200974336 09:30:50 INFO - PROCESS | 21447 | 09:30:53 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32851/tests/kraken/driver.html?testName=audio-dft) 09:30:54 INFO - PROCESS | 21447 | RSS: Main: 180838400 09:30:54 INFO - PROCESS | 21447 | 09:30:56 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32851/tests/kraken/driver.html?testName=audio-fft) 09:30:57 INFO - PROCESS | 21447 | RSS: Main: 262414336 09:30:57 INFO - PROCESS | 21447 | 09:30:59 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32851/tests/kraken/driver.html?testName=audio-oscillator) 09:31:00 INFO - PROCESS | 21447 | RSS: Main: 172908544 09:31:00 INFO - PROCESS | 21447 | 09:31:02 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32851/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:02 INFO - PROCESS | 21447 | RSS: Main: 177713152 09:31:02 INFO - PROCESS | 21447 | 09:31:05 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32851/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:06 INFO - PROCESS | 21447 | RSS: Main: 217464832 09:31:06 INFO - PROCESS | 21447 | 09:31:09 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32851/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:10 INFO - PROCESS | 21447 | RSS: Main: 216760320 09:31:10 INFO - PROCESS | 21447 | 09:31:13 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32851/tests/kraken/driver.html?testName=json-parse-financial) 09:31:13 INFO - PROCESS | 21447 | RSS: Main: 214642688 09:31:13 INFO - PROCESS | 21447 | 09:31:15 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32851/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:15 INFO - PROCESS | 21447 | RSS: Main: 176332800 09:31:15 INFO - PROCESS | 21447 | 09:31:17 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:31:18 INFO - PROCESS | 21447 | RSS: Main: 184066048 09:31:18 INFO - PROCESS | 21447 | 09:31:20 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:31:20 INFO - PROCESS | 21447 | RSS: Main: 193175552 09:31:20 INFO - PROCESS | 21447 | 09:31:23 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:31:24 INFO - PROCESS | 21447 | RSS: Main: 202149888 09:31:24 INFO - PROCESS | 21447 | 09:31:26 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:31:27 INFO - PROCESS | 21447 | RSS: Main: 198856704 09:31:27 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | Cycle 1(1): loaded http://localhost:32851/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32851/tests/kraken/driver.html?testName=ai-astar) 09:31:29 INFO - PROCESS | 21447 | RSS: Main: 250474496 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | __start_tp_report 09:31:29 INFO - PROCESS | 21447 | _x_x_mozilla_page_load 09:31:29 INFO - PROCESS | 21447 | _x_x_mozilla_page_load_details 09:31:29 INFO - PROCESS | 21447 | |i|pagename|runs| 09:31:29 INFO - PROCESS | 21447 | |0;ai-astar;109;109;112;110;110;110;111;110;110;110 09:31:29 INFO - PROCESS | 21447 | |1;audio-beat-detection;147;119;119;168;132;124;120;117;141;117 09:31:29 INFO - PROCESS | 21447 | |2;audio-dft;160;161;159;161;161;162;160;162;162;161 09:31:29 INFO - PROCESS | 21447 | |3;audio-fft;75;76;76;112;75;76;75;76;93;75 09:31:29 INFO - PROCESS | 21447 | |4;audio-oscillator;84;84;107;83;84;85;93;83;86;102 09:31:29 INFO - PROCESS | 21447 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;116;117;116 09:31:29 INFO - PROCESS | 21447 | |6;imaging-darkroom;146;147;148;146;147;147;146;146;147;147 09:31:29 INFO - PROCESS | 21447 | |7;imaging-desaturate;96;96;96;95;95;95;95;95;95;95 09:31:29 INFO - PROCESS | 21447 | |8;json-parse-financial;50;49;49;49;51;52;51;51;51;52 09:31:29 INFO - PROCESS | 21447 | |9;json-stringify-tinderbox;69;68;67;68;68;67;68;68;68;69 09:31:29 INFO - PROCESS | 21447 | |10;stanford-crypto-aes;82;81;81;82;81;82;87;80;82;81 09:31:29 INFO - PROCESS | 21447 | |11;stanford-crypto-ccm;130;127;137;124;131;122;125;138;163;136 09:31:29 INFO - PROCESS | 21447 | |12;stanford-crypto-pbkdf2;165;174;165;164;165;170;168;167;172;163 09:31:29 INFO - PROCESS | 21447 | |13;stanford-crypto-sha256-iterative;63;65;63;62;62;62;65;62;66;61 09:31:29 INFO - PROCESS | 21447 | __end_tp_report 09:31:29 INFO - PROCESS | 21447 | __start_cc_report 09:31:29 INFO - PROCESS | 21447 | _x_x_mozilla_cycle_collect,1561 09:31:29 INFO - PROCESS | 21447 | __end_cc_report 09:31:29 INFO - PROCESS | 21447 | __startTimestamp1482773489484__endTimestamp 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | ------- Summary: start ------- 09:31:29 INFO - PROCESS | 21447 | Number of tests: 14 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.00 stddev:0.88 (0.8%) stddev-sans-first:0.83 09:31:29 INFO - PROCESS | 21447 | Values: 109.0 109.0 112.0 110.0 110.0 110.0 111.0 110.0 110.0 110.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:128.00 stddev:16.91 (13.2%) stddev-sans-first:16.83 09:31:29 INFO - PROCESS | 21447 | Values: 147.0 119.0 119.0 168.0 132.0 124.0 120.0 117.0 141.0 117.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 09:31:29 INFO - PROCESS | 21447 | Values: 160.0 161.0 159.0 161.0 161.0 162.0 160.0 162.0 162.0 161.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:12.24 (16.1%) stddev-sans-first:12.80 09:31:29 INFO - PROCESS | 21447 | Values: 75.0 76.0 76.0 112.0 75.0 76.0 75.0 76.0 93.0 75.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:84.00 stddev:8.70 (10.4%) stddev-sans-first:9.03 09:31:29 INFO - PROCESS | 21447 | Values: 84.0 84.0 107.0 83.0 84.0 85.0 93.0 83.0 86.0 102.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:31:29 INFO - PROCESS | 21447 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:31:29 INFO - PROCESS | 21447 | Values: 146.0 147.0 148.0 146.0 147.0 147.0 146.0 146.0 147.0 147.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 09:31:29 INFO - PROCESS | 21447 | Values: 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:51.00 stddev:1.18 (2.3%) stddev-sans-first:1.24 09:31:29 INFO - PROCESS | 21447 | Values: 50.0 49.0 49.0 49.0 51.0 52.0 51.0 51.0 51.0 52.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 09:31:29 INFO - PROCESS | 21447 | Values: 69.0 68.0 67.0 68.0 68.0 67.0 68.0 68.0 68.0 69.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.00 stddev:1.91 (2.3%) stddev-sans-first:2.03 09:31:29 INFO - PROCESS | 21447 | Values: 82.0 81.0 81.0 82.0 81.0 82.0 87.0 80.0 82.0 81.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:133.50 stddev:11.85 (8.9%) stddev-sans-first:12.51 09:31:29 INFO - PROCESS | 21447 | Values: 130.0 127.0 137.0 124.0 131.0 122.0 125.0 138.0 163.0 136.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:3.65 (2.2%) stddev-sans-first:3.78 09:31:29 INFO - PROCESS | 21447 | Values: 165.0 174.0 165.0 164.0 165.0 170.0 168.0 167.0 172.0 163.0 09:31:29 INFO - PROCESS | 21447 | 09:31:29 INFO - PROCESS | 21447 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:1.66 (2.6%) stddev-sans-first:1.76 09:31:29 INFO - PROCESS | 21447 | Values: 63.0 65.0 63.0 62.0 62.0 62.0 65.0 62.0 66.0 61.0 09:31:29 INFO - PROCESS | 21447 | -------- Summary: end -------- 09:31:29 INFO - PROCESS | 21447 | 09:31:30 INFO - TEST-INFO | 21447: exit 0 09:31:30 INFO - TEST-OK | kraken | took 60753ms 09:31:30 INFO - SUITE-END | took 1176s 09:31:30 INFO - Completed test suite (00:19:36) 09:31:30 INFO - javascript benchmark 09:31:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3097.0399176255355, 3125.468670326046, 3132.3578666373387, 3156.636986746525, 3178.6344570374413, 5101.260317253996, 5132.307138536296, 5134.768935397907, 5223.895522089556, 5226.712530810799, 5196.740162991845, 5225.163973764198, 5238.4499627539135, 5250.05499010178, 5257.263983042377, 4740.146773580754, 4766.499517550659, 4802.447718512363, 4822.951770482286, 4837.129316722989, 4772.546608072227, 4814.590759785422, 4818.036392721452, 4850.854474365757, 4856.538628830273, 4294.475585839161, 4866.099771542276, 4872.829450969217, 4892.902141957159, 4929.137400954836, 4608.1244563533155, 4623.8150473981, 4854.854354369375, 4895.9510404895855, 4898.706077635354, 4694.953050469486, 4737.478877323491, 4745.908441058576, 4766.046790641885, 4800.943792365674, 5203.505666961632, 5208.635395522315, 5219.138842091088, 5235.502627250406, 5286.15421532553, 5411.377691565442, 5536.723163841792, 5606.439356064402, 5625.606207565472, 5626.071698169797, 5504.146857237135, 5655.915161272585, 5664.745086471119, 5714.828555143353, 5786.855328616776, 5307.57539396855, 5313.415524292324, 5318.893622127536, 5324.54741346986, 5329.120695085307, 5818.359980402152, 5830.271216097988, 5831.183634291203, 5831.504322132666, 5837.241158649348, 5399.919001214985, 5426.945730542723, 5435.972820135874, 5439.564834813205, 5465.535429488538, 5562.610617256753, 5563.693996830132, 5570.442955704397, 5572.442755724395, 5612.1581762735095, 5504.871501342215, 5507.586930980113, 5556.222128901957, 5557.277553917965, 5579.860503487445, 5434.809781657702, 5470.288862447856, 5471.753771080311, 5610.214569960209, 5647.463490968352, 4260.25445547028, 4278.101598664253, 4299.333603291495, 4302.978485107617, 4303.096349766584, 3801.4867992820614, 3808.4858544096746, 3815.1797363566966, 3844.0774214188395, 3852.075501879529, 5248.816291429782, 5254.238135470412, 5304.098303288451, 5441.863953401196, 5445.047116754552, 5635.436456354414, 5675.0, 5675.2054712340305, 5756.021476348947, 5760.164776107525, 5386.353637563439, 5421.241026256327, 5430.348358197041, 5437.537809286255, 5455.18172274162, 4731.33761273422, 4753.786079626425, 4755.643326750507, 4758.04839032188, 4792.9281060784115, 5747.137929310553, 5789.3921138279875, 5884.08796636522, 5888.146218798164, 5914.0, 5731.512821410227, 5731.512821410227, 5735.627184233012, 5767.990601644779, 5778.335491418541, 5972.163897054329, 5980.641161530322, 5983.222181116427, 5988.341282459013, 5993.190919225849, 5357.785688572413, 5451.018816613047, 5452.754626041917, 5624.971875140598, 5629.493345598916, 5576.498115169654, 5604.579656525695, 5633.6619802811965, 5671.149327600893, 5678.432156784454, 5509.586780991522, 5517.310336207974, 5521.917171242515, 5524.254225679642, 5527.391986881359, 5279.52484276417, 5496.780128794963, 5518.365387980433, 5522.834314970557, 5545.639533430475, 5234.869128271671, 5497.230387745799, 5498.422665620213, 5545.5009049184155, 5617.4101719320515, 5597.216389705363, 5610.158476228598, 5611.663300201838, 5614.129809879475, 5615.943840561542, 5173.379194496684, 5193.584513239008, 5205.375354957429, 5307.9734601326745, 5411.918821217606], "value": 5188.3888787001815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.113473548632, 594.5778497266941, 597.3339726205282, 602.587227748992, 606.484488185043, 32737.67262327376, 44663.33005004923, 46915.06169876605, 46988.29517557231, 47013.29480057802, 44599.77700111495, 45001.324980125246, 45017.09965800682, 45078.01290799923, 45086.323705144365, 8323.25090741833, 8865.601047952841, 9031.909680903173, 9043.773905652379, 9052.728418147433, 8236.546989915552, 10583.788324233548, 10808.459577021156, 10830.566777328897, 11161.0, 10759.246852720313, 10827.458627068654, 10830.72923176923, 10831.187660925423, 10835.945820270888, 10794.838077428805, 10799.352038877692, 10850.186236032328, 10865.0, 10872.293300935493, 10552.15582753378, 10639.202059845542, 10640.521176547074, 10649.520771565298, 10654.0, 41636.58363416357, 41908.74273771792, 41962.0, 41967.370489442685, 41991.370129448085, 4120.464339635828, 4165.45849039626, 4170.627435561829, 4226.788660566959, 4247.68142389322, 31826.36347273065, 31922.20194495133, 32264.6773532264, 32267.6773232267, 33399.16502087443, 4281.207976524353, 4342.782860856944, 4353.455818022747, 4506.909861802778, 4588.541145885418, 318992.0, 319618.9999999977, 320248.0, 321017.0, 321967.0, 54224.49306083217, 54824.17763733547, 54912.725436372566, 54919.45080549143, 54924.45075549193, 59210.40789592135, 59246.703766481754, 59334.406655932886, 59352.70323648354, 59364.40635593675, 14662.0, 15451.304691288919, 15643.921780391252, 15661.843381566267, 15687.0, 11186.832197517044, 11218.66344009681, 11391.145664075228, 11657.125715571367, 11682.065434765198, 15754.369825207092, 15999.60000999961, 16110.436134735462, 16217.432389866299, 16297.103659298615, 5950.642961422329, 6021.397860214031, 6063.9090413643835, 6083.178770865965, 6139.662318572432, 918.1186061381148, 919.1589695428742, 922.9400088994194, 926.5274709898, 928.6842473558887, 28911.42177156445, 31787.523187152212, 31796.36407271888, 31880.20299492485, 32019.839900800813, 1057.8095942730383, 1063.3832377221347, 1074.462768615692, 1083.1497274639528, 1185.25921299188, 15050.548483545512, 16361.263743131587, 16431.342746290014, 16447.671046579002, 16474.314777621166, 472493.0, 478458.0, 480166.0, 481859.0, 481861.0, 57997.42002580005, 58403.4159658398, 58406.831863362495, 58415.415845840995, 58502.70748646314, 1384.259421209648, 1416.0300194366891, 1418.0853349589456, 1419.936102875373, 1423.0252277190157, 28692.708828102368, 30177.849110754305, 30548.389032218787, 30999.84500077485, 31102.22244443907, 49714.0, 50109.24836127388, 50407.747961259956, 50487.74756126196, 50612.493875060776, 381988.0, 382729.0, 383301.0, 383578.0, 385679.0, 35115.29769404649, 39007.804960975016, 39553.440190383, 39831.80084099561, 42721.35917961171, 45231.321530176414, 46934.530654693015, 47033.29450058183, 47480.20158387251, 47520.2871956914, 39212.2157556853, 39409.315183538936, 39642.216100276135, 40639.79680101581, 41559.58440415557, 38659.42010869783, 38790.80604596959, 39429.2114157721, 39654.80172599119, 40699.59300407077, 35833.82083089568, 36671.44992825163, 36932.0, 38829.96271155838, 39291.0, 29952.25119372033, 32327.676723232467, 33172.66827331696, 33223.66776332206, 33595.32809343848, 25864.8706756465, 25905.611415829153, 26202.344941376617, 26350.4729905397, 26672.59991100174], "value": 19968.837938869416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.64212209011885, 401.6525705264947, 402.46271227910756, 410.9506859176899, 421.95147558030817, 13034.957203423726, 17747.73378399325, 17793.288268469245, 19243.903780481112, 19256.807431925678, 15885.761713574306, 16772.748408773878, 17882.642347153083, 18040.54898627535, 18589.7211541827, 5309.840704778853, 5618.747156377953, 5670.773169073233, 5672.5178359839465, 5677.318721753375, 5491.670499770006, 5637.830865074034, 5705.400932902048, 5719.142128680711, 5729.541636669056, 5605.215269862222, 5633.63381380211, 5644.74598643062, 5652.660840349592, 5655.066913959192, 5420.159875219348, 5512.972435137799, 5638.238837756891, 5645.774169033254, 5646.040173170551, 5045.747712614355, 5048.091343558158, 5051.469595692436, 5054.090263752523, 5058.873528161788, 21525.56948861014, 21971.121155153774, 22016.559668806534, 22072.117115315366, 22199.778002219933, 2971.1680729395803, 3079.0608864296382, 3116.7974081684843, 3129.29590842061, 3180.236743181643, 13449.125806822525, 13941.542504400486, 14209.573712788633, 14218.715625687531, 14516.854831451656, 1920.3470819921295, 1946.3771593090216, 1958.1188465190721, 1969.5962327722782, 2006.518435575466, 77230.0, 77251.61374193092, 77380.61309693416, 77637.0, 77904.99999999943, 10689.83965240522, 10714.0, 10725.83911241332, 10809.0, 11596.710082247842, 14937.551873443814, 15054.322555485029, 15081.170535620646, 15086.0, 15089.170095644626, 7111.431085513146, 7363.668634911442, 7428.925710742823, 7979.760607181794, 8108.675652973815, 7424.740134095281, 7454.329110380092, 7522.962385188039, 7620.5808680522, 7624.885626715603, 6571.4085732283365, 6578.144841170713, 6617.834554136089, 6665.1335326408225, 6667.633280169637, 1851.9722204166949, 1937.9127939242767, 1984.3550845974828, 1984.474114359696, 2027.3107143571256, 1051.7055224537141, 1067.8665166854144, 1072.3137192197, 1075.2741899217997, 1116.413882711583, 17163.141842908055, 17848.732269015974, 17857.553561160814, 17934.0, 18357.265709371743, 1085.2891356161726, 1090.7000574841982, 1095.2552264460244, 1099.7910397024539, 1122.4612186150694, 9722.708318750449, 10153.441560714084, 10215.489225538693, 10296.948515257525, 10339.276250662382, 419122.0, 422818.0, 431930.0, 432859.0, 434049.0, 69632.3036769636, 70339.37285017186, 70866.291337087, 71117.64441177761, 71626.0, 1014.7767491151935, 1058.6241884131023, 1066.6373433032807, 1068.9577661779704, 1075.763332066944, 15971.28129234164, 16253.67492650164, 16274.593135171242, 16284.43004494837, 16510.17449127515, 31068.0, 32591.18522036968, 35311.0, 38224.23551529009, 39527.407088894266, 51157.48842511527, 51385.0, 53391.199132013826, 54576.454235458725, 54834.0, 8037.156098609562, 8128.309093727099, 8235.464694794819, 8481.491110533389, 8505.702300419456, 11790.94104529472, 12246.877531224574, 12315.0, 12355.814662779885, 12424.192427492184, 10051.949740251252, 10293.794124117625, 10383.221258405802, 10833.945830270799, 10884.183686223407, 10006.8498972514, 10044.748881278027, 10317.432541210006, 10605.787884242427, 10666.253362264877, 10749.81752007263, 10799.946000269949, 11079.224454288122, 11357.772844542898, 11409.60066397672, 8182.386321026066, 8210.876836847332, 8225.95887020561, 8483.618237179218, 8699.217070463688, 8287.751367458986, 9928.255380846322, 10170.8474372883, 10536.15710743154, 10572.629957951434], "value": 9006.588905309003, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.631326385244, 1359.170905747494, 1362.8773410393067, 1368.0492058019681, 1375.1542801177272, 7914.802129946755, 8393.0, 8394.62224199911, 8396.62215200316, 8405.747827565176, 7131.108611423572, 7340.192578816326, 8037.517748935068, 8188.877166842502, 8247.42268041236, 2324.2213858357472, 2357.5049239659693, 2358.657994590792, 2361.8110551155864, 2368.5736567417857, 2297.597920363938, 2375.061850569024, 2386.451116243265, 2388.319328991238, 2388.7014123234594, 2087.7390326209224, 2161.8702877827304, 2274.9886250568725, 2343.2150229672993, 2346.4485845826216, 2201.9449513762206, 2206.470447092702, 2222.2444368914485, 2234.139856155392, 2241.753407125223, 2331.918382856592, 2340.590396680574, 2357.834951553392, 2379.7382287948312, 2379.9524009519714, 8353.624086916103, 8409.411341206118, 8410.579471026424, 8421.368397370221, 8423.15768423159, 5060.2409638554145, 5242.0, 5246.895062098737, 5247.10799164143, 5252.553532949704, 3283.622383425889, 3312.5528053712683, 3557.7331700122595, 3559.733020023483, 3562.2697347043795, 1329.7141114660299, 1383.5849245226493, 1384.6469150366593, 1448.478547722819, 1449.2753623188405, 11260.54957801687, 11313.0, 11315.207935444521, 11324.603638872682, 11328.09375249978, 4570.954290457119, 4663.813447462131, 4757.405324334458, 4764.0, 4766.189747742892, 9085.13691199335, 9092.909070909338, 9149.771255718528, 9157.404768690014, 9161.175494205554, 5208.374995000624, 5221.399539052982, 5223.164293712987, 5227.424983251839, 5227.817026404057, 4744.786484608201, 4880.072786170616, 4899.3385892904, 4904.6321525885705, 4905.386826646669, 3431.69114779671, 3495.405781017233, 3506.368853606324, 3537.2218112015316, 3556.2354093869944, 1093.8851420600881, 1095.1129585035987, 1100.4002818463957, 1106.3804269609045, 1108.501174471475, 685.3831551603497, 806.3790881021581, 813.7151996801073, 821.7616891101633, 832.8584140695975, 5316.149416093406, 5497.175423686479, 5543.556515478752, 5567.916481252784, 5640.520555752725, 942.1473566422399, 945.272140451862, 949.3070058857073, 964.5948701545368, 966.6085235479642, 3630.3102410541915, 3928.489296391451, 4033.778142202207, 4090.9795451022555, 4096.897577560525, 9836.606535738634, 10362.948185259176, 10371.94814025925, 10374.533146008447, 10385.480725963671, 10241.900410415996, 10243.38539687621, 10249.30886403735, 10267.486625668687, 10298.07317341428, 7663.961680191564, 7866.213378662088, 7923.405744569181, 7971.0, 7985.361171106298, 5285.471452854684, 5409.188621706776, 5488.0, 5490.011797876421, 5634.85912852182, 8964.910350896407, 9252.583633736363, 9279.0, 9383.671571494964, 9405.482698451651, 9339.20616747584, 9486.667966621135, 9495.572699228689, 9534.475603841855, 9617.182539483942, 7222.566646001257, 7328.487005909536, 7367.373773229336, 7374.852502950018, 7415.555066696015, 7783.182765809508, 7867.213278672087, 7875.606219688878, 7894.21057894206, 7896.131425543071, 7028.613426261605, 7054.435645148478, 7159.785206443815, 7194.064771579661, 7316.634168291458, 6878.896816547655, 7100.573965562083, 7123.643817809192, 7264.963675181591, 7315.158756743042, 6327.90508142369, 6329.145565348619, 6383.904241436475, 6394.200724909387, 6396.42432181106, 4816.373871396696, 4904.558589726942, 4908.779104940358, 4911.926321105258, 4962.950370496394, 5061.83577777106, 5109.9744501277255, 5120.003198752564, 5163.690178589297, 5227.55565776898], "value": 4426.054350765492, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.13142888501966, 395.3259692224757, 398.30098177698136, 401.61043787526097, 405.1936646074313, 5139.3318868547085, 5185.3777546694355, 5193.948060519394, 5223.555997740196, 5233.874716935858, 5065.366829146356, 5070.0, 5118.744062796864, 5143.434222235561, 5144.691318520882, 4080.9183816323657, 4088.7751173685438, 4108.77401742904, 4115.403266526352, 4124.793760311987, 4101.343784994401, 4106.67146628269, 4110.958890411102, 4123.9793801030955, 4146.792660366985, 3972.960270397288, 4117.300058989964, 4128.793560321987, 4132.566080561543, 4145.854895078673, 4091.5908409158847, 4106.8973275668195, 4112.362583799486, 4118.91762164755, 4132.40080188374, 3739.813009349549, 3740.4763333133383, 3750.5185451746406, 3752.6434988676306, 3771.2652179997235, 5012.398512178562, 5015.22264049073, 5075.720835354054, 5090.847274581768, 5104.132297509433, 4650.7209567425725, 4737.407824021997, 4748.857534273978, 4766.666333356667, 4783.617310615143, 5382.838514844561, 5391.541718953894, 5406.864828379322, 5408.945910540883, 5413.2692086568595, 4103.548609652936, 4116.608922152391, 4133.6693064554765, 4133.751974881487, 4138.751674899516, 5345.438728933484, 5490.478404551562, 5503.724813759336, 5583.441655834384, 5601.383847776741, 4155.854545090907, 4208.894777630584, 4259.74441533509, 4295.463067116611, 4300.741955482681, 4358.951292892336, 4374.321980093091, 4375.27807911691, 4376.059147283349, 4384.846530371422, 4509.391232183613, 4569.634429245652, 4767.69010014348, 4770.330384365514, 4792.496787837296, 5452.0, 5460.31746031746, 5472.659198496394, 5509.283793106951, 5544.833654990357, 1462.6782107936237, 1469.7648376259747, 1474.373391308711, 1480.5854360778999, 1486.0, 1007.773251018515, 1060.7242117049623, 1065.9307145035702, 1079.7192729890285, 1098.5441041967654, 5061.342025536656, 5068.062408454448, 5091.7963281469065, 5122.617982638091, 5129.79480820763, 5390.838274851761, 5405.567554595623, 5409.43200963901, 5410.91883621746, 5414.91877621836, 4912.263160525878, 5010.092128140057, 5062.620303477254, 5140.511651393112, 5164.767585458663, 2847.430513897229, 2862.822267283314, 2863.6849946505868, 2869.512182928907, 2870.5790633636484, 5570.387257401682, 5582.91625625616, 5595.328560572676, 5622.240997465372, 5635.549156067505, 5287.259783630295, 5495.642783219078, 5524.977879092381, 5533.474319939599, 5559.49893528745, 5572.3870374258795, 5583.720813959367, 5617.775288988395, 5625.296837895263, 5640.379558248589, 5170.362034884022, 5405.216243644728, 5407.0, 5416.9729151353995, 5447.645903016291, 5353.678779273256, 5358.91002509014, 5362.222477740628, 5370.952664230437, 5377.408485066561, 5599.916001259903, 5652.519535839499, 5665.49010589049, 5667.744951477111, 5750.6773442107715, 4231.301835197158, 4261.446012018418, 4278.914421711611, 4296.033392486665, 4308.24605693996, 5546.89062187556, 5548.55611551083, 5555.916661250165, 5611.410801865745, 5620.875824834968, 5189.792408303777, 5473.397926228032, 5488.725563721718, 5492.5331346835965, 5536.7231638418725, 5517.862053448535, 5524.696141712084, 5525.834224973257, 5536.861578460571, 5545.833624991256, 5263.868403289948, 5548.585110796721, 5553.0, 5554.389017208185, 5555.30558680165, 5434.972825135849, 5454.3454785426, 5463.562914966714, 5464.3442786865835, 5496.587755918402, 5347.27811745425, 5350.384705758887, 5393.164059570773, 5409.107497262908, 5431.158170483582], "value": 4219.56387166839, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.0983000739103, 753.1489416958835, 792.6393490961613, 829.4525613095358, 834.9081601023886, 14712.852871471296, 15834.68330633388, 15844.049357038586, 15932.442364517245, 16290.755638665429, 15211.543653690407, 15325.846741532581, 15338.46315378962, 15376.308066137022, 15429.922850385761, 706.4736771105534, 728.6466063958976, 787.5943888897223, 800.7237503061434, 802.7471346525855, 811.3914564076942, 814.2305521282391, 814.9837003259931, 816.6243527977138, 826.5164878546058, 884.4339622641514, 885.8626912828522, 886.0873300500494, 886.1360173830496, 898.9595468203914, 749.5203070035154, 774.8256642255532, 840.474703310431, 881.0880738435688, 881.7442941546943, 631.516889579472, 634.4607083978627, 641.3394203969918, 649.9675016249168, 651.5308977536166, 14505.0, 15109.47116850905, 15137.545873623809, 15155.696886062327, 15220.771688424682, 13464.663383415493, 13780.460009315131, 14171.149731016172, 14309.427622895071, 14347.42610295587, 13024.29787951182, 13169.0, 13229.470821167142, 13241.470341186341, 13601.387937542835, 4375.671824613167, 4392.428984232061, 4429.446319210098, 4457.1977044132045, 4500.729956202638, 75525.24474755237, 75572.62213688952, 75751.62124189344, 77736.61131694305, 80420.59789701014, 11204.943975280072, 11237.719057023476, 11243.494042768096, 11279.210455268052, 11492.54029838797, 21247.575048498944, 21337.89331053366, 21406.892965535073, 21543.2459863904, 21628.243011494524, 7789.493682910593, 7960.960195199103, 8088.919110808816, 8213.096559378464, 8266.421350505527, 4468.463784345834, 4515.0, 4527.275635898306, 4641.721496710208, 4701.388819453449, 3563.9287214256087, 3633.9818300908855, 3645.434957581526, 3667.8716244931297, 3699.186179040607, 588.1677426441623, 592.1059200607115, 596.2249076200922, 599.2060519811267, 608.7808388979963, 637.719403462475, 649.5226008883467, 654.4044919123575, 658.2512590795375, 671.6238906212536, 8340.791480213044, 8530.573471326408, 8653.69712060075, 8657.134286571418, 8674.78313042179, 614.4132353602299, 625.893598088326, 638.141699414287, 640.8365866703961, 660.5706290910946, 3969.543502497192, 4173.800517618989, 4195.706300558932, 4204.2432362175105, 4238.406623072772, 81320.59339703263, 82417.0, 83088.0, 83341.58329208315, 85161.57419212864, 70637.64681176664, 71603.0, 71869.6406517964, 72481.27518724745, 73783.0, 64321.67839160774, 66172.05193454714, 66560.33439665638, 67760.32239677639, 69497.0, 7742.341900938258, 7863.921360786548, 7964.681412743425, 8071.152528984607, 8099.271065603888, 42826.78586607047, 43828.56171438372, 44620.77689611531, 44900.77549612231, 45480.31779523376, 50003.749981249865, 52443.73778131085, 52587.73706131445, 52720.472795271555, 53103.0, 11099.944500277446, 11206.831897521706, 11207.887921121011, 11266.887331126913, 11507.884921150682, 12161.209521381083, 12273.570425035081, 12314.445849936954, 12569.371531423576, 12919.870801292243, 11070.501827417947, 11143.108551315712, 11352.772944541228, 11572.710682233012, 11743.412829358327, 11557.133215009077, 11579.478923448296, 11705.531778729097, 12019.0, 12062.638120856389, 11106.61126860556, 11262.831057534308, 11468.82796758066, 11632.360220187971, 11935.880641193477, 9058.41120327203, 9103.089691030844, 9214.401063930824, 9386.342955993015, 9414.858777118487, 8441.15588441151, 8529.189726975927, 8904.19862212404, 9101.36290459688, 9189.816203675755], "value": 6945.88789592487, "unit": "score"}], "name": "dromaeo_css", "value": 7033.433947154405, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 112.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 119.0, 168.0, 132.0, 124.0, 120.0, 117.0, 141.0, 117.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 161.0, 161.0, 162.0, 160.0, 162.0, 162.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 112.0, 75.0, 76.0, 75.0, 76.0, 93.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 107.0, 83.0, 84.0, 85.0, 93.0, 83.0, 86.0, 102.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 148.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 49.0, 51.0, 52.0, 51.0, 51.0, 51.0, 52.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 69.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 82.0, 81.0, 82.0, 87.0, 80.0, 82.0, 81.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 127.0, 137.0, 124.0, 131.0, 122.0, 125.0, 138.0, 163.0, 136.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 174.0, 165.0, 164.0, 165.0, 170.0, 168.0, 167.0, 172.0, 163.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 62.0, 62.0, 62.0, 65.0, 62.0, 66.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1494.1999999999998, "alertThreshold": 2.0}]} 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 INFO - # 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.797396Z] 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: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401551360; Read time: 4; Write time: 226488 09:31:30 INFO - TinderboxPrint: CPU usage
13.1% 09:31:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 09:31:30 INFO - TinderboxPrint: I/O write bytes / time
401,551,360 / 226,488 09:31:30 INFO - TinderboxPrint: CPU idle
8,284.9 (86.9%) 09:31:30 INFO - TinderboxPrint: CPU user
1,198.0 (12.6%) 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: 105594880; Read time: 0; Write time: 57568 09:31:30 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295956480; Read time: 4; Write time: 168920 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/dromaeojs_raw.log ... 09:31:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:31:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:33 INFO - (blobuploader) - INFO - Done attempting. 09:31:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:31:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:31:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:33 INFO - (blobuploader) - INFO - Done attempting. 09:31:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:31:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: 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 - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:31:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:31:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:38 INFO - (blobuploader) - INFO - Done attempting. 09:31:38 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:38 INFO - Return code: 0 09:31:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6cb51659b8b6dc41130e4d9a0d176597a02e01ce4f2f73ac982254669b5d45d6e487e15b9398aabfa020b91be7aa8f1dcdc19a179d62b58406309aea42517282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25ea8af2aa2948430ec53c16d0dcf53818a4549c0955d5ef019ed1aed95c419cfb2edfaf3ce9f0d5629d4beb49e4b1f84e5a9785ced5ee3ced4ff1cb0ece343c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5921f235db9a304b30d48036b5e7cb62c4cc07f3d6954e73875c869bacde3f45574840ae9970445c67f6e08cb5ab4a4b17acc14b1157b1b9d7b72df759f8e745", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e82a3171c9dfe2d5018968a3b7821f8709999553f1c6c493c918dd7aeb5bf805f0a3f9a29ce904c651c658853ab93aecdbc08d61aae1e1c4e5edc0e2ace99fd"} 09:31:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:38 INFO - Contents: 09:31:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6cb51659b8b6dc41130e4d9a0d176597a02e01ce4f2f73ac982254669b5d45d6e487e15b9398aabfa020b91be7aa8f1dcdc19a179d62b58406309aea42517282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25ea8af2aa2948430ec53c16d0dcf53818a4549c0955d5ef019ed1aed95c419cfb2edfaf3ce9f0d5629d4beb49e4b1f84e5a9785ced5ee3ced4ff1cb0ece343c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5921f235db9a304b30d48036b5e7cb62c4cc07f3d6954e73875c869bacde3f45574840ae9970445c67f6e08cb5ab4a4b17acc14b1157b1b9d7b72df759f8e745", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e82a3171c9dfe2d5018968a3b7821f8709999553f1c6c493c918dd7aeb5bf805f0a3f9a29ce904c651c658853ab93aecdbc08d61aae1e1c4e5edc0e2ace99fd"} 09:31:38 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:38 INFO - Copying logs to upload dir... 09:31:38 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.500887 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-26 09:31:38.244139) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:38.251974) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6cb51659b8b6dc41130e4d9a0d176597a02e01ce4f2f73ac982254669b5d45d6e487e15b9398aabfa020b91be7aa8f1dcdc19a179d62b58406309aea42517282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25ea8af2aa2948430ec53c16d0dcf53818a4549c0955d5ef019ed1aed95c419cfb2edfaf3ce9f0d5629d4beb49e4b1f84e5a9785ced5ee3ced4ff1cb0ece343c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5921f235db9a304b30d48036b5e7cb62c4cc07f3d6954e73875c869bacde3f45574840ae9970445c67f6e08cb5ab4a4b17acc14b1157b1b9d7b72df759f8e745", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e82a3171c9dfe2d5018968a3b7821f8709999553f1c6c493c918dd7aeb5bf805f0a3f9a29ce904c651c658853ab93aecdbc08d61aae1e1c4e5edc0e2ace99fd"} 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.011715 build_url: 'https://queue.taskcluster.net/v1/task/Bsbj7XzQR0iGzG9uvAUWnA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6cb51659b8b6dc41130e4d9a0d176597a02e01ce4f2f73ac982254669b5d45d6e487e15b9398aabfa020b91be7aa8f1dcdc19a179d62b58406309aea42517282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25ea8af2aa2948430ec53c16d0dcf53818a4549c0955d5ef019ed1aed95c419cfb2edfaf3ce9f0d5629d4beb49e4b1f84e5a9785ced5ee3ced4ff1cb0ece343c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5921f235db9a304b30d48036b5e7cb62c4cc07f3d6954e73875c869bacde3f45574840ae9970445c67f6e08cb5ab4a4b17acc14b1157b1b9d7b72df759f8e745", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e82a3171c9dfe2d5018968a3b7821f8709999553f1c6c493c918dd7aeb5bf805f0a3f9a29ce904c651c658853ab93aecdbc08d61aae1e1c4e5edc0e2ace99fd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:38.281372) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:38.281866) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1482771881.880289-2053743488 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 09:31:38.364826) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 09:31:38.365198) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 09:31:38.365549) ========= ========= Total master_lag: 0.23 =========