builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-040 starttime: 1471982772.91 results: success (0) buildid: 20160823103647 builduid: 50d7bc2eabcd4906a2e5a33581301de0 revision: 7214320961eda063ecef1be314c1c4da9b949dbe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:12.914525) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:12.915001) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:12.915309) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:12.977199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:12.977510) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-23 13:06:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 19.2M=0.001s 2016-08-23 13:06:13 (19.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131215 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.201498) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.201819) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014077 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.230693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.231021) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 7214320961eda063ecef1be314c1c4da9b949dbe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 7214320961eda063ecef1be314c1c4da9b949dbe --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=9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-23 13:06:13,603 truncating revision to first 12 chars 2016-08-23 13:06:13,603 Setting DEBUG logging. 2016-08-23 13:06:13,603 attempt 1/10 2016-08-23 13:06:13,603 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/7214320961ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 13:06:13,874 unpacking tar archive at: larch-7214320961ed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.555814 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.801120) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.801447) ========= script_repo_revision: 7214320961eda063ecef1be314c1c4da9b949dbe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.801868) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.802159) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 13:06:13.812950) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-23 13:06:13.813238) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:06:14 INFO - MultiFileLogger online at 20160823 13:06:14 in /builds/slave/test 13:06:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 13:06:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:06:14 INFO - {'append_to_log': False, 13:06:14 INFO - 'base_work_dir': '/builds/slave/test', 13:06:14 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 13:06:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:06:14 INFO - 'branch': 'Larch-Non-PGO', 13:06:14 INFO - 'buildbot_json_path': 'buildprops.json', 13:06:14 INFO - 'config_files': ('talos/linux_config.py',), 13:06:14 INFO - 'default_actions': ('clobber', 13:06:14 INFO - 'read-buildbot-config', 13:06:14 INFO - 'download-and-extract', 13:06:14 INFO - 'populate-webroot', 13:06:14 INFO - 'create-virtualenv', 13:06:14 INFO - 'install', 13:06:14 INFO - 'run-tests'), 13:06:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:06:14 INFO - 'download_minidump_stackwalk': True, 13:06:14 INFO - 'download_symbols': 'ondemand', 13:06:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:06:14 INFO - 'tooltool.py': '/tools/tooltool.py', 13:06:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:06:14 INFO - '/tools/misc-python/virtualenv.py')}, 13:06:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:06:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:06:14 INFO - 'installer_path': 'installer.exe', 13:06:14 INFO - 'log_level': 'info', 13:06:14 INFO - 'log_name': 'talos', 13:06:14 INFO - 'log_to_console': True, 13:06:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:06:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:06:14 INFO - 'opt_config_files': (), 13:06:14 INFO - 'pip_index': False, 13:06:14 INFO - 'sps_profile': False, 13:06:14 INFO - 'sps_profile_interval': 0, 13:06:14 INFO - 'suite': 'dromaeojs', 13:06:14 INFO - 'system_bits': '32', 13:06:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:06:14 INFO - 'title': 'talos-linux64-ix-040', 13:06:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:06:14 INFO - 'use_talos_json': True, 13:06:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:06:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:06:14 INFO - 'work_dir': 'build'} 13:06:14 INFO - [mozharness: 2016-08-23 20:06:14.278211Z] Running clobber step. 13:06:14 INFO - Running pre-action listener: _resource_record_pre_action 13:06:14 INFO - Running main action method: clobber 13:06:14 INFO - rmtree: /builds/slave/test/build 13:06:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:06:15 INFO - Running post-action listener: _resource_record_post_action 13:06:15 INFO - [mozharness: 2016-08-23 20:06:15.255801Z] Finished clobber step (success) 13:06:15 INFO - [mozharness: 2016-08-23 20:06:15.255902Z] Running read-buildbot-config step. 13:06:15 INFO - Running pre-action listener: _resource_record_pre_action 13:06:15 INFO - Running main action method: read_buildbot_config 13:06:15 INFO - Using buildbot properties: 13:06:15 INFO - { 13:06:15 INFO - "project": "", 13:06:15 INFO - "product": "firefox", 13:06:15 INFO - "script_repo_revision": "production", 13:06:15 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 13:06:15 INFO - "repository": "", 13:06:15 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 13:06:15 INFO - "buildid": "20160823103647", 13:06:15 INFO - "pgo_build": "False", 13:06:15 INFO - "basedir": "/builds/slave/test", 13:06:15 INFO - "buildnumber": 2, 13:06:15 INFO - "slavename": "talos-linux64-ix-040", 13:06:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:06:15 INFO - "platform": "ubuntu64_hw", 13:06:15 INFO - "scheduler": "tests-larch-linux64-talos", 13:06:15 INFO - "branch": "larch", 13:06:15 INFO - "repo_path": "projects/larch", 13:06:15 INFO - "revision": "7214320961eda063ecef1be314c1c4da9b949dbe", 13:06:15 INFO - "stage_platform": "linux64", 13:06:15 INFO - "builduid": "50d7bc2eabcd4906a2e5a33581301de0", 13:06:15 INFO - "slavebuilddir": "test" 13:06:15 INFO - } 13:06:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 13:06:15 INFO - Running post-action listener: _resource_record_post_action 13:06:15 INFO - [mozharness: 2016-08-23 20:06:15.258337Z] Finished read-buildbot-config step (success) 13:06:15 INFO - [mozharness: 2016-08-23 20:06:15.258425Z] Running download-and-extract step. 13:06:15 INFO - Running pre-action listener: _resource_record_pre_action 13:06:15 INFO - Running main action method: download_and_extract 13:06:15 INFO - mkdir: /builds/slave/test/build/tests 13:06:15 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')]} 13:06:15 INFO - trying https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 13:06:15 INFO - Downloading https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 13:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:06:15 INFO - Downloaded 1361 bytes. 13:06:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 13:06:15 INFO - Using the following test package requirements: 13:06:15 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 13:06:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 13:06:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 13:06:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'jsshell-linux-x86_64.zip'], 13:06:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 13:06:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 13:06:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 13:06:15 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 13:06:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 13:06:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 13:06:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 13:06:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:06:15 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')]} 13:06:15 INFO - trying https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:06:15 INFO - Downloading https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:06:16 INFO - Downloaded 20330692 bytes. 13:06:16 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 13:06:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:06: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')]} 13:06:20 INFO - trying https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:06:20 INFO - Downloading https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 13:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:06:21 INFO - Downloaded 20330692 bytes. 13:06:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 13:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:26 INFO - trying https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 13:06:26 INFO - Downloading https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 13:06:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:06:27 INFO - Downloaded 14413981 bytes. 13:06:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 13:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:27 INFO - trying https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:06:27 INFO - Downloading https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:06:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:06:28 INFO - Downloaded 55747458 bytes. 13:06:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:06:28 INFO - mkdir: /builds/slave/test/properties 13:06:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:06:28 INFO - Writing to file /builds/slave/test/properties/build_url 13:06:28 INFO - Contents: 13:06:28 INFO - build_url:https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 13:06:29 INFO - Running post-action listener: _resource_record_post_action 13:06:29 INFO - Running post-action listener: set_extra_try_arguments 13:06:29 INFO - [mozharness: 2016-08-23 20:06:29.015350Z] Finished download-and-extract step (success) 13:06:29 INFO - [mozharness: 2016-08-23 20:06:29.015574Z] Running populate-webroot step. 13:06:29 INFO - Running pre-action listener: _resource_record_pre_action 13:06:29 INFO - Running main action method: populate_webroot 13:06:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:06:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:06:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:06:29 INFO - '--tppagecycles', 13:06:29 INFO - '7'], 13:06:29 INFO - 'tests': ['tsvgm']}}, 13:06:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:06:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:06:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:06:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:06:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:06:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:06:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['damp', 'tps']}, 13:06:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'tests': ['damp', 'tps']}, 13:06:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['dromaeo_dom']}, 13:06:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:06:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['basic_compositor_video']}, 13:06:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:06:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['a11yr', 13:06:29 INFO - 'ts_paint', 13:06:29 INFO - 'tpaint', 13:06:29 INFO - 'sessionrestore', 13:06:29 INFO - 'sessionrestore_no_auto_restore', 13:06:29 INFO - 'tabpaint']}, 13:06:29 INFO - 'other-e10s': {'tests': ['a11yr', 13:06:29 INFO - 'ts_paint', 13:06:29 INFO - 'tpaint', 13:06:29 INFO - 'sessionrestore', 13:06:29 INFO - 'sessionrestore_no_auto_restore', 13:06:29 INFO - 'tabpaint']}, 13:06:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:06:29 INFO - 'ts_paint', 13:06:29 INFO - 'tpaint', 13:06:29 INFO - 'sessionrestore', 13:06:29 INFO - 'sessionrestore_no_auto_restore', 13:06:29 INFO - 'tabpaint']}, 13:06:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:06:29 INFO - 'ts_paint', 13:06:29 INFO - 'tpaint', 13:06:29 INFO - 'sessionrestore', 13:06:29 INFO - 'sessionrestore_no_auto_restore', 13:06:29 INFO - 'tabpaint']}, 13:06:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['a11yr', 13:06:29 INFO - 'ts_paint', 13:06:29 INFO - 'tpaint', 13:06:29 INFO - 'sessionrestore', 13:06:29 INFO - 'sessionrestore_no_auto_restore', 13:06:29 INFO - 'tabpaint']}, 13:06:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['a11yr', 13:06:29 INFO - 'ts_paint', 13:06:29 INFO - 'tpaint', 13:06:29 INFO - 'sessionrestore', 13:06:29 INFO - 'sessionrestore_no_auto_restore', 13:06:29 INFO - 'tabpaint']}, 13:06:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['tsvgx', 13:06:29 INFO - 'tsvgr_opacity', 13:06:29 INFO - 'tart', 13:06:29 INFO - 'tscrollx', 13:06:29 INFO - 'cart']}, 13:06:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:06:29 INFO - 'tsvgr_opacity', 13:06:29 INFO - 'tart', 13:06:29 INFO - 'tscrollx', 13:06:29 INFO - 'cart']}, 13:06:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'talos_options': ['--disable-e10s'], 13:06:29 INFO - 'tests': ['tp5o']}, 13:06:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'tests': ['tp5o']}, 13:06:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'talos_options': ['--disable-e10s', 13:06:29 INFO - '--xperf_path', 13:06:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:06:29 INFO - 'tests': ['tp5n']}, 13:06:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:29 INFO - 'talos_options': ['--xperf_path', 13:06:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:06:29 INFO - 'tests': ['tp5n']}}, 13:06:29 INFO - 'talos.zip': {'path': '', 13:06:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:06:29 INFO - Running post-action listener: _resource_record_post_action 13:06:29 INFO - [mozharness: 2016-08-23 20:06:29.034232Z] Finished populate-webroot step (success) 13:06:29 INFO - [mozharness: 2016-08-23 20:06:29.034319Z] Running create-virtualenv step. 13:06:29 INFO - Running pre-action listener: _resource_record_pre_action 13:06:29 INFO - Running main action method: create_virtualenv 13:06:29 INFO - Creating virtualenv /builds/slave/test/build/venv 13:06:29 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 13:06:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:06:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:06:29 INFO - Using real prefix '/usr' 13:06:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:06:29 INFO - Installing distribute.............................................................................................................................................................................................done. 13:06:31 INFO - Installing pip.................done. 13:06:31 INFO - Return code: 0 13:06:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:31 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 13:06:31 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 13:06:31 INFO - Using env: {'DISPLAY': ':0', 13:06:31 INFO - 'HOME': '/home/cltbld', 13:06:31 INFO - 'LANG': 'en_US.UTF-8', 13:06:31 INFO - 'LANGUAGE': 'en_US:en', 13:06:31 INFO - 'LOGNAME': 'cltbld', 13:06:31 INFO - 'MAIL': '/var/mail/cltbld', 13:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:31 INFO - 'MOZ_NO_REMOTE': '1', 13:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:31 INFO - 'NO_EM_RESTART': '1', 13:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:31 INFO - 'PWD': '/builds/slave/test', 13:06:31 INFO - 'SHELL': '/bin/bash', 13:06:31 INFO - 'SHLVL': '1', 13:06:31 INFO - 'TERM': 'linux', 13:06:31 INFO - 'TMOUT': '86400', 13:06:31 INFO - 'USER': 'cltbld', 13:06:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:31 INFO - '_': '/tools/buildbot/bin/python'} 13:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:31 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 13:06:31 INFO - Cleaning up... 13:06:31 INFO - Return code: 0 13:06:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:31 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 13:06:31 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 13:06:31 INFO - Using env: {'DISPLAY': ':0', 13:06:31 INFO - 'HOME': '/home/cltbld', 13:06:31 INFO - 'LANG': 'en_US.UTF-8', 13:06:31 INFO - 'LANGUAGE': 'en_US:en', 13:06:31 INFO - 'LOGNAME': 'cltbld', 13:06:31 INFO - 'MAIL': '/var/mail/cltbld', 13:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:31 INFO - 'MOZ_NO_REMOTE': '1', 13:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:31 INFO - 'NO_EM_RESTART': '1', 13:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:31 INFO - 'PWD': '/builds/slave/test', 13:06:31 INFO - 'SHELL': '/bin/bash', 13:06:31 INFO - 'SHLVL': '1', 13:06:31 INFO - 'TERM': 'linux', 13:06:31 INFO - 'TMOUT': '86400', 13:06:31 INFO - 'USER': 'cltbld', 13:06:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:31 INFO - '_': '/tools/buildbot/bin/python'} 13:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:32 INFO - Downloading/unpacking psutil>=3.1.1 13:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:06:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:06:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:06:34 INFO - Installing collected packages: psutil 13:06:34 INFO - Running setup.py install for psutil 13:06:34 INFO - building 'psutil._psutil_linux' extension 13:06:34 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 13:06:34 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 13:06:34 INFO - building 'psutil._psutil_posix' extension 13:06:34 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 13:06:34 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 13:06:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:06:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:06:34 INFO - Successfully installed psutil 13:06:34 INFO - Cleaning up... 13:06:34 INFO - Return code: 0 13:06:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:06:34 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')]} 13:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:34 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')]} 13:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:34 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:34 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 13:06:34 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 13:06:34 INFO - Using env: {'DISPLAY': ':0', 13:06:34 INFO - 'HOME': '/home/cltbld', 13:06:34 INFO - 'LANG': 'en_US.UTF-8', 13:06:34 INFO - 'LANGUAGE': 'en_US:en', 13:06:34 INFO - 'LOGNAME': 'cltbld', 13:06:34 INFO - 'MAIL': '/var/mail/cltbld', 13:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:34 INFO - 'MOZ_NO_REMOTE': '1', 13:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:34 INFO - 'NO_EM_RESTART': '1', 13:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:34 INFO - 'PWD': '/builds/slave/test', 13:06:34 INFO - 'SHELL': '/bin/bash', 13:06:34 INFO - 'SHLVL': '1', 13:06:34 INFO - 'TERM': 'linux', 13:06:34 INFO - 'TMOUT': '86400', 13:06:34 INFO - 'USER': 'cltbld', 13:06:34 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:34 INFO - '_': '/tools/buildbot/bin/python'} 13:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06: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 13:06:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:06:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:06:36 INFO - Installing collected packages: mozsystemmonitor 13:06:36 INFO - Running setup.py install for mozsystemmonitor 13:06:36 INFO - Successfully installed mozsystemmonitor 13:06:36 INFO - Cleaning up... 13:06:36 INFO - Return code: 0 13:06:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:06: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')]} 13:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06: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')]} 13:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06: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==2.5.1'] in /builds/slave/test/build 13:06: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==2.5.1 13:06:36 INFO - Using env: {'DISPLAY': ':0', 13:06:36 INFO - 'HOME': '/home/cltbld', 13:06:36 INFO - 'LANG': 'en_US.UTF-8', 13:06:36 INFO - 'LANGUAGE': 'en_US:en', 13:06:36 INFO - 'LOGNAME': 'cltbld', 13:06:36 INFO - 'MAIL': '/var/mail/cltbld', 13:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:36 INFO - 'MOZ_NO_REMOTE': '1', 13:06:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:36 INFO - 'NO_EM_RESTART': '1', 13:06:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:36 INFO - 'PWD': '/builds/slave/test', 13:06:36 INFO - 'SHELL': '/bin/bash', 13:06:36 INFO - 'SHLVL': '1', 13:06:36 INFO - 'TERM': 'linux', 13:06:36 INFO - 'TMOUT': '86400', 13:06:36 INFO - 'USER': 'cltbld', 13:06:36 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:36 INFO - '_': '/tools/buildbot/bin/python'} 13:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:37 INFO - Downloading/unpacking jsonschema==2.5.1 13:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:06:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:39 INFO - Downloading functools32-3.2.3-2.tar.gz 13:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:06:39 INFO - warning: no files found matching '*.txt' 13:06:39 INFO - no previously-included directories found matching 'build' 13:06:39 INFO - no previously-included directories found matching 'dist' 13:06:39 INFO - no previously-included directories found matching '.git*' 13:06:39 INFO - Installing collected packages: jsonschema, functools32 13:06:39 INFO - Running setup.py install for functools32 13:06:39 INFO - warning: no files found matching '*.txt' 13:06:39 INFO - no previously-included directories found matching 'build' 13:06:39 INFO - no previously-included directories found matching 'dist' 13:06:39 INFO - no previously-included directories found matching '.git*' 13:06:39 INFO - Successfully installed jsonschema functools32 13:06:39 INFO - Cleaning up... 13:06:39 INFO - Return code: 0 13:06:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:39 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:39 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 13:06:39 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 13:06:39 INFO - Using env: {'DISPLAY': ':0', 13:06:39 INFO - 'HOME': '/home/cltbld', 13:06:39 INFO - 'LANG': 'en_US.UTF-8', 13:06:39 INFO - 'LANGUAGE': 'en_US:en', 13:06:39 INFO - 'LOGNAME': 'cltbld', 13:06:39 INFO - 'MAIL': '/var/mail/cltbld', 13:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:39 INFO - 'MOZ_NO_REMOTE': '1', 13:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:39 INFO - 'NO_EM_RESTART': '1', 13:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:39 INFO - 'PWD': '/builds/slave/test', 13:06:39 INFO - 'SHELL': '/bin/bash', 13:06:39 INFO - 'SHLVL': '1', 13:06:39 INFO - 'TERM': 'linux', 13:06:39 INFO - 'TMOUT': '86400', 13:06:39 INFO - 'USER': 'cltbld', 13:06:39 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:39 INFO - '_': '/tools/buildbot/bin/python'} 13:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:06:39 INFO - Cleaning up... 13:06:39 INFO - Return code: 0 13:06:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:39 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:39 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 13:06:39 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 13:06:39 INFO - Using env: {'DISPLAY': ':0', 13:06:39 INFO - 'HOME': '/home/cltbld', 13:06:39 INFO - 'LANG': 'en_US.UTF-8', 13:06:39 INFO - 'LANGUAGE': 'en_US:en', 13:06:39 INFO - 'LOGNAME': 'cltbld', 13:06:39 INFO - 'MAIL': '/var/mail/cltbld', 13:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:39 INFO - 'MOZ_NO_REMOTE': '1', 13:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:39 INFO - 'NO_EM_RESTART': '1', 13:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:39 INFO - 'PWD': '/builds/slave/test', 13:06:39 INFO - 'SHELL': '/bin/bash', 13:06:39 INFO - 'SHLVL': '1', 13:06:39 INFO - 'TERM': 'linux', 13:06:39 INFO - 'TMOUT': '86400', 13:06:39 INFO - 'USER': 'cltbld', 13:06:39 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:39 INFO - '_': '/tools/buildbot/bin/python'} 13:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:39 INFO - Downloading/unpacking blobuploader==1.2.4 13:06:39 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 13:06:39 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 13:06:39 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 13:06:39 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 13:06:39 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 13:06:39 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 13:06:41 INFO - Downloading blobuploader-1.2.4.tar.gz 13:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:06:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:06:41 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 13:06:41 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 13:06:41 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 13:06:41 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 13:06:41 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 13:06:41 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 13:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:06:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 INFO - Downloading docopt-0.6.1.tar.gz 13:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:06:42 INFO - Installing collected packages: blobuploader, requests, docopt 13:06:42 INFO - Running setup.py install for blobuploader 13:06:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:06:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:06:42 INFO - Running setup.py install for requests 13:06:42 INFO - Running setup.py install for docopt 13:06:43 INFO - Successfully installed blobuploader requests docopt 13:06:43 INFO - Cleaning up... 13:06:43 INFO - Return code: 0 13:06:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:43 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')]} 13:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:43 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')]} 13:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:43 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:43 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 13:06:43 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 13:06:43 INFO - Using env: {'DISPLAY': ':0', 13:06:43 INFO - 'HOME': '/home/cltbld', 13:06:43 INFO - 'LANG': 'en_US.UTF-8', 13:06:43 INFO - 'LANGUAGE': 'en_US:en', 13:06:43 INFO - 'LOGNAME': 'cltbld', 13:06:43 INFO - 'MAIL': '/var/mail/cltbld', 13:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:43 INFO - 'MOZ_NO_REMOTE': '1', 13:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:43 INFO - 'NO_EM_RESTART': '1', 13:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:43 INFO - 'PWD': '/builds/slave/test', 13:06:43 INFO - 'SHELL': '/bin/bash', 13:06:43 INFO - 'SHLVL': '1', 13:06:43 INFO - 'TERM': 'linux', 13:06:43 INFO - 'TMOUT': '86400', 13:06:43 INFO - 'USER': 'cltbld', 13:06:43 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:43 INFO - '_': '/tools/buildbot/bin/python'} 13:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:43 INFO - Running setup.py (path:/tmp/pip-eLBjUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:43 INFO - Running setup.py (path:/tmp/pip-VySM0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:43 INFO - Running setup.py (path:/tmp/pip-Wmsszd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:43 INFO - Running setup.py (path:/tmp/pip-Lsm60z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:43 INFO - Running setup.py (path:/tmp/pip-ioGcmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:43 INFO - Running setup.py (path:/tmp/pip-aCboTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:43 INFO - Running setup.py (path:/tmp/pip-mXa9kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:43 INFO - Running setup.py (path:/tmp/pip-vshdp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:43 INFO - Running setup.py (path:/tmp/pip-A3HJSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:44 INFO - Running setup.py (path:/tmp/pip-i98JzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:44 INFO - Running setup.py (path:/tmp/pip-B1DHzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:44 INFO - Running setup.py (path:/tmp/pip-ui7Tkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:44 INFO - Running setup.py (path:/tmp/pip-HUl3Ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:44 INFO - Running setup.py (path:/tmp/pip-0BWG4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:44 INFO - Running setup.py (path:/tmp/pip-CITSpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:44 INFO - Running setup.py (path:/tmp/pip-KbNi3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:44 INFO - Running setup.py (path:/tmp/pip-kdYm8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:06:44 INFO - Running setup.py install for manifestparser 13:06:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:06:44 INFO - Running setup.py install for mozcrash 13:06:44 INFO - Running setup.py install for mozdebug 13:06:44 INFO - Running setup.py install for mozdevice 13:06:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozfile 13:06:45 INFO - Running setup.py install for mozhttpd 13:06:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozinfo 13:06:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozInstall 13:06:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozleak 13:06:45 INFO - Running setup.py install for mozlog 13:06:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for moznetwork 13:06:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozprocess 13:06:45 INFO - Running setup.py install for mozprofile 13:06:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:06:45 INFO - Running setup.py install for mozrunner 13:06:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Running setup.py install for mozscreenshot 13:06:46 INFO - Running setup.py install for moztest 13:06:46 INFO - Running setup.py install for mozversion 13:06:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:06:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:06:46 INFO - Cleaning up... 13:06:46 INFO - Return code: 0 13:06:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:46 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:46 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 13:06:46 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 13:06:46 INFO - Using env: {'DISPLAY': ':0', 13:06:46 INFO - 'HOME': '/home/cltbld', 13:06:46 INFO - 'LANG': 'en_US.UTF-8', 13:06:46 INFO - 'LANGUAGE': 'en_US:en', 13:06:46 INFO - 'LOGNAME': 'cltbld', 13:06:46 INFO - 'MAIL': '/var/mail/cltbld', 13:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:46 INFO - 'MOZ_NO_REMOTE': '1', 13:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:46 INFO - 'NO_EM_RESTART': '1', 13:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:46 INFO - 'PWD': '/builds/slave/test', 13:06:46 INFO - 'SHELL': '/bin/bash', 13:06:46 INFO - 'SHLVL': '1', 13:06:46 INFO - 'TERM': 'linux', 13:06:46 INFO - 'TMOUT': '86400', 13:06:46 INFO - 'USER': 'cltbld', 13:06:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:46 INFO - '_': '/tools/buildbot/bin/python'} 13:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:46 INFO - Running setup.py (path:/tmp/pip-m00Jc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:46 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)) 13:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:46 INFO - Running setup.py (path:/tmp/pip-7WEQOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 13:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:46 INFO - Running setup.py (path:/tmp/pip-C86CfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:46 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)) 13:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:46 INFO - Running setup.py (path:/tmp/pip-5qUE1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 13:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:46 INFO - Running setup.py (path:/tmp/pip-5SgwJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:46 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)) 13:06:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:46 INFO - Running setup.py (path:/tmp/pip-CSpSwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:47 INFO - Running setup.py (path:/tmp/pip-hf_44b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:47 INFO - Running setup.py (path:/tmp/pip-Tj7EDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:47 INFO - Running setup.py (path:/tmp/pip-ecJ205-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:47 INFO - Running setup.py (path:/tmp/pip-MayjqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:47 INFO - Running setup.py (path:/tmp/pip-RPUJuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:47 INFO - Running setup.py (path:/tmp/pip-JzQSZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:47 INFO - Running setup.py (path:/tmp/pip-OIEu82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:47 INFO - Running setup.py (path:/tmp/pip-EyN2AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:47 INFO - Running setup.py (path:/tmp/pip-PlTZhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:47 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)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:47 INFO - Running setup.py (path:/tmp/pip-0Nrcsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:47 INFO - Running setup.py (path:/tmp/pip-EnbcKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:47 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)) 13:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:06:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:06:47 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)) 13:06:47 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:06:47 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:06:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:06:47 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 13:06:47 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 13:06:47 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 13:06:47 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 13:06:47 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 13:06:47 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 13:06:49 INFO - Downloading blessings-1.6.tar.gz 13:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:06:49 INFO - Installing collected packages: blessings 13:06:49 INFO - Running setup.py install for blessings 13:06:49 INFO - Successfully installed blessings 13:06:49 INFO - Cleaning up... 13:06:49 INFO - Return code: 0 13:06:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:06:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:50 INFO - Reading from file tmpfile_stdout 13:06:50 INFO - Current package versions: 13:06:50 INFO - argparse == 1.2.1 13:06:50 INFO - blessings == 1.6 13:06:50 INFO - blobuploader == 1.2.4 13:06:50 INFO - docopt == 0.6.1 13:06:50 INFO - functools32 == 3.2.3-2 13:06:50 INFO - jsonschema == 2.5.1 13:06:50 INFO - manifestparser == 1.1 13:06:50 INFO - mozInstall == 1.12 13:06:50 INFO - mozcrash == 0.17 13:06:50 INFO - mozdebug == 0.1 13:06:50 INFO - mozdevice == 0.48 13:06:50 INFO - mozfile == 1.2 13:06:50 INFO - mozhttpd == 0.7 13:06:50 INFO - mozinfo == 0.9 13:06:50 INFO - mozleak == 0.1 13:06:50 INFO - mozlog == 3.3 13:06:50 INFO - moznetwork == 0.27 13:06:50 INFO - mozprocess == 0.23 13:06:50 INFO - mozprofile == 0.28 13:06:50 INFO - mozrunner == 6.12 13:06:50 INFO - mozscreenshot == 0.1 13:06:50 INFO - mozsystemmonitor == 0.3 13:06:50 INFO - moztest == 0.7 13:06:50 INFO - mozversion == 1.4 13:06:50 INFO - psutil == 3.1.1 13:06:50 INFO - requests == 1.2.3 13:06:50 INFO - wsgiref == 0.1.2 13:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:50 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')]} 13:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:50 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')]} 13:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:50 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:50 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 13:06:50 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 13:06:50 INFO - Using env: {'DISPLAY': ':0', 13:06:50 INFO - 'HOME': '/home/cltbld', 13:06:50 INFO - 'LANG': 'en_US.UTF-8', 13:06:50 INFO - 'LANGUAGE': 'en_US:en', 13:06:50 INFO - 'LOGNAME': 'cltbld', 13:06:50 INFO - 'MAIL': '/var/mail/cltbld', 13:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:50 INFO - 'MOZ_NO_REMOTE': '1', 13:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:50 INFO - 'NO_EM_RESTART': '1', 13:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:50 INFO - 'PWD': '/builds/slave/test', 13:06:50 INFO - 'SHELL': '/bin/bash', 13:06:50 INFO - 'SHLVL': '1', 13:06:50 INFO - 'TERM': 'linux', 13:06:50 INFO - 'TMOUT': '86400', 13:06:50 INFO - 'USER': 'cltbld', 13:06:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:50 INFO - '_': '/tools/buildbot/bin/python'} 13:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 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)) 13:06:50 INFO - Cleaning up... 13:06:50 INFO - Return code: 0 13:06:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:06:50 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')]} 13:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:50 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')]} 13:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:50 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 0x7f7a074baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228b230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', '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 13:06:50 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 13:06:50 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 13:06:50 INFO - Using env: {'DISPLAY': ':0', 13:06:50 INFO - 'HOME': '/home/cltbld', 13:06:50 INFO - 'LANG': 'en_US.UTF-8', 13:06:50 INFO - 'LANGUAGE': 'en_US:en', 13:06:50 INFO - 'LOGNAME': 'cltbld', 13:06:50 INFO - 'MAIL': '/var/mail/cltbld', 13:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:50 INFO - 'MOZ_NO_REMOTE': '1', 13:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:06:50 INFO - 'NO_EM_RESTART': '1', 13:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:50 INFO - 'PWD': '/builds/slave/test', 13:06:50 INFO - 'SHELL': '/bin/bash', 13:06:50 INFO - 'SHLVL': '1', 13:06:50 INFO - 'TERM': 'linux', 13:06:50 INFO - 'TMOUT': '86400', 13:06:50 INFO - 'USER': 'cltbld', 13:06:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:50 INFO - '_': '/tools/buildbot/bin/python'} 13:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:06:50 INFO - Cleaning up... 13:06:50 INFO - Return code: 0 13:06:50 INFO - Running post-action listener: _resource_record_post_action 13:06:50 INFO - Running post-action listener: _start_resource_monitoring 13:06:50 INFO - Starting resource monitoring. 13:06:50 INFO - [mozharness: 2016-08-23 20:06:50.665183Z] Finished create-virtualenv step (success) 13:06:50 INFO - [mozharness: 2016-08-23 20:06:50.665634Z] Running install step. 13:06:50 INFO - Running pre-action listener: _resource_record_pre_action 13:06:50 INFO - Running main action method: install 13:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:50 INFO - Reading from file tmpfile_stdout 13:06:50 INFO - Detecting whether we're running mozinstall >=1.0... 13:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:06:50 INFO - Reading from file tmpfile_stdout 13:06:50 INFO - Output received: 13:06:50 INFO - Usage: mozinstall [options] installer 13:06:50 INFO - Options: 13:06:50 INFO - -h, --help show this help message and exit 13:06:50 INFO - -d DEST, --destination=DEST 13:06:50 INFO - Directory to install application into. [default: 13:06:50 INFO - "/builds/slave/test"] 13:06:50 INFO - --app=APP Application being installed. [default: firefox] 13:06:50 INFO - mkdir: /builds/slave/test/build/application 13:06:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:07:07 INFO - Reading from file tmpfile_stdout 13:07:07 INFO - Output received: 13:07:07 INFO - /builds/slave/test/build/application/firefox/firefox 13:07:07 INFO - Running post-action listener: _resource_record_post_action 13:07:07 INFO - [mozharness: 2016-08-23 20:07:07.056433Z] Finished install step (success) 13:07:07 INFO - [mozharness: 2016-08-23 20:07:07.056579Z] Running run-tests step. 13:07:07 INFO - Running pre-action listener: _resource_record_pre_action 13:07:07 INFO - Running main action method: run_tests 13:07:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:07:07 INFO - Python 2.7.3 13:07:07 INFO - Return code: 0 13:07:07 INFO - grabbing minidump binary from tooltool 13:07:07 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')]} 13:07:07 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 0x2285090>, '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 0x22889b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2288e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:07:07 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 13:07:07 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 13:07:07 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 13:07:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:07:07 INFO - Return code: 0 13:07:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:07:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:07:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:07:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:07:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:07:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 13:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 13:07:07 INFO - Using env: {'DISPLAY': ':0', 13:07:07 INFO - 'HOME': '/home/cltbld', 13:07:07 INFO - 'LANG': 'en_US.UTF-8', 13:07:07 INFO - 'LANGUAGE': 'en_US:en', 13:07:07 INFO - 'LOGNAME': 'cltbld', 13:07:07 INFO - 'MAIL': '/var/mail/cltbld', 13:07:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:07:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:07 INFO - 'MOZ_NO_REMOTE': '1', 13:07:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:07:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:07:07 INFO - 'NO_EM_RESTART': '1', 13:07:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:07 INFO - 'PWD': '/builds/slave/test', 13:07:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:07:07 INFO - 'SHELL': '/bin/bash', 13:07:07 INFO - 'SHLVL': '1', 13:07:07 INFO - 'TERM': 'linux', 13:07:07 INFO - 'TMOUT': '86400', 13:07:07 INFO - 'USER': 'cltbld', 13:07:07 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867', 13:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:07 INFO - '_': '/tools/buildbot/bin/python'} 13:07:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 13:07:07 INFO - mozversion application_buildid: 20160823103647 13:07:07 INFO - mozversion application_changeset: 7214320961eda063ecef1be314c1c4da9b949dbe 13:07:07 INFO - mozversion application_display_name: Nightly 13:07:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:07:07 INFO - mozversion application_name: Firefox 13:07:07 INFO - mozversion application_remotingname: firefox 13:07:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:07:07 INFO - mozversion application_vendor: Mozilla 13:07:07 INFO - mozversion application_version: 51.0a1 13:07:07 INFO - mozversion platform_buildid: 20160823103647 13:07:07 INFO - mozversion platform_changeset: 7214320961eda063ecef1be314c1c4da9b949dbe 13:07:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:07:07 INFO - mozversion platform_version: 51.0a1 13:07:07 INFO - using testdate: 1471982827 13:07:07 INFO - actual date: 1471982827 13:07:07 INFO - starting webserver on 'localhost:37071' 13:07:07 INFO - SUITE-START | Running 2 tests 13:07:07 INFO - TEST-START | dromaeo_css 13:07:07 INFO - operating with platform_type : linux_ 13:07:07 INFO - Initialising browser for dromaeo_css test... 13:07:07 INFO - TEST-INFO | started process 9764 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkgbbZo/profile http://localhost:37071/getInfo.html) 13:07:12 INFO - PROCESS | 9764 | __metrics Screen width/height:1600/1200 13:07:12 INFO - PROCESS | 9764 | colorDepth:24 13:07:12 INFO - PROCESS | 9764 | Browser inner width/height: 1024/697 13:07:12 INFO - PROCESS | 9764 | __metrics 13:07:12 INFO - PROCESS | 9764 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:07:12 INFO - PROCESS | 9764 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:07:13 INFO - PROCESS | 9764 | console.error: PushService: 13:07:13 INFO - PROCESS | 9764 | stateChangeProcessEnqueue: Error transitioning state 13:07:13 INFO - PROCESS | 9764 | UnknownError 13:07:13 INFO - TEST-INFO | 9764: exit 0 13:07:13 INFO - Browser initialized. 13:07:13 INFO - Running cycle 1/1 for dromaeo_css test... 13:07:13 INFO - TEST-INFO | started process 9846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkgbbZo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:26 INFO - PROCESS | 9846 | RSS: Main: 178171904 13:07:26 INFO - PROCESS | 9846 | 13:08:17 INFO - PROCESS | 9846 | 1471982897495 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:10:30 INFO - PROCESS | 9846 | Cycle 1(1): loaded http://localhost:37071/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37071/tests/dromaeo/cssquery-ext.html) 13:10:31 INFO - PROCESS | 9846 | RSS: Main: 277946368 13:10:31 INFO - PROCESS | 9846 | 13:13:34 INFO - PROCESS | 9846 | Cycle 1(1): loaded http://localhost:37071/tests/dromaeo/cssquery-ext.html (next: http://localhost:37071/tests/dromaeo/cssquery-jquery.html) 13:13:34 INFO - PROCESS | 9846 | RSS: Main: 280039424 13:13:34 INFO - PROCESS | 9846 | 13:16:37 INFO - PROCESS | 9846 | Cycle 1(1): loaded http://localhost:37071/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37071/tests/dromaeo/cssquery-mootools.html) 13:16:37 INFO - PROCESS | 9846 | RSS: Main: 220999680 13:16:37 INFO - PROCESS | 9846 | 13:19:40 INFO - PROCESS | 9846 | Cycle 1(1): loaded http://localhost:37071/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37071/tests/dromaeo/cssquery-prototype.html) 13:19:40 INFO - PROCESS | 9846 | RSS: Main: 221982720 13:19:40 INFO - PROCESS | 9846 | 13:22:44 INFO - PROCESS | 9846 | Cycle 1(1): loaded http://localhost:37071/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37071/tests/dromaeo/cssquery-yui.html) 13:22:44 INFO - PROCESS | 9846 | RSS: Main: 221224960 13:22:44 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | Cycle 1(1): loaded http://localhost:37071/tests/dromaeo/cssquery-yui.html (next: http://localhost:37071/tests/dromaeo/cssquery-dojo.html) 13:25:47 INFO - PROCESS | 9846 | RSS: Main: 317083648 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | __start_tp_report 13:25:47 INFO - PROCESS | 9846 | _x_x_mozilla_page_load 13:25:47 INFO - PROCESS | 9846 | _x_x_mozilla_page_load_details 13:25:47 INFO - PROCESS | 9846 | |i|pagename|runs| 13:25:47 INFO - PROCESS | 9846 | |0;dojo.html;2566.332753484094;2606.3614414468457;2612.66035415396;2620.9213723588286;2626.894924203032;4539.205639013172;4603.677742558022;4611.423572053493;4647.117047760923;4746.59653929416;4488.573585509372;4671.112488627158;4719.882002949928;4731.550502702246;4766.833160839363;4372.71577347473;4388.253996820548;4391.824327026915;4412;4457.576530229623;4406.933895991547;4423.491298500665;4438;4479.17135329965;4485.730856148642;4247.978760106211;4279.786010699452;4284.464441944757;4320.481542214954;4335.241332766785;4346.15250026245;4354;4361.563843615645;4402.691811573191;4416.249237629579;4389.056352884145;4389.4513185851765;4402.163588918095;4403.163398954189;4403.493598236179;4564.8402305919135;4630.606398456135;4718.598919091882;4724.83463078794;4784.712917224978;5084.618653600958;5092.490750924915;5106.4382917879;5111.131107711698;5116.079105760961;4866.7566621668775;5100;5130.640855140142;5132.74336283188;5139.17773156297;4690.296455531698;4744.7390393528685;4744.810207591727;4784.760761961888;4822.551875409099;5277.1292736698015;5278.102722537178;5281.419043905167;5289.153735402318;5292.947070529245;4765.618750499952;5047.87380315495;5051.419086804991;5052.343195384577;5054.772535235923;4956.752162391866;5014.197728363444;5015.673981191211;5084.008618319466;5106.285120083191;5039.193729003415;5049.6212784041345;5052.242163675478;5054.671446355975;5057.848264552069;5004.274380214849;5012.122878496249;5021.347224860745;5026.451061789086;5028.969061342416;4110.609492098246;4151.356539736345;4170.708050436501;4210.873673789792;4213.536510983789;3736.439534069911;3748.1379282765115;3749.968758591355;3750.062484378905;3756.286305601927;4430.269005614037;4436.0906014267;4437.003348493126;4440.511543730187;4440.533743956903;4872.902541949212;4985.925211121836;4995.225740010304;4999.825006124768;5065.088284108896;4428.402165707653;4429.889252768707;4434.290513517822;4443.866683999485;4445.977770111129;4530.79611417487;4535.682502224879;4541.114482675846;4547.636189104864;4547.795349209294;4618.237990731492;4738.857834264978;4785.641076919314;4785.665003449725;4794.664373493822;5106.795728170831;5129.230615407719;5147.3308469898675;5153.484651534816;5156.845294641167;4877.731724755173;4887.34020907173;4890.168671325812;4891.217405215149;4895.730734809477;4797.544233297831;4882.218844984785;4906.803727850989;5066.468020857904;5079.568236699922;5056.191009438472;5073.239014147907;5077.2637967493765;5077.568406685473;5088.516590923857;4948.802047918043;4953.356063711694;4954.157793175241;4962.602991760578;4964.900701986012;4734.124187025412;4824.927626085681;4870.610351171968;4940.901181976412;4952.2819191217795;4877.170880950318;4885.413750349981;4934.259861020875;4946.35697359341;4949.059678661043;5045.066662667418;5054.16606259963;5055.266986286894;5057.165567681292;5057.266696329085;4686.0862131885315;4740.810367585258;4765.070811191785;4845.1520983828395;4915.680480768881 13:25:47 INFO - PROCESS | 9846 | |1;ext.html;562.8930503204391;567.2087438023957;576.9740361683724;577.1919312961855;578.0087150536829;41979.79010104949;43918.78040609796;43933.78033109838;43969;44028.11943761127;40622.18755624893;41156.38265426021;41224.38163427551;41267.380989285186;41288;7652.273034061769;7788.337991270749;7795.220477952216;7803.648835802403;7804.141544430108;7711.344535714443;7783.299503044725;7799.766007019798;7801.258880406353;7804.336631386339;7825.178356272595;7865.882011769828;7869.095054068798;7869.8032549186155;7874.330681892017;7503.474756767028;7546.20764819697;7556.848863022708;7578.583177925212;7582.848343033136;7712.6529306181355;7787.8442431151625;7788.286576953061;7789.182135875765;7791.259830316111;38315.425268620995;38749.22501549981;38780.80609596934;38790;38860.222795544214;2711.4848178845955;2717.6331195288776;2816.676082250547;2972.0043785331723;3074.969885088498;29377.265568360963;29487.115386538437;29598.26004349887;29660.70339296601;29921.40157196844;3273.2307907641684;3470.1498132957586;3519.3489204496996;3520.2783429396914;3538.433850583894;255278;256622;258061;258077.9999999981;258426;46775.76612116986;46827.29759053617;46973.29540056902;47017.29474057892;47063.29405058927;32873;33371.16572085717;33526.3294734104;35596.82201588975;35620;13263.469461221635;13406.731865362639;13412.731745365038;13429.462821487226;13430.865691342962;9892.752681183028;9929.106380425796;9934.056264654846;9935.751606209902;9938.900610993942;17719.73420398695;17817.643647126984;17837;17944.282228710967;17978.02241753406;6986.067558448868;7015.89476157858;7103.715851365888;7178.892316615255;7206.927930720625;745.7427187620262;842.5450256861349;848.5629900601195;905.9365844390829;938.5070016780219;28607.284817879303;29016.41967160645;29080.418391632473;29179.41641167207;29310.284494523363;900.8738776571286;975.9951200244095;985.261054209343;1010.8534262531891;1037.5745944162857;14605.415783368759;14985.475508357154;15084.849151508344;15188.316525756367;15294.235288235544;380807;385014;386076;387576;388633;48245;48674.756626216644;48880.51119488759;48899.511004890206;48930.755346223756;1072.367303291062;1113.660333598244;1122.4948773051997;1203.8796120388067;1206.7948448763555;22066.33800985973;29592.260193495335;31108;31552;32128.19679508031;46147.30779038384;46501.53498464972;46647.76676116598;46692.53307466882;46700;329706;334408;334905;335541;335645;25273.87363063173;25333.113341032975;25607.74392256128;25649.230523083592;25667.35831604225;40870.59129408787;41025.589744103374;41150.9712257184;41816.38223208618;41973.37039944464;35198.64801351954;35259.64740352564;35435.46846797249;35454.290914182086;35522.289554208255;34971.47542786809;35306.64693353033;35416.29167416689;35623.64376356203;35667;25711.485770284864;27570;27571.724282756917;35087.29825403529;35450.82274588714;27182;31447.55586105166;31665.366692666477;31801.705491883306;31997.520037199927;24966.126185583416;25238.49523009566;25420.74579254184;25621.743782561934;25705.228843134737 13:25:47 INFO - PROCESS | 9846 | |2;jquery.html;356.82693893461675;360.3154007385966;360.40713027070467;373.88783364990496;386.33164625198407;15991.600209994742;16575.751363729538;17031.978081315116;17168.141592920365;17170.742438863395;14896.404143834261;15603.063816171038;15646.295916683748;15787.131707756069;15797.736181105513;4997.60019198465;5158.716270605115;5192.428832828386;5231.084560201968;5237.47625237477;3822.4266360045935;3995.5405128410316;5114.897702045975;5196.636235463519;5834.329052159013;6035.366286539916;6167.4757645600175;6168.722407491674;6174.783882564111;6182.196314479134;5901.616394934337;6041.093835924603;6095.603785753912;6099.420555047263;6152.354002829706;5288.693601118629;5331.680099194061;5334.519893209591;5341.67949923002;5342.065138600729;20705.68941465879;21075;21081;21134.788652113435;21153.788462115488;2067.65883629201;2072.202202152182;2492.7382624824313;2514.8742562871785;2559.6160575913577;12598.874011259863;13214.021462232502;13685.79471307931;13765.518206862711;14331.856681433157;1699.2862997541063;1728.3259528783785;1783.2688597675021;1836.3205613922935;1839.062078340043;67785.66107169433;69219.65390173017;69629.30370696279;70709.64645176793;71886.28113718847;9409.952950235205;9463.195628371666;9775.462349570842;9913.45475998827;10068.345557538736;13355.53255636048;13381.732365352638;13458.663533411744;13553.59339219825;13576;7011;7226.675729164538;7230.602316872622;7393.8151546211775;7394.223606521346;6698.933010669928;6873.5875847449315;6970.337817907291;7023.824404389931;7071.858562828715;5977.312609049928;6055.757769689163;6150.907736383958;6152.384761523812;6222.751089956351;1630.1441743084738;1753.3775509693876;1786.9910650446925;1830.3502256698946;1840.6410749903641;820.3929092471528;824.4435006370676;828.9792755181048;836.7322456813882;898.9841479128543;16738.83261167397;16872.317329429377;18017.369392071214;18040.819591803913;18302.450926472226;836.0928392693874;844.079952851395;872.5986046418718;873.7859224489913;889.2219308105408;9634.229261659051;10215.7446063849;10262.179025678077;10267.332623379456;10285.382877027403;392543;394363;402815;403106;404199;42585.78707106445;42835.14329713388;63299.36700633027;67123;72742.63628681823;842.1241908415302;847.220557087481;865.2083343740533;908.3596064774318;921.829461549602;15259.847401526287;15398.229203641724;15427.768583471481;15807.446739364066;16212;35463.82268088643;35591.64408355884;36115.458268125476;36609.816950915076;37218.255634886606;45384.319235212155;45847.31229031634;46019.76990115028;46051.76974115242;48117.5188248127;6687.297833727389;6817.7272909083085;6858.8285292866085;7499.362554182955;7815.726449574237;11142.331460112095;11350.772984540428;11460.14048946349;11649.88350116488;11796.70508237301;9675.274354423305;9682.661106861226;9739.513024348613;9821.165200957712;10010.399376037461;9803.215742740422;10320.43237621938;10377.16982641402;10423.374597524173;10500.947495262475;9232.769180770534;9614.423134611945;9975.900240997787;10186.134178594903;10209.9489502552;6852.177738671391;6886.827829304308;6944.131983502062;7025;7041.718331266692;8373.371997100363;9969.750756231153;9975.251856110675;10005.899941000496;10403.479826008821 13:25:47 INFO - PROCESS | 9846 | |3;mootools.html;1332.5269529317095;1377.3388773388774;1409.7110092431053;1589.1418633937674;1610.9597260068497;7198.7120515179395;7742.729004484837;7743.845123097534;7745.651445684944;7746.883796743054;6687.765928192514;7227.2772722727705;7458.664360103795;7475.514091584043;7628.351590114833;2118.0786357934307;2133.17872619042;2136.3377353020537;2138.2088627207954;2148.4414052346424;2113.3448630924463;2139.5934772393275;2164.101897712447;2169.533550286688;2175.9347219583437;1829.5089502055848;1896.2983696032488;1897.9715304270446;2047.6416627090275;2118.110393634677;2090.3206457901165;2098.8530802843657;2116.153538584563;2127.77658345873;2139.443744626393;2065.783092775267;2087.2068613926613;2108.5782843431375;2138.8930553472264;2160.83793715472;7649.655765490566;7699.384049276045;7716.228377162295;7718.073831140243;7720.07359116904;4166.187593419285;4300.591443812864;4302.333138363559;4303.806328715215;4304.612584867377;2819.887204511817;2890.8843646254127;2955.541891006876;2964.43675701616;2964.5997790298247;890.4301247201796;920.4385324951774;925.4447331601053;943.5235206220815;949.0225068179786;10232.283740138193;10269.435181065039;10284.382937023802;10285.434301113435;10287.382757034602;3811.599782022903;3966.7223294369696;4069.2471892699946;4132.752034877917;4134.834606615762;8732;8759.781005474913;8765.649374024968;8772.824543509094;8783.121687831166;4340.934885976713;4361.323994780814;4365.367021781887;4366.978165109154;4368.803403846835;3933.350997085506;3959.980200098981;3965.2862484753023;3981.980090099531;3985.242803867256;3494.0740703713536;3604.6034936157002;3649.1606930406156;3691;3731.3097077040834;1141.577616281981;1161.1987728467332;1174.506707182985;1178.0752109593927;1198.161287099034;617.4227097663698;716.3409663109951;726.1576571177409;736.2931585677702;772.1544908325375;4985.5762260208285;5142.845714628567;5144.6398752087;5181.481851814864;5215.634905556651;349.3066263467016;361.7630452053909;364.5698076269974;874.8119154381711;938.6620816506053;3457.6888080072913;3483.390406678821;3506.5967413747744;3540.8760693375607;3605.332054279814;9263.861042084374;9605.615775369046;9621.90378096224;9628.71113866585;9640.180584650243;9482.146606805283;9579.281553883488;9608.567614457366;9609.807803843883;9611.375260608178;6746.763863264762;6896.827539318328;7021.5787052777;7169.139703235541;7173.92826071743;5196.714180720097;5273.762680679302;5330.387005494263;5429.294191755047;5568.276124103841;8568.100349463217;8714.302855771397;8761.474311541328;8827;8827.911720882708;8318.41771076043;8585.227329540372;8612.569371531274;8795.47227166372;8831.558422078999;6460.418562329413;6601.966990165019;6645.8006259810345;6658.234303055211;6672.299408562031;7047.189573199148;7091.716331346688;7105.573665580082;7110.400159963967;7117.252688467844;6506.3818937200895;6538.169312592482;6562.442192413508;6566.343365663395;6716;6399.9680001601555;6437.130987316839;6439.420452159328;6531.902021469777;6566.9343306566325;5968.701564921824;6069.878602427839;6070.150178974859;6070.362611925684;6072.574919755575;4277.251480990876;4325.783710814384;4440.3561483585345;4450.287953927356;4503.842365517191;4444.666650001198;4454.465464144194;4515.571020753023;4520.412346394947;4604.539546045368 13:25:47 INFO - PROCESS | 9846 | |4;prototype.html;292.30577378725525;293.8369205091175;302.72905749354317;302.86673863500056;305.4593369735568;4735.8816029599275;4785.21044027735;4792.448868380137;4805.975970120153;4826;4718.787654555545;4723.197056500395;4729.858104256869;4735.8816029599275;4769.356136921515;3803.3724435468253;3804.1440675847853;3807.162424266657;3811.599782022882;3824.6366595173413;3490.7730997485082;3760.492333534965;3785.1483416231267;3829.617038296162;3840.615938406165;3671.3208056509498;3692.2246328271094;3696.724630002645;3703.759255648388;3703.8518059401536;3658.1220507078374;3672.9632703672887;3685.834137463794;3697.3159965406485;3705.8332375043187;3028.6971302869756;3115.875364985398;3148.511980642981;3237.5629290046013;3245.204924793417;4622.722636641813;4635.327877457749;4643.721376717408;4648.48866624671;4662.650301227422;4200.852970146061;4203.7809035379705;4204.768737719424;4206.390073439365;4209.326507758775;4716.976415117902;4721.3390125382475;4969.291736003886;4970.676906001098;4973.726445045521;3521.3133438979407;3529.2764983178645;3537.0192692092255;3542.2738338640515;3547.592026916912;5168.5865130789825;5179.792808287664;5182.222666600002;5185.496206100264;5199.168133098724;3478.9478157827652;3548.8580456781438;3553.7867727936405;3557.7509574330065;3566.928661426757;3603.4594810778594;3642.9817850910576;3646.1431563582537;3646.8176591170336;3652.192164878349;4110.301248787713;4115.938260926088;4118.91762164755;4145;4152.626263636287;4880.585150262267;4911.16510193268;4915.606751459874;4916.901661966741;4927.457979622239;1228.6791698923694;1245.2217364147407;1248.7065539598186;1254.2725219372742;1254.5170109507906;782.7573452229827;801.4229754576695;809.4253080312926;813.1868131868132;816.2408959667566;3972.682185425159;3993.4808474898077;3995.760254384746;3998.540167880673;4006.45912801768;4514.45826500822;4540.614047805908;4553.975355544976;4573.428321459817;4593.701409408378;3967.583403742623;3984.3425834736945;3995.4617472272803;4034.3141665916864;4055.5944405559208;2292.151903795573;2379.762023797606;2413.963790543143;2417.2023232333286;2426.272118364519;4279.97860010698;4603.953960460352;4755.167845627071;4772.448954089935;4846.903061938741;5196.168613021899;5238.476152384731;5300.734963251822;5304.363476382859;5314.335708036496;4917;4918;4922.852314430501;4924.729139897268;4926.778294976663;5018.322526458881;5122.385313762224;5127.53852153308;5150.71232191952;5152.690838549699;5070.264811602223;5072.721000345016;5076.82231121909;5080;5083.949160508348;4740.032886352125;4849.466558678472;4856.9314750754775;4865.829695960624;4893.951060489349;3688.4098544232793;3711.9955224178643;3726.366517691945;3729.9254014920093;3750.981245093866;5088.542031217208;5101.285819985131;5107.526881720466;5109.463506331782;5111;4938.111139994836;4949.480304568113;4959.628027897994;4979.302897594267;4979.850604481871;4751.881202969954;4920.21276595743;4937.382827146606;4954.50454954516;4963.379577552806;4872.6832755870755;4884.389451318585;4899.828506002273;4910.361652985089;4916.852494425173;4977.950220497749;5003.524665156804;5006.874828129326;5007.621379844545;5016.59867210615;4645.67480276391;4876.634252431011;4910.582600479;4915.5330243626795;4918.442409946879 13:25:47 INFO - PROCESS | 9846 | |5;yui.html;563.6927874308503;566.3515275010113;586.2759492027343;611.6360765344618;629.8551333193366;11382.089432845363;12196.51213951442;12210.267383956969;12238.632841014762;12243.449044792984;11109.222354435193;11944.343061131634;12144.514219431212;12145.574904878331;12147.149699521036;737.6385571070164;753.2655660730771;756.043604839878;756.2701992577162;756.4591317208195;748.2891253309351;751.2187325181832;751.2487512487512;751.8909758085074;751.9135299440552;797.2824457987799;798.349345283595;798.744401791427;799.1369321133192;799.2526987266924;787.940904432167;788.2787249666549;788.8264581792025;788.8934993775853;788.9368850491946;577.0997244298907;577.768892443022;577.8642019125502;577.890200861835;579.099500277069;10370.792584148276;10371.0666040056;10371.740706482324;10390.116840068604;10424.635137770141;10405.219608529314;10499.37003779768;10531.894681053169;10671.359718416843;11049.668509944715;9461.952690236574;9507.619695212252;9610.855837162448;9617.326787124905;9637.325387222898;3154.3375891062906;3199.504076868066;3216.1637974126897;3237.320162765822;3257.1857035741064;61322.386776132116;62272.68863655698;62506.68746656283;63327;64787;7876.684932602631;7956.609384667603;7958.442908996431;8127.75616731499;8159.714409995621;15077.095374277578;15129.924350378398;15173.772393414107;15180.240987950558;15210.771838422432;6496.220453545605;6515.576487528296;6521.804345869631;6596.5052621052655;6764.425023872927;3481.5299934509026;3495.7378196635354;3565.108722819295;3634.7819130852236;3752.962470375316;2774.140016594862;2903.840288784137;2904.9273768155545;2908.0839535546656;2909.2726818295428;463.60130287952336;475.9262314341283;477.5511019641595;483.7073570489873;487.71224977263586;446.35501700043125;454.0918163672655;466.5148245824305;497.59943245686844;498.67087722103236;7490.737824176128;7526.397888168934;7539.283768042027;7635.618219089023;8034.67861285542;431.2324063167567;433.3629564540121;483.16368037779915;511.5856156513236;518.5410911343413;3553.129483276614;3577.910552236163;3629.5825980012637;3775.4714339992424;3776.962230377716;50165;89422.10577894138;94655;102283.4885825581;102317.48841255895;90109.54945225363;90765.54617226872;91100;91699;95487.52256238674;79148.60425697835;81400;81435.59282203669;81496;82038;6911.7926462206215;6992.860142797217;7116.110486189226;7227.205007449282;7268.672909719181;45769.77115114403;45942.540574593826;46817.76591117022;48124;48225;36019;50661.24008139807;51889;53919.46080539144;58979.410205897395;10195.235357348376;10477.31897426665;10527.78944421092;10628.309159904582;10661.253712240068;9816.754581135243;9852.458114803754;10229.335093218917;10722.89277107219;11438.055530559659;9669.758256043655;9676.27427942893;9900.504974751379;10787.500434219546;11098.112151027737;10788.514516846917;11128.221024528206;11222.382768947786;11231.494582743633;11721.296722196696;11109.611163609236;11132;11261.268017578832;11306.208565400342;11683.824742629038;9183.816323673624;9258;9666.468344241135;9733;9964;9375.156235938799;9483.525823708926;9527.90472095298;9544.570494327632;9585 13:25:47 INFO - PROCESS | 9846 | __end_tp_report 13:25:47 INFO - PROCESS | 9846 | __start_cc_report 13:25:47 INFO - PROCESS | 9846 | _x_x_mozilla_cycle_collect,979 13:25:47 INFO - PROCESS | 9846 | __end_cc_report 13:25:47 INFO - PROCESS | 9846 | __startTimestamp1471983947520__endTimestamp 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | ------- Summary: start ------- 13:25:47 INFO - PROCESS | 9846 | Number of tests: 6 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | [#0] dojo.html Cycles:165 Average:4682.59 Median:4785.67 stddev:499.55 (10.4%) stddev-sans-first:472.69 13:25:47 INFO - PROCESS | 9846 | Values: 2566.3 2606.4 2612.7 2620.9 2626.9 4539.2 4603.7 4611.4 4647.1 4746.6 4488.6 4671.1 4719.9 4731.6 4766.8 4372.7 4388.3 4391.8 4412.0 4457.6 4406.9 4423.5 4438.0 4479.2 4485.7 4248.0 4279.8 4284.5 4320.5 4335.2 4346.2 4354.0 4361.6 4402.7 4416.2 4389.1 4389.5 4402.2 4403.2 4403.5 4564.8 4630.6 4718.6 4724.8 4784.7 5084.6 5092.5 5106.4 5111.1 5116.1 4866.8 5100.0 5130.6 5132.7 5139.2 4690.3 4744.7 4744.8 4784.8 4822.6 5277.1 5278.1 5281.4 5289.2 5292.9 4765.6 5047.9 5051.4 5052.3 5054.8 4956.8 5014.2 5015.7 5084.0 5106.3 5039.2 5049.6 5052.2 5054.7 5057.8 5004.3 5012.1 5021.3 5026.5 5029.0 4110.6 4151.4 4170.7 4210.9 4213.5 3736.4 3748.1 3750.0 3750.1 3756.3 4430.3 4436.1 4437.0 4440.5 4440.5 4872.9 4985.9 4995.2 4999.8 5065.1 4428.4 4429.9 4434.3 4443.9 4446.0 4530.8 4535.7 4541.1 4547.6 4547.8 4618.2 4738.9 4785.6 4785.7 4794.7 5106.8 5129.2 5147.3 5153.5 5156.8 4877.7 4887.3 4890.2 4891.2 4895.7 4797.5 4882.2 4906.8 5066.5 5079.6 5056.2 5073.2 5077.3 5077.6 5088.5 4948.8 4953.4 4954.2 4962.6 4964.9 4734.1 4824.9 4870.6 4940.9 4952.3 4877.2 4885.4 4934.3 4946.4 4949.1 5045.1 5054.2 5055.3 5057.2 5057.3 4686.1 4740.8 4765.1 4845.2 4915.7 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | [#1] ext.html Cycles:180 Average:47242.02 Median:35645.32 stddev:86899.10 (243.8%) stddev-sans-first:87072.19 13:25:47 INFO - PROCESS | 9846 | Values: 562.9 567.2 577.0 577.2 578.0 41979.8 43918.8 43933.8 43969.0 44028.1 40622.2 41156.4 41224.4 41267.4 41288.0 7652.3 7788.3 7795.2 7803.6 7804.1 7711.3 7783.3 7799.8 7801.3 7804.3 7825.2 7865.9 7869.1 7869.8 7874.3 7503.5 7546.2 7556.8 7578.6 7582.8 7712.7 7787.8 7788.3 7789.2 7791.3 38315.4 38749.2 38780.8 38790.0 38860.2 2711.5 2717.6 2816.7 2972.0 3075.0 29377.3 29487.1 29598.3 29660.7 29921.4 3273.2 3470.1 3519.3 3520.3 3538.4 255278.0 256622.0 258061.0 258078.0 258426.0 46775.8 46827.3 46973.3 47017.3 47063.3 32873.0 33371.2 33526.3 35596.8 35620.0 13263.5 13406.7 13412.7 13429.5 13430.9 9892.8 9929.1 9934.1 9935.8 9938.9 17719.7 17817.6 17837.0 17944.3 17978.0 6986.1 7015.9 7103.7 7178.9 7206.9 745.7 842.5 848.6 905.9 938.5 28607.3 29016.4 29080.4 29179.4 29310.3 900.9 976.0 985.3 1010.9 1037.6 14605.4 14985.5 15084.8 15188.3 15294.2 380807.0 385014.0 386076.0 387576.0 388633.0 48245.0 48674.8 48880.5 48899.5 48930.8 1072.4 1113.7 1122.5 1203.9 1206.8 22066.3 29592.3 31108.0 31552.0 32128.2 46147.3 46501.5 46647.8 46692.5 46700.0 329706.0 334408.0 334905.0 335541.0 335645.0 25273.9 25333.1 25607.7 25649.2 25667.4 40870.6 41025.6 41151.0 41816.4 41973.4 35198.6 35259.6 35435.5 35454.3 35522.3 34971.5 35306.6 35416.3 35623.6 35667.0 25711.5 27570.0 27571.7 35087.3 35450.8 27182.0 31447.6 31665.4 31801.7 31997.5 24966.1 25238.5 25420.7 25621.7 25705.2 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | [#2] jquery.html Cycles:180 Average:24019.89 Median:46035.77 stddev:65505.30 (142.3%) stddev-sans-first:65664.96 13:25:47 INFO - PROCESS | 9846 | Values: 356.8 360.3 360.4 373.9 386.3 15991.6 16575.8 17032.0 17168.1 17170.7 14896.4 15603.1 15646.3 15787.1 15797.7 4997.6 5158.7 5192.4 5231.1 5237.5 3822.4 3995.5 5114.9 5196.6 5834.3 6035.4 6167.5 6168.7 6174.8 6182.2 5901.6 6041.1 6095.6 6099.4 6152.4 5288.7 5331.7 5334.5 5341.7 5342.1 20705.7 21075.0 21081.0 21134.8 21153.8 2067.7 2072.2 2492.7 2514.9 2559.6 12598.9 13214.0 13685.8 13765.5 14331.9 1699.3 1728.3 1783.3 1836.3 1839.1 67785.7 69219.7 69629.3 70709.6 71886.3 9410.0 9463.2 9775.5 9913.5 10068.3 13355.5 13381.7 13458.7 13553.6 13576.0 7011.0 7226.7 7230.6 7393.8 7394.2 6698.9 6873.6 6970.3 7023.8 7071.9 5977.3 6055.8 6150.9 6152.4 6222.8 1630.1 1753.4 1787.0 1830.4 1840.6 820.4 824.4 829.0 836.7 899.0 16738.8 16872.3 18017.4 18040.8 18302.5 836.1 844.1 872.6 873.8 889.2 9634.2 10215.7 10262.2 10267.3 10285.4 392543.0 394363.0 402815.0 403106.0 404199.0 42585.8 42835.1 63299.4 67123.0 72742.6 842.1 847.2 865.2 908.4 921.8 15259.8 15398.2 15427.8 15807.4 16212.0 35463.8 35591.6 36115.5 36609.8 37218.3 45384.3 45847.3 46019.8 46051.8 48117.5 6687.3 6817.7 6858.8 7499.4 7815.7 11142.3 11350.8 11460.1 11649.9 11796.7 9675.3 9682.7 9739.5 9821.2 10010.4 9803.2 10320.4 10377.2 10423.4 10500.9 9232.8 9614.4 9975.9 10186.1 10209.9 6852.2 6886.8 6944.1 7025.0 7041.7 8373.4 9969.8 9975.3 10005.9 10403.5 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | [#3] mootools.html Cycles:180 Average:4983.15 Median:5163.06 stddev:2814.05 (54.5%) stddev-sans-first:2808.57 13:25:47 INFO - PROCESS | 9846 | Values: 1332.5 1377.3 1409.7 1589.1 1611.0 7198.7 7742.7 7743.8 7745.7 7746.9 6687.8 7227.3 7458.7 7475.5 7628.4 2118.1 2133.2 2136.3 2138.2 2148.4 2113.3 2139.6 2164.1 2169.5 2175.9 1829.5 1896.3 1898.0 2047.6 2118.1 2090.3 2098.9 2116.2 2127.8 2139.4 2065.8 2087.2 2108.6 2138.9 2160.8 7649.7 7699.4 7716.2 7718.1 7720.1 4166.2 4300.6 4302.3 4303.8 4304.6 2819.9 2890.9 2955.5 2964.4 2964.6 890.4 920.4 925.4 943.5 949.0 10232.3 10269.4 10284.4 10285.4 10287.4 3811.6 3966.7 4069.2 4132.8 4134.8 8732.0 8759.8 8765.6 8772.8 8783.1 4340.9 4361.3 4365.4 4367.0 4368.8 3933.4 3960.0 3965.3 3982.0 3985.2 3494.1 3604.6 3649.2 3691.0 3731.3 1141.6 1161.2 1174.5 1178.1 1198.2 617.4 716.3 726.2 736.3 772.2 4985.6 5142.8 5144.6 5181.5 5215.6 349.3 361.8 364.6 874.8 938.7 3457.7 3483.4 3506.6 3540.9 3605.3 9263.9 9605.6 9621.9 9628.7 9640.2 9482.1 9579.3 9608.6 9609.8 9611.4 6746.8 6896.8 7021.6 7169.1 7173.9 5196.7 5273.8 5330.4 5429.3 5568.3 8568.1 8714.3 8761.5 8827.0 8827.9 8318.4 8585.2 8612.6 8795.5 8831.6 6460.4 6602.0 6645.8 6658.2 6672.3 7047.2 7091.7 7105.6 7110.4 7117.3 6506.4 6538.2 6562.4 6566.3 6716.0 6400.0 6437.1 6439.4 6531.9 6566.9 5968.7 6069.9 6070.2 6070.4 6072.6 4277.3 4325.8 4440.4 4450.3 4503.8 4444.7 4454.5 4515.6 4520.4 4604.5 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | [#4] prototype.html Cycles:180 Average:4079.93 Median:4647.08 stddev:1205.76 (25.9%) stddev-sans-first:1175.15 13:25:47 INFO - PROCESS | 9846 | Values: 292.3 293.8 302.7 302.9 305.5 4735.9 4785.2 4792.4 4806.0 4826.0 4718.8 4723.2 4729.9 4735.9 4769.4 3803.4 3804.1 3807.2 3811.6 3824.6 3490.8 3760.5 3785.1 3829.6 3840.6 3671.3 3692.2 3696.7 3703.8 3703.9 3658.1 3673.0 3685.8 3697.3 3705.8 3028.7 3115.9 3148.5 3237.6 3245.2 4622.7 4635.3 4643.7 4648.5 4662.7 4200.9 4203.8 4204.8 4206.4 4209.3 4717.0 4721.3 4969.3 4970.7 4973.7 3521.3 3529.3 3537.0 3542.3 3547.6 5168.6 5179.8 5182.2 5185.5 5199.2 3478.9 3548.9 3553.8 3557.8 3566.9 3603.5 3643.0 3646.1 3646.8 3652.2 4110.3 4115.9 4118.9 4145.0 4152.6 4880.6 4911.2 4915.6 4916.9 4927.5 1228.7 1245.2 1248.7 1254.3 1254.5 782.8 801.4 809.4 813.2 816.2 3972.7 3993.5 3995.8 3998.5 4006.5 4514.5 4540.6 4554.0 4573.4 4593.7 3967.6 3984.3 3995.5 4034.3 4055.6 2292.2 2379.8 2414.0 2417.2 2426.3 4280.0 4604.0 4755.2 4772.4 4846.9 5196.2 5238.5 5300.7 5304.4 5314.3 4917.0 4918.0 4922.9 4924.7 4926.8 5018.3 5122.4 5127.5 5150.7 5152.7 5070.3 5072.7 5076.8 5080.0 5083.9 4740.0 4849.5 4856.9 4865.8 4894.0 3688.4 3712.0 3726.4 3729.9 3751.0 5088.5 5101.3 5107.5 5109.5 5111.0 4938.1 4949.5 4959.6 4979.3 4979.9 4751.9 4920.2 4937.4 4954.5 4963.4 4872.7 4884.4 4899.8 4910.4 4916.9 4978.0 5003.5 5006.9 5007.6 5016.6 4645.7 4876.6 4910.6 4915.5 4918.4 13:25:47 INFO - PROCESS | 9846 | 13:25:47 INFO - PROCESS | 9846 | [#5] yui.html Cycles:180 Average:16988.78 Median:52904.23 stddev:25515.21 (48.2%) stddev-sans-first:25556.98 13:25:47 INFO - PROCESS | 9846 | Values: 563.7 566.4 586.3 611.6 629.9 11382.1 12196.5 12210.3 12238.6 12243.4 11109.2 11944.3 12144.5 12145.6 12147.1 737.6 753.3 756.0 756.3 756.5 748.3 751.2 751.2 751.9 751.9 797.3 798.3 798.7 799.1 799.3 787.9 788.3 788.8 788.9 788.9 577.1 577.8 577.9 577.9 579.1 10370.8 10371.1 10371.7 10390.1 10424.6 10405.2 10499.4 10531.9 10671.4 11049.7 9462.0 9507.6 9610.9 9617.3 9637.3 3154.3 3199.5 3216.2 3237.3 3257.2 61322.4 62272.7 62506.7 63327.0 64787.0 7876.7 7956.6 7958.4 8127.8 8159.7 15077.1 15129.9 15173.8 15180.2 15210.8 6496.2 6515.6 6521.8 6596.5 6764.4 3481.5 3495.7 3565.1 3634.8 3753.0 2774.1 2903.8 2904.9 2908.1 2909.3 463.6 475.9 477.6 483.7 487.7 446.4 454.1 466.5 497.6 498.7 7490.7 7526.4 7539.3 7635.6 8034.7 431.2 433.4 483.2 511.6 518.5 3553.1 3577.9 3629.6 3775.5 3777.0 50165.0 89422.1 94655.0 102283.5 102317.5 90109.5 90765.5 91100.0 91699.0 95487.5 79148.6 81400.0 81435.6 81496.0 82038.0 6911.8 6992.9 7116.1 7227.2 7268.7 45769.8 45942.5 46817.8 48124.0 48225.0 36019.0 50661.2 51889.0 53919.5 58979.4 10195.2 10477.3 10527.8 10628.3 10661.3 9816.8 9852.5 10229.3 10722.9 11438.1 9669.8 9676.3 9900.5 10787.5 11098.1 10788.5 11128.2 11222.4 11231.5 11721.3 11109.6 11132.0 11261.3 11306.2 11683.8 9183.8 9258.0 9666.5 9733.0 9964.0 9375.2 9483.5 9527.9 9544.6 9585.0 13:25:47 INFO - PROCESS | 9846 | -------- Summary: end -------- 13:25:47 INFO - PROCESS | 9846 | 13:25:48 INFO - TEST-INFO | 9846: exit 0 13:25:48 INFO - TEST-OK | dromaeo_css | took 1121234ms 13:25:48 INFO - TEST-START | kraken 13:25:48 INFO - operating with platform_type : linux_ 13:25:48 INFO - Initialising browser for kraken test... 13:25:48 INFO - TEST-INFO | started process 10153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBFgcuO/profile http://localhost:37071/getInfo.html) 13:25:53 INFO - PROCESS | 10153 | __metrics Screen width/height:1600/1200 13:25:53 INFO - PROCESS | 10153 | colorDepth:24 13:25:53 INFO - PROCESS | 10153 | Browser inner width/height: 1024/697 13:25:53 INFO - PROCESS | 10153 | __metrics 13:25:53 INFO - PROCESS | 10153 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:25:53 INFO - PROCESS | 10153 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:25:54 INFO - PROCESS | 10153 | console.error: PushService: 13:25:54 INFO - PROCESS | 10153 | stateChangeProcessEnqueue: Error transitioning state 13:25:54 INFO - PROCESS | 10153 | UnknownError 13:25:54 INFO - TEST-INFO | 10153: exit 0 13:25:54 INFO - Browser initialized. 13:25:54 INFO - Running cycle 1/1 for kraken test... 13:25:54 INFO - TEST-INFO | started process 10232 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBFgcuO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:26:07 INFO - PROCESS | 10232 | RSS: Main: 178315264 13:26:07 INFO - PROCESS | 10232 | 13:26:10 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37071/tests/kraken/driver.html?testName=audio-beat-detection) 13:26:11 INFO - PROCESS | 10232 | RSS: Main: 238530560 13:26:11 INFO - PROCESS | 10232 | 13:26:13 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37071/tests/kraken/driver.html?testName=audio-dft) 13:26:14 INFO - PROCESS | 10232 | RSS: Main: 229183488 13:26:14 INFO - PROCESS | 10232 | 13:26:17 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37071/tests/kraken/driver.html?testName=audio-fft) 13:26:18 INFO - PROCESS | 10232 | RSS: Main: 303812608 13:26:18 INFO - PROCESS | 10232 | 13:26:20 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37071/tests/kraken/driver.html?testName=audio-oscillator) 13:26:21 INFO - PROCESS | 10232 | RSS: Main: 212373504 13:26:21 INFO - PROCESS | 10232 | 13:26:23 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37071/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:26:23 INFO - PROCESS | 10232 | RSS: Main: 216072192 13:26:23 INFO - PROCESS | 10232 | 13:26:27 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37071/tests/kraken/driver.html?testName=imaging-darkroom) 13:26:27 INFO - PROCESS | 10232 | RSS: Main: 252981248 13:26:27 INFO - PROCESS | 10232 | 13:26:31 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37071/tests/kraken/driver.html?testName=imaging-desaturate) 13:26:31 INFO - PROCESS | 10232 | RSS: Main: 246521856 13:26:31 INFO - PROCESS | 10232 | 13:26:34 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37071/tests/kraken/driver.html?testName=json-parse-financial) 13:26:35 INFO - PROCESS | 10232 | RSS: Main: 249966592 13:26:35 INFO - PROCESS | 10232 | 13:26:37 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37071/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:26:37 INFO - PROCESS | 10232 | RSS: Main: 213483520 13:26:37 INFO - PROCESS | 10232 | 13:26:39 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:26:40 INFO - PROCESS | 10232 | RSS: Main: 220999680 13:26:40 INFO - PROCESS | 10232 | 13:26:42 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:26:43 INFO - PROCESS | 10232 | RSS: Main: 225447936 13:26:43 INFO - PROCESS | 10232 | 13:26:46 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:26:46 INFO - PROCESS | 10232 | RSS: Main: 220983296 13:26:46 INFO - PROCESS | 10232 | 13:26:49 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:26:50 INFO - PROCESS | 10232 | RSS: Main: 223903744 13:26:50 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | Cycle 1(1): loaded http://localhost:37071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37071/tests/kraken/driver.html?testName=ai-astar) 13:26:52 INFO - PROCESS | 10232 | RSS: Main: 275243008 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | __start_tp_report 13:26:52 INFO - PROCESS | 10232 | _x_x_mozilla_page_load 13:26:52 INFO - PROCESS | 10232 | _x_x_mozilla_page_load_details 13:26:52 INFO - PROCESS | 10232 | |i|pagename|runs| 13:26:52 INFO - PROCESS | 10232 | |0;ai-astar;104;114;111;116;114;114;121;114;114;114 13:26:52 INFO - PROCESS | 10232 | |1;audio-beat-detection;154;126;146;193;125;158;148;125;180;121 13:26:52 INFO - PROCESS | 10232 | |2;audio-dft;163;165;163;163;163;161;162;162;163;160 13:26:52 INFO - PROCESS | 10232 | |3;audio-fft;75;76;115;121;76;77;75;120;109;77 13:26:52 INFO - PROCESS | 10232 | |4;audio-oscillator;87;86;158;87;87;87;147;87;86;142 13:26:52 INFO - PROCESS | 10232 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;116;117;116 13:26:52 INFO - PROCESS | 10232 | |6;imaging-darkroom;146;148;148;148;147;147;147;147;148;147 13:26:52 INFO - PROCESS | 10232 | |7;imaging-desaturate;96;95;95;95;96;96;96;96;95;95 13:26:52 INFO - PROCESS | 10232 | |8;json-parse-financial;65;65;66;65;65;65;66;65;65;65 13:26:52 INFO - PROCESS | 10232 | |9;json-stringify-tinderbox;66;67;66;66;66;67;66;67;69;67 13:26:52 INFO - PROCESS | 10232 | |10;stanford-crypto-aes;82;82;83;82;91;82;92;85;82;83 13:26:52 INFO - PROCESS | 10232 | |11;stanford-crypto-ccm;142;135;132;131;131;130;132;145;151;144 13:26:52 INFO - PROCESS | 10232 | |12;stanford-crypto-pbkdf2;173;174;173;172;171;175;170;174;176;170 13:26:52 INFO - PROCESS | 10232 | |13;stanford-crypto-sha256-iterative;69;69;70;71;69;70;69;69;68;69 13:26:52 INFO - PROCESS | 10232 | __end_tp_report 13:26:52 INFO - PROCESS | 10232 | __start_cc_report 13:26:52 INFO - PROCESS | 10232 | _x_x_mozilla_cycle_collect,2014 13:26:52 INFO - PROCESS | 10232 | __end_cc_report 13:26:52 INFO - PROCESS | 10232 | __startTimestamp1471984012332__endTimestamp 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | ------- Summary: start ------- 13:26:52 INFO - PROCESS | 10232 | Number of tests: 14 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:4.22 (3.7%) stddev-sans-first:2.69 13:26:52 INFO - PROCESS | 10232 | Values: 104.0 114.0 111.0 116.0 114.0 114.0 121.0 114.0 114.0 114.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:151.00 stddev:24.63 (16.3%) stddev-sans-first:26.01 13:26:52 INFO - PROCESS | 10232 | Values: 154.0 126.0 146.0 193.0 125.0 158.0 148.0 125.0 180.0 121.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 13:26:52 INFO - PROCESS | 10232 | Values: 163.0 165.0 163.0 163.0 163.0 161.0 162.0 162.0 163.0 160.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#3] audio-fft Cycles:10 Average:92.10 Median:75.50 stddev:21.04 (27.9%) stddev-sans-first:21.38 13:26:52 INFO - PROCESS | 10232 | Values: 75.0 76.0 115.0 121.0 76.0 77.0 75.0 120.0 109.0 77.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#4] audio-oscillator Cycles:10 Average:105.40 Median:87.00 stddev:30.34 (34.9%) stddev-sans-first:31.44 13:26:52 INFO - PROCESS | 10232 | Values: 87.0 86.0 158.0 87.0 87.0 87.0 147.0 87.0 86.0 142.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 13:26:52 INFO - PROCESS | 10232 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.67 (0.5%) stddev-sans-first:0.53 13:26:52 INFO - PROCESS | 10232 | Values: 146.0 148.0 148.0 148.0 147.0 147.0 147.0 147.0 148.0 147.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 13:26:52 INFO - PROCESS | 10232 | Values: 96.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 13:26:52 INFO - PROCESS | 10232 | Values: 65.0 65.0 66.0 65.0 65.0 65.0 66.0 65.0 65.0 65.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:0.97 13:26:52 INFO - PROCESS | 10232 | Values: 66.0 67.0 66.0 66.0 66.0 67.0 66.0 67.0 69.0 67.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.00 stddev:3.86 (4.7%) stddev-sans-first:4.00 13:26:52 INFO - PROCESS | 10232 | Values: 82.0 82.0 83.0 82.0 91.0 82.0 92.0 85.0 82.0 83.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#11] stanford-crypto-ccm Cycles:10 Average:137.30 Median:138.50 stddev:7.51 (5.4%) stddev-sans-first:7.77 13:26:52 INFO - PROCESS | 10232 | Values: 142.0 135.0 132.0 131.0 131.0 130.0 132.0 145.0 151.0 144.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:173.50 stddev:2.04 (1.2%) stddev-sans-first:2.17 13:26:52 INFO - PROCESS | 10232 | Values: 173.0 174.0 173.0 172.0 171.0 175.0 170.0 174.0 176.0 170.0 13:26:52 INFO - PROCESS | 10232 | 13:26:52 INFO - PROCESS | 10232 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 13:26:52 INFO - PROCESS | 10232 | Values: 69.0 69.0 70.0 71.0 69.0 70.0 69.0 69.0 68.0 69.0 13:26:52 INFO - PROCESS | 10232 | -------- Summary: end -------- 13:26:52 INFO - PROCESS | 10232 | 13:26:53 INFO - TEST-INFO | 10232: exit 0 13:26:53 INFO - TEST-OK | kraken | took 65076ms 13:26:53 INFO - SUITE-END | took 1186s 13:26:53 INFO - Completed test suite (00:19:46) 13:26:53 INFO - javascript benchmark 13:26:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2566.332753484094, 2606.3614414468457, 2612.66035415396, 2620.9213723588286, 2626.894924203032, 4539.205639013172, 4603.677742558022, 4611.423572053493, 4647.117047760923, 4746.59653929416, 4488.573585509372, 4671.112488627158, 4719.882002949928, 4731.550502702246, 4766.833160839363, 4372.71577347473, 4388.253996820548, 4391.824327026915, 4412.0, 4457.576530229623, 4406.933895991547, 4423.491298500665, 4438.0, 4479.17135329965, 4485.730856148642, 4247.978760106211, 4279.786010699452, 4284.464441944757, 4320.481542214954, 4335.241332766785, 4346.15250026245, 4354.0, 4361.563843615645, 4402.691811573191, 4416.249237629579, 4389.056352884145, 4389.4513185851765, 4402.163588918095, 4403.163398954189, 4403.493598236179, 4564.8402305919135, 4630.606398456135, 4718.598919091882, 4724.83463078794, 4784.712917224978, 5084.618653600958, 5092.490750924915, 5106.4382917879, 5111.131107711698, 5116.079105760961, 4866.7566621668775, 5100.0, 5130.640855140142, 5132.74336283188, 5139.17773156297, 4690.296455531698, 4744.7390393528685, 4744.810207591727, 4784.760761961888, 4822.551875409099, 5277.1292736698015, 5278.102722537178, 5281.419043905167, 5289.153735402318, 5292.947070529245, 4765.618750499952, 5047.87380315495, 5051.419086804991, 5052.343195384577, 5054.772535235923, 4956.752162391866, 5014.197728363444, 5015.673981191211, 5084.008618319466, 5106.285120083191, 5039.193729003415, 5049.6212784041345, 5052.242163675478, 5054.671446355975, 5057.848264552069, 5004.274380214849, 5012.122878496249, 5021.347224860745, 5026.451061789086, 5028.969061342416, 4110.609492098246, 4151.356539736345, 4170.708050436501, 4210.873673789792, 4213.536510983789, 3736.439534069911, 3748.1379282765115, 3749.968758591355, 3750.062484378905, 3756.286305601927, 4430.269005614037, 4436.0906014267, 4437.003348493126, 4440.511543730187, 4440.533743956903, 4872.902541949212, 4985.925211121836, 4995.225740010304, 4999.825006124768, 5065.088284108896, 4428.402165707653, 4429.889252768707, 4434.290513517822, 4443.866683999485, 4445.977770111129, 4530.79611417487, 4535.682502224879, 4541.114482675846, 4547.636189104864, 4547.795349209294, 4618.237990731492, 4738.857834264978, 4785.641076919314, 4785.665003449725, 4794.664373493822, 5106.795728170831, 5129.230615407719, 5147.3308469898675, 5153.484651534816, 5156.845294641167, 4877.731724755173, 4887.34020907173, 4890.168671325812, 4891.217405215149, 4895.730734809477, 4797.544233297831, 4882.218844984785, 4906.803727850989, 5066.468020857904, 5079.568236699922, 5056.191009438472, 5073.239014147907, 5077.2637967493765, 5077.568406685473, 5088.516590923857, 4948.802047918043, 4953.356063711694, 4954.157793175241, 4962.602991760578, 4964.900701986012, 4734.124187025412, 4824.927626085681, 4870.610351171968, 4940.901181976412, 4952.2819191217795, 4877.170880950318, 4885.413750349981, 4934.259861020875, 4946.35697359341, 4949.059678661043, 5045.066662667418, 5054.16606259963, 5055.266986286894, 5057.165567681292, 5057.266696329085, 4686.0862131885315, 4740.810367585258, 4765.070811191785, 4845.1520983828395, 4915.680480768881], "value": 4650.007109022203, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8930503204391, 567.2087438023957, 576.9740361683724, 577.1919312961855, 578.0087150536829, 41979.79010104949, 43918.78040609796, 43933.78033109838, 43969.0, 44028.11943761127, 40622.18755624893, 41156.38265426021, 41224.38163427551, 41267.380989285186, 41288.0, 7652.273034061769, 7788.337991270749, 7795.220477952216, 7803.648835802403, 7804.141544430108, 7711.344535714443, 7783.299503044725, 7799.766007019798, 7801.258880406353, 7804.336631386339, 7825.178356272595, 7865.882011769828, 7869.095054068798, 7869.8032549186155, 7874.330681892017, 7503.474756767028, 7546.20764819697, 7556.848863022708, 7578.583177925212, 7582.848343033136, 7712.6529306181355, 7787.8442431151625, 7788.286576953061, 7789.182135875765, 7791.259830316111, 38315.425268620995, 38749.22501549981, 38780.80609596934, 38790.0, 38860.222795544214, 2711.4848178845955, 2717.6331195288776, 2816.676082250547, 2972.0043785331723, 3074.969885088498, 29377.265568360963, 29487.115386538437, 29598.26004349887, 29660.70339296601, 29921.40157196844, 3273.2307907641684, 3470.1498132957586, 3519.3489204496996, 3520.2783429396914, 3538.433850583894, 255278.0, 256622.0, 258061.0, 258077.9999999981, 258426.0, 46775.76612116986, 46827.29759053617, 46973.29540056902, 47017.29474057892, 47063.29405058927, 32873.0, 33371.16572085717, 33526.3294734104, 35596.82201588975, 35620.0, 13263.469461221635, 13406.731865362639, 13412.731745365038, 13429.462821487226, 13430.865691342962, 9892.752681183028, 9929.106380425796, 9934.056264654846, 9935.751606209902, 9938.900610993942, 17719.73420398695, 17817.643647126984, 17837.0, 17944.282228710967, 17978.02241753406, 6986.067558448868, 7015.89476157858, 7103.715851365888, 7178.892316615255, 7206.927930720625, 745.7427187620262, 842.5450256861349, 848.5629900601195, 905.9365844390829, 938.5070016780219, 28607.284817879303, 29016.41967160645, 29080.418391632473, 29179.41641167207, 29310.284494523363, 900.8738776571286, 975.9951200244095, 985.261054209343, 1010.8534262531891, 1037.5745944162857, 14605.415783368759, 14985.475508357154, 15084.849151508344, 15188.316525756367, 15294.235288235544, 380807.0, 385014.0, 386076.0, 387576.0, 388633.0, 48245.0, 48674.756626216644, 48880.51119488759, 48899.511004890206, 48930.755346223756, 1072.367303291062, 1113.660333598244, 1122.4948773051997, 1203.8796120388067, 1206.7948448763555, 22066.33800985973, 29592.260193495335, 31108.0, 31552.0, 32128.19679508031, 46147.30779038384, 46501.53498464972, 46647.76676116598, 46692.53307466882, 46700.0, 329706.0, 334408.0, 334905.0, 335541.0, 335645.0, 25273.87363063173, 25333.113341032975, 25607.74392256128, 25649.230523083592, 25667.35831604225, 40870.59129408787, 41025.589744103374, 41150.9712257184, 41816.38223208618, 41973.37039944464, 35198.64801351954, 35259.64740352564, 35435.46846797249, 35454.290914182086, 35522.289554208255, 34971.47542786809, 35306.64693353033, 35416.29167416689, 35623.64376356203, 35667.0, 25711.485770284864, 27570.0, 27571.724282756917, 35087.29825403529, 35450.82274588714, 27182.0, 31447.55586105166, 31665.366692666477, 31801.705491883306, 31997.520037199927, 24966.126185583416, 25238.49523009566, 25420.74579254184, 25621.743782561934, 25705.228843134737], "value": 17120.745233025802, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.82693893461675, 360.3154007385966, 360.40713027070467, 373.88783364990496, 386.33164625198407, 15991.600209994742, 16575.751363729538, 17031.978081315116, 17168.141592920365, 17170.742438863395, 14896.404143834261, 15603.063816171038, 15646.295916683748, 15787.131707756069, 15797.736181105513, 4997.60019198465, 5158.716270605115, 5192.428832828386, 5231.084560201968, 5237.47625237477, 3822.4266360045935, 3995.5405128410316, 5114.897702045975, 5196.636235463519, 5834.329052159013, 6035.366286539916, 6167.4757645600175, 6168.722407491674, 6174.783882564111, 6182.196314479134, 5901.616394934337, 6041.093835924603, 6095.603785753912, 6099.420555047263, 6152.354002829706, 5288.693601118629, 5331.680099194061, 5334.519893209591, 5341.67949923002, 5342.065138600729, 20705.68941465879, 21075.0, 21081.0, 21134.788652113435, 21153.788462115488, 2067.65883629201, 2072.202202152182, 2492.7382624824313, 2514.8742562871785, 2559.6160575913577, 12598.874011259863, 13214.021462232502, 13685.79471307931, 13765.518206862711, 14331.856681433157, 1699.2862997541063, 1728.3259528783785, 1783.2688597675021, 1836.3205613922935, 1839.062078340043, 67785.66107169433, 69219.65390173017, 69629.30370696279, 70709.64645176793, 71886.28113718847, 9409.952950235205, 9463.195628371666, 9775.462349570842, 9913.45475998827, 10068.345557538736, 13355.53255636048, 13381.732365352638, 13458.663533411744, 13553.59339219825, 13576.0, 7011.0, 7226.675729164538, 7230.602316872622, 7393.8151546211775, 7394.223606521346, 6698.933010669928, 6873.5875847449315, 6970.337817907291, 7023.824404389931, 7071.858562828715, 5977.312609049928, 6055.757769689163, 6150.907736383958, 6152.384761523812, 6222.751089956351, 1630.1441743084738, 1753.3775509693876, 1786.9910650446925, 1830.3502256698946, 1840.6410749903641, 820.3929092471528, 824.4435006370676, 828.9792755181048, 836.7322456813882, 898.9841479128543, 16738.83261167397, 16872.317329429377, 18017.369392071214, 18040.819591803913, 18302.450926472226, 836.0928392693874, 844.079952851395, 872.5986046418718, 873.7859224489913, 889.2219308105408, 9634.229261659051, 10215.7446063849, 10262.179025678077, 10267.332623379456, 10285.382877027403, 392543.0, 394363.0, 402815.0, 403106.0, 404199.0, 42585.78707106445, 42835.14329713388, 63299.36700633027, 67123.0, 72742.63628681823, 842.1241908415302, 847.220557087481, 865.2083343740533, 908.3596064774318, 921.829461549602, 15259.847401526287, 15398.229203641724, 15427.768583471481, 15807.446739364066, 16212.0, 35463.82268088643, 35591.64408355884, 36115.458268125476, 36609.816950915076, 37218.255634886606, 45384.319235212155, 45847.31229031634, 46019.76990115028, 46051.76974115242, 48117.5188248127, 6687.297833727389, 6817.7272909083085, 6858.8285292866085, 7499.362554182955, 7815.726449574237, 11142.331460112095, 11350.772984540428, 11460.14048946349, 11649.88350116488, 11796.70508237301, 9675.274354423305, 9682.661106861226, 9739.513024348613, 9821.165200957712, 10010.399376037461, 9803.215742740422, 10320.43237621938, 10377.16982641402, 10423.374597524173, 10500.947495262475, 9232.769180770534, 9614.423134611945, 9975.900240997787, 10186.134178594903, 10209.9489502552, 6852.177738671391, 6886.827829304308, 6944.131983502062, 7025.0, 7041.718331266692, 8373.371997100363, 9969.750756231153, 9975.251856110675, 10005.899941000496, 10403.479826008821], "value": 8265.034713961013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.5269529317095, 1377.3388773388774, 1409.7110092431053, 1589.1418633937674, 1610.9597260068497, 7198.7120515179395, 7742.729004484837, 7743.845123097534, 7745.651445684944, 7746.883796743054, 6687.765928192514, 7227.2772722727705, 7458.664360103795, 7475.514091584043, 7628.351590114833, 2118.0786357934307, 2133.17872619042, 2136.3377353020537, 2138.2088627207954, 2148.4414052346424, 2113.3448630924463, 2139.5934772393275, 2164.101897712447, 2169.533550286688, 2175.9347219583437, 1829.5089502055848, 1896.2983696032488, 1897.9715304270446, 2047.6416627090275, 2118.110393634677, 2090.3206457901165, 2098.8530802843657, 2116.153538584563, 2127.77658345873, 2139.443744626393, 2065.783092775267, 2087.2068613926613, 2108.5782843431375, 2138.8930553472264, 2160.83793715472, 7649.655765490566, 7699.384049276045, 7716.228377162295, 7718.073831140243, 7720.07359116904, 4166.187593419285, 4300.591443812864, 4302.333138363559, 4303.806328715215, 4304.612584867377, 2819.887204511817, 2890.8843646254127, 2955.541891006876, 2964.43675701616, 2964.5997790298247, 890.4301247201796, 920.4385324951774, 925.4447331601053, 943.5235206220815, 949.0225068179786, 10232.283740138193, 10269.435181065039, 10284.382937023802, 10285.434301113435, 10287.382757034602, 3811.599782022903, 3966.7223294369696, 4069.2471892699946, 4132.752034877917, 4134.834606615762, 8732.0, 8759.781005474913, 8765.649374024968, 8772.824543509094, 8783.121687831166, 4340.934885976713, 4361.323994780814, 4365.367021781887, 4366.978165109154, 4368.803403846835, 3933.350997085506, 3959.980200098981, 3965.2862484753023, 3981.980090099531, 3985.242803867256, 3494.0740703713536, 3604.6034936157002, 3649.1606930406156, 3691.0, 3731.3097077040834, 1141.577616281981, 1161.1987728467332, 1174.506707182985, 1178.0752109593927, 1198.161287099034, 617.4227097663698, 716.3409663109951, 726.1576571177409, 736.2931585677702, 772.1544908325375, 4985.5762260208285, 5142.845714628567, 5144.6398752087, 5181.481851814864, 5215.634905556651, 349.3066263467016, 361.7630452053909, 364.5698076269974, 874.8119154381711, 938.6620816506053, 3457.6888080072913, 3483.390406678821, 3506.5967413747744, 3540.8760693375607, 3605.332054279814, 9263.861042084374, 9605.615775369046, 9621.90378096224, 9628.71113866585, 9640.180584650243, 9482.146606805283, 9579.281553883488, 9608.567614457366, 9609.807803843883, 9611.375260608178, 6746.763863264762, 6896.827539318328, 7021.5787052777, 7169.139703235541, 7173.92826071743, 5196.714180720097, 5273.762680679302, 5330.387005494263, 5429.294191755047, 5568.276124103841, 8568.100349463217, 8714.302855771397, 8761.474311541328, 8827.0, 8827.911720882708, 8318.41771076043, 8585.227329540372, 8612.569371531274, 8795.47227166372, 8831.558422078999, 6460.418562329413, 6601.966990165019, 6645.8006259810345, 6658.234303055211, 6672.299408562031, 7047.189573199148, 7091.716331346688, 7105.573665580082, 7110.400159963967, 7117.252688467844, 6506.3818937200895, 6538.169312592482, 6562.442192413508, 6566.343365663395, 6716.0, 6399.9680001601555, 6437.130987316839, 6439.420452159328, 6531.902021469777, 6566.9343306566325, 5968.701564921824, 6069.878602427839, 6070.150178974859, 6070.362611925684, 6072.574919755575, 4277.251480990876, 4325.783710814384, 4440.3561483585345, 4450.287953927356, 4503.842365517191, 4444.666650001198, 4454.465464144194, 4515.571020753023, 4520.412346394947, 4604.539546045368], "value": 3970.0402029447027, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.30577378725525, 293.8369205091175, 302.72905749354317, 302.86673863500056, 305.4593369735568, 4735.8816029599275, 4785.21044027735, 4792.448868380137, 4805.975970120153, 4826.0, 4718.787654555545, 4723.197056500395, 4729.858104256869, 4735.8816029599275, 4769.356136921515, 3803.3724435468253, 3804.1440675847853, 3807.162424266657, 3811.599782022882, 3824.6366595173413, 3490.7730997485082, 3760.492333534965, 3785.1483416231267, 3829.617038296162, 3840.615938406165, 3671.3208056509498, 3692.2246328271094, 3696.724630002645, 3703.759255648388, 3703.8518059401536, 3658.1220507078374, 3672.9632703672887, 3685.834137463794, 3697.3159965406485, 3705.8332375043187, 3028.6971302869756, 3115.875364985398, 3148.511980642981, 3237.5629290046013, 3245.204924793417, 4622.722636641813, 4635.327877457749, 4643.721376717408, 4648.48866624671, 4662.650301227422, 4200.852970146061, 4203.7809035379705, 4204.768737719424, 4206.390073439365, 4209.326507758775, 4716.976415117902, 4721.3390125382475, 4969.291736003886, 4970.676906001098, 4973.726445045521, 3521.3133438979407, 3529.2764983178645, 3537.0192692092255, 3542.2738338640515, 3547.592026916912, 5168.5865130789825, 5179.792808287664, 5182.222666600002, 5185.496206100264, 5199.168133098724, 3478.9478157827652, 3548.8580456781438, 3553.7867727936405, 3557.7509574330065, 3566.928661426757, 3603.4594810778594, 3642.9817850910576, 3646.1431563582537, 3646.8176591170336, 3652.192164878349, 4110.301248787713, 4115.938260926088, 4118.91762164755, 4145.0, 4152.626263636287, 4880.585150262267, 4911.16510193268, 4915.606751459874, 4916.901661966741, 4927.457979622239, 1228.6791698923694, 1245.2217364147407, 1248.7065539598186, 1254.2725219372742, 1254.5170109507906, 782.7573452229827, 801.4229754576695, 809.4253080312926, 813.1868131868132, 816.2408959667566, 3972.682185425159, 3993.4808474898077, 3995.760254384746, 3998.540167880673, 4006.45912801768, 4514.45826500822, 4540.614047805908, 4553.975355544976, 4573.428321459817, 4593.701409408378, 3967.583403742623, 3984.3425834736945, 3995.4617472272803, 4034.3141665916864, 4055.5944405559208, 2292.151903795573, 2379.762023797606, 2413.963790543143, 2417.2023232333286, 2426.272118364519, 4279.97860010698, 4603.953960460352, 4755.167845627071, 4772.448954089935, 4846.903061938741, 5196.168613021899, 5238.476152384731, 5300.734963251822, 5304.363476382859, 5314.335708036496, 4917.0, 4918.0, 4922.852314430501, 4924.729139897268, 4926.778294976663, 5018.322526458881, 5122.385313762224, 5127.53852153308, 5150.71232191952, 5152.690838549699, 5070.264811602223, 5072.721000345016, 5076.82231121909, 5080.0, 5083.949160508348, 4740.032886352125, 4849.466558678472, 4856.9314750754775, 4865.829695960624, 4893.951060489349, 3688.4098544232793, 3711.9955224178643, 3726.366517691945, 3729.9254014920093, 3750.981245093866, 5088.542031217208, 5101.285819985131, 5107.526881720466, 5109.463506331782, 5111.0, 4938.111139994836, 4949.480304568113, 4959.628027897994, 4979.302897594267, 4979.850604481871, 4751.881202969954, 4920.21276595743, 4937.382827146606, 4954.50454954516, 4963.379577552806, 4872.6832755870755, 4884.389451318585, 4899.828506002273, 4910.361652985089, 4916.852494425173, 4977.950220497749, 5003.524665156804, 5006.874828129326, 5007.621379844545, 5016.59867210615, 4645.67480276391, 4876.634252431011, 4910.582600479, 4915.5330243626795, 4918.442409946879], "value": 3696.5782656859087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.6927874308503, 566.3515275010113, 586.2759492027343, 611.6360765344618, 629.8551333193366, 11382.089432845363, 12196.51213951442, 12210.267383956969, 12238.632841014762, 12243.449044792984, 11109.222354435193, 11944.343061131634, 12144.514219431212, 12145.574904878331, 12147.149699521036, 737.6385571070164, 753.2655660730771, 756.043604839878, 756.2701992577162, 756.4591317208195, 748.2891253309351, 751.2187325181832, 751.2487512487512, 751.8909758085074, 751.9135299440552, 797.2824457987799, 798.349345283595, 798.744401791427, 799.1369321133192, 799.2526987266924, 787.940904432167, 788.2787249666549, 788.8264581792025, 788.8934993775853, 788.9368850491946, 577.0997244298907, 577.768892443022, 577.8642019125502, 577.890200861835, 579.099500277069, 10370.792584148276, 10371.0666040056, 10371.740706482324, 10390.116840068604, 10424.635137770141, 10405.219608529314, 10499.37003779768, 10531.894681053169, 10671.359718416843, 11049.668509944715, 9461.952690236574, 9507.619695212252, 9610.855837162448, 9617.326787124905, 9637.325387222898, 3154.3375891062906, 3199.504076868066, 3216.1637974126897, 3237.320162765822, 3257.1857035741064, 61322.386776132116, 62272.68863655698, 62506.68746656283, 63327.0, 64787.0, 7876.684932602631, 7956.609384667603, 7958.442908996431, 8127.75616731499, 8159.714409995621, 15077.095374277578, 15129.924350378398, 15173.772393414107, 15180.240987950558, 15210.771838422432, 6496.220453545605, 6515.576487528296, 6521.804345869631, 6596.5052621052655, 6764.425023872927, 3481.5299934509026, 3495.7378196635354, 3565.108722819295, 3634.7819130852236, 3752.962470375316, 2774.140016594862, 2903.840288784137, 2904.9273768155545, 2908.0839535546656, 2909.2726818295428, 463.60130287952336, 475.9262314341283, 477.5511019641595, 483.7073570489873, 487.71224977263586, 446.35501700043125, 454.0918163672655, 466.5148245824305, 497.59943245686844, 498.67087722103236, 7490.737824176128, 7526.397888168934, 7539.283768042027, 7635.618219089023, 8034.67861285542, 431.2324063167567, 433.3629564540121, 483.16368037779915, 511.5856156513236, 518.5410911343413, 3553.129483276614, 3577.910552236163, 3629.5825980012637, 3775.4714339992424, 3776.962230377716, 50165.0, 89422.10577894138, 94655.0, 102283.4885825581, 102317.48841255895, 90109.54945225363, 90765.54617226872, 91100.0, 91699.0, 95487.52256238674, 79148.60425697835, 81400.0, 81435.59282203669, 81496.0, 82038.0, 6911.7926462206215, 6992.860142797217, 7116.110486189226, 7227.205007449282, 7268.672909719181, 45769.77115114403, 45942.540574593826, 46817.76591117022, 48124.0, 48225.0, 36019.0, 50661.24008139807, 51889.0, 53919.46080539144, 58979.410205897395, 10195.235357348376, 10477.31897426665, 10527.78944421092, 10628.309159904582, 10661.253712240068, 9816.754581135243, 9852.458114803754, 10229.335093218917, 10722.89277107219, 11438.055530559659, 9669.758256043655, 9676.27427942893, 9900.504974751379, 10787.500434219546, 11098.112151027737, 10788.514516846917, 11128.221024528206, 11222.382768947786, 11231.494582743633, 11721.296722196696, 11109.611163609236, 11132.0, 11261.268017578832, 11306.208565400342, 11683.824742629038, 9183.816323673624, 9258.0, 9666.468344241135, 9733.0, 9964.0, 9375.156235938799, 9483.525823708926, 9527.90472095298, 9544.570494327632, 9585.0], "value": 6041.133076551185, "unit": "score"}], "name": "dromaeo_css", "value": 6227.747139151232, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 111.0, 116.0, 114.0, 114.0, 121.0, 114.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 146.0, 193.0, 125.0, 158.0, 148.0, 125.0, 180.0, 121.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 163.0, 163.0, 161.0, 162.0, 162.0, 163.0, 160.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 115.0, 121.0, 76.0, 77.0, 75.0, 120.0, 109.0, 77.0], "value": 92.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 158.0, 87.0, 87.0, 87.0, 147.0, 87.0, 86.0, 142.0], "value": 105.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 69.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 82.0, 91.0, 82.0, 92.0, 85.0, 82.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 135.0, 132.0, 131.0, 131.0, 130.0, 132.0, 145.0, 151.0, 144.0], "value": 137.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 173.0, 172.0, 171.0, 175.0, 170.0, 174.0, 176.0, 170.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 71.0, 69.0, 70.0, 69.0, 69.0, 68.0, 69.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1576.4, "alertThreshold": 2.0}]} 13:26:54 INFO - Return code: 0 13:26:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:26:54 INFO - # TBPL SUCCESS # 13:26:54 INFO - Running post-action listener: _resource_record_post_action 13:26:54 INFO - [mozharness: 2016-08-23 20:26:54.147837Z] Finished run-tests step (success) 13:26:54 INFO - Running post-run listener: _resource_record_post_run 13:26:54 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 413270016; Read time: 16; Write time: 298440 13:26:54 INFO - TinderboxPrint: CPU usage
13.3% 13:26:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 13:26:54 INFO - TinderboxPrint: I/O write bytes / time
413,270,016 / 298,440 13:26:54 INFO - TinderboxPrint: CPU idle
8,325.5 (86.7%) 13:26:54 INFO - TinderboxPrint: CPU user
1,207.1 (12.6%) 13:26:54 INFO - TinderboxPrint: Swap in / out
0 / 0 13:26:54 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 122236928; Read time: 0; Write time: 119604 13:26:54 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 290009088; Read time: 0; Write time: 178812 13:26:54 INFO - Running post-run listener: _upload_blobber_files 13:26:54 INFO - Blob upload gear active. 13:26:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:26:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:26:55 INFO - (blobuploader) - INFO - Open directory for files ... 13:26:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:26:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:26:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:26:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:26:59 INFO - (blobuploader) - INFO - Done attempting. 13:26:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:26:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:26:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:27:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:01 INFO - (blobuploader) - INFO - Done attempting. 13:27:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:27:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:27:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:27:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:01 INFO - (blobuploader) - INFO - Done attempting. 13:27:01 INFO - (blobuploader) - INFO - Iteration through files over. 13:27:01 INFO - Return code: 0 13:27:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:27:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:27:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5451167b6d572c390c76738802287a9e248f9a113c8839b97de6edd06e1d25cd8b726ebd8156f28bb923631f0e17f5f7deca432d234f8d130eb9d986995524d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3ee9f073a41221882fd9a4abf1b8ba07b1911d5121cb3532130e2da606037f5fc409250a7c26b009a88b26f6c4a78f03a79d3b8ebc4b5b80d5d3e5a01828940d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/64ff894331170d7a0d0e6105568296e9e2be3a7be22b51caece8c447bc63e865b1479d349536734b57ac16058d1f77fe6074103ef22e2445d63e301a2f484876"} 13:27:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:27:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:27:01 INFO - Contents: 13:27:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5451167b6d572c390c76738802287a9e248f9a113c8839b97de6edd06e1d25cd8b726ebd8156f28bb923631f0e17f5f7deca432d234f8d130eb9d986995524d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3ee9f073a41221882fd9a4abf1b8ba07b1911d5121cb3532130e2da606037f5fc409250a7c26b009a88b26f6c4a78f03a79d3b8ebc4b5b80d5d3e5a01828940d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/64ff894331170d7a0d0e6105568296e9e2be3a7be22b51caece8c447bc63e865b1479d349536734b57ac16058d1f77fe6074103ef22e2445d63e301a2f484876"} 13:27:01 INFO - Running post-run listener: copy_logs_to_upload_dir 13:27:01 INFO - Copying logs to upload dir... 13:27:01 INFO - mkdir: /builds/slave/test/build/upload/logs 13:27:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.751164 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-23 13:27:01.586595) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 13:27:01.590466) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5451167b6d572c390c76738802287a9e248f9a113c8839b97de6edd06e1d25cd8b726ebd8156f28bb923631f0e17f5f7deca432d234f8d130eb9d986995524d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3ee9f073a41221882fd9a4abf1b8ba07b1911d5121cb3532130e2da606037f5fc409250a7c26b009a88b26f6c4a78f03a79d3b8ebc4b5b80d5d3e5a01828940d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/64ff894331170d7a0d0e6105568296e9e2be3a7be22b51caece8c447bc63e865b1479d349536734b57ac16058d1f77fe6074103ef22e2445d63e301a2f484876"} build_url:https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/DlBw0_GQQlGoZ0CEKBc1Og/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/5451167b6d572c390c76738802287a9e248f9a113c8839b97de6edd06e1d25cd8b726ebd8156f28bb923631f0e17f5f7deca432d234f8d130eb9d986995524d9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/3ee9f073a41221882fd9a4abf1b8ba07b1911d5121cb3532130e2da606037f5fc409250a7c26b009a88b26f6c4a78f03a79d3b8ebc4b5b80d5d3e5a01828940d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/64ff894331170d7a0d0e6105568296e9e2be3a7be22b51caece8c447bc63e865b1479d349536734b57ac16058d1f77fe6074103ef22e2445d63e301a2f484876"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 13:27:01.616954) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:27:01.617381) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1471982769.453510-1972446867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:27:01.702533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 13:27:01.703137) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 13:27:01.703696) ========= ========= Total master_lag: 0.24 =========