builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-041 starttime: 1482903061.27 results: success (0) buildid: 20161227203436 builduid: 749215d6f3b04827be3e93b1ea3ae4b5 revision: d7b6af32811bddcec10a47d24bd455a1ec1836fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.270099) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.270540) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.270822) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.320760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.321052) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-27 21:31:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.5M=0.001s 2016-12-27 21:31:02 (22.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093258 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.433728) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.434058) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014528 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.493812) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:01.494148) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d7b6af32811bddcec10a47d24bd455a1ec1836fc --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 d7b6af32811bddcec10a47d24bd455a1ec1836fc --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=8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-27 21:31:02,577 truncating revision to first 12 chars 2016-12-27 21:31:02,577 Setting DEBUG logging. 2016-12-27 21:31:02,577 attempt 1/10 2016-12-27 21:31:02,577 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d7b6af32811b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 21:31:02,902 unpacking tar archive at: mozilla-central-d7b6af32811b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.692660 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:02.199774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:02.200092) ========= script_repo_revision: d7b6af32811bddcec10a47d24bd455a1ec1836fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:02.200470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:02.200734) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 21:31:02.208726) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-27 21:31:02.209010) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:31:03 INFO - MultiFileLogger online at 20161227 21:31:03 in /builds/slave/test 21:31:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:31:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:31:03 INFO - {'append_to_log': False, 21:31:03 INFO - 'base_work_dir': '/builds/slave/test', 21:31:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:31:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:31:03 INFO - 'branch': 'Firefox-Non-PGO', 21:31:03 INFO - 'buildbot_json_path': 'buildprops.json', 21:31:03 INFO - 'config_files': ('talos/linux_config.py',), 21:31:03 INFO - 'default_actions': ('clobber', 21:31:03 INFO - 'read-buildbot-config', 21:31:03 INFO - 'download-and-extract', 21:31:03 INFO - 'populate-webroot', 21:31:03 INFO - 'create-virtualenv', 21:31:03 INFO - 'install', 21:31:03 INFO - 'run-tests'), 21:31:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:31:03 INFO - 'download_minidump_stackwalk': True, 21:31:03 INFO - 'download_symbols': 'ondemand', 21:31:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:31:03 INFO - 'tooltool.py': '/tools/tooltool.py', 21:31:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:31:03 INFO - '/tools/misc-python/virtualenv.py')}, 21:31:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:31:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:31:03 INFO - 'installer_path': 'installer.exe', 21:31:03 INFO - 'log_level': 'info', 21:31:03 INFO - 'log_name': 'talos', 21:31:03 INFO - 'log_to_console': True, 21:31:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:31:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:31:03 INFO - 'opt_config_files': (), 21:31:03 INFO - 'pip_index': False, 21:31:03 INFO - 'sps_profile': False, 21:31:03 INFO - 'sps_profile_interval': 0, 21:31:03 INFO - 'suite': 'dromaeojs', 21:31:03 INFO - 'system_bits': '32', 21:31:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:31:03 INFO - 'title': 'talos-linux64-ix-041', 21:31:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:31:03 INFO - 'use_talos_json': True, 21:31:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:31:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:31:03 INFO - 'work_dir': 'build'} 21:31:03 INFO - [mozharness: 2016-12-28 05:31:03.387727Z] Running clobber step. 21:31:03 INFO - Running pre-action listener: _resource_record_pre_action 21:31:03 INFO - Running main action method: clobber 21:31:03 INFO - rmtree: /builds/slave/test/build 21:31:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:31:04 INFO - Running post-action listener: _resource_record_post_action 21:31:04 INFO - [mozharness: 2016-12-28 05:31:04.365918Z] Finished clobber step (success) 21:31:04 INFO - [mozharness: 2016-12-28 05:31:04.366018Z] Running read-buildbot-config step. 21:31:04 INFO - Running pre-action listener: _resource_record_pre_action 21:31:04 INFO - Running main action method: read_buildbot_config 21:31:04 INFO - Using buildbot properties: 21:31:04 INFO - { 21:31:04 INFO - "project": "", 21:31:04 INFO - "product": "firefox", 21:31:04 INFO - "script_repo_revision": "production", 21:31:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:31:04 INFO - "repository": "", 21:31:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 21:31:04 INFO - "buildid": "20161227203436", 21:31:04 INFO - "pgo_build": "False", 21:31:04 INFO - "basedir": "/builds/slave/test", 21:31:04 INFO - "buildnumber": 754, 21:31:04 INFO - "slavename": "talos-linux64-ix-041", 21:31:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:31:04 INFO - "platform": "ubuntu64_hw", 21:31:04 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 21:31:04 INFO - "branch": "mozilla-central", 21:31:04 INFO - "repo_path": "mozilla-central", 21:31:04 INFO - "revision": "d7b6af32811bddcec10a47d24bd455a1ec1836fc", 21:31:04 INFO - "stage_platform": "linux64", 21:31:04 INFO - "builduid": "749215d6f3b04827be3e93b1ea3ae4b5", 21:31:04 INFO - "slavebuilddir": "test" 21:31:04 INFO - } 21:31:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:31:04 INFO - Running post-action listener: _resource_record_post_action 21:31:04 INFO - [mozharness: 2016-12-28 05:31:04.368482Z] Finished read-buildbot-config step (success) 21:31:04 INFO - [mozharness: 2016-12-28 05:31:04.368572Z] Running download-and-extract step. 21:31:04 INFO - Running pre-action listener: _resource_record_pre_action 21:31:04 INFO - Running main action method: download_and_extract 21:31:04 INFO - mkdir: /builds/slave/test/build/tests 21:31:04 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')]} 21:31:04 INFO - trying https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:31:04 INFO - Downloading https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/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 21:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/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 21:31:04 INFO - Downloaded 1361 bytes. 21:31:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:31:04 INFO - Using the following test package requirements: 21:31:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:31:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:31:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:31:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'jsshell-linux-x86_64.zip'], 21:31:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:31:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:31:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:31:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:31:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:31:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:31:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:31:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:31:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:31:04 INFO - Fetch https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:31:05 INFO - Expected file size: 20241338 21:31:05 INFO - Obtained file size: 20241338 21:31:09 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 21:31:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:31:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:31:09 INFO - Fetch https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:31:10 INFO - Expected file size: 20241338 21:31:10 INFO - Obtained file size: 20241338 21:31:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:31:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:31:15 INFO - Fetch https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:31:15 INFO - Expected file size: 14409776 21:31:15 INFO - Obtained file size: 14409776 21:31: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')]} 21:31:16 INFO - trying https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:31:16 INFO - Downloading https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/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 21:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/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 21:31:17 INFO - Downloaded 55901210 bytes. 21:31:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:31:17 INFO - mkdir: /builds/slave/test/properties 21:31:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:31:17 INFO - Writing to file /builds/slave/test/properties/build_url 21:31:17 INFO - Contents: 21:31:17 INFO - build_url:https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:31:17 INFO - Running post-action listener: _resource_record_post_action 21:31:17 INFO - Running post-action listener: set_extra_try_arguments 21:31:17 INFO - [mozharness: 2016-12-28 05:31:17.779673Z] Finished download-and-extract step (success) 21:31:17 INFO - [mozharness: 2016-12-28 05:31:17.779901Z] Running populate-webroot step. 21:31:17 INFO - Running pre-action listener: _resource_record_pre_action 21:31:17 INFO - Running main action method: populate_webroot 21:31:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:31:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:31:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:31:17 INFO - '--tppagecycles', 21:31:17 INFO - '7'], 21:31:17 INFO - 'tests': ['tsvgm']}}, 21:31:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:31:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:31:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:31:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:31:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:31:17 INFO - 'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:31:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:31:17 INFO - 'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['damp', 'tps']}, 21:31:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:31:17 INFO - 'tests': ['damp', 'tps']}, 21:31:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['dromaeo_dom']}, 21:31:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:31:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['basic_compositor_video']}, 21:31:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:31:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['a11yr', 21:31:17 INFO - 'ts_paint', 21:31:17 INFO - 'tpaint', 21:31:17 INFO - 'sessionrestore', 21:31:17 INFO - 'sessionrestore_no_auto_restore', 21:31:17 INFO - 'tabpaint']}, 21:31:17 INFO - 'other-e10s': {'tests': ['a11yr', 21:31:17 INFO - 'ts_paint', 21:31:17 INFO - 'tpaint', 21:31:17 INFO - 'sessionrestore', 21:31:17 INFO - 'sessionrestore_no_auto_restore', 21:31:17 INFO - 'tabpaint']}, 21:31:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:31:17 INFO - 'ts_paint', 21:31:17 INFO - 'tpaint', 21:31:17 INFO - 'sessionrestore', 21:31:17 INFO - 'sessionrestore_no_auto_restore', 21:31:17 INFO - 'tabpaint']}, 21:31:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:31:17 INFO - 'ts_paint', 21:31:17 INFO - 'tpaint', 21:31:17 INFO - 'sessionrestore', 21:31:17 INFO - 'sessionrestore_no_auto_restore', 21:31:17 INFO - 'tabpaint']}, 21:31:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['a11yr', 21:31:17 INFO - 'ts_paint', 21:31:17 INFO - 'tpaint', 21:31:17 INFO - 'sessionrestore', 21:31:17 INFO - 'sessionrestore_no_auto_restore', 21:31:17 INFO - 'tabpaint']}, 21:31:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['a11yr', 21:31:17 INFO - 'ts_paint', 21:31:17 INFO - 'tpaint', 21:31:17 INFO - 'sessionrestore', 21:31:17 INFO - 'sessionrestore_no_auto_restore', 21:31:17 INFO - 'tabpaint']}, 21:31:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['tsvgx', 21:31:17 INFO - 'tsvgr_opacity', 21:31:17 INFO - 'tart', 21:31:17 INFO - 'tscrollx', 21:31:17 INFO - 'cart', 21:31:17 INFO - 'tsvg_static']}, 21:31:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:31:17 INFO - 'tsvgr_opacity', 21:31:17 INFO - 'tart', 21:31:17 INFO - 'tscrollx', 21:31:17 INFO - 'cart', 21:31:17 INFO - 'tsvg_static']}, 21:31:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:31:17 INFO - 'talos_options': ['--disable-e10s'], 21:31:17 INFO - 'tests': ['tp5o']}, 21:31:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:31:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:31:17 INFO - 'talos_options': ['--disable-e10s', 21:31:17 INFO - '--xperf_path', 21:31:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:17 INFO - 'tests': ['tp5n']}, 21:31:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:31:17 INFO - 'talos_options': ['--xperf_path', 21:31:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:17 INFO - 'tests': ['tp5n']}}, 21:31:17 INFO - 'talos.zip': {'path': '', 21:31:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:31:17 INFO - Running post-action listener: _resource_record_post_action 21:31:17 INFO - [mozharness: 2016-12-28 05:31:17.795002Z] Finished populate-webroot step (success) 21:31:17 INFO - [mozharness: 2016-12-28 05:31:17.795089Z] Running create-virtualenv step. 21:31:17 INFO - Running pre-action listener: _resource_record_pre_action 21:31:17 INFO - Running main action method: create_virtualenv 21:31:17 INFO - Creating virtualenv /builds/slave/test/build/venv 21:31:17 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 21:31:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:31:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:31:17 INFO - Using real prefix '/usr' 21:31:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:31:18 INFO - Installing distribute.............................................................................................................................................................................................done. 21:31:20 INFO - Installing pip.................done. 21:31:20 INFO - Return code: 0 21:31:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:31:20 INFO - Reading from file tmpfile_stdout 21:31:20 INFO - Output received: 21:31:20 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) 21:31:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:31:20 INFO - Using env: {'DISPLAY': ':0', 21:31:20 INFO - 'HOME': '/home/cltbld', 21:31:20 INFO - 'LANG': 'en_US.UTF-8', 21:31:20 INFO - 'LANGUAGE': 'en_US:en', 21:31:20 INFO - 'LOGNAME': 'cltbld', 21:31:20 INFO - 'MAIL': '/var/mail/cltbld', 21:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:20 INFO - 'MOZ_NO_REMOTE': '1', 21:31:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:20 INFO - 'NO_EM_RESTART': '1', 21:31:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:20 INFO - 'PWD': '/builds/slave/test', 21:31:20 INFO - 'SHELL': '/bin/bash', 21:31:20 INFO - 'SHLVL': '1', 21:31:20 INFO - 'TERM': 'linux', 21:31:20 INFO - 'TMOUT': '86400', 21:31:20 INFO - 'USER': 'cltbld', 21:31:20 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:20 INFO - '_': '/tools/buildbot/bin/python'} 21:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:20 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 21:31:20 INFO - Cleaning up... 21:31:20 INFO - Return code: 0 21:31:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:31:20 INFO - Using env: {'DISPLAY': ':0', 21:31:20 INFO - 'HOME': '/home/cltbld', 21:31:20 INFO - 'LANG': 'en_US.UTF-8', 21:31:20 INFO - 'LANGUAGE': 'en_US:en', 21:31:20 INFO - 'LOGNAME': 'cltbld', 21:31:20 INFO - 'MAIL': '/var/mail/cltbld', 21:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:20 INFO - 'MOZ_NO_REMOTE': '1', 21:31:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:20 INFO - 'NO_EM_RESTART': '1', 21:31:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:20 INFO - 'PWD': '/builds/slave/test', 21:31:20 INFO - 'SHELL': '/bin/bash', 21:31:20 INFO - 'SHLVL': '1', 21:31:20 INFO - 'TERM': 'linux', 21:31:20 INFO - 'TMOUT': '86400', 21:31:20 INFO - 'USER': 'cltbld', 21:31:20 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:20 INFO - '_': '/tools/buildbot/bin/python'} 21:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:21 INFO - Downloading/unpacking psutil>=3.1.1 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:31:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:23 INFO - Installing collected packages: psutil 21:31:23 INFO - Running setup.py install for psutil 21:31:23 INFO - building 'psutil._psutil_linux' extension 21:31:23 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 21:31:23 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 21:31:23 INFO - building 'psutil._psutil_posix' extension 21:31:23 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 21:31:23 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 21:31:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:23 INFO - Successfully installed psutil 21:31:23 INFO - Cleaning up... 21:31:23 INFO - Return code: 0 21:31:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:31:23 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')]} 21:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:23 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')]} 21:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:23 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:23 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 21:31:23 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 21:31:23 INFO - Using env: {'DISPLAY': ':0', 21:31:23 INFO - 'HOME': '/home/cltbld', 21:31:23 INFO - 'LANG': 'en_US.UTF-8', 21:31:23 INFO - 'LANGUAGE': 'en_US:en', 21:31:23 INFO - 'LOGNAME': 'cltbld', 21:31:23 INFO - 'MAIL': '/var/mail/cltbld', 21:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:23 INFO - 'MOZ_NO_REMOTE': '1', 21:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:23 INFO - 'NO_EM_RESTART': '1', 21:31:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:23 INFO - 'PWD': '/builds/slave/test', 21:31:23 INFO - 'SHELL': '/bin/bash', 21:31:23 INFO - 'SHLVL': '1', 21:31:23 INFO - 'TERM': 'linux', 21:31:23 INFO - 'TMOUT': '86400', 21:31:23 INFO - 'USER': 'cltbld', 21:31:23 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:23 INFO - '_': '/tools/buildbot/bin/python'} 21:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:31:25 INFO - Installing collected packages: mozsystemmonitor 21:31:25 INFO - Running setup.py install for mozsystemmonitor 21:31:26 INFO - Successfully installed mozsystemmonitor 21:31:26 INFO - Cleaning up... 21:31:26 INFO - Return code: 0 21:31:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:31:26 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')]} 21:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:26 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')]} 21:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:26 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:26 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 21:31:26 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 21:31:26 INFO - Using env: {'DISPLAY': ':0', 21:31:26 INFO - 'HOME': '/home/cltbld', 21:31:26 INFO - 'LANG': 'en_US.UTF-8', 21:31:26 INFO - 'LANGUAGE': 'en_US:en', 21:31:26 INFO - 'LOGNAME': 'cltbld', 21:31:26 INFO - 'MAIL': '/var/mail/cltbld', 21:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:26 INFO - 'MOZ_NO_REMOTE': '1', 21:31:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:26 INFO - 'NO_EM_RESTART': '1', 21:31:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:26 INFO - 'PWD': '/builds/slave/test', 21:31:26 INFO - 'SHELL': '/bin/bash', 21:31:26 INFO - 'SHLVL': '1', 21:31:26 INFO - 'TERM': 'linux', 21:31:26 INFO - 'TMOUT': '86400', 21:31:26 INFO - 'USER': 'cltbld', 21:31:26 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:26 INFO - '_': '/tools/buildbot/bin/python'} 21:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:26 INFO - Downloading/unpacking jsonschema==2.5.1 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:31:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:28 INFO - Downloading functools32-3.2.3-2.tar.gz 21:31:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:31:28 INFO - warning: no files found matching '*.txt' 21:31:28 INFO - no previously-included directories found matching 'build' 21:31:28 INFO - no previously-included directories found matching 'dist' 21:31:28 INFO - no previously-included directories found matching '.git*' 21:31:28 INFO - Installing collected packages: jsonschema, functools32 21:31:28 INFO - Running setup.py install for functools32 21:31:28 INFO - warning: no files found matching '*.txt' 21:31:28 INFO - no previously-included directories found matching 'build' 21:31:28 INFO - no previously-included directories found matching 'dist' 21:31:28 INFO - no previously-included directories found matching '.git*' 21:31:28 INFO - Successfully installed jsonschema functools32 21:31:28 INFO - Cleaning up... 21:31:28 INFO - Return code: 0 21:31:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:31: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')]} 21:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31: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')]} 21:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:28 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:28 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 21:31:28 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 21:31:28 INFO - Using env: {'DISPLAY': ':0', 21:31:28 INFO - 'HOME': '/home/cltbld', 21:31:28 INFO - 'LANG': 'en_US.UTF-8', 21:31:28 INFO - 'LANGUAGE': 'en_US:en', 21:31:28 INFO - 'LOGNAME': 'cltbld', 21:31:28 INFO - 'MAIL': '/var/mail/cltbld', 21:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:28 INFO - 'MOZ_NO_REMOTE': '1', 21:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:28 INFO - 'NO_EM_RESTART': '1', 21:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:28 INFO - 'PWD': '/builds/slave/test', 21:31:28 INFO - 'SHELL': '/bin/bash', 21:31:28 INFO - 'SHLVL': '1', 21:31:28 INFO - 'TERM': 'linux', 21:31:28 INFO - 'TMOUT': '86400', 21:31:28 INFO - 'USER': 'cltbld', 21:31:28 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:28 INFO - '_': '/tools/buildbot/bin/python'} 21:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:31:29 INFO - Cleaning up... 21:31:29 INFO - Return code: 0 21:31:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:29 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:29 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 21:31:29 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 21:31:29 INFO - Using env: {'DISPLAY': ':0', 21:31:29 INFO - 'HOME': '/home/cltbld', 21:31:29 INFO - 'LANG': 'en_US.UTF-8', 21:31:29 INFO - 'LANGUAGE': 'en_US:en', 21:31:29 INFO - 'LOGNAME': 'cltbld', 21:31:29 INFO - 'MAIL': '/var/mail/cltbld', 21:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:29 INFO - 'MOZ_NO_REMOTE': '1', 21:31:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:29 INFO - 'NO_EM_RESTART': '1', 21:31:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:29 INFO - 'PWD': '/builds/slave/test', 21:31:29 INFO - 'SHELL': '/bin/bash', 21:31:29 INFO - 'SHLVL': '1', 21:31:29 INFO - 'TERM': 'linux', 21:31:29 INFO - 'TMOUT': '86400', 21:31:29 INFO - 'USER': 'cltbld', 21:31:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:29 INFO - '_': '/tools/buildbot/bin/python'} 21:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:29 INFO - Downloading/unpacking blobuploader==1.2.4 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:31 INFO - Downloading blobuploader-1.2.4.tar.gz 21:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:31:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:31:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:31 INFO - Downloading docopt-0.6.1.tar.gz 21:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:31:32 INFO - Installing collected packages: blobuploader, requests, docopt 21:31:32 INFO - Running setup.py install for blobuploader 21:31:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:31:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:31:32 INFO - Running setup.py install for requests 21:31:32 INFO - Running setup.py install for docopt 21:31:32 INFO - Successfully installed blobuploader requests docopt 21:31:32 INFO - Cleaning up... 21:31:32 INFO - Return code: 0 21:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:32 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:32 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 21:31:32 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 21:31:32 INFO - Using env: {'DISPLAY': ':0', 21:31:32 INFO - 'HOME': '/home/cltbld', 21:31:32 INFO - 'LANG': 'en_US.UTF-8', 21:31:32 INFO - 'LANGUAGE': 'en_US:en', 21:31:32 INFO - 'LOGNAME': 'cltbld', 21:31:32 INFO - 'MAIL': '/var/mail/cltbld', 21:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:32 INFO - 'MOZ_NO_REMOTE': '1', 21:31:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:32 INFO - 'NO_EM_RESTART': '1', 21:31:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:32 INFO - 'PWD': '/builds/slave/test', 21:31:32 INFO - 'SHELL': '/bin/bash', 21:31:32 INFO - 'SHLVL': '1', 21:31:32 INFO - 'TERM': 'linux', 21:31:32 INFO - 'TMOUT': '86400', 21:31:32 INFO - 'USER': 'cltbld', 21:31:32 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:32 INFO - '_': '/tools/buildbot/bin/python'} 21:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:32 INFO - Running setup.py (path:/tmp/pip-d6gWS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:32 INFO - Running setup.py (path:/tmp/pip-SSq9X_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:33 INFO - Running setup.py (path:/tmp/pip-LlLMnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:33 INFO - Running setup.py (path:/tmp/pip-vIkAcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:33 INFO - Running setup.py (path:/tmp/pip-yNJPQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:33 INFO - Running setup.py (path:/tmp/pip-OKkzzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:33 INFO - Running setup.py (path:/tmp/pip-RAFACT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:33 INFO - Running setup.py (path:/tmp/pip-DSK5uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:33 INFO - Running setup.py (path:/tmp/pip-yYVPHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:33 INFO - Running setup.py (path:/tmp/pip-6yw8yG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:33 INFO - Running setup.py (path:/tmp/pip-9LeFnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:31:33 INFO - Running setup.py (path:/tmp/pip-wOun0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:31:33 INFO - Running setup.py (path:/tmp/pip-ihphX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:31:33 INFO - Running setup.py (path:/tmp/pip-wN3eEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:33 INFO - Running setup.py (path:/tmp/pip-wHohwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:31:33 INFO - Running setup.py (path:/tmp/pip-gOcEvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:31:34 INFO - Running setup.py (path:/tmp/pip-OGUTfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:31:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:31:34 INFO - Running setup.py install for manifestparser 21:31:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Running setup.py install for mozcrash 21:31:34 INFO - Running setup.py install for mozdebug 21:31:34 INFO - Running setup.py install for mozdevice 21:31:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Running setup.py install for mozfile 21:31:34 INFO - Running setup.py install for mozhttpd 21:31:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Running setup.py install for mozinfo 21:31:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Running setup.py install for mozInstall 21:31:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Running setup.py install for mozleak 21:31:35 INFO - Running setup.py install for mozlog 21:31:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:31:35 INFO - Running setup.py install for moznetwork 21:31:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:31:35 INFO - Running setup.py install for mozprocess 21:31:35 INFO - Running setup.py install for mozprofile 21:31:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:31:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:31:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:31:35 INFO - Running setup.py install for mozrunner 21:31:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:31:35 INFO - Running setup.py install for mozscreenshot 21:31:35 INFO - Running setup.py install for moztest 21:31:35 INFO - Running setup.py install for mozversion 21:31:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:31:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:31:35 INFO - Cleaning up... 21:31:35 INFO - Return code: 0 21:31:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31: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')]} 21:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31: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')]} 21:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:35 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:35 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 21:31:35 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 21:31:35 INFO - Using env: {'DISPLAY': ':0', 21:31:35 INFO - 'HOME': '/home/cltbld', 21:31:35 INFO - 'LANG': 'en_US.UTF-8', 21:31:35 INFO - 'LANGUAGE': 'en_US:en', 21:31:35 INFO - 'LOGNAME': 'cltbld', 21:31:35 INFO - 'MAIL': '/var/mail/cltbld', 21:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:35 INFO - 'MOZ_NO_REMOTE': '1', 21:31:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:35 INFO - 'NO_EM_RESTART': '1', 21:31:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:35 INFO - 'PWD': '/builds/slave/test', 21:31:35 INFO - 'SHELL': '/bin/bash', 21:31:35 INFO - 'SHLVL': '1', 21:31:35 INFO - 'TERM': 'linux', 21:31:35 INFO - 'TMOUT': '86400', 21:31:35 INFO - 'USER': 'cltbld', 21:31:35 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:35 INFO - '_': '/tools/buildbot/bin/python'} 21:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:36 INFO - Running setup.py (path:/tmp/pip-tJ1wS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:36 INFO - Running setup.py (path:/tmp/pip-kTFrUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:36 INFO - Running setup.py (path:/tmp/pip-msi62S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:36 INFO - Running setup.py (path:/tmp/pip-sx_UOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:36 INFO - Running setup.py (path:/tmp/pip-7HW1s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:36 INFO - Running setup.py (path:/tmp/pip-32u6Vu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:36 INFO - Running setup.py (path:/tmp/pip-WBgamN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:36 INFO - Running setup.py (path:/tmp/pip-k2zyVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:36 INFO - Running setup.py (path:/tmp/pip-AZ0NtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:36 INFO - Running setup.py (path:/tmp/pip-Rjs5xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:36 INFO - Running setup.py (path:/tmp/pip-qmph78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:31:36 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)) 21:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:31:36 INFO - Running setup.py (path:/tmp/pip-7R7qJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:31:37 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)) 21:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:31:37 INFO - Running setup.py (path:/tmp/pip-sYVR3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:31:37 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)) 21:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:31:37 INFO - Running setup.py (path:/tmp/pip-YyjA0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:31:37 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)) 21:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:37 INFO - Running setup.py (path:/tmp/pip-tutCgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:37 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)) 21:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:31:37 INFO - Running setup.py (path:/tmp/pip-dL5VW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:31:37 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)) 21:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:31:37 INFO - Running setup.py (path:/tmp/pip-5Erp4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:31:37 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)) 21:31:37 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)) 21:31:37 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)) 21:31:37 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)) 21:31:37 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)) 21:31:37 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)) 21:31:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:39 INFO - Downloading blessings-1.6.tar.gz 21:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:31:39 INFO - Installing collected packages: blessings 21:31:39 INFO - Running setup.py install for blessings 21:31:39 INFO - Successfully installed blessings 21:31:39 INFO - Cleaning up... 21:31:39 INFO - Return code: 0 21:31:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:31:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:31:39 INFO - Reading from file tmpfile_stdout 21:31:39 INFO - Current package versions: 21:31:39 INFO - argparse == 1.2.1 21:31:39 INFO - blessings == 1.6 21:31:39 INFO - blobuploader == 1.2.4 21:31:39 INFO - docopt == 0.6.1 21:31:39 INFO - functools32 == 3.2.3-2 21:31:39 INFO - jsonschema == 2.5.1 21:31:39 INFO - manifestparser == 1.1 21:31:39 INFO - mozInstall == 1.12 21:31:39 INFO - mozcrash == 1.0 21:31:39 INFO - mozdebug == 0.1 21:31:39 INFO - mozdevice == 0.49 21:31:39 INFO - mozfile == 1.2 21:31:39 INFO - mozhttpd == 0.7 21:31:39 INFO - mozinfo == 0.9 21:31:39 INFO - mozleak == 0.1 21:31:39 INFO - mozlog == 3.4 21:31:39 INFO - moznetwork == 0.27 21:31:39 INFO - mozprocess == 0.23 21:31:39 INFO - mozprofile == 0.28 21:31:39 INFO - mozrunner == 6.13 21:31:39 INFO - mozscreenshot == 0.1 21:31:39 INFO - mozsystemmonitor == 0.3 21:31:39 INFO - moztest == 0.7 21:31:39 INFO - mozversion == 1.4 21:31:39 INFO - psutil == 3.1.1 21:31:39 INFO - requests == 1.2.3 21:31:39 INFO - wsgiref == 0.1.2 21:31:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:39 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:39 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 21:31:39 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 21:31:39 INFO - Using env: {'DISPLAY': ':0', 21:31:39 INFO - 'HOME': '/home/cltbld', 21:31:39 INFO - 'LANG': 'en_US.UTF-8', 21:31:39 INFO - 'LANGUAGE': 'en_US:en', 21:31:39 INFO - 'LOGNAME': 'cltbld', 21:31:39 INFO - 'MAIL': '/var/mail/cltbld', 21:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:39 INFO - 'MOZ_NO_REMOTE': '1', 21:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:39 INFO - 'NO_EM_RESTART': '1', 21:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:39 INFO - 'PWD': '/builds/slave/test', 21:31:39 INFO - 'SHELL': '/bin/bash', 21:31:39 INFO - 'SHLVL': '1', 21:31:39 INFO - 'TERM': 'linux', 21:31:39 INFO - 'TMOUT': '86400', 21:31:39 INFO - 'USER': 'cltbld', 21:31:39 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:39 INFO - '_': '/tools/buildbot/bin/python'} 21:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 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)) 21:31:40 INFO - Cleaning up... 21:31:40 INFO - Return code: 0 21:31:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:31:40 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')]} 21:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:40 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')]} 21:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:40 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 0xb071f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc17a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', '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 21:31:40 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 21:31:40 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 21:31:40 INFO - Using env: {'DISPLAY': ':0', 21:31:40 INFO - 'HOME': '/home/cltbld', 21:31:40 INFO - 'LANG': 'en_US.UTF-8', 21:31:40 INFO - 'LANGUAGE': 'en_US:en', 21:31:40 INFO - 'LOGNAME': 'cltbld', 21:31:40 INFO - 'MAIL': '/var/mail/cltbld', 21:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:40 INFO - 'MOZ_NO_REMOTE': '1', 21:31:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:40 INFO - 'NO_EM_RESTART': '1', 21:31:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:40 INFO - 'PWD': '/builds/slave/test', 21:31:40 INFO - 'SHELL': '/bin/bash', 21:31:40 INFO - 'SHLVL': '1', 21:31:40 INFO - 'TERM': 'linux', 21:31:40 INFO - 'TMOUT': '86400', 21:31:40 INFO - 'USER': 'cltbld', 21:31:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:40 INFO - '_': '/tools/buildbot/bin/python'} 21:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:31:40 INFO - Cleaning up... 21:31:40 INFO - Return code: 0 21:31:40 INFO - Running post-action listener: _resource_record_post_action 21:31:40 INFO - Running post-action listener: _start_resource_monitoring 21:31:40 INFO - Starting resource monitoring. 21:31:40 INFO - [mozharness: 2016-12-28 05:31:40.422956Z] Finished create-virtualenv step (success) 21:31:40 INFO - [mozharness: 2016-12-28 05:31:40.423525Z] Running install step. 21:31:40 INFO - Running pre-action listener: _resource_record_pre_action 21:31:40 INFO - Running main action method: install 21:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:31:40 INFO - Reading from file tmpfile_stdout 21:31:40 INFO - Detecting whether we're running mozinstall >=1.0... 21:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:31:40 INFO - Reading from file tmpfile_stdout 21:31:40 INFO - Output received: 21:31:40 INFO - Usage: mozinstall [options] installer 21:31:40 INFO - Options: 21:31:40 INFO - -h, --help show this help message and exit 21:31:40 INFO - -d DEST, --destination=DEST 21:31:40 INFO - Directory to install application into. [default: 21:31:40 INFO - "/builds/slave/test"] 21:31:40 INFO - --app=APP Application being installed. [default: firefox] 21:31:40 INFO - mkdir: /builds/slave/test/build/application 21:31:40 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'] 21:31:40 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 21:31:56 INFO - Reading from file tmpfile_stdout 21:31:56 INFO - Output received: 21:31:56 INFO - /builds/slave/test/build/application/firefox/firefox 21:31:56 INFO - Running post-action listener: _resource_record_post_action 21:31:56 INFO - [mozharness: 2016-12-28 05:31:56.925472Z] Finished install step (success) 21:31:56 INFO - [mozharness: 2016-12-28 05:31:56.925718Z] Running run-tests step. 21:31:56 INFO - Running pre-action listener: _resource_record_pre_action 21:31:56 INFO - Running main action method: run_tests 21:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:31:56 INFO - Python 2.7.3 21:31:56 INFO - Return code: 0 21:31:56 INFO - grabbing minidump binary from tooltool 21:31:56 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')]} 21:31:56 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 0xf02850>, '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 0xe776d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe77b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:31:56 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 21:31:56 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 21:31:56 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 21:31:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:31:57 INFO - Return code: 0 21:31:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:31:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:31:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:31:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:31:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 21:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 21:31:57 INFO - Using env: {'DISPLAY': ':0', 21:31:57 INFO - 'HOME': '/home/cltbld', 21:31:57 INFO - 'LANG': 'en_US.UTF-8', 21:31:57 INFO - 'LANGUAGE': 'en_US:en', 21:31:57 INFO - 'LOGNAME': 'cltbld', 21:31:57 INFO - 'MAIL': '/var/mail/cltbld', 21:31:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:31:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:57 INFO - 'MOZ_NO_REMOTE': '1', 21:31:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:57 INFO - 'NO_EM_RESTART': '1', 21:31:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:57 INFO - 'PWD': '/builds/slave/test', 21:31:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:31:57 INFO - 'SHELL': '/bin/bash', 21:31:57 INFO - 'SHLVL': '1', 21:31:57 INFO - 'TERM': 'linux', 21:31:57 INFO - 'TMOUT': '86400', 21:31:57 INFO - 'USER': 'cltbld', 21:31:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389', 21:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:57 INFO - '_': '/tools/buildbot/bin/python'} 21:31:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 21:31:57 INFO - mozversion application_buildid: 20161227203436 21:31:57 INFO - mozversion application_changeset: d7b6af32811bddcec10a47d24bd455a1ec1836fc 21:31:57 INFO - mozversion application_display_name: Nightly 21:31:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:31:57 INFO - mozversion application_name: Firefox 21:31:57 INFO - mozversion application_remotingname: firefox 21:31:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:31:57 INFO - mozversion application_vendor: Mozilla 21:31:57 INFO - mozversion application_version: 53.0a1 21:31:57 INFO - mozversion platform_buildid: 20161227203436 21:31:57 INFO - mozversion platform_changeset: d7b6af32811bddcec10a47d24bd455a1ec1836fc 21:31:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:31:57 INFO - mozversion platform_version: 53.0a1 21:31:57 INFO - using testdate: 1482903117 21:31:57 INFO - actual date: 1482903117 21:31:57 INFO - starting webserver on 'localhost:33324' 21:31:57 INFO - SUITE-START | Running 2 tests 21:31:57 INFO - TEST-START | dromaeo_css 21:31:57 INFO - operating with platform_type : linux_ 21:31:57 INFO - Initialising browser for dromaeo_css test... 21:31:57 INFO - TEST-INFO | started process 26990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8Nsj77/profile http://localhost:33324/getInfo.html) 21:31:57 INFO - PROCESS | 26990 | 1482903117729 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < 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 21:32:01 INFO - PROCESS | 26990 | __metrics Screen width/height:1600/1200 21:32:01 INFO - PROCESS | 26990 | colorDepth:24 21:32:01 INFO - PROCESS | 26990 | Browser inner width/height: 1024/697 21:32:01 INFO - PROCESS | 26990 | __metrics 21:32:01 INFO - PROCESS | 26990 | 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" 21:32:01 INFO - PROCESS | 26990 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:32:02 INFO - PROCESS | 26990 | console.error: PushService: 21:32:02 INFO - PROCESS | 26990 | stateChangeProcessEnqueue: Error transitioning state 21:32:02 INFO - PROCESS | 26990 | UnknownError 21:32:03 INFO - TEST-INFO | 26990: exit 0 21:32:03 INFO - Browser initialized. 21:32:03 INFO - Running cycle 1/1 for dromaeo_css test... 21:32:03 INFO - TEST-INFO | started process 27072 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8Nsj77/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:32:15 INFO - PROCESS | 27072 | RSS: Main: 151470080 21:32:15 INFO - PROCESS | 27072 | 21:35:19 INFO - PROCESS | 27072 | Cycle 1(1): loaded http://localhost:33324/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33324/tests/dromaeo/cssquery-ext.html) 21:35:21 INFO - PROCESS | 27072 | RSS: Main: 266489856 21:35:21 INFO - PROCESS | 27072 | 21:37:03 INFO - PROCESS | 27072 | ************************* 21:37:03 INFO - PROCESS | 27072 | A coding exception was thrown and uncaught in a Task. 21:37:03 INFO - PROCESS | 27072 | 21:37:03 INFO - PROCESS | 27072 | Full message: ReferenceError: fetch is not defined 21:37:03 INFO - PROCESS | 27072 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 21:37:03 INFO - PROCESS | 27072 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 21:37:03 INFO - PROCESS | 27072 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 21:37:03 INFO - PROCESS | 27072 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:37:03 INFO - PROCESS | 27072 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:37:03 INFO - PROCESS | 27072 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:37:03 INFO - PROCESS | 27072 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 21:37:03 INFO - PROCESS | 27072 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 21:37:03 INFO - PROCESS | 27072 | TaskImpl@resource://gre/modules/Task.jsm:277:3 21:37:03 INFO - PROCESS | 27072 | asyncFunction@resource://gre/modules/Task.jsm:252:14 21:37:03 INFO - PROCESS | 27072 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 21:37:03 INFO - PROCESS | 27072 | 21:37:03 INFO - PROCESS | 27072 | ************************* 21:38:23 INFO - PROCESS | 27072 | Cycle 1(1): loaded http://localhost:33324/tests/dromaeo/cssquery-ext.html (next: http://localhost:33324/tests/dromaeo/cssquery-jquery.html) 21:38:24 INFO - PROCESS | 27072 | RSS: Main: 238829568 21:38:24 INFO - PROCESS | 27072 | 21:41:26 INFO - PROCESS | 27072 | Cycle 1(1): loaded http://localhost:33324/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33324/tests/dromaeo/cssquery-mootools.html) 21:41:27 INFO - PROCESS | 27072 | RSS: Main: 224133120 21:41:27 INFO - PROCESS | 27072 | 21:44:29 INFO - PROCESS | 27072 | Cycle 1(1): loaded http://localhost:33324/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33324/tests/dromaeo/cssquery-prototype.html) 21:44:30 INFO - PROCESS | 27072 | RSS: Main: 183721984 21:44:30 INFO - PROCESS | 27072 | 21:47:33 INFO - PROCESS | 27072 | Cycle 1(1): loaded http://localhost:33324/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33324/tests/dromaeo/cssquery-yui.html) 21:47:33 INFO - PROCESS | 27072 | RSS: Main: 183894016 21:47:33 INFO - PROCESS | 27072 | 21:50:36 INFO - PROCESS | 27072 | Cycle 1(1): loaded http://localhost:33324/tests/dromaeo/cssquery-yui.html (next: http://localhost:33324/tests/dromaeo/cssquery-dojo.html) 21:50:37 INFO - PROCESS | 27072 | RSS: Main: 275849216 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | __start_tp_report 21:50:37 INFO - PROCESS | 27072 | _x_x_mozilla_page_load 21:50:37 INFO - PROCESS | 27072 | _x_x_mozilla_page_load_details 21:50:37 INFO - PROCESS | 27072 | |i|pagename|runs| 21:50:37 INFO - PROCESS | 27072 | |0;dojo.html;2517.9244622661313;2547.3249588858944;2576.896924123035;2578.3554111472145;2583.1862963166595;4596.49438561758;4656.464506581744;4715.269133284342;4725.385699859018;4732.171869922763;4676.251799712047;4748.287756836477;4769.117713223056;4813.229883218686;4857.53853383928;4333.783310834454;4356.041670832412;4390.934135987963;4394.143142087294;4425.7565833879125;4050.453188819501;4459.353393757903;4479.955200448003;4500.909981800362;4541.909161816761;4419.116176764628;4425.5353187915125;4482.484514280867;4495.100979804036;4527.275635898257;4279.422277992462;4316.223079845627;4319.632831209351;4368.825246990116;4397.208502469554;4395.978020109879;4401.185780630562;4402.295632698753;4433.401490798733;4467.128909862578;4756.191447453941;4758.2386818108935;4764.285357196413;4783.234682450791;4813.566778989873;4948.752562371867;4977.527134922177;5026.79892804291;5102.744862756847;5111.182210846247;5120.487951204887;5131.948680513185;5146.176611742141;5171.560417364529;5190.71451070191;4758.2624693172265;4804.327394164819;4810.95189048112;4817.8313759018265;4856.732879691651;5118.872028199325;5150.716710580954;5243.68537887728;5248.9737551312;5249.346436030113;4969.726665033458;5013.423456302499;5014.323066385991;5014.949850501521;5021.874453138701;5070.898582028339;5071.847844564669;5073.416557095908;5087.567556757643;5090.974545127325;5037.7733002014265;5067.366579177603;5072.416672082684;5108.157154069537;5147.047796157723;4988.052270068748;4988.401391833003;5008.223725322581;5028.924566131511;5038.244263360525;4213.1784302060805;4258.957410425918;4302.612764851178;4303.698741088157;4346.521882592912;3703.999920021579;3704.4628528863163;3725.5529336479253;3728.067983004249;3759.642833930772;4263.70154089217;4264.744115353089;4268.594483524123;4304.591063848992;4310.31035034393;4971.527704868032;5073.518015788494;5082.440931497532;5083.110455670243;5085.898282034413;4217.093324935154;4286.164197981426;4313.223619748475;4336.436263285753;4376.234159022222;4625.468071171791;4627.930581041287;4630.4443466784205;4638.95361046392;4665.930011049837;4784.808607655655;4787.664863459594;4788.377510923628;4790.664653474223;4839.7096174229655;5020.698758074527;5051.09080365523;5052.115879720961;5172.922406163835;5175.042617115846;4688.789004494738;4737.763111844325;4774.355462012584;4778.761061946958;4782.995570930144;4924.384451943507;4962.875928101682;4972.2790195422185;5011.874703132451;5014.122528557415;4982.701037937735;5012.974935125301;5037.546620804145;5049.495050494921;5091.1854103343285;4872.561469467765;4880.6095512358215;4904.166291730486;4909.926351104665;4931.605471562339;4938.1358262304675;4945.925811112908;4956.983818317164;4967.155583550732;5031.4213865405945;4904.754762261944;4919.409670839522;4929.975350123226;4936.160852654985;4952.603791696728;5014.52362025607;5016.197408414636;5018.799248030038;5022.4977502249485;5060.089183946778;4713.222318317439;4761.476237613929;4830.372051633265;4863.392075990501;4870.439899411613 21:50:37 INFO - PROCESS | 27072 | |1;ext.html;530.9442508536604;553.9639923404981;554.9833504994849;558.874253293009;564.0946281218646;27474.313142171457;37584.248315033714;39264.80367598162;39303.21393572134;42790.358144627855;39739.80130099345;40184.79907600465;40188.19623607526;40226;40251.19497610046;7433.145188303333;7600.657970391318;7782.260685234894;7784.3772498200005;7787.922120778805;7401.555906645618;9771.609135634566;10020.298579099466;10035.598576056986;10371.222158338154;9997.650082247123;10053.64812231572;10057.094861462467;10080.34477758947;10080.445575493344;9669.661561845336;9736.172425343855;9741.951290243574;9747.463889486075;9750.46372449515;9909.801803963881;9976.95011524945;9977.15194208493;9978.950105249427;9983.201343892544;36760;37205.81397093024;37227.81386093079;37243;37264.44103338452;3649.4525821126776;3730.757500762442;3749.887503374903;3780.300644380771;3796.924061518782;25377.746222537724;25518.489630207478;25744.485110297876;29444.41111177786;29479.557806632918;3129.358481511285;3162.067190178903;3167.144870884871;3218.43677356464;3300.884469043572;228990;229368;229917;230445;230514;49578.75210623924;49918.75040624774;49961.25058124131;50056.24915626268;50079.499205008215;52160.73919630377;52234.47765522296;52303;52368.47631523712;52405;12398.938005310096;12781.105322627513;12785.168964017495;12803.807942880865;12810.359482025862;7284.526505777108;7401.407887368998;7857.882131768028;9419.481928493999;9469.431834089977;14471.49349772753;14817.629559261104;14823.481178158712;14825.629359266104;15045.623859403602;6303.212098487689;6352.174217351715;6496.740130394825;6529.269743517972;6532.379423954717;717.1000394504968;736.1276886889053;744.5160645580416;744.728174216418;746.1642959884855;26277.868610656824;27348.863255683595;27780.722192777812;27955;28007.159785206477;806.5362416610473;818.4639061414783;831.6091437024588;847.7538019111896;864.101334612009;14315.928420357832;14461;14570;14618.561443156723;14817.481388151364;341340;344531;344806;345301;345412;48761;48829.5117048825;48919.75540122276;48929.26606100911;48991.265131023065;1119.5689659480995;1162.2503485251966;1172.6188988578747;1174.8472698549133;1196.4137572589368;24581.87709061443;25470.617940731274;26316.4736705261;26696.733032670203;26847.597286040334;40289.1942161161;40922.38616420697;40929.79535102305;40960.59039409687;40996.590034099274;281485;282557;283641;284576;285129;34488.13779655529;34875;34922.47616285707;35158.82420587983;35235.64764352324;38251.426228607146;38513.614863851006;38842.80578597202;39021;39889.601103988585;33724.49413258754;33731.66268337285;33852.83073584616;33873;34149.65850341465;33123.83438082794;33569;33632.66367336296;33644.83177584096;33806;33443.49834752432;33847.153821154665;33862.32275354528;34137.48793768046;37274.813625931696;33732.66267337295;34654.13364665904;36820.07949801169;37381.25237495289;37470;29829.254268643457;30045.549316760702;30120.24699382533;30179.396412072074;30652.540211897285 21:50:37 INFO - PROCESS | 27072 | |2;jquery.html;358.78472916250234;371.56712430019047;377.06299844885456;378.56654131019985;388.8561232344033;12128.941716409772;13012;16187.676246475063;16205.270762815688;16211.51365459038;15449.92275038626;15873.444429444971;16058.59853503663;16220.675586488293;16244;3855.209682015194;4883.072216278904;5037.722925239111;5047.747612619354;5048.343715317003;3899.3760998240286;4011.0373510357595;4012.879613611596;4013.799310034501;5817.214676018747;5854.063349864023;6030.155778191035;6074;6079.118527813464;6082.604630699013;5905.822825315226;6030.517558595324;6069.787557435513;6073.848153796146;6077.817665469999;4585.977070114628;4854.975725121351;5326.7070311132875;5329.307190065306;5338.332708411449;11565.595204167814;11704.824427633592;11758.823617645741;11774.470148843237;11786.057115430745;2338.1232037985756;2401.267613377919;2442.1086303499274;2446.60854263317;2473.591857343536;9011.729648110568;9046.185843274137;9204.171624553823;11615.477303521362;11703.005244554222;1624.34214143271;1657.4861792844256;1661.2192269633254;1682.7896512935883;1698.3800912666911;61022.389776102114;61490.69254653743;61584.69207653933;63275.6836215816;63779;9489;9578.13796758295;9744.756381090388;9919.553620087114;9971.501424928869;13055.477780888848;13336;13338.666533336744;13342;13353.265570393722;5249.370075590953;5345.893082138336;5371.086915224343;6563;6564.573302735307;5734.369219385864;5786.768529258782;5925.881482370329;5929.762809487572;5934.287885453687;4132.524759652619;4224.634204845296;4280.357946308017;4328.026194106301;4411.404460397869;1768.9380871669687;1819.3086627081625;1831.1759708131394;1851.9166637501344;1860.3767737807987;742.3838214282133;789.9842003159905;794.4438892775081;800.5476905548346;802.7110240313483;14637.364614623446;14972.401103955937;15212.847871521364;15296.923515382352;15897.205139742966;777.2577188784697;789.243932250749;792.3502727763179;800.1478425476848;800.8158123631597;8217.8356432871;8278.8344233115;8434;8734.475931444007;8894.73315800527;319657;334188;337084;341715;341936;54782;55021.724891375285;61613.07580386298;64430.355696443374;75814.24185758072;802.450321529754;839.7312859884901;839.7858546070713;842.3766412854566;846.0693237438768;14415.351309190903;14440.711185776436;14452.494162704255;14475.348609312818;15054.32255548481;34839.65160348364;34849.12877177989;35473;35505.644943550236;35951.28097438089;46018.76990615026;46050;46247.53752462432;46357;47570.286445704034;6534.901976470262;6782.593044417351;6783.694733737092;7218.855622887618;7220.097487814023;10975.341479511255;11537.942310288396;11601.651950441501;11665.35840528779;11967.581134660246;9465.148136667733;9668.226541876773;9817.509124543887;10159.288849780445;10165.726130379075;9737.951310243403;9814.85277720849;9940.502974851373;10223.7444063899;10320.174386049248;9522.666706665234;9599.760005999906;9731.367461115005;9902.702918912444;10209.081182693297;6891.517593768388;6948.374646281859;7105.04081948951;7121.430285577245;7126.251743566851;7957.363410926996;9355;9383.343165978316;9413.341066125306;9721.465319407218 21:50:37 INFO - PROCESS | 27072 | |3;mootools.html;1277.2145130744589;1284.7880099783536;1288.859359466872;1293.0883726972486;1294.1975974895563;7040.295970402964;7483.326500614957;7491.513051651646;7499.512531685436;7508.662110205033;6481.74073037078;6750.324967503259;7039;7218.458615603825;7279.3084656957635;2107.0518266779973;2145.0990583954776;2149.1833103420677;2150.118451434912;2150.924717634883;2086.4247493142734;2176.3688530326185;2184.2355175688463;2186.5189658275153;2190.463336482556;1722.905240211788;1789.105447276362;2083.0626218201796;2157.870527768339;2159.1903036361364;2116.767155612889;2117.9046942887608;2137.700721898935;2138.1981756841183;2142.1431427428997;2112.8204102651293;2143.485563464773;2145.281330754199;2146.162996431393;2148.3340164548886;7375.778726638209;7435.40516758658;7440.888386674204;7442.032535770315;7446.925530744677;4478;4586.440610192551;4623.283391074388;4628.560286772751;4629.467611224719;2785.080923295308;2947.557866320069;2951.8228906265476;2963.4221326841275;3003.759699224057;1059.1526778577108;1096.2655021135859;1138.9772204555863;1175.488662431845;1188.6196417146516;9587.760305992337;9895.752606184831;9919.107280344731;9931.602735890629;9936.950315248378;3988.2422339755353;4054;4070.247004304213;4071.3689378146437;4071.5114186297833;7908.604569771604;7912.0505539335645;7913.406494512819;7919.366450683931;7922.801929951797;4573.794179261941;4575.95424045762;4578.702384345007;4579.198640237945;4579.244424669959;4158.313878210061;4167.9583204168175;4180.853670121531;4190.979045104816;4289.099289149313;3175.5712978747574;3237.4010808000594;3293.407186706416;3310.4537751270773;3316.303576249015;1003.1022235099545;1023.1814548361281;1029.5006921642996;1032.8244198486275;1034.8395998620226;574.5282599789708;680.5474359550942;686.0361192524512;705.0200221691853;729.9671514781847;4820.156472617278;4841.709497430165;4890.1931181355385;4974.403071631427;5088.821891233789;767.9040119985002;772.7913463364972;806.846699127164;806.9072056713553;886.3618194899661;3084.953725694116;3145.4338219120777;3154.6372167200607;3163.9050828475183;3191.872325107016;8533.786655333543;8980.8652870207;9001.459912405277;9005.324600654978;9014.86477702835;8763.809047738065;8928;8930.330225233134;8940.776480588038;8959.238464730572;6857.794266172023;6991.23096459389;6993.89509157363;7003.509754317149;7010.684519196649;4635.605973492156;4774.116788394019;4840.128776820206;4983.426905905867;5044.798208071783;7708.113566939874;7909.762707118795;8126.959365203136;8127.1466496017065;8135.145809689898;7997.200279971957;8216.21945915137;8251.174882511701;8258.463199891989;8266.83466330658;6356;6492.448141907991;6493.383128602775;6497.155369801895;6515.771947981798;6913.5506192097355;6953.443724502129;6968.5122041458635;7005.894911576229;7018.263082376481;6300.432961033529;6316.368363163647;6319.210098737658;6359.268684101203;6369.299377068612;6199.163112979871;6277.246730392382;6281.937180628135;6296.968515157395;6436.613803171825;5290.312259406252;5412.702301373462;5453.836384908459;5521.917171242354;5527.585431092603;4128.298189307885;4131.07050913542;4244.554321796167;4266.424032755538;4369.14801613695;4147.97926010368;4200.180964711852;4238;4268.914621707487;4379.693421460594 21:50:37 INFO - PROCESS | 27072 | |4;prototype.html;346.12257926157196;347.26726606859523;348.4511893767317;349.57002886449663;355.8985689078612;4776.713397196167;4803.783829727662;4819.2530157825595;4838.4193896732395;4882.218844984807;4703.835365762199;4735.271625856563;4744.667873248865;4757.666963312561;4803.375561177051;3646.5988741238575;3706.9258614827686;3874.9925019494854;3911.0808959894543;3936.5079365079296;3887.7667339959694;3906.964654366595;3914.0606254498857;3923;3923.274194274054;3884.348712210527;3885.1841113366227;3892.579208588873;3901.102746368337;3915.8237879295357;3732.144909144493;3751.193493398905;3838.692904567628;3842.8462861485787;3927.5483319418204;3451.2924850405607;3471.968898194559;3474.114100904253;3474.74909032748;3488.4418493040994;4663.88340291492;4686.625069994392;4688.460827004904;4691.179043667345;4725.881852953669;4253.425787518676;4389.848974002715;4393.51671316155;4396.702972623084;4447.911041779179;5020.548150666421;5051.797928082872;5054.393472783289;5068.214426763858;5070.619703522214;3836.9232615347537;3841.3853783394525;3843.7693738375788;3851.114243723959;3860.2665493556133;5095.541401273903;5280.653433374465;5321.467853214725;5325.121354976424;5327.920081198785;3918.0792513759243;3927.6072392761066;3936.7047471439755;3938.4879965604287;3960.940585891214;3965.980170099189;3967.3850553164307;3985.4021896715726;3992.5608183099835;3997.400389941532;4408.801603927831;4426.9778651106535;4430.291153415438;4435.556444355603;4435.977820110878;4941.703497790144;5023.246513023076;5040.462658889074;5042.672226305279;5079.492050794891;1314.6984485359426;1319.7822359310799;1333.2067419885152;1333.9399727012308;1340.5911197084788;925.6575067225169;962.7496850818837;973.6592192732487;979.7948523316361;980.5783513089298;4160.524487929677;4178.6657067434535;4272.6154646082;4345;4347.978260108743;5174.793008279702;5176.7411629418375;5185.066687996122;5189.636725429184;5241.88512018064;4343.1747967886;4345.261305578059;4378.299472084451;4388.166248412856;4388.978055109768;2611.490759301918;2653.0714250012343;2675.8528280944734;2685.516607010718;2694.986525067362;4681.531846815359;4710.88222794426;4714.787834547454;4720.89059071118;4733.95266047342;5185;5189.844304670866;5193.2989046479;5209.739513024334;5225.790968361223;4575.604440645635;4802.519748025239;4820.204666230103;4820.30105634674;4855.32025516428;5187.896242075062;5196.766145523385;5207.947920520746;5237.502437268454;5254.579633629377;5000.57495112905;5157.535821776059;5205.271262023395;5214.13966695491;5221.329174664071;4818.397700287464;4847.587955023862;4864.343313652612;4890.828820991248;4899.069176856386;3621.1671315598087;3622.818859056984;3664.193877346979;3669.853205871735;3671;5278.287431196893;5281.973590132025;5286.577073834007;5294.232336311136;5324.361076670824;4920.926186107283;5081.92377114351;5099.643024988215;5105.821296254613;5147.2279158126585;5055.924161137659;5056.584156436204;5073.300444351184;5086.821961231487;5099.898002040012;4887.975560122177;5011.624128190328;5040.974795126001;5042.84871453857;5055.143843728281;4834.709917404967;4984.900301993867;5090.720010399464;5117;5149.9227511586605;4862.3922009748785;4868.659193856396;4984.252362145562;4992.2511623256805;4993.702896667573 21:50:37 INFO - PROCESS | 27072 | |5;yui.html;662.6752891083371;674.7233634209975;723.1069629008174;728;755.4975940999234;11475.540978360867;12139.453724582392;12166.574169904055;12177.817332740005;12254.080943929208;13111.147775394593;13298.608854742051;13318.800217996737;13320.733585328288;13333.733325333487;648.9902651460231;676.1413005483058;787.7439832054577;814.5764202614666;820.7496713502379;830.6054624053556;832.4464231286188;833.6332013913858;834.92903103236;835.9968038353969;878.1920633017608;878.2710350409145;881.951492667903;882.0782282514788;883.823235352932;675.395521008697;697.9092717946685;701.8210356359095;704.3695892176473;867.4274978514181;520.7188118416088;607.8328459673581;628.9465395441393;630.6909614289011;632.4118569730149;13091.672708182276;13127.606171814765;13145.474181032747;13161.802572961318;13166.605001849959;11498.770024599462;11502.252353596989;11768.411579420994;11913.166078374517;12124.575639852563;11533.480993355319;11534.424696442862;11552.133589980785;11579.421028948518;11712.531498740125;4159.459270294872;4175.853845115406;4193.51774545928;4220.36694495825;4229.894252643709;61048.38951610471;61908.38091619071;63820.361796381905;64524;64736.734214302276;9862.802743945082;9946.602135914627;10067.949660251652;10140.797184056277;10180.949095254624;17636.911815441097;17669.646607067785;17697.38059167923;17708.557286067953;17715.379961701277;6008.218931538872;6123.203983482119;6294.464970477469;6312.021636646327;6342.365763423621;3164.5095010273444;3215.8552865121123;3256.5115232714807;3269.4441944869427;3276.819774912355;2753.2703833484143;2796.860156992142;2822.379076603144;3138.419392412411;3282.179455136216;542.2733537060358;548.4625067433938;550.0813641219173;557.4007941462944;560.3220103674518;603.5955909540618;624.8937680594219;626.9154362952119;628.9905651415232;632.4339715954195;7757.418193635387;7790.104138024087;7861.5464606700525;7880.014998125234;8131.512109273463;487.75124686410186;500.9549140577366;511.7671459485925;511.90529751996;515.5772266741311;3642.2715456908722;3806.200697853482;3826.885193444201;3911.4328422378594;4026.2350153471334;59970.40029599735;62520.68739656363;63493.36506634967;63753;63840;57167.42832571621;57177;57280.42719572751;57756;58183.92712510084;42705;46032;48855.51144488652;49872.50127498679;50211.24683129756;8066.395020373613;8689.478631282143;8782.473051616924;8783.780405489913;8946.329025322997;33089.834550827094;33093.503597445575;33669.818298119724;34541.65458345485;34953.300933981685;39084.80457597694;40957.79521102375;41735.373969389875;42029.78985105055;42134.36798448087;9462.2430205582;9671.758206044906;9690.224782017563;9698.660546880825;9947.403155810674;12218.20581662189;12617.87382126167;12795.552155674506;12844.972402207988;13641.590752277447;11327.433628318386;11546.19176657626;11667.6499705009;11974.101942354184;12312.26126432417;11483.598074067368;11664.94167529157;11780;12151.088668350087;12346.197497162655;11368.488418021043;11669.941650291694;11759.88240117622;11908.166428349932;12170.75658486843;10097.798044038931;10228.181745460197;10565.471726413494;10602.151827853908;10674.30617009923;9347.298952578447;9973.800523989334;10005.249606279704;10056.14522765544;10090.54592543352 21:50:37 INFO - PROCESS | 27072 | __end_tp_report 21:50:37 INFO - PROCESS | 27072 | __start_cc_report 21:50:37 INFO - PROCESS | 27072 | _x_x_mozilla_cycle_collect,1194 21:50:37 INFO - PROCESS | 27072 | __end_cc_report 21:50:37 INFO - PROCESS | 27072 | __startTimestamp1482904237119__endTimestamp 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | ------- Summary: start ------- 21:50:37 INFO - PROCESS | 27072 | Number of tests: 6 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | [#0] dojo.html Cycles:165 Average:4689.97 Median:4813.57 stddev:506.19 (10.5%) stddev-sans-first:478.21 21:50:37 INFO - PROCESS | 27072 | Values: 2517.9 2547.3 2576.9 2578.4 2583.2 4596.5 4656.5 4715.3 4725.4 4732.2 4676.3 4748.3 4769.1 4813.2 4857.5 4333.8 4356.0 4390.9 4394.1 4425.8 4050.5 4459.4 4480.0 4500.9 4541.9 4419.1 4425.5 4482.5 4495.1 4527.3 4279.4 4316.2 4319.6 4368.8 4397.2 4396.0 4401.2 4402.3 4433.4 4467.1 4756.2 4758.2 4764.3 4783.2 4813.6 4948.8 4977.5 5026.8 5102.7 5111.2 5120.5 5131.9 5146.2 5171.6 5190.7 4758.3 4804.3 4811.0 4817.8 4856.7 5118.9 5150.7 5243.7 5249.0 5249.3 4969.7 5013.4 5014.3 5014.9 5021.9 5070.9 5071.8 5073.4 5087.6 5091.0 5037.8 5067.4 5072.4 5108.2 5147.0 4988.1 4988.4 5008.2 5028.9 5038.2 4213.2 4259.0 4302.6 4303.7 4346.5 3704.0 3704.5 3725.6 3728.1 3759.6 4263.7 4264.7 4268.6 4304.6 4310.3 4971.5 5073.5 5082.4 5083.1 5085.9 4217.1 4286.2 4313.2 4336.4 4376.2 4625.5 4627.9 4630.4 4639.0 4665.9 4784.8 4787.7 4788.4 4790.7 4839.7 5020.7 5051.1 5052.1 5172.9 5175.0 4688.8 4737.8 4774.4 4778.8 4783.0 4924.4 4962.9 4972.3 5011.9 5014.1 4982.7 5013.0 5037.5 5049.5 5091.2 4872.6 4880.6 4904.2 4909.9 4931.6 4938.1 4945.9 4957.0 4967.2 5031.4 4904.8 4919.4 4930.0 4936.2 4952.6 5014.5 5016.2 5018.8 5022.5 5060.1 4713.2 4761.5 4830.4 4863.4 4870.4 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | [#1] ext.html Cycles:180 Average:44459.85 Median:36790.04 stddev:75857.35 (206.2%) stddev-sans-first:75998.44 21:50:37 INFO - PROCESS | 27072 | Values: 530.9 554.0 555.0 558.9 564.1 27474.3 37584.2 39264.8 39303.2 42790.4 39739.8 40184.8 40188.2 40226.0 40251.2 7433.1 7600.7 7782.3 7784.4 7787.9 7401.6 9771.6 10020.3 10035.6 10371.2 9997.7 10053.6 10057.1 10080.3 10080.4 9669.7 9736.2 9742.0 9747.5 9750.5 9909.8 9977.0 9977.2 9979.0 9983.2 36760.0 37205.8 37227.8 37243.0 37264.4 3649.5 3730.8 3749.9 3780.3 3796.9 25377.7 25518.5 25744.5 29444.4 29479.6 3129.4 3162.1 3167.1 3218.4 3300.9 228990.0 229368.0 229917.0 230445.0 230514.0 49578.8 49918.8 49961.3 50056.2 50079.5 52160.7 52234.5 52303.0 52368.5 52405.0 12398.9 12781.1 12785.2 12803.8 12810.4 7284.5 7401.4 7857.9 9419.5 9469.4 14471.5 14817.6 14823.5 14825.6 15045.6 6303.2 6352.2 6496.7 6529.3 6532.4 717.1 736.1 744.5 744.7 746.2 26277.9 27348.9 27780.7 27955.0 28007.2 806.5 818.5 831.6 847.8 864.1 14315.9 14461.0 14570.0 14618.6 14817.5 341340.0 344531.0 344806.0 345301.0 345412.0 48761.0 48829.5 48919.8 48929.3 48991.3 1119.6 1162.3 1172.6 1174.8 1196.4 24581.9 25470.6 26316.5 26696.7 26847.6 40289.2 40922.4 40929.8 40960.6 40996.6 281485.0 282557.0 283641.0 284576.0 285129.0 34488.1 34875.0 34922.5 35158.8 35235.6 38251.4 38513.6 38842.8 39021.0 39889.6 33724.5 33731.7 33852.8 33873.0 34149.7 33123.8 33569.0 33632.7 33644.8 33806.0 33443.5 33847.2 33862.3 34137.5 37274.8 33732.7 34654.1 36820.1 37381.3 37470.0 29829.3 30045.5 30120.2 30179.4 30652.5 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | [#2] jquery.html Cycles:180 Average:21398.70 Median:5042.74 stddev:55262.34 (1095.9%) stddev-sans-first:55394.78 21:50:37 INFO - PROCESS | 27072 | Values: 358.8 371.6 377.1 378.6 388.9 12128.9 13012.0 16187.7 16205.3 16211.5 15449.9 15873.4 16058.6 16220.7 16244.0 3855.2 4883.1 5037.7 5047.7 5048.3 3899.4 4011.0 4012.9 4013.8 5817.2 5854.1 6030.2 6074.0 6079.1 6082.6 5905.8 6030.5 6069.8 6073.8 6077.8 4586.0 4855.0 5326.7 5329.3 5338.3 11565.6 11704.8 11758.8 11774.5 11786.1 2338.1 2401.3 2442.1 2446.6 2473.6 9011.7 9046.2 9204.2 11615.5 11703.0 1624.3 1657.5 1661.2 1682.8 1698.4 61022.4 61490.7 61584.7 63275.7 63779.0 9489.0 9578.1 9744.8 9919.6 9971.5 13055.5 13336.0 13338.7 13342.0 13353.3 5249.4 5345.9 5371.1 6563.0 6564.6 5734.4 5786.8 5925.9 5929.8 5934.3 4132.5 4224.6 4280.4 4328.0 4411.4 1768.9 1819.3 1831.2 1851.9 1860.4 742.4 790.0 794.4 800.5 802.7 14637.4 14972.4 15212.8 15296.9 15897.2 777.3 789.2 792.4 800.1 800.8 8217.8 8278.8 8434.0 8734.5 8894.7 319657.0 334188.0 337084.0 341715.0 341936.0 54782.0 55021.7 61613.1 64430.4 75814.2 802.5 839.7 839.8 842.4 846.1 14415.4 14440.7 14452.5 14475.3 15054.3 34839.7 34849.1 35473.0 35505.6 35951.3 46018.8 46050.0 46247.5 46357.0 47570.3 6534.9 6782.6 6783.7 7218.9 7220.1 10975.3 11537.9 11601.7 11665.4 11967.6 9465.1 9668.2 9817.5 10159.3 10165.7 9738.0 9814.9 9940.5 10223.7 10320.2 9522.7 9599.8 9731.4 9902.7 10209.1 6891.5 6948.4 7105.0 7121.4 7126.3 7957.4 9355.0 9383.3 9413.3 9721.5 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | [#3] mootools.html Cycles:180 Average:4793.13 Median:4932.30 stddev:2621.74 (53.2%) stddev-sans-first:2615.78 21:50:37 INFO - PROCESS | 27072 | Values: 1277.2 1284.8 1288.9 1293.1 1294.2 7040.3 7483.3 7491.5 7499.5 7508.7 6481.7 6750.3 7039.0 7218.5 7279.3 2107.1 2145.1 2149.2 2150.1 2150.9 2086.4 2176.4 2184.2 2186.5 2190.5 1722.9 1789.1 2083.1 2157.9 2159.2 2116.8 2117.9 2137.7 2138.2 2142.1 2112.8 2143.5 2145.3 2146.2 2148.3 7375.8 7435.4 7440.9 7442.0 7446.9 4478.0 4586.4 4623.3 4628.6 4629.5 2785.1 2947.6 2951.8 2963.4 3003.8 1059.2 1096.3 1139.0 1175.5 1188.6 9587.8 9895.8 9919.1 9931.6 9937.0 3988.2 4054.0 4070.2 4071.4 4071.5 7908.6 7912.1 7913.4 7919.4 7922.8 4573.8 4576.0 4578.7 4579.2 4579.2 4158.3 4168.0 4180.9 4191.0 4289.1 3175.6 3237.4 3293.4 3310.5 3316.3 1003.1 1023.2 1029.5 1032.8 1034.8 574.5 680.5 686.0 705.0 730.0 4820.2 4841.7 4890.2 4974.4 5088.8 767.9 772.8 806.8 806.9 886.4 3085.0 3145.4 3154.6 3163.9 3191.9 8533.8 8980.9 9001.5 9005.3 9014.9 8763.8 8928.0 8930.3 8940.8 8959.2 6857.8 6991.2 6993.9 7003.5 7010.7 4635.6 4774.1 4840.1 4983.4 5044.8 7708.1 7909.8 8127.0 8127.1 8135.1 7997.2 8216.2 8251.2 8258.5 8266.8 6356.0 6492.4 6493.4 6497.2 6515.8 6913.6 6953.4 6968.5 7005.9 7018.3 6300.4 6316.4 6319.2 6359.3 6369.3 6199.2 6277.2 6281.9 6297.0 6436.6 5290.3 5412.7 5453.8 5521.9 5527.6 4128.3 4131.1 4244.6 4266.4 4369.1 4148.0 4200.2 4238.0 4268.9 4379.7 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | [#4] prototype.html Cycles:180 Average:4228.47 Median:4729.92 stddev:1190.08 (25.2%) stddev-sans-first:1157.20 21:50:37 INFO - PROCESS | 27072 | Values: 346.1 347.3 348.5 349.6 355.9 4776.7 4803.8 4819.3 4838.4 4882.2 4703.8 4735.3 4744.7 4757.7 4803.4 3646.6 3706.9 3875.0 3911.1 3936.5 3887.8 3907.0 3914.1 3923.0 3923.3 3884.3 3885.2 3892.6 3901.1 3915.8 3732.1 3751.2 3838.7 3842.8 3927.5 3451.3 3472.0 3474.1 3474.7 3488.4 4663.9 4686.6 4688.5 4691.2 4725.9 4253.4 4389.8 4393.5 4396.7 4447.9 5020.5 5051.8 5054.4 5068.2 5070.6 3836.9 3841.4 3843.8 3851.1 3860.3 5095.5 5280.7 5321.5 5325.1 5327.9 3918.1 3927.6 3936.7 3938.5 3960.9 3966.0 3967.4 3985.4 3992.6 3997.4 4408.8 4427.0 4430.3 4435.6 4436.0 4941.7 5023.2 5040.5 5042.7 5079.5 1314.7 1319.8 1333.2 1333.9 1340.6 925.7 962.7 973.7 979.8 980.6 4160.5 4178.7 4272.6 4345.0 4348.0 5174.8 5176.7 5185.1 5189.6 5241.9 4343.2 4345.3 4378.3 4388.2 4389.0 2611.5 2653.1 2675.9 2685.5 2695.0 4681.5 4710.9 4714.8 4720.9 4734.0 5185.0 5189.8 5193.3 5209.7 5225.8 4575.6 4802.5 4820.2 4820.3 4855.3 5187.9 5196.8 5207.9 5237.5 5254.6 5000.6 5157.5 5205.3 5214.1 5221.3 4818.4 4847.6 4864.3 4890.8 4899.1 3621.2 3622.8 3664.2 3669.9 3671.0 5278.3 5282.0 5286.6 5294.2 5324.4 4920.9 5081.9 5099.6 5105.8 5147.2 5055.9 5056.6 5073.3 5086.8 5099.9 4888.0 5011.6 5041.0 5042.8 5055.1 4834.7 4984.9 5090.7 5117.0 5149.9 4862.4 4868.7 4984.3 4992.3 4993.7 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - PROCESS | 27072 | [#5] yui.html Cycles:180 Average:14361.20 Median:23467.45 stddev:17707.35 (75.5%) stddev-sans-first:17727.14 21:50:37 INFO - PROCESS | 27072 | Values: 662.7 674.7 723.1 728.0 755.5 11475.5 12139.5 12166.6 12177.8 12254.1 13111.1 13298.6 13318.8 13320.7 13333.7 649.0 676.1 787.7 814.6 820.7 830.6 832.4 833.6 834.9 836.0 878.2 878.3 882.0 882.1 883.8 675.4 697.9 701.8 704.4 867.4 520.7 607.8 628.9 630.7 632.4 13091.7 13127.6 13145.5 13161.8 13166.6 11498.8 11502.3 11768.4 11913.2 12124.6 11533.5 11534.4 11552.1 11579.4 11712.5 4159.5 4175.9 4193.5 4220.4 4229.9 61048.4 61908.4 63820.4 64524.0 64736.7 9862.8 9946.6 10067.9 10140.8 10180.9 17636.9 17669.6 17697.4 17708.6 17715.4 6008.2 6123.2 6294.5 6312.0 6342.4 3164.5 3215.9 3256.5 3269.4 3276.8 2753.3 2796.9 2822.4 3138.4 3282.2 542.3 548.5 550.1 557.4 560.3 603.6 624.9 626.9 629.0 632.4 7757.4 7790.1 7861.5 7880.0 8131.5 487.8 501.0 511.8 511.9 515.6 3642.3 3806.2 3826.9 3911.4 4026.2 59970.4 62520.7 63493.4 63753.0 63840.0 57167.4 57177.0 57280.4 57756.0 58183.9 42705.0 46032.0 48855.5 49872.5 50211.2 8066.4 8689.5 8782.5 8783.8 8946.3 33089.8 33093.5 33669.8 34541.7 34953.3 39084.8 40957.8 41735.4 42029.8 42134.4 9462.2 9671.8 9690.2 9698.7 9947.4 12218.2 12617.9 12795.6 12845.0 13641.6 11327.4 11546.2 11667.6 11974.1 12312.3 11483.6 11664.9 11780.0 12151.1 12346.2 11368.5 11669.9 11759.9 11908.2 12170.8 10097.8 10228.2 10565.5 10602.2 10674.3 9347.3 9973.8 10005.2 10056.1 10090.5 21:50:37 INFO - PROCESS | 27072 | -------- Summary: end -------- 21:50:37 INFO - PROCESS | 27072 | 21:50:37 INFO - TEST-INFO | 27072: exit 0 21:50:37 INFO - TEST-OK | dromaeo_css | took 1120775ms 21:50:37 INFO - TEST-START | kraken 21:50:37 INFO - operating with platform_type : linux_ 21:50:37 INFO - Initialising browser for kraken test... 21:50:37 INFO - TEST-INFO | started process 27377 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2VRBjN/profile http://localhost:33324/getInfo.html) 21:50:38 INFO - PROCESS | 27377 | 1482904238478 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:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < 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 21:50:42 INFO - PROCESS | 27377 | __metrics Screen width/height:1600/1200 21:50:42 INFO - PROCESS | 27377 | colorDepth:24 21:50:42 INFO - PROCESS | 27377 | Browser inner width/height: 1024/697 21:50:42 INFO - PROCESS | 27377 | __metrics 21:50:42 INFO - PROCESS | 27377 | 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" 21:50:42 INFO - PROCESS | 27377 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:50:43 INFO - PROCESS | 27377 | console.error: PushService: 21:50:43 INFO - PROCESS | 27377 | stateChangeProcessEnqueue: Error transitioning state 21:50:43 INFO - PROCESS | 27377 | UnknownError 21:50:44 INFO - TEST-INFO | 27377: exit 0 21:50:44 INFO - Browser initialized. 21:50:44 INFO - Running cycle 1/1 for kraken test... 21:50:44 INFO - TEST-INFO | started process 27460 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2VRBjN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:50:56 INFO - PROCESS | 27460 | RSS: Main: 150982656 21:50:56 INFO - PROCESS | 27460 | 21:50:58 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33324/tests/kraken/driver.html?testName=audio-beat-detection) 21:50:59 INFO - PROCESS | 27460 | RSS: Main: 204931072 21:50:59 INFO - PROCESS | 27460 | 21:51:02 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33324/tests/kraken/driver.html?testName=audio-dft) 21:51:03 INFO - PROCESS | 27460 | RSS: Main: 185528320 21:51:03 INFO - PROCESS | 27460 | 21:51:06 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33324/tests/kraken/driver.html?testName=audio-fft) 21:51:06 INFO - PROCESS | 27460 | RSS: Main: 267575296 21:51:06 INFO - PROCESS | 27460 | 21:51:09 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33324/tests/kraken/driver.html?testName=audio-oscillator) 21:51:09 INFO - PROCESS | 27460 | RSS: Main: 178970624 21:51:09 INFO - PROCESS | 27460 | 21:51:11 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33324/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:51:12 INFO - PROCESS | 27460 | RSS: Main: 181645312 21:51:12 INFO - PROCESS | 27460 | 21:51:15 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33324/tests/kraken/driver.html?testName=imaging-darkroom) 21:51:16 INFO - PROCESS | 27460 | RSS: Main: 221360128 21:51:16 INFO - PROCESS | 27460 | 21:51:19 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33324/tests/kraken/driver.html?testName=imaging-desaturate) 21:51:20 INFO - PROCESS | 27460 | RSS: Main: 215142400 21:51:20 INFO - PROCESS | 27460 | 21:51:23 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33324/tests/kraken/driver.html?testName=json-parse-financial) 21:51:23 INFO - PROCESS | 27460 | RSS: Main: 216850432 21:51:23 INFO - PROCESS | 27460 | 21:51:25 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33324/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:51:26 INFO - PROCESS | 27460 | RSS: Main: 180416512 21:51:26 INFO - PROCESS | 27460 | 21:51:28 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:51:28 INFO - PROCESS | 27460 | RSS: Main: 188112896 21:51:28 INFO - PROCESS | 27460 | 21:51:30 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:51:31 INFO - PROCESS | 27460 | RSS: Main: 196444160 21:51:31 INFO - PROCESS | 27460 | 21:51:34 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:51:35 INFO - PROCESS | 27460 | RSS: Main: 206340096 21:51:35 INFO - PROCESS | 27460 | 21:51:38 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:51:38 INFO - PROCESS | 27460 | RSS: Main: 202166272 21:51:38 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | Cycle 1(1): loaded http://localhost:33324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33324/tests/kraken/driver.html?testName=ai-astar) 21:51:40 INFO - PROCESS | 27460 | RSS: Main: 253743104 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | __start_tp_report 21:51:40 INFO - PROCESS | 27460 | _x_x_mozilla_page_load 21:51:40 INFO - PROCESS | 27460 | _x_x_mozilla_page_load_details 21:51:40 INFO - PROCESS | 27460 | |i|pagename|runs| 21:51:40 INFO - PROCESS | 27460 | |0;ai-astar;111;117;115;116;117;116;117;117;115;115 21:51:40 INFO - PROCESS | 27460 | |1;audio-beat-detection;153;125;125;156;129;127;126;125;145;128 21:51:40 INFO - PROCESS | 27460 | |2;audio-dft;162;162;163;162;163;161;160;161;159;161 21:51:40 INFO - PROCESS | 27460 | |3;audio-fft;76;77;78;110;77;77;77;77;96;77 21:51:40 INFO - PROCESS | 27460 | |4;audio-oscillator;85;86;112;84;85;85;103;86;86;100 21:51:40 INFO - PROCESS | 27460 | |5;imaging-gaussian-blur;118;117;117;117;118;118;118;117;117;117 21:51:40 INFO - PROCESS | 27460 | |6;imaging-darkroom;150;149;149;149;149;150;149;149;148;149 21:51:40 INFO - PROCESS | 27460 | |7;imaging-desaturate;96;96;95;96;95;96;95;95;96;95 21:51:40 INFO - PROCESS | 27460 | |8;json-parse-financial;63;63;62;63;64;64;64;64;75;64 21:51:40 INFO - PROCESS | 27460 | |9;json-stringify-tinderbox;75;68;68;67;68;69;68;68;68;68 21:51:40 INFO - PROCESS | 27460 | |10;stanford-crypto-aes;85;84;88;101;84;83;85;92;84;93 21:51:40 INFO - PROCESS | 27460 | |11;stanford-crypto-ccm;135;138;134;143;138;147;133;130;188;131 21:51:40 INFO - PROCESS | 27460 | |12;stanford-crypto-pbkdf2;181;184;180;178;179;177;176;177;181;178 21:51:40 INFO - PROCESS | 27460 | |13;stanford-crypto-sha256-iterative;71;69;69;69;69;68;71;74;77;67 21:51:40 INFO - PROCESS | 27460 | __end_tp_report 21:51:40 INFO - PROCESS | 27460 | __start_cc_report 21:51:40 INFO - PROCESS | 27460 | _x_x_mozilla_cycle_collect,1821 21:51:40 INFO - PROCESS | 27460 | __end_cc_report 21:51:40 INFO - PROCESS | 27460 | __startTimestamp1482904300749__endTimestamp 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | ------- Summary: start ------- 21:51:40 INFO - PROCESS | 27460 | Number of tests: 14 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.50 stddev:1.84 (1.6%) stddev-sans-first:0.93 21:51:40 INFO - PROCESS | 27460 | Values: 111.0 117.0 115.0 116.0 117.0 116.0 117.0 117.0 115.0 115.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:128.50 stddev:12.40 (9.6%) stddev-sans-first:11.05 21:51:40 INFO - PROCESS | 27460 | Values: 153.0 125.0 125.0 156.0 129.0 127.0 126.0 125.0 145.0 128.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.26 (0.8%) stddev-sans-first:1.32 21:51:40 INFO - PROCESS | 27460 | Values: 162.0 162.0 163.0 162.0 163.0 161.0 160.0 161.0 159.0 161.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#3] audio-fft Cycles:10 Average:82.20 Median:77.00 stddev:11.46 (14.9%) stddev-sans-first:11.93 21:51:40 INFO - PROCESS | 27460 | Values: 76.0 77.0 78.0 110.0 77.0 77.0 77.0 77.0 96.0 77.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:85.00 stddev:9.99 (11.7%) stddev-sans-first:10.34 21:51:40 INFO - PROCESS | 27460 | Values: 85.0 86.0 112.0 84.0 85.0 85.0 103.0 86.0 86.0 100.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 21:51:40 INFO - PROCESS | 27460 | Values: 118.0 117.0 117.0 117.0 118.0 118.0 118.0 117.0 117.0 117.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 21:51:40 INFO - PROCESS | 27460 | Values: 150.0 149.0 149.0 149.0 149.0 150.0 149.0 149.0 148.0 149.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:51:40 INFO - PROCESS | 27460 | Values: 96.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:3.72 (5.8%) stddev-sans-first:3.90 21:51:40 INFO - PROCESS | 27460 | Values: 63.0 63.0 62.0 63.0 64.0 64.0 64.0 64.0 75.0 64.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:68.00 stddev:2.26 (3.3%) stddev-sans-first:0.50 21:51:40 INFO - PROCESS | 27460 | Values: 75.0 68.0 68.0 67.0 68.0 69.0 68.0 68.0 68.0 68.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:85.00 stddev:5.78 (6.8%) stddev-sans-first:6.04 21:51:40 INFO - PROCESS | 27460 | Values: 85.0 84.0 88.0 101.0 84.0 83.0 85.0 92.0 84.0 93.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:138.00 stddev:17.10 (12.4%) stddev-sans-first:17.97 21:51:40 INFO - PROCESS | 27460 | Values: 135.0 138.0 134.0 143.0 138.0 147.0 133.0 130.0 188.0 131.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:179.50 stddev:2.42 (1.4%) stddev-sans-first:2.47 21:51:40 INFO - PROCESS | 27460 | Values: 181.0 184.0 180.0 178.0 179.0 177.0 176.0 177.0 181.0 178.0 21:51:40 INFO - PROCESS | 27460 | 21:51:40 INFO - PROCESS | 27460 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:70.00 stddev:3.03 (4.3%) stddev-sans-first:3.20 21:51:40 INFO - PROCESS | 27460 | Values: 71.0 69.0 69.0 69.0 69.0 68.0 71.0 74.0 77.0 67.0 21:51:40 INFO - PROCESS | 27460 | -------- Summary: end -------- 21:51:40 INFO - PROCESS | 27460 | 21:51:41 INFO - TEST-INFO | 27460: exit 0 21:51:41 INFO - TEST-OK | kraken | took 64005ms 21:51:41 INFO - SUITE-END | took 1184s 21:51:42 INFO - Completed test suite (00:19:45) 21:51:42 INFO - javascript benchmark 21:51:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2517.9244622661313, 2547.3249588858944, 2576.896924123035, 2578.3554111472145, 2583.1862963166595, 4596.49438561758, 4656.464506581744, 4715.269133284342, 4725.385699859018, 4732.171869922763, 4676.251799712047, 4748.287756836477, 4769.117713223056, 4813.229883218686, 4857.53853383928, 4333.783310834454, 4356.041670832412, 4390.934135987963, 4394.143142087294, 4425.7565833879125, 4050.453188819501, 4459.353393757903, 4479.955200448003, 4500.909981800362, 4541.909161816761, 4419.116176764628, 4425.5353187915125, 4482.484514280867, 4495.100979804036, 4527.275635898257, 4279.422277992462, 4316.223079845627, 4319.632831209351, 4368.825246990116, 4397.208502469554, 4395.978020109879, 4401.185780630562, 4402.295632698753, 4433.401490798733, 4467.128909862578, 4756.191447453941, 4758.2386818108935, 4764.285357196413, 4783.234682450791, 4813.566778989873, 4948.752562371867, 4977.527134922177, 5026.79892804291, 5102.744862756847, 5111.182210846247, 5120.487951204887, 5131.948680513185, 5146.176611742141, 5171.560417364529, 5190.71451070191, 4758.2624693172265, 4804.327394164819, 4810.95189048112, 4817.8313759018265, 4856.732879691651, 5118.872028199325, 5150.716710580954, 5243.68537887728, 5248.9737551312, 5249.346436030113, 4969.726665033458, 5013.423456302499, 5014.323066385991, 5014.949850501521, 5021.874453138701, 5070.898582028339, 5071.847844564669, 5073.416557095908, 5087.567556757643, 5090.974545127325, 5037.7733002014265, 5067.366579177603, 5072.416672082684, 5108.157154069537, 5147.047796157723, 4988.052270068748, 4988.401391833003, 5008.223725322581, 5028.924566131511, 5038.244263360525, 4213.1784302060805, 4258.957410425918, 4302.612764851178, 4303.698741088157, 4346.521882592912, 3703.999920021579, 3704.4628528863163, 3725.5529336479253, 3728.067983004249, 3759.642833930772, 4263.70154089217, 4264.744115353089, 4268.594483524123, 4304.591063848992, 4310.31035034393, 4971.527704868032, 5073.518015788494, 5082.440931497532, 5083.110455670243, 5085.898282034413, 4217.093324935154, 4286.164197981426, 4313.223619748475, 4336.436263285753, 4376.234159022222, 4625.468071171791, 4627.930581041287, 4630.4443466784205, 4638.95361046392, 4665.930011049837, 4784.808607655655, 4787.664863459594, 4788.377510923628, 4790.664653474223, 4839.7096174229655, 5020.698758074527, 5051.09080365523, 5052.115879720961, 5172.922406163835, 5175.042617115846, 4688.789004494738, 4737.763111844325, 4774.355462012584, 4778.761061946958, 4782.995570930144, 4924.384451943507, 4962.875928101682, 4972.2790195422185, 5011.874703132451, 5014.122528557415, 4982.701037937735, 5012.974935125301, 5037.546620804145, 5049.495050494921, 5091.1854103343285, 4872.561469467765, 4880.6095512358215, 4904.166291730486, 4909.926351104665, 4931.605471562339, 4938.1358262304675, 4945.925811112908, 4956.983818317164, 4967.155583550732, 5031.4213865405945, 4904.754762261944, 4919.409670839522, 4929.975350123226, 4936.160852654985, 4952.603791696728, 5014.52362025607, 5016.197408414636, 5018.799248030038, 5022.4977502249485, 5060.089183946778, 4713.222318317439, 4761.476237613929, 4830.372051633265, 4863.392075990501, 4870.439899411613], "value": 4656.044599794874, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.9442508536604, 553.9639923404981, 554.9833504994849, 558.874253293009, 564.0946281218646, 27474.313142171457, 37584.248315033714, 39264.80367598162, 39303.21393572134, 42790.358144627855, 39739.80130099345, 40184.79907600465, 40188.19623607526, 40226.0, 40251.19497610046, 7433.145188303333, 7600.657970391318, 7782.260685234894, 7784.3772498200005, 7787.922120778805, 7401.555906645618, 9771.609135634566, 10020.298579099466, 10035.598576056986, 10371.222158338154, 9997.650082247123, 10053.64812231572, 10057.094861462467, 10080.34477758947, 10080.445575493344, 9669.661561845336, 9736.172425343855, 9741.951290243574, 9747.463889486075, 9750.46372449515, 9909.801803963881, 9976.95011524945, 9977.15194208493, 9978.950105249427, 9983.201343892544, 36760.0, 37205.81397093024, 37227.81386093079, 37243.0, 37264.44103338452, 3649.4525821126776, 3730.757500762442, 3749.887503374903, 3780.300644380771, 3796.924061518782, 25377.746222537724, 25518.489630207478, 25744.485110297876, 29444.41111177786, 29479.557806632918, 3129.358481511285, 3162.067190178903, 3167.144870884871, 3218.43677356464, 3300.884469043572, 228990.0, 229368.0, 229917.0, 230445.0, 230514.0, 49578.75210623924, 49918.75040624774, 49961.25058124131, 50056.24915626268, 50079.499205008215, 52160.73919630377, 52234.47765522296, 52303.0, 52368.47631523712, 52405.0, 12398.938005310096, 12781.105322627513, 12785.168964017495, 12803.807942880865, 12810.359482025862, 7284.526505777108, 7401.407887368998, 7857.882131768028, 9419.481928493999, 9469.431834089977, 14471.49349772753, 14817.629559261104, 14823.481178158712, 14825.629359266104, 15045.623859403602, 6303.212098487689, 6352.174217351715, 6496.740130394825, 6529.269743517972, 6532.379423954717, 717.1000394504968, 736.1276886889053, 744.5160645580416, 744.728174216418, 746.1642959884855, 26277.868610656824, 27348.863255683595, 27780.722192777812, 27955.0, 28007.159785206477, 806.5362416610473, 818.4639061414783, 831.6091437024588, 847.7538019111896, 864.101334612009, 14315.928420357832, 14461.0, 14570.0, 14618.561443156723, 14817.481388151364, 341340.0, 344531.0, 344806.0, 345301.0, 345412.0, 48761.0, 48829.5117048825, 48919.75540122276, 48929.26606100911, 48991.265131023065, 1119.5689659480995, 1162.2503485251966, 1172.6188988578747, 1174.8472698549133, 1196.4137572589368, 24581.87709061443, 25470.617940731274, 26316.4736705261, 26696.733032670203, 26847.597286040334, 40289.1942161161, 40922.38616420697, 40929.79535102305, 40960.59039409687, 40996.590034099274, 281485.0, 282557.0, 283641.0, 284576.0, 285129.0, 34488.13779655529, 34875.0, 34922.47616285707, 35158.82420587983, 35235.64764352324, 38251.426228607146, 38513.614863851006, 38842.80578597202, 39021.0, 39889.601103988585, 33724.49413258754, 33731.66268337285, 33852.83073584616, 33873.0, 34149.65850341465, 33123.83438082794, 33569.0, 33632.66367336296, 33644.83177584096, 33806.0, 33443.49834752432, 33847.153821154665, 33862.32275354528, 34137.48793768046, 37274.813625931696, 33732.66267337295, 34654.13364665904, 36820.07949801169, 37381.25237495289, 37470.0, 29829.254268643457, 30045.549316760702, 30120.24699382533, 30179.396412072074, 30652.540211897285], "value": 17286.17834833335, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.78472916250234, 371.56712430019047, 377.06299844885456, 378.56654131019985, 388.8561232344033, 12128.941716409772, 13012.0, 16187.676246475063, 16205.270762815688, 16211.51365459038, 15449.92275038626, 15873.444429444971, 16058.59853503663, 16220.675586488293, 16244.0, 3855.209682015194, 4883.072216278904, 5037.722925239111, 5047.747612619354, 5048.343715317003, 3899.3760998240286, 4011.0373510357595, 4012.879613611596, 4013.799310034501, 5817.214676018747, 5854.063349864023, 6030.155778191035, 6074.0, 6079.118527813464, 6082.604630699013, 5905.822825315226, 6030.517558595324, 6069.787557435513, 6073.848153796146, 6077.817665469999, 4585.977070114628, 4854.975725121351, 5326.7070311132875, 5329.307190065306, 5338.332708411449, 11565.595204167814, 11704.824427633592, 11758.823617645741, 11774.470148843237, 11786.057115430745, 2338.1232037985756, 2401.267613377919, 2442.1086303499274, 2446.60854263317, 2473.591857343536, 9011.729648110568, 9046.185843274137, 9204.171624553823, 11615.477303521362, 11703.005244554222, 1624.34214143271, 1657.4861792844256, 1661.2192269633254, 1682.7896512935883, 1698.3800912666911, 61022.389776102114, 61490.69254653743, 61584.69207653933, 63275.6836215816, 63779.0, 9489.0, 9578.13796758295, 9744.756381090388, 9919.553620087114, 9971.501424928869, 13055.477780888848, 13336.0, 13338.666533336744, 13342.0, 13353.265570393722, 5249.370075590953, 5345.893082138336, 5371.086915224343, 6563.0, 6564.573302735307, 5734.369219385864, 5786.768529258782, 5925.881482370329, 5929.762809487572, 5934.287885453687, 4132.524759652619, 4224.634204845296, 4280.357946308017, 4328.026194106301, 4411.404460397869, 1768.9380871669687, 1819.3086627081625, 1831.1759708131394, 1851.9166637501344, 1860.3767737807987, 742.3838214282133, 789.9842003159905, 794.4438892775081, 800.5476905548346, 802.7110240313483, 14637.364614623446, 14972.401103955937, 15212.847871521364, 15296.923515382352, 15897.205139742966, 777.2577188784697, 789.243932250749, 792.3502727763179, 800.1478425476848, 800.8158123631597, 8217.8356432871, 8278.8344233115, 8434.0, 8734.475931444007, 8894.73315800527, 319657.0, 334188.0, 337084.0, 341715.0, 341936.0, 54782.0, 55021.724891375285, 61613.07580386298, 64430.355696443374, 75814.24185758072, 802.450321529754, 839.7312859884901, 839.7858546070713, 842.3766412854566, 846.0693237438768, 14415.351309190903, 14440.711185776436, 14452.494162704255, 14475.348609312818, 15054.32255548481, 34839.65160348364, 34849.12877177989, 35473.0, 35505.644943550236, 35951.28097438089, 46018.76990615026, 46050.0, 46247.53752462432, 46357.0, 47570.286445704034, 6534.901976470262, 6782.593044417351, 6783.694733737092, 7218.855622887618, 7220.097487814023, 10975.341479511255, 11537.942310288396, 11601.651950441501, 11665.35840528779, 11967.581134660246, 9465.148136667733, 9668.226541876773, 9817.509124543887, 10159.288849780445, 10165.726130379075, 9737.951310243403, 9814.85277720849, 9940.502974851373, 10223.7444063899, 10320.174386049248, 9522.666706665234, 9599.760005999906, 9731.367461115005, 9902.702918912444, 10209.081182693297, 6891.517593768388, 6948.374646281859, 7105.04081948951, 7121.430285577245, 7126.251743566851, 7957.363410926996, 9355.0, 9383.343165978316, 9413.341066125306, 9721.465319407218], "value": 7627.376639489038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1277.2145130744589, 1284.7880099783536, 1288.859359466872, 1293.0883726972486, 1294.1975974895563, 7040.295970402964, 7483.326500614957, 7491.513051651646, 7499.512531685436, 7508.662110205033, 6481.74073037078, 6750.324967503259, 7039.0, 7218.458615603825, 7279.3084656957635, 2107.0518266779973, 2145.0990583954776, 2149.1833103420677, 2150.118451434912, 2150.924717634883, 2086.4247493142734, 2176.3688530326185, 2184.2355175688463, 2186.5189658275153, 2190.463336482556, 1722.905240211788, 1789.105447276362, 2083.0626218201796, 2157.870527768339, 2159.1903036361364, 2116.767155612889, 2117.9046942887608, 2137.700721898935, 2138.1981756841183, 2142.1431427428997, 2112.8204102651293, 2143.485563464773, 2145.281330754199, 2146.162996431393, 2148.3340164548886, 7375.778726638209, 7435.40516758658, 7440.888386674204, 7442.032535770315, 7446.925530744677, 4478.0, 4586.440610192551, 4623.283391074388, 4628.560286772751, 4629.467611224719, 2785.080923295308, 2947.557866320069, 2951.8228906265476, 2963.4221326841275, 3003.759699224057, 1059.1526778577108, 1096.2655021135859, 1138.9772204555863, 1175.488662431845, 1188.6196417146516, 9587.760305992337, 9895.752606184831, 9919.107280344731, 9931.602735890629, 9936.950315248378, 3988.2422339755353, 4054.0, 4070.247004304213, 4071.3689378146437, 4071.5114186297833, 7908.604569771604, 7912.0505539335645, 7913.406494512819, 7919.366450683931, 7922.801929951797, 4573.794179261941, 4575.95424045762, 4578.702384345007, 4579.198640237945, 4579.244424669959, 4158.313878210061, 4167.9583204168175, 4180.853670121531, 4190.979045104816, 4289.099289149313, 3175.5712978747574, 3237.4010808000594, 3293.407186706416, 3310.4537751270773, 3316.303576249015, 1003.1022235099545, 1023.1814548361281, 1029.5006921642996, 1032.8244198486275, 1034.8395998620226, 574.5282599789708, 680.5474359550942, 686.0361192524512, 705.0200221691853, 729.9671514781847, 4820.156472617278, 4841.709497430165, 4890.1931181355385, 4974.403071631427, 5088.821891233789, 767.9040119985002, 772.7913463364972, 806.846699127164, 806.9072056713553, 886.3618194899661, 3084.953725694116, 3145.4338219120777, 3154.6372167200607, 3163.9050828475183, 3191.872325107016, 8533.786655333543, 8980.8652870207, 9001.459912405277, 9005.324600654978, 9014.86477702835, 8763.809047738065, 8928.0, 8930.330225233134, 8940.776480588038, 8959.238464730572, 6857.794266172023, 6991.23096459389, 6993.89509157363, 7003.509754317149, 7010.684519196649, 4635.605973492156, 4774.116788394019, 4840.128776820206, 4983.426905905867, 5044.798208071783, 7708.113566939874, 7909.762707118795, 8126.959365203136, 8127.1466496017065, 8135.145809689898, 7997.200279971957, 8216.21945915137, 8251.174882511701, 8258.463199891989, 8266.83466330658, 6356.0, 6492.448141907991, 6493.383128602775, 6497.155369801895, 6515.771947981798, 6913.5506192097355, 6953.443724502129, 6968.5122041458635, 7005.894911576229, 7018.263082376481, 6300.432961033529, 6316.368363163647, 6319.210098737658, 6359.268684101203, 6369.299377068612, 6199.163112979871, 6277.246730392382, 6281.937180628135, 6296.968515157395, 6436.613803171825, 5290.312259406252, 5412.702301373462, 5453.836384908459, 5521.917171242354, 5527.585431092603, 4128.298189307885, 4131.07050913542, 4244.554321796167, 4266.424032755538, 4369.14801613695, 4147.97926010368, 4200.180964711852, 4238.0, 4268.914621707487, 4379.693421460594], "value": 3886.499342125544, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.12257926157196, 347.26726606859523, 348.4511893767317, 349.57002886449663, 355.8985689078612, 4776.713397196167, 4803.783829727662, 4819.2530157825595, 4838.4193896732395, 4882.218844984807, 4703.835365762199, 4735.271625856563, 4744.667873248865, 4757.666963312561, 4803.375561177051, 3646.5988741238575, 3706.9258614827686, 3874.9925019494854, 3911.0808959894543, 3936.5079365079296, 3887.7667339959694, 3906.964654366595, 3914.0606254498857, 3923.0, 3923.274194274054, 3884.348712210527, 3885.1841113366227, 3892.579208588873, 3901.102746368337, 3915.8237879295357, 3732.144909144493, 3751.193493398905, 3838.692904567628, 3842.8462861485787, 3927.5483319418204, 3451.2924850405607, 3471.968898194559, 3474.114100904253, 3474.74909032748, 3488.4418493040994, 4663.88340291492, 4686.625069994392, 4688.460827004904, 4691.179043667345, 4725.881852953669, 4253.425787518676, 4389.848974002715, 4393.51671316155, 4396.702972623084, 4447.911041779179, 5020.548150666421, 5051.797928082872, 5054.393472783289, 5068.214426763858, 5070.619703522214, 3836.9232615347537, 3841.3853783394525, 3843.7693738375788, 3851.114243723959, 3860.2665493556133, 5095.541401273903, 5280.653433374465, 5321.467853214725, 5325.121354976424, 5327.920081198785, 3918.0792513759243, 3927.6072392761066, 3936.7047471439755, 3938.4879965604287, 3960.940585891214, 3965.980170099189, 3967.3850553164307, 3985.4021896715726, 3992.5608183099835, 3997.400389941532, 4408.801603927831, 4426.9778651106535, 4430.291153415438, 4435.556444355603, 4435.977820110878, 4941.703497790144, 5023.246513023076, 5040.462658889074, 5042.672226305279, 5079.492050794891, 1314.6984485359426, 1319.7822359310799, 1333.2067419885152, 1333.9399727012308, 1340.5911197084788, 925.6575067225169, 962.7496850818837, 973.6592192732487, 979.7948523316361, 980.5783513089298, 4160.524487929677, 4178.6657067434535, 4272.6154646082, 4345.0, 4347.978260108743, 5174.793008279702, 5176.7411629418375, 5185.066687996122, 5189.636725429184, 5241.88512018064, 4343.1747967886, 4345.261305578059, 4378.299472084451, 4388.166248412856, 4388.978055109768, 2611.490759301918, 2653.0714250012343, 2675.8528280944734, 2685.516607010718, 2694.986525067362, 4681.531846815359, 4710.88222794426, 4714.787834547454, 4720.89059071118, 4733.95266047342, 5185.0, 5189.844304670866, 5193.2989046479, 5209.739513024334, 5225.790968361223, 4575.604440645635, 4802.519748025239, 4820.204666230103, 4820.30105634674, 4855.32025516428, 5187.896242075062, 5196.766145523385, 5207.947920520746, 5237.502437268454, 5254.579633629377, 5000.57495112905, 5157.535821776059, 5205.271262023395, 5214.13966695491, 5221.329174664071, 4818.397700287464, 4847.587955023862, 4864.343313652612, 4890.828820991248, 4899.069176856386, 3621.1671315598087, 3622.818859056984, 3664.193877346979, 3669.853205871735, 3671.0, 5278.287431196893, 5281.973590132025, 5286.577073834007, 5294.232336311136, 5324.361076670824, 4920.926186107283, 5081.92377114351, 5099.643024988215, 5105.821296254613, 5147.2279158126585, 5055.924161137659, 5056.584156436204, 5073.300444351184, 5086.821961231487, 5099.898002040012, 4887.975560122177, 5011.624128190328, 5040.974795126001, 5042.84871453857, 5055.143843728281, 4834.709917404967, 4984.900301993867, 5090.720010399464, 5117.0, 5149.9227511586605, 4862.3922009748785, 4868.659193856396, 4984.252362145562, 4992.2511623256805, 4993.702896667573], "value": 3868.7731039929454, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.6752891083371, 674.7233634209975, 723.1069629008174, 728.0, 755.4975940999234, 11475.540978360867, 12139.453724582392, 12166.574169904055, 12177.817332740005, 12254.080943929208, 13111.147775394593, 13298.608854742051, 13318.800217996737, 13320.733585328288, 13333.733325333487, 648.9902651460231, 676.1413005483058, 787.7439832054577, 814.5764202614666, 820.7496713502379, 830.6054624053556, 832.4464231286188, 833.6332013913858, 834.92903103236, 835.9968038353969, 878.1920633017608, 878.2710350409145, 881.951492667903, 882.0782282514788, 883.823235352932, 675.395521008697, 697.9092717946685, 701.8210356359095, 704.3695892176473, 867.4274978514181, 520.7188118416088, 607.8328459673581, 628.9465395441393, 630.6909614289011, 632.4118569730149, 13091.672708182276, 13127.606171814765, 13145.474181032747, 13161.802572961318, 13166.605001849959, 11498.770024599462, 11502.252353596989, 11768.411579420994, 11913.166078374517, 12124.575639852563, 11533.480993355319, 11534.424696442862, 11552.133589980785, 11579.421028948518, 11712.531498740125, 4159.459270294872, 4175.853845115406, 4193.51774545928, 4220.36694495825, 4229.894252643709, 61048.38951610471, 61908.38091619071, 63820.361796381905, 64524.0, 64736.734214302276, 9862.802743945082, 9946.602135914627, 10067.949660251652, 10140.797184056277, 10180.949095254624, 17636.911815441097, 17669.646607067785, 17697.38059167923, 17708.557286067953, 17715.379961701277, 6008.218931538872, 6123.203983482119, 6294.464970477469, 6312.021636646327, 6342.365763423621, 3164.5095010273444, 3215.8552865121123, 3256.5115232714807, 3269.4441944869427, 3276.819774912355, 2753.2703833484143, 2796.860156992142, 2822.379076603144, 3138.419392412411, 3282.179455136216, 542.2733537060358, 548.4625067433938, 550.0813641219173, 557.4007941462944, 560.3220103674518, 603.5955909540618, 624.8937680594219, 626.9154362952119, 628.9905651415232, 632.4339715954195, 7757.418193635387, 7790.104138024087, 7861.5464606700525, 7880.014998125234, 8131.512109273463, 487.75124686410186, 500.9549140577366, 511.7671459485925, 511.90529751996, 515.5772266741311, 3642.2715456908722, 3806.200697853482, 3826.885193444201, 3911.4328422378594, 4026.2350153471334, 59970.40029599735, 62520.68739656363, 63493.36506634967, 63753.0, 63840.0, 57167.42832571621, 57177.0, 57280.42719572751, 57756.0, 58183.92712510084, 42705.0, 46032.0, 48855.51144488652, 49872.50127498679, 50211.24683129756, 8066.395020373613, 8689.478631282143, 8782.473051616924, 8783.780405489913, 8946.329025322997, 33089.834550827094, 33093.503597445575, 33669.818298119724, 34541.65458345485, 34953.300933981685, 39084.80457597694, 40957.79521102375, 41735.373969389875, 42029.78985105055, 42134.36798448087, 9462.2430205582, 9671.758206044906, 9690.224782017563, 9698.660546880825, 9947.403155810674, 12218.20581662189, 12617.87382126167, 12795.552155674506, 12844.972402207988, 13641.590752277447, 11327.433628318386, 11546.19176657626, 11667.6499705009, 11974.101942354184, 12312.26126432417, 11483.598074067368, 11664.94167529157, 11780.0, 12151.088668350087, 12346.197497162655, 11368.488418021043, 11669.941650291694, 11759.88240117622, 11908.166428349932, 12170.75658486843, 10097.798044038931, 10228.181745460197, 10565.471726413494, 10602.151827853908, 10674.30617009923, 9347.298952578447, 9973.800523989334, 10005.249606279704, 10056.14522765544, 10090.54592543352], "value": 6192.2820486403525, "unit": "score"}], "name": "dromaeo_css", "value": 6206.602333460618, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 117.0, 115.0, 116.0, 117.0, 116.0, 117.0, 117.0, 115.0, 115.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 125.0, 125.0, 156.0, 129.0, 127.0, 126.0, 125.0, 145.0, 128.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 162.0, 163.0, 161.0, 160.0, 161.0, 159.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 110.0, 77.0, 77.0, 77.0, 77.0, 96.0, 77.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 112.0, 84.0, 85.0, 85.0, 103.0, 86.0, 86.0, 100.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 149.0, 149.0, 149.0, 150.0, 149.0, 149.0, 148.0, 149.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 64.0, 64.0, 64.0, 64.0, 75.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 68.0, 68.0, 67.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 88.0, 101.0, 84.0, 83.0, 85.0, 92.0, 84.0, 93.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 138.0, 134.0, 143.0, 138.0, 147.0, 133.0, 130.0, 188.0, 131.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 180.0, 178.0, 179.0, 177.0, 176.0, 177.0, 181.0, 178.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 69.0, 69.0, 68.0, 71.0, 74.0, 77.0, 67.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1558.7, "alertThreshold": 2.0}]} 21:51:42 INFO - Return code: 0 21:51:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:51:42 INFO - # TBPL SUCCESS # 21:51:42 INFO - Running post-action listener: _resource_record_post_action 21:51:42 INFO - [mozharness: 2016-12-28 05:51:42.518693Z] Finished run-tests step (success) 21:51:42 INFO - Running post-run listener: _resource_record_post_run 21:51:42 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395198464; Read time: 16; Write time: 227136 21:51:42 INFO - TinderboxPrint: CPU usage
13.1% 21:51:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 21:51:42 INFO - TinderboxPrint: I/O write bytes / time
395,198,464 / 227,136 21:51:42 INFO - TinderboxPrint: CPU idle
8,340.0 (86.9%) 21:51:42 INFO - TinderboxPrint: CPU user
1,209.5 (12.6%) 21:51:42 INFO - TinderboxPrint: Swap in / out
0 / 0 21:51:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99250176; Read time: 0; Write time: 52300 21:51:42 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 294805504; Read time: 0; Write time: 174812 21:51:43 INFO - Running post-run listener: _upload_blobber_files 21:51:43 INFO - Blob upload gear active. 21:51:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:51:43 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 21:51:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:51:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:51:43 INFO - (blobuploader) - INFO - Open directory for files ... 21:51:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:51:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:51:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:46 INFO - (blobuploader) - INFO - Done attempting. 21:51:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:51:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:51:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:47 INFO - (blobuploader) - INFO - Done attempting. 21:51:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:51:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:51:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:48 INFO - (blobuploader) - INFO - Done attempting. 21:51:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:51:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:51:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:50 INFO - (blobuploader) - INFO - Done attempting. 21:51:50 INFO - (blobuploader) - INFO - Iteration through files over. 21:51:50 INFO - Return code: 0 21:51:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:51:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:51:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17b93bd1e6e4b29cb9f99a1b9defa02c3f5c4c774d4a5846823c03408bf12bc984120e8f664fed41f3cdc07ecf6f881420c9b0e462899958ae54ea161114bdc6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f45ebb9c7b16ac3b2a22e393ed9225682b26706a9891cdfefea96852ff18a846334380a91637614bd638588e89668c7d231940443be0be745654fd66f97ff8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08d864dfea3b3836ceb85c815be45a785e6024c0c3cb5dd848a733753b673f5275836440de8f9ec347513687a2f48e9c47340bb05f910cc0de58b120413cac23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cfe7c752579453cfbfe45322fc2b7e2a1f4b0285bb66030ccbf9cdf832f0f53aabb0e491c2803361048ef9ad81fed55c510432f7c932897c7717c3f676c41e1f"} 21:51:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:51:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:51:50 INFO - Contents: 21:51:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17b93bd1e6e4b29cb9f99a1b9defa02c3f5c4c774d4a5846823c03408bf12bc984120e8f664fed41f3cdc07ecf6f881420c9b0e462899958ae54ea161114bdc6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f45ebb9c7b16ac3b2a22e393ed9225682b26706a9891cdfefea96852ff18a846334380a91637614bd638588e89668c7d231940443be0be745654fd66f97ff8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08d864dfea3b3836ceb85c815be45a785e6024c0c3cb5dd848a733753b673f5275836440de8f9ec347513687a2f48e9c47340bb05f910cc0de58b120413cac23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cfe7c752579453cfbfe45322fc2b7e2a1f4b0285bb66030ccbf9cdf832f0f53aabb0e491c2803361048ef9ad81fed55c510432f7c932897c7717c3f676c41e1f"} 21:51:50 INFO - Running post-run listener: copy_logs_to_upload_dir 21:51:50 INFO - Copying logs to upload dir... 21:51:50 INFO - mkdir: /builds/slave/test/build/upload/logs 21:51:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.415542 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-27 21:51:49.639485) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 21:51:49.643557) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17b93bd1e6e4b29cb9f99a1b9defa02c3f5c4c774d4a5846823c03408bf12bc984120e8f664fed41f3cdc07ecf6f881420c9b0e462899958ae54ea161114bdc6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f45ebb9c7b16ac3b2a22e393ed9225682b26706a9891cdfefea96852ff18a846334380a91637614bd638588e89668c7d231940443be0be745654fd66f97ff8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08d864dfea3b3836ceb85c815be45a785e6024c0c3cb5dd848a733753b673f5275836440de8f9ec347513687a2f48e9c47340bb05f910cc0de58b120413cac23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cfe7c752579453cfbfe45322fc2b7e2a1f4b0285bb66030ccbf9cdf832f0f53aabb0e491c2803361048ef9ad81fed55c510432f7c932897c7717c3f676c41e1f"} build_url:https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011680 build_url: 'https://queue.taskcluster.net/v1/task/abQlKZ6pQY6YW1YRk_1Upw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17b93bd1e6e4b29cb9f99a1b9defa02c3f5c4c774d4a5846823c03408bf12bc984120e8f664fed41f3cdc07ecf6f881420c9b0e462899958ae54ea161114bdc6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f45ebb9c7b16ac3b2a22e393ed9225682b26706a9891cdfefea96852ff18a846334380a91637614bd638588e89668c7d231940443be0be745654fd66f97ff8a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/08d864dfea3b3836ceb85c815be45a785e6024c0c3cb5dd848a733753b673f5275836440de8f9ec347513687a2f48e9c47340bb05f910cc0de58b120413cac23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cfe7c752579453cfbfe45322fc2b7e2a1f4b0285bb66030ccbf9cdf832f0f53aabb0e491c2803361048ef9ad81fed55c510432f7c932897c7717c3f676c41e1f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 21:51:49.669253) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:51:49.669577) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1482900933.180534-1714659389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004375 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 21:51:49.755120) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 21:51:49.755469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 21:51:49.755790) ========= ========= Total master_lag: 0.19 =========