builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1492445744.85 results: success (0) revision: 5049738b566005c47b8b6e4c07639340ff09a15a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:44.854854) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:44.855321) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:44.855605) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:44.870590) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:44.870948) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-17 09:15:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.1M=0s 2017-04-17 09:15:45 (32.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148070 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.035016) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.035509) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020930 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.086327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.086687) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5049738b566005c47b8b6e4c07639340ff09a15a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5049738b566005c47b8b6e4c07639340ff09a15a --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=78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-17 09:15:45,206 truncating revision to first 12 chars 2017-04-17 09:15:45,206 Setting DEBUG logging. 2017-04-17 09:15:45,206 attempt 1/10 2017-04-17 09:15:45,206 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5049738b5660?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-17 09:15:45,535 unpacking tar archive at: mozilla-aurora-5049738b5660/testing/mozharness/ program finished with exit code 0 elapsedTime=0.671800 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.772240) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.772574) ========= script_repo_revision: 5049738b566005c47b8b6e4c07639340ff09a15a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.772934) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.773193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 09:15:45.782926) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-17 09:15:45.783282) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:45 INFO - MultiFileLogger online at 20170417 09:15:45 in /builds/slave/test 09:15:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:15:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:45 INFO - {'append_to_log': False, 09:15:45 INFO - 'base_work_dir': '/builds/slave/test', 09:15:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:15:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:45 INFO - 'branch': 'Mozilla-Aurora', 09:15:45 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:45 INFO - 'config_files': ('talos/linux_config.py',), 09:15:45 INFO - 'default_actions': ('clobber', 09:15:45 INFO - 'read-buildbot-config', 09:15:45 INFO - 'download-and-extract', 09:15:46 INFO - 'populate-webroot', 09:15:46 INFO - 'create-virtualenv', 09:15:46 INFO - 'install', 09:15:46 INFO - 'run-tests'), 09:15:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:46 INFO - 'download_minidump_stackwalk': True, 09:15:46 INFO - 'download_symbols': 'ondemand', 09:15:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:46 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:46 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:46 INFO - 'gecko_profile': False, 09:15:46 INFO - 'gecko_profile_interval': 0, 09:15:46 INFO - 'installer_path': 'installer.exe', 09:15:46 INFO - 'log_level': 'info', 09:15:46 INFO - 'log_name': 'talos', 09:15:46 INFO - 'log_to_console': True, 09:15:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:46 INFO - 'opt_config_files': (), 09:15:46 INFO - 'pip_index': False, 09:15:46 INFO - 'suite': 'dromaeojs', 09:15:46 INFO - 'system_bits': '32', 09:15:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:46 INFO - 'title': 'talos-linux64-ix-019', 09:15:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:46 INFO - 'use_talos_json': True, 09:15:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:46 INFO - 'work_dir': 'build'} 09:15:46 INFO - [mozharness: 2017-04-17 16:15:46.002381Z] Running clobber step. 09:15:46 INFO - Running pre-action listener: _resource_record_pre_action 09:15:46 INFO - Running main action method: clobber 09:15:46 INFO - rmtree: /builds/slave/test/build 09:15:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:46 INFO - Running post-action listener: _resource_record_post_action 09:15:46 INFO - [mozharness: 2017-04-17 16:15:46.929554Z] Finished clobber step (success) 09:15:46 INFO - [mozharness: 2017-04-17 16:15:46.929654Z] Running read-buildbot-config step. 09:15:46 INFO - Running pre-action listener: _resource_record_pre_action 09:15:46 INFO - Running main action method: read_buildbot_config 09:15:46 INFO - Using buildbot properties: 09:15:46 INFO - { 09:15:46 INFO - "project": "", 09:15:46 INFO - "product": "firefox", 09:15:46 INFO - "who": "ffxbld@noreply.mozilla.org", 09:15:46 INFO - "installer_path": "public/build/target.tar.bz2", 09:15:46 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:15:46 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 09:15:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:15:46 INFO - "stage_platform": "linux64-pgo", 09:15:46 INFO - "basedir": "/builds/slave/test", 09:15:46 INFO - "buildnumber": 839, 09:15:46 INFO - "platform": "ubuntu64_hw", 09:15:46 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:15:46 INFO - "slavebuilddir": "test", 09:15:46 INFO - "taskId": "NDY-KF46SvubvJJX0nNZEA", 09:15:46 INFO - "branch": "mozilla-aurora", 09:15:46 INFO - "script_repo_revision": "production", 09:15:46 INFO - "revision": "5049738b566005c47b8b6e4c07639340ff09a15a", 09:15:46 INFO - "slavename": "talos-linux64-ix-019", 09:15:46 INFO - "repo_path": "releases/mozilla-aurora" 09:15:46 INFO - } 09:15:46 INFO - Finding installer, test and symbols from parent task. 09:15:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDY-KF46SvubvJJX0nNZEA'}, attempt #1 09:15:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDY-KF46SvubvJJX0nNZEA'}, attempt #1 09:15:47 INFO - Task dependencies: CM8mFEhLRNa_MrlhhwCd0g 09:15:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g'}, attempt #1 09:15:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2 09:15:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/test_packages.json 09:15:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.crashreporter-symbols.zip 09:15:49 INFO - Running post-action listener: _resource_record_post_action 09:15:49 INFO - [mozharness: 2017-04-17 16:15:49.341337Z] Finished read-buildbot-config step (success) 09:15:49 INFO - [mozharness: 2017-04-17 16:15:49.341553Z] Running download-and-extract step. 09:15:49 INFO - Running pre-action listener: _resource_record_pre_action 09:15:49 INFO - Running main action method: download_and_extract 09:15:49 INFO - mkdir: /builds/slave/test/build/tests 09:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:49 INFO - trying https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.test_packages.json 09:15:49 INFO - Downloading https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:15:49 INFO - Downloaded 896 bytes. 09:15:49 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:15:49 INFO - Using the following test package requirements: 09:15:49 INFO - {u'common': [u'target.common.tests.zip'], 09:15:49 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:15:49 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:15:49 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:15:49 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:15:49 INFO - u'mozbase': [u'target.common.tests.zip'], 09:15:49 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:15:49 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:15:49 INFO - u'web-platform': [u'target.common.tests.zip', 09:15:49 INFO - u'target.web-platform.tests.zip'], 09:15:49 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:15:49 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:15:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.common.tests.zip 09:15:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:15:49 INFO - Fetch https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.common.tests.zip into memory 09:15:50 INFO - Expected file size: 59542471 09:15:50 INFO - Obtained file size: 59542471 09:16:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:16:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.common.tests.zip 09:16:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:16:00 INFO - Fetch https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.common.tests.zip into memory 09:16:01 INFO - Expected file size: 59542471 09:16:01 INFO - Obtained file size: 59542471 09:16:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.talos.tests.zip 09:16:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:16:12 INFO - Fetch https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.talos.tests.zip into memory 09:16:13 INFO - Expected file size: 14404424 09:16:13 INFO - Obtained file size: 14404424 09:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:13 INFO - trying https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2 09:16:13 INFO - Downloading https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:16:14 INFO - Downloaded 66726236 bytes. 09:16:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2 09:16:14 INFO - mkdir: /builds/slave/test/properties 09:16:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:14 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:14 INFO - Contents: 09:16:14 INFO - build_url:https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2 09:16:14 INFO - Running post-action listener: _resource_record_post_action 09:16:14 INFO - Running post-action listener: set_extra_try_arguments 09:16:14 INFO - [mozharness: 2017-04-17 16:16:14.431891Z] Finished download-and-extract step (success) 09:16:14 INFO - [mozharness: 2017-04-17 16:16:14.431987Z] Running populate-webroot step. 09:16:14 INFO - Running pre-action listener: _resource_record_pre_action 09:16:14 INFO - Running main action method: populate_webroot 09:16:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:14 INFO - '--tppagecycles', 09:16:14 INFO - '7'], 09:16:14 INFO - 'tests': ['tsvgm']}}, 09:16:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:14 INFO - 'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:14 INFO - 'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['damp', 'tps']}, 09:16:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:14 INFO - 'tests': ['damp', 'tps']}, 09:16:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['dromaeo_dom']}, 09:16:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['basic_compositor_video']}, 09:16:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['a11yr', 09:16:14 INFO - 'ts_paint', 09:16:14 INFO - 'tpaint', 09:16:14 INFO - 'sessionrestore', 09:16:14 INFO - 'sessionrestore_no_auto_restore', 09:16:14 INFO - 'tabpaint']}, 09:16:14 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:14 INFO - 'ts_paint', 09:16:14 INFO - 'tpaint', 09:16:14 INFO - 'sessionrestore', 09:16:14 INFO - 'sessionrestore_no_auto_restore', 09:16:14 INFO - 'tabpaint']}, 09:16:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:14 INFO - 'ts_paint', 09:16:14 INFO - 'tpaint', 09:16:14 INFO - 'sessionrestore', 09:16:14 INFO - 'sessionrestore_no_auto_restore', 09:16:14 INFO - 'tabpaint']}, 09:16:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:14 INFO - 'ts_paint', 09:16:14 INFO - 'tpaint', 09:16:14 INFO - 'sessionrestore', 09:16:14 INFO - 'sessionrestore_no_auto_restore', 09:16:14 INFO - 'tabpaint']}, 09:16:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['a11yr', 09:16:14 INFO - 'ts_paint', 09:16:14 INFO - 'tpaint', 09:16:14 INFO - 'sessionrestore', 09:16:14 INFO - 'sessionrestore_no_auto_restore', 09:16:14 INFO - 'tabpaint']}, 09:16:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['a11yr', 09:16:14 INFO - 'ts_paint', 09:16:14 INFO - 'tpaint', 09:16:14 INFO - 'sessionrestore', 09:16:14 INFO - 'sessionrestore_no_auto_restore', 09:16:14 INFO - 'tabpaint']}, 09:16:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['tsvgx', 09:16:14 INFO - 'tsvgr_opacity', 09:16:14 INFO - 'tart', 09:16:14 INFO - 'tscrollx', 09:16:14 INFO - 'cart', 09:16:14 INFO - 'tsvg_static']}, 09:16:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:14 INFO - 'tsvgr_opacity', 09:16:14 INFO - 'tart', 09:16:14 INFO - 'tscrollx', 09:16:14 INFO - 'cart', 09:16:14 INFO - 'tsvg_static']}, 09:16:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:14 INFO - 'talos_options': ['--disable-e10s'], 09:16:14 INFO - 'tests': ['tp5o']}, 09:16:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:14 INFO - 'talos_options': ['--disable-e10s', 09:16:14 INFO - '--xperf_path', 09:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:14 INFO - 'tests': ['tp5n']}, 09:16:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:14 INFO - 'talos_options': ['--xperf_path', 09:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:14 INFO - 'tests': ['tp5n']}}, 09:16:14 INFO - 'talos.zip': {'path': '', 09:16:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:14 INFO - Running post-action listener: _resource_record_post_action 09:16:14 INFO - [mozharness: 2017-04-17 16:16:14.445104Z] Finished populate-webroot step (success) 09:16:14 INFO - [mozharness: 2017-04-17 16:16:14.445194Z] Running create-virtualenv step. 09:16:14 INFO - Running pre-action listener: _resource_record_pre_action 09:16:14 INFO - Running main action method: create_virtualenv 09:16:14 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:16:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:14 INFO - Using real prefix '/usr' 09:16:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:15 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:17 INFO - Installing pip.................done. 09:16:17 INFO - Return code: 0 09:16:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:17 INFO - Reading from file tmpfile_stdout 09:16:17 INFO - Output received: 09:16:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:16:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:16:17 INFO - Using env: {'DISPLAY': ':0', 09:16:17 INFO - 'HOME': '/home/cltbld', 09:16:17 INFO - 'LANG': 'en_US.UTF-8', 09:16:17 INFO - 'LANGUAGE': 'en_US:en', 09:16:17 INFO - 'LOGNAME': 'cltbld', 09:16:17 INFO - 'MAIL': '/var/mail/cltbld', 09:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:17 INFO - 'MOZ_NO_REMOTE': '1', 09:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:17 INFO - 'NO_EM_RESTART': '1', 09:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:17 INFO - 'PWD': '/builds/slave/test', 09:16:17 INFO - 'SHELL': '/bin/bash', 09:16:17 INFO - 'SHLVL': '1', 09:16:17 INFO - 'TERM': 'linux', 09:16:17 INFO - 'TMOUT': '86400', 09:16:17 INFO - 'USER': 'cltbld', 09:16:17 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:17 INFO - '_': '/tools/buildbot/bin/python'} 09:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:16:17 INFO - Cleaning up... 09:16:17 INFO - Return code: 0 09:16:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:16:17 INFO - Using env: {'DISPLAY': ':0', 09:16:17 INFO - 'HOME': '/home/cltbld', 09:16:17 INFO - 'LANG': 'en_US.UTF-8', 09:16:17 INFO - 'LANGUAGE': 'en_US:en', 09:16:17 INFO - 'LOGNAME': 'cltbld', 09:16:17 INFO - 'MAIL': '/var/mail/cltbld', 09:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:17 INFO - 'MOZ_NO_REMOTE': '1', 09:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:17 INFO - 'NO_EM_RESTART': '1', 09:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:17 INFO - 'PWD': '/builds/slave/test', 09:16:17 INFO - 'SHELL': '/bin/bash', 09:16:17 INFO - 'SHLVL': '1', 09:16:17 INFO - 'TERM': 'linux', 09:16:17 INFO - 'TMOUT': '86400', 09:16:17 INFO - 'USER': 'cltbld', 09:16:17 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:17 INFO - '_': '/tools/buildbot/bin/python'} 09:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:17 INFO - Downloading/unpacking psutil>=3.1.1 09:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:19 INFO - Installing collected packages: psutil 09:16:19 INFO - Running setup.py install for psutil 09:16:19 INFO - building 'psutil._psutil_linux' extension 09:16:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:16:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:16:20 INFO - building 'psutil._psutil_posix' extension 09:16:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:16:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:16:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:20 INFO - Successfully installed psutil 09:16:20 INFO - Cleaning up... 09:16:20 INFO - Return code: 0 09:16:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:16:20 INFO - Using env: {'DISPLAY': ':0', 09:16:20 INFO - 'HOME': '/home/cltbld', 09:16:20 INFO - 'LANG': 'en_US.UTF-8', 09:16:20 INFO - 'LANGUAGE': 'en_US:en', 09:16:20 INFO - 'LOGNAME': 'cltbld', 09:16:20 INFO - 'MAIL': '/var/mail/cltbld', 09:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:20 INFO - 'MOZ_NO_REMOTE': '1', 09:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:20 INFO - 'NO_EM_RESTART': '1', 09:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:20 INFO - 'PWD': '/builds/slave/test', 09:16:20 INFO - 'SHELL': '/bin/bash', 09:16:20 INFO - 'SHLVL': '1', 09:16:20 INFO - 'TERM': 'linux', 09:16:20 INFO - 'TMOUT': '86400', 09:16:20 INFO - 'USER': 'cltbld', 09:16:20 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:20 INFO - '_': '/tools/buildbot/bin/python'} 09:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:22 INFO - Installing collected packages: mozsystemmonitor 09:16:22 INFO - Running setup.py install for mozsystemmonitor 09:16:22 INFO - Successfully installed mozsystemmonitor 09:16:22 INFO - Cleaning up... 09:16:22 INFO - Return code: 0 09:16:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:16:22 INFO - Using env: {'DISPLAY': ':0', 09:16:22 INFO - 'HOME': '/home/cltbld', 09:16:22 INFO - 'LANG': 'en_US.UTF-8', 09:16:22 INFO - 'LANGUAGE': 'en_US:en', 09:16:22 INFO - 'LOGNAME': 'cltbld', 09:16:22 INFO - 'MAIL': '/var/mail/cltbld', 09:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:22 INFO - 'MOZ_NO_REMOTE': '1', 09:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:22 INFO - 'NO_EM_RESTART': '1', 09:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:22 INFO - 'PWD': '/builds/slave/test', 09:16:22 INFO - 'SHELL': '/bin/bash', 09:16:22 INFO - 'SHLVL': '1', 09:16:22 INFO - 'TERM': 'linux', 09:16:22 INFO - 'TMOUT': '86400', 09:16:22 INFO - 'USER': 'cltbld', 09:16:22 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:22 INFO - '_': '/tools/buildbot/bin/python'} 09:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:23 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:25 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:25 INFO - warning: no files found matching '*.txt' 09:16:25 INFO - no previously-included directories found matching 'build' 09:16:25 INFO - no previously-included directories found matching 'dist' 09:16:25 INFO - no previously-included directories found matching '.git*' 09:16:25 INFO - Installing collected packages: jsonschema, functools32 09:16:25 INFO - Running setup.py install for functools32 09:16:25 INFO - warning: no files found matching '*.txt' 09:16:25 INFO - no previously-included directories found matching 'build' 09:16:25 INFO - no previously-included directories found matching 'dist' 09:16:25 INFO - no previously-included directories found matching '.git*' 09:16:25 INFO - Successfully installed jsonschema functools32 09:16:25 INFO - Cleaning up... 09:16:25 INFO - Return code: 0 09:16:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:16:25 INFO - Using env: {'DISPLAY': ':0', 09:16:25 INFO - 'HOME': '/home/cltbld', 09:16:25 INFO - 'LANG': 'en_US.UTF-8', 09:16:25 INFO - 'LANGUAGE': 'en_US:en', 09:16:25 INFO - 'LOGNAME': 'cltbld', 09:16:25 INFO - 'MAIL': '/var/mail/cltbld', 09:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:25 INFO - 'MOZ_NO_REMOTE': '1', 09:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:25 INFO - 'NO_EM_RESTART': '1', 09:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:25 INFO - 'PWD': '/builds/slave/test', 09:16:25 INFO - 'SHELL': '/bin/bash', 09:16:25 INFO - 'SHLVL': '1', 09:16:25 INFO - 'TERM': 'linux', 09:16:25 INFO - 'TMOUT': '86400', 09:16:25 INFO - 'USER': 'cltbld', 09:16:25 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:25 INFO - '_': '/tools/buildbot/bin/python'} 09:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:25 INFO - Cleaning up... 09:16:25 INFO - Return code: 0 09:16:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:25 INFO - Using env: {'DISPLAY': ':0', 09:16:25 INFO - 'HOME': '/home/cltbld', 09:16:25 INFO - 'LANG': 'en_US.UTF-8', 09:16:25 INFO - 'LANGUAGE': 'en_US:en', 09:16:25 INFO - 'LOGNAME': 'cltbld', 09:16:25 INFO - 'MAIL': '/var/mail/cltbld', 09:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:25 INFO - 'MOZ_NO_REMOTE': '1', 09:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:25 INFO - 'NO_EM_RESTART': '1', 09:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:25 INFO - 'PWD': '/builds/slave/test', 09:16:25 INFO - 'SHELL': '/bin/bash', 09:16:25 INFO - 'SHLVL': '1', 09:16:25 INFO - 'TERM': 'linux', 09:16:25 INFO - 'TMOUT': '86400', 09:16:25 INFO - 'USER': 'cltbld', 09:16:25 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:25 INFO - '_': '/tools/buildbot/bin/python'} 09:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:28 INFO - Downloading docopt-0.6.1.tar.gz 09:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:28 INFO - Running setup.py install for blobuploader 09:16:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:28 INFO - Running setup.py install for requests 09:16:29 INFO - Running setup.py install for docopt 09:16:29 INFO - Successfully installed blobuploader requests docopt 09:16:29 INFO - Cleaning up... 09:16:29 INFO - Return code: 0 09:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:29 INFO - Using env: {'DISPLAY': ':0', 09:16:29 INFO - 'HOME': '/home/cltbld', 09:16:29 INFO - 'LANG': 'en_US.UTF-8', 09:16:29 INFO - 'LANGUAGE': 'en_US:en', 09:16:29 INFO - 'LOGNAME': 'cltbld', 09:16:29 INFO - 'MAIL': '/var/mail/cltbld', 09:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:29 INFO - 'MOZ_NO_REMOTE': '1', 09:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:29 INFO - 'NO_EM_RESTART': '1', 09:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:29 INFO - 'PWD': '/builds/slave/test', 09:16:29 INFO - 'SHELL': '/bin/bash', 09:16:29 INFO - 'SHLVL': '1', 09:16:29 INFO - 'TERM': 'linux', 09:16:29 INFO - 'TMOUT': '86400', 09:16:29 INFO - 'USER': 'cltbld', 09:16:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:29 INFO - '_': '/tools/buildbot/bin/python'} 09:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:29 INFO - Running setup.py (path:/tmp/pip-8u7Mnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:29 INFO - Running setup.py (path:/tmp/pip-rDYLTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:29 INFO - Running setup.py (path:/tmp/pip-eEyncl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:29 INFO - Running setup.py (path:/tmp/pip-CjquqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:29 INFO - Running setup.py (path:/tmp/pip-ixyE0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:29 INFO - Running setup.py (path:/tmp/pip-q3KV5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:30 INFO - Running setup.py (path:/tmp/pip-YxjlP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:30 INFO - Running setup.py (path:/tmp/pip-IrTq5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:30 INFO - Running setup.py (path:/tmp/pip-Olsz2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:30 INFO - Running setup.py (path:/tmp/pip-m63SVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:30 INFO - Running setup.py (path:/tmp/pip-NL3Z05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:30 INFO - Running setup.py (path:/tmp/pip-y0dzRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:30 INFO - Running setup.py (path:/tmp/pip-bbAqtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:30 INFO - Running setup.py (path:/tmp/pip-feDxMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:30 INFO - Running setup.py (path:/tmp/pip-5fvCP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:30 INFO - Running setup.py (path:/tmp/pip-VUcO9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:30 INFO - Running setup.py (path:/tmp/pip-myFMf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:30 INFO - Running setup.py install for manifestparser 09:16:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:30 INFO - Running setup.py install for mozcrash 09:16:31 INFO - Running setup.py install for mozdebug 09:16:31 INFO - Running setup.py install for mozdevice 09:16:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Running setup.py install for mozfile 09:16:31 INFO - Running setup.py install for mozhttpd 09:16:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Running setup.py install for mozinfo 09:16:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Running setup.py install for mozInstall 09:16:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Running setup.py install for mozleak 09:16:31 INFO - Running setup.py install for mozlog 09:16:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Running setup.py install for moznetwork 09:16:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:31 INFO - Running setup.py install for mozprocess 09:16:32 INFO - Running setup.py install for mozprofile 09:16:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:32 INFO - Running setup.py install for mozrunner 09:16:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:32 INFO - Running setup.py install for mozscreenshot 09:16:32 INFO - Running setup.py install for moztest 09:16:32 INFO - Running setup.py install for mozversion 09:16:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:32 INFO - Cleaning up... 09:16:32 INFO - Return code: 0 09:16:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:32 INFO - Using env: {'DISPLAY': ':0', 09:16:32 INFO - 'HOME': '/home/cltbld', 09:16:32 INFO - 'LANG': 'en_US.UTF-8', 09:16:32 INFO - 'LANGUAGE': 'en_US:en', 09:16:32 INFO - 'LOGNAME': 'cltbld', 09:16:32 INFO - 'MAIL': '/var/mail/cltbld', 09:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:32 INFO - 'MOZ_NO_REMOTE': '1', 09:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:32 INFO - 'NO_EM_RESTART': '1', 09:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:32 INFO - 'PWD': '/builds/slave/test', 09:16:32 INFO - 'SHELL': '/bin/bash', 09:16:32 INFO - 'SHLVL': '1', 09:16:32 INFO - 'TERM': 'linux', 09:16:32 INFO - 'TMOUT': '86400', 09:16:32 INFO - 'USER': 'cltbld', 09:16:32 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:32 INFO - '_': '/tools/buildbot/bin/python'} 09:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:32 INFO - Running setup.py (path:/tmp/pip-pnx2RJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:32 INFO - Running setup.py (path:/tmp/pip-ADr7x3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:32 INFO - Running setup.py (path:/tmp/pip-gfpihE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:33 INFO - Running setup.py (path:/tmp/pip-lQ0UnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:33 INFO - Running setup.py (path:/tmp/pip-2FoFqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:33 INFO - Running setup.py (path:/tmp/pip-1i5V18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:33 INFO - Running setup.py (path:/tmp/pip-Urs9FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:33 INFO - Running setup.py (path:/tmp/pip-UX0bag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:33 INFO - Running setup.py (path:/tmp/pip-odHpgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:33 INFO - Running setup.py (path:/tmp/pip-80f1cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:33 INFO - Running setup.py (path:/tmp/pip-CKAjqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:33 INFO - Running setup.py (path:/tmp/pip-7I2gRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:33 INFO - Running setup.py (path:/tmp/pip-c9ftyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:33 INFO - Running setup.py (path:/tmp/pip-9NrEZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:33 INFO - Running setup.py (path:/tmp/pip-15ZoBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:33 INFO - Running setup.py (path:/tmp/pip-_pcDmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:34 INFO - Running setup.py (path:/tmp/pip-RcD9cV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:36 INFO - Downloading blessings-1.6.tar.gz 09:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:36 INFO - Installing collected packages: blessings 09:16:36 INFO - Running setup.py install for blessings 09:16:36 INFO - Successfully installed blessings 09:16:36 INFO - Cleaning up... 09:16:36 INFO - Return code: 0 09:16:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:36 INFO - Reading from file tmpfile_stdout 09:16:36 INFO - Current package versions: 09:16:36 INFO - argparse == 1.2.1 09:16:36 INFO - blessings == 1.6 09:16:36 INFO - blobuploader == 1.2.4 09:16:36 INFO - docopt == 0.6.1 09:16:36 INFO - functools32 == 3.2.3-2 09:16:36 INFO - jsonschema == 2.5.1 09:16:36 INFO - manifestparser == 1.1 09:16:36 INFO - mozInstall == 1.12 09:16:36 INFO - mozcrash == 1.0 09:16:36 INFO - mozdebug == 0.1 09:16:36 INFO - mozdevice == 0.49 09:16:36 INFO - mozfile == 1.2 09:16:36 INFO - mozhttpd == 0.7 09:16:36 INFO - mozinfo == 0.9 09:16:36 INFO - mozleak == 0.1 09:16:36 INFO - mozlog == 3.4 09:16:36 INFO - moznetwork == 0.27 09:16:36 INFO - mozprocess == 0.25 09:16:36 INFO - mozprofile == 0.28 09:16:36 INFO - mozrunner == 6.13 09:16:36 INFO - mozscreenshot == 0.1 09:16:36 INFO - mozsystemmonitor == 0.3 09:16:36 INFO - moztest == 0.8 09:16:36 INFO - mozversion == 1.4 09:16:36 INFO - psutil == 3.1.1 09:16:36 INFO - requests == 1.2.3 09:16:36 INFO - wsgiref == 0.1.2 09:16:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:36 INFO - Using env: {'DISPLAY': ':0', 09:16:36 INFO - 'HOME': '/home/cltbld', 09:16:36 INFO - 'LANG': 'en_US.UTF-8', 09:16:36 INFO - 'LANGUAGE': 'en_US:en', 09:16:36 INFO - 'LOGNAME': 'cltbld', 09:16:36 INFO - 'MAIL': '/var/mail/cltbld', 09:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:36 INFO - 'MOZ_NO_REMOTE': '1', 09:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:36 INFO - 'NO_EM_RESTART': '1', 09:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:36 INFO - 'PWD': '/builds/slave/test', 09:16:36 INFO - 'SHELL': '/bin/bash', 09:16:36 INFO - 'SHLVL': '1', 09:16:36 INFO - 'TERM': 'linux', 09:16:36 INFO - 'TMOUT': '86400', 09:16:36 INFO - 'USER': 'cltbld', 09:16:36 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:36 INFO - '_': '/tools/buildbot/bin/python'} 09:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:16:36 INFO - Cleaning up... 09:16:36 INFO - Return code: 0 09:16:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:16:36 INFO - Using env: {'DISPLAY': ':0', 09:16:36 INFO - 'HOME': '/home/cltbld', 09:16:36 INFO - 'LANG': 'en_US.UTF-8', 09:16:36 INFO - 'LANGUAGE': 'en_US:en', 09:16:36 INFO - 'LOGNAME': 'cltbld', 09:16:36 INFO - 'MAIL': '/var/mail/cltbld', 09:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:36 INFO - 'MOZ_NO_REMOTE': '1', 09:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:36 INFO - 'NO_EM_RESTART': '1', 09:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:36 INFO - 'PWD': '/builds/slave/test', 09:16:36 INFO - 'SHELL': '/bin/bash', 09:16:36 INFO - 'SHLVL': '1', 09:16:36 INFO - 'TERM': 'linux', 09:16:36 INFO - 'TMOUT': '86400', 09:16:36 INFO - 'USER': 'cltbld', 09:16:36 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:36 INFO - '_': '/tools/buildbot/bin/python'} 09:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:37 INFO - Cleaning up... 09:16:37 INFO - Return code: 0 09:16:37 INFO - Running post-action listener: _resource_record_post_action 09:16:37 INFO - Running post-action listener: _start_resource_monitoring 09:16:37 INFO - Starting resource monitoring. 09:16:37 INFO - [mozharness: 2017-04-17 16:16:37.086497Z] Finished create-virtualenv step (success) 09:16:37 INFO - [mozharness: 2017-04-17 16:16:37.087169Z] Running install step. 09:16:37 INFO - Running pre-action listener: _resource_record_pre_action 09:16:37 INFO - Running main action method: install 09:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:37 INFO - Reading from file tmpfile_stdout 09:16:37 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:37 INFO - Reading from file tmpfile_stdout 09:16:37 INFO - Output received: 09:16:37 INFO - Usage: mozinstall [options] installer 09:16:37 INFO - Options: 09:16:37 INFO - -h, --help show this help message and exit 09:16:37 INFO - -d DEST, --destination=DEST 09:16:37 INFO - Directory to install application into. [default: 09:16:37 INFO - "/builds/slave/test"] 09:16:37 INFO - --app=APP Application being installed. [default: firefox] 09:16:37 INFO - mkdir: /builds/slave/test/build/application 09:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:16:54 INFO - Reading from file tmpfile_stdout 09:16:54 INFO - Output received: 09:16:54 INFO - /builds/slave/test/build/application/firefox/firefox 09:16:54 INFO - Running post-action listener: _resource_record_post_action 09:16:54 INFO - [mozharness: 2017-04-17 16:16:54.129522Z] Finished install step (success) 09:16:54 INFO - [mozharness: 2017-04-17 16:16:54.129769Z] Running run-tests step. 09:16:54 INFO - Running pre-action listener: _resource_record_pre_action 09:16:54 INFO - Running main action method: run_tests 09:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:54 INFO - Python 2.7.3 09:16:54 INFO - Return code: 0 09:16:54 INFO - grabbing minidump binary from tooltool 09:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:54 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1106290>, '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 0x1106b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1107040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:16:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:16:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:16:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:54 INFO - Return code: 0 09:16:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:16:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:54 INFO - ENV: RUST_BACKTRACE is now 1 09:16:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:16:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:16:54 INFO - Using env: {'DISPLAY': ':0', 09:16:54 INFO - 'HOME': '/home/cltbld', 09:16:54 INFO - 'LANG': 'en_US.UTF-8', 09:16:54 INFO - 'LANGUAGE': 'en_US:en', 09:16:54 INFO - 'LOGNAME': 'cltbld', 09:16:54 INFO - 'MAIL': '/var/mail/cltbld', 09:16:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:54 INFO - 'MOZ_NO_REMOTE': '1', 09:16:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:54 INFO - 'NO_EM_RESTART': '1', 09:16:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:54 INFO - 'PWD': '/builds/slave/test', 09:16:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:54 INFO - 'RUST_BACKTRACE': '1', 09:16:54 INFO - 'SHELL': '/bin/bash', 09:16:54 INFO - 'SHLVL': '1', 09:16:54 INFO - 'TERM': 'linux', 09:16:54 INFO - 'TMOUT': '86400', 09:16:54 INFO - 'USER': 'cltbld', 09:16:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565', 09:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:54 INFO - '_': '/tools/buildbot/bin/python'} 09:16:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:16:54 INFO - mozversion application_buildid: 20170417145435 09:16:54 INFO - mozversion application_changeset: 5049738b566005c47b8b6e4c07639340ff09a15a 09:16:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:16:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:54 INFO - mozversion application_name: Firefox 09:16:54 INFO - mozversion application_remotingname: firefox-dev 09:16:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:16:54 INFO - mozversion application_vendor: Mozilla 09:16:54 INFO - mozversion application_version: 54.0a2 09:16:54 INFO - mozversion platform_buildid: 20170417145435 09:16:54 INFO - mozversion platform_changeset: 5049738b566005c47b8b6e4c07639340ff09a15a 09:16:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:16:54 INFO - mozversion platform_version: 54.0a2 09:16:54 INFO - using testdate: 1492445814 09:16:54 INFO - actual date: 1492445814 09:16:54 INFO - starting webserver on 'localhost:47549' 09:16:54 INFO - SUITE-START | Running 2 tests 09:16:54 INFO - TEST-START | dromaeo_css 09:16:54 INFO - operating with platform_type : linux_ 09:16:54 INFO - Initialising browser for dromaeo_css test... 09:16:54 INFO - TEST-INFO | started process 21671 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRqI4n4/profile http://localhost:47549/getInfo.html) 09:16:58 INFO - PROCESS | 21671 | __metrics Screen width/height:1600/1200 09:16:58 INFO - PROCESS | 21671 | colorDepth:24 09:16:58 INFO - PROCESS | 21671 | Browser inner width/height: 1024/697 09:16:58 INFO - PROCESS | 21671 | __metrics 09:16:59 INFO - PROCESS | 21671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:16:59 INFO - PROCESS | 21671 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:00 INFO - TEST-INFO | 21671: exit 0 09:17:00 INFO - Browser initialized. 09:17:00 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:00 INFO - TEST-INFO | started process 21764 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRqI4n4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:12 INFO - PROCESS | 21764 | RSS: Main: 140107776 09:17:12 INFO - PROCESS | 21764 | 09:20:13 INFO - PROCESS | 21764 | Cycle 1(1): loaded http://localhost:47549/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47549/tests/dromaeo/cssquery-ext.html) 09:20:15 INFO - PROCESS | 21764 | RSS: Main: 295137280 09:20:15 INFO - PROCESS | 21764 | 09:22:02 INFO - PROCESS | 21764 | ************************* 09:22:02 INFO - PROCESS | 21764 | A coding exception was thrown and uncaught in a Task. 09:22:02 INFO - PROCESS | 21764 | 09:22:02 ERROR - PROCESS | 21764 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:22:02 INFO - PROCESS | 21764 | Full stack: 09:22:02 INFO - PROCESS | 21764 | ************************* 09:22:02 INFO - PROCESS | 21764 | ************************* 09:22:02 INFO - PROCESS | 21764 | A coding exception was thrown and uncaught in a Task. 09:22:02 INFO - PROCESS | 21764 | 09:22:02 ERROR - PROCESS | 21764 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:22:02 INFO - PROCESS | 21764 | Full stack: 09:22:02 INFO - PROCESS | 21764 | ************************* 09:22:02 INFO - PROCESS | 21764 | ************************* 09:22:02 INFO - PROCESS | 21764 | A coding exception was thrown and uncaught in a Task. 09:22:02 INFO - PROCESS | 21764 | 09:22:02 ERROR - PROCESS | 21764 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:22:02 INFO - PROCESS | 21764 | Full stack: 09:22:02 INFO - PROCESS | 21764 | ************************* 09:22:02 INFO - PROCESS | 21764 | ************************* 09:22:02 INFO - PROCESS | 21764 | A coding exception was thrown and uncaught in a Task. 09:22:02 INFO - PROCESS | 21764 | 09:22:02 ERROR - PROCESS | 21764 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:22:02 INFO - PROCESS | 21764 | Full stack: 09:22:02 INFO - PROCESS | 21764 | ************************* 09:23:17 INFO - PROCESS | 21764 | Cycle 1(1): loaded http://localhost:47549/tests/dromaeo/cssquery-ext.html (next: http://localhost:47549/tests/dromaeo/cssquery-jquery.html) 09:23:18 INFO - PROCESS | 21764 | RSS: Main: 224731136 09:23:18 INFO - PROCESS | 21764 | 09:26:19 INFO - PROCESS | 21764 | Cycle 1(1): loaded http://localhost:47549/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47549/tests/dromaeo/cssquery-mootools.html) 09:26:20 INFO - PROCESS | 21764 | RSS: Main: 231743488 09:26:20 INFO - PROCESS | 21764 | 09:29:22 INFO - PROCESS | 21764 | Cycle 1(1): loaded http://localhost:47549/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47549/tests/dromaeo/cssquery-prototype.html) 09:29:23 INFO - PROCESS | 21764 | RSS: Main: 169553920 09:29:23 INFO - PROCESS | 21764 | 09:32:25 INFO - PROCESS | 21764 | Cycle 1(1): loaded http://localhost:47549/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47549/tests/dromaeo/cssquery-yui.html) 09:32:26 INFO - PROCESS | 21764 | RSS: Main: 176287744 09:32:26 INFO - PROCESS | 21764 | 09:35:28 INFO - PROCESS | 21764 | Cycle 1(1): loaded http://localhost:47549/tests/dromaeo/cssquery-yui.html (next: http://localhost:47549/tests/dromaeo/cssquery-dojo.html) 09:35:29 INFO - PROCESS | 21764 | RSS: Main: 259383296 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | __start_tp_report 09:35:29 INFO - PROCESS | 21764 | _x_x_mozilla_page_load 09:35:29 INFO - PROCESS | 21764 | _x_x_mozilla_page_load_details 09:35:29 INFO - PROCESS | 21764 | |i|pagename|runs| 09:35:29 INFO - PROCESS | 21764 | |0;dojo.html;3406.6763657452543;3441.277331760332;3444.948325775114;3476.2178509835276;3497.422925217339;5768.711564421777;5833.095870140124;5841.649501029941;5863.032599621063;5863.442972917572;5891.204687367204;5902.144189092579;5914.408559144094;5917.704114794265;5934.525237980962;5195.142801437768;5236.371635403757;5255.553277971377;5261.210818377255;5288.788448462076;5058.367704036996;5082.288479612857;5282.23407605897;5286.709230992294;5294.285271488338;5109.821156259532;5242.606804489659;5279.366476022863;5291.550218231436;5297.417284098746;5282.788688452457;5294.57643388528;5299.311089558333;5304.20436934459;5310.60170487215;5000.3249561309485;5172.61205409592;5223.294855194538;5249.580033597341;5303.390110137345;5639;5688.715564221772;5769.913451298234;5770.221020162308;5779.104238842986;6219.315875253718;6293.46505547029;6294;6302.086197501382;6302.180716506825;6338.87322253557;6348.904766428507;6363.968180159116;6367.108604795332;6367.140436041168;5575.776968921238;5602.523785478239;5638.718064096861;5639.859003524863;5640.323161220639;6174.876502469925;6178.320384757673;6180.845478862974;6181.196444462191;6308.463780578611;6141.486007784275;6185.195924529782;6200.3179650238435;6206.10011548332;6211.9689401553605;6310.463610593151;6312.274088479791;6312.842178945563;6313.589616674902;6314.368563143741;5922.200502932135;6277.309495955441;6281.685915704196;6287.308396076428;6290.968545157245;6079.604825686316;6138.143176733788;6149.2313460817395;6171.9691401543605;6173.197484327098;4757.119932812441;4758.405199350082;4762.714237145782;4785.066911952136;4786.329913812069;4215.367694845736;4226.070264541796;4227.027783609787;4246.320588705854;4267.061246525759;5790.507806836466;5825.504832089235;6025.758969641253;6054.8183554493435;6140.8771824563255;6347.936520634735;6360.268569114519;6362.141110950055;6366.649834259068;6367.395097465734;6094.512439004958;6116.633002019893;6120.387961203933;6122;6125.754969801158;5030.346055012898;5095.031943930641;5251;5303.946960530422;5361.463853614685;6509.707063182073;6538.542302038812;6543.70553325111;6561.507886908501;6563.967180164069;6269.529785266214;6327.335629758809;6331;6333.366663333814;6338.90491642635;6151.907721384272;6185.103160041859;6398.584092034003;6549.574277671936;6557.082008518906;6155.969220153871;6179.2584889811715;6187.81436556886;6190.350013248544;6194.473469754941;6255.843603909939;6285;6295.842603934938;6301.96849015752;6304.085907543472;5822.592418530662;5865.618734782225;5877.265341832271;5940.970295148497;5941.049432090845;5938.257717785277;6062.969685151546;6070.878582428239;6151.169592105009;6172.166757487682;6173.783917563043;6173.876522469615;6175.62946223228;6177;6181.5363847710705;6123.785667501795;6175.722092505758;6180.6909654517995;6186.319504854552;6190.50475961931;5759.308882934075;5801.564882633735;5871.530277577698;5975.880482390415;5986.072158815216 09:35:29 INFO - PROCESS | 21764 | |1;ext.html;763.4236151705466;818.2186012358198;837.7696133563268;904.846176150054;915.9496227707474;31920;43551.346729799036;44344.55655443444;44409.333859992046;44415.11169776603;45223.547764522344;45551.31673024907;45574.54425455753;45650.771746141305;45689.08621827562;7706.845863082735;7838.72564460244;8951.418157819737;9088.182063614273;9095.590698418588;10602.151827853791;10828;10831.133509319276;10836.837447438294;11205.775884482306;10921.071708904752;10946.124310055216;10954.178436617245;10960.725981850477;10963.342199468058;10819.83770243443;10837.67486975392;10838.891611083867;10848.403337816417;10852.891471085268;10623.097036751886;10658.840117398246;10676.306040107369;10678.412687302196;10686.839697404544;42421.57578424207;42472.36291455631;42701.57298427007;42739.57260427387;42770;4173.290540608104;4203.096334288142;4259.10558782656;4304.956950430487;4347.065380943112;29211.56182657262;29563.70436295631;29884.252893677614;30179.849100754574;30846.845765771253;4323.200207961537;4402.955970440319;4409.823607055714;4414.337849322627;4473.7986790594505;240842;241310;241417.99999999825;241841;242461;53752.73123634357;53764.73117634387;53817.1927421089;53824.73087634537;53966;56914.715426422605;56987.71506142443;57118;57165.42834571684;57212.14181787276;15692.92153539248;16043.839561604467;16061.036337819534;16075.356985720675;16089.436869709503;9652.179564737076;9784.804303914025;13704.725905482033;14005.159690418608;14028.929855350658;13328.800067998987;13363.1313964592;13386.39761210748;13420.127691700009;13445.596632101053;5295.637270333289;5442.945570544323;5450.672959622435;5506.944930550723;5614.6631202128;1016.1515134862473;1020.433659319085;1031.5512751952924;1037.7405648587853;1039.5374058543875;28917.710822891924;29004.398240703464;29068.563971540443;29200.707992920223;29238.853805730836;1089.6022951622758;1095.808233559124;1106.2975010867997;1116.0625074937093;1116.7264020315074;14424.06243594163;15222.847771522365;15254.618634534003;15315.463958761613;15385.384584616713;327148;327263;327621;327793;328458;57621.711891440274;57641.13538296845;57677.42322576804;57775.422245777845;57777.71111144417;1479.8076250087636;1517.1352329172487;1529.1054732981108;1538.0617823127884;1548.1717281254475;25940.35149121287;27057.59413608837;27135.57361504796;27352.72647273502;27445.862770686017;43689.34465982949;43966;43972;44078;44136;291845;292292;292684;292812;293150;34485.48271775875;34769.478457822646;35014.12464688403;35085.824570878;35166.648333516336;41668.58331416647;42343.280473654995;42437;42983.355249670654;43448.78275608602;29894;30520.694793051785;31942;31976.6802331983;32292;29875.850620746758;30489.54265685972;30498.237544061576;33016.88079622219;33309.16727081843;35466.46800297946;35605.64394356023;35713.64286357207;36119.81940090283;36138.4579231317;29439.264018398855;30283;30815.69184308218;31080.68919310778;31242.531362029134;24316.27051188467;24994;25144.497110058062;25344.52716616538;25417.491650166525 09:35:29 INFO - PROCESS | 21764 | |2;jquery.html;423.46854697354814;466.8902807840157;472.3623108803115;484.968477048992;489.25877295896714;18058.458246252616;19232.13455394507;20864.269750558735;20925.37223883286;20959.580808383824;20395.796042039576;20501.179952801907;20697.586048279027;20940.476488087807;21092.15631374747;6565.080888675589;6672.2660507344035;6894.482913781461;6899.896501552481;6902.965485172592;6725.831854203635;6740.191177058735;6764.966175169117;6783.559068660546;6787.355201255898;6796.490263230253;6802.523823332369;7598.696052157907;7820.296173344398;7864.68541258349;7784.377249820029;7859.096203936563;8007.359411247145;8043.678252869879;8050.557219352904;7647.082350118021;7923.088844782808;7927.207279272085;7929.722459713882;7938.206179382073;29340;29405.411891762258;29822;29976;30032;3064.846757662108;3317.9834100829585;3327.1515763480156;3362.596488421405;3413.675700808419;17487.562810929827;18388.816111838845;18406.263749450005;18672.81327186738;19013.88916407445;2094.7800480949586;2458.8770561472033;2616.358992046961;2644.1803041057333;2736;116602.41698791452;116995;117740;119032;120133;16595.842540469905;20305.289314874204;22496.21263255811;22706.545869082525;22711.545769084856;42067.36898946518;42160.789196053825;42197.789011054745;43132.784336078745;43171;10836;10947.4526273686;11027.724306892393;11101.281070841198;11408.714782130513;8717.825643487095;8793.472391656522;8839.29285657146;9048.728538143867;9054.86417703735;10836.891631083588;11337.659870203906;11343.319400835977;11472;11701.531938722526;4015.257177422186;4380.627646650007;4442.022754993896;4473.597376236155;4481.9243381588185;947.0907928388824;950.1211379474097;954.7326748510428;964.262339310428;971.7036303927229;19670;20995.895020525106;21190.682139767607;21225.257116001176;22603.667094841592;1031.3193292426963;1061.792950374685;1063.7340664833791;1063.9468026598795;1090.6455401994383;11182.776344473064;11351;11461.197716159788;11526.769464610661;11529.135314851419;716522;722154;726368;727216;744088;100475.49762251142;113874;118510;118664.40667796606;120595;1395.0792476965357;1412.837523684769;1461.7295800276984;1472.977905331421;1473.6978919321514;14114.78827817563;18098.547536311697;19538;19538.742918320648;19726.40820775379;50293;50386;50869.74565127151;51121.48878511167;51219.74390128026;63762.362376377496;63968;63999;64871.35128648653;65055.67472162609;12519.186252893533;12813;12832.165909215872;12881.35593220354;12891.41988610496;13713.862861371259;13827.654308641962;13837;13936.860631393556;13939.372728227052;12134.817977730165;12284;12313.815292770794;12588.496460141492;12676.809847852473;11610.535578576762;11661.416929153678;11670.416479175838;11729.35488548104;11863.525458981545;11930.463129159036;12547.93726031364;12569.685757856127;12639.557615483414;12868.22790632565;12376.938115309365;12378.133530652767;12691.301978391277;12916.870831291943;12917.935410322889;12365.196262242926;12482.068148533182;12526.498940042296;12690.365481726061;13040.347982601023 09:35:29 INFO - PROCESS | 21764 | |3;mootools.html;1550.7983962084927;1590.2525812867948;1598.3446786817399;1611.6615510742743;1618.1909045477257;11227.6631701049;11907.047436205105;11914.880851191485;11915.344656043913;11925.642230733081;11243.325400475976;11695.29828210308;11714.297142171476;11726.882731172685;12070.939645301762;2481.888315025828;2519.7858182054547;2527.494501099778;2528.40582463121;2529.6837895263093;2277.2257432472957;2345.9061637534564;2373.1219448803968;2387.0929046962215;2389.9842566909083;2347;2367.0413482539598;2372.822038347122;2474.7772700456953;2492.0779311654624;2283.2579411691268;2314.872682002489;2420.5037967216767;2425.0663494554638;2426.672399226108;2359.4927090675587;2381.2856143156982;2393.748656391089;2399.016403274649;2400.135951057618;11765.529378824836;11991.100667449977;12039;12045.39773011346;12051.698707532294;5758.884822303572;5889.79385721502;5900.350961394243;5902.527797776168;5906.704664766788;3576.445650924111;3671.2106897017;3726.981365093184;3761.567419746709;3794.753341032824;1401.5655146904492;2298.770122987705;2348.5420343033115;2357.552065107624;2392.736798952114;15373.077615343003;15678.843211567852;17076.743848842154;17521.561960950952;17614.823851761448;4733.786979585858;4809.014152098812;4919;4966.255061740696;4966.478519755446;15115.244237788067;15128.319225634872;15129.848701513065;15131.924340378227;15135.091894486366;6040.667763272974;6088.756449741961;6095.238095238095;6100.450959413674;6102.206713127353;5361.597880159004;5382.892342153135;5431.511163995259;5456.454354564591;5503.972480137573;4148.340663734531;4159.438475805788;4259.74441533509;4289.1850548395705;4301.591348821857;1080.1790639114329;1085.8154113800672;1094.4746521669642;1095.8301463273206;1097.7639807441437;1019.1795604538418;1035.254616675992;1046.3512622174148;1047.1518070363031;1055.8680164979378;7042.894356584656;7049.295070493013;7148.535345202546;7235.131784185932;7264.564126152448;1037.2894567221476;1054.541274545575;1057.4033209853023;1071.9410432426134;1090.7545802194602;4594.770261486912;4679.766011699401;4719.197736384823;4797.95202047982;4815.711057336571;14948.40206391754;15510.767338489932;15543;15607.687846243012;15611.375544978073;14664.633384165481;14868.925655371653;15115.470958516618;15240.542783716506;15297.6175595611;8910.59902304385;10792.730181745519;10996.835047474295;11043.282186657842;11053.226274160892;6183.628982261079;7001.019857220095;7305.671244793891;7315.8536829264185;7387.4090072795125;12618.43217055253;13099.410526526139;13259.867401325862;13322.866771332163;13439.932800335935;13201.405936732677;13383.19700817954;13559.728805424034;13607.319634018457;13795;10147.18822494184;10279;10335.94832025835;10386.532606032897;10526.631567895087;11127.165462590176;11200.943995279971;11312.886871131182;11334.773304534028;11356.716082098013;9514.85727714071;9624.181944534794;9750.561224744763;9763.658271960447;9842.311038227255;9349.205317547812;9459.148676619137;9486.95256523713;9608.855867161858;9880.654177103766;7813.101493328113;7849.803754906173;7907.090684571348;7965.721199757981;7975.202479751978;5937.673427961331;6005.1892994446935;6084.269887613515;6102.603330783485;6184.257889053342;6096.481799047131;6264.624122552661;6284.183056202664;6290.276618188784;6530.967345163243 09:35:29 INFO - PROCESS | 21764 | |4;prototype.html;420.6298457357525;426.81860209411;434.76305413549613;440.37466797148045;443.48555675416526;5227.320448341715;5280.3663560372715;5289.365276166861;5303.7348132593315;5320.813771517997;5024.497550244982;5196.766145523451;5200.893638076575;5201.895962080756;5229.50319719627;4290.4636920384955;4307.5261721210645;4322.243607368706;4338.197433474801;4338.804753786071;4310.288802347609;4317.633001194902;4321.913561728764;4327.718698284617;4329.220740266751;4307.224699554079;4309.547497512763;4328.675349348811;4342.199281463454;4356.194104090738;4150.83396664133;4282.421873047146;4305.763183024932;4334.612923864364;4344;4057.7971101445105;4076.408920706511;4088.3867419887256;4090.386442033689;4091.161311931077;5091.988664477548;5095.949040509585;5115.335006449175;5118.718470484122;5131.512506311894;4938.432080310774;4966.900661986776;4998.300237966688;5050.84847454577;5081.974590127063;5449.3188351456065;5457.126859702428;5481.479259470344;5485.753141108659;5501.202325662756;4526.34368016639;4548.954510454886;4555.362249285103;4557.692980133775;4572.497025327211;5519.420460851633;5654.897295027471;5666.829995100154;5667.971660141714;5686.914696279559;4625.329327247598;4648.953510464919;4654.138984287918;4657.394538709946;4666.976665116653;4733.573978341966;4780.402449693788;4798.329278102255;4798.49615790344;4800.85543968701;4863.392075990501;5026.648134630614;5044.7802966694535;5052.393712754493;5062.190049592121;5459.918101228484;5469.972650136724;5481.451854814487;5490.3411590609385;5508.421615730291;1551.2166355990153;1636.2636813433764;1646.1851383565213;1655.973296556143;1657.395050806471;1245.2155142260317;1249.3066348176667;1259.9164718342217;1282.557517656407;1289.5873320537337;5311.681299122065;5365.436629153961;5388.164834450667;5396.488983084742;5451.64564303319;5489.945100549024;5559.749811258503;5579.386267510652;5656.575756818255;5662.782501762059;5190.662606930538;5267.174430262324;5327.295265514998;5369.543588794996;5412.5940554458575;2921.0798598441424;2930.296728785076;2935.5890175375466;2936.8986630013746;2955.7635389168813;5802.82591522255;5819.650820950672;5836.620619659709;5849.181114643953;5856.385079566669;5552.7223638817895;5589.009879714334;5677.126548239071;5742.454466825729;5770.855728606734;5755.59710820247;5793.740441227925;5815.331236907809;5818.301803783573;5843.707814609337;5602.254895101452;5610.747516361856;5613.382527921844;5631.549476041989;5685.687287199244;5302.310699609026;5538.196961440649;5540.584456165884;5557.5553955682635;5567.276254086943;5796.855078623068;5820.854478638068;5829.883402332014;5831.7375718093635;5832.416758324133;4450.443694538183;4628.745419001987;4649.535046495459;4680.274557443602;4690.296455531698;5619.634723742944;5637.534241097263;5647.830565083054;5694.402087780723;5709.857253568528;5474.822913073708;5482.890342193054;5531.751071201725;5556.861078473071;5563.638363506359;5276.314079169683;5404.108322126962;5501.889962200654;5535.474129957647;5573.749181286771;5539.639923404966;5574.554035677217;5589.692566908697;5590.385057643737;5590.909772594305;5480.808171713971;5570.749316280696;5573.359063707726;5586.636868603528;5606.411326810626;5182.9374978130145;5388.568914486753;5396.595255355785;5416.918746218888;5421.83734487966 09:35:29 INFO - PROCESS | 21764 | |5;yui.html;885.9313502970572;895.8118795053039;918.3984490158946;930.6463543853336;935.2704890185653;16482.42311519097;16724.331026758915;17388.65222695545;17427.302907883684;18793.906030469847;15750.291236894338;15766.047048362196;15822.76265856013;16336.34654613817;16587.917060414682;876.3733930239894;897.2463130969984;899.0964081098487;902.5938327752505;903.439867282286;887.9600417981206;901.378049146087;904.4030939579878;904.6291020681522;904.728581425573;959.596969272907;963.4315753705313;964.4791812421284;964.6816550538306;965.932384733069;952.4285428742734;955.8088382323493;957.0907637744136;957.1816097236866;957.7126861941459;697.3305626598471;697.7243988624516;698.6925752668809;700.5726506830829;700.6636814329099;18210.726839097424;18316.450506484827;18489.445316640522;18588.44234672962;18610.62778744431;15848.92075539615;16149;16172.433964811175;16225.675486490321;16614.916925415295;13887.166769993833;14080.225587592678;14233.359498822474;14243.074200176956;14321.78517322241;3956.2680904032845;4014.759114453113;4034.5763694811917;4068.105016896278;4159.188958153161;50223.49776502225;65300;81319.18680813175;83569;86684;12711.237325760483;12914.870851291367;13079.934600327128;13101.868981310256;13261.270630115243;20992;23253.883730581238;23328.766712333;23495.765042349358;23680;9039.412438191497;9439.433633981984;9515.524223788922;9570.56932438042;9579.952100239594;4986.077575648516;5082.618803589746;5091.235417289815;5105.106606343949;5199.63602547818;3913.412988051815;4022.798860057044;4030.0327921298676;4030.3349947258375;4031.3146765049423;672.7040102355046;680.4012469027224;682.2563405887606;687.0552989639247;687.2405991379553;797.6091715059579;800.4236949396425;816.8856360109589;816.9060558035783;837.660747397305;11700.765984680258;11827;11883.88116118845;11986.220895641756;12004.459799308877;851.5188918261287;856.9271611913057;859.8968123825056;861.4142383179372;864.6325311742485;5554.833354999357;5778.855528611734;5960.612560183574;6204.472619827366;6384.233891932998;78486;81526;83828.58085709655;85567.14432855716;87289.5635521831;71294.64352678307;72693;73223.63388183025;73349.6332518334;74265.62867185738;50733;60768;60832.39167608356;60842.69578652078;61330.999999999105;11492.770144596894;11894.643160705193;11934.164608477324;12249.632511024683;12264.87735122673;43653.34519982139;44446.555534445535;44716.32925506185;44754.77622611996;45316.09367812691;52354.21468677896;52718.73640631772;52962;53106;54786;13667.521636742666;14199.64500887486;14242.572722818331;14339.713205736036;14507.78238326447;15438.768418473508;15479.458218962733;16101;16624.916875415547;17011.829881701025;15346.23268836576;15942.601434963755;16013.599660008593;16024.919875400548;16793.496195113676;14081.295935203403;14647.12117272967;14804.407823687363;15041.323140458488;15513.689726205639;14978.850211497745;15749.921250393674;15923.203839807731;16159.67680646404;16705.498835034967;10002.24983126254;10092.949535252277;10162.085412312927;10284.537195826355;10497.370157790558;12218.877811221773;12250.20373675744;12440.129190956546;12470.438830252842;12530.686732831753 09:35:29 INFO - PROCESS | 21764 | __end_tp_report 09:35:29 INFO - PROCESS | 21764 | __start_cc_report 09:35:29 INFO - PROCESS | 21764 | _x_x_mozilla_cycle_collect,1458 09:35:29 INFO - PROCESS | 21764 | __end_cc_report 09:35:29 INFO - PROCESS | 21764 | __startTimestamp1492446929206__endTimestamp 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | ------- Summary: start ------- 09:35:29 INFO - PROCESS | 21764 | Number of tests: 6 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | [#0] dojo.html Cycles:165 Average:5787.42 Median:6070.88 stddev:674.09 (11.1%) stddev-sans-first:649.77 09:35:29 INFO - PROCESS | 21764 | Values: 3406.7 3441.3 3444.9 3476.2 3497.4 5768.7 5833.1 5841.6 5863.0 5863.4 5891.2 5902.1 5914.4 5917.7 5934.5 5195.1 5236.4 5255.6 5261.2 5288.8 5058.4 5082.3 5282.2 5286.7 5294.3 5109.8 5242.6 5279.4 5291.6 5297.4 5282.8 5294.6 5299.3 5304.2 5310.6 5000.3 5172.6 5223.3 5249.6 5303.4 5639.0 5688.7 5769.9 5770.2 5779.1 6219.3 6293.5 6294.0 6302.1 6302.2 6338.9 6348.9 6364.0 6367.1 6367.1 5575.8 5602.5 5638.7 5639.9 5640.3 6174.9 6178.3 6180.8 6181.2 6308.5 6141.5 6185.2 6200.3 6206.1 6212.0 6310.5 6312.3 6312.8 6313.6 6314.4 5922.2 6277.3 6281.7 6287.3 6291.0 6079.6 6138.1 6149.2 6172.0 6173.2 4757.1 4758.4 4762.7 4785.1 4786.3 4215.4 4226.1 4227.0 4246.3 4267.1 5790.5 5825.5 6025.8 6054.8 6140.9 6347.9 6360.3 6362.1 6366.6 6367.4 6094.5 6116.6 6120.4 6122.0 6125.8 5030.3 5095.0 5251.0 5303.9 5361.5 6509.7 6538.5 6543.7 6561.5 6564.0 6269.5 6327.3 6331.0 6333.4 6338.9 6151.9 6185.1 6398.6 6549.6 6557.1 6156.0 6179.3 6187.8 6190.4 6194.5 6255.8 6285.0 6295.8 6302.0 6304.1 5822.6 5865.6 5877.3 5941.0 5941.0 5938.3 6063.0 6070.9 6151.2 6172.2 6173.8 6173.9 6175.6 6177.0 6181.5 6123.8 6175.7 6180.7 6186.3 6190.5 5759.3 5801.6 5871.5 5975.9 5986.1 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | [#1] ext.html Cycles:180 Average:45809.44 Median:30963.77 stddev:75595.76 (244.1%) stddev-sans-first:75732.16 09:35:29 INFO - PROCESS | 21764 | Values: 763.4 818.2 837.8 904.8 915.9 31920.0 43551.3 44344.6 44409.3 44415.1 45223.5 45551.3 45574.5 45650.8 45689.1 7706.8 7838.7 8951.4 9088.2 9095.6 10602.2 10828.0 10831.1 10836.8 11205.8 10921.1 10946.1 10954.2 10960.7 10963.3 10819.8 10837.7 10838.9 10848.4 10852.9 10623.1 10658.8 10676.3 10678.4 10686.8 42421.6 42472.4 42701.6 42739.6 42770.0 4173.3 4203.1 4259.1 4305.0 4347.1 29211.6 29563.7 29884.3 30179.8 30846.8 4323.2 4403.0 4409.8 4414.3 4473.8 240842.0 241310.0 241418.0 241841.0 242461.0 53752.7 53764.7 53817.2 53824.7 53966.0 56914.7 56987.7 57118.0 57165.4 57212.1 15692.9 16043.8 16061.0 16075.4 16089.4 9652.2 9784.8 13704.7 14005.2 14028.9 13328.8 13363.1 13386.4 13420.1 13445.6 5295.6 5442.9 5450.7 5506.9 5614.7 1016.2 1020.4 1031.6 1037.7 1039.5 28917.7 29004.4 29068.6 29200.7 29238.9 1089.6 1095.8 1106.3 1116.1 1116.7 14424.1 15222.8 15254.6 15315.5 15385.4 327148.0 327263.0 327621.0 327793.0 328458.0 57621.7 57641.1 57677.4 57775.4 57777.7 1479.8 1517.1 1529.1 1538.1 1548.2 25940.4 27057.6 27135.6 27352.7 27445.9 43689.3 43966.0 43972.0 44078.0 44136.0 291845.0 292292.0 292684.0 292812.0 293150.0 34485.5 34769.5 35014.1 35085.8 35166.6 41668.6 42343.3 42437.0 42983.4 43448.8 29894.0 30520.7 31942.0 31976.7 32292.0 29875.9 30489.5 30498.2 33016.9 33309.2 35466.5 35605.6 35713.6 36119.8 36138.5 29439.3 30283.0 30815.7 31080.7 31242.5 24316.3 24994.0 25144.5 25344.5 25417.5 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | [#2] jquery.html Cycles:180 Average:40240.95 Median:11517.63 stddev:119532.40 (1037.8%) stddev-sans-first:119830.33 09:35:29 INFO - PROCESS | 21764 | Values: 423.5 466.9 472.4 485.0 489.3 18058.5 19232.1 20864.3 20925.4 20959.6 20395.8 20501.2 20697.6 20940.5 21092.2 6565.1 6672.3 6894.5 6899.9 6903.0 6725.8 6740.2 6765.0 6783.6 6787.4 6796.5 6802.5 7598.7 7820.3 7864.7 7784.4 7859.1 8007.4 8043.7 8050.6 7647.1 7923.1 7927.2 7929.7 7938.2 29340.0 29405.4 29822.0 29976.0 30032.0 3064.8 3318.0 3327.2 3362.6 3413.7 17487.6 18388.8 18406.3 18672.8 19013.9 2094.8 2458.9 2616.4 2644.2 2736.0 116602.4 116995.0 117740.0 119032.0 120133.0 16595.8 20305.3 22496.2 22706.5 22711.5 42067.4 42160.8 42197.8 43132.8 43171.0 10836.0 10947.5 11027.7 11101.3 11408.7 8717.8 8793.5 8839.3 9048.7 9054.9 10836.9 11337.7 11343.3 11472.0 11701.5 4015.3 4380.6 4442.0 4473.6 4481.9 947.1 950.1 954.7 964.3 971.7 19670.0 20995.9 21190.7 21225.3 22603.7 1031.3 1061.8 1063.7 1063.9 1090.6 11182.8 11351.0 11461.2 11526.8 11529.1 716522.0 722154.0 726368.0 727216.0 744088.0 100475.5 113874.0 118510.0 118664.4 120595.0 1395.1 1412.8 1461.7 1473.0 1473.7 14114.8 18098.5 19538.0 19538.7 19726.4 50293.0 50386.0 50869.7 51121.5 51219.7 63762.4 63968.0 63999.0 64871.4 65055.7 12519.2 12813.0 12832.2 12881.4 12891.4 13713.9 13827.7 13837.0 13936.9 13939.4 12134.8 12284.0 12313.8 12588.5 12676.8 11610.5 11661.4 11670.4 11729.4 11863.5 11930.5 12547.9 12569.7 12639.6 12868.2 12376.9 12378.1 12691.3 12916.9 12917.9 12365.2 12482.1 12526.5 12690.4 13040.3 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | [#3] mootools.html Cycles:180 Average:7337.31 Median:2372.97 stddev:4720.95 (198.9%) stddev-sans-first:4714.17 09:35:29 INFO - PROCESS | 21764 | Values: 1550.8 1590.3 1598.3 1611.7 1618.2 11227.7 11907.0 11914.9 11915.3 11925.6 11243.3 11695.3 11714.3 11726.9 12070.9 2481.9 2519.8 2527.5 2528.4 2529.7 2277.2 2345.9 2373.1 2387.1 2390.0 2347.0 2367.0 2372.8 2474.8 2492.1 2283.3 2314.9 2420.5 2425.1 2426.7 2359.5 2381.3 2393.7 2399.0 2400.1 11765.5 11991.1 12039.0 12045.4 12051.7 5758.9 5889.8 5900.4 5902.5 5906.7 3576.4 3671.2 3727.0 3761.6 3794.8 1401.6 2298.8 2348.5 2357.6 2392.7 15373.1 15678.8 17076.7 17521.6 17614.8 4733.8 4809.0 4919.0 4966.3 4966.5 15115.2 15128.3 15129.8 15131.9 15135.1 6040.7 6088.8 6095.2 6100.5 6102.2 5361.6 5382.9 5431.5 5456.5 5504.0 4148.3 4159.4 4259.7 4289.2 4301.6 1080.2 1085.8 1094.5 1095.8 1097.8 1019.2 1035.3 1046.4 1047.2 1055.9 7042.9 7049.3 7148.5 7235.1 7264.6 1037.3 1054.5 1057.4 1071.9 1090.8 4594.8 4679.8 4719.2 4798.0 4815.7 14948.4 15510.8 15543.0 15607.7 15611.4 14664.6 14868.9 15115.5 15240.5 15297.6 8910.6 10792.7 10996.8 11043.3 11053.2 6183.6 7001.0 7305.7 7315.9 7387.4 12618.4 13099.4 13259.9 13322.9 13439.9 13201.4 13383.2 13559.7 13607.3 13795.0 10147.2 10279.0 10335.9 10386.5 10526.6 11127.2 11200.9 11312.9 11334.8 11356.7 9514.9 9624.2 9750.6 9763.7 9842.3 9349.2 9459.1 9487.0 9608.9 9880.7 7813.1 7849.8 7907.1 7965.7 7975.2 5937.7 6005.2 6084.3 6102.6 6184.3 6096.5 6264.6 6284.2 6290.3 6531.0 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | [#4] prototype.html Cycles:180 Average:4779.69 Median:5284.87 stddev:1277.31 (24.2%) stddev-sans-first:1238.29 09:35:29 INFO - PROCESS | 21764 | Values: 420.6 426.8 434.8 440.4 443.5 5227.3 5280.4 5289.4 5303.7 5320.8 5024.5 5196.8 5200.9 5201.9 5229.5 4290.5 4307.5 4322.2 4338.2 4338.8 4310.3 4317.6 4321.9 4327.7 4329.2 4307.2 4309.5 4328.7 4342.2 4356.2 4150.8 4282.4 4305.8 4334.6 4344.0 4057.8 4076.4 4088.4 4090.4 4091.2 5092.0 5095.9 5115.3 5118.7 5131.5 4938.4 4966.9 4998.3 5050.8 5082.0 5449.3 5457.1 5481.5 5485.8 5501.2 4526.3 4549.0 4555.4 4557.7 4572.5 5519.4 5654.9 5666.8 5668.0 5686.9 4625.3 4649.0 4654.1 4657.4 4667.0 4733.6 4780.4 4798.3 4798.5 4800.9 4863.4 5026.6 5044.8 5052.4 5062.2 5459.9 5470.0 5481.5 5490.3 5508.4 1551.2 1636.3 1646.2 1656.0 1657.4 1245.2 1249.3 1259.9 1282.6 1289.6 5311.7 5365.4 5388.2 5396.5 5451.6 5489.9 5559.7 5579.4 5656.6 5662.8 5190.7 5267.2 5327.3 5369.5 5412.6 2921.1 2930.3 2935.6 2936.9 2955.8 5802.8 5819.7 5836.6 5849.2 5856.4 5552.7 5589.0 5677.1 5742.5 5770.9 5755.6 5793.7 5815.3 5818.3 5843.7 5602.3 5610.7 5613.4 5631.5 5685.7 5302.3 5538.2 5540.6 5557.6 5567.3 5796.9 5820.9 5829.9 5831.7 5832.4 4450.4 4628.7 4649.5 4680.3 4690.3 5619.6 5637.5 5647.8 5694.4 5709.9 5474.8 5482.9 5531.8 5556.9 5563.6 5276.3 5404.1 5501.9 5535.5 5573.7 5539.6 5574.6 5589.7 5590.4 5590.9 5480.8 5570.7 5573.4 5586.6 5606.4 5182.9 5388.6 5396.6 5416.9 5421.8 09:35:29 INFO - PROCESS | 21764 | 09:35:29 INFO - PROCESS | 21764 | [#5] yui.html Cycles:180 Average:18452.93 Median:44049.95 stddev:22353.08 (50.7%) stddev-sans-first:22376.86 09:35:29 INFO - PROCESS | 21764 | Values: 885.9 895.8 918.4 930.6 935.3 16482.4 16724.3 17388.7 17427.3 18793.9 15750.3 15766.0 15822.8 16336.3 16587.9 876.4 897.2 899.1 902.6 903.4 888.0 901.4 904.4 904.6 904.7 959.6 963.4 964.5 964.7 965.9 952.4 955.8 957.1 957.2 957.7 697.3 697.7 698.7 700.6 700.7 18210.7 18316.5 18489.4 18588.4 18610.6 15848.9 16149.0 16172.4 16225.7 16614.9 13887.2 14080.2 14233.4 14243.1 14321.8 3956.3 4014.8 4034.6 4068.1 4159.2 50223.5 65300.0 81319.2 83569.0 86684.0 12711.2 12914.9 13079.9 13101.9 13261.3 20992.0 23253.9 23328.8 23495.8 23680.0 9039.4 9439.4 9515.5 9570.6 9580.0 4986.1 5082.6 5091.2 5105.1 5199.6 3913.4 4022.8 4030.0 4030.3 4031.3 672.7 680.4 682.3 687.1 687.2 797.6 800.4 816.9 816.9 837.7 11700.8 11827.0 11883.9 11986.2 12004.5 851.5 856.9 859.9 861.4 864.6 5554.8 5778.9 5960.6 6204.5 6384.2 78486.0 81526.0 83828.6 85567.1 87289.6 71294.6 72693.0 73223.6 73349.6 74265.6 50733.0 60768.0 60832.4 60842.7 61331.0 11492.8 11894.6 11934.2 12249.6 12264.9 43653.3 44446.6 44716.3 44754.8 45316.1 52354.2 52718.7 52962.0 53106.0 54786.0 13667.5 14199.6 14242.6 14339.7 14507.8 15438.8 15479.5 16101.0 16624.9 17011.8 15346.2 15942.6 16013.6 16024.9 16793.5 14081.3 14647.1 14804.4 15041.3 15513.7 14978.9 15749.9 15923.2 16159.7 16705.5 10002.2 10092.9 10162.1 10284.5 10497.4 12218.9 12250.2 12440.1 12470.4 12530.7 09:35:29 INFO - PROCESS | 21764 | -------- Summary: end -------- 09:35:29 INFO - PROCESS | 21764 | 09:35:30 INFO - TEST-INFO | 21764: exit 0 09:35:30 INFO - TEST-OK | dromaeo_css | took 1115603ms 09:35:30 INFO - TEST-START | kraken 09:35:30 INFO - operating with platform_type : linux_ 09:35:30 INFO - Initialising browser for kraken test... 09:35:30 INFO - TEST-INFO | started process 22091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvtLmyK/profile http://localhost:47549/getInfo.html) 09:35:34 INFO - PROCESS | 22091 | __metrics Screen width/height:1600/1200 09:35:34 INFO - PROCESS | 22091 | colorDepth:24 09:35:34 INFO - PROCESS | 22091 | Browser inner width/height: 1024/697 09:35:34 INFO - PROCESS | 22091 | __metrics 09:35:35 INFO - PROCESS | 22091 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:35:35 INFO - PROCESS | 22091 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:35 INFO - PROCESS | 22091 | console.error: PushService: 09:35:35 INFO - PROCESS | 22091 | stateChangeProcessEnqueue: Error transitioning state 09:35:35 INFO - PROCESS | 22091 | UnknownError 09:35:36 INFO - TEST-INFO | 22091: exit 0 09:35:36 INFO - Browser initialized. 09:35:36 INFO - Running cycle 1/1 for kraken test... 09:35:36 INFO - TEST-INFO | started process 22181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvtLmyK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:48 INFO - PROCESS | 22181 | RSS: Main: 140320768 09:35:48 INFO - PROCESS | 22181 | 09:35:50 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47549/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:51 INFO - PROCESS | 22181 | RSS: Main: 183771136 09:35:51 INFO - PROCESS | 22181 | 09:35:53 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47549/tests/kraken/driver.html?testName=audio-dft) 09:35:54 INFO - PROCESS | 22181 | RSS: Main: 174231552 09:35:54 INFO - PROCESS | 22181 | 09:35:57 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47549/tests/kraken/driver.html?testName=audio-fft) 09:35:57 INFO - PROCESS | 22181 | RSS: Main: 266149888 09:35:57 INFO - PROCESS | 22181 | 09:36:00 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47549/tests/kraken/driver.html?testName=audio-oscillator) 09:36:00 INFO - PROCESS | 22181 | RSS: Main: 172208128 09:36:00 INFO - PROCESS | 22181 | 09:36:02 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47549/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:03 INFO - PROCESS | 22181 | RSS: Main: 174723072 09:36:03 INFO - PROCESS | 22181 | 09:36:06 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47549/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:06 INFO - PROCESS | 22181 | RSS: Main: 213123072 09:36:06 INFO - PROCESS | 22181 | 09:36:10 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47549/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:10 INFO - PROCESS | 22181 | RSS: Main: 212357120 09:36:10 INFO - PROCESS | 22181 | 09:36:13 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47549/tests/kraken/driver.html?testName=json-parse-financial) 09:36:14 INFO - PROCESS | 22181 | RSS: Main: 211214336 09:36:14 INFO - PROCESS | 22181 | 09:36:15 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47549/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:16 INFO - PROCESS | 22181 | RSS: Main: 173268992 09:36:16 INFO - PROCESS | 22181 | 09:36:17 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:18 INFO - PROCESS | 22181 | RSS: Main: 179675136 09:36:18 INFO - PROCESS | 22181 | 09:36:20 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:21 INFO - PROCESS | 22181 | RSS: Main: 189272064 09:36:21 INFO - PROCESS | 22181 | 09:36:23 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:24 INFO - PROCESS | 22181 | RSS: Main: 198881280 09:36:24 INFO - PROCESS | 22181 | 09:36:27 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:27 INFO - PROCESS | 22181 | RSS: Main: 195727360 09:36:27 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | Cycle 1(1): loaded http://localhost:47549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47549/tests/kraken/driver.html?testName=ai-astar) 09:36:29 INFO - PROCESS | 22181 | RSS: Main: 245104640 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | __start_tp_report 09:36:29 INFO - PROCESS | 22181 | _x_x_mozilla_page_load 09:36:29 INFO - PROCESS | 22181 | _x_x_mozilla_page_load_details 09:36:29 INFO - PROCESS | 22181 | |i|pagename|runs| 09:36:29 INFO - PROCESS | 22181 | |0;ai-astar;102;112;111;112;113;114;112;112;112;110 09:36:29 INFO - PROCESS | 22181 | |1;audio-beat-detection;152;115;121;152;118;116;116;117;138;118 09:36:29 INFO - PROCESS | 22181 | |2;audio-dft;163;162;161;160;161;161;161;159;162;160 09:36:29 INFO - PROCESS | 22181 | |3;audio-fft;76;76;75;110;76;76;76;75;88;75 09:36:29 INFO - PROCESS | 22181 | |4;audio-oscillator;84;83;114;82;83;82;94;84;83;105 09:36:29 INFO - PROCESS | 22181 | |5;imaging-gaussian-blur;113;112;114;113;113;113;113;112;112;113 09:36:29 INFO - PROCESS | 22181 | |6;imaging-darkroom;146;147;146;146;146;146;146;147;147;147 09:36:29 INFO - PROCESS | 22181 | |7;imaging-desaturate;93;94;95;94;94;94;94;94;95;94 09:36:29 INFO - PROCESS | 22181 | |8;json-parse-financial;55;55;55;55;56;57;56;55;56;55 09:36:29 INFO - PROCESS | 22181 | |9;json-stringify-tinderbox;53;55;53;53;52;54;54;53;53;54 09:36:29 INFO - PROCESS | 22181 | |10;stanford-crypto-aes;75;74;75;84;79;79;75;82;81;79 09:36:29 INFO - PROCESS | 22181 | |11;stanford-crypto-ccm;122;126;121;119;118;128;120;129;174;119 09:36:29 INFO - PROCESS | 22181 | |12;stanford-crypto-pbkdf2;168;165;162;161;160;160;161;159;159;160 09:36:29 INFO - PROCESS | 22181 | |13;stanford-crypto-sha256-iterative;62;65;60;65;61;61;60;59;59;58 09:36:29 INFO - PROCESS | 22181 | __end_tp_report 09:36:29 INFO - PROCESS | 22181 | __start_cc_report 09:36:29 INFO - PROCESS | 22181 | _x_x_mozilla_cycle_collect,1694 09:36:29 INFO - PROCESS | 22181 | __end_cc_report 09:36:29 INFO - PROCESS | 22181 | __startTimestamp1492446989589__endTimestamp 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | ------- Summary: start ------- 09:36:29 INFO - PROCESS | 22181 | Number of tests: 14 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:3.33 (3.0%) stddev-sans-first:1.12 09:36:29 INFO - PROCESS | 22181 | Values: 102.0 112.0 111.0 112.0 113.0 114.0 112.0 112.0 112.0 110.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:119.50 stddev:15.09 (12.6%) stddev-sans-first:12.83 09:36:29 INFO - PROCESS | 22181 | Values: 152.0 115.0 121.0 152.0 118.0 116.0 116.0 117.0 138.0 118.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:0.97 09:36:29 INFO - PROCESS | 22181 | Values: 163.0 162.0 161.0 160.0 161.0 161.0 161.0 159.0 162.0 160.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#3] audio-fft Cycles:10 Average:80.30 Median:76.00 stddev:11.15 (14.7%) stddev-sans-first:11.71 09:36:29 INFO - PROCESS | 22181 | Values: 76.0 76.0 75.0 110.0 76.0 76.0 76.0 75.0 88.0 75.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:83.00 stddev:11.35 (13.7%) stddev-sans-first:11.87 09:36:29 INFO - PROCESS | 22181 | Values: 84.0 83.0 114.0 82.0 83.0 82.0 94.0 84.0 83.0 105.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 09:36:29 INFO - PROCESS | 22181 | Values: 113.0 112.0 114.0 113.0 113.0 113.0 113.0 112.0 112.0 113.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:36:29 INFO - PROCESS | 22181 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 147.0 147.0 147.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 09:36:29 INFO - PROCESS | 22181 | Values: 93.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 09:36:29 INFO - PROCESS | 22181 | Values: 55.0 55.0 55.0 55.0 56.0 57.0 56.0 55.0 56.0 55.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#9] json-stringify-tinderbox Cycles:10 Average:53.40 Median:53.50 stddev:0.84 (1.6%) stddev-sans-first:0.88 09:36:29 INFO - PROCESS | 22181 | Values: 53.0 55.0 53.0 53.0 52.0 54.0 54.0 53.0 53.0 54.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:79.00 stddev:3.43 (4.3%) stddev-sans-first:3.43 09:36:29 INFO - PROCESS | 22181 | Values: 75.0 74.0 75.0 84.0 79.0 79.0 75.0 82.0 81.0 79.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:124.00 stddev:16.77 (13.5%) stddev-sans-first:17.66 09:36:29 INFO - PROCESS | 22181 | Values: 122.0 126.0 121.0 119.0 118.0 128.0 120.0 129.0 174.0 119.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:161.00 stddev:2.88 (1.8%) stddev-sans-first:1.86 09:36:29 INFO - PROCESS | 22181 | Values: 168.0 165.0 162.0 161.0 160.0 160.0 161.0 159.0 159.0 160.0 09:36:29 INFO - PROCESS | 22181 | 09:36:29 INFO - PROCESS | 22181 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:2.40 (3.9%) stddev-sans-first:2.52 09:36:29 INFO - PROCESS | 22181 | Values: 62.0 65.0 60.0 65.0 61.0 61.0 60.0 59.0 59.0 58.0 09:36:29 INFO - PROCESS | 22181 | -------- Summary: end -------- 09:36:29 INFO - PROCESS | 22181 | 09:36:30 INFO - TEST-INFO | 22181: exit 0 09:36:30 INFO - TEST-OK | kraken | took 60677ms 09:36:30 INFO - SUITE-END | took 1176s 09:36:30 INFO - Completed test suite (00:19:36) 09:36:30 INFO - javascript benchmark 09:36:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7982.266284679174, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3406.6763657452543, 3441.277331760332, 3444.948325775114, 3476.2178509835276, 3497.422925217339, 5768.711564421777, 5833.095870140124, 5841.649501029941, 5863.032599621063, 5863.442972917572, 5891.204687367204, 5902.144189092579, 5914.408559144094, 5917.704114794265, 5934.525237980962, 5195.142801437768, 5236.371635403757, 5255.553277971377, 5261.210818377255, 5288.788448462076, 5058.367704036996, 5082.288479612857, 5282.23407605897, 5286.709230992294, 5294.285271488338, 5109.821156259532, 5242.606804489659, 5279.366476022863, 5291.550218231436, 5297.417284098746, 5282.788688452457, 5294.57643388528, 5299.311089558333, 5304.20436934459, 5310.60170487215, 5000.3249561309485, 5172.61205409592, 5223.294855194538, 5249.580033597341, 5303.390110137345, 5639.0, 5688.715564221772, 5769.913451298234, 5770.221020162308, 5779.104238842986, 6219.315875253718, 6293.46505547029, 6294.0, 6302.086197501382, 6302.180716506825, 6338.87322253557, 6348.904766428507, 6363.968180159116, 6367.108604795332, 6367.140436041168, 5575.776968921238, 5602.523785478239, 5638.718064096861, 5639.859003524863, 5640.323161220639, 6174.876502469925, 6178.320384757673, 6180.845478862974, 6181.196444462191, 6308.463780578611, 6141.486007784275, 6185.195924529782, 6200.3179650238435, 6206.10011548332, 6211.9689401553605, 6310.463610593151, 6312.274088479791, 6312.842178945563, 6313.589616674902, 6314.368563143741, 5922.200502932135, 6277.309495955441, 6281.685915704196, 6287.308396076428, 6290.968545157245, 6079.604825686316, 6138.143176733788, 6149.2313460817395, 6171.9691401543605, 6173.197484327098, 4757.119932812441, 4758.405199350082, 4762.714237145782, 4785.066911952136, 4786.329913812069, 4215.367694845736, 4226.070264541796, 4227.027783609787, 4246.320588705854, 4267.061246525759, 5790.507806836466, 5825.504832089235, 6025.758969641253, 6054.8183554493435, 6140.8771824563255, 6347.936520634735, 6360.268569114519, 6362.141110950055, 6366.649834259068, 6367.395097465734, 6094.512439004958, 6116.633002019893, 6120.387961203933, 6122.0, 6125.754969801158, 5030.346055012898, 5095.031943930641, 5251.0, 5303.946960530422, 5361.463853614685, 6509.707063182073, 6538.542302038812, 6543.70553325111, 6561.507886908501, 6563.967180164069, 6269.529785266214, 6327.335629758809, 6331.0, 6333.366663333814, 6338.90491642635, 6151.907721384272, 6185.103160041859, 6398.584092034003, 6549.574277671936, 6557.082008518906, 6155.969220153871, 6179.2584889811715, 6187.81436556886, 6190.350013248544, 6194.473469754941, 6255.843603909939, 6285.0, 6295.842603934938, 6301.96849015752, 6304.085907543472, 5822.592418530662, 5865.618734782225, 5877.265341832271, 5940.970295148497, 5941.049432090845, 5938.257717785277, 6062.969685151546, 6070.878582428239, 6151.169592105009, 6172.166757487682, 6173.783917563043, 6173.876522469615, 6175.62946223228, 6177.0, 6181.5363847710705, 6123.785667501795, 6175.722092505758, 6180.6909654517995, 6186.319504854552, 6190.50475961931, 5759.308882934075, 5801.564882633735, 5871.530277577698, 5975.880482390415, 5986.072158815216], "value": 5741.867523447482, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.4236151705466, 818.2186012358198, 837.7696133563268, 904.846176150054, 915.9496227707474, 31920.0, 43551.346729799036, 44344.55655443444, 44409.333859992046, 44415.11169776603, 45223.547764522344, 45551.31673024907, 45574.54425455753, 45650.771746141305, 45689.08621827562, 7706.845863082735, 7838.72564460244, 8951.418157819737, 9088.182063614273, 9095.590698418588, 10602.151827853791, 10828.0, 10831.133509319276, 10836.837447438294, 11205.775884482306, 10921.071708904752, 10946.124310055216, 10954.178436617245, 10960.725981850477, 10963.342199468058, 10819.83770243443, 10837.67486975392, 10838.891611083867, 10848.403337816417, 10852.891471085268, 10623.097036751886, 10658.840117398246, 10676.306040107369, 10678.412687302196, 10686.839697404544, 42421.57578424207, 42472.36291455631, 42701.57298427007, 42739.57260427387, 42770.0, 4173.290540608104, 4203.096334288142, 4259.10558782656, 4304.956950430487, 4347.065380943112, 29211.56182657262, 29563.70436295631, 29884.252893677614, 30179.849100754574, 30846.845765771253, 4323.200207961537, 4402.955970440319, 4409.823607055714, 4414.337849322627, 4473.7986790594505, 240842.0, 241310.0, 241417.99999999825, 241841.0, 242461.0, 53752.73123634357, 53764.73117634387, 53817.1927421089, 53824.73087634537, 53966.0, 56914.715426422605, 56987.71506142443, 57118.0, 57165.42834571684, 57212.14181787276, 15692.92153539248, 16043.839561604467, 16061.036337819534, 16075.356985720675, 16089.436869709503, 9652.179564737076, 9784.804303914025, 13704.725905482033, 14005.159690418608, 14028.929855350658, 13328.800067998987, 13363.1313964592, 13386.39761210748, 13420.127691700009, 13445.596632101053, 5295.637270333289, 5442.945570544323, 5450.672959622435, 5506.944930550723, 5614.6631202128, 1016.1515134862473, 1020.433659319085, 1031.5512751952924, 1037.7405648587853, 1039.5374058543875, 28917.710822891924, 29004.398240703464, 29068.563971540443, 29200.707992920223, 29238.853805730836, 1089.6022951622758, 1095.808233559124, 1106.2975010867997, 1116.0625074937093, 1116.7264020315074, 14424.06243594163, 15222.847771522365, 15254.618634534003, 15315.463958761613, 15385.384584616713, 327148.0, 327263.0, 327621.0, 327793.0, 328458.0, 57621.711891440274, 57641.13538296845, 57677.42322576804, 57775.422245777845, 57777.71111144417, 1479.8076250087636, 1517.1352329172487, 1529.1054732981108, 1538.0617823127884, 1548.1717281254475, 25940.35149121287, 27057.59413608837, 27135.57361504796, 27352.72647273502, 27445.862770686017, 43689.34465982949, 43966.0, 43972.0, 44078.0, 44136.0, 291845.0, 292292.0, 292684.0, 292812.0, 293150.0, 34485.48271775875, 34769.478457822646, 35014.12464688403, 35085.824570878, 35166.648333516336, 41668.58331416647, 42343.280473654995, 42437.0, 42983.355249670654, 43448.78275608602, 29894.0, 30520.694793051785, 31942.0, 31976.6802331983, 32292.0, 29875.850620746758, 30489.54265685972, 30498.237544061576, 33016.88079622219, 33309.16727081843, 35466.46800297946, 35605.64394356023, 35713.64286357207, 36119.81940090283, 36138.4579231317, 29439.264018398855, 30283.0, 30815.69184308218, 31080.68919310778, 31242.531362029134, 24316.27051188467, 24994.0, 25144.497110058062, 25344.52716616538, 25417.491650166525], "value": 18939.669138580804, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.46854697354814, 466.8902807840157, 472.3623108803115, 484.968477048992, 489.25877295896714, 18058.458246252616, 19232.13455394507, 20864.269750558735, 20925.37223883286, 20959.580808383824, 20395.796042039576, 20501.179952801907, 20697.586048279027, 20940.476488087807, 21092.15631374747, 6565.080888675589, 6672.2660507344035, 6894.482913781461, 6899.896501552481, 6902.965485172592, 6725.831854203635, 6740.191177058735, 6764.966175169117, 6783.559068660546, 6787.355201255898, 6796.490263230253, 6802.523823332369, 7598.696052157907, 7820.296173344398, 7864.68541258349, 7784.377249820029, 7859.096203936563, 8007.359411247145, 8043.678252869879, 8050.557219352904, 7647.082350118021, 7923.088844782808, 7927.207279272085, 7929.722459713882, 7938.206179382073, 29340.0, 29405.411891762258, 29822.0, 29976.0, 30032.0, 3064.846757662108, 3317.9834100829585, 3327.1515763480156, 3362.596488421405, 3413.675700808419, 17487.562810929827, 18388.816111838845, 18406.263749450005, 18672.81327186738, 19013.88916407445, 2094.7800480949586, 2458.8770561472033, 2616.358992046961, 2644.1803041057333, 2736.0, 116602.41698791452, 116995.0, 117740.0, 119032.0, 120133.0, 16595.842540469905, 20305.289314874204, 22496.21263255811, 22706.545869082525, 22711.545769084856, 42067.36898946518, 42160.789196053825, 42197.789011054745, 43132.784336078745, 43171.0, 10836.0, 10947.4526273686, 11027.724306892393, 11101.281070841198, 11408.714782130513, 8717.825643487095, 8793.472391656522, 8839.29285657146, 9048.728538143867, 9054.86417703735, 10836.891631083588, 11337.659870203906, 11343.319400835977, 11472.0, 11701.531938722526, 4015.257177422186, 4380.627646650007, 4442.022754993896, 4473.597376236155, 4481.9243381588185, 947.0907928388824, 950.1211379474097, 954.7326748510428, 964.262339310428, 971.7036303927229, 19670.0, 20995.895020525106, 21190.682139767607, 21225.257116001176, 22603.667094841592, 1031.3193292426963, 1061.792950374685, 1063.7340664833791, 1063.9468026598795, 1090.6455401994383, 11182.776344473064, 11351.0, 11461.197716159788, 11526.769464610661, 11529.135314851419, 716522.0, 722154.0, 726368.0, 727216.0, 744088.0, 100475.49762251142, 113874.0, 118510.0, 118664.40667796606, 120595.0, 1395.0792476965357, 1412.837523684769, 1461.7295800276984, 1472.977905331421, 1473.6978919321514, 14114.78827817563, 18098.547536311697, 19538.0, 19538.742918320648, 19726.40820775379, 50293.0, 50386.0, 50869.74565127151, 51121.48878511167, 51219.74390128026, 63762.362376377496, 63968.0, 63999.0, 64871.35128648653, 65055.67472162609, 12519.186252893533, 12813.0, 12832.165909215872, 12881.35593220354, 12891.41988610496, 13713.862861371259, 13827.654308641962, 13837.0, 13936.860631393556, 13939.372728227052, 12134.817977730165, 12284.0, 12313.815292770794, 12588.496460141492, 12676.809847852473, 11610.535578576762, 11661.416929153678, 11670.416479175838, 11729.35488548104, 11863.525458981545, 11930.463129159036, 12547.93726031364, 12569.685757856127, 12639.557615483414, 12868.22790632565, 12376.938115309365, 12378.133530652767, 12691.301978391277, 12916.870831291943, 12917.935410322889, 12365.196262242926, 12482.068148533182, 12526.498940042296, 12690.365481726061, 13040.347982601023], "value": 12064.709635142217, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1550.7983962084927, 1590.2525812867948, 1598.3446786817399, 1611.6615510742743, 1618.1909045477257, 11227.6631701049, 11907.047436205105, 11914.880851191485, 11915.344656043913, 11925.642230733081, 11243.325400475976, 11695.29828210308, 11714.297142171476, 11726.882731172685, 12070.939645301762, 2481.888315025828, 2519.7858182054547, 2527.494501099778, 2528.40582463121, 2529.6837895263093, 2277.2257432472957, 2345.9061637534564, 2373.1219448803968, 2387.0929046962215, 2389.9842566909083, 2347.0, 2367.0413482539598, 2372.822038347122, 2474.7772700456953, 2492.0779311654624, 2283.2579411691268, 2314.872682002489, 2420.5037967216767, 2425.0663494554638, 2426.672399226108, 2359.4927090675587, 2381.2856143156982, 2393.748656391089, 2399.016403274649, 2400.135951057618, 11765.529378824836, 11991.100667449977, 12039.0, 12045.39773011346, 12051.698707532294, 5758.884822303572, 5889.79385721502, 5900.350961394243, 5902.527797776168, 5906.704664766788, 3576.445650924111, 3671.2106897017, 3726.981365093184, 3761.567419746709, 3794.753341032824, 1401.5655146904492, 2298.770122987705, 2348.5420343033115, 2357.552065107624, 2392.736798952114, 15373.077615343003, 15678.843211567852, 17076.743848842154, 17521.561960950952, 17614.823851761448, 4733.786979585858, 4809.014152098812, 4919.0, 4966.255061740696, 4966.478519755446, 15115.244237788067, 15128.319225634872, 15129.848701513065, 15131.924340378227, 15135.091894486366, 6040.667763272974, 6088.756449741961, 6095.238095238095, 6100.450959413674, 6102.206713127353, 5361.597880159004, 5382.892342153135, 5431.511163995259, 5456.454354564591, 5503.972480137573, 4148.340663734531, 4159.438475805788, 4259.74441533509, 4289.1850548395705, 4301.591348821857, 1080.1790639114329, 1085.8154113800672, 1094.4746521669642, 1095.8301463273206, 1097.7639807441437, 1019.1795604538418, 1035.254616675992, 1046.3512622174148, 1047.1518070363031, 1055.8680164979378, 7042.894356584656, 7049.295070493013, 7148.535345202546, 7235.131784185932, 7264.564126152448, 1037.2894567221476, 1054.541274545575, 1057.4033209853023, 1071.9410432426134, 1090.7545802194602, 4594.770261486912, 4679.766011699401, 4719.197736384823, 4797.95202047982, 4815.711057336571, 14948.40206391754, 15510.767338489932, 15543.0, 15607.687846243012, 15611.375544978073, 14664.633384165481, 14868.925655371653, 15115.470958516618, 15240.542783716506, 15297.6175595611, 8910.59902304385, 10792.730181745519, 10996.835047474295, 11043.282186657842, 11053.226274160892, 6183.628982261079, 7001.019857220095, 7305.671244793891, 7315.8536829264185, 7387.4090072795125, 12618.43217055253, 13099.410526526139, 13259.867401325862, 13322.866771332163, 13439.932800335935, 13201.405936732677, 13383.19700817954, 13559.728805424034, 13607.319634018457, 13795.0, 10147.18822494184, 10279.0, 10335.94832025835, 10386.532606032897, 10526.631567895087, 11127.165462590176, 11200.943995279971, 11312.886871131182, 11334.773304534028, 11356.716082098013, 9514.85727714071, 9624.181944534794, 9750.561224744763, 9763.658271960447, 9842.311038227255, 9349.205317547812, 9459.148676619137, 9486.95256523713, 9608.855867161858, 9880.654177103766, 7813.101493328113, 7849.803754906173, 7907.090684571348, 7965.721199757981, 7975.202479751978, 5937.673427961331, 6005.1892994446935, 6084.269887613515, 6102.603330783485, 6184.257889053342, 6096.481799047131, 6264.624122552661, 6284.183056202664, 6290.276618188784, 6530.967345163243], "value": 5567.970262418006, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.6298457357525, 426.81860209411, 434.76305413549613, 440.37466797148045, 443.48555675416526, 5227.320448341715, 5280.3663560372715, 5289.365276166861, 5303.7348132593315, 5320.813771517997, 5024.497550244982, 5196.766145523451, 5200.893638076575, 5201.895962080756, 5229.50319719627, 4290.4636920384955, 4307.5261721210645, 4322.243607368706, 4338.197433474801, 4338.804753786071, 4310.288802347609, 4317.633001194902, 4321.913561728764, 4327.718698284617, 4329.220740266751, 4307.224699554079, 4309.547497512763, 4328.675349348811, 4342.199281463454, 4356.194104090738, 4150.83396664133, 4282.421873047146, 4305.763183024932, 4334.612923864364, 4344.0, 4057.7971101445105, 4076.408920706511, 4088.3867419887256, 4090.386442033689, 4091.161311931077, 5091.988664477548, 5095.949040509585, 5115.335006449175, 5118.718470484122, 5131.512506311894, 4938.432080310774, 4966.900661986776, 4998.300237966688, 5050.84847454577, 5081.974590127063, 5449.3188351456065, 5457.126859702428, 5481.479259470344, 5485.753141108659, 5501.202325662756, 4526.34368016639, 4548.954510454886, 4555.362249285103, 4557.692980133775, 4572.497025327211, 5519.420460851633, 5654.897295027471, 5666.829995100154, 5667.971660141714, 5686.914696279559, 4625.329327247598, 4648.953510464919, 4654.138984287918, 4657.394538709946, 4666.976665116653, 4733.573978341966, 4780.402449693788, 4798.329278102255, 4798.49615790344, 4800.85543968701, 4863.392075990501, 5026.648134630614, 5044.7802966694535, 5052.393712754493, 5062.190049592121, 5459.918101228484, 5469.972650136724, 5481.451854814487, 5490.3411590609385, 5508.421615730291, 1551.2166355990153, 1636.2636813433764, 1646.1851383565213, 1655.973296556143, 1657.395050806471, 1245.2155142260317, 1249.3066348176667, 1259.9164718342217, 1282.557517656407, 1289.5873320537337, 5311.681299122065, 5365.436629153961, 5388.164834450667, 5396.488983084742, 5451.64564303319, 5489.945100549024, 5559.749811258503, 5579.386267510652, 5656.575756818255, 5662.782501762059, 5190.662606930538, 5267.174430262324, 5327.295265514998, 5369.543588794996, 5412.5940554458575, 2921.0798598441424, 2930.296728785076, 2935.5890175375466, 2936.8986630013746, 2955.7635389168813, 5802.82591522255, 5819.650820950672, 5836.620619659709, 5849.181114643953, 5856.385079566669, 5552.7223638817895, 5589.009879714334, 5677.126548239071, 5742.454466825729, 5770.855728606734, 5755.59710820247, 5793.740441227925, 5815.331236907809, 5818.301803783573, 5843.707814609337, 5602.254895101452, 5610.747516361856, 5613.382527921844, 5631.549476041989, 5685.687287199244, 5302.310699609026, 5538.196961440649, 5540.584456165884, 5557.5553955682635, 5567.276254086943, 5796.855078623068, 5820.854478638068, 5829.883402332014, 5831.7375718093635, 5832.416758324133, 4450.443694538183, 4628.745419001987, 4649.535046495459, 4680.274557443602, 4690.296455531698, 5619.634723742944, 5637.534241097263, 5647.830565083054, 5694.402087780723, 5709.857253568528, 5474.822913073708, 5482.890342193054, 5531.751071201725, 5556.861078473071, 5563.638363506359, 5276.314079169683, 5404.108322126962, 5501.889962200654, 5535.474129957647, 5573.749181286771, 5539.639923404966, 5574.554035677217, 5589.692566908697, 5590.385057643737, 5590.909772594305, 5480.808171713971, 5570.749316280696, 5573.359063707726, 5586.636868603528, 5606.411326810626, 5182.9374978130145, 5388.568914486753, 5396.595255355785, 5416.918746218888, 5421.83734487966], "value": 4412.443007569754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.9313502970572, 895.8118795053039, 918.3984490158946, 930.6463543853336, 935.2704890185653, 16482.42311519097, 16724.331026758915, 17388.65222695545, 17427.302907883684, 18793.906030469847, 15750.291236894338, 15766.047048362196, 15822.76265856013, 16336.34654613817, 16587.917060414682, 876.3733930239894, 897.2463130969984, 899.0964081098487, 902.5938327752505, 903.439867282286, 887.9600417981206, 901.378049146087, 904.4030939579878, 904.6291020681522, 904.728581425573, 959.596969272907, 963.4315753705313, 964.4791812421284, 964.6816550538306, 965.932384733069, 952.4285428742734, 955.8088382323493, 957.0907637744136, 957.1816097236866, 957.7126861941459, 697.3305626598471, 697.7243988624516, 698.6925752668809, 700.5726506830829, 700.6636814329099, 18210.726839097424, 18316.450506484827, 18489.445316640522, 18588.44234672962, 18610.62778744431, 15848.92075539615, 16149.0, 16172.433964811175, 16225.675486490321, 16614.916925415295, 13887.166769993833, 14080.225587592678, 14233.359498822474, 14243.074200176956, 14321.78517322241, 3956.2680904032845, 4014.759114453113, 4034.5763694811917, 4068.105016896278, 4159.188958153161, 50223.49776502225, 65300.0, 81319.18680813175, 83569.0, 86684.0, 12711.237325760483, 12914.870851291367, 13079.934600327128, 13101.868981310256, 13261.270630115243, 20992.0, 23253.883730581238, 23328.766712333, 23495.765042349358, 23680.0, 9039.412438191497, 9439.433633981984, 9515.524223788922, 9570.56932438042, 9579.952100239594, 4986.077575648516, 5082.618803589746, 5091.235417289815, 5105.106606343949, 5199.63602547818, 3913.412988051815, 4022.798860057044, 4030.0327921298676, 4030.3349947258375, 4031.3146765049423, 672.7040102355046, 680.4012469027224, 682.2563405887606, 687.0552989639247, 687.2405991379553, 797.6091715059579, 800.4236949396425, 816.8856360109589, 816.9060558035783, 837.660747397305, 11700.765984680258, 11827.0, 11883.88116118845, 11986.220895641756, 12004.459799308877, 851.5188918261287, 856.9271611913057, 859.8968123825056, 861.4142383179372, 864.6325311742485, 5554.833354999357, 5778.855528611734, 5960.612560183574, 6204.472619827366, 6384.233891932998, 78486.0, 81526.0, 83828.58085709655, 85567.14432855716, 87289.5635521831, 71294.64352678307, 72693.0, 73223.63388183025, 73349.6332518334, 74265.62867185738, 50733.0, 60768.0, 60832.39167608356, 60842.69578652078, 61330.999999999105, 11492.770144596894, 11894.643160705193, 11934.164608477324, 12249.632511024683, 12264.87735122673, 43653.34519982139, 44446.555534445535, 44716.32925506185, 44754.77622611996, 45316.09367812691, 52354.21468677896, 52718.73640631772, 52962.0, 53106.0, 54786.0, 13667.521636742666, 14199.64500887486, 14242.572722818331, 14339.713205736036, 14507.78238326447, 15438.768418473508, 15479.458218962733, 16101.0, 16624.916875415547, 17011.829881701025, 15346.23268836576, 15942.601434963755, 16013.599660008593, 16024.919875400548, 16793.496195113676, 14081.295935203403, 14647.12117272967, 14804.407823687363, 15041.323140458488, 15513.689726205639, 14978.850211497745, 15749.921250393674, 15923.203839807731, 16159.67680646404, 16705.498835034967, 10002.24983126254, 10092.949535252277, 10162.085412312927, 10284.537195826355, 10497.370157790558, 12218.877811221773, 12250.20373675744, 12440.129190956546, 12470.438830252842, 12530.686732831753], "value": 8024.170836608043, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1458.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 112.0, 113.0, 114.0, 112.0, 112.0, 112.0, 110.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 121.0, 152.0, 118.0, 116.0, 116.0, 117.0, 138.0, 118.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 161.0, 160.0, 161.0, 161.0, 161.0, 159.0, 162.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 110.0, 76.0, 76.0, 76.0, 75.0, 88.0, 75.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 114.0, 82.0, 83.0, 82.0, 94.0, 84.0, 83.0, 105.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0, 112.0, 112.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 56.0, 57.0, 56.0, 55.0, 56.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 53.0, 52.0, 54.0, 54.0, 53.0, 53.0, 54.0], "value": 53.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 84.0, 79.0, 79.0, 75.0, 82.0, 81.0, 79.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 126.0, 121.0, 119.0, 118.0, 128.0, 120.0, 129.0, 174.0, 119.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 162.0, 161.0, 160.0, 160.0, 161.0, 159.0, 159.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 60.0, 65.0, 61.0, 61.0, 60.0, 59.0, 59.0, 58.0], "value": 61.0, "unit": "score"}]}]} 09:36:30 INFO - Return code: 0 09:36:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:30 ERROR - # TBPL SUCCESS # 09:36:30 INFO - Running post-action listener: _resource_record_post_action 09:36:30 INFO - [mozharness: 2017-04-17 16:36:30.928694Z] Finished run-tests step (success) 09:36:30 INFO - Running post-run listener: _resource_record_post_run 09:36:31 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 369909760; Read time: 12; Write time: 414404 09:36:31 INFO - TinderboxPrint: CPU usage
13.1% 09:36:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:36:31 INFO - TinderboxPrint: I/O write bytes / time
369,909,760 / 414,404 09:36:31 INFO - TinderboxPrint: CPU idle
8,268.5 (86.8%) 09:36:31 INFO - TinderboxPrint: CPU user
1,202.0 (12.6%) 09:36:31 INFO - TinderboxPrint: Swap in / out
0 / 0 09:36:31 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118132736; Read time: 0; Write time: 158744 09:36:31 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 251199488; Read time: 0; Write time: 255564 09:36:31 INFO - Running post-run listener: _upload_blobber_files 09:36:31 INFO - Blob upload gear active. 09:36:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:36:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:32 INFO - (blobuploader) - INFO - Done attempting. 09:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:36:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:33 INFO - (blobuploader) - INFO - Done attempting. 09:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:34 INFO - (blobuploader) - INFO - Done attempting. 09:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:37 INFO - (blobuploader) - INFO - Done attempting. 09:36:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:37 INFO - Return code: 0 09:36:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:37 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32d0b591dc300869fcd6ce51075a75f84f4473212bdd438d4a6147f2f40bee20806e361ad7d5a28f299a1c054e64cfabe72be5c24d203a84fd63ae2abdb79900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/914d8e957a22af78c3d79f4a50cf070bba2d85e04e59cdaef7d74e279f70ab2ca901ecc77cec78a4ae4ade7644f663afb608d866f9178eaba9993c9228a301f5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/948d832443510e2720c53fa145314d4e3588771e20a84f937e49e97ba13f586977341993eb6c7f25039c6a699879e1cea95fac1472f80c0472add275b805ef14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d884af4e5e8fa85f486c4de5aa3681e0d5a28ef8766dccecfc64c25912f2a539683222fd2f3ac55a151e07544bcdb617c390adddc2b4703ad18a92be2597155"} 09:36:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:37 INFO - Contents: 09:36:37 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32d0b591dc300869fcd6ce51075a75f84f4473212bdd438d4a6147f2f40bee20806e361ad7d5a28f299a1c054e64cfabe72be5c24d203a84fd63ae2abdb79900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/914d8e957a22af78c3d79f4a50cf070bba2d85e04e59cdaef7d74e279f70ab2ca901ecc77cec78a4ae4ade7644f663afb608d866f9178eaba9993c9228a301f5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/948d832443510e2720c53fa145314d4e3588771e20a84f937e49e97ba13f586977341993eb6c7f25039c6a699879e1cea95fac1472f80c0472add275b805ef14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d884af4e5e8fa85f486c4de5aa3681e0d5a28ef8766dccecfc64c25912f2a539683222fd2f3ac55a151e07544bcdb617c390adddc2b4703ad18a92be2597155"} 09:36:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:37 INFO - Copying logs to upload dir... 09:36:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.356147 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-17 09:36:37.159044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 09:36:37.162966) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32d0b591dc300869fcd6ce51075a75f84f4473212bdd438d4a6147f2f40bee20806e361ad7d5a28f299a1c054e64cfabe72be5c24d203a84fd63ae2abdb79900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/914d8e957a22af78c3d79f4a50cf070bba2d85e04e59cdaef7d74e279f70ab2ca901ecc77cec78a4ae4ade7644f663afb608d866f9178eaba9993c9228a301f5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/948d832443510e2720c53fa145314d4e3588771e20a84f937e49e97ba13f586977341993eb6c7f25039c6a699879e1cea95fac1472f80c0472add275b805ef14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d884af4e5e8fa85f486c4de5aa3681e0d5a28ef8766dccecfc64c25912f2a539683222fd2f3ac55a151e07544bcdb617c390adddc2b4703ad18a92be2597155"} build_url:https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/CM8mFEhLRNa_MrlhhwCd0g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32d0b591dc300869fcd6ce51075a75f84f4473212bdd438d4a6147f2f40bee20806e361ad7d5a28f299a1c054e64cfabe72be5c24d203a84fd63ae2abdb79900", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/914d8e957a22af78c3d79f4a50cf070bba2d85e04e59cdaef7d74e279f70ab2ca901ecc77cec78a4ae4ade7644f663afb608d866f9178eaba9993c9228a301f5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/948d832443510e2720c53fa145314d4e3588771e20a84f937e49e97ba13f586977341993eb6c7f25039c6a699879e1cea95fac1472f80c0472add275b805ef14", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d884af4e5e8fa85f486c4de5aa3681e0d5a28ef8766dccecfc64c25912f2a539683222fd2f3ac55a151e07544bcdb617c390adddc2b4703ad18a92be2597155"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 09:36:37.188929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:36:37.189377) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1492445744.62729-1178334565 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004563 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:36:37.271488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 09:36:37.271835) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 09:36:37.272166) ========= ========= Total master_lag: 0.17 =========